Project

General

Profile

Bug #1392 » err.txt

Arnaud Fiorini, 03/22/2024 12:25 PM

 
Mar 22 12:21:39 dorsal-vm2 systemd[1]: Started LTTng session daemon.
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302744946 [Main]: [sessiond configuration]
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302817488 [Main]: version 2.13.11
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302826274 [Main]: verbose: 3
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302833988 [Main]: verbose consumer: 0
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302841820 [Main]: quiet mode: False
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302849478 [Main]: agent_tcp_port: [5345, 5354]
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302856881 [Main]: application socket timeout: 5
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302863909 [Main]: no-kernel: False
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302871126 [Main]: background: False
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302878379 [Main]: daemonize: False
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302886271 [Main]: signal parent on start: False
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302893250 [Main]: tracing group name: tracing
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302900724 [Main]: kmod_probe_list: None
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302909121 [Main]: kmod_extra_probe_list: None
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302917764 [Main]: rundir: /var/run/lttng
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302927102 [Main]: application socket path: /var/run/lttng/lttng-ust-sock-8
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302935580 [Main]: client socket path: /var/run/lttng/client-lttng-sessiond
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302942865 [Main]: wait shm path: /lttng-ust-wait-8
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302950536 [Main]: health socket path: /var/run/lttng/sessiond-health
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302957912 [Main]: LTTNG_UST_CLOCK_PLUGIN: None
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302965936 [Main]: pid file path: /var/run/lttng/lttng-sessiond.pid
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302974145 [Main]: lock file path: /var/run/lttng/lttng-sessiond.lck
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302981854 [Main]: session load path: None
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302989200 [Main]: agent port file path: /var/run/lttng/agent.port
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.302996834 [Main]: consumerd32 path: /var/run/lttng/ustconsumerd32
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303004583 [Main]: consumerd32 bin path: Unknown
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303012134 [Main]: consumerd32 lib dir: Unknown
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303019201 [Main]: consumerd32 err unix sock path:/var/run/lttng/ustconsumerd32/error
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303026266 [Main]: consumerd32 cmd unix sock path:/var/run/lttng/ustconsumerd32/command
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303033238 [Main]: consumerd64 path: /var/run/lttng/ustconsumerd64
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303040671 [Main]: consumerd64 bin path: /usr/lib/x86_64-linux-gnu/lttng/libexec/lttng-consumerd
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303047499 [Main]: consumerd64 lib dir: /usr/lib/x86_64-linux-gnu
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303054750 [Main]: consumerd64 err unix sock path:/var/run/lttng/ustconsumerd64/error
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303061561 [Main]: consumerd64 cmd unix sock path:/var/run/lttng/ustconsumerd64/command
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303068369 [Main]: kconsumerd path: /var/run/lttng/kconsumerd
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303076248 [Main]: kconsumerd err unix sock path: /var/run/lttng/kconsumerd/error
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303083232 [Main]: kconsumerd cmd unix sock path: /var/run/lttng/kconsumerd/command
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.303094577 [Main]: Starting lttng-sessiond {6813864d-4ea4-4e08-a240-5db7047e6535} (in sessiond_uuid_log() at main.c:1437)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.303102160 [Main]: Creating LTTng run directory: /var/run/lttng (in create_lttng_rundir() at main.c:1069)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48961]: DBG1 - 12:21:39.304074905 [Main]: Resetting run_as worker signal handlers to default (in reset_sighandler() at runas.c:1377)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48961]: DBG1 - 12:21:39.304245470 [Main]: run_as signal handler set for SIGTERM and SIGINT (in set_worker_sighandlers() at runas.c:1438)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.306004740 [Main]: Health check time delta in seconds set to 20 (in health_init() at health.c:62)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.306308763 [HT cleanup]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.306396935 [HT cleanup]: startup. (in thread_ht_cleanup() at ht-cleanup.c:98)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.306453961 [HT cleanup]: epoll set max size is 1783184 (in compat_epoll_set_max_size() at poll.c:350)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306454582 [Main]: Created hashtable size 4 at 0x5567c6d611d0 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306535136 [Main]: Created hashtable size 4 at 0x5567c6d618d0 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306547574 [Main]: Created hashtable size 4 at 0x5567c6d62040 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306575009 [Main]: Created hashtable size 16 at 0x5567c6d62800 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306588138 [Main]: Created hashtable size 16 at 0x5567c6d630a0 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306597602 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306602503 [Main]: Created hashtable size 16 at 0x5567c6d63910 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306646192 [Main]: Created hashtable size 4 at 0x5567c6d64180 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.306670715 [Main]: Created hashtable size 4 at 0x5567c6d64910 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:39.306679676 [Main]: Creating consumer directory: /var/run/lttng/kconsumerd (in set_consumer_sockets() at main.c:1111)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.307625928 [Main]: libkmod: could not find module by name='lttng_ring_buffer_client_discard'
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: (in log_kmod() at modprobe.c:261)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: Error: Unable to load required module lttng-ring-buffer-client-discard
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: Warning: No kernel tracer available
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:39.307651799 [Main]: Creating consumer directory: /var/run/lttng/ustconsumerd64 (in set_consumer_sockets() at main.c:1111)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:39.307736225 [Main]: Creating consumer directory: /var/run/lttng/ustconsumerd32 (in set_consumer_sockets() at main.c:1111)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.307831628 [Main]: Created hashtable size 4 at 0x5567c6d65e30 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.307841015 [Main]: Global buffer per UID registry initialized (in buffer_reg_init_uid_registry() at buffer-registry.c:90)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.307851401 [Main]: Created hashtable size 4 at 0x5567c6d665c0 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.307859157 [Main]: Global buffer per PID registry initialized (in buffer_reg_init_pid_registry() at buffer-registry.c:219)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.307867404 [Main]: Command subsystem initialized (in cmd_init() at cmd.c:6090)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.307893683 [Main]: '48960' written in file /var/run/lttng/lttng-sessiond.pid (in utils_create_pid_file() at utils.c:257)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.307964659 [Main]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:545)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308065966 [Main]: Waiting for health management thread to be ready (in wait_until_thread_is_ready() at health.c:35)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308133838 [Health management]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308172901 [Health management]: [thread] Manage health check started (in thread_manage_health() at health.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308413586 [Health management]: Marking health management thread as ready (in mark_thread_as_ready() at health.c:28)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308450346 [Health management]: Health check ready (in thread_manage_health() at health.c:136)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308486252 [Main]: Health management thread is ready (in wait_until_thread_is_ready() at health.c:37)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308584145 [Main]: Waiting for notification thread to be ready (in wait_until_thread_is_ready() at notification-thread.c:414)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308675544 [Notification]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308716853 [Notification]: Started notification thread (in thread_notification() at notification-thread.c:642)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308774029 [Notification]: Creating notification channel UNIX socket at /var/run/lttng/sessiond-notification (in notification_channel_socket_create() at notification-thread.c:223)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308952840 [Notification]: Notification channel UNIX socket created (fd = 34) (in notification_channel_socket_create() at notification-thread.c:258)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.308993528 [Notification]: Listening on notification channel socket (in init_thread_state() at notification-thread.c:442)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309245745 [Notification]: Marking notification thread as ready (in mark_thread_as_ready() at notification-thread.c:407)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309276300 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309308357 [Main]: Notification thread is ready (in wait_until_thread_is_ready() at notification-thread.c:416)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309384356 [Action Executor]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309435530 [Action Executor]: Entering work execution loop (in action_executor_thread() at action-executor.c:761)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309449446 [Action Executor]: No work items enqueued, entering wait (in action_executor_thread() at action-executor.c:770)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309481032 [Timer]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309521290 [Main]: Created client socket (fd = 40) (in create_client_sock() at client.c:2435)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309553116 [Rotation]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309583612 [Rotation]: Started rotation thread (in thread_rotation() at rotation-thread.c:840)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309612918 [Main]: Waiting for client thread to be ready (in wait_thread_status() at client.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309713627 [Client management]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309774209 [Notification]: Poll wait returned (1) (in thread_notification() at notification-thread.c:670)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309776966 [Client management]: [thread] Manage client started (in thread_manage_clients() at client.c:2468)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309790967 [Notification]: Handling fd (34) activity (1) (in thread_notification() at notification-thread.c:694)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309849105 [Notification]: Handling new notification channel client connection (in handle_notification_thread_client_connect() at notification-thread-events.c:3351)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309881951 [Notification]: Client socket (fd = 44) set as non-blocking (in socket_set_non_blocking() at notification-thread-events.c:3319)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309901456 [Notification]: Added new notification channel client socket (44) to poll set (in handle_notification_thread_client_connect() at notification-thread-events.c:3404)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309905949 [Client management]: Marking client thread's state as running (in set_thread_status() at client.c:58)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309916329 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309965255 [Notification]: Poll wait returned (1) (in thread_notification() at notification-thread.c:670)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309983695 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310013840 [Main]: Client thread is ready (in wait_thread_status() at client.c:68)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.309985215 [Notification]: Handling fd (44) activity (1) (in thread_notification() at notification-thread.c:694)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310053457 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310066753 [Notification]: Poll wait returned (1) (in thread_notification() at notification-thread.c:670)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310077708 [Notification]: Handling fd (44) activity (1) (in thread_notification() at notification-thread.c:694)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310093942 [Main]: All permissions are set (in set_permissions() at main.c:1057)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310116340 [Notification]: Received handshake from client: uid = 0, gid = 0, protocol version = 1.1, client is sessiond = true (in client_handle_message_handshake() at notification-thread-events.c:3879)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310133855 [Notification]: Send command reply (0) (in client_send_command_reply() at notification-thread-events.c:3755)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310147116 [Notification]: Flushing client (socket fd = 44) outgoing queue (in client_flush_outgoing_queue() at notification-thread-events.c:3644)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310194280 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.310218161 [Main]: Session daemon application socket created (fd = 47) (in create_application_socket() at register.c:71)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310256735 [Rotation]: Entering poll wait (in thread_rotation() at rotation-thread.c:864)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310261364 [UST registration dispatch]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310295950 [UST registration dispatch]: [thread] Dispatch UST command started (in thread_dispatch_ust_registration() at dispatch.c:250)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310297402 [Main]: Waiting for application registration thread to be ready (in wait_thread_status() at register.c:131)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310324497 [UST registration dispatch]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:67)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310355052 [UST application registration]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310377765 [UST registration dispatch]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at dispatch.c:279)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310401303 [UST application registration]: [thread] Manage application registration started (in thread_application_registration() at register.c:168)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310495167 [UST application registration]: Marking application registration thread's state as running (in set_thread_status() at register.c:124)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310523729 [UST application registration]: Accepting application registration (in thread_application_registration() at register.c:207)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310550987 [Main]: Application registration thread is ready (in wait_thread_status() at register.c:134)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310561732 [Main]: Notifying applications of session daemon state: 1 (in notify_ust_apps() at register.c:91)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310592135 [Main]: Wait shared memory file descriptor created successfully: path = '/lttng-ust-wait-8', mmap_size = 4096, global = true, fd = 50 (in get_wait_shm() at shm.c:133)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310620503 [Main]: Futex wait update active 1 (in futex_wait_update() at futex.c:55)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310783436 [UST application management]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310812944 [UST application management]: [thread] Manage application started (in thread_application_management() at manage-apps.c:52)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310861930 [Main]: Waiting for agent management thread to be ready (in wait_until_thread_is_ready() at agent-thread.c:348)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310910458 [Application notification]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310942866 [Application notification]: [ust-thread] Manage application notify command (in thread_application_notification() at notify-apps.c:39)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310964997 [UST application management]: Apps thread polling (in thread_application_management() at manage-apps.c:88)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.310952676 [Agent management]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.311043191 [Agent management]: Manage agent application registration. (in thread_agent_management() at agent-thread.c:366)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.311087011 [Application notification]: [ust-thread] Manage notify polling (in thread_application_notification() at notify-apps.c:74)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.311119782 [Agent management]: URI string: tcp://localhost (in uri_parse() at uri.c:298)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:39.311445568 [Agent management]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:155)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.311460579 [Agent management]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 0, data: 0 (in uri_parse() at uri.c:510)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.311494539 [Agent management]: Trying to bind on port 5345 (in init_tcp_socket() at agent-thread.c:140)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.311524224 [Agent management]: Listening on TCP port 5345 and socket 59 (in init_tcp_socket() at agent-thread.c:177)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.311568867 [Agent management]: '5345' written in file /var/run/lttng/agent.port (in utils_create_pid_file() at utils.c:257)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.311606163 [Agent management]: Marking agent management thread as ready (in mark_thread_as_ready() at agent-thread.c:341)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.311641720 [Agent management]: Manage agent polling (in thread_agent_management() at agent-thread.c:421)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.311687991 [Main]: Agent management thread is ready (in wait_until_thread_is_ready() at agent-thread.c:350)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.311875916 [Kernel management]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.311903868 [Kernel management]: [thread] Thread manage kernel started (in thread_kernel_management() at manage-kernel.c:173)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.312071054 [Kernel management]: Updating kernel poll set (in update_kernel_poll() at manage-kernel.c:37)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:39.312084434 [Kernel management]: Thread kernel polling (in thread_kernel_management() at manage-kernel.c:227)
Mar 22 12:21:39 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:39.314536059 [Main]: Home directory is '/root' (in utils_get_home_dir() at utils.c:796)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.018815184 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.018891074 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.018913997 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.018949785 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.018965362 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.018987801 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.019039980 [Client management]: Processing client command 'LTTNG_LIST_SESSIONS' (13) (in process_client_msg() at client.c:1016)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.019082237 [Client management]: Counting number of available session for UID 1000 (in lttng_sessions_count() at client.c:596)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.019097749 [Client management]: Getting all available session for UID 1000 GID 1000 (in cmd_list_lttng_sessions() at cmd.c:3947)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.019115971 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:47 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:47.019156261 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288624183 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288691231 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288738771 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288761877 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288844610 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288873415 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288912849 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.288934519 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289197009 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289228350 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289271122 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289292970 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289365180 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289391961 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289467771 [Client management]: Processing client command 'LTTNG_CREATE_SESSION_EXT' (49) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.289595152 [Client management]: Created hashtable size 4 at 0x7f95640090d0 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.289635115 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289649275 [Client management]: Generated session name "auto-20240322-122149" (in session_create() at session.c:1300)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.289666796 [Client management]: Created hashtable size 4 at 0x7f9564009860 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289679694 [Client management]: Allocating ltt_sessions_ht_by_id (in ltt_sessions_ht_alloc() at session.c:306)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.289700333 [Client management]: Created hashtable size 4 at 0x7f9564009ff0 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289713380 [Client management]: Allocating ltt_sessions_ht_by_name (in ltt_sessions_ht_alloc() at session.c:314)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.289726717 [Client management]: Created hashtable size 4 at 0x7f956400a780 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289742956 [Client management]: Tracing session auto-20240322-122149 created with ID 0 by uid = 1000, gid = 1000 (in session_create() at session.c:1348)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.289759119 [Client management]: URI string: file:///home/ubuntu/lttng-traces/auto-20240322-122149 (in uri_parse() at uri.c:298)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.289804040 [Client management]: URI file destination: /home/ubuntu/lttng-traces/auto-20240322-122149 (in uri_parse() at uri.c:335)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.289816308 [Client management]: URI dtype: 3, proto: 0, host: , subdir: , ctrl: 0, data: 0 (in uri_parse() at uri.c:510)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.289835893 [Client management]: Setting trace directory path from URI to /home/ubuntu/lttng-traces/auto-20240322-122149 (in add_uri_to_consumer() at cmd.c:719)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289906570 [Client management]: Sending response (size: 8262, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.289938940 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290202795 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290226013 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290254873 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290270155 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290323275 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290355254 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290403993 [Client management]: Processing client command 'LTTNG_LIST_SESSIONS' (13) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290416360 [Client management]: Counting number of available session for UID 1000 (in lttng_sessions_count() at client.c:596)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290427999 [Client management]: Getting all available session for UID 1000 GID 1000 (in cmd_list_lttng_sessions() at cmd.c:3947)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290441168 [Client management]: Sending response (size: 4409, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.290463089 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299428226 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299500656 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299517637 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299548784 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299563245 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299585219 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299632854 [Client management]: Processing client command 'LTTNG_ENABLE_CHANNEL' (5) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299648759 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.299661226 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299674059 [Client management]: Creating UST session (in create_ust_session() at client.c:510)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299695192 [Client management]: Created hashtable size 4 at 0x7f956400d110 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299710597 [Client management]: Created hashtable size 4 at 0x7f956400d8a0 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299774755 [Client management]: Created hashtable size 4 at 0x7f956400f6e0 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.299787057 [Client management]: UST trace session create successful (in trace_ust_create_session() at trace-ust.c:324)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299797773 [Client management]: Copying tracing session consumer output in UST session (in copy_session_consumer() at client.c:454)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299840758 [Client management]: Created hashtable size 4 at 0x7f956400fe70 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299858526 [Client management]: Copy session consumer subdir ust (in copy_session_consumer() at client.c:476)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.299872563 [Client management]: Spawning consumerd (in spawn_consumerd() at client.c:204)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299914453 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.299992389 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.301301098 [Client management]: Consumer pid 48981 (in start_consumerd() at client.c:390)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.301393059 [Client management]: Spawning consumer control thread (in start_consumerd() at client.c:393)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.301562571 [Client management]: Waiting for consumer management thread to be ready (in wait_until_thread_is_ready() at manage-consumer.c:46)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48981]: DBG1 - 12:21:49.301615383 [Client management]: Using 64-bit UST consumer at: /usr/lib/x86_64-linux-gnu/lttng/libexec/lttng-consumerd (in spawn_consumerd() at client.c:284)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.301689641 [Consumer management]: Entering thread entry point (in launch_thread() at thread.c:65)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.301740446 [Consumer management]: [thread] Manage consumer started (in thread_consumer_management() at manage-consumer.c:65)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.364075986 [Consumer management]: Receiving code from consumer err_sock (in thread_consumer_management() at manage-consumer.c:162)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.466030031 [Consumer management]: Consumer command socket ready (fd: 69) (in thread_consumer_management() at manage-consumer.c:204)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.466070911 [Consumer management]: Consumer metadata socket ready (fd: 70) (in thread_consumer_management() at manage-consumer.c:205)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.466111079 [Consumer management]: Sending consumer initialization command (in consumer_init() at consumer.c:1791)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.466500994 [Consumer management]: Sending SET_CHANNEL_MONITOR_PIPE command to consumer (in consumer_send_pipe() at consumer.c:1230)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.466680612 [Consumer management]: Sending channel monitor pipe 18 to consumer on socket 69 (in consumer_send_pipe() at consumer.c:1236)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.466811052 [Consumer management]: channel monitor pipe successfully sent (in consumer_send_pipe() at consumer.c:1244)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.466950611 [Consumer management]: Marking consumer management thread as ready (in mark_thread_as_ready() at manage-consumer.c:31)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.467090089 [Client management]: Consumer management thread is ready (in wait_until_thread_is_ready() at manage-consumer.c:48)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.467139259 [Client management]: Consumer socket created (fd: 69) and added to output (in consumer_create_socket() at consumer.c:351)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.467155342 [Client management]: Setting relayd for session auto-20240322-122149 (in cmd_setup_relayd() at cmd.c:1004)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.467207547 [Client management]: Enabling channel chan_ust for session auto-20240322-122149 (in cmd_enable_channel_internal() at cmd.c:1332)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.467225392 [Client management]: Trace UST channel chan_ust not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:188)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.467284419 [Client management]: Created hashtable size 4 at 0x7f956401b270 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.467306275 [Client management]: Created hashtable size 4 at 0x7f956401b9c0 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.467318979 [Client management]: Trace UST channel chan_ust created (in trace_ust_create_channel() at trace-ust.c:402)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.467333056 [Client management]: Channel chan_ust is being created for UST with buffer 1 and id 0 (in channel_ust_create() at channel.c:451)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.467350624 [Client management]: Channel chan_ust created successfully (in channel_ust_create() at channel.c:481)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.467374739 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.467419171 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475605114 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475668496 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475684446 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475707595 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475720726 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475741960 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475777001 [Client management]: Processing client command 'LTTNG_ADD_CONTEXT' (0) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475792782 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.475806234 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.475821796 [Client management]: Consumer socket created (fd: 69) and added to output (in consumer_create_socket() at consumer.c:351)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475833780 [Client management]: Setting relayd for session auto-20240322-122149 (in cmd_setup_relayd() at cmd.c:1004)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.475868991 [Client management]: Trace UST channel chan_ust found by name (in trace_ust_find_channel_by_name() at trace-ust.c:183)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475885650 [Client management]: Context UST 1 added to channel chan_ust (in add_uctx_to_channel() at context.c:176)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475900240 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.475923937 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476165522 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476192696 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476206887 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476230338 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476243547 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476263702 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476281473 [Client management]: Processing client command 'LTTNG_ADD_CONTEXT' (0) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476295671 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.476307268 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.476320528 [Client management]: Consumer socket created (fd: 69) and added to output (in consumer_create_socket() at consumer.c:351)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476331858 [Client management]: Setting relayd for session auto-20240322-122149 (in cmd_setup_relayd() at cmd.c:1004)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.476359882 [Client management]: Trace UST channel chan_ust found by name (in trace_ust_find_channel_by_name() at trace-ust.c:183)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476375539 [Client management]: Context UST 4 added to channel chan_ust (in add_uctx_to_channel() at context.c:176)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476388459 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476410994 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476627746 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476655755 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476669446 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476690556 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476703169 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476723378 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476741055 [Client management]: Processing client command 'LTTNG_ADD_CONTEXT' (0) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476753528 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.476765072 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.476789557 [Client management]: Consumer socket created (fd: 69) and added to output (in consumer_create_socket() at consumer.c:351)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476801428 [Client management]: Setting relayd for session auto-20240322-122149 (in cmd_setup_relayd() at cmd.c:1004)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.476829161 [Client management]: Trace UST channel chan_ust found by name (in trace_ust_find_channel_by_name() at trace-ust.c:183)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476844030 [Client management]: Context UST 3 added to channel chan_ust (in add_uctx_to_channel() at context.c:176)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476856141 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.476878877 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477143255 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477171791 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477185256 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477208131 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477220447 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477239304 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477256500 [Client management]: Processing client command 'LTTNG_ADD_CONTEXT' (0) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477270112 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.477281056 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.477293828 [Client management]: Consumer socket created (fd: 69) and added to output (in consumer_create_socket() at consumer.c:351)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477304717 [Client management]: Setting relayd for session auto-20240322-122149 (in cmd_setup_relayd() at cmd.c:1004)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.477332386 [Client management]: Trace UST channel chan_ust found by name (in trace_ust_find_channel_by_name() at trace-ust.c:183)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477346420 [Client management]: Context UST 0 added to channel chan_ust (in add_uctx_to_channel() at context.c:176)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477359329 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.477381036 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485523883 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485560466 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485572314 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485595908 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485607099 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485623332 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485662854 [Client management]: Processing client command 'LTTNG_ENABLE_EVENT' (6) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485675027 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.485683601 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.485694738 [Client management]: Consumer socket created (fd: 69) and added to output (in consumer_create_socket() at consumer.c:351)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485703459 [Client management]: Setting relayd for session auto-20240322-122149 (in cmd_setup_relayd() at cmd.c:1004)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485734112 [Client management]: Enable event command for event '*' (in _cmd_enable_event() at cmd.c:2088)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.485745419 [Client management]: Trace UST channel chan_ust found by name (in trace_ust_find_channel_by_name() at trace-ust.c:183)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.485756154 [Client management]: Trace UST event * NOT found (in trace_ust_find_event() at trace-ust.c:226)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.485775329 [Client management]: Trace UST event *, loglevel (0,-1) created (in trace_ust_create_event() at trace-ust.c:523)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485788498 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.485816732 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493606002 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493664461 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493679921 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493703204 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493718087 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493740010 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493771503 [Client management]: Processing client command 'LTTNG_START_TRACE' (16) (in process_client_msg() at client.c:1016)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493786159 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.493798257 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493818287 [Client management]: Creating initial trace chunk of session "auto-20240322-122149" (in cmd_start_trace() at cmd.c:2774)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493835354 [Client management]: Creating trace chunk: chunk_id = 0, creation time = 20240322-122149 (in lttng_trace_chunk_create() at trace-chunk.c:427)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493849116 [Client management]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:458)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.493860937 [Client management]: Creating base output directory of session "auto-20240322-122149" at /home/ubuntu/lttng-traces/auto-20240322-122149 (in session_create_new_trace_chunk() at session.c:701)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.494070838 [Client management]: mkdirat() recursive fd = -100 (AT_FDCWD), path = /home/ubuntu/lttng-traces/auto-20240322-122149, mode = 504, uid = 1000, gid = 1000 (in run_as_mkdirat_recursive() at runas.c:1689)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.494100606 [Client management]: Using run_as worker (in run_as() at runas.c:1646)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.497180599 [Client management]: Creating trace chunk subdirectory "ust" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1226)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.497235719 [Client management]: mkdirat() recursive fd = 71, path = ust, mode = 504, uid = 1000, gid = 1000 (in run_as_mkdirat_recursive() at runas.c:1689)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.497265710 [Client management]: Using run_as worker (in run_as() at runas.c:1646)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.499189010 [Client management]: Adding new top-level directory "ust" to trace chunk "(unnamed)" (in add_top_level_directory_unique() at trace-chunk.c:1199)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.499241764 [Client management]: Sending consumer create trace chunk command: relayd_id = -1, session_id = 0, chunk_id = 0, creation_timestamp = 20240322T122149-0400 (in consumer_create_trace_chunk() at consumer.c:1941)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.499461928 [Client management]: Sending trace chunk domain directory fd to consumer (in consumer_create_trace_chunk() at consumer.c:1956)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.499757448 [Client management]: Starting all UST traces (in ust_app_start_trace_all() at ust-app.c:5652)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.499779199 [Client management]: Clearing stream quiescent state for all ust apps (in ust_app_clear_quiescent_session() at ust-app.c:5520)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.499790337 [Client management]: Opening packets of session channels: session name = auto-20240322-122149, session id = 0 (in session_open_packets() at session.c:900)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.499803451 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.500429329 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515059789 [UST application registration]: UST registration received with pid:48995 ppid:48978 uid:1000 gid:1000 sock:64 name:hello (version 9.0) (in thread_application_registration() at register.c:311)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515116192 [UST application registration]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:121)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515126937 [UST application registration]: Accepting application registration (in thread_application_registration() at register.c:207)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515180464 [UST registration dispatch]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:104)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515195474 [UST registration dispatch]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:67)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515205941 [UST registration dispatch]: Dispatching UST registration pid:48995 ppid:48978 uid:1000 gid:1000 sock:64 name:hello (version 9.0) (in thread_dispatch_ust_registration() at dispatch.c:286)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.515238340 [UST registration dispatch]: UST app creating application for socket 64 (in ust_app_create() at ust-app.c:3947)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.515287054 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544000da0 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.515298532 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544001530 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.515313543 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544001cc0 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.515323779 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544002450 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515349046 [UST registration dispatch]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at dispatch.c:279)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515372492 [UST application registration]: UST registration received with pid:48995 ppid:48978 uid:1000 gid:1000 sock:72 name:hello (version 9.0) (in thread_application_registration() at register.c:311)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515387448 [UST application registration]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:121)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515395157 [UST application registration]: Accepting application registration (in thread_application_registration() at register.c:207)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515408711 [UST registration dispatch]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:104)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515416284 [UST registration dispatch]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:67)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515433886 [UST registration dispatch]: Dispatching UST registration pid:48995 ppid:48978 uid:1000 gid:1000 sock:72 name:hello (version 9.0) (in thread_dispatch_ust_registration() at dispatch.c:286)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.515443983 [UST registration dispatch]: UST app notify socket 72 is set (in thread_dispatch_ust_registration() at dispatch.c:353)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515462995 [UST registration dispatch]: App registered with pid:48995 ppid:48978 uid:1000 gid:1000 sock =64 name:hello notify_sock =72 (version 9.0) (in ust_app_add() at ust-app.c:4066)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.515928499 [UST registration dispatch]: Beginning of waiter "wait" period (in lttng_waiter_wait() at waiter.c:46)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.516001119 [Notification]: Poll wait returned (1) (in thread_notification() at notification-thread.c:670)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.516015184 [Notification]: Handling fd (33) activity (1) (in thread_notification() at notification-thread.c:694)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.516026388 [Notification]: Received `ADD_TRACER_EVENT_SOURCE` command (in handle_notification_thread_command() at notification-thread-events.c:3162)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.516036892 [Notification]: Adding tracer event source fd to poll set: tracer_event_source_fd = 73, domain = 'ust' (in handle_notification_thread_command_add_tracer_event_source() at notification-thread-events.c:2037)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.516055392 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.516069837 [UST registration dispatch]: End of waiter "wait" period (in lttng_waiter_wait() at waiter.c:106)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.518515086 [UST registration dispatch]: UST application global event notifier rules update: app = 'hello', pid = 48995) (in ust_app_global_update_event_notifier_rules() at ust-app.c:6125)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.518534861 [UST registration dispatch]: Beginning of waiter "wait" period (in lttng_waiter_wait() at waiter.c:46)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.518594951 [Notification]: Poll wait returned (1) (in thread_notification() at notification-thread.c:670)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.518604482 [Notification]: Handling fd (33) activity (1) (in thread_notification() at notification-thread.c:694)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518598438 [Application notification]: [ust-thread] Manage notify return from poll on 2 fds (in thread_application_notification() at notify-apps.c:80)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.518616368 [Notification]: Received `LIST_TRIGGERS` command (in handle_notification_thread_command() at notification-thread-events.c:3162)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518677264 [Application notification]: UST thread notify added sock 72 to pollset (in thread_application_notification() at notify-apps.c:134)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.518691640 [UST registration dispatch]: End of waiter "wait" period (in lttng_waiter_wait() at waiter.c:106)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.518726737 [UST registration dispatch]: UST app global update for app sock 64 for session id 0 (in ust_app_global_update() at ust-app.c:6092)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.518737607 [UST registration dispatch]: UST app pid: 48995 session id 0 not found, creating it (in find_or_create_ust_app_session() at ust-app.c:2664)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518704926 [Application notification]: [ust-thread] Manage notify polling (in thread_application_notification() at notify-apps.c:74)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518775757 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544007fe0 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.518802054 [UST registration dispatch]: Shadow copy of session handle -1 (in shadow_copy_session() at ust-app.c:2389)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518823924 [UST registration dispatch]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:193)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518850619 [UST registration dispatch]: Created hashtable size 4 at 0x7f954400a7f0 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518859951 [UST registration dispatch]: Buffer registry per UID created id: 0, ABI: 64, uid: 1000, domain: 2 (in buffer_reg_uid_create() at buffer-registry.c:142)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518886521 [UST registration dispatch]: Created hashtable size 4 at 0x7f954400e060 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518896365 [UST registration dispatch]: Created hashtable size 4 at 0x7f954400e7f0 of type U64 (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518913029 [UST registration dispatch]: Append to metadata: "/* CTF 1.8 */
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518932577 [UST registration dispatch]: Append to metadata: "typealias integer { size = 8; align = 8; signed = false; } := uint8_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer { size = 16; align = 8; signed = false; } := uint16_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer { size = 32; align = 8; signed = false; } := uint32_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer { size = 64; align = 8; signed = false; } := uint64_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer { size = 64; align = 8; signed = false; } := unsigned long;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer { size = 5; align = 1; signed = false; } := uint5_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer { size = 27; align = 1; signed = false; } := uint27_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: trace {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: major = 1;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: minor = 8;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uuid = "99f198f8-1fb5-4c9a-9953-e0578def48f6";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: byte_order = le;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: packet.header := struct {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint32_t magic;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint8_t uuid[16];
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint32_t stream_id;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_t stream_instance_id;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: };
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: };
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518943791 [UST registration dispatch]: Append to metadata: "env {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: domain = "ust";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: tracer_name = "lttng-ust";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: tracer_major = 2;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: tracer_minor = 13;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: tracer_buffering_scheme = "uid";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: tracer_buffering_id = 1000;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: architecture_bit_width = 64;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518953947 [UST registration dispatch]: Session 0 found by id. (in session_find_by_id() at session.c:1181)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518963454 [UST registration dispatch]: Append to metadata: " trace_name = "" (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518971882 [UST registration dispatch]: Append to metadata: "a" (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518980138 [UST registration dispatch]: Append to metadata: "u" (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518988208 [UST registration dispatch]: Append to metadata: "t" (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.518995973 [UST registration dispatch]: Append to metadata: "o" (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519003764 [UST registration dispatch]: Append to metadata: "";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519021614 [UST registration dispatch]: Append to metadata: " trace_creation_datetime = "20240322T122149-0400";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: hostname = "dorsal-vm2";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519031805 [UST registration dispatch]: Append to metadata: "};
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519040843 [UST registration dispatch]: Append to metadata: "clock {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: name = "monotonic";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519074734 [UST registration dispatch]: Append to metadata: " uuid = "007c29fa-d7f5-498d-a2b4-5527734c3ec9";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519103228 [UST registration dispatch]: Append to metadata: " description = "Monotonic Clock";
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: freq = 1000000000; /* Frequency, in Hz */
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: /* clock value offset from Epoch is: offset * (1/freq) */
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: offset = 1711121552733009992;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: };
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519114403 [UST registration dispatch]: Append to metadata: "typealias integer {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: size = 27; align = 1; signed = false;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: map = clock.monotonic.value;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } := uint27_clock_monotonic_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: size = 32; align = 8; signed = false;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: map = clock.monotonic.value;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } := uint32_clock_monotonic_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: typealias integer {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: size = 64; align = 8; signed = false;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: map = clock.monotonic.value;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } := uint64_clock_monotonic_t;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519124023 [UST registration dispatch]: Append to metadata: "struct packet_context {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_clock_monotonic_t timestamp_begin;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_clock_monotonic_t timestamp_end;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_t content_size;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_t packet_size;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_t packet_seq_num;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: unsigned long events_discarded;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint32_t cpu_id;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: };
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519139440 [UST registration dispatch]: Append to metadata: "struct event_header_compact {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: enum : uint5_t { compact = 0 ... 30, extended = 31 } id;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: variant <id> {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: struct {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint27_clock_monotonic_t timestamp;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } compact;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: struct {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint32_t id;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_clock_monotonic_t timestamp;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } extended;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } v;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } align(8);
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: struct event_header_large {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: enum : uint16_t { compact = 0 ... 65534, extended = 65535 } id;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: variant <id> {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: struct {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint32_clock_monotonic_t timestamp;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } compact;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: struct {
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint32_t id;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: uint64_clock_monotonic_t timestamp;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } extended;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } v;
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: } align(8);
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: " (in lttng_metadata_printf() at ust-metadata.c:137)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519148642 [UST registration dispatch]: Buffer registry per UID adding to global registry with id: 0 (in buffer_reg_uid_add() at buffer-registry.c:164)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519159560 [UST registration dispatch]: UST app buffer registry per UID created successfully (in setup_buffer_reg_uid() at ust-app.c:2628)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.518681463 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.519496524 [UST registration dispatch]: UST app session created successfully with handle 3 (in find_or_create_ust_app_session() at ust-app.c:2739)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519513664 [UST registration dispatch]: Created hashtable size 4 at 0x7f954400fea0 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519533380 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544011f10 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519542440 [UST registration dispatch]: UST app channel chan_ust allocated (in alloc_ust_app_channel() at ust-app.c:1224)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.519550148 [UST registration dispatch]: UST app shadow copy of channel chan_ust started (in shadow_copy_channel() at ust-app.c:2346)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519557956 [UST registration dispatch]: UST app shadow copy of channel chan_ust done (in shadow_copy_channel() at ust-app.c:2372)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.519567010 [UST registration dispatch]: UST app creating channel chan_ust with per UID buffers (in create_channel_per_uid() at ust-app.c:3379)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519575470 [UST registration dispatch]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:193)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.519584935 [UST registration dispatch]: UST app creating buffer registry channel for chan_ust (in create_buffer_reg_channel() at ust-app.c:3200)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519592945 [UST registration dispatch]: Buffer registry channel create with key: 0 (in buffer_reg_channel_create() at buffer-registry.c:370)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519603242 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544012610 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519613116 [UST registration dispatch]: Session 0 found by id. (in session_find_by_id() at session.c:1181)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:49.519629155 [UST registration dispatch]: Asking UST consumer for channel (in ask_channel_creation() at ust-consumer.c:57)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519650225 [UST registration dispatch]: Consumer trace path relative to current trace chunk: "ust/uid/1000/64-bit" (in setup_channel_trace_path() at consumer.c:72)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.519659890 [UST registration dispatch]: Creating trace chunk subdirectory "ust/uid/1000/64-bit/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1226)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.519802431 [UST registration dispatch]: mkdirat() recursive fd = 71, path = ust/uid/1000/64-bit/index, mode = 504, uid = 1000, gid = 1000 (in run_as_mkdirat_recursive() at runas.c:1689)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:49.519824084 [UST registration dispatch]: Using run_as worker (in run_as() at runas.c:1646)
Mar 22 12:21:49 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:49.522834388 [UST registration dispatch]: UST registry channel finding key 0 (in ust_registry_channel_find() at ust-registry.c:816)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.551028950 [Notification]: Poll wait returned (1) (in thread_notification() at notification-thread.c:670)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.551067980 [Notification]: Handling fd (73) activity (16) (in thread_notification() at notification-thread.c:694)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:52.551079404 [Notification]: Removing tracer event source from poll set: tracer_event_source_fd = 73, domain = 'ust' (in remove_tracer_event_source_from_pollset() at notification-thread-events.c:2140)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.551111958 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:52.551881005 [Application notification]: [ust-thread] Manage notify return from poll on 3 fds (in thread_application_notification() at notify-apps.c:80)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:52.551921856 [Application notification]: UST app receiving notify from sock 72 (in ust_app_recv_notify() at ust-app.c:6775)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:52.551948759 [Application notification]: UST app recv notify failed. Application died: sock = 72 (in ust_app_recv_notify() at ust-app.c:6780)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.551973439 [Application notification]: UST app notify socket unregister 72 (in ust_app_notify_sock_unregister() at ust-app.c:6952)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:52.552025132 [Application notification]: [ust-thread] Manage notify polling (in thread_application_notification() at notify-apps.c:74)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559680412 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559767791 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559784822 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559822191 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559834755 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559853157 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559908052 [Client management]: Processing client command 'LTTNG_STOP_TRACE' (17) (in process_client_msg() at client.c:1016)
Mar 22 12:21:52 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:52.559925433 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: Error: ask_channel_creation consumer command failed
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: Error: Error creating UST channel "chan_ust" on the consumer daemon
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795076372 [UST registration dispatch]: UST registry channel finding key 0 (in ust_registry_channel_find() at ust-registry.c:816)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795113001 [UST registration dispatch]: Buffer registry channel destroy with key 0 (in buffer_reg_channel_destroy() at buffer-registry.c:542)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795131032 [UST registration dispatch]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:193)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795160445 [UST registration dispatch]: Created hashtable size 4 at 0x7f9544012f40 of type ULONG (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795178998 [UST registration dispatch]: Created hashtable size 4 at 0x7f95440136d0 of type STRING (in lttng_ht_new() at hashtable.c:152)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795193152 [UST registration dispatch]: UST app channel metadata allocated (in alloc_ust_app_channel() at ust-app.c:1224)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795211049 [UST registration dispatch]: Session 0 found by id. (in session_find_by_id() at session.c:1181)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.795228229 [UST registration dispatch]: Asking UST consumer for channel (in ask_channel_creation() at ust-consumer.c:57)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795266043 [UST registration dispatch]: Consumer trace path relative to current trace chunk: "ust/uid/1000/64-bit" (in setup_channel_trace_path() at consumer.c:72)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.795285986 [UST registration dispatch]: Creating trace chunk subdirectory "ust/uid/1000/64-bit/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1226)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.795362177 [UST registration dispatch]: mkdirat() recursive fd = 71, path = ust/uid/1000/64-bit/index, mode = 504, uid = 1000, gid = 1000 (in run_as_mkdirat_recursive() at runas.c:1689)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.795386327 [UST registration dispatch]: Using run_as worker (in run_as() at runas.c:1646)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.800508533 [UST registration dispatch]: UST ask channel 2 successfully done with 1 stream(s) (in ask_channel_creation() at ust-consumer.c:179)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.800556687 [UST registration dispatch]: Consumer setup metadata channel key 2 (in consumer_setup_metadata() at consumer.c:1427)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.800711746 [UST registration dispatch]: UST metadata with key 2 created for app pid 48995 (in create_ust_app_metadata() at ust-app.c:3892)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.800729174 [UST registration dispatch]: UST app deleting channel metadata (in delete_ust_app_channel() at ust-app.c:544)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.800746683 [UST registration dispatch]: Starting tracing for ust app pid 48995 (in ust_app_start_trace() at ust-app.c:5104)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.800769911 [UST registration dispatch]: UST app start session failed. Application is dead: pid = 48995, sock = 64 (in ust_app_start_trace() at ust-app.c:5144)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.800855625 [UST registration dispatch]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at dispatch.c:279)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.800894317 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.800906070 [UST application management]: Apps thread return from poll on 2 fds (in thread_application_management() at manage-apps.c:94)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.800929141 [Client management]: Begin stop session "auto-20240322-122149" (id 0) (in cmd_stop_trace() at cmd.c:2886)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801010686 [Client management]: Stopping all UST traces (in ust_app_stop_trace_all() at ust-app.c:5689)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801014596 [UST application management]: Apps with sock 64 added to poll set (in thread_application_management() at manage-apps.c:145)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801099256 [Client management]: Stopping tracing for ust app pid 48995 (in ust_app_stop_trace() at ust-app.c:5208)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801105402 [UST application management]: Apps thread polling (in thread_application_management() at manage-apps.c:88)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801127260 [UST application management]: Apps thread return from poll on 3 fds (in thread_application_management() at manage-apps.c:94)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801147816 [UST application management]: PID 48995 unregistering with sock 64 (in ust_app_unregister_by_socket() at ust-app.c:4337)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801155482 [Client management]: Flushing session buffers for all ust apps (in ust_app_flush_session() at ust-app.c:5375)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.801183924 [Client management]: Consumer push metadata to consumer socket 69 (in consumer_push_metadata() at consumer.c:1462)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.801169548 [UST application management]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:193)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.801278940 [UST application management]: Consumer push metadata to consumer socket 69 (in consumer_push_metadata() at consumer.c:1462)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.801336407 [Client management]: Consumer pushing metadata on sock 69 of len 2423 (in consumer_push_metadata() at consumer.c:1479)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801925286 [Client management]: Completed stop session "auto-20240322-122149" (id 0) (in cmd_stop_trace() at cmd.c:2910)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.801949984 [Client management]: Sending response (size: 24, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802052565 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.802165780 [UST application management]: Consumer pushing metadata on sock 69 of len 2423 (in consumer_push_metadata() at consumer.c:1479)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802321370 [UST application management]: Apps thread polling (in thread_application_management() at manage-apps.c:88)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802443657 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802490814 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802507049 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802537058 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802555254 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802577065 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802628778 [Client management]: Processing client command 'LTTNG_DATA_PENDING' (24) (in process_client_msg() at client.c:1016)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802644763 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.802657634 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802670728 [Client management]: Data pending for session auto-20240322-122149 (in cmd_data_pending() at cmd.c:4008)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.802683494 [Client management]: Consumer data pending for id 0 (in consumer_is_data_pending() at consumer.c:1272)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802806570 [Client management]: Consumer data is NOT pending for session id 0 (in consumer_is_data_pending() at consumer.c:1307)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802825523 [Client management]: Sending response (size: 25, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.802866400 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803129498 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803163144 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803181050 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803207399 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803249428 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803271569 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803317882 [Client management]: Processing client command 'LTTNG_LIST_SESSIONS' (13) (in process_client_msg() at client.c:1016)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803333312 [Client management]: Counting number of available session for UID 1000 (in lttng_sessions_count() at client.c:596)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803358989 [Client management]: Getting all available session for UID 1000 GID 1000 (in cmd_list_lttng_sessions() at cmd.c:3947)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803384797 [Client management]: Sending response (size: 4409, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803427895 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803611281 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803644688 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803659953 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803686479 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803699863 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803721390 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803768148 [Client management]: Processing client command 'LTTNG_LIST_DOMAINS' (11) (in process_client_msg() at client.c:1016)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803783304 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.803795290 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.803807881 [Client management]: Listing domains found UST global domain (in cmd_list_domains() at cmd.c:3652)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803824881 [Client management]: Sending response (size: 332, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.803863063 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804032987 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804065377 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804079700 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804105897 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804120467 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804141973 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804186964 [Client management]: Processing client command 'LTTNG_LIST_CHANNELS' (10) (in process_client_msg() at client.c:1016)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804201747 [Client management]: Getting session auto-20240322-122149 by name (in process_client_msg() at client.c:1133)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.804213223 [Client management]: Trying to find session by name auto-20240322-122149 (in session_find_by_name() at session.c:1143)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.804227236 [Client management]: Consumer socket created (fd: 69) and added to output (in consumer_create_socket() at consumer.c:351)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804239741 [Client management]: Setting relayd for session auto-20240322-122149 (in cmd_setup_relayd() at cmd.c:1004)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804252469 [Client management]: Listing channels for session auto-20240322-122149 (in cmd_list_channels() at cmd.c:3725)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804274332 [Client management]: Sending response (size: 120, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.804308652 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813260684 [48960/48991]: Call RCU deleting app PID 48995 (in delete_ust_app_rcu() at ust-app.c:1119)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813299950 [48960/48991]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:193)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813332218 [48960/48991]: No metadata to push for metadata key 2 (in ust_app_push_metadata() at ust-app.c:680)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813333306 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813431466 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813459668 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813475810 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813489100 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813348612 [48960/48991]: UST app deleting channel chan_ust (in delete_ust_app_channel() at ust-app.c:544)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813516113 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813573664 [48960/48991]: UST app release session handle failed. Application is dead: pid = 48995, sock = -1 (in delete_ust_app_session() at ust-app.c:971)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813628561 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813632931 [Notification]: Poll wait returned (1) (in thread_notification() at notification-thread.c:670)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813659597 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813649830 [Notification]: Handling fd (33) activity (1) (in thread_notification() at notification-thread.c:694)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813674026 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813693902 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813704933 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813717534 [Notification]: Received `REMOVE_TRACER_EVENT_SOURCE` command (in handle_notification_thread_command() at notification-thread-events.c:3162)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813731059 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813778622 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813757587 [Notification]: Entering poll wait (in thread_notification() at notification-thread.c:668)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813816274 [48960/48991]: Beginning of waiter "wait" period (in lttng_waiter_wait() at waiter.c:46)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813843119 [48960/48991]: End of waiter "wait" period (in lttng_waiter_wait() at waiter.c:106)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813781816 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.813902758 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.813821791 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG2 - 12:21:56.813955921 [48960/48991]: UST app pid 48995 deleted (in delete_ust_app() at ust-app.c:1103)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814021472 [Client management]: Incomplete recv() from client... continuing (in thread_manage_clients() at client.c:2608)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814048264 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814059801 [Client management]: Wait for client response (in thread_manage_clients() at client.c:2576)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814077846 [Client management]: Receiving data from client ... (in thread_manage_clients() at client.c:2604)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814120066 [Client management]: Processing client command 'LTTNG_LIST_SESSIONS' (13) (in process_client_msg() at client.c:1016)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814132641 [Client management]: Counting number of available session for UID 1000 (in lttng_sessions_count() at client.c:596)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814144237 [Client management]: Getting all available session for UID 1000 GID 1000 (in cmd_list_lttng_sessions() at cmd.c:3947)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814157381 [Client management]: Sending response (size: 4409, retcode: Success (10)) (in thread_manage_clients() at client.c:2683)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG1 - 12:21:56.814193863 [Client management]: Accepting client command ... (in thread_manage_clients() at client.c:2534)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.824269140 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.824358359 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.824375097 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.824402854 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.824416739 [HT cleanup]: Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:125)
Mar 22 12:21:56 dorsal-vm2 lttng-sessiond[48960]: DBG3 - 12:21:56.824453561 [HT cleanup]: Polling. (in thread_ht_cleanup() at ht-cleanup.c:122)
(5-5/8)