Hello,
I'm having troubles using sssd (1.7.0/1.6.x) with openldapclient
=2.4.28, though it works with openldapclient 2.4.24 and 2.4.25. As
we discussed on on #freeipa, below is the log file with debug level 5.
Regards,
Vlad.
Thu Feb 23 11:46:24:618609 2012) [sssd] [check_file] (0x0020): lstat
for [/var/run/nscd/socket] failed: [2][No such file or directory].
(Thu Feb 23 11:46:24:620079 2012) [sssd] [ldb] (0x0400):
server_sort:Unable to register control with rootdse!
(Thu Feb 23 11:46:24:620218 2012) [sssd] [confdb_get_domain_internal]
(0x0020): No enumeration for [LDAP]!
(Thu Feb 23 11:46:24:620347 2012) [sssd] [ldb] (0x0400):
server_sort:Unable to register control with rootdse!
(Thu Feb 23 11:46:24 2012) [sssd] [server_setup] (0x0080): CONFDB:
/var/lib/sss/db/config.ldb
(Thu Feb 23 11:46:24 2012) [sssd] [sysdb_domain_init_internal]
(0x0200): DB File for LDAP: /var/lib/sss/db/cache_LDAP.ldb
(Thu Feb 23 11:46:24 2012) [sssd] [ldb] (0x0400): asq: Unable to
register control with rootdse!
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_new_server] (0x0080): D-BUS
Server listening on
unix:path=/var/lib/sss/pipes/private/sbus-monitor,guid=521f64b5ecd741301c4525d30004337a
(Thu Feb 23 11:46:24 2012) [sssd] [start_service] (0x0100): Queueing
service LDAP for startup
(Thu Feb 23 11:46:24:623542 2012) [sssd[be[LDAP]]] [ldb] (0x0400):
server_sort:Unable to register control with rootdse!
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [server_setup] (0x0080):
CONFDB: /var/lib/sss/db/config.ldb
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [recreate_ares_channel]
(0x0100): Initializing new c-ares channel
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [resolv_get_family_order]
(0x1000): Lookup order: ipv4_first
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_context_init]
(0x0080): Created new fail over context, retry timeout is 30
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]]
[confdb_get_domain_internal] (0x0020): No enumeration for [LDAP]!
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]]
[sysdb_domain_init_internal] (0x0200): DB File for LDAP:
/var/lib/sss/db/cache_LDAP.ldb
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [ldb] (0x0400): asq:
Unable to register control with rootdse!
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sbus_init_connection]
(0x0200): (Thu Feb 23 11:46:24 2012) [sssd]
[sbus_server_init_new_connection] (0x0200): Entering.
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_server_init_new_connection]
(0x0200): Adding connection 0x247bee0.
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_init_connection] (0x0200):
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [monitor_common_send_id]
(0x0100): Sending ID: (%BE_LDAP,1)
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_server_init_new_connection]
(0x0200): Got a connection
(Thu Feb 23 11:46:24 2012) [sssd] [monitor_service_init] (0x0080):
Initializing D-BUS Service
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [create_socket_symlink]
(0x1000): Symlinking the dbus path
/var/lib/sss/pipes/private/sbus-dp_LDAP.7227 to a link
/var/lib/sss/pipes/private/sbus-dp_LDAP
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sbus_new_server]
(0x0080): D-BUS Server listening on
unix:path=/var/lib/sss/pipes/private/sbus-dp_LDAP.7227,guid=84618cd3c6ab44497aa670de0004337a
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [load_backend_module]
(0x1000): Loading backend [ldap] with path
[/usr/lib64/sssd/libsss_ldap.so].
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_uri has value ldaps://srv1.net.home,ldaps://srv2.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_search_base has value dc=net,dc=home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_default_bind_dn has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_default_authtok_type has value password
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_default_authtok has no binary value.
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_search_timeout has value 6
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_network_timeout has value 6
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_opt_timeout has value 6
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_tls_reqcert has value hard
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_user_search_base has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_user_search_scope has value sub
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_user_search_filter has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_group_search_base has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_group_search_scope has value sub
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_group_search_filter has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_sudo_search_base has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_schema has value rfc2307
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_offline_timeout has value 60
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_force_upper_case_realm is FALSE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_enumeration_refresh_timeout has value 300
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_purge_cache_timeout has value 10800
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option entry_cache_timeout has value 5400
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_tls_cacert has value /etc/ssl/cacert.pem
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_tls_cacertdir has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_tls_cert has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_tls_key has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_tls_cipher_suite has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_id_use_start_tls is FALSE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_sasl_mech has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_sasl_authid has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_sasl_realm has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_sasl_minssf has value -1
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_krb5_keytab has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_krb5_init_creds is TRUE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_server has value srv2.net.home,srv1.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_realm has value NET.HOME
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_canonicalize is TRUE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_pwd_policy has value none
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_referrals is TRUE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option account_cache_expiration has value 0
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_dns_service_name has value ldap
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_krb5_ticket_lifetime has value 86400
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_access_filter has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_netgroup_search_base has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_group_nesting_level has value 2
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_deref has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_account_expire_policy has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_access_order has value filter
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_chpass_uri has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_chpass_dns_service_name has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_enumeration_search_timeout has value 60
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_auth_disable_tls_never_use_in_production is FALSE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_page_size has value 1000
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_deref_threshold has value 10
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_sasl_canonicalize is FALSE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option ldap_connection_expire_timeout has value 900
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [ldap_get_options]
(0x0400): Option ldap_user_search_base set to dc=net,dc=home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [ldap_get_options]
(0x0400): Option ldap_group_search_base set to dc=net,dc=home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [ldap_get_options]
(0x0400): Option ldap_netgroup_search_base set to dc=net,dc=home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_parse_search_base]
(0x0100): Search base added: [DEFAULT][dc=net,dc=home][SUBTREE][]
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_parse_search_base]
(0x0100): Search base added: [USER][dc=net,dc=home][SUBTREE][]
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_parse_search_base]
(0x0100): Search base added: [GROUP][dc=net,dc=home][SUBTREE][]
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_parse_search_base]
(0x0100): Search base added: [NETGROUP][dc=net,dc=home][SUBTREE][]
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_entry_usn has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_rootdse_last_usn has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_object_class has value posixAccount
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_name has value uid
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_pwd has value userPassword
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_uid_number has value uidNumber
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_gid_number has value gidNumber
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_gecos has value gecos
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_home_directory has value homeDirectory
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shell has value loginShell
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_principal has value krbPrincipalName
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_fullname has value cn
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_member_of has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_uuid has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_modify_timestamp has value modifyTimestamp
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_entry_usn has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shadow_last_change has value shadowLastChange
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shadow_min has value shadowMin
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shadow_max has value shadowMax
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shadow_warning has value shadowWarning
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shadow_inactive has value shadowInactive
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shadow_expire has value shadowExpire
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_shadow_flag has value shadowFlag
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_krb_last_pwd_change has value krbLastPwdChange
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_krb_password_expiration has value krbPasswordExpiration
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_pwd_attribute has value pwdAttribute
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_authorized_service has value authorizedService
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_ad_account_expires has value accountExpires
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_ad_user_account_control has value userAccountControl
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_ns_account_lock has value nsAccountLock
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_authorized_host has value host
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_nds_login_disabled has value loginDisabled
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_nds_login_expiration_time has value loginExpirationTime
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_user_nds_login_allowed_time_map has value
loginAllowedTimeMap
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_object_class has value posixGroup
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_name has value cn
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_pwd has value userPassword
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_gid_number has value gidNumber
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_member has value memberuid
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_uuid has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_modify_timestamp has value modifyTimestamp
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_group_entry_usn has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_netgroup_object_class has value nisNetgroup
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_netgroup_name has value cn
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_netgroup_member has value memberNisNetgroup
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_netgroup_triple has value nisNetgroupTriple
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_netgroup_uuid has value nsUniqueId
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_get_map] (0x0200):
Option ldap_netgroup_modify_timestamp has value modifyTimestamp
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sssm_ldap_id_init]
(0x1000): Service name for discovery set to ldap
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_new_service] (0x0080):
Creating new service 'LDAP'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_service_init]
(0x0400): Added URI ldaps://srv1.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_add_server] (0x0080):
Adding new server 'srv1.net.home', to service 'LDAP'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sdap_service_init]
(0x0400): Added URI ldaps://srv2.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_add_server] (0x0080):
Adding new server 'srv2.net.home', to service 'LDAP'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]]
[ldap_id_cleanup_set_timer] (0x0400): Scheduling next cleanup at
1329993994.632259
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [load_backend_module]
(0x1000): Loading backend [krb5] with path
[/usr/lib64/sssd/libsss_krb5.so].
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_server has value srv2.net.home,srv1.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_realm has value NET.HOME
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_ccachedir has value /tmp
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_ccname_template has value FILE:%d/krb5cc_%U_XXXXXX
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_auth_timeout has value 15
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_keytab has value /etc/krb5.keytab
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_validate is FALSE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_kpasswd has value srv2.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_store_password_if_offline is FALSE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_renewable_lifetime has value 5d
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_lifetime has value 24h
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_renew_interval has value 0
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_use_fast has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_fast_principal has value (null)
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [dp_get_options] (0x0400):
Option krb5_canonicalize is FALSE
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_new_service] (0x0080):
Creating new service 'KERBEROS'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_add_server] (0x0080):
Adding new server 'srv2.net.home', to service 'KERBEROS'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [krb5_service_init]
(0x0400): Added Server srv2.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_add_server] (0x0080):
Adding new server 'srv1.net.home', to service 'KERBEROS'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [krb5_service_init]
(0x0400): Added Server srv1.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_new_service] (0x0080):
Creating new service 'KPASSWD'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [fo_add_server] (0x0080):
Adding new server 'srv2.net.home', to service 'KPASSWD'
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [krb5_service_init]
(0x0400): Added Server srv2.net.home
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [load_backend_module]
(0x0200): no module name found in confdb, using [permit].
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [load_backend_module]
(0x0200): no module name found in confdb, using [krb5].
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [load_backend_module]
(0x1000): Backend [krb5] already loaded.
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [load_backend_module]
(0x0200): no module name found in confdb, using [ldap].
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [load_backend_module]
(0x1000): Backend [ldap] already loaded.
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [main] (0x0020): Backend
provider (LDAP) started!
(Thu Feb 23 11:46:24 2012) [sssd] [client_registration] (0x0100):
Received ID registration: (%BE_LDAP,1)
(Thu Feb 23 11:46:24 2012) [sssd] [mark_service_as_started] (0x0200):
Marking LDAP as started.
(Thu Feb 23 11:46:24 2012) [sssd] [mark_service_as_started] (0x0100):
Now starting services!
(Thu Feb 23 11:46:24 2012) [sssd] [start_service] (0x0100): Queueing
service nss for startup
(Thu Feb 23 11:46:24 2012) [sssd] [start_service] (0x0100): Queueing
service pam for startup
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [id_callback] (0x0100):
Got id ack and version (1) from Monitor
(Thu Feb 23 11:46:24:636885 2012) [sssd[nss]] [ldb] (0x0400):
server_sort:Unable to register control with rootdse!
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [server_setup] (0x0080):
CONFDB: /var/lib/sss/db/config.ldb
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [confdb_get_domain_internal]
(0x0020): No enumeration for [LDAP]!
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [confdb_get_domain_internal]
(0x0020): No enumeration for [LDAP]!
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sbus_init_connection]
(0x0200): Adding connection 1822520
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [monitor_common_send_id]
(0x0100): Sending ID: (nss,1)
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_server_init_new_connection]
(0x0200): Entering.
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_server_init_new_connection]
(0x0200): Adding connection 0x247d190.
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_init_connection] (0x0200):
Adding connection 247D190
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sbus_init_connection]
(0x0200): Adding connection 1541D30
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_server_init_new_connection]
(0x0200): Got a connection
(Thu Feb 23 11:46:24 2012) [sssd] [monitor_service_init] (0x0080):
Initializing D-BUS Service
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [be_client_init] (0x0100):
Set-up Backend ID timeout [0x15429b0]
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_server_init_new_connection]
(0x0200): Entering.
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sbus_init_connection]
(0x0200): Adding connection 222EC90
(Thu Feb 23 11:46:24 2012) [sssd] [sbus_init_connection] (0x0200):
Adding connection 247F4D0
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [dp_common_send_id] (0x0100):
Sending ID to DP: (1,PAM)
[sssd] [sbus_server_init_new_connection] (0x0200): Got a connection
(Thu Feb 23 11:46:24 2012) [sssd] [monitor_service_init] (0x0080):
Initializing D-BUS Service
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sbus_init_connection]
(0x0200): Adding connection 1824B90
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [dp_common_send_id] (0x0100):
Sending ID to DP: (1,NSS)
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sysdb_domain_init_internal]
(0x0200): DB File for LDAP: /var/lib/sss/db/cache_LDAP.ldb
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]]
[sbus_server_init_new_connection] (0x0200): Entering.
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [ldb] (0x0400): asq: Unable to
register control with rootdse!
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]]
[sbus_server_init_new_connection] (0x0200): Adding connection 0x1549580.
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [sbus_init_connection]
(0x0200): Adding connection 1549580
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]]
[sbus_server_init_new_connection] (0x0200): Got a connection
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [be_client_init] (0x0100):
Set-up Backend ID timeout [0x1549db0]
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sysdb_domain_init_internal]
(0x0200): DB File for LDAP: /var/lib/sss/db/cache_LDAP.ldb
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_process_init] (0x0020):
Responder Initialization complete
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [ldb] (0x0400): asq: Unable to
register control with rootdse!
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/root] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/ldap] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/cron] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_process_init] (0x0020):
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/portage] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/messagebus] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/nobody] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/haldaemon] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/ntp] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/root] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [sss_ncache_set_str] (0x0400):
Adding [NCE/GROUP/LDAP/root] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/ldap] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/cron] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/portage] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/messagebus] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/nobody] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/haldaemon] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/csh in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/LDAP/ntp] to negative cache permanently
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/esh in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/fish in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/ksh in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/sash in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/sh in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [sss_ncache_set_str] (0x0400):
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/tcsh in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_get_etc_shells] (0x0400):
Found shell /bin/zsh in /etc/shells
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [nss_process_init] (0x0020):
NSS Initialization complete
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [client_registration]
(0x0100): Cancel DP ID timeout [0x15429b0]
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [client_registration]
(0x0100): Added Frontend client [PAM]
(Thu Feb 23 11:46:24 2012) [sssd] [client_registration] (0x0100):
Received ID registration: (nss,1)
(Thu Feb 23 11:46:24 2012) [sssd] [mark_service_as_started] (0x0200):
Marking nss as started.
(Thu Feb 23 11:46:24 2012) [sssd[pam]] [dp_id_callback] (0x0100): Got
id ack and version (1) from DP
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [id_callback] (0x0100): Got id
ack and version (1) from Monitor
(Thu Feb 23 11:46:24 2012) [sssd[be[LDAP]]] [client_registration]
(0x0100): Cancel DP ID timeout [0x1549db0]
[client_registration] (0x0100): Received ID registration: (pam,1)
(Thu Feb 23 11:46:24 2012) [sssd] [mark_service_as_started] (0x0200):
Marking pam as started.
(Thu Feb 23 11:46:24 2012) [sssd[nss]] [dp_id_callback] (0x0100GotGot
id ack and version (1) from DP
(Thu Feb 23 11:46:29 2012) [sssd] [services_startup_timeout] (0x0400):
Handling timeout
(Thu Feb 23 11:46:34 2012) [sssd] [service_send_ping] (0x0100): Pinging LDAP
(Thu Feb 23 11:46:34 2012) [sssd] [service_send_ping] (0x0100): Pinging nss
(Thu Feb 23 11:46:34 2012) [sssd] [service_send_ping] (0x0100): Pinging pam
(Thu Feb 23 11:46:34 2012) [sssd] [ping_check] (0x0100): Service LDAP
replied to ping
(Thu Feb 23 11:46:34 2012) [sssd] [ping_check] (0x0100): Service nss
replied to ping
(Thu Feb 23 11:46:34 2012) [sssd] [ping_check] (0x0100): Service pam
replied to ping
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1329993994)(!(lastLogin=*))))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [cleanup_users] (0x0100):
Found 1 expired user entries!
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [cleanup_users] (0x0200):
User mpd is still logged in or a dummy entry, keeping data
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_groups]
(0x0400): Search groups with filter:
(&(objectclass=group)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1329993994)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [cleanup_groups] (0x0100):
Found 16 expired group entries!
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=pulse-access,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=152)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=vboxusers,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=446)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=plugdev,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=444)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=portage,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=250)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=scanner,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=441)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=audio,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=18)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=cdemu,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=449)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=cdrom,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=19)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=games,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=35)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=pulse,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=153)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=users,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=100)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=video,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=27)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=wheel,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=10)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=cdrw,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=80)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=kvm,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=450)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]] [sysdb_search_users]
(0x0400): Search users with filter:
(&(objectclass=user)(|(memberOf=name=usb,cn=groups,cn=LDAP,cn=sysdb)(gidNumber=85)))
(Thu Feb 23 11:46:34 2012) [sssd[be[LDAP]]]
[ldap_id_cleanup_set_timer] (0x0400): Scheduling next cleanup at
1330004794.633702
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [accept_fd_handler] (0x0100):
Client connected!
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Received client version [1].
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Offered version [1].
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups] (0x0100):
Requesting info for [test] from [<ALL>]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0400): Initgroups for [test@LDAP] completed
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [accept_fd_handler] (0x0100):
Client connected to privileged pipe!
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Received client version [3].
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Offered version [3].
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_cmd_authenticate]
(0x0100): entering pam_cmd_authenticate
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_AUTHENTICATE
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
domain: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 8
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7236
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_dp_get_account_int_send]
(0x0400): Sending request for [LDAP][3][1][name=test]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_get_account_info]
(0x0100): Got request for [3][1][name=test]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'LDAP'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv1.net.home' is 'name not resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_port_status]
(0x1000): Port status of port 636 for server 'srv1.net.home' is
'neutral'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv1.net.home' is 'name not resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record
of 'srv1.net.home' in files
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [set_server_common_status]
(0x0100): Marking server 'srv1.net.home' as 'resolving name'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA
record of 'srv1.net.home' in files
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_next] (0x0200): No more address families to retry
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record
of 'srv1.net.home' in DNS
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [request_watch_destructor]
(0x0400): Deleting request watch
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [set_server_common_status]
(0x0100): Marking server 'srv1.net.home' as 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_resolve_server_done]
(0x0100): Found address for server srv1.net.home: [192.168.1.201] TTL
86400
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sdap_uri_callback]
(0x0400): Constructed uri 'ldaps://srv1.net.home'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sss_ldap_init_send]
(0x0400): Setting 6 seconds timeout for connecting
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to
[ldaps://srv1.net.home:636/??base] with fd [18].
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with
[(objectclass=*)][].
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedControl]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedExtension]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedFeatures]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedLDAPVersion]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedSASLMechanisms]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[defaultNamingContext]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[highestCommittedUSN]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sdap_process_result]
(0x0100): ldap_result gave -1, something bad happend!
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sdap_get_generic_done]
(0x0100): sdap_get_generic_ext_recv failed [5]: Input/output error
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_set_port_status]
(0x0100): Marking port 636 of server 'srv1.net.home' as 'not working'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'LDAP'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv2.net.home' is 'name not resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_port_status]
(0x1000): Port status of port 636 for server 'srv2.net.home' is
'neutral'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv2.net.home' is 'name not resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record
of 'srv2.net.home' in files
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [set_server_common_status]
(0x0100): Marking server 'srv2.net.home' as 'resolving name'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA
record of 'srv2.net.home' in files
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_next] (0x0200): No more address families to retry
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record
of 'srv2.net.home' in DNS
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [request_watch_destructor]
(0x0400): Deleting request watch
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [set_server_common_status]
(0x0100): Marking server 'srv2.net.home' as 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_resolve_server_done]
(0x0100): Found address for server srv2.net.home: [192.168.1.202] TTL
86400
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sdap_uri_callback]
(0x0400): Constructed uri 'ldaps://srv2.net.home'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sss_ldap_init_send]
(0x0400): Setting 6 seconds timeout for connecting
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to
[ldaps://srv2.net.home:636/??base] with fd [19].
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with
[(objectclass=*)][].
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedControl]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedExtension]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedFeatures]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedLDAPVersion]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedSASLMechanisms]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[defaultNamingContext]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[highestCommittedUSN]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sdap_process_result]
(0x0100): ldap_result gave -1, something bad happend!
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sdap_get_generic_done]
(0x0100): sdap_get_generic_ext_recv failed [5]: Input/output error
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_set_port_status]
(0x0100): Marking port 636 of server 'srv2.net.home' as 'not working'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'LDAP'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv1.net.home' is 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_port_status]
(0x1000): Port status of port 636 for server 'srv1.net.home' is 'not
working'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv2.net.home' is 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_port_status]
(0x1000): Port status of port 636 for server 'srv2.net.home' is 'not
working'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_resolve_service_send]
(0x0020): No available servers for service 'LDAP'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [sdap_id_op_connect_done]
(0x0020): Failed to connect, going offline (5 [Input/output error])
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_run_offline_cb]
(0x0080): Going offline. Running callbacks.
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [acctinfo_callback]
(0x0100): Request processed. Returned 1,11,Offline
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_dp_get_reply] (0x0100):
Got reply (1, 11, Offline) from Data Provider
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_dp_callback]
(0x0040): Unable to get information from Data Provider
Error: 1, 11, Offline
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0400): Returning info for user [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_AUTHENTICATE
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 8
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7236
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Got request with the following data
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
command: PAM_AUTHENTICATE
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
user: test
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): ruser:
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok type: 1
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok size: 8
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
cli_pid: 7236
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [krb5_pam_handler]
(0x1000): Wait queue of user [test] is empty, running request
immediately.
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [check_for_valid_tgt]
(0x1000): TGT end time [1330078391].
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [check_for_valid_tgt]
(0x0080): TGT is valid.
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'KERBEROS'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv2.net.home' is 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_port_status]
(0x1000): Port status of port 0 for server 'srv2.net.home' is 'neutral'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv2.net.home' is 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_resolve_server_done]
(0x0100): Found address for server srv2.net.home: [192.168.1.202] TTL
86400
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'KPASSWD'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv2.net.home' is 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_port_status]
(0x1000): Port status of port 0 for server 'srv2.net.home' is 'neutral'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [get_server_status]
(0x1000): Status of server 'srv2.net.home' is 'name resolved'
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_resolve_server_done]
(0x0100): Found address for server srv2.net.home: [192.168.1.202] TTL
86400
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [check_wait_queue]
(0x1000): Wait queue for user [test] is empty.
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler_callback]
(0x0100): Backend returned: (1, 9, <NULL>) [Provider is Offline
(Authentication service cannot retrieve authentication info)]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler_callback]
(0x0100): Sending result [9][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler_callback]
(0x0100): Sent result [9][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_process_reply]
(0x0100): received: [9][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sysdb_cache_auth] (0x0100):
Hashes do match!
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): blen: 89
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_cmd_acct_mgmt] (0x0100):
entering pam_cmd_acct_mgmt
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_ACCT_MGMT
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
domain: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7236
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0400): Returning info for user [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_ACCT_MGMT
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7236
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Got request with the following data
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
command: PAM_ACCT_MGMT
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
user: test
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): ruser:
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
cli_pid: 7236
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler_callback]
(0x0100): Backend returned: (0, 0, <NULL>) [Success]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler_callback]
(0x0100): Sending result [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler_callback]
(0x0100): Sent result [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_process_reply]
(0x0100): received: [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): blen: 21
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [client_recv] (0x0200): Client
disconnected!
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [client_recv] (0x0200): Client
disconnected!
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [accept_fd_handler] (0x0100):
Client connected!
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Received client version [1].
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Offered version [1].
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups] (0x0100):
Requesting info for [test] from [<ALL>]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0400): Initgroups for [test@LDAP] completed
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [accept_fd_handler] (0x0100):
Client connected to privileged pipe!
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Received client version [3].
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Offered version [3].
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_cmd_setcred] (0x0100):
entering pam_cmd_setcred
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
domain: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0400): Returning info for user [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Got request with the following data
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
user: test
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): ruser:
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Sending result [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_process_reply]
(0x0100): received: [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): blen: 21
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups] (0x0100):
Requesting info for [test] from [<ALL>]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0400): Initgroups for [test@LDAP] completed
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_cmd_open_session]
(0x0100): entering pam_cmd_open_session
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_OPEN_SESSION
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
domain: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0400): Returning info for user [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_OPEN_SESSION
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Got request with the following data
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
command: PAM_OPEN_SESSION
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
user: test
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): ruser:
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Sending result [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_process_reply]
(0x0100): received: [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): blen: 21
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [accept_fd_handler] (0x0100):
Client connected!
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Received client version [1].
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Offered version [1].
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups] (0x0100):
Requesting info for [test] from [<ALL>]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0400): Initgroups for [test@LDAP] completed
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [accept_fd_handler] (0x0100):
Client connected!
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Received client version [3].
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Offered version [3].
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_cmd_setcred] (0x0100):
entering pam_cmd_setcred
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
domain: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7245
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_check_user_search]
(0x0400): Returning info for user [test@LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 0
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7245
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Got request with the following data
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
domain: LDAP
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
user: test
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
tty: ssh
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): ruser:
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): priv: 0
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
cli_pid: 7245
(Thu Feb 23 11:46:35 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Sending result [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_dp_process_reply]
(0x0100): received: [0][LDAP]
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:35 2012) [sssd[pam]] [pam_reply] (0x0100): blen: 21
(Thu Feb 23 11:46:36 2012) [sssd[nss]] [nss_cmd_initgroups] (0xClient
disconnected!
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_cmd_close_session]
(0x0100): entering pam_cmd_close_session
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_CLOSE_SESSION
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
domain: (null)
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_check_user_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_check_user_search]
(0x0400): Returning info for user [test@LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_CLOSE_SESSION
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): domain: LDAP
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Got request with the following data
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
command: PAM_CLOSE_SESSION
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
domain: LDAP
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
user: test
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
tty: ssh
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): ruser:
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Sending result [0][LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_dp_process_reply]
(0x0100): received: [0][LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_reply] (0x0100): blen: 21
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_cmd_setcred] (0x0100):
entering pam_cmd_setcred
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
domain: (null)
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_check_user_search]
(0x0100): Requesting info for [test@LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_check_user_search]
(0x0400): Returning info for user [test@LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): domain: LDAP
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): user: test
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
ruser: (null)
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Got request with the following data
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
domain: LDAP
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
user: test
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
service: sshd
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
tty: ssh
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): ruser:
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
rhost: srv1.net.home
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
authtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok type: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
newauthtok size: 0
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100): priv: 1
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [pam_print_data] (0x0100):
cli_pid: 7232
(Thu Feb 23 11:46:36 2012) [sssd[be[LDAP]]] [be_pam_handler] (0x0100):
Sending result [0][LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_dp_process_reply]
(0x0100): received: [0][LDAP]
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_reply] (0x0100): pam_reply
get called.
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [pam_reply] (0x0100): blen: 21
(Thu Feb 23 11:46:36 2012) [sssd[pam]] [client_recv] (0x0200): Client
disconnected!
(Thu Feb 23 11:46:36 2012) [sssd[nss]] [client_recv] (0x0200): Client
disconnected!
(Thu Feb 23 11:46:39 2012) [sssd] [monitor_quit] (0x0010): Monitor
received Interrupt: terminating children
(Thu Feb 23 11:46:39 2012) [sssd] [monitor_quit] (0x0020): Terminating
[pam][7229]
(Thu Feb 23 11:46:39 2012) [sssd] [monitor_quit] (0x0020): Child [pam]
exited gracefully
(Thu Feb 23 11:46:39 2012) [sssd] [monitor_quit] (0x0020): Terminating
[nss][7228]
(Thu Feb 23 11:46:39 2012) [sssd[be[LDAP]]] [sbus_dispatch] (0x0080):
Connection is not open for dispatching.
(Thu Feb 23 11:46:39 2012) [sssd[be[LDAP]]] [be_client_destructor]
(0x0100): Removed PAM client
(Thu Feb 23 11:46:39 2012) [sssd] [monitor_quit] (0x0020): Child [nss]
exited gracefully
(Thu Feb 23 11:46:39 2012) [sssd] [monitor_quit] (0x0020): Terminating
[LDAP][7227]
(Thu Feb 23 11:46:39 2012) [sssd[be[LDAP]]] [remove_krb5_info_files]
(0x0200): Could not remove [/var/lib/sss/pubconf/kdcinfo.NET.HOME],
[2][No such file or directory]
(Thu Feb 23 11:46:39 2012) [sssd[be[LDAP]]] [remove_krb5_info_files]
(0x0200): Could not remove
[/var/lib/sss/pubconf/kpasswdinfo.NET.HOME], [2][No such file or
directory]
(Thu Feb 23 11:46:39 2012) [sssd[be[LDAP]]] [be_client_destructor]
(0x0100): Removed NSS client
(Thu Feb 23 11:46:39 2012) [sssd] [monitor_quit] (0x0020): Child
[LDAP] exited gracefully