We have been successfully using sssd on:
Linux cat.xxx.yyy.zzz 2.6.32-131.2.1.el6.x86_64 #1 SMP Wed May 18 07:07:37 EDT 2011 x86_64 x86_64 x86_64 GNU/Linux
rpm -qa |grep sssd
sssd-client-1.5.1-66.el6_2.3.x86_64
sssd-1.5.1-66.el6_2.3.x86_64
but with this new system we have:
Linux jupiter.xxx.yyy.zzz 2.6.32-279.1.1.el6.x86_64 #1 SMP Wed Jun 20 11:41:22 EDT 2012 x86_64 x86_64 x86_64 GNU/Linux
rpm -qa|grep sssd
sssd-1.8.0-32.el6.x86_64
sssd-client-1.8.0-32.el6.x86_64
This is the same sssd.conf from the 1.5 system (that works).  We don't get connected to the ldap server and a simple id request returns nothing but the error [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server.
/etc/sssd/sssd.conf
[sssd]
config_file_version = 2
reconnection_retries = 3
sbus_timeout = 30
services = nss, pam
domains = default
[nss]
filter_groups = root
filter_users = root
reconnection_retries = 3
[pam]
reconnection_retries = 3
[domain/LOCAL]
description = LOCAL Users domain
id_provider = local
enumerate = true
min_id = 0
max_id = 999
[domain/default]
auth_provider = krb5
cache_credentials = True
chpass_provider = krb5
debug_level = 8
enumerate = True
krb5_server = ads.yy.zzz:88
krb5_kpasswd = ads.yy.zzz:749
krb5_realm = ADS.YY.ZZZ
id_provider = ldap
ldap_group_object_class = posixGroup
ldap_group_member = memberUid
ldap_id_use_start_tls = False
ldap_group_search_filter = posixGroup
ldap_schema = rfc2307
ldap_search_base = dc=xxxx,dc=yyyyy,dc=edu
ldap_tls_cacertdir = /etc/openldap/cacerts
ldap_uri = ldap://ds-10.xxx.yyy.zzz/
ldap_user_gecos = displaName
ldap_user_object_class = posixAccount
ldap_user_search_base = dc=xxxx,dc=yyyyy,dc=edu
ldap_user_search_filter = posixAccount
From /var/log/sssd/sssd_default.log
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [server_setup] (0x0080): CONFDB: /var/lib/sss/db/config.ldb
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [recreate_ares_channel] (0x0100): Initializing new c-ares channel
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [resolv_get_family_order] (0x1000): Lookup order: ipv4_first
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [fo_context_init] (0x0080): Created new fail over context, retry timeout is 30
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sysdb_domain_init_internal] (0x0200): DB File for default: /var/lib/sss/db/cache_default.ldb
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [ldb] (0x0400): asq: Unable to register control with rootdse!
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_init_connection] (0x0200): Adding connection AC2CA0
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xac2f30/0xac09c0 (21), -/W (enabled)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [monitor_common_send_id] (0x0100): Sending ID: (%BE_default,1)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_add_timeout] (0x2000): 0xac1c80
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [create_socket_symlink] (0x1000): Symlinking the dbus path /var/lib/sss/pipes/private/sbus-dp_default.25381 to a link /var/lib/sss/pipes/private/sbus-dp_default
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_new_server] (0x0080): D-BUS Server listening on unix:path=/var/lib/sss/pipes/private/sbus-dp_default.25381,guid=c4684b0b6a3e89ec0b0599d70000214b
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xac41a0/0xac3af0 (22), R/- (enabled)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Loading backend [ldap] with path [/usr/lib64/sssd/libsss_ldap.so].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_uri has value ldap://ds-10.xxx.yyy.zzz/
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_search_base has value dc=xxxx,dc=yyyyy,dc=edu
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_default_bind_dn has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_default_authtok_type has value password
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_default_authtok has no binary value.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_search_timeout has value 6
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_network_timeout has value 6
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_opt_timeout has value 6
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_reqcert has value hard
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_user_search_base has value dc=xxxx,dc=yyyyy,dc=edu
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_user_search_scope has value sub
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_user_search_filter has value posixAccount
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_search_base has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_search_scope has value sub
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_search_filter has value posixGroup
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_service_search_base has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_search_base has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_refresh_enabled is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_refresh_timeout has value 300
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_autofs_search_base has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_schema has value rfc2307
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_offline_timeout has value 60
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_force_upper_case_realm is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_enumeration_refresh_timeout has value 300
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_purge_cache_timeout has value 10800
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cacert has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cacertdir has value /etc/openldap/cacerts
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cert has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_key has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cipher_suite has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_id_use_start_tls is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_mech has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_authid has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_realm has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_minssf has value -1
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_krb5_keytab has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_krb5_init_creds is TRUE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_server has value ads.yy.zzz:88
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_realm has value ADS.YY.ZZZ
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_canonicalize is TRUE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_pwd_policy has value none
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_referrals is TRUE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option account_cache_expiration has value 0
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_dns_service_name has value ldap
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_krb5_ticket_lifetime has value 86400
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_access_filter has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_netgroup_search_base has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_nesting_level has value 2
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_deref has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_account_expire_policy has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_access_order has value filter
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_chpass_uri has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_chpass_dns_service_name has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_chpass_update_last_change is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_enumeration_search_timeout has value 60
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_auth_disable_tls_never_use_in_production is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_page_size has value 1000
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_deref_threshold has value 10
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_canonicalize is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_connection_expire_timeout has value 900
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_disable_paging is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [ldap_get_options] (0x0400): Option ldap_group_search_base set to dc=xxxx,dc=yyyyy,dc=edu
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [ldap_get_options] (0x0400): Option ldap_netgroup_search_base set to dc=xxxx,dc=yyyyy,dc=edu
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [ldap_get_options] (0x0400): Option ldap_service_search_base set to dc=xxxx,dc=yyyyy,dc=edu
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [DEFAULT][dc=xxxx,dc=yyyyy,dc=edu][SUBTREE][]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [USER][dc=xxxx,dc=yyyyy,dc=edu][SUBTREE][posixAccount]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [GROUP][dc=xxxx,dc=yyyyy,dc=edu][SUBTREE][posixGroup]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [NETGROUP][dc=xxxx,dc=yyyyy,dc=edu][SUBTREE][]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [SERVICE][dc=xxxx,dc=yyyyy,dc=edu][SUBTREE][]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_entry_usn has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_rootdse_last_usn has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_object_class has value posixAccount
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_name has value uid
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_pwd has value userPassword
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_uid_number has value uidNumber
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_gid_number has value gidNumber
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_gecos has value displaName
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_home_directory has value homeDirectory
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shell has value loginShell
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_principal has value krbPrincipalName
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_fullname has value cn
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_member_of has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_uuid has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_modify_timestamp has value modifyTimestamp
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_entry_usn has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shadow_last_change has value shadowLastChange
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shadow_min has value shadowMin
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shadow_max has value shadowMax
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shadow_warning has value shadowWarning
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shadow_inactive has value shadowInactive
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shadow_expire has value shadowExpire
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_shadow_flag has value shadowFlag
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_krb_last_pwd_change has value krbLastPwdChange
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_krb_password_expiration has value krbPasswordExpiration
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_pwd_attribute has value pwdAttribute
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_authorized_service has value authorizedService
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_ad_account_expires has value accountExpires
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_ad_user_account_control has value userAccountControl
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_ns_account_lock has value nsAccountLock
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_authorized_host has value host
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_nds_login_disabled has value loginDisabled
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_nds_login_expiration_time has value loginExpirationTime
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_nds_login_allowed_time_map has value loginAllowedTimeMap
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_user_ssh_public_key has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_object_class has value posixGroup
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_name has value cn
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_pwd has value userPassword
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_gid_number has value gidNumber
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_member has value memberUid
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_uuid has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_modify_timestamp has value modifyTimestamp
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_group_entry_usn has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_netgroup_object_class has value nisNetgroup
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_netgroup_name has value cn
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_netgroup_member has value memberNisNetgroup
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_netgroup_triple has value nisNetgroupTriple
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_netgroup_uuid has value nsUniqueId
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_netgroup_modify_timestamp has value modifyTimestamp
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_service_object_class has value ipService
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_service_name has value cn
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_service_port has value ipServicePort
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_service_proto has value ipServiceProtocol
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_service_entry_usn has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sssm_ldap_id_init] (0x1000): Service name for discovery set to ldap
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [fo_new_service] (0x0080): Creating new service 'LDAP'
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_service_init] (0x0400): Added URI ldap://ds-10.xxx.yyy.zzz/
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [fo_add_server] (0x0080): Adding new server 'ds-10.xxx.yyy.zzz', to service 'LDAP'
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [ldap_id_enumerate_set_timer] (0x0400): Scheduling next enumeration at 1344625118.196118
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x2000): ID backend target successfully loaded from provider [ldap].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Loading backend [krb5] with path [/usr/lib64/sssd/libsss_krb5.so].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_server has value ads.yy.zzz:88
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_realm has value ADS.YY.ZZZ
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_ccachedir has value /tmp
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_ccname_template has value FILE:%d/krb5cc_%U_XXXXXX
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_auth_timeout has value 15
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_keytab has value /etc/krb5.keytab
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_validate is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_kpasswd has value ads.yy.zzz:749
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_store_password_if_offline is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_renewable_lifetime has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_lifetime has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_renew_interval has value 0
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_use_fast has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_fast_principal has value (null)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_canonicalize is FALSE
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [fo_new_service] (0x0080): Creating new service 'KERBEROS'
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [fo_add_server] (0x0080): Adding new server 'ads.yy.zzz', to service 'KERBEROS'
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [krb5_service_init] (0x0400): Added Server ads.yy.zzz:88
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [fo_new_service] (0x0080): Creating new service 'KPASSWD'
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [fo_add_server] (0x0080): Adding new server 'ads.yy.zzz', to service 'KPASSWD'
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [krb5_service_init] (0x0400): Added Server ads.yy.zzz:749
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [check_and_export_lifetime] (0x0200): No lifetime configured.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [check_and_export_lifetime] (0x0200): No lifetime configured.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x2000): AUTH backend target successfully loaded from provider [krb5].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [permit].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x2000): ACCESS backend target successfully loaded from provider [permit].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [krb5] already loaded.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x2000): CHPASS backend target successfully loaded from provider [krb5].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sssm_ldap_sudo_init] (0x0080): Sudo init handler called but SSSD is built without sudo support, ignoring
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x2000): SUDO backend target successfully loaded from provider [ldap].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sssm_ldap_id_init] (0x2000): Re-using sdap_id_ctx for this provider
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_autofs_init] (0x2000): Initializing autofs LDAP back end
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [ldap_get_autofs_options] (0x0200): Option ldap_autofs_search_base set to dc=xxxx,dc=yyyyy,dc=edu
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [AUTOFS][dc=xxxx,dc=yyyyy,dc=edu][SUBTREE][]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_autofs_map_object_class has value automountMap
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_autofs_map_name has value ou
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_autofs_entry_object_class has value automount
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_autofs_entry_key has value cn
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sdap_get_map] (0x0200): Option ldap_autofs_entry_value has value automountInformation
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x2000): autofs backend target successfully loaded from provider [ldap].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x0020): No Session module provided for [default] !!
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_process_init] (0x0020): No host info module provided for [default] !!
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [main] (0x0020): Backend provider (default) started!
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_remove_timeout] (0x2000): 0xac1c80
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [id_callback] (0x0100): Got id ack and version (1) from Monitor
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Entering.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0xad0450.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_init_connection] (0x0200): Adding connection AD0450
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xac3760/0xac3210 (30), -/W (disabled)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Got a connection
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0xad0ca0]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Entering.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0xad4120.
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_init_connection] (0x0200): Adding connection AD4120
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xad44d0/0xad0df0 (31), -/W (disabled)
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Got a connection
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0xad4740]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [client_registration] (0x0100): Cancel DP ID timeout [0xad4740]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [client_registration] (0x0100): Added Frontend client [PAM]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [client_registration] (0x0100): Cancel DP ID timeout [0xad0ca0]
(Fri Aug 10 14:58:28 2012) [sssd[be[default]]] [client_registration] (0x0100): Added Frontend client [NSS]
(Fri Aug 10 14:58:29 2012) [sssd[be[default]]] [check_if_online] (0x2000): Backend is already online, nothing to do.
(Fri Aug 10 14:58:30 2012) [sssd[be[default]]] [check_if_online] (0x2000): Backend is already online, nothing to do.
(Fri Aug 10 14:58:33 2012) [sssd[be[default]]] [check_if_online] (0x2000): Backend is already online, nothing to do.
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4097][1][name=gallantm]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'name not resolved'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [get_port_status] (0x1000): Port status of port 389 for server 'ds-10.xxx.yyy.zzz' is 'neutral'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 10 seconds
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'name not resolved'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'ds-10.xxx.yyy.zzz' in files
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'ds-10.xxx.yyy.zzz' as 'resolving name'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of 'ds-10.xxx.yyy.zzz' in files
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying DNS
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'ds-10.xxx.yyy.zzz' in DNS
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 5 seconds
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [request_watch_destructor] (0x0400): Deleting request watch
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'ds-10.xxx.yyy.zzz' as 'name resolved'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [be_resolve_server_done] (0x1000): Saving the first resolved server
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [be_resolve_server_done] (0x0200): Found address for server ds-10.xxx.yyy.zzz: [192.168.36.1] TTL 3600
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_uri_callback] (0x0400): Constructed uri 'ldap://ds-10.xxx.yyy.zzz/'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://ds-10.xxx.yyy.zzz:389/??base] with fd [33].
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad81f0], connected[1], ops[0xae2130], ldap[0xad8450]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad81f0], connected[1], ops[0xae2130], ldap[0xad8450]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [get_naming_context] (0x0200): Using value from [namingContexts] as naming context.
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_set_search_base] (0x0100): Setting option [ldap_sudo_search_base] to [dc=xxxx,dc=yyyyy,dc=edu].
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [SUDO][dc=xxxx,dc=yyyyy,dc=edu][SUBTREE][]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server!
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_server_opts_from_rootdse] (0x0200): Will use modification timestamp as usn!
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_send] (0x0100): Executing simple bind as: (null)
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_send] (0x2000): ldap simple bind sent, msgid = 2
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad81f0], connected[1], ops[0xae2f50], ldap[0xad8450]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad81f0], connected[1], ops[0xae2f50], ldap[0xad8450]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_done] (0x0200): Server returned no controls.
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_done] (0x0080): Bind result: Success(0), no errmsg set
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_users_next_base] (0x0400): Searching for users with base [dc=xxxx,dc=yyyyy,dc=edu]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(&(uid=gallantm)(objectclass=posixAccount))(posixAccount))][dc=xxxx,dc=yyyyy,dc=edu].
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [displaName]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0080): ldap_search_ext failed: Bad search filter
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_done] (0x0100): sdap_get_generic_ext_recv failed [5]: Input/output error
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0200): communication error on cached connection, moving to next server
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_handle_release] (0x2000): Trace: sh[0xad81f0], connected[1], ops[(nil)], ldap[0xad8450], destructor_lock[0], release_memory[0]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'working'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [get_port_status] (0x1000): Port status of port 389 for server 'ds-10.xxx.yyy.zzz' is 'neutral'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 10 seconds
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'working'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [be_resolve_server_done] (0x1000): Saving the first resolved server
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [be_resolve_server_done] (0x0200): Found address for server ds-10.xxx.yyy.zzz: [192.168.36.1] TTL 3600
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://ds-10.xxx.yyy.zzz:389/??base] with fd [33].
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad8410], connected[1], ops[0xae2250], ldap[0xae2000]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad8410], connected[1], ops[0xae2250], ldap[0xae2000]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server!
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_send] (0x0100): Executing simple bind as: (null)
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_send] (0x2000): ldap simple bind sent, msgid = 2
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad8410], connected[1], ops[0xae2d20], ldap[0xae2000]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad8410], connected[1], ops[0xae2d20], ldap[0xae2000]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_done] (0x0200): Server returned no controls.
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [simple_bind_done] (0x0080): Bind result: Success(0), no errmsg set
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_id_op_connect_done] (0x2000): Old USN: 0, New USN: 0
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_users_next_base] (0x0400): Searching for users with base [dc=xxxx,dc=yyyyy,dc=edu]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(&(uid=gallantm)(objectclass=posixAccount))(posixAccount))][dc=xxxx,dc=yyyyy,dc=edu].
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [displaName]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0080): ldap_search_ext failed: Bad search filter
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_get_generic_done] (0x0100): sdap_get_generic_ext_recv failed [5]: Input/output error
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0200): communication error on cached connection, moving to next server
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [sdap_handle_release] (0x2000): Trace: sh[0xad8410], connected[1], ops[(nil)], ldap[0xae2000], destructor_lock[0], release_memory[0]
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,5,User lookup failed
(Fri Aug 10 14:58:34 2012) [sssd[be[default]]] [check_if_online] (0x2000): Backend is already online, nothing to do.
(Fri Aug 10 14:58:37 2012) [sssd[be[default]]] [check_if_online] (0x2000): Backend is already online, nothing to do.
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [get_port_status] (0x1000): Port status of port 389 for server 'ds-10.xxx.yyy.zzz' is 'neutral'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 10 seconds
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [be_resolve_server_done] (0x1000): Saving the first resolved server
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [be_resolve_server_done] (0x0200): Found address for server ds-10.xxx.yyy.zzz: [192.168.36.1] TTL 3600
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://ds-10.xxx.yyy.zzz:389/??base] with fd [33].
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xae24d0], connected[1], ops[0xae2140], ldap[0xae33b0]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xae24d0], connected[1], ops[0xae2140], ldap[0xae33b0]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server!
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_send] (0x0100): Executing simple bind as: (null)
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_send] (0x2000): ldap simple bind sent, msgid = 2
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xae24d0], connected[1], ops[0xad7340], ldap[0xae33b0]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xae24d0], connected[1], ops[0xad7340], ldap[0xae33b0]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_done] (0x0200): Server returned no controls.
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_done] (0x0080): Bind result: Success(0), no errmsg set
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_id_op_connect_done] (0x2000): Old USN: 0, New USN: 0
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_users_next_base] (0x0400): Searching for users with base [dc=xxxx,dc=yyyyy,dc=edu]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(&(objectclass=posixAccount)(uid=*)(uidNumber=*)(gidNumber=*))(posixAccount))][dc=xxxx,dc=yyyyy,dc=edu].
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [displaName]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0080): ldap_search_ext failed: Bad search filter
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_done] (0x0100): sdap_get_generic_ext_recv failed [5]: Input/output error
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0200): communication error on cached connection, moving to next server
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_handle_release] (0x2000): Trace: sh[0xae24d0], connected[1], ops[(nil)], ldap[0xae33b0], destructor_lock[0], release_memory[0]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [get_port_status] (0x1000): Port status of port 389 for server 'ds-10.xxx.yyy.zzz' is 'neutral'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 10 seconds
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'ds-10.xxx.yyy.zzz' is 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [be_resolve_server_done] (0x1000): Saving the first resolved server
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [be_resolve_server_done] (0x0200): Found address for server ds-10.xxx.yyy.zzz: [192.168.36.1] TTL 3600
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_uri_callback] (0x0400): Constructed uri 'ldap://ds-10.xxx.yyy.zzz/'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://ds-10.xxx.yyy.zzz:389/??base] with fd [33].
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad7ec0], connected[1], ops[0xad8470], ldap[0xae3290]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad7ec0], connected[1], ops[0xad8470], ldap[0xae3290]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad7ec0], connected[1], ops[0xad8470], ldap[0xae3290]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server!
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_send] (0x0100): Executing simple bind as: (null)
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_send] (0x2000): ldap simple bind sent, msgid = 2
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad7ec0], connected[1], ops[0xad7880], ldap[0xae3290]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xad7ec0], connected[1], ops[0xad7880], ldap[0xae3290]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_done] (0x0200): Server returned no controls.
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [simple_bind_done] (0x0080): Bind result: Success(0), no errmsg set
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'ds-10.xxx.yyy.zzz' as 'working'
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_id_op_connect_done] (0x2000): Old USN: 0, New USN: 0
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_users_next_base] (0x0400): Searching for users with base [dc=xxxx,dc=yyyyy,dc=edu]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(&(objectclass=posixAccount)(uid=*)(uidNumber=*)(gidNumber=*))(posixAccount))][dc=xxxx,dc=yyyyy,dc=edu].
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [displaName]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0080): ldap_search_ext failed: Bad search filter
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_get_generic_done] (0x0100): sdap_get_generic_ext_recv failed [5]: Input/output error
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0200): communication error on cached connection, moving to next server
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [sdap_handle_release] (0x2000): Trace: sh[0xad7ec0], connected[1], ops[(nil)], ldap[0xae3290], destructor_lock[0], release_memory[0]
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [ldap_id_enumerate_set_timer] (0x0400): Scheduling next enumeration at 1344625418.407316
(Fri Aug 10 14:58:38 2012) [sssd[be[default]]] [check_if_online] (0x2000): Backend is already online, nothing to do.
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [sbus_remove_watch] (0x2000): 0xad44d0/0xad0e40
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [sbus_remove_watch] (0x2000): 0xad44d0/0xad0df0
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [sbus_dispatch] (0x0080): Connection is not open for dispatching.
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [be_client_destructor] (0x0400): Removed PAM client
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [sbus_remove_watch] (0x2000): 0xac3760/0xacdad0
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [sbus_remove_watch] (0x2000): 0xac3760/0xac3210
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [remove_krb5_info_files] (0x0200): Could not remove [/var/lib/sss/pubconf/kdcinfo.ADS.YY.ZZZ], [2][No such file or directory]
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [remove_krb5_info_files] (0x0200): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.ADS.YY.ZZZ], [2][No such file or directory]
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [sbus_remove_watch] (0x2000): 0xac41a0/0xac3af0
(Fri Aug 10 14:58:41 2012) [sssd[be[default]]] [be_client_destructor] (0x0400): Removed NSS clien