(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [server_setup] (0x0400): CONFDB: /var/lib/sss/db/config.ldb
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option lookup_family_order has value ipv4_first
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option dns_resolver_timeout has value 6
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option dns_resolver_op_timeout has value 6
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option dns_discovery_domain has value inf.ed.ac.uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_res_get_opts] (0x0100): Lookup order: ipv4_first
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [recreate_ares_channel] (0x0100): Initializing new c-ares channel
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_context_init] (0x0400): Created new fail over context, retry timeout is 30
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [confdb_get_domain_internal] (0x0400): No enumeration for [INF]!
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [confdb_get_domain_internal] (0x0400): Please note that when enumeration is disabled `getent passwd` does not return all users by design. See sssd.conf man page for more detailed information
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sysdb_domain_init_internal] (0x0200): DB File for INF: /var/lib/sss/db/cache_INF.ldb
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sysdb_domain_init_internal] (0x0200): Timestamp file for INF: /var/lib/sss/db/timestamps_INF.ldb
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [ldb] (0x0400): asq: Unable to register control with rootdse!
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_init_connection] (0x0400): Adding connection 0x55bf8e289350
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.service with path /org/freedesktop/sssd/service
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_conn_register_path] (0x0400): Registering object path /org/freedesktop/sssd/service with D-Bus connection
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Properties with path /org/freedesktop/sssd/service
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Introspectable with path /org/freedesktop/sssd/service
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [monitor_common_send_id] (0x0100): Sending ID: (%BE_INF,1)
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sss_names_init_from_args] (0x0100): Using re [(?P<name>[^@]+)@?(?P<domain>[^@]*$)].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sss_fqnames_init] (0x0100): Using fq format [%1$s@%2$s].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_new_server] (0x0400): D-BUS Server listening on unix:path=/var/lib/sss/pipes/private/sbus-dp_INF.5999,guid=bce0592a6c72ffc38ea970bb5cdd2d9a
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [ldap] provider for [id]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [krb5] provider for [auth]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [permit] provider for [access]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [krb5] provider for [chpass]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [ldap] provider for [sudo]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [ldap] provider for [autofs]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [ldap] provider for [selinux]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [ldap] provider for [hostid]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [ldap] provider for [subdomains]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_configuration] (0x0100): Using [ldap] provider for [session]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [id] with module [ldap]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): About to load module [ldap].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_module_run_constructor] (0x0400): Executing module [ldap] constructor.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_uri has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_backup_uri has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_search_base has value dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_default_bind_dn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_default_authtok_type has value password
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_default_authtok has no binary value.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_search_timeout has value 6
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_network_timeout has value 6
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_opt_timeout has value 6
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_tls_reqcert has value demand
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_user_search_base has value ou=People,dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_user_search_scope has value sub
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_user_search_filter has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_user_extra_attrs has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_group_search_base has value ou=Group,dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_group_search_scope has value sub
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_group_search_filter has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_host_search_base has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_service_search_base has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_search_base has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_full_refresh_interval has value 21600
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_smart_refresh_interval has value 900
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_use_host_filter is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_hostnames has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_ip has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_include_netgroups is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sudo_include_regexp is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_autofs_search_base has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_autofs_map_master_name has value auto.master
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_schema has value rfc2307
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_offline_timeout has value 60
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_force_upper_case_realm is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_enumeration_refresh_timeout has value 300
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_purge_cache_timeout has value 0
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_tls_cacert has value /etc/pki/tls/certs/dice-sixkts-2018.crt
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_tls_cacertdir has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_tls_cert has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_tls_key has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_tls_cipher_suite has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_id_use_start_tls is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_id_mapping is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sasl_mech has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sasl_authid has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sasl_realm has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sasl_minssf has value -1
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_krb5_keytab has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_krb5_init_creds is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_server has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_backup_server has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_realm has value INF.ED.AC.UK
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_canonicalize is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_use_kdcinfo is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_pwd_policy has value none
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_referrals is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option account_cache_expiration has value 0
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_dns_service_name has value ldap
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_krb5_ticket_lifetime has value 86400
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_access_filter has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_netgroup_search_base has value ou=Netgroup,dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_group_nesting_level has value 2
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_deref has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_account_expire_policy has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_access_order has value filter
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_chpass_uri has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_chpass_backup_uri has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_chpass_dns_service_name has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_chpass_update_last_change is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_enumeration_search_timeout has value 60
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_auth_disable_tls_never_use_in_production is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_page_size has value 1000
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_deref_threshold has value 10
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_sasl_canonicalize is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_connection_expire_timeout has value 900
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_disable_paging is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_idmap_range_min has value 200000
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_idmap_range_max has value 2000200000
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_idmap_range_size has value 200000
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_idmap_autorid_compat is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_idmap_default_domain has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_idmap_default_domain_sid has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_idmap_helper_table_size has value 10
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_groups_use_matching_rule_in_chain is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_initgroups_use_matching_rule_in_chain is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_use_tokengroups is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_rfc2307_fallback_to_local_users is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_disable_range_retrieval is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_min_id has value 0
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_max_id has value 0
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option ldap_pwdlockout_dn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option wildcard_limit has value 1000
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [ldap_get_options] (0x0400): Option ldap_host_search_base set to dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [ldap_get_options] (0x0400): Option ldap_service_search_base set to dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [DEFAULT][dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [USER][ou=People,dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [GROUP][ou=Group,dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [NETGROUP][ou=Netgroup,dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [HOST][dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [SERVICE][dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_entry_usn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_rootdse_last_usn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_object_class has value posixAccount
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_name has value uid
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_pwd has value userPassword
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_uid_number has value uidNumber
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_gid_number has value gidNumber
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_gecos has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_home_directory has value afsHomeDirectory
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shell has value loginShell
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_principal has value krbPrincipalName
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_fullname has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_member_of has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_uuid has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_objectsid has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_primary_group has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_modify_timestamp has value modifyTimestamp
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_entry_usn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_last_change has value shadowLastChange
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_min has value shadowMin
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_max has value shadowMax
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_warning has value shadowWarning
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_inactive has value shadowInactive
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_expire has value shadowExpire
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_shadow_flag has value shadowFlag
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_krb_last_pwd_change has value krbLastPwdChange
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_krb_password_expiration has value krbPasswordExpiration
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_pwd_attribute has value pwdAttribute
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_authorized_service has value authorizedService
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_ad_account_expires has value accountExpires
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_ad_user_account_control has value userAccountControl
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_ns_account_lock has value nsAccountLock
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_authorized_host has value host
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_authorized_rhost has value rhost
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_disabled has value loginDisabled
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_expiration_time has value loginExpirationTime
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_allowed_time_map has value loginAllowedTimeMap
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_ssh_public_key has value sshPublicKey
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_auth_type has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_certificate has value userCertificate;binary
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_user_email has value mail
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_object_class has value posixGroup
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_object_class_alt has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_name has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_pwd has value userPassword
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_gid_number has value gidNumber
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_member has value memberuid
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_uuid has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_objectsid has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_modify_timestamp has value modifyTimestamp
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_entry_usn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_type has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_group_external_member has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_netgroup_object_class has value nisNetgroup
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_netgroup_name has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_netgroup_member has value memberNisNetgroup
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_netgroup_triple has value nisNetgroupTriple
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_netgroup_modify_timestamp has value modifyTimestamp
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_host_object_class has value ipHost
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_host_name has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_host_fqdn has value fqdn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_host_serverhostname has value serverHostname
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_host_member_of has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_host_ssh_public_key has value sshPublicKey
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_host_uuid has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_service_object_class has value ipService
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_service_name has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_service_port has value ipServicePort
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_service_proto has value ipServiceProtocol
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_service_entry_usn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [krb5_try_kdcip] (0x0100): No KDC found in configuration, trying legacy option
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [get_sdap_service] (0x0100): Service name for discovery set to ldap
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_new_service] (0x0400): Creating new service 'LDAP'
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_service_init] (0x0100): No primary servers defined, using service discovery
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_add_srv_server] (0x0400): Adding new SRV server to service 'LDAP' using 'tcp'.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [_sdap_urls_init] (0x0400): Added service lookup
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sysdb_idmap_get_mappings] (0x0080): Could not locate ID mappings: [No such file or directory]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_id_setup_tasks] (0x0400): Setting up cleanup task for INF
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_fo_set_srv_lookup_plugin] (0x0400): Trying to set SRV lookup plugin to DNS
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_fo_set_srv_lookup_plugin] (0x0400): SRV lookup plugin is now DNS
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_run_constructor] (0x0400): Executing target [id] constructor
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [auth] with module [krb5]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): About to load module [krb5].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_module_run_constructor] (0x0400): Executing module [krb5] constructor.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_server has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_backup_server has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_realm has value INF.ED.AC.UK
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_ccachedir has value /tmp
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_ccname_template has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_auth_timeout has value 6
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_keytab has value /etc/krb5.keytab
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_validate is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_kpasswd has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_backup_kpasswd has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_store_password_if_offline is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_renewable_lifetime has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_lifetime has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_renew_interval has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_use_fast has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_fast_principal has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_canonicalize is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_use_enterprise_principal is FALSE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_use_kdcinfo is TRUE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_get_options] (0x0400): Option krb5_map_user has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [krb5_try_kdcip] (0x0100): No KDC found in configuration, trying legacy option
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [krb5_service_new] (0x0100): write_kdcinfo for realm INF.ED.AC.UK set to true
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_new_service] (0x0400): Creating new service 'KERBEROS'
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [krb5_service_init] (0x0100): No primary servers defined, using service discovery
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_add_srv_server] (0x0400): Adding new SRV server to service 'KERBEROS' using 'udp'.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_add_srv_server] (0x0400): Adding new SRV server to service 'KERBEROS' using 'tcp'.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [_krb5_servers_init] (0x0400): Added service lookup
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [krb5_service_new] (0x0100): write_kdcinfo for realm INF.ED.AC.UK set to true
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_new_service] (0x0400): Creating new service 'KPASSWD'
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [krb5_service_init] (0x0100): No primary servers defined, using service discovery
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_add_srv_server] (0x0400): Adding new SRV server to service 'KPASSWD' using 'udp'.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_add_srv_server] (0x0400): Adding new SRV server to service 'KPASSWD' using 'tcp'.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [_krb5_servers_init] (0x0400): Added service lookup
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [check_lifetime] (0x0200): No lifetime configured.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [check_lifetime] (0x0200): No lifetime configured.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sss_krb5_check_options] (0x0100): No KDC explicitly configured, using defaults.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sss_krb5_check_options] (0x0100): No kpasswd server explicitly configured, using the KDC or defaults.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sss_krb5_check_options] (0x0100): ccache is of type FILE
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [parse_krb5_map_user] (0x0100): krb5_map_user is empty!
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_fo_set_srv_lookup_plugin] (0x0400): Trying to set SRV lookup plugin to DNS
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [fo_set_srv_lookup_plugin] (0x0080): SRV lookup plugin is already set
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_fo_set_srv_lookup_plugin] (0x0080): Unable to set SRV lookup plugin, another plugin may be already in place
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_run_constructor] (0x0400): Executing target [auth] constructor
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [access] with module [permit]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [chpass] with module [krb5]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): Module [krb5] is already loaded.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_run_constructor] (0x0400): Executing target [chpass] constructor
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [sudo] with module [ldap]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): Module [ldap] is already loaded.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_run_constructor] (0x0400): Executing target [sudo] constructor
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [ldap_get_sudo_options] (0x0200): Option ldap_sudo_search_base set to dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [SUDO][dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_object_class has value sudoRole
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_name has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_command has value sudoCommand
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_host has value sudoHost
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_user has value sudoUser
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_option has value sudoOption
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_runas has value sudoRunAs
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasuser has value sudoRunAsUser
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasgroup has value sudoRunAsGroup
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_notbefore has value sudoNotBefore
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_notafter has value sudoNotAfter
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_order has value sudoOrder
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_sudorule_entry_usn has no value 
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_ptask_create] (0x0400): Periodic task [SUDO Full Refresh] was created
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_ptask_schedule] (0x0400): Task [SUDO Full Refresh]: scheduling task 10 seconds from now [1557999012]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_ptask_create] (0x0400): Periodic task [SUDO Smart Refresh] was created
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [be_ptask_schedule] (0x0400): Task [SUDO Smart Refresh]: scheduling task 910 seconds from now [1557999912]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [autofs] with module [ldap]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): Module [ldap] is already loaded.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_run_constructor] (0x0400): Executing target [autofs] constructor
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [ldap_get_autofs_options] (0x0200): Option ldap_autofs_search_base set to dc=inf,dc=ed,dc=ac,dc=uk
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [common_parse_search_base] (0x0100): Search base added: [AUTOFS][dc=inf,dc=ed,dc=ac,dc=uk][SUBTREE][]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_autofs_map_object_class has value nisMap
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_autofs_map_name has value nisMapName
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_object_class has value nisObject
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_key has value cn
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_value has value nisMapEntry
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [selinux] with module [ldap]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): Module [ldap] is already loaded.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0100): Target [selinux] is not supported by module [ldap].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [hostid] with module [ldap]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): Module [ldap] is already loaded.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_run_constructor] (0x0400): Executing target [hostid] constructor
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [subdomains] with module [ldap]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): Module [ldap] is already loaded.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0100): Target [subdomains] is not supported by module [ldap].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0400): Initializing target [session] with module [ldap]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_load_module] (0x0400): Module [ldap] is already loaded.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_target_init] (0x0100): Target [session] is not supported by module [ldap].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [become_user] (0x0200): Trying to become user [0][0].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [become_user] (0x0200): Already user [0].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [main] (0x0400): Backend provider (INF) started!
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [id_callback] (0x0100): Got id ack and version (1) from Monitor
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_server_init_new_connection] (0x0200): Entering.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0x55bf8e29e540.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_init_connection] (0x0400): Adding connection 0x55bf8e29e540
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_server_init_new_connection] (0x0200): Got a connection
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55bf8e2a3430]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.Client with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_conn_register_path] (0x0400): Registering object path /org/freedesktop/sssd/dataprovider with D-Bus connection
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Properties with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Introspectable with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.dataprovider with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.Backend with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.Failover with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.AccessControl with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_server_init_new_connection] (0x0200): Entering.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0x55bf8e2a7a00.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_init_connection] (0x0400): Adding connection 0x55bf8e2a7a00
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_server_init_new_connection] (0x0200): Got a connection
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55bf8e2a8d90]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.Client with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_conn_register_path] (0x0400): Registering object path /org/freedesktop/sssd/dataprovider with D-Bus connection
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Properties with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Introspectable with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.dataprovider with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.Backend with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.Failover with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.DataProvider.AccessControl with path /org/freedesktop/sssd/dataprovider
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55bf8e2a3430]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_client_register] (0x0100): Added Frontend client [InfoPipe]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_attach_req] (0x0400): DP Request [Subdomains #0]: New request. Flags [0000].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_attach_req] (0x0400): Number of active DP request: 1
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_find_method] (0x0100): Target [subdomains] is not initialized
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [_dp_req_recv] (0x0400): DP Request [Subdomains #0]: Receiving request data.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_req_reply_gen_error] (0x0080): DP Request [Subdomains #0]: Finished. Target is not supported with this configuration.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_table_value_destructor] (0x0400): Removing [8:8:0000:<ALL>] from reply table
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_req_destructor] (0x0400): DP Request [Subdomains #0]: Request removed.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_req_destructor] (0x0400): Number of active DP request: 0
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55bf8e2a8d90]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_client_register] (0x0100): Added Frontend client [NSS]
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_attach_req] (0x0400): DP Request [Subdomains #1]: New request. Flags [0000].
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_attach_req] (0x0400): Number of active DP request: 1
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_find_method] (0x0100): Target [subdomains] is not initialized
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [_dp_req_recv] (0x0400): DP Request [Subdomains #1]: Receiving request data.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_req_reply_gen_error] (0x0080): DP Request [Subdomains #1]: Finished. Target is not supported with this configuration.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_table_value_destructor] (0x0400): Removing [8:8:0000:<ALL>] from reply table
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_req_destructor] (0x0400): DP Request [Subdomains #1]: Request removed.
(Thu May 16 10:30:02 2019) [sssd[be[INF]]] [dp_req_destructor] (0x0400): Number of active DP request: 0
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_ptask_execute] (0x0400): Task [SUDO Full Refresh]: executing task, timeout 21600 seconds
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_full_refresh_send] (0x0400): Issuing a full refresh of sudo rules
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [resolve_srv_send] (0x0200): The status of SRV lookup is neutral
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will use DNS discovery domain 'inf.ed.ac.uk'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_ldap._tcp.inf.ed.ac.uk'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [request_watch_destructor] (0x0400): Deleting request watch
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_discover_srv_done] (0x0400): Got answer. Processing...
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_discover_srv_done] (0x0400): Got 7 servers
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'redmires.inf.ed.ac.uk:389' to service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'hutter.inf.ed.ac.uk:389' to service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'schneider.inf.ed.ac.uk:389' to service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'nelson.inf.ed.ac.uk:389' to service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'damflask.inf.ed.ac.uk:389' to service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'campbell.inf.ed.ac.uk:389' to service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'klein.inf.ed.ac.uk:389' to service 'LDAP'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service 'LDAP' as 'resolved'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'redmires.inf.ed.ac.uk' in files
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [set_server_common_status] (0x0100): Marking server 'redmires.inf.ed.ac.uk' as 'resolving name'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of 'redmires.inf.ed.ac.uk' in files
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'redmires.inf.ed.ac.uk' in DNS
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [request_watch_destructor] (0x0400): Deleting request watch
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [set_server_common_status] (0x0100): Marking server 'redmires.inf.ed.ac.uk' as 'name resolved'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_resolve_server_process] (0x0200): Found address for server redmires.inf.ed.ac.uk: [129.215.32.61] TTL 2401
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_uri_callback] (0x0400): Constructed uri 'ldap://redmires.inf.ed.ac.uk:389'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sssd_async_socket_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sys_connect_done] (0x0100): Executing START TLS
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_connect_done] (0x0080): START TLS result: Success(0), (null)
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server!
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_get_server_opts_from_rootdse] (0x0200): Will use modification timestamp as usn!
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_cli_connect_recv] (0x0400): Connection established.
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'redmires.inf.ed.ac.uk' as 'working'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [set_server_common_status] (0x0100): Marking server 'redmires.inf.ed.ac.uk' as 'working'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [fo_set_port_status] (0x0400): Marking port 389 of duplicate server 'redmires.inf.ed.ac.uk' as 'working'
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_refresh_connect_done] (0x0400): SUDO LDAP connection successful
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [check_ipv4_addr] (0x0200): Loopback IPv4 address 127.0.0.1
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [check_ipv6_addr] (0x0200): Loopback IPv6 address ::1
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_run_online_cb] (0x0080): Going online. Running callbacks.
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_load_sudoers_send] (0x0400): About to fetch sudo rules
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_search_bases_ex_next_base] (0x0400): Issuing LDAP lookup with base [dc=inf,dc=ed,dc=ac,dc=uk]
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=sudoRole)(|(&(!(sudoHost=*))(cn=defaults))(sudoHost=ALL)(sudoHost=dilley.inf.ed.ac.uk)(sudoHost=dilley)(sudoHost=129.215.202.54)(sudoHost=129.215.202.0/24)(sudoHost=2001:630:3c1:202:216:3eff:fe0d:f1c5)(sudoHost=2001:630:3c1:202::/64)(sudoHost=fe80::216:3eff:fe0d:f1c5)(sudoHost=fe80::/64)(sudoHost=+*)(|(sudoHost=*\\*)(sudoHost=*?*)(sudoHost=*\2A*)(sudoHost=*[*]*))))][dc=inf,dc=ed,dc=ac,dc=uk].
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_search_bases_ex_done] (0x0400): Receiving data from base [dc=inf,dc=ed,dc=ac,dc=uk]
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_load_sudoers_done] (0x0040): Received 0 sudo rules
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_refresh_done] (0x0400): Received 0 rules
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sysdb_sudo_purge_all] (0x0400): Deleting all cached sudo rules
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_refresh_done] (0x0400): Sudoers is successfully stored in cache
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_set_usn] (0x0400): SUDO USN value is empty.
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_full_refresh_done] (0x0400): Successful full refresh of sudo rules
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_ptask_done] (0x0400): Task [SUDO Full Refresh]: finished successfully
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_ptask_schedule] (0x0400): Task [SUDO Full Refresh]: scheduling task 21600 seconds from last execution time [1558020612]
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_ptask_online_cb] (0x0400): Back end is online
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_ptask_enable] (0x0080): Task [SUDO Smart Refresh]: already enabled
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_ptask_online_cb] (0x0400): Back end is online
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [be_ptask_enable] (0x0080): Task [SUDO Full Refresh]: already enabled
(Thu May 16 10:30:12 2019) [sssd[be[INF]]] [sdap_sudo_online_cb] (0x0400): We are back online. SUDO host information will be renewed on next refresh.
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][name=groupname@inf]
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_attach_req] (0x0400): DP Request [Account #2]: New request. Flags [0x0001].
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_attach_req] (0x0400): Number of active DP request: 1
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [ou=Group,dc=inf,dc=ed,dc=ac,dc=uk]
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(cn=groupname)(objectClass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][ou=Group,dc=inf,dc=ed,dc=ac,dc=uk].
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_process_missing_member_2307] (0x0400): Adding a ghost entry
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_get_primary_name] (0x0400): Processing object groupname
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_save_group] (0x0400): Processing group groupname@inf
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_process_ghost_members] (0x0400): Group has 5 members
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [sdap_save_group] (0x0400): Storing info for group groupname@inf
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_req_done] (0x0400): DP Request [Account #2]: Request handler finished [0]: Success
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [_dp_req_recv] (0x0400): DP Request [Account #2]: Receiving request data.
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #2]: Finished. Success.
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::INF:name=groupname@inf] from reply table
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_req_destructor] (0x0400): DP Request [Account #2]: Request removed.
(Thu May 16 10:30:17 2019) [sssd[be[INF]]] [dp_req_destructor] (0x0400): Number of active DP request: 0