Attachment 1448024 Details for Bug 1553056 – sssd_kcm.log with debug_level = 10

sssd_kcm.log with debug_level = 10

sssd_kcm.log (text/plain), 10.01 KB, created by Vojtech Sokol on 2018-06-05 22:32:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Vojtech Sokol

Created: 2018-06-05 22:32:07 UTC

Size: 10.01 KB

patch

obsolete

>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55e748090940
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55e748090a00
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Running timer event 0x55e748090940 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Destroying timer event 0x55e748090a00 "ltdb_timeout"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Ending timer event 0x55e748090940 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [server_setup] (0x0400): CONFDB: /var/lib/sss/db/config.ldb
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55e748092970
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55e748092a30
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Running timer event 0x55e748092970 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Destroying timer event 0x55e748092a30 "ltdb_timeout"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Ending timer event 0x55e748092970 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55e7480928b0
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55e748092a30
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Running timer event 0x55e7480928b0 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Destroying timer event 0x55e748092a30 "ltdb_timeout"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Ending timer event 0x55e7480928b0 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55e748092970
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55e748092a30
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Running timer event 0x55e748092970 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Destroying timer event 0x55e748092a30 "ltdb_timeout"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Ending timer event 0x55e748092970 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55e7480928b0
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55e748092a30
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Running timer event 0x55e7480928b0 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Destroying timer event 0x55e748092a30 "ltdb_timeout"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Ending timer event 0x55e7480928b0 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_get_ccdb_be] (0x0100): KCM database type: secrets
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55e748092970
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55e748092a30
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Running timer event 0x55e748092970 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Destroying timer event 0x55e748092a30 "ltdb_timeout"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ldb] (0x4000): Ending timer event 0x55e748092970 "ltdb_callback"
>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_responder_idle_timer] (0x2000): Re-scheduling the idle timeout for the responder [0x55e748092660]
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [setup_responder_idle_timer] (0x2000): Setting up the idle timeout for the responder [0x55e748092660]
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_ccdb_init] (0x0200): KCM back end: sssd-secrets
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [responder_set_fd_limit] (0x0100): Maximum file descriptors set to [2048]
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_process_init] (0x0400): KCM Initialization complete
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [get_client_cred] (0x4000): Client creds: euid[1000] egid[1000] pid[13961].
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [setup_client_idle_timer] (0x4000): Idle timer re-set for client [0x55e748090560][12]
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [accept_fd_handler] (0x0400): Client connected!
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_input_parse] (0x1000): Received message with length 4
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_get_opt] (0x2000): The client requested operation 20
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_cmd_send] (0x0400): KCM operation GET_DEFAULT_CACHE
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_cmd_send] (0x1000): 0 bytes on KCM input
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_op_queue_send] (0x0200): Adding request by 1000 to the wait queue
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_op_queue_get] (0x1000): No existing queue for this ID
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_op_queue_send] (0x1000): Queue was empty, running the request immediately
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_op_get_default_ccache_send] (0x1000): Getting client's default ccache
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ccdb_sec_get_default_send] (0x2000): Getting the default ccache
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_request_send] (0x0400): Sending TCURL request for http://localhost/kcm/persistent/1000/default, at socket /var/run/secrets.socket
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: 0
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [handle_socket] (0x2000): Activity on curl socket 13 socket data (nil)
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: 0
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [check_curl_timeouts] (0x4000): Still tracking 1 outstanding requests
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: 200
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [check_curl_timeouts] (0x4000): Still tracking 1 outstanding requests
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_write_data] (0x2000): ---> begin libcurl data
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_write_data] (0x2000): 012f694c-bbd8-4a59-bdda-a2d84db807de
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_write_data] (0x2000): <--- end libcurl data
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [handle_socket] (0x2000): Activity on curl socket 13 socket data 0x55e74808f6f0
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [handle_curlmsg_done] (0x0400): Handled http://localhost/kcm/persistent/1000/default
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_request_done] (0x0400): TCURL request finished [0]: Success
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: -1
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [check_curl_timeouts] (0x4000): Still tracking 0 outstanding requests
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ccdb_sec_get_default_done] (0x2000): Got the default ccache
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ccdb_sec_name_by_uuid_send] (0x2000): Translating UUID to name
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [sec_list_send] (0x2000): Listing all ccaches in the secrets store
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_request_send] (0x0400): Sending TCURL request for http://localhost/kcm/persistent/1000/ccache/, at socket /var/run/secrets.socket
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: 0
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [handle_socket] (0x2000): Activity on curl socket 13 socket data (nil)
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: 0
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [check_curl_timeouts] (0x4000): Still tracking 1 outstanding requests
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: 5000
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [check_curl_timeouts] (0x4000): Still tracking 1 outstanding requests
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_write_data] (0x2000): ---> begin libcurl data
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_write_data] (0x2000): <html>
><head>
><title>404 Not Found</title></head>
><body>
><h1>Not Found</h1>
><p>The requested resource was not found.</p>
></body>
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_write_data] (0x2000): <--- end libcurl data
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [handle_socket] (0x2000): Activity on curl socket 13 socket data 0x55e74808f6f0
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [handle_curlmsg_done] (0x0400): Handled http://localhost/kcm/persistent/1000/ccache/
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [tcurl_request_done] (0x0400): TCURL request finished [0]: Success
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [schedule_fd_processing] (0x2000): timeout_ms: -1
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [check_curl_timeouts] (0x4000): Still tracking 0 outstanding requests
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [sec_list_done] (0x2000): Nothing to list
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [sec_list_done] (0x2000): list done
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [ccdb_sec_name_by_uuid_done] (0x2000): No such UUID
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_ccdb_name_by_uuid_done] (0x0040): Failed to resolve cache by UUID [1432158218]: No credentials available
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_op_get_default_ccache_byuuid_done] (0x0040): Cannot get ccahe by UUID [1432158218]: No credentials available
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_cmd_done] (0x0040): op receive function failed [1432158218]: No credentials available
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_cmd_request_done] (0x0040): KCM operation failed [1432158218]: No credentials available
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_reply_error] (0x0040): KCM operation returs failure [1432158218]: No credentials available
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_failbuf_construct] (0x1000): Sent reply with error -1765328243
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [queue_removal_cb] (0x0200): Removed queue for 1000 
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_send] (0x2000): All data sent!
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [kcm_recv] (0x4000): Client closed connection.
>(Wed Jun  6 00:06:46 2018) [sssd[kcm]] [client_close_fn] (0x2000): Terminated client [0x55e748090560][12]

Actions: View

Attachments on bug 1553056: 1405727 | 1405728 | 1448024 | 1448025 | 1448026