On Wed, Aug 21, 2019 at 07:10:50PM -0000, Martijn Bakkes via FreeIPA-users wrote:
SSSD_NSS SERVER log
(Wed Aug 21 14:08:13 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer re-set for client [0x559f771f0e20][21] (Wed Aug 21 14:08:28 2019) [sssd[nss]] [get_client_cred] (0x4000): Client creds: euid[994] egid[989] pid[1917]. (Wed Aug 21 14:08:28 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer re-set for client [0x559f771e45f0][22] (Wed Aug 21 14:08:28 2019) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected! (Wed Aug 21 14:08:28 2019) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1]. (Wed Aug 21 14:08:28 2019) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1]. (Wed Aug 21 14:08:28 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name: <MY USERNAME>@<AD DOMAIN> (Wed Aug 21 14:08:28 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #1: Setting "User by name" plugin
...
(Wed Aug 21 14:08:28 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #26: This request type does not support filtering result by negative cache (Wed Aug 21 14:08:28 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR #26: Found 1 entries in domain <AD DOMAIN> (Wed Aug 21 14:08:28 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #26: Finished: Success (Wed Aug 21 14:08:28 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply: success
Hi,
here everything happened in 14:08:28, so there is no visible delay in the logs. Did you see a delay on the client for this attempt?
Can you try again and send new logs which "hopefully" show some delay?
bye, Sumit
(Wed Aug 21 14:08:43 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer re-set for client [0x559f771f0e20][21] (Wed Aug 21 14:08:58 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer re-set for client [0x559f771e45f0][22] (Wed Aug 21 14:09:13 2019) [sssd[nss]] [client_idle_handler] (0x2000): Terminating idle client [0x559f771f0e20][21] (Wed Aug 21 14:09:13 2019) [sssd[nss]] [client_close_fn] (0x2000): Terminated client [0x559f771f0e20][21] (Wed Aug 21 14:09:28 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer re-set for client [0x559f771e45f0][22] (Wed Aug 21 14:09:58 2019) [sssd[nss]] [client_idle_handler] (0x2000): Terminating idle client [0x559f771e45f0][22] (Wed Aug 21 14:09:58 2019) [sssd[nss]] [client_close_fn] (0x2000): Terminated client [0x559f771e45f0][22] (Wed Aug 21 14:10:01 2019) [sssd[nss]] [get_client_cred] (0x4000): Client creds: euid[0] egid[0] pid[18338]. (Wed Aug 21 14:10:01 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer re-set for client [0x559f771f0e20][21] (Wed Aug 21 14:10:01 2019) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected! (Wed Aug 21 14:10:01 2019) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1]. (Wed Aug 21 14:10:01 2019) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1]. (Wed Aug 21 14:10:01 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name: root (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #27: Setting "Initgroups by name" plugin (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #27: New request 'Initgroups by name' (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #27: Parsing input name [root] (Wed Aug 21 14:10:01 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #27: Setting name [root] (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #27: Performing a multi-domain search (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #27: Search will check the cache and check the data provider (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request type POSIX-only for domain <IPA DOMAIN> type POSIX is valid (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #27: Using domain [<IPA DOMAIN>] (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #27: Preparing input data for domain [<IPA DOMAIN>] rules (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #27: Looking up root@<IPA DOMAIN> (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #27: Checking negative cache for [root@<IPA DOMAIN>] (Wed Aug 21 14:10:01 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/<IPA DOMAIN>/root@<IPA DOMAIN>] (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #27: [root@<IPA DOMAIN>] does not exist (negative cache) (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request type POSIX-only for domain <AD DOMAIN> type POSIX is valid (Wed Aug 21 14:10:01 2019) [sssd[nss]] [cache_req_process_result] (0x0400): CR #27: Finished: Not found (Wed Aug 21 14:10:01 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain <AD DOMAIN> is Active (Wed Aug 21 14:10:01 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply: not found (Wed Aug 21 14:10:01 2019) [sssd[nss]] [client_recv] (0x0200): Client disconnected! (Wed Aug 21 14:10:01 2019) [sssd[nss]] [client_close_fn] (0x2000): Terminated client [0x559f771f0e20][21] _______________________________________________ FreeIPA-users mailing list -- freeipa-users@lists.fedorahosted.org To unsubscribe send an email to freeipa-users-leave@lists.fedorahosted.org Fedora Code of Conduct: https://docs.fedoraproject.org/en-US/project/code-of-conduct/ List Guidelines: https://fedoraproject.org/wiki/Mailing_list_guidelines List Archives: https://lists.fedorahosted.org/archives/list/freeipa-users@lists.fedorahoste...