Attachment 16514 Details for Bug 51006 – sssd.log

sssd.log

sssd_TEST.ALT.log (text/x-log), 195.35 KB, created by Белая Алёна on 2024-07-30 17:15:56 MSK

(hide)

Description:

Filename:

MIME Type:

Creator: Белая Алёна

Created: 2024-07-30 17:15:56 MSK

Size: 195.35 KB

patch

obsolete

>(2024-07-30 17:13:17): [be[TEST.ALT]] [server_setup] (0x3f7c0): Starting with debug level = 0x17f0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [server_setup] (0x0400): CONFDB: /var/lib/sss/db/config.ldb
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option lookup_family_order has value ipv4_first
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dns_resolver_timeout has value 6
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dns_resolver_op_timeout has value 3
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dns_resolver_server_timeout has value 1000
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dns_resolver_use_search_list is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dns_discovery_domain has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_res_get_opts] (0x0100): Lookup order: ipv4_first
>(2024-07-30 17:13:17): [be[TEST.ALT]] [recreate_ares_channel] (0x0100): Initializing new c-ares channel
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_context_init] (0x0400): Created new fail over context, retry timeout is 30
>(2024-07-30 17:13:17): [be[TEST.ALT]] [confdb_init_domain_provider_and_enum] (0x0400): No enumeration for [TEST.ALT]!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [confdb_init_domain_provider_and_enum] (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
>(2024-07-30 17:13:17): [be[TEST.ALT]] [confdb_init_domain_pwd_expire] (0x1000): pwd_expiration_warning is -1
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sysdb_domain_init_internal] (0x0200): DB File for TEST.ALT: /var/lib/sss/db/cache_TEST.ALT.ldb
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sysdb_domain_init_internal] (0x0200): Timestamp file for TEST.ALT: /var/lib/sss/db/timestamps_TEST.ALT.ldb
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ldb] (0x0400): asq: Unable to register control with rootdse!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_domain_get_state] (0x1000): Domain TEST.ALT is Active
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_names_init_from_args] (0x0100): Using re [^(((?P<domain>[^\\]+)\\(?P<name>.+))|((?P<name>.+)@(?P<domain>[^@]+))|((?P<name>[^@\\]+)))$].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_fqnames_init] (0x0100): Using fq format [%1$s@%2$s].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_socket_listen] (0x0400): D-BUS Server listening on unix:path=/var/lib/sss/pipes/private/sbus-dp_TEST.ALT.2653,guid=873fb56c0a1cfd0800718c2966a8f4fd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_symlink_create] (0x1000): Symlinking the dbus path /var/lib/sss/pipes/private/sbus-dp_TEST.ALT.2653 to a link /var/lib/sss/pipes/private/sbus-dp_TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus on path /org/freedesktop/DBus
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_new_connection] (0x0200): Adding connection 0x55906cb6e2f0.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55906cb59ec0]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.Hello: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x0200): We do not listen to this signal!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_request_name] (0x0400): Requesting name: sssd.domain_TEST_2eALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.RequestName: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x0200): We do not listen to this signal!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_listen] (0x0400): Registering signal listener org.freedesktop.DBus.NameOwnerChanged on path /org/freedesktop/DBus
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_listen] (0x0400): Registering signal listener org.freedesktop.DBus.NameAcquired on path /org/freedesktop/DBus
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_connect_private_done] (0x0400): Connected to unix:path=/var/lib/sss/pipes/private/sbus-dp_TEST.ALT bus as sssd.domain_TEST_2eALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [id]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [auth]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [access]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [chpass]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [sudo]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [autofs]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [none] provider for [selinux]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [hostid]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [subdomains]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [session]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_configuration] (0x0100): Using [ad] provider for [resolver]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [id] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): About to load module [ad].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_module_open_lib] (0x1000): Loading module [ad] with path [/usr/lib64/sssd/libsss_ad.so]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_module_run_constructor] (0x0400): Executing module [ad] constructor.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_domain has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_enabled_domains has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_backup_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_hostname has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_keytab has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_realm has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_enable_dns_sites is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_access_filter has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_enable_gc is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_access_control has value permissive
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_implicit_deny is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_ignore_unreadable is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_cache_timeout has value 5
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_map_interactive has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_map_remote_interactive has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_map_network has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_map_batch has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_map_service has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_map_permit has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_map_deny has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_gpo_default_right has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_site has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_confd_path has value /var/lib/sss/pubconf/krb5.include.d
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_maximum_machine_account_password_age has value 30
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_machine_account_password_renewal_opts has value 86400:750:300
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_update_samba_machine_account_password is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_use_ldaps is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ad_allow_remote_domain_local_groups is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_common_options] (0x0100): No AD server set, will use service discovery!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_common_options] (0x0100): Setting ad_hostname to [iez60w9u.test.alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_common_options] (0x0100): Setting domain option case_sensitive to [false]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [krb5_service_new] (0x0100): write_kdcinfo for realm TEST.ALT set to false
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_new_service] (0x0400): Creating new service 'AD'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_new_service] (0x0400): Creating new service 'AD_GC'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_failover_init] (0x0100): No primary servers defined, using service discovery
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_add_srv_server] (0x0400): Adding new SRV server to service 'AD_GC' using 'tcp'.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_add_srv_server] (0x0400): Adding new SRV server to service 'AD' using 'tcp'.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [_ad_servers_init] (0x0100): Added service discovery for AD
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_uri has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_backup_uri has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_default_bind_dn has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_default_authtok_type has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_default_authtok has no binary value.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_search_timeout has value 6
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_network_timeout has value 6
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_opt_timeout has value 8
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_tls_reqcert has value hard
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_user_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_user_search_scope has value sub
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_user_search_filter has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_user_extra_attrs has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_group_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_group_search_scope has value sub
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_group_search_filter has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_host_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_service_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_full_refresh_interval has value 21600
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_smart_refresh_interval has value 900
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_random_offset has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_use_host_filter is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_hostnames has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_ip has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_include_netgroups is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sudo_include_regexp is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_autofs_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_autofs_map_master_name has value auto.master
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_iphost_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_ipnetwork_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_schema has value ad
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_pwmodify_mode has value exop
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_offline_timeout has value 60
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_force_upper_case_realm is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_enumeration_refresh_timeout has value 300
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_enumeration_refresh_offset has value 30
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_purge_cache_timeout has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_purge_cache_offset has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_tls_cacert has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_tls_cacertdir has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_tls_cert has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_tls_key has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_tls_cipher_suite has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_id_use_start_tls is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_id_mapping is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sasl_mech has value GSS-SPNEGO
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sasl_authid has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sasl_realm has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sasl_minssf has value -1
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sasl_maxssf has value -1
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_krb5_keytab has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_krb5_init_creds is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_backup_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_realm has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_canonicalize is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_use_kdcinfo is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_kdcinfo_lookahead has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_pwd_policy has value none
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_referrals is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option account_cache_expiration has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_dns_service_name has value ldap
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_krb5_ticket_lifetime has value 86400
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_access_filter has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_netgroup_search_base has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_group_nesting_level has value 2
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_deref has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_account_expire_policy has value ad
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_access_order has value filter
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_chpass_uri has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_chpass_backup_uri has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_chpass_dns_service_name has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_chpass_update_last_change is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_enumeration_search_timeout has value 60
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_auth_disable_tls_never_use_in_production is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_page_size has value 1000
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_deref_threshold has value 10
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_ignore_unreadable_references is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_sasl_canonicalize is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_connection_expire_timeout has value 900
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_connection_expire_offset has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_connection_idle_timeout has value 900
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_disable_paging is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_idmap_range_min has value 200000
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_idmap_range_max has value 2000200000
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_idmap_range_size has value 200000
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_idmap_autorid_compat is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_idmap_default_domain has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_idmap_default_domain_sid has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_idmap_helper_table_size has value 10
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_use_tokengroups is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_rfc2307_fallback_to_local_users is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_disable_range_retrieval is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_min_id has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_max_id has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_pwdlockout_dn has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option wildcard_limit has value 1000
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option ldap_library_debug_level has value 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_entry_usn has value uSNChanged
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_rootdse_last_usn has value highestCommittedUSN
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_object_class has value user
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_name has value sAMAccountName
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_pwd has value unixUserPassword
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_uid_number has value uidNumber
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_gid_number has value gidNumber
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_gecos has value gecos
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_home_directory has value unixHomeDirectory
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shell has value loginShell
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_principal has value userPrincipalName
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_fullname has value name
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_member_of has value memberOf
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_uuid has value objectGUID
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_objectsid has value objectSID
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_primary_group has value primaryGroupID
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_modify_timestamp has value whenChanged
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_entry_usn has value uSNChanged
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shadow_last_change has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shadow_min has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shadow_max has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shadow_warning has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shadow_inactive has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shadow_expire has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_shadow_flag has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_krb_last_pwd_change has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_krb_password_expiration has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_pwd_attribute has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_authorized_service has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_ad_account_expires has value accountExpires
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_ad_user_account_control has value userAccountControl
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_ns_account_lock has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_authorized_host has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_authorized_rhost has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_disabled has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_expiration_time has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_nds_login_allowed_time_map has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_ssh_public_key has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_auth_type has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_certificate has value userCertificate;binary
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_email has value mail
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_user_passkey has value altSecurityIdentities
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_object_class has value group
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_object_class_alt has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_name has value sAMAccountName
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_pwd has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_gid_number has value gidNumber
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_member has value member
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_uuid has value objectGUID
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_objectsid has value objectSID
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_modify_timestamp has value whenChanged
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_entry_usn has value uSNChanged
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_type has value groupType
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_group_external_member has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_netgroup_object_class has value nisNetgroup
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_netgroup_name has value cn
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_netgroup_member has value memberNisNetgroup
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_netgroup_triple has value nisNetgroupTriple
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_netgroup_modify_timestamp has value modifyTimestamp
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_service_object_class has value ipService
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_service_name has value cn
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_service_port has value ipServicePort
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_service_proto has value ipServiceProtocol
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_service_entry_usn has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_iphost_object_class has value device
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_iphost_name has value cn
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_iphost_number has value ipHostNumber
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_iphost_entry_usn has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_ipnetwork_object_class has value ipNetwork
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_ipnetwork_name has value cn
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_ipnetwork_number has value ipNetworkNumber
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_ipnetwork_entry_usn has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_set_sdap_options] (0x0100): Option krb5_realm set to TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_sasl_options] (0x0100): Will look for iez60w9u.test.alt@TEST.ALT in default keytab
>(2024-07-30 17:13:17): [be[TEST.ALT]] [select_principal_from_keytab] (0x0200): trying to select the most appropriate principal from keytab
>(2024-07-30 17:13:17): [be[TEST.ALT]] [find_principal_in_keytab] (0x0400): No principal matching iez60w9u.test.alt@TEST.ALT found in keytab.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [match_principal] (0x1000): Principal matched to the sample (IEZ60W9U$@TEST.ALT).
>(2024-07-30 17:13:17): [be[TEST.ALT]] [select_principal_from_keytab] (0x0200): Selected primary: IEZ60W9U$
>(2024-07-30 17:13:17): [be[TEST.ALT]] [select_principal_from_keytab] (0x0200): Selected realm: TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_sasl_options] (0x0100): Option ldap_sasl_authid set to IEZ60W9U$
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_sasl_options] (0x0100): Option ldap_sasl_realm set to TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_set_search_bases] (0x0100): Search base not set. SSSD will attempt to discover it later, when connecting to the LDAP server.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_update is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_update_per_family is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_refresh_interval has value 60
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_refresh_interval_offset has value 300
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_iface has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_ttl has value 10
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_update_ptr is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_force_tcp is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_auth has value gss-tsig
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_auth_ptr has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option dyndns_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_dyndns_init] (0x0100): Dynamic DNS updates are on. Checking for nsupdate..
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [Dyndns update] was created
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [Dyndns update]: scheduling task 23 seconds from now [1722348820]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_idmap_init] (0x0100): Initializing [1] domains for ID-mapping
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_idmap_add_domain] (0x1000): Adding domain [S-1-5-21-2128886017-959441534-230989675] as slice [8497]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sysdb_idmap_store_mapping] (0x1000): No changes needed, canceling transaction
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_setup_tasks] (0x0400): Setting up cleanup task for TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_fo_set_srv_lookup_plugin] (0x0400): Trying to set SRV lookup plugin to AD
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_fo_set_srv_lookup_plugin] (0x0400): SRV lookup plugin is now AD
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [AD machine account password renewal] was created
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [AD machine account password renewal]: scheduling task 1007 seconds from now [1722349804]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sysdb_get_certmap] (0x0400): No certificate maps found.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_backup_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_realm has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_ccachedir has value /tmp
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_ccname_template has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_auth_timeout has value 6
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_keytab has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_validate is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_kpasswd has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_backup_kpasswd has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_store_password_if_offline is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_renewable_lifetime has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_lifetime has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_renew_interval has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_use_fast has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_fast_principal has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_fast_use_anonymous_pkinit is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_canonicalize is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_use_enterprise_principal is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_use_kdcinfo is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_kdcinfo_lookahead has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_map_user has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_get_options] (0x0400): Option krb5_use_subdomain_realm is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_auth_options] (0x0100): Option krb5_server set to (null)
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_auth_options] (0x0100): Option krb5_realm set to TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_auth_options] (0x0100): Option krb5_use_kdcinfo set to true
>(2024-07-30 17:13:17): [be[TEST.ALT]] [check_lifetime] (0x0200): No lifetime configured.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [check_lifetime] (0x0200): No lifetime configured.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_krb5_check_options] (0x0100): No KDC explicitly configured, using defaults.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_krb5_check_options] (0x0100): No kpasswd server explicitly configured, using the KDC or defaults.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [parse_krb5_map_user] (0x0100): krb5_map_user is empty!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [id] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [auth] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [auth] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [access] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [access] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_domain has value TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_enabled_domains has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_backup_server has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_hostname has value iez60w9u.test.alt
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option krb5_keytab has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option krb5_realm has value TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_enable_dns_sites is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_access_filter has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_enable_gc is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_access_control has value permissive
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_implicit_deny is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_ignore_unreadable is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_cache_timeout has value 5
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_interactive has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_remote_interactive has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_network has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_batch has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_service has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_permit has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_deny has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_default_right has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_site has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option krb5_confd_path has value /var/lib/sss/pubconf/krb5.include.d
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_maximum_machine_account_password_age has value 30
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_machine_account_password_renewal_opts has value 86400:750:300
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_update_samba_machine_account_password is TRUE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_use_ldaps is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_allow_remote_domain_local_groups is FALSE
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [chpass] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [chpass] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [sudo] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [sudo] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ldap_get_sudo_options] (0x0400): Search base not set, trying to discover it later connecting to the LDAP server.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_object_class has value sudoRole
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_object_class_attr has value objectCategory
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_name has value cn
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_command has value sudoCommand
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_host has value sudoHost
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_user has value sudoUser
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_option has value sudoOption
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_runas has value sudoRunAs
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasuser has value sudoRunAsUser
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasgroup has value sudoRunAsGroup
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_notbefore has value sudoNotBefore
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_notafter has value sudoNotAfter
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_order has value sudoOrder
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_entry_usn has no value 
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [SUDO Full Refresh] was created
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Full Refresh]: scheduling task 10 seconds from now [1722348807]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [SUDO Smart Refresh] was created
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Smart Refresh]: scheduling task 910 seconds from now [1722349707]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [autofs] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [autofs] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ldap_get_autofs_options] (0x0400): Search base not set, trying to discover it later connecting to the LDAP server.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_object_class has value automountMap
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_name has value automountMapName
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_object_class has value automount
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_key has value automountKey
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_value has value automountInformation
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_object_class has value nisMap
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_name has value nisMapName
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_object_class has value nisObject
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_key has value cn
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_value has value nisMapEntry
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [selinux] with module [none]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_special] (0x0400): Target [selinux] is explicitly disabled.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [hostid] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0100): Target [hostid] is not supported by module [ad].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [subdomains] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [subdomains] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [Subdomains Refresh] was created
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [Subdomains Refresh]: scheduling task 51 seconds from now [1722348848]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_write_krb5_localauth_snippet] (0x0200): File for localauth plugin configuration is [/var/lib/sss/pubconf/krb5.include.d/localauth_plugin]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_write_krb5_libdefaults_snippet] (0x0200): File for KRB5 libdefaults configuration is [/var/lib/sss/pubconf/krb5.include.d/krb5_libdefaults]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_write_domain_mappings] (0x0200): Mapping file for domain [TEST.ALT] is [/var/lib/sss/pubconf/krb5.include.d/domain_realm_TEST_ALT]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_set_search_bases] (0x0100): Search base not set. SSSD will attempt to discover it later, when connecting to the LDAP server.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [session] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0100): Target [session] is not supported by module [ad].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [resolver] with module [ad]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [resolver] constructor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_resolver_setup_tasks] (0x0400): Setting up resolver cleanup task for TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Client on path /sssd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Backend on path /sssd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Failover on path /sssd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.AccessControl on path /sssd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.dataprovider on path /sssd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Autofs on path /sssd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dbus_connect_address] (0x0400): Connected to unix:path=/var/lib/sss/pipes/private/sbus-monitor bus as sssd.domain_TEST_2eALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_listen] (0x0400): Registering signal listener org.freedesktop.DBus.NameOwnerChanged on path /org/freedesktop/DBus
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_listen] (0x0400): Registering signal listener org.freedesktop.DBus.NameAcquired on path /org/freedesktop/DBus
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.service on path /sssd
>(2024-07-30 17:13:17): [be[TEST.ALT]] [_snotify_create] (0x0400): Added a watch for /etc/resolv.conf with inotify flags 0x8D88 internal flags 0x1 using function watched_file_inotify_cb after delay 1.0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [watch_update_resolv] (0x0400): Reloading /etc/resolv.conf.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [recreate_ares_channel] (0x0100): Initializing new c-ares channel
>(2024-07-30 17:13:17): [be[TEST.ALT]] [recreate_ares_channel] (0x0100): Destroying the old c-ares channel
>(2024-07-30 17:13:17): [be[TEST.ALT]] [become_user_ex] (0x0200): Trying to become user [474][440].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_initialized] (0x0400): Backend provider (TEST.ALT) started!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_acquired] (0x0400): D-Bus name acquired: :1.2
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameAcquired: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_acquired] (0x0400): D-Bus name acquired: sssd.domain_TEST_2eALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameAcquired: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sss_monitor_service_init_done] (0x0100): Got id ack and version (1) from Monitor
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_new_connection] (0x0200): Adding connection 0x55906cba9410.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55906cba54f0]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.Hello: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_request_name] (0x0400): Requesting name: sssd.pac
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.RequestName: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.GetConnectionUnixUser: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Added Frontend client [pac]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55906cba54f0]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.DataProvider.Client.Register: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#1] DP Request [Subdomains #1]: REQ_TRACE: New request. Flags [0000].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#1] Number of active DP request: 1
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_resolve_service_send] (0x0100): [RID#1] Trying to resolve service 'AD'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [get_port_status] (0x1000): [RID#1] Port status of port 0 for server '(no name)' is 'neutral'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolve_srv_send] (0x0200): [RID#1] The status of SRV lookup is neutral
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_send] (0x0400): [RID#1] Sending CLDAP ping
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_discover_srv_next_domain] (0x0400): [RID#1] SRV resolution of service 'ldap'. Will use DNS discovery domain 'Default-First-Site-Name._sites.TEST.ALT'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_send] (0x0100): [RID#1] Trying to resolve SRV record of '_ldap._tcp.Default-First-Site-Name._sites.TEST.ALT'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_done] (0x1000): [RID#1] Using TTL [900]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got answer. Processing...
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got 1 servers
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_domain_discovery_done] (0x0400): [RID#1] Found 1 domain controllers in domain Default-First-Site-Name._sites.TEST.ALT
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_connect_host_send] (0x0400): [RID#1] Resolving host dc1.test.alt
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in files
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve AAAA record of 'dc1.test.alt' in files
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_next] (0x0200): [RID#1] No more address families to retry
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in DNS
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_new_connection] (0x0200): Adding connection 0x55906cbb28e0.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55906cba52b0]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): [RID#1] Parsing an A reply
>(2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_connect_host_resolv_done] (0x0400): [RID#1] Connecting to cldap://dc1.test.alt:389
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sssd_async_socket_init_send] (0x0400): [RID#1] Setting 6 seconds timeout [ldap_network_timeout] for connecting
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_connect_host_done] (0x0400): [RID#1] Successful connection to cldap://dc1.test.alt:389
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(&(DnsDomain=TEST.ALT)(NtVer=\14\00\00\00))][].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [netlogon]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_new_connection] (0x0200): Adding connection 0x55906cbbfcc0.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55906cbc2e90]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.Hello: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_request_name] (0x0400): Requesting name: sssd.pam
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.RequestName: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.Hello: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.GetConnectionUnixUser: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_request_name] (0x0400): Requesting name: sssd.nss
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.RequestName: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Added Frontend client [pam]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55906cba52b0]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.DataProvider.Client.Register: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.GetConnectionUnixUser: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_dc_done] (0x0400): [RID#1] dc1.test.alt:389: found site (Default-First-Site-Name) and forest (test.alt)
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_done] (0x0400): [RID#1] Found site: Default-First-Site-Name
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_done] (0x0400): [RID#1] Found forest: test.alt
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_srv_plugin_ping_done] (0x0400): [RID#1] About to discover primary and backup servers
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_servers_send] (0x0400): [RID#1] Looking up primary servers
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_discover_srv_next_domain] (0x0400): [RID#1] SRV resolution of service 'ldap'. Will use DNS discovery domain 'Default-First-Site-Name._sites.TEST.ALT'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_send] (0x0100): [RID#1] Trying to resolve SRV record of '_ldap._tcp.Default-First-Site-Name._sites.TEST.ALT'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_done] (0x1000): [RID#1] Using TTL [900]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got answer. Processing...
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got 1 servers
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_servers_primary_done] (0x0400): [RID#1] Looking up backup servers
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_discover_srv_next_domain] (0x0400): [RID#1] SRV resolution of service 'ldap'. Will use DNS discovery domain 'TEST.ALT'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_send] (0x0100): [RID#1] Trying to resolve SRV record of '_ldap._tcp.TEST.ALT'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Added Frontend client [nss]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55906cbc2e90]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.DataProvider.Client.Register: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_done] (0x1000): [RID#1] Using TTL [900]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got answer. Processing...
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got 1 servers
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_srv_plugin_servers_done] (0x0400): [RID#1] Got 1 primary and 1 backup servers
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_add_server_to_list] (0x0400): [RID#1] Inserted primary server 'dc1.test.alt:389' to service 'AD'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_add_server_to_list] (0x0400): [RID#1] Server 'dc1.test.alt:389' for service 'AD' is already present
>(2024-07-30 17:13:17): [be[TEST.ALT]] [set_srv_data_status] (0x0100): [RID#1] Marking SRV lookup of service 'AD' as 'resolved'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [get_server_status] (0x1000): [RID#1] Status of server 'dc1.test.alt' is 'name not resolved'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in files
>(2024-07-30 17:13:17): [be[TEST.ALT]] [set_server_common_status] (0x0100): [RID#1] Marking server 'dc1.test.alt' as 'resolving name'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve AAAA record of 'dc1.test.alt' in files
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_next] (0x0200): [RID#1] No more address families to retry
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in DNS
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): [RID#1] Parsing an A reply
>(2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>(2024-07-30 17:13:17): [be[TEST.ALT]] [set_server_common_status] (0x0100): [RID#1] Marking server 'dc1.test.alt' as 'name resolved'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x1000): [RID#1] Saving the first resolved server
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x0200): [RID#1] Found address for server dc1.test.alt: [10.88.14.204] TTL 900
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_resolve_callback] (0x0100): [RID#1] Constructed uri 'ldap://dc1.test.alt'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_resolve_callback] (0x0100): [RID#1] Constructed GC uri 'ldap://dc1.test.alt'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sssd_async_socket_init_send] (0x0400): [RID#1] Setting 6 seconds timeout [ldap_network_timeout] for connecting
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(objectclass=*)][].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [*]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [altServer]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [namingContexts]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedControl]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedExtension]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedFeatures]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedLDAPVersion]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedSASLMechanisms]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [domainControllerFunctionality]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [defaultNamingContext]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [lastUSN]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [highestCommittedUSN]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>(2024-07-30 17:13:17): [be[TEST.ALT]] [get_naming_context] (0x0200): [RID#1] Using value from [defaultNamingContext] as naming context.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [DEFAULT][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_user_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [USER][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_group_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [GROUP][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_netgroup_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [NETGROUP][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_host_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [HOST][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_sudo_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [SUDO][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_service_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [SERVICE][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_autofs_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [AUTOFS][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_iphost_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [IPHOST][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_ipnetwork_search_base] to [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [IPNETWORK][DC=test,DC=alt][SUBTREE][]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_server_opts_from_rootdse] (0x0100): [RID#1] Setting AD compatibility level to [4]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_server_opts_from_rootdse] (0x0100): [RID#1] Will look for schema at [CN=Schema,CN=Configuration,DC=test,DC=alt]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_kinit_send] (0x0400): [RID#1] Attempting kinit (default, IEZ60W9U$, TEST.ALT, 86400)
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_kinit_next_kdc] (0x1000): [RID#1] Resolving next KDC for service AD
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_resolve_service_send] (0x0100): [RID#1] Trying to resolve service 'AD'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [get_server_status] (0x1000): [RID#1] Status of server 'dc1.test.alt' is 'name resolved'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [resolve_srv_send] (0x0200): [RID#1] The status of SRV lookup is resolved
>(2024-07-30 17:13:17): [be[TEST.ALT]] [get_server_status] (0x1000): [RID#1] Status of server 'dc1.test.alt' is 'name resolved'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x1000): [RID#1] Saving the first resolved server
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x0200): [RID#1] Found address for server dc1.test.alt: [10.88.14.204] TTL 900
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_kinit_kdc_resolved] (0x1000): [RID#1] KDC resolved, attempting to get TGT...
>(2024-07-30 17:13:17): [be[TEST.ALT]] [create_tgt_req_send_buffer] (0x0400): [RID#1] buffer size: 41
>(2024-07-30 17:13:17): [be[TEST.ALT]] [set_tgt_child_timeout] (0x0400): [RID#1] Setting 8 seconds timeout for TGT child
>(2024-07-30 17:13:17): [be[TEST.ALT]] [_write_pipe_handler] (0x0400): [RID#1] All data has been sent!
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [child_sig_handler] (0x1000): [RID#1] Waiting for child [2657].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [child_sig_handler] (0x0100): [RID#1] child [2657] finished successfully.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [_read_pipe_handler] (0x0400): [RID#1] EOF received, client finished
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_tgt_recv] (0x0400): [RID#1] Child responded: 0 [FILE:/var/lib/sss/db/ccache_TEST.ALT], expired on [1722384797]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_cli_auth_step] (0x0100): [RID#1] expire timeout is 900
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_cli_auth_step] (0x1000): [RID#1] the connection will expire at 1722349697
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sasl_bind_send] (0x0100): [RID#1] Executing sasl bind mech: GSS-SPNEGO, user: IEZ60W9U$
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_cli_connect_recv] (0x0400): [RID#1] Connection established.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_set_port_status] (0x0100): [RID#1] Marking port 389 of server 'dc1.test.alt' as 'working'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [set_server_common_status] (0x0100): [RID#1] Marking server 'dc1.test.alt' as 'working'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [fo_set_port_status] (0x0400): [RID#1] Marking port 389 of duplicate server 'dc1.test.alt' as 'working'
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_conn_data_start_idle_timer] (0x0100): [RID#1] idle timeout is 900
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [objectclass=domain][DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [objectSID]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_run_online_cb] (0x0080): [RID#1] Going online. Running callbacks.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_next_done] (0x0400): [RID#1] Found SID [S-1-5-21-2128886017-959441534-230989675].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(&(DnsDomain=TEST.ALT)(NtVer=\14\00\00\00))][].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [netlogon]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): [RID#1] Found flat name [TEST].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): [RID#1] Found site [Default-First-Site-Name].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): [RID#1] Found forest [test.alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_check_gc_usability_send] (0x0400): [RID#1] GC always usable while ID mapping
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_search_bases_ex_next_base] (0x0400): [RID#1] Issuing LDAP lookup with base [DC=test,DC=alt]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(&(objectclass=trustedDomain)(trustType=2)(!(msDS-TrustForestTrustInfo=*)))][DC=test,DC=alt].
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [flatName]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustPartner]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [securityIdentifier]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustType]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [cn]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustAttributes]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustDirection]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): [RID#1] Additional References: ldap://test.alt/CN=Configuration,DC=test,DC=alt
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): [RID#1] Additional References: ldap://test.alt/DC=DomainDnsZones,DC=test,DC=alt
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): [RID#1] Additional References: ldap://test.alt/DC=ForestDnsZones,DC=test,DC=alt
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_search_bases_ex_done] (0x0400): [RID#1] Receiving data from base [DC=test,DC=alt]
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_slave_domain_done] (0x1000): [RID#1] There are no changes
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_subdomains_refresh_done] (0x0400): [RID#1] Subdomains refreshed.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_done] (0x0400): [RID#1] DP Request [Subdomains #1]: Request handler finished [0]: ÐÑÐ¿Ð¾Ð»Ð½ÐµÐ½Ð¾
>(2024-07-30 17:13:17): [be[TEST.ALT]] [_dp_req_recv] (0x0400): [RID#1] DP Request [Subdomains #1]: Receiving request data.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#1] DP Request [Subdomains #1]: Request removed.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#1] Number of active DP request: 0
>(2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_reply_std] (0x1000): [RID#1] DP Request [Subdomains #1]: Returning [Success]: 0,0,Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getDomains: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getDomains: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getDomains: Success
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [Subdomains Refresh]: already enabled
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [SUDO Smart Refresh]: already enabled
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [SUDO Full Refresh]: already enabled
>(2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_sudo_online_cb] (0x0400): [RID#1] We are back online. SUDO host information will be renewed on next refresh.
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [AD machine account password renewal]: already enabled
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>(2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [Dyndns update]: already enabled
>(2024-07-30 17:13:17): [be[TEST.ALT]] [ad_online_cb] (0x0400): [RID#1] The AD provider is online
>(2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_execute] (0x0400): Task [SUDO Full Refresh]: executing task, timeout 21600 seconds
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_full_refresh_send] (0x0400): Issuing a full refresh of sudo rules
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_refresh_connect_done] (0x0400): SUDO LDAP connection successful
>(2024-07-30 17:13:27): [be[TEST.ALT]] [check_ipv4_addr] (0x0200): Loopback IPv4 address 127.0.0.1
>(2024-07-30 17:13:27): [be[TEST.ALT]] [check_ipv6_addr] (0x0200): Loopback IPv6 address ::1
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_load_sudoers_send] (0x0400): About to fetch sudo rules
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_search_bases_ex_next_base] (0x0400): Issuing LDAP lookup with base [DC=test,DC=alt]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectCategory=sudoRole)(|(&(!(sudoHost=*))(cn=defaults))(sudoHost=ALL)(sudoHost=iez60w9u.test.alt)(sudoHost=iez60w9u)(sudoHost=10.88.15.163)(sudoHost=10.88.8.0/21)(sudoHost=2a0c:88c0:2:2000:24cb:15ff:feb0:c8c9)(sudoHost=2a0c:88c0:2:2000::/64)(sudoHost=fe80::24cb:15ff:feb0:c8c9)(sudoHost=fe80::/64)(sudoHost=+*)))][DC=test,DC=alt].
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectCategory]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoCommand]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoHost]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoUser]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOption]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAs]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsUser]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsGroup]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotBefore]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotAfter]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOrder]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/CN=Configuration,DC=test,DC=alt
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/DC=DomainDnsZones,DC=test,DC=alt
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/DC=ForestDnsZones,DC=test,DC=alt
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_search_bases_ex_done] (0x0400): Receiving data from base [DC=test,DC=alt]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_load_sudoers_done] (0x0200): Received 0 sudo rules
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_refresh_done] (0x0400): Received 0 rules
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sysdb_sudo_purge_all] (0x0400): Deleting all cached sudo rules
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_refresh_done] (0x0400): Sudoers is successfully stored in cache
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_set_usn] (0x0200): SUDO higher USN value: [4236]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_full_refresh_done] (0x0400): Successful full refresh of sudo rules
>(2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_postpone] (0x0400): Task [SUDO Smart Refresh]: rescheduling task
>(2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Smart Refresh]: scheduling task 900 seconds from now [1722349707]
>(2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_done] (0x0400): Task [SUDO Full Refresh]: finished successfully
>(2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Full Refresh]: scheduling task 21600 seconds from last execution time [1722370407]
>(2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.service.resetOffline: Success
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_ptask_execute] (0x0400): Task [Dyndns update]: executing task, timeout 60 seconds
>(2024-07-30 17:13:40): [be[TEST.ALT]] [ad_dyndns_update_send] (0x0400): Performing update
>(2024-07-30 17:13:40): [be[TEST.ALT]] [check_ipv6_addr] (0x0200): Link local IPv6 address fe80::24cb:15ff:feb0:c8c9
>(2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'iez60w9u.test.alt' in DNS
>(2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
>(2024-07-30 17:13:40): [be[TEST.ALT]] [request_watch_destructor] (0x0400): Deleting request watch
>(2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve AAAA record of 'iez60w9u.test.alt' in DNS
>(2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an AAAA reply
>(2024-07-30 17:13:40): [be[TEST.ALT]] [request_watch_destructor] (0x0400): Deleting request watch
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_msg_create_common] (0x0200): Creating update message for auto-discovered realm.
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_create_fwd_msg] (0x0400):  -- Begin nsupdate message -- 
>
>update delete iez60w9u.test.alt. in A
>update add iez60w9u.test.alt. 10 in A 10.88.15.163
>send
>update delete iez60w9u.test.alt. in AAAA
>update add iez60w9u.test.alt. 10 in AAAA 2a0c:88c0:2:2000:24cb:15ff:feb0:c8c9
>send
> -- End nsupdate message -- 
>(2024-07-30 17:13:40): [be[TEST.ALT]] [_write_pipe_handler] (0x0400): All data has been sent!
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_stdin_done] (0x1000): Sending nsupdate data complete
>(2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x1000): Waiting for child [2661].
>(2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x0100): child [2661] finished successfully.
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_done] (0x0200): nsupdate child status: 0
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_msg_create_common] (0x0200): Creating update message for auto-discovered realm.
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_create_ptr_msg] (0x0400):  -- Begin nsupdate message -- 
>
>update delete 163.15.88.10.in-addr.arpa. in PTR
>update add 163.15.88.10.in-addr.arpa. 10 in PTR iez60w9u.test.alt.
>send
>update delete 9.c.8.c.0.b.e.f.f.f.5.1.b.c.4.2.0.0.0.2.2.0.0.0.0.c.8.8.c.0.a.2.ip6.arpa. in PTR
>update add 9.c.8.c.0.b.e.f.f.f.5.1.b.c.4.2.0.0.0.2.2.0.0.0.0.c.8.8.c.0.a.2.ip6.arpa. 10 in PTR iez60w9u.test.alt.
>send
> -- End nsupdate message -- 
>(2024-07-30 17:13:40): [be[TEST.ALT]] [_write_pipe_handler] (0x0400): All data has been sent!
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_stdin_done] (0x1000): Sending nsupdate data complete
>(2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x1000): Waiting for child [2666].
>(2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x0020): child [2666] failed with status [2].
>********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_auth_options] (0x0100): Option krb5_realm set to TEST.ALT
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_auth_options] (0x0100): Option krb5_use_kdcinfo set to true
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [check_lifetime] (0x0200): No lifetime configured.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [check_lifetime] (0x0200): No lifetime configured.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sss_krb5_check_options] (0x0100): No KDC explicitly configured, using defaults.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sss_krb5_check_options] (0x0100): No kpasswd server explicitly configured, using the KDC or defaults.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [parse_krb5_map_user] (0x0100): krb5_map_user is empty!
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [id] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [auth] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [auth] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [access] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [access] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_domain has value TEST.ALT
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_enabled_domains has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_server has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_backup_server has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_hostname has value iez60w9u.test.alt
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option krb5_keytab has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option krb5_realm has value TEST.ALT
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_enable_dns_sites is TRUE
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_access_filter has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_enable_gc is TRUE
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_access_control has value permissive
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_implicit_deny is FALSE
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_ignore_unreadable is TRUE
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_cache_timeout has value 5
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_interactive has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_remote_interactive has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_network has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_batch has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_service has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_permit has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_map_deny has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_gpo_default_right has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_site has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option krb5_confd_path has value /var/lib/sss/pubconf/krb5.include.d
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_maximum_machine_account_password_age has value 30
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_machine_account_password_renewal_opts has value 86400:750:300
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_update_samba_machine_account_password is TRUE
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_use_ldaps is FALSE
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_copy_options_ex] (0x0400): Option ad_allow_remote_domain_local_groups is FALSE
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): gpo_map_type: Interactive
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): login
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): su
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): su-l
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): gdm-fingerprint
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): gdm-password
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): gdm-smartcard
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): kdm
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): lightdm
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): lxdm
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): sddm
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): unity
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): xdm
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): gpo_map_type: Remote Interactive
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): sshd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): cockpit
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): gpo_map_type: Network
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): ftp
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): samba
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): gpo_map_type: Batch
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): crond
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): gpo_map_type: Service
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): gpo_map_type: Permitted
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): polkit-1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): sudo
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): sudo-i
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): Default service (not explicitly removed): systemd-user
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_option] (0x4000): gpo_map_type: Denied
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_options] (0x4000): gpo_default_right_config: (null)
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_gpo_parse_map_options] (0x4000): gpo_default_right: 6
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [chpass] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [chpass] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [sudo] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [sudo] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssm_ad_sudo_init] (0x2000): Initializing AD sudo handler
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_sudo_init] (0x2000): Initializing sudo AD back end
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_sudo_init] (0x2000): Initializing sudo LDAP back end
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ldap_get_sudo_options] (0x0400): Search base not set, trying to discover it later connecting to the LDAP server.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_object_class has value sudoRole
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_object_class_attr has value objectCategory
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_name has value cn
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_command has value sudoCommand
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_host has value sudoHost
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_user has value sudoUser
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_option has value sudoOption
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_runas has value sudoRunAs
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasuser has value sudoRunAsUser
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_runasgroup has value sudoRunAsGroup
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_notbefore has value sudoNotBefore
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_notafter has value sudoNotAfter
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_order has value sudoOrder
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_sudorule_entry_usn has no value 
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [SUDO Full Refresh] was created
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Full Refresh]: scheduling task 10 seconds from now [1722348807]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [SUDO Smart Refresh] was created
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Smart Refresh]: scheduling task 910 seconds from now [1722349707]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [autofs] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [autofs] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssm_ad_autofs_init] (0x2000): Initializing AD autofs handler
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_autofs_init] (0x2000): Initializing autofs AD back end
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_autofs_init] (0x2000): Initializing autofs LDAP back end
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ldap_get_autofs_options] (0x0400): Search base not set, trying to discover it later connecting to the LDAP server.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_object_class has value automountMap
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_name has value automountMapName
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_object_class has value automount
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_key has value automountKey
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_value has value automountInformation
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_object_class has value nisMap
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_map_name has value nisMapName
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_object_class has value nisObject
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_key has value cn
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_map] (0x0400): Option ldap_autofs_entry_value has value nisMapEntry
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [selinux] with module [none]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_special] (0x0400): Target [selinux] is explicitly disabled.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [hostid] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0100): Target [hostid] is not supported by module [ad].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [subdomains] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [subdomains] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssm_ad_subdomains_init] (0x2000): Initializing AD subdomains handler
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_create] (0x0400): Periodic task [Subdomains Refresh] was created
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [Subdomains Refresh]: scheduling task 51 seconds from now [1722348848]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sss_write_krb5_localauth_snippet] (0x0200): File for localauth plugin configuration is [/var/lib/sss/pubconf/krb5.include.d/localauth_plugin]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sss_write_krb5_libdefaults_snippet] (0x0200): File for KRB5 libdefaults configuration is [/var/lib/sss/pubconf/krb5.include.d/krb5_libdefaults]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sss_write_domain_mappings] (0x0200): Mapping file for domain [TEST.ALT] is [/var/lib/sss/pubconf/krb5.include.d/domain_realm_TEST_ALT]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_set_search_bases] (0x0100): Search base not set. SSSD will attempt to discover it later, when connecting to the LDAP server.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [session] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0100): Target [session] is not supported by module [ad].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_init] (0x0400): Initializing target [resolver] with module [ad]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_load_module] (0x0400): Module [ad] is already loaded.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_target_run_constructor] (0x0400): Executing target [resolver] constructor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssm_ad_resolver_init] (0x2000): Initializing AD resolver handler
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_resolver_setup_tasks] (0x0400): Setting up resolver cleanup task for TEST.ALT
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Client on path /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Backend on path /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Failover on path /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.AccessControl on path /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.dataprovider on path /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.DataProvider.Autofs on path /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dbus_connect_address] (0x0400): Connected to unix:path=/var/lib/sss/pipes/private/sbus-monitor bus as sssd.domain_TEST_2eALT
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_add] (0x2000): Created a disabled -/W watch on 18
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 18
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_listen] (0x0400): Registering signal listener org.freedesktop.DBus.NameOwnerChanged on path /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_listen] (0x0400): Registering signal listener org.freedesktop.DBus.NameAcquired on path /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface sssd.service on path /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [snotify_watch] (0x2000): Opened inotify fd 19
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [snotify_watch] (0x2000): Opened file watch 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [snotify_watch] (0x2000): Opened directory watch 2
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [_snotify_create] (0x0400): Added a watch for /etc/resolv.conf with inotify flags 0x8D88 internal flags 0x1 using function watched_file_inotify_cb after delay 1.0
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [watch_update_resolv] (0x0400): Reloading /etc/resolv.conf.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [recreate_ares_channel] (0x0100): Initializing new c-ares channel
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [recreate_ares_channel] (0x0100): Destroying the old c-ares channel
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [check_if_online] (0x2000): Schedule check_if_online_delayed in 1s.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [become_user_ex] (0x0200): Trying to become user [474][440].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_initialized] (0x0400): Backend provider (TEST.ALT) started!
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameAcquired on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameAcquired on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_acquired] (0x0400): D-Bus name acquired: :1.2
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameAcquired: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_acquired] (0x0400): D-Bus name acquired: sssd.domain_TEST_2eALT
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameAcquired: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.1: org.freedesktop.DBus.NameOwnerChanged
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.1: org.freedesktop.DBus.NameAcquired
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sss_monitor_service_init_done] (0x0100): Got id ack and version (1) from Monitor
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_new_connection] (0x0200): Adding connection 0x55906cba9410.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_add] (0x2000): Created a disabled -/W watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55906cba54f0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled R/- watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled -/W watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled -/W watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled R/- watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled -/W watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled -/W watch on 20
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.Hello on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_hello] (0x4000): Assigning unique name :1.2 to connection 0x55906cb96160
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.Hello: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner :1.2 has changed from [] to [:1.2]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.RequestName on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_request_name] (0x0400): Requesting name: sssd.pac
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.RequestName: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner sssd.pac has changed from [] to [sssd.pac]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.2: org.freedesktop.DBus.NameOwnerChanged
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.2: org.freedesktop.DBus.NameAcquired
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method sssd.DataProvider.Client.Register on /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.pac]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.GetConnectionUnixUser on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.GetConnectionUnixUser: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.pac]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_add] (0x2000): Inserting identity of sender [sssd.pac]: 474
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Added Frontend client [pac]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55906cba54f0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.DataProvider.Client.Register: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner :1.3 has changed from [] to [:1.3]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner sssd.pac has changed from [] to [sssd.pac]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method sssd.dataprovider.getDomains on /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.pac]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#1] DP Request [Subdomains #1]: REQ_TRACE: New request. Flags [0000].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#1] Number of active DP request: 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_op_connect_step] (0x4000): [RID#1] beginning to connect
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_resolve_service_send] (0x0100): [RID#1] Trying to resolve service 'AD'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [get_port_status] (0x1000): [RID#1] Port status of port 0 for server '(no name)' is 'neutral'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_resolve_service_activate_timeout] (0x2000): [RID#1] Resolve timeout [dns_resolver_timeout] set to 6 seconds
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolve_srv_send] (0x0200): [RID#1] The status of SRV lookup is neutral
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_send] (0x0400): [RID#1] Sending CLDAP ping
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_discover_srv_next_domain] (0x0400): [RID#1] SRV resolution of service 'ldap'. Will use DNS discovery domain 'Default-First-Site-Name._sites.TEST.ALT'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_send] (0x0100): [RID#1] Trying to resolve SRV record of '_ldap._tcp.Default-First-Site-Name._sites.TEST.ALT'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_request_timeout] (0x2000): [RID#1] Scheduling a timeout of 3 seconds
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_timeout_watcher] (0x2000): [RID#1] Scheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [unschedule_timeout_watcher] (0x4000): [RID#1] Unscheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_done] (0x1000): [RID#1] Using TTL [900]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got answer. Processing...
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got 1 servers
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_domain_discovery_done] (0x0400): [RID#1] Found 1 domain controllers in domain Default-First-Site-Name._sites.TEST.ALT
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_parallel_batch] (0x4000): [RID#1] Batch 1: dc1.test.alt:389
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_connect_host_send] (0x0400): [RID#1] Resolving host dc1.test.alt
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_is_unix] (0x4000): [RID#1] [dc1.test.alt] does not look like a unix domain socket
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_is_address] (0x4000): [RID#1] [dc1.test.alt] does not look like an IP address
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): [RID#1] Querying files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): [RID#1] Querying files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve AAAA record of 'dc1.test.alt' in files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_next] (0x0200): [RID#1] No more address families to retry
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): [RID#1] Querying DNS
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in DNS
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_request_timeout] (0x2000): [RID#1] Scheduling a timeout of 3 seconds
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_timeout_watcher] (0x2000): [RID#1] Scheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_new_connection] (0x0200): Adding connection 0x55906cbb28e0.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_add] (0x2000): Created a disabled -/W watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55906cba52b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [unschedule_timeout_watcher] (0x4000): [RID#1] Unscheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): [RID#1] Parsing an A reply
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_connect_host_resolv_done] (0x0400): [RID#1] Connecting to cldap://dc1.test.alt:389
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssd_async_socket_init_send] (0x4000): [RID#1] Using file descriptor [23] for the connection.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssd_async_socket_init_send] (0x0400): [RID#1] Setting 6 seconds timeout [ldap_network_timeout] for connecting
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_ldap_connect_callback_add] (0x4000): [RID#1] New connection to [cldap://dc1.test.alt:389/??base] with fd [23]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_connect_host_done] (0x0400): [RID#1] Successful connection to cldap://dc1.test.alt:389
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(&(DnsDomain=TEST.ALT)(NtVer=\14\00\00\00))][].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [netlogon]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x2000): [RID#1] ldap_search_ext called, msgid = 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_add] (0x2000): [RID#1] New operation 1 timeout 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled R/- watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled -/W watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled -/W watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled R/- watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled -/W watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled -/W watch on 22
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.Hello on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_new_connection] (0x0200): Adding connection 0x55906cbbfcc0.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Introspectable on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_router_add_path] (0x0400): Registering interface org.freedesktop.DBus.Properties on path /*
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_add] (0x2000): Created a disabled -/W watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x55906cbc2e90]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_hello] (0x4000): Assigning unique name :1.3 to connection 0x55906cbb2bb0
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.Hello: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled R/- watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled -/W watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled -/W watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner :1.3 has changed from [] to [:1.3]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.RequestName on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled R/- watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled -/W watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_request_name] (0x0400): Requesting name: sssd.pam
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.RequestName: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to enabled R/- watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_watch_toggle] (0x4000): Toggle to disabled -/W watch on 21
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner sssd.pam has changed from [] to [sssd.pam]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.Hello on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_hello] (0x4000): Assigning unique name :1.4 to connection 0x55906cbb6240
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.Hello: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.3: org.freedesktop.DBus.NameOwnerChanged
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.3: org.freedesktop.DBus.NameAcquired
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method sssd.DataProvider.Client.Register on /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.pam]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner :1.4 has changed from [] to [:1.4]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.GetConnectionUnixUser on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.GetConnectionUnixUser: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.RequestName on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_server_bus_request_name] (0x0400): Requesting name: sssd.nss
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.RequestName: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.pam]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_add] (0x2000): Inserting identity of sender [sssd.pam]: 474
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Added Frontend client [pam]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55906cba52b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.DataProvider.Client.Register: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner sssd.nss has changed from [] to [sssd.nss]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.AddMatch on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.4: org.freedesktop.DBus.NameOwnerChanged
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_match_rule_add] (0x4000): Adding match rule for :1.4: org.freedesktop.DBus.NameAcquired
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.AddMatch: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method sssd.DataProvider.Client.Register on /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.nss]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method org.freedesktop.DBus.GetConnectionUnixUser on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.GetConnectionUnixUser: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner :1.4 has changed from [] to [:1.4]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.nss]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_add] (0x2000): Inserting identity of sender [sssd.nss]: 474
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cba54f0], ldap[0x55906cbb2090]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_ENTRY]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [netlogon]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cba54f0], ldap[0x55906cbb2090]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_RESULT]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_destructor] (0x2000): [RID#1] Operation 1 finished
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_handle_release] (0x2000): [RID#1] Trace: sh[0x55906cb8e1a0], connected[1], ops[(nil)], ldap[0x55906cbb2090], destructor_lock[0], release_memory[0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [remove_connection_callback] (0x4000): [RID#1] Successfully removed connection callback.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_dc_done] (0x0400): [RID#1] dc1.test.alt:389: found site (Default-First-Site-Name) and forest (test.alt)
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_done] (0x0400): [RID#1] Found site: Default-First-Site-Name
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_cldap_ping_done] (0x0400): [RID#1] Found forest: test.alt
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_srv_plugin_ping_done] (0x0400): [RID#1] About to discover primary and backup servers
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_servers_send] (0x0400): [RID#1] Looking up primary servers
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_discover_srv_next_domain] (0x0400): [RID#1] SRV resolution of service 'ldap'. Will use DNS discovery domain 'Default-First-Site-Name._sites.TEST.ALT'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_send] (0x0100): [RID#1] Trying to resolve SRV record of '_ldap._tcp.Default-First-Site-Name._sites.TEST.ALT'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_request_timeout] (0x2000): [RID#1] Scheduling a timeout of 3 seconds
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_timeout_watcher] (0x2000): [RID#1] Scheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [unschedule_timeout_watcher] (0x4000): [RID#1] Unscheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_done] (0x1000): [RID#1] Using TTL [900]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got answer. Processing...
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got 1 servers
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_servers_primary_done] (0x0400): [RID#1] Looking up backup servers
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_discover_srv_next_domain] (0x0400): [RID#1] SRV resolution of service 'ldap'. Will use DNS discovery domain 'TEST.ALT'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_send] (0x0100): [RID#1] Trying to resolve SRV record of '_ldap._tcp.TEST.ALT'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_request_timeout] (0x2000): [RID#1] Scheduling a timeout of 3 seconds
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_timeout_watcher] (0x2000): [RID#1] Scheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Added Frontend client [nss]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x55906cbc2e90]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.DataProvider.Client.Register: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner sssd.pam has changed from [] to [sssd.pam]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method sssd.dataprovider.getDomains on /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.pam]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_requests_add] (0x4000): Chaining request: 474:0:sssd.dataprovider.getDomains:/sssd:
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [unschedule_timeout_watcher] (0x4000): [RID#1] Unscheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_getsrv_done] (0x1000): [RID#1] Using TTL [900]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got answer. Processing...
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_discover_srv_done] (0x0400): [RID#1] Got 1 servers
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_srv_plugin_servers_done] (0x0400): [RID#1] Got 1 primary and 1 backup servers
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_add_server_to_list] (0x0400): [RID#1] Inserted primary server 'dc1.test.alt:389' to service 'AD'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_add_server_to_list] (0x0400): [RID#1] Server 'dc1.test.alt:389' for service 'AD' is already present
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [set_srv_data_status] (0x0100): [RID#1] Marking SRV lookup of service 'AD' as 'resolved'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [get_server_status] (0x1000): [RID#1] Status of server 'dc1.test.alt' is 'name not resolved'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_is_unix] (0x4000): [RID#1] [dc1.test.alt] does not look like a unix domain socket
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_is_address] (0x4000): [RID#1] [dc1.test.alt] does not look like an IP address
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): [RID#1] Querying files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [set_server_common_status] (0x0100): [RID#1] Marking server 'dc1.test.alt' as 'resolving name'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): [RID#1] Querying files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_files_send] (0x0100): [RID#1] Trying to resolve AAAA record of 'dc1.test.alt' in files
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_next] (0x0200): [RID#1] No more address families to retry
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): [RID#1] Querying DNS
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): [RID#1] Trying to resolve A record of 'dc1.test.alt' in DNS
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_request_timeout] (0x2000): [RID#1] Scheduling a timeout of 3 seconds
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [schedule_timeout_watcher] (0x2000): [RID#1] Scheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [unschedule_timeout_watcher] (0x4000): [RID#1] Unscheduling DNS timeout watcher
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): [RID#1] Parsing an A reply
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [request_watch_destructor] (0x0400): [RID#1] Deleting request watch
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [set_server_common_status] (0x0100): [RID#1] Marking server 'dc1.test.alt' as 'name resolved'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x1000): [RID#1] Saving the first resolved server
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x0200): [RID#1] Found address for server dc1.test.alt: [10.88.14.204] TTL 900
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_resolve_callback] (0x0100): [RID#1] Constructed uri 'ldap://dc1.test.alt'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_resolve_callback] (0x0100): [RID#1] Constructed GC uri 'ldap://dc1.test.alt'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [unique_filename_destructor] (0x2000): [RID#1] Unlinking [/var/lib/sss/pubconf/.krb5info_dummy_rYsR8p]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [unlink_dbg] (0x2000): [RID#1] File already removed: [/var/lib/sss/pubconf/.krb5info_dummy_rYsR8p]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssd_async_socket_init_send] (0x4000): [RID#1] Using file descriptor [23] for the connection.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sssd_async_socket_init_send] (0x0400): [RID#1] Setting 6 seconds timeout [ldap_network_timeout] for connecting
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_ldap_connect_callback_add] (0x4000): [RID#1] New connection to [ldap://dc1.test.alt:389/??base] with fd [23]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_rootdse_send] (0x4000): [RID#1] Getting rootdse
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(objectclass=*)][].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [*]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [altServer]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [namingContexts]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedControl]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedExtension]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedFeatures]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedLDAPVersion]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [supportedSASLMechanisms]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [domainControllerFunctionality]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [defaultNamingContext]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [lastUSN]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [highestCommittedUSN]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x2000): [RID#1] ldap_search_ext called, msgid = 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_add] (0x2000): [RID#1] New operation 1 timeout 6
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbc2e90], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_ENTRY]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [configurationNamingContext]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [defaultNamingContext]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [rootDomainNamingContext]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [schemaNamingContext]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [subschemaSubentry]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [supportedCapabilities]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [supportedLDAPVersion]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [vendorName]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [isSynchronized]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [dsServiceName]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [serverName]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [dnsHostName]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [ldapServiceName]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [currentTime]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [supportedControl]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [namingContexts]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [supportedSASLMechanisms]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [highestCommittedUSN]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [domainFunctionality]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [forestFunctionality]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [domainControllerFunctionality]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [isGlobalCatalogReady]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbc2e90], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_RESULT]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_destructor] (0x2000): [RID#1] Operation 1 finished
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_rootdse_done] (0x2000): [RID#1] Got rootdse
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [get_naming_context] (0x0200): [RID#1] Using value from [defaultNamingContext] as naming context.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [DEFAULT][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_user_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [USER][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_group_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [GROUP][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_netgroup_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [NETGROUP][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_host_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [HOST][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_sudo_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [SUDO][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_service_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [SERVICE][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_autofs_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [AUTOFS][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_iphost_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [IPHOST][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_set_search_base] (0x0100): [RID#1] Setting option [ldap_ipnetwork_search_base] to [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [common_parse_search_base] (0x0100): [RID#1] Search base added: [IPNETWORK][DC=test,DC=alt][SUBTREE][]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_server_opts_from_rootdse] (0x4000): [RID#1] USN value: 4235 (int: 4235)
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_server_opts_from_rootdse] (0x0100): [RID#1] Setting AD compatibility level to [4]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_server_opts_from_rootdse] (0x0100): [RID#1] Will look for schema at [CN=Schema,CN=Configuration,DC=test,DC=alt]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_kinit_send] (0x0400): [RID#1] Attempting kinit (default, IEZ60W9U$, TEST.ALT, 86400)
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_kinit_next_kdc] (0x1000): [RID#1] Resolving next KDC for service AD
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_resolve_service_send] (0x0100): [RID#1] Trying to resolve service 'AD'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [get_server_status] (0x1000): [RID#1] Status of server 'dc1.test.alt' is 'name resolved'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_resolve_service_activate_timeout] (0x2000): [RID#1] Resolve timeout [dns_resolver_timeout] set to 6 seconds
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [resolve_srv_send] (0x0200): [RID#1] The status of SRV lookup is resolved
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [get_server_status] (0x1000): [RID#1] Status of server 'dc1.test.alt' is 'name resolved'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x1000): [RID#1] Saving the first resolved server
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_resolve_server_process] (0x0200): [RID#1] Found address for server dc1.test.alt: [10.88.14.204] TTL 900
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_kinit_kdc_resolved] (0x1000): [RID#1] KDC resolved, attempting to get TGT...
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [create_tgt_req_send_buffer] (0x0400): [RID#1] buffer size: 41
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [child_handler_setup] (0x2000): [RID#1] Setting up signal handler up for pid [2657]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [child_handler_setup] (0x2000): [RID#1] Signal handler set up for pid [2657]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [set_tgt_child_timeout] (0x0400): [RID#1] Setting 8 seconds timeout for TGT child
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[(nil)], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [_write_pipe_handler] (0x0400): [RID#1] All data has been sent!
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner :1.5 has changed from [] to [:1.5]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_signal_handler] (0x2000): Received D-Bus signal org.freedesktop.DBus.NameOwnerChanged on /org/freedesktop/DBus
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_name_owner_changed] (0x4000): Name of owner sssd.nss has changed from [] to [sssd.nss]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): org.freedesktop.DBus.NameOwnerChanged: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method sssd.dataprovider.getDomains on /sssd
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.nss]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_requests_add] (0x4000): Chaining request: 474:0:sssd.dataprovider.getDomains:/sssd:
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [child_sig_handler] (0x1000): [RID#1] Waiting for child [2657].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [child_sig_handler] (0x0100): [RID#1] child [2657] finished successfully.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [_read_pipe_handler] (0x0400): [RID#1] EOF received, client finished
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_tgt_recv] (0x0400): [RID#1] Child responded: 0 [FILE:/var/lib/sss/db/ccache_TEST.ALT], expired on [1722384797]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_cli_auth_step] (0x0100): [RID#1] expire timeout is 900
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_cli_auth_step] (0x1000): [RID#1] the connection will expire at 1722349697
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sasl_bind_send] (0x0100): [RID#1] Executing sasl bind mech: GSS-SPNEGO, user: IEZ60W9U$
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_sasl_log] (0x4000): [RID#1] SASL: GSSAPI client step 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_sasl_log] (0x4000): [RID#1] SASL: GSSAPI client step 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_sasl_log] (0x4000): [RID#1] SASL: GSSAPI client step 1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_cli_connect_recv] (0x0400): [RID#1] Connection established.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [_be_fo_set_port_status] (0x8000): [RID#1] Setting status: PORT_WORKING. Called from: src/providers/ldap/sdap_async_connection.c: sdap_cli_connect_recv: 2178
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_set_port_status] (0x0100): [RID#1] Marking port 389 of server 'dc1.test.alt' as 'working'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [set_server_common_status] (0x0100): [RID#1] Marking server 'dc1.test.alt' as 'working'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [fo_set_port_status] (0x0400): [RID#1] Marking port 389 of duplicate server 'dc1.test.alt' as 'working'
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_conn_data_start_idle_timer] (0x0100): [RID#1] idle timeout is 900
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_conn_data_start_idle_timer] (0x4000): [RID#1] Scheduling connection idle timer to run at 1722349697
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_op_connect_done] (0x4000): [RID#1] notify connected to op #1
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [objectclass=domain][DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [objectSID]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x2000): [RID#1] ldap_search_ext called, msgid = 3
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_add] (0x2000): [RID#1] New operation 3 timeout 6
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_op_connect_done] (0x4000): [RID#1] caching successful connection after 1 notifies
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_run_unconditional_online_cb] (0x4000): [RID#1] List of unconditional online callbacks is empty, nothing to do.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_run_online_cb] (0x0080): [RID#1] Going online. Running callbacks.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cb5dc00], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_ENTRY]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [objectSid]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cb5dc00], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_RESULT]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_destructor] (0x2000): [RID#1] Operation 3 finished
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_next_done] (0x0400): [RID#1] Found SID [S-1-5-21-2128886017-959441534-230989675].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(&(DnsDomain=TEST.ALT)(NtVer=\14\00\00\00))][].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [netlogon]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x2000): [RID#1] ldap_search_ext called, msgid = 4
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_add] (0x2000): [RID#1] New operation 4 timeout 6
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb960], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb960], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_ENTRY]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): [RID#1] OriginalDN: [].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_parse_range] (0x2000): [RID#1] No sub-attributes for [netlogon]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb960], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_RESULT]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_destructor] (0x2000): [RID#1] Operation 4 finished
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): [RID#1] Found flat name [TEST].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): [RID#1] Found site [Default-First-Site-Name].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): [RID#1] Found forest [test.alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_check_gc_usability_send] (0x0400): [RID#1] GC always usable while ID mapping
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_op_done] (0x4000): [RID#1] releasing operation connection
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_conn_data_idle] (0x4000): [RID#1] Marking connection as idle
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_op_connect_step] (0x4000): [RID#1] reusing cached connection
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_conn_data_not_idle] (0x4000): [RID#1] Marking connection as not idle
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_search_bases_ex_next_base] (0x0400): [RID#1] Issuing LDAP lookup with base [DC=test,DC=alt]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): [RID#1] calling ldap_search_ext with [(&(objectclass=trustedDomain)(trustType=2)(!(msDS-TrustForestTrustInfo=*)))][DC=test,DC=alt].
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [flatName]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustPartner]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [securityIdentifier]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustType]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [cn]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustAttributes]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): [RID#1] Requesting attrs: [trustDirection]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x2000): [RID#1] ldap_search_ext called, msgid = 5
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_add] (0x2000): [RID#1] New operation 5 timeout 6
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb6d0], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb6d0], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_REFERENCE]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): [RID#1] Additional References: ldap://test.alt/CN=Configuration,DC=test,DC=alt
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb6d0], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_REFERENCE]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): [RID#1] Additional References: ldap://test.alt/DC=DomainDnsZones,DC=test,DC=alt
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb6d0], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_REFERENCE]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): [RID#1] Additional References: ldap://test.alt/DC=ForestDnsZones,DC=test,DC=alt
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cbbb6d0], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_message] (0x4000): [RID#1] Message type: [LDAP_RES_SEARCH_RESULT]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): [RID#1] Search result: Success(0), no errmsg set
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_op_destructor] (0x2000): [RID#1] Operation 5 finished
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [generic_ext_search_handler] (0x4000): [RID#1] Request included referrals which were ignored.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_search_bases_ex_done] (0x0400): [RID#1] Receiving data from base [DC=test,DC=alt]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_op_done] (0x4000): [RID#1] releasing operation connection
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_id_conn_data_idle] (0x4000): [RID#1] Marking connection as idle
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_get_slave_domain_done] (0x1000): [RID#1] There are no changes
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_subdomains_refresh_done] (0x0400): [RID#1] Subdomains refreshed.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_done] (0x0400): [RID#1] DP Request [Subdomains #1]: Request handler finished [0]: ÐÑÐ¿Ð¾Ð»Ð½ÐµÐ½Ð¾
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [_dp_req_recv] (0x0400): [RID#1] DP Request [Subdomains #1]: Receiving request data.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#1] DP Request [Subdomains #1]: Request removed.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#1] Number of active DP request: 0
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [dp_req_reply_std] (0x1000): [RID#1] DP Request [Subdomains #1]: Returning [Success]: 0,0,Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getDomains: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getDomains: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getDomains: Success
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[(nil)], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [Subdomains Refresh]: already enabled
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [SUDO Smart Refresh]: already enabled
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [SUDO Full Refresh]: already enabled
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [sdap_sudo_online_cb] (0x0400): [RID#1] We are back online. SUDO host information will be renewed on next refresh.
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [AD machine account password renewal]: already enabled
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_online_cb] (0x0400): [RID#1] Back end is online
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [be_ptask_enable] (0x0080): [RID#1] Task [Dyndns update]: already enabled
>   *  (2024-07-30 17:13:17): [be[TEST.ALT]] [ad_online_cb] (0x0400): [RID#1] The AD provider is online
>   *  (2024-07-30 17:13:18): [be[TEST.ALT]] [be_run_unconditional_online_cb] (0x4000): List of unconditional online callbacks is empty, nothing to do.
>   *  (2024-07-30 17:13:18): [be[TEST.ALT]] [check_if_online_delayed] (0x2000): Backend is already online, nothing to do.
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_execute] (0x0400): Task [SUDO Full Refresh]: executing task, timeout 21600 seconds
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_full_refresh_send] (0x0400): Issuing a full refresh of sudo rules
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_id_op_connect_step] (0x4000): reusing cached connection
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_id_conn_data_not_idle] (0x4000): Marking connection as not idle
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_refresh_connect_done] (0x0400): SUDO LDAP connection successful
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [check_ipv4_addr] (0x0200): Loopback IPv4 address 127.0.0.1
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: 10.88.15.163 in network 10.88.8.0/21
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [check_ipv6_addr] (0x0200): Loopback IPv6 address ::1
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: 2a0c:88c0:2:2000:24cb:15ff:feb0:c8c9 in network 2a0c:88c0:2:2000::/64
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: fe80::24cb:15ff:feb0:c8c9 in network fe80::/64
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_get_hostnames_send] (0x2000): Found fqdn: iez60w9u.test.alt
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_get_hostnames_send] (0x2000): Found hostname: iez60w9u
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_load_sudoers_send] (0x0400): About to fetch sudo rules
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_search_bases_ex_next_base] (0x0400): Issuing LDAP lookup with base [DC=test,DC=alt]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectCategory=sudoRole)(|(&(!(sudoHost=*))(cn=defaults))(sudoHost=ALL)(sudoHost=iez60w9u.test.alt)(sudoHost=iez60w9u)(sudoHost=10.88.15.163)(sudoHost=10.88.8.0/21)(sudoHost=2a0c:88c0:2:2000:24cb:15ff:feb0:c8c9)(sudoHost=2a0c:88c0:2:2000::/64)(sudoHost=fe80::24cb:15ff:feb0:c8c9)(sudoHost=fe80::/64)(sudoHost=+*)))][DC=test,DC=alt].
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectCategory]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoCommand]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoHost]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoUser]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOption]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAs]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsUser]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsGroup]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotBefore]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotAfter]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOrder]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 6
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_op_add] (0x2000): New operation 6 timeout 6
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cb84e00], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/CN=Configuration,DC=test,DC=alt
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cb84e00], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/DC=DomainDnsZones,DC=test,DC=alt
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cb84e00], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/DC=ForestDnsZones,DC=test,DC=alt
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[0x55906cb84e00], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x2000): Total count [0]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_op_destructor] (0x2000): Operation 6 finished
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [generic_ext_search_handler] (0x4000): Request included referrals which were ignored.
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_search_bases_ex_done] (0x0400): Receiving data from base [DC=test,DC=alt]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_load_sudoers_done] (0x0200): Received 0 sudo rules
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_id_op_done] (0x4000): releasing operation connection
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_id_conn_data_idle] (0x4000): Marking connection as idle
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_refresh_done] (0x0400): Received 0 rules
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sysdb_sudo_purge_all] (0x0400): Deleting all cached sudo rules
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sysdb_merge_res_ts_attrs] (0x2000): TS cache doesn't handle this DN type, skipping
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sysdb_delete_recursive_with_filter] (0x4000): Found [1] items to delete.
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sysdb_delete_recursive_with_filter] (0x4000): Trying to delete [cn=sudorules,cn=custom,cn=TEST.ALT,cn=sysdb].
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_refresh_done] (0x0400): Sudoers is successfully stored in cache
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_set_usn] (0x0200): SUDO higher USN value: [4236]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_sudo_full_refresh_done] (0x0400): Successful full refresh of sudo rules
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_postpone] (0x0400): Task [SUDO Smart Refresh]: rescheduling task
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Smart Refresh]: scheduling task 900 seconds from now [1722349707]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_done] (0x0400): Task [SUDO Full Refresh]: finished successfully
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [SUDO Full Refresh]: scheduling task 21600 seconds from last execution time [1722370407]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: sh[0x55906cb8e1a0], connected[1], ops[(nil)], ldap[0x55906cbc36b0]
>   *  (2024-07-30 17:13:27): [be[TEST.ALT]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_method_handler] (0x2000): Received D-Bus method sssd.service.resetOffline on /sssd
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.monitor]
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_dispatch] (0x4000): Dispatching.
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_senders_lookup] (0x2000): Looking for identity of sender [sssd.monitor]
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_senders_add] (0x2000): Inserting identity of sender [sssd.monitor]: 0
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [check_if_online] (0x2000): Schedule check_if_online_delayed in 1s.
>   *  (2024-07-30 17:13:35): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.service.resetOffline: Success
>   *  (2024-07-30 17:13:36): [be[TEST.ALT]] [be_run_unconditional_online_cb] (0x4000): List of unconditional online callbacks is empty, nothing to do.
>   *  (2024-07-30 17:13:36): [be[TEST.ALT]] [check_if_online_delayed] (0x2000): Backend is already online, nothing to do.
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [be_ptask_execute] (0x0400): Task [Dyndns update]: executing task, timeout 60 seconds
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [ad_dyndns_update_send] (0x0400): Performing update
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_id_op_connect_step] (0x4000): reusing cached connection
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_id_conn_data_not_idle] (0x4000): Marking connection as not idle
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_id_op_connect_step] (0x4000): reusing cached connection
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_id_conn_data_not_idle] (0x4000): Marking connection as not idle
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [check_ipv6_addr] (0x0200): Link local IPv6 address fe80::24cb:15ff:feb0:c8c9
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_id_op_destroy] (0x4000): releasing operation connection
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_is_unix] (0x4000): [iez60w9u.test.alt] does not look like a unix domain socket
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_is_address] (0x4000): [iez60w9u.test.alt] does not look like an IP address
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): Querying DNS
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'iez60w9u.test.alt' in DNS
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 3 seconds
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [unschedule_timeout_watcher] (0x4000): Unscheduling DNS timeout watcher
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [request_watch_destructor] (0x0400): Deleting request watch
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_is_unix] (0x4000): [iez60w9u.test.alt] does not look like a unix domain socket
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_is_address] (0x4000): [iez60w9u.test.alt] does not look like an IP address
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_step] (0x2000): Querying DNS
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve AAAA record of 'iez60w9u.test.alt' in DNS
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 3 seconds
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [unschedule_timeout_watcher] (0x4000): Unscheduling DNS timeout watcher
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an AAAA reply
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [request_watch_destructor] (0x0400): Deleting request watch
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_msg_create_common] (0x0200): Creating update message for auto-discovered realm.
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_create_fwd_msg] (0x0400):  -- Begin nsupdate message -- 
>
>update delete iez60w9u.test.alt. in A
>update add iez60w9u.test.alt. 10 in A 10.88.15.163
>send
>update delete iez60w9u.test.alt. in AAAA
>update add iez60w9u.test.alt. 10 in AAAA 2a0c:88c0:2:2000:24cb:15ff:feb0:c8c9
>send
> -- End nsupdate message -- 
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [2661]
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_handler_setup] (0x2000): Signal handler set up for pid [2661]
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [_write_pipe_handler] (0x0400): All data has been sent!
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_stdin_done] (0x1000): Sending nsupdate data complete
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x1000): Waiting for child [2661].
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x0100): child [2661] finished successfully.
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_done] (0x0200): nsupdate child status: 0
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_msg_create_common] (0x0200): Creating update message for auto-discovered realm.
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_create_ptr_msg] (0x0400):  -- Begin nsupdate message -- 
>
>update delete 163.15.88.10.in-addr.arpa. in PTR
>update add 163.15.88.10.in-addr.arpa. 10 in PTR iez60w9u.test.alt.
>send
>update delete 9.c.8.c.0.b.e.f.f.f.5.1.b.c.4.2.0.0.0.2.2.0.0.0.0.c.8.8.c.0.a.2.ip6.arpa. in PTR
>update add 9.c.8.c.0.b.e.f.f.f.5.1.b.c.4.2.0.0.0.2.2.0.0.0.0.c.8.8.c.0.a.2.ip6.arpa. 10 in PTR iez60w9u.test.alt.
>send
> -- End nsupdate message -- 
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [2666]
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_handler_setup] (0x2000): Signal handler set up for pid [2666]
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [_write_pipe_handler] (0x0400): All data has been sent!
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_stdin_done] (0x1000): Sending nsupdate data complete
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x1000): Waiting for child [2666].
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x0020): child [2666] failed with status [2].
>********************** BACKTRACE DUMP ENDS HERE *********************************
>
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_handler] (0x0040): Dynamic DNS child failed with status [512]
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_done] (0x0040): nsupdate child execution failed [1432158240]: Dynamic DNS update failed
>********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_handler] (0x0040): Dynamic DNS child failed with status [512]
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_done] (0x0040): nsupdate child execution failed [1432158240]: Dynamic DNS update failed
>********************** BACKTRACE DUMP ENDS HERE *********************************
>
>(2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_dyndns_update_ptr_done] (0x0080): nsupdate failed, retrying
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_msg_create_common] (0x0200): Creating update message for realm [TEST.ALT].
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_create_ptr_msg] (0x0400):  -- Begin nsupdate message -- 
>realm TEST.ALT
>update delete 163.15.88.10.in-addr.arpa. in PTR
>update add 163.15.88.10.in-addr.arpa. 10 in PTR iez60w9u.test.alt.
>send
>update delete 9.c.8.c.0.b.e.f.f.f.5.1.b.c.4.2.0.0.0.2.2.0.0.0.0.c.8.8.c.0.a.2.ip6.arpa. in PTR
>update add 9.c.8.c.0.b.e.f.f.f.5.1.b.c.4.2.0.0.0.2.2.0.0.0.0.c.8.8.c.0.a.2.ip6.arpa. 10 in PTR iez60w9u.test.alt.
>send
> -- End nsupdate message -- 
>(2024-07-30 17:13:40): [be[TEST.ALT]] [_write_pipe_handler] (0x0400): All data has been sent!
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_stdin_done] (0x1000): Sending nsupdate data complete
>(2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x1000): Waiting for child [2671].
>(2024-07-30 17:13:40): [be[TEST.ALT]] [child_sig_handler] (0x0020): child [2671] failed with status [2].
>   *  ... skipping repetitive backtrace ...
>(2024-07-30 17:13:40): [be[TEST.ALT]] [nsupdate_child_handler] (0x0040): Dynamic DNS child failed with status [512]
>   *  ... skipping repetitive backtrace ...
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_nsupdate_done] (0x0040): nsupdate child execution failed [1432158240]: Dynamic DNS update failed
>   *  ... skipping repetitive backtrace ...
>(2024-07-30 17:13:40): [be[TEST.ALT]] [ad_dyndns_sdap_update_done] (0x0040): Dynamic DNS update failed [1432158240]: Dynamic DNS update failed
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_ptask_done] (0x0040): Task [Dyndns update]: failed with [1432158240]: Dynamic DNS update failed
>********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [ad_dyndns_sdap_update_done] (0x0040): Dynamic DNS update failed [1432158240]: Dynamic DNS update failed
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_id_op_destroy] (0x4000): releasing operation connection
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [sdap_id_conn_data_idle] (0x4000): Marking connection as idle
>   *  (2024-07-30 17:13:40): [be[TEST.ALT]] [be_ptask_done] (0x0040): Task [Dyndns update]: failed with [1432158240]: Dynamic DNS update failed
>********************** BACKTRACE DUMP ENDS HERE *********************************
>
>(2024-07-30 17:13:40): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [Dyndns update]: scheduling task 210 seconds from now [1722349030]
>(2024-07-30 17:13:46): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.service.resetOffline: Success
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#2] DP Request [AccountDomain #2]: REQ_TRACE: New request. [sssd.nss CID #1] Flags [0x0001].
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#2] Number of active DP request: 1
>(2024-07-30 17:13:56): [be[TEST.ALT]] [ad_get_account_domain_send] (0x0100): [RID#2] No point in locating domain with GC if ID-mapping is enabled
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_done] (0x0400): [RID#2] DP Request [AccountDomain #2]: Request handler finished [0]: ÐÑÐ¿Ð¾Ð»Ð½ÐµÐ½Ð¾
>(2024-07-30 17:13:56): [be[TEST.ALT]] [_dp_req_recv] (0x0400): [RID#2] DP Request [AccountDomain #2]: Receiving request data.
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#2] DP Request [AccountDomain #2]: Request removed.
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#2] Number of active DP request: 0
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_reply_std] (0x1000): [RID#2] DP Request [AccountDomain #2]: Returning [Internal Error]: 3,1432158304,GetAccountDomain() not supported
>(2024-07-30 17:13:56): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getAccountDomain: Success
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_get_account_info_send] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=440]
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#3] DP Request [Account #3]: REQ_TRACE: New request. [sssd.nss CID #1] Flags [0x0001].
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_attach_req] (0x0400): [RID#3] Number of active DP request: 1
>(2024-07-30 17:13:56): [be[TEST.ALT]] [sss_domain_get_state] (0x1000): [RID#3] Domain TEST.ALT is Active
>(2024-07-30 17:13:56): [be[TEST.ALT]] [ad_account_can_shortcut] (0x0080): [RID#3] Mapping ID [440] to SID failed: [IDMAP domain not found]
>(2024-07-30 17:13:56): [be[TEST.ALT]] [ad_handle_acct_info_send] (0x0400): [RID#3] This ID is from different domain
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_done] (0x0400): [RID#3] DP Request [Account #3]: Request handler finished [0]: ÐÑÐ¿Ð¾Ð»Ð½ÐµÐ½Ð¾
>(2024-07-30 17:13:56): [be[TEST.ALT]] [_dp_req_recv] (0x0400): [RID#3] DP Request [Account #3]: Receiving request data.
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#3] DP Request [Account #3]: Request removed.
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_destructor] (0x0400): [RID#3] Number of active DP request: 0
>(2024-07-30 17:13:56): [be[TEST.ALT]] [dp_req_reply_std] (0x1000): [RID#3] DP Request [Account #3]: Returning [Success]: 0,0,Success
>(2024-07-30 17:13:56): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.dataprovider.getAccountInfo: Success
>(2024-07-30 17:14:00): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.service.resetOffline: Success
>(2024-07-30 17:14:08): [be[TEST.ALT]] [be_ptask_execute] (0x0400): Task [Subdomains Refresh]: executing task, timeout 14400 seconds
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectclass=domain][DC=test,DC=alt].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectSID]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): OriginalDN: [DC=test,DC=alt].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>(2024-07-30 17:14:08): [be[TEST.ALT]] [ad_domain_info_next_done] (0x0400): Found SID [S-1-5-21-2128886017-959441534-230989675].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(DnsDomain=TEST.ALT)(NtVer=\14\00\00\00))][].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [netlogon]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_parse_entry] (0x1000): OriginalDN: [].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>(2024-07-30 17:14:08): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): Found flat name [TEST].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): Found site [Default-First-Site-Name].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [ad_domain_info_netlogon_done] (0x0400): Found forest [test.alt].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [ad_check_gc_usability_send] (0x0400): GC always usable while ID mapping
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_search_bases_ex_next_base] (0x0400): Issuing LDAP lookup with base [DC=test,DC=alt]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectclass=trustedDomain)(trustType=2)(!(msDS-TrustForestTrustInfo=*)))][DC=test,DC=alt].
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [flatName]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [trustPartner]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [securityIdentifier]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [trustType]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [trustAttributes]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [trustDirection]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/CN=Configuration,DC=test,DC=alt
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/DC=DomainDnsZones,DC=test,DC=alt
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://test.alt/DC=ForestDnsZones,DC=test,DC=alt
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sdap_search_bases_ex_done] (0x0400): Receiving data from base [DC=test,DC=alt]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [ad_get_slave_domain_done] (0x1000): There are no changes
>(2024-07-30 17:14:08): [be[TEST.ALT]] [ad_subdomains_refresh_done] (0x0400): Subdomains refreshed.
>(2024-07-30 17:14:08): [be[TEST.ALT]] [be_ptask_done] (0x0400): Task [Subdomains Refresh]: finished successfully
>(2024-07-30 17:14:08): [be[TEST.ALT]] [be_ptask_schedule] (0x0400): Task [Subdomains Refresh]: scheduling task 14620 seconds from last execution time [1722363468]
>(2024-07-30 17:14:08): [be[TEST.ALT]] [sbus_issue_request_done] (0x0400): sssd.service.resetOffline: Success

Actions: View

Attachments on bug 51006: 16514