Hi folks, On 02/23/16 13:46, Lukas Slebodnik wrote: > On (23/02/16 13:01), Harald Dunkel wrote: >> On 02/23/2016 11:58 AM, Lukas Slebodnik wrote: >>> I would rather focus on different thing. >>> Why is sssd_be process blocked for long time? >>> >> >> I have no idea. Was it really blocked? >> > It needn't be blocked itself. But it was busy > with some non-blocking operation which main process > considered as bad state. > > Would you mind to share sssd log files with > high debug level? >
It happened again :-(.This *really* needs to be fixed. I wouldn't like to move back to ypbind. Logfiles are attached. sssd is version 1.13.3. The server was rebooted at 05:56. At 06:03:18 sssd wrote the first logfile entries. Every helpful comment is highly appreciated. Harri
(Thu May 12 05:55:10 2016) [sssd] [message_type] (0x0200): netlink Message type: 24 (Thu May 12 05:55:40 2016) [sssd] [message_type] (0x0200): netlink Message type: 24 (Thu May 12 05:55:48 2016) [sssd] [message_type] (0x0200): netlink Message type: 25 (Thu May 12 05:56:05 2016) [sssd] [monitor_quit_signal] (0x0040): Monitor received Terminated: terminating children (Thu May 12 05:56:05 2016) [sssd] [monitor_quit] (0x0040): Returned with: 0 (Thu May 12 05:56:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [example.com][51281] (Thu May 12 05:56:05 2016) [sssd] [monitor_quit] (0x0020): Child [example.com] exited gracefully (Thu May 12 05:56:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [pac][445] (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Child [pac] terminated with a signal (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Terminating [ssh][444] (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Child [ssh] exited gracefully (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Terminating [pam][443] (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Child [pam] exited gracefully (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Terminating [sudo][442] (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Child [sudo] exited gracefully (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Terminating [nss][441] (Thu May 12 05:56:12 2016) [sssd] [monitor_quit] (0x0020): Child [nss] exited gracefully (Thu May 12 06:03:18 2016) [sssd] [sysdb_domain_init_internal] (0x0200): DB File for example.com: /var/lib/sss/db/cache_example.com.ldb (Thu May 12 06:03:20 2016) [sssd] [get_ping_config] (0x0100): Time between service pings for [example.com]: [10] (Thu May 12 06:03:20 2016) [sssd] [get_ping_config] (0x0100): Time between SIGTERM and SIGKILL for [example.com]: [60] (Thu May 12 06:03:20 2016) [sssd] [start_service] (0x0100): Queueing service example.com for startup (Thu May 12 06:03:22 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:22 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Adding connection 0x10f2a40. (Thu May 12 06:03:22 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:25 2016) [sssd] [services_startup_timeout] (0x0020): Providers did not start in time, forcing services startup! (Thu May 12 06:03:25 2016) [sssd] [services_startup_timeout] (0x0100): Now starting services! (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between service pings for [nss]: [10] (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between SIGTERM and SIGKILL for [nss]: [60] (Thu May 12 06:03:25 2016) [sssd] [start_service] (0x0100): Queueing service nss for startup (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between service pings for [sudo]: [10] (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between SIGTERM and SIGKILL for [sudo]: [60] (Thu May 12 06:03:25 2016) [sssd] [start_service] (0x0100): Queueing service sudo for startup (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between service pings for [pam]: [10] (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between SIGTERM and SIGKILL for [pam]: [60] (Thu May 12 06:03:25 2016) [sssd] [start_service] (0x0100): Queueing service pam for startup (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between service pings for [ssh]: [10] (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between SIGTERM and SIGKILL for [ssh]: [60] (Thu May 12 06:03:25 2016) [sssd] [start_service] (0x0100): Queueing service ssh for startup (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between service pings for [pac]: [10] (Thu May 12 06:03:25 2016) [sssd] [get_ping_config] (0x0100): Time between SIGTERM and SIGKILL for [pac]: [60] (Thu May 12 06:03:25 2016) [sssd] [start_service] (0x0100): Queueing service pac for startup (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Adding connection 0x10f8ea0. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Adding connection 0x10fa7c0. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Adding connection 0x10fc830. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:26 2016) [sssd] [client_registration] (0x0100): Received ID registration: (ssh,1) (Thu May 12 06:03:26 2016) [sssd] [mark_service_as_started] (0x0200): Marking ssh as started. (Thu May 12 06:03:26 2016) [sssd] [client_registration] (0x0100): Received ID registration: (sudo,1) (Thu May 12 06:03:26 2016) [sssd] [mark_service_as_started] (0x0200): Marking sudo as started. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Adding connection 0x10ffed0. (Thu May 12 06:03:26 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:26 2016) [sssd] [client_registration] (0x0100): Received ID registration: (pam,1) (Thu May 12 06:03:26 2016) [sssd] [mark_service_as_started] (0x0200): Marking pam as started. (Thu May 12 06:03:29 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:29 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Adding connection 0x11027b0. (Thu May 12 06:03:29 2016) [sssd] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:29 2016) [sssd] [client_registration] (0x0100): Received ID registration: (pac,1) (Thu May 12 06:03:29 2016) [sssd] [mark_service_as_started] (0x0200): Marking pac as started. (Thu May 12 06:03:30 2016) [sssd] [tasks_check_handler] (0x0020): Child (example.com) not responding! (yet) (Thu May 12 06:03:31 2016) [sssd] [client_registration] (0x0100): Received ID registration: (%BE_example.com,1) (Thu May 12 06:03:31 2016) [sssd] [mark_service_as_started] (0x0200): Marking example.com as started. (Thu May 12 06:03:35 2016) [sssd] [tasks_check_handler] (0x0020): Child (nss) not responding! (yet) (Thu May 12 06:03:37 2016) [sssd] [init_timeout] (0x0040): Client timed out before Identification! (Thu May 12 06:03:44 2016) [sssd] [message_type] (0x0200): netlink Message type: 25 (Thu May 12 06:03:47 2016) [sssd] [tasks_check_handler] (0x0020): Child (nss) not responding! (yet) (Thu May 12 06:03:57 2016) [sssd] [tasks_check_handler] (0x0020): Child (nss) not responding! (yet) (Thu May 12 06:04:05 2016) [sssd] [monitor_quit_signal] (0x0040): Monitor received Terminated: terminating children (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0040): Returned with: 0 (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [pac][441] (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Child [pac] terminated with a signal (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [ssh][440] (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Child [ssh] exited gracefully (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [pam][439] (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Child [pam] exited gracefully (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [sudo][438] (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Child [sudo] exited gracefully (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [nss][437] (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Child [nss] exited gracefully (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Terminating [example.com][417] (Thu May 12 06:04:05 2016) [sssd] [monitor_quit] (0x0020): Child [example.com] exited gracefully (Thu May 12 06:04:05 2016) [sssd] [monitor_cleanup] (0x0010): Error removing pidfile! (2 [No such file or directory])
(Thu May 12 06:03:21 2016) [sssd[be[example.com]]] [be_res_get_opts] (0x0100): Lookup order: ipv4_first (Thu May 12 06:03:22 2016) [sssd[be[example.com]]] [recreate_ares_channel] (0x0100): Initializing new c-ares channel (Thu May 12 06:03:22 2016) [sssd[be[example.com]]] [sysdb_domain_init_internal] (0x0200): DB File for example.com: /var/lib/sss/db/cache_example.com.ldb (Thu May 12 06:03:22 2016) [sssd[be[example.com]]] [monitor_common_send_id] (0x0100): Sending ID: (%BE_example.com,1) (Thu May 12 06:03:22 2016) [sssd[be[example.com]]] [sss_names_init_from_args] (0x0100): Using re [(((?P<domain>[^\\]+)\\(?P<name>.+$))|((?P<name>[^@]+)@(?P<domain>.+$))|(^(?P<name>[^@\\]+)$))]. (Thu May 12 06:03:22 2016) [sssd[be[example.com]]] [sss_fqnames_init] (0x0100): Using fq format [%1$s@%2$s]. (Thu May 12 06:03:28 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [DEFAULT][cn=accounts,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [sdap_set_sasl_options] (0x0100): Will look for srvvm01.ac.example....@example.com in default keytab (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [select_principal_from_keytab] (0x0200): trying to select the most appropriate principal from keytab (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [select_principal_from_keytab] (0x0200): Selected primary: host/srvvm01.ac.example.com (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [select_principal_from_keytab] (0x0200): Selected realm: EXAMPLE.COM (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [sdap_set_sasl_options] (0x0100): Option ldap_sasl_authid set to host/srvvm01.ac.example.com (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [sdap_set_sasl_options] (0x0100): Option ldap_sasl_realm set to EXAMPLE.COM (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [USER][cn=accounts,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [GROUP][cn=accounts,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [SUDO][ou=SUDOers,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [NETGROUP][cn=ng,cn=alt,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_id_options] (0x0100): Option ipa_host_search_base set to cn=accounts,dc=aixigo,dc=de (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [IPA_HOST][cn=accounts,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [IPA_HBAC][cn=hbac,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_id_options] (0x0100): Option ipa_selinux_search_base set to cn=selinux,dc=aixigo,dc=de (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [IPA_SELINUX][cn=selinux,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [SERVICE][cn=accounts,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_id_options] (0x0100): Option ipa_subdomains_search_base set to cn=trusts,dc=aixigo,dc=de (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [IPA_SUBDOMAINS][cn=trusts,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_id_options] (0x0100): Option ipa_master_domain_search_base set to cn=ad,cn=etc,dc=aixigo,dc=de (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [IPA_MASTER_DOMAIN][cn=ad,cn=etc,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_id_options] (0x0100): Option ipa_ranges_search_base set to cn=ranges,cn=etc,dc=aixigo,dc=de (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [IPA_RANGES][cn=ranges,cn=etc,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_id_options] (0x0100): Option ipa_views_search_base set to cn=views,cn=accounts,dc=aixigo,dc=de (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [IPA_VIEWS][cn=views,cn=accounts,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [krb5_try_kdcip] (0x0100): No KDC found in configuration, trying legacy option (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_auth_options] (0x0100): Option krb5_fast_principal set to host/srvvm01.ac.example....@example.com (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [ipa_get_auth_options] (0x0100): Option krb5_use_kdcinfo set to true (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [check_and_export_lifetime] (0x0200): No lifetime configured. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [check_and_export_lifetime] (0x0200): No lifetime configured. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [check_and_export_options] (0x0100): No KDC explicitly configured, using defaults. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [check_and_export_options] (0x0100): No kpasswd server explicitly configured, using the KDC or defaults. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [check_and_export_options] (0x0100): ccache is of type FILE (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [parse_krb5_map_user] (0x0200): Warning: krb5_map_user is empty! (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [load_backend_module] (0x0200): no module name found in confdb, using [ipa]. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [SUDO][ou=SUDOers,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [load_backend_module] (0x0200): no module name found in confdb, using [ipa]. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [common_parse_search_base] (0x0100): Search base added: [AUTOFS][cn=default,cn=automount,dc=aixigo,dc=de][SUBTREE][] (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [load_backend_module] (0x0200): no module name found in confdb, using [ipa]. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [load_backend_module] (0x0200): no module name found in confdb, using [ipa]. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [load_backend_module] (0x0200): no module name found in confdb, using [ipa]. (Thu May 12 06:03:29 2016) [sssd[be[example.com]]] [sss_write_krb5_localauth_snippet] (0x0200): File for localauth plugin configuration is [/var/lib/sss/pubconf/krb5.include.d/localauth_plugin] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sss_write_domain_mappings] (0x0200): Mapping file for domain [example.com] is [/var/lib/sss/pubconf/krb5.include.d/domain_realm_aixigo_de] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [become_user] (0x0200): Trying to become user [0][0]. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [become_user] (0x0200): Already user [0]. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [id_callback] (0x0010): The Monitor returned an error [org.freedesktop.DBus.Error.NoReply] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0x12cf2e0. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0x12cd150] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0x12cf610. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0x12d09a0] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0x12d3a10. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0x12d50c0] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0x12d66e0. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0x12d78d0] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Entering. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Adding connection 0x12d8ef0. (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [sbus_server_init_new_connection] (0x0200): Got a connection (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [be_client_init] (0x0100): Set-up Backend ID timeout [0x12da280] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Cancel DP ID timeout [0x12cd150] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Added Frontend client [SUDO] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA' (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [resolve_srv_send] (0x0200): The status of SRV lookup is neutral (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_ldap._tcp.example.com' (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Cancel DP ID timeout [0x12d09a0] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Added Frontend client [SSH] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service 'IPA' as 'resolved' (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'ipa2.example.com' in files (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [set_server_common_status] (0x0100): Marking server 'ipa2.example.com' as 'resolving name' (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [set_server_common_status] (0x0100): Marking server 'ipa2.example.com' as 'name resolved' (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [be_resolve_server_process] (0x0200): Found address for server ipa2.example.com: [172.29.96.4] TTL 7200 (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Cancel DP ID timeout [0x12da280] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Added Frontend client [PAC] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Cancel DP ID timeout [0x12d78d0] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [client_registration] (0x0100): Added Frontend client [PAM] (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA' (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved (Thu May 12 06:03:31 2016) [sssd[be[example.com]]] [be_resolve_server_process] (0x0200): Found address for server ipa2.example.com: [172.29.96.4] TTL 7200 (Thu May 12 06:03:36 2016) [sssd[be[example.com]]] [init_timeout] (0x0040): Client timed out before Identification [0x12d50c0]! (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [sdap_kinit_done] (0x0080): Communication with KDC timed out, trying the next one (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'ipa2.example.com' as 'not working' (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA' (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'ipa1.example.com' in files (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [set_server_common_status] (0x0100): Marking server 'ipa1.example.com' as 'resolving name' (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [set_server_common_status] (0x0100): Marking server 'ipa1.example.com' as 'name resolved' (Thu May 12 06:03:37 2016) [sssd[be[example.com]]] [be_resolve_server_process] (0x0200): Found address for server ipa1.example.com: [172.29.96.3] TTL 7200 (Thu May 12 06:03:38 2016) [sssd[be[example.com]]] [child_sig_handler] (0x0020): waitpid did not found a child with changed status. (Thu May 12 06:03:38 2016) [sssd[be[example.com]]] [child_sig_handler] (0x0020): child [446] was terminated by signal [9]. (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [child_sig_handler] (0x0100): child [451] finished successfully. (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900 (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [sasl_bind_send] (0x0100): Executing sasl bind mech: GSSAPI, user: host/srvvm01.ac.example.com (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'ipa2.example.com' as 'working' (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [set_server_common_status] (0x0100): Marking server 'ipa2.example.com' as 'working' (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [be_run_online_cb] (0x0080): Going online. Running callbacks. (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [sysdb_range_create] (0x0040): Invalid range, skipping. Expected that either the secondary base RID or the SID of the trusted domain is set, but not both or none of them. (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [ipa_subdomains_handler_master_done] (0x0020): Master domain record not found! (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [sysdb_range_create] (0x0040): Invalid range, skipping. Expected that either the secondary base RID or the SID of the trusted domain is set, but not both or none of them. (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [ipa_subdomains_handler_master_done] (0x0020): Master domain record not found! (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [be_ptask_enable] (0x0080): Task [SUDO Smart Refresh]: already enabled (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [be_ptask_enable] (0x0080): Task [SUDO Full Refresh]: already enabled (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [delayed_online_authentication_callback] (0x0200): Backend is online, starting delayed online authentication. (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [check_ipv4_addr] (0x0200): Loopback IPv4 address 127.0.0.1 (Thu May 12 06:03:39 2016) [sssd[be[example.com]]] [check_ipv6_addr] (0x0200): Loopback IPv6 address ::1 (Thu May 12 06:04:05 2016) [sssd[be[example.com]]] [remove_krb5_info_files] (0x0200): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.EXAMPLE.COM], [2][No such file or directory]
-- Manage your subscription for the Freeipa-users mailing list: https://www.redhat.com/mailman/listinfo/freeipa-users Go to http://freeipa.org for more info on the project
