Attachment 608698 Details for Bug 853558 – sssd_$DOMAIN log at debug_level 8

sssd_$DOMAIN log at debug_level 8

sssd_default.log (text/plain), 138.05 KB, created by John Florian on 2012-08-31 20:41:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: John Florian

Created: 2012-08-31 20:41:22 UTC

Size: 138.05 KB

patch

obsolete

>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [server_setup] (0x0400): CONFDB: /var/lib/sss/db/config.ldb
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [recreate_ares_channel] (0x0100): Initializing new c-ares channel
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [resolv_get_family_order] (0x1000): Lookup order: ipv4_first
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_context_init] (0x0400): Created new fail over context, retry timeout is 30
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [confdb_get_domain_internal] (0x0400): No enumeration for [default]!
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sysdb_domain_init_internal] (0x0200): DB File for default: /var/lib/sss/db/cache_default.ldb
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldb] (0x0400): asq: Unable to register control with rootdse!
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_init_connection] (0x0200): Adding connection B7A27F08
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xb7a28220/0xb7a261e8 (16), -/W (enabled)
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [monitor_common_send_id] (0x0100): Sending ID: (%BE_default,1)
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_add_timeout] (0x2000): 0xb7a24840
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [create_socket_symlink] (0x1000): Symlinking the dbus path /var/lib/sss/pipes/private/sbus-dp_default.13066 to a link /var/lib/sss/pipes/private/sbus-dp_default
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_new_server] (0x0400): D-BUS Server listening on unix:path=/var/lib/sss/pipes/private/sbus-dp_default.13066,guid=933d8e4e2d985cac2630f81d50411c0f
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xb7a28be0/0xb7a28c88 (17), R/- (enabled)
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Loading backend [ldap] with path [/usr/lib/sssd/libsss_ldap.so].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_uri has value ldap://10.1.y.z
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_backup_uri has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_search_base has value dc=mydomain,dc=com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_default_bind_dn has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_default_authtok_type has value password
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_default_authtok has no binary value.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_search_timeout has value 6
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_network_timeout has value 6
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_opt_timeout has value 6
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_reqcert has value hard
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_user_search_base has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_user_search_scope has value sub
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_user_search_filter has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_search_base has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_search_scope has value sub
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_search_filter has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_service_search_base has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_search_base has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_full_refresh_interval has value 21600
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_smart_refresh_interval has value 900
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_use_host_filter is TRUE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_hostnames has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_ip has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_include_netgroups is TRUE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sudo_include_regexp is TRUE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_autofs_search_base has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_schema has value rfc2307
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_offline_timeout has value 60
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_force_upper_case_realm is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_enumeration_refresh_timeout has value 300
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_purge_cache_timeout has value 10800
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cacert has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cacertdir has value /etc/openldap/cacerts
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cert has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_key has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_tls_cipher_suite has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_id_use_start_tls is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_id_mapping is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_mech has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_authid has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_realm has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_minssf has value -1
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_krb5_keytab has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_krb5_init_creds is TRUE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_server has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_backup_server has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_realm has value MYDOMAIN.COM
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_canonicalize is TRUE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_pwd_policy has value none
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_referrals is TRUE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option account_cache_expiration has value 0
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_dns_service_name has value ldap
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_krb5_ticket_lifetime has value 86400
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_access_filter has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_netgroup_search_base has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_group_nesting_level has value 2
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_deref has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_account_expire_policy has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_access_order has value filter
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_chpass_uri has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_chpass_backup_uri has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_chpass_dns_service_name has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_chpass_update_last_change is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_enumeration_search_timeout has value 60
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_auth_disable_tls_never_use_in_production is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_page_size has value 1000
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_deref_threshold has value 10
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_sasl_canonicalize is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_connection_expire_timeout has value 900
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_disable_paging is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_idmap_range_min has value 100001
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_idmap_range_max has value 2000100000
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_idmap_range_size has value 200000
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_idmap_autorid_compat is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_idmap_default_domain has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_idmap_default_domain_sid has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_groups_use_matching_rule_in_chain is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option ldap_initgroups_use_matching_rule_in_chain is TRUE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldap_get_options] (0x0400): Option ldap_user_search_base set to dc=mydomain,dc=com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldap_get_options] (0x0400): Option ldap_group_search_base set to dc=mydomain,dc=com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldap_get_options] (0x0400): Option ldap_netgroup_search_base set to dc=mydomain,dc=com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldap_get_options] (0x0400): Option ldap_service_search_base set to dc=mydomain,dc=com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [DEFAULT][dc=mydomain,dc=com][SUBTREE][]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [USER][dc=mydomain,dc=com][SUBTREE][]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [GROUP][dc=mydomain,dc=com][SUBTREE][]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [NETGROUP][dc=mydomain,dc=com][SUBTREE][]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [SERVICE][dc=mydomain,dc=com][SUBTREE][]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_entry_usn has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_rootdse_last_usn has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_object_class has value posixAccount
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_name has value uid
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_pwd has value userPassword
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_uid_number has value uidNumber
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_gid_number has value gidNumber
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_gecos has value gecos
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_home_directory has value homeDirectory
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shell has value loginShell
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_principal has value krbPrincipalName
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_fullname has value cn
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_member_of has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_uuid has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_objectsid has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_primary_group has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_modify_timestamp has value modifyTimestamp
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_entry_usn has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_last_change has value shadowLastChange
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_min has value shadowMin
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_max has value shadowMax
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_warning has value shadowWarning
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_inactive has value shadowInactive
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_expire has value shadowExpire
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_flag has value shadowFlag
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_krb_last_pwd_change has value krbLastPwdChange
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_krb_password_expiration has value krbPasswordExpiration
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_pwd_attribute has value pwdAttribute
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_authorized_service has value authorizedService
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_ad_account_expires has value accountExpires
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_ad_user_account_control has value userAccountControl
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_ns_account_lock has value nsAccountLock
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_authorized_host has value host
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_disabled has value loginDisabled
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_expiration_time has value loginExpirationTime
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_allowed_time_map has value loginAllowedTimeMap
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_user_ssh_public_key has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_object_class has value posixGroup
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_name has value cn
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_pwd has value userPassword
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_gid_number has value gidNumber
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_member has value memberuid
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_uuid has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_objectsid has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_modify_timestamp has value modifyTimestamp
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_group_entry_usn has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_netgroup_object_class has value nisNetgroup
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_netgroup_name has value cn
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_netgroup_member has value memberNisNetgroup
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_netgroup_triple has value nisNetgroupTriple
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_netgroup_uuid has value nsUniqueId
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_netgroup_modify_timestamp has value modifyTimestamp
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_service_object_class has value ipService
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_service_name has value cn
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_service_port has value ipServicePort
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_service_proto has value ipServiceProtocol
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_service_entry_usn has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [krb5_try_kdcip] (0x0100): No KDC found in configuration, trying legacy option
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sssm_ldap_id_init] (0x1000): Service name for discovery set to ldap
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_new_service] (0x0400): Creating new service 'LDAP'
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_urls_init] (0x0400): Added URI ldap://10.1.y.z
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_add_server] (0x0080): Adding new server '10.1.y.z', to service 'LDAP'
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldap_id_cleanup_set_timer] (0x0400): Scheduling next cleanup at 1346444313.127016
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x2000): ID backend target successfully loaded from provider [ldap].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Loading backend [krb5] with path [/usr/lib/sssd/libsss_krb5.so].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_server has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_backup_server has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_realm has value MYDOMAIN.COM
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_ccachedir has value /run/user/%U
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_ccname_template has value DIR:%d/krb5cc
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_auth_timeout has value 15
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_keytab has value /etc/krb5.keytab
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_validate is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_kpasswd has value mydomain.com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_backup_kpasswd has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_store_password_if_offline is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_renewable_lifetime has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_lifetime has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_renew_interval has value 0
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_use_fast has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_fast_principal has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [dp_get_options] (0x0400): Option krb5_canonicalize is FALSE
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [krb5_try_kdcip] (0x0100): No KDC found in configuration, trying legacy option
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_new_service] (0x0400): Creating new service 'KERBEROS'
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [krb5_service_init] (0x0400): No primary or backup servers defined, using service discovery
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_add_srv_server] (0x0400): Adding new SRV server in domain 'unknown', to service 'KERBEROS' using udp
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_add_srv_server] (0x0400): Adding new SRV server in domain 'unknown', to service 'KERBEROS' using tcp
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [krb5_servers_init] (0x0400): Added service lookup
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_new_service] (0x0400): Creating new service 'KPASSWD'
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [fo_add_server] (0x0080): Adding new server 'mydomain.com', to service 'KPASSWD'
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [krb5_servers_init] (0x0400): Added Server mydomain.com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [check_and_export_lifetime] (0x0200): No lifetime configured.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [check_and_export_lifetime] (0x0200): No lifetime configured.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [check_and_export_options] (0x0100): No KDC explicitly configured, using defaults.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [check_and_export_options] (0x0100): ccache is of type DIR
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x2000): AUTH backend target successfully loaded from provider [krb5].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [permit].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x2000): ACCESS backend target successfully loaded from provider [permit].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [krb5] already loaded.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x2000): CHPASS backend target successfully loaded from provider [krb5].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sssm_ldap_id_init] (0x2000): Re-using sdap_id_ctx for this provider
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_sudo_init] (0x2000): Initializing sudo LDAP back end
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldap_get_sudo_options] (0x0200): Option ldap_sudo_search_base set to dc=mydomain,dc=com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [SUDO][dc=mydomain,dc=com][SUBTREE][]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_object_class has value sudoRole
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_name has value cn
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_command has value sudoCommand
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_host has value sudoHost
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_user has value sudoUser
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_option has value sudoOption
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasuser has value sudoRunAsUser
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasgroup has value sudoRunAsGroup
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_notbefore has value sudoNotBefore
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_notafter has value sudoNotAfter
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_order has value sudoOrder
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_sudorule_entry_usn has no value 
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: 10.1.a.b in network 10.1.0.0/16
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: fe80::5054:ff:fe78:f9d2 in network fe80::/64
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_sudo_get_hostnames_send] (0x2000): Found fqdn: test-host.mydomain.com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_sudo_get_hostnames_send] (0x2000): Found hostname: test-host
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x2000): SUDO backend target successfully loaded from provider [ldap].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sssm_ldap_id_init] (0x2000): Re-using sdap_id_ctx for this provider
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_autofs_init] (0x2000): Initializing autofs LDAP back end
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [ldap_get_autofs_options] (0x0200): Option ldap_autofs_search_base set to dc=mydomain,dc=com
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [common_parse_search_base] (0x0100): Search base added: [AUTOFS][dc=mydomain,dc=com][SUBTREE][]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_autofs_map_object_class has value automountMap
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_autofs_map_name has value ou
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_object_class has value automount
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_key has value cn
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_value has value automountInformation
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x2000): autofs backend target successfully loaded from provider [ldap].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x0020): No selinux module provided for [default] !!
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x0200): no module name found in confdb, using [ldap].
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [load_backend_module] (0x1000): Backend [ldap] already loaded.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x0020): No host info module provided for [default] !!
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_process_init] (0x0020): Subdomains are not supported for [default] !!
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [main] (0x0400): Backend provider (default) started!
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sdap_sudo_setup_periodical_refresh] (0x0400): Full refresh scheduled at: 1346444313
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_remove_timeout] (0x2000): 0xb7a24840
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [id_callback] (0x0100): Got id ack and version (1) from Monitor
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Entering.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0xb7a3a768.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_init_connection] (0x0200): Adding connection B7A3A768
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xb7a28798/0xb7a24840 (19), -/W (disabled)
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Got a connection
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0xb7a3bcc8]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Entering.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0xb7a3b000.
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_init_connection] (0x0200): Adding connection B7A3B000
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_add_watch] (0x2000): 0xb7a40750/0xb7a3aad8 (20), -/W (disabled)
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [sbus_server_init_new_connection] (0x0200): Got a connection
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0xb7a408b0]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [client_registration] (0x0100): Cancel DP ID timeout [0xb7a408b0]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [client_registration] (0x0100): Added Frontend client [PAM]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [client_registration] (0x0100): Cancel DP ID timeout [0xb7a3bcc8]
>(Fri Aug 31 16:18:23 2012) [sssd[be[default]]] [client_registration] (0x0100): Added Frontend client [NSS]
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4098][1][idnumber=698]
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP'
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server '10.1.y.z' is 'name not resolved'
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [get_port_status] (0x1000): Port status of port 389 for server '10.1.y.z' is 'neutral'
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 10 seconds
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server '10.1.y.z' is 'name not resolved'
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server '10.1.y.z' as 'resolving name'
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server '10.1.y.z' as 'name resolved'
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [be_resolve_server_process] (0x1000): Saving the first resolved server
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [be_resolve_server_process] (0x0200): Found address for server 10.1.y.z: [10.1.y.z] TTL 7200
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [sdap_uri_callback] (0x0400): Constructed uri 'ldap://10.1.y.z'
>(Fri Aug 31 16:18:26 2012) [sssd[be[default]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://10.1.y.z:389/??base] with fd [21].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4ce88], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [namingContexts]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedControl]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedExtension]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedLDAPVersion]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4ce88], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_rootdse_done] (0x2000): Got rootdse
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_rootdse_done] (0x2000): Auto-detecting support for match rule
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(sssmatchingruletest:1.2.840.113556.1.4.1941:=)][].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sssmatchingruletest]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 2
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4cc10], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_matching_rule_done] (0x0100): LDAP server supports the matching rule extension
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_server_opts_from_rootdse] (0x0200): Will use modification timestamp as usn!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_cli_auth_step] (0x1000): No authentication requested or SASL auth forced off
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [fo_set_port_status] (0x0100): Marking port 389 of server '10.1.y.z' as 'working'
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server '10.1.y.z' as 'working'
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=698)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberuid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 3
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a433d8], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a433d8], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberUid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a433d8], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): Member already cached in sysdb: my_user_name
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #1 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #2 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_send] (0x1000): All group members processed
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x2000): This is a posix group
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=pupadmin,ou=Group,dc=mydomain,dc=com] to attributes of [pupadmin].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20120517152713Z] to attributes of [pupadmin].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x1000): Original USN value is not available for [pupadmin].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x0400): Storing info for group pupadmin
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4098][1][idnumber=699]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=699)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberuid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 4
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d960], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberUid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d960], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): Member already cached in sysdb: my_user_name
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #1 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #2 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #3 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #4 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #5 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #6 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_send] (0x1000): All group members processed
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x2000): This is a posix group
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=sudoers,ou=Group,dc=mydomain,dc=com] to attributes of [sudoers].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20120228205441Z] to attributes of [sudoers].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x1000): Original USN value is not available for [sudoers].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x0400): Storing info for group sudoers
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4098][1][idnumber=700]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=700)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberuid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 5
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d530], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [userPassword]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberUid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d530], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #0 (ck9174): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=ck9174))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #1 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #2 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #3 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #4 (python): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=python))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #5 (root): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=root))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #6 (lh5717): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=lh5717))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #7 (cvs): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=cvs))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #8 (anonymous): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=anonymous))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #9 (d9792): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=d9792))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #10 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #11 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): Member already cached in sysdb: my_user_name
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #13 (d9744): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=d9744))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #14 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #15 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #16 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #17 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #18 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #19 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #20 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #21 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #22 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #23 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_send] (0x1000): All group members processed
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x2000): This is a posix group
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=cvsusers,ou=Group,dc=mydomain,dc=com] to attributes of [cvsusers].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20120216164104Z] to attributes of [cvsusers].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x1000): Original USN value is not available for [cvsusers].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x0400): Storing info for group cvsusers
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4098][1][idnumber=2001]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=2001)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberuid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 6
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4cd30], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberUid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4cd30], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #0 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #1 (d9792): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=d9792))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #2 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #3 (dg6958): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=dg6958))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #4 (mtennes): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=mtennes))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #5 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #6 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #7 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #8 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #9 (bm8918): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=bm8918))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #10 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): Member already cached in sysdb: my_user_name
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #12 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #13 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #14 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #15 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #16 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #17 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_send] (0x1000): All group members processed
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x2000): This is a posix group
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=mdct,ou=Group,dc=mydomain,dc=com] to attributes of [mdct].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20120216164137Z] to attributes of [mdct].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x1000): Original USN value is not available for [mdct].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x0400): Storing info for group mdct
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4098][1][idnumber=10385]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=10385)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberuid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 7
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a42f88], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberUid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a42f88], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #0 (ranslide): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=ranslide))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #1 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #2 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #3 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #4 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #5 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): Member already cached in sysdb: my_user_name
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_send] (0x1000): All group members processed
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x2000): This is a posix group
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=ranslide,ou=Group,dc=mydomain,dc=com] to attributes of [ranslide].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20110930182125Z] to attributes of [ranslide].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x1000): Original USN value is not available for [ranslide].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x0400): Storing info for group ranslide
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4098][1][idnumber=10398]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=10398)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberuid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 8
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d9d0], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberUid]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d9d0], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #0 (isdslide): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=isdslide))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #1 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #2 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): Member already cached in sysdb: my_user_name
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #4 (APP_First2Warn): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=APP_First2Warn))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #5 (dother_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=dother_member_user_name))
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_process_group_send] (0x1000): All group members processed
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x2000): This is a posix group
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=isdslide,ou=Group,dc=mydomain,dc=com] to attributes of [isdslide].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20101004132330Z] to attributes of [isdslide].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x1000): Original USN value is not available for [isdslide].
>(Fri Aug 31 16:18:27 2012) [sssd[be[default]]] [sdap_save_group] (0x0400): Storing info for group isdslide
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [4098][1][idnumber=10413]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=10413)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberuid]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 9
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d5f0], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberUid]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d5f0], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #0 (leoslide): not found in sysdb
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=leoslide))
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): Member already cached in sysdb: my_user_name
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #2 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_user_by_name] (0x0400): No such entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_group_members_2307] (0x1000): member #3 (other_member_user_name): not found in sysdb
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(nameAlias=other_member_user_name))
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_group_send] (0x1000): All group members processed
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_save_group] (0x2000): This is a posix group
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=leoslide,ou=Group,dc=mydomain,dc=com] to attributes of [leoslide].
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20110930182015Z] to attributes of [leoslide].
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_save_group] (0x1000): Original USN value is not available for [leoslide].
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_save_group] (0x0400): Storing info for group leoslide
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [be_get_subdomains] (0x0400): Got get subdomains [not forced][]
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [be_get_subdomains] (0x0100): Undefined backend target.
>(Fri Aug 31 16:18:28 2012) [sssd[be[default]]] [be_get_subdomains] (0x1000): Request processed. Returned 3,19,Subdomains back end target is not configured
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_get_account_info] (0x0100): Got request for [3][1][name=my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=my_user_name)(objectclass=posixAccount))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 10
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d330], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [uidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [homeDirectory]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [loginShell]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gecos]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [userPassword]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [shadowLastChange]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a4d330], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [uid=my_user_name,ou=People,dc=mydomain,dc=com] to attributes of [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_save_user] (0x1000): Original memberOf is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20120628172442Z] to attributes of [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_save_user] (0x1000): Original USN value is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_save_user] (0x1000): User principal is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding shadowLastChange [15519] to attributes of [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMin is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMax is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowWarning is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowInactive is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowExpire is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowFlag is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): krbLastPwdChange is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): krbPasswordExpiration is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): pwdAttribute is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedService is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): adAccountExpires is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): adUserAccountControl is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): nsAccountLock is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedHost is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginDisabled is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginExpirationTime is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginAllowedTimeMap is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_attrs_add_ldap_attr] (0x2000): sshPublicKey is not available for [my_user_name].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_save_user] (0x0400): Storing info for user my_user_name
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userPrincipalName] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMin] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMax] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowWarning] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowInactive] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowExpire] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowFlag] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [krbLastPwdChange] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [krbPasswordExpiration] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [pwdAttribute] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedService] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adAccountExpires] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adUserAccountControl] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [nsAccountLock] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedHost] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginDisabled] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginExpirationTime] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginAllowedTimeMap] from [my_user_name]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_initgr_rfc2307_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(memberuid=my_user_name)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 11
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [userPassword]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59e90], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_initgr_common_store] (0x2000): Updating memberships for my_user_name
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_id_op_done] (0x0400): Connection is marked for disconnection, executing ...
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[(nil)], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_pam_handler] (0x0100): Got request with the following data
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): command: PAM_AUTHENTICATE
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): domain: default
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): user: my_user_name
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): service: sudo
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): tty: /dev/pts/1
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): ruser: my_user_name
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): rhost: 
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): authtok type: 1
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): authtok size: 8
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): priv: 0
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [pam_print_data] (0x0100): cli_pid: 13071
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [krb5_pam_handler] (0x1000): Wait queue of user [my_user_name] is empty, running request immediately.
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [cc_residual_is_used] (0x1000): User [my_uid] is still active, reusing ccache [/run/user/my_uid/ccdir].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [cc_dir_check_existing] (0x0400): ccache DIR::/run/user/my_uid/ccdir/tktMCZUqh is missing or empty
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'KERBEROS'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [get_port_status] (0x1000): Port status of port 0 for server '(no name)' is 'neutral'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 10 seconds
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_send] (0x0400): The status of SRV lookup is neutral
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_get_domain_send] (0x1000): Host name is: test-host.mydomain.com
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'test-host.mydomain.com' in files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of 'test-host.mydomain.com' in files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'test-host.mydomain.com' in DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 5 seconds
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [request_watch_destructor] (0x0400): Deleting request watch
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve AAAA record of 'test-host.mydomain.com' in DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 5 seconds
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [request_watch_destructor] (0x0400): Deleting request watch
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_next] (0x0100): No more hosts databases to retry
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_get_domain_done] (0x0040): Could not get fully qualified name for host name test-host.mydomain.com error [2]: No such file or directory, resolver returned: [4]: Domain name not found
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_cont] (0x0100): Searching for servers via SRV query '_KERBEROS._udp.mydomain.com'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_KERBEROS._udp.mydomain.com'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 5 seconds
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [request_watch_destructor] (0x0400): Deleting request watch
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'lkf-dc01.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'lan-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'wax-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'mas-dc03.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'atl-dc03.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'wmd-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'cor-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'hrc-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'tij-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'qmn-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'hlt-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'dfb-dc03.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'ran-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'cam-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'plr-dc03.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'mas-dc04.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'tum-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'smt-dc03.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'lit-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'owb-dc09.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'aur-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'leo-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'lod-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'plc-dc02.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolve_srv_done] (0x0400): Inserted server 'dpi-dc01.mydomain.com:88' for service KERBEROS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service 'KERBEROS' as 'resolved'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'lkf-dc01.mydomain.com' is 'name not resolved'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'lkf-dc01.mydomain.com' in files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'lkf-dc01.mydomain.com' as 'resolving name'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of 'lkf-dc01.mydomain.com' in files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'lkf-dc01.mydomain.com' in DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 5 seconds
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [request_watch_destructor] (0x0400): Deleting request watch
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'lkf-dc01.mydomain.com' as 'name resolved'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_resolve_server_process] (0x1000): Saving the first resolved server
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_resolve_server_process] (0x0200): Found address for server lkf-dc01.mydomain.com: [10.101.p.q] TTL 3600
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'KPASSWD'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'mydomain.com' is 'name not resolved'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [get_port_status] (0x1000): Port status of port 0 for server 'mydomain.com' is 'neutral'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 10 seconds
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [get_server_status] (0x1000): Status of server 'mydomain.com' is 'name not resolved'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'mydomain.com' in files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'mydomain.com' as 'resolving name'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of 'mydomain.com' in files
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_step] (0x2000): Querying DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'mydomain.com' in DNS
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 5 seconds
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [request_watch_destructor] (0x0400): Deleting request watch
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [set_server_common_status] (0x0100): Marking server 'mydomain.com' as 'name resolved'
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_resolve_server_process] (0x1000): Saving the first resolved server
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_resolve_server_process] (0x0200): Found address for server mydomain.com: [10.77.m.n] TTL 600
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [krb5_find_ccache_step] (0x0080): Saved ccache Fri Aug 31 16:18:31 if of different type than ccache in configuration file, reusing the old ccache
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [13072]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [become_user] (0x0200): Trying to become user [my_uid][my_uid].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [child_handler_setup] (0x2000): Signal handler set up for pid [13072]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [write_pipe_handler] (0x0400): All data has been sent!
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [read_pipe_handler] (0x0400): EOF received, client finished
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [parse_krb5_child_response] (0x1000): child response [4][1][64].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [parse_krb5_child_response] (0x1000): child response [4][6][8].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [check_wait_queue] (0x1000): Wait queue for user [my_user_name] is empty.
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 4, <NULL>) [Success]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_pam_handler_callback] (0x0100): Sending result [4][default]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [be_pam_handler_callback] (0x0100): Sent result [4][default]
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [child_sig_handler] (0x1000): Waiting for child [13072].
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [child_sig_handler] (0x0100): child [13072] finished successfully.
>(Fri Aug 31 16:18:31 2012) [sssd[be[default]]] [sss_child_handler] (0x2000): waitpid failed [10]: No child processes
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): Search users with filter: (&(objectclass=user)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1346444313)(!(lastLogin=*))))
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sysdb_search_users] (0x2000): No such entry
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sysdb_search_groups] (0x2000): Search groups with filter: (&(objectclass=group)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1346444313)))
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sysdb_search_groups] (0x2000): No such entry
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [ldap_id_cleanup_set_timer] (0x0400): Scheduling next cleanup at 1346455113.128085
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_sudo_full_refresh_send] (0x0400): Issuing a full refresh of sudo rules
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_sudo_refresh_connect_done] (0x0400): SUDO LDAP connection successful
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_sudo_load_sudoers_next_base] (0x0400): Searching for sudo rules with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=sudoRole)(|(sudoHost=ALL)(sudoHost=test-host.mydomain.com)(sudoHost=test-host)(sudoHost=10.1.a.b)(sudoHost=10.1.0.0/16)(sudoHost=fe80::5054:ff:fe78:f9d2)(sudoHost=fe80::/64)(sudoHost=+*)(|(sudoHost=*\\*)(sudoHost=*?*)(sudoHost=*\**)(sudoHost=*[*]*))))][dc=mydomain,dc=com].
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoCommand]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoHost]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoUser]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOption]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsUser]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsGroup]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotBefore]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotAfter]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOrder]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 12
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59cc8], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_process_message] (0x0040): Unmatched msgid, discarding message (type: 78)
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_process_result] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59cc8], ldap[0xb7a395c8]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_process_result] (0x0040): ldap_result error: [Can't contact LDAP server]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_handle_release] (0x2000): Trace: sh[0xb7a434c8], connected[1], ops[0xb7a59cc8], ldap[0xb7a395c8], destructor_lock[0], release_memory[0]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_get_generic_done] (0x0100): sdap_get_generic_ext_recv failed [5]: Input/output error
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_sudo_load_sudoers_process] (0x0400): Receiving sudo rules with base [dc=mydomain,dc=com]
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_sudo_schedule_full_refresh] (0x0400): Full refresh scheduled at: 1346465913
>(Fri Aug 31 16:18:33 2012) [sssd[be[default]]] [sdap_sudo_schedule_smart_refresh] (0x0400): Smart refresh scheduled at: 1346445213

Actions: View

Attachments on bug 853558: 608698 | 611834