sudo lttng-sessiond -vvv --verbose-consumer

gistfile1.txt

$ sudo lttng-sessiond -vvv --verbose-consumer
DEBUG1 - 18:05:27.379944 [22377/22377]: [sessiond configuration]
DEBUG1 - 18:05:27.380072 [22377/22377]:         verbose:                      3
DEBUG1 - 18:05:27.380106 [22377/22377]:         verbose consumer:             1
DEBUG1 - 18:05:27.380126 [22377/22377]:         quiet mode:                   False
DEBUG1 - 18:05:27.380143 [22377/22377]:         agent_tcp_port:               5345
DEBUG1 - 18:05:27.380159 [22377/22377]:         application socket timeout:   5
DEBUG1 - 18:05:27.380176 [22377/22377]:         no-kernel:                    False
DEBUG1 - 18:05:27.380192 [22377/22377]:         background:                   False
DEBUG1 - 18:05:27.380207 [22377/22377]:         daemonize:                    False
DEBUG1 - 18:05:27.380223 [22377/22377]:         signal parent on start:       False
DEBUG1 - 18:05:27.380239 [22377/22377]:         tracing group name:           tracing
DEBUG1 - 18:05:27.380255 [22377/22377]:         kmod_probe_list:              None
DEBUG1 - 18:05:27.380271 [22377/22377]:         kmod_extra_probe_list:        None
DEBUG1 - 18:05:27.380287 [22377/22377]:         rundir:                       /var/run/lttng
DEBUG1 - 18:05:27.380303 [22377/22377]:         application socket path:      /var/run/lttng/lttng-ust-sock-7
DEBUG1 - 18:05:27.380319 [22377/22377]:         client socket path:           /var/run/lttng/client-lttng-sessiond
DEBUG1 - 18:05:27.380335 [22377/22377]:         wait shm path:                /lttng-ust-wait-7
DEBUG1 - 18:05:27.380351 [22377/22377]:         health socket path:           /var/run/lttng/sessiond-health
DEBUG1 - 18:05:27.380368 [22377/22377]:         LTTNG_UST_CLOCK_PLUGIN:       None
DEBUG1 - 18:05:27.380384 [22377/22377]:         pid file path:                /var/run/lttng/lttng-sessiond.pid
DEBUG1 - 18:05:27.380400 [22377/22377]:         lock file path:               /var/run/lttng/lttng-sessiond.lck
DEBUG1 - 18:05:27.380415 [22377/22377]:         session load path:            None
DEBUG1 - 18:05:27.380430 [22377/22377]:         agent port file path:         /var/run/lttng/agent.port
DEBUG1 - 18:05:27.381027 [22378/22378]: Resetting run_as worker signal handlers to default (in reset_sighandler() at runas.c:536)
DEBUG1 - 18:05:27.381304 [22378/22378]: run_as signal handler set for SIGTERM and SIGINT (in set_worker_sighandlers() at runas.c:597)
DEBUG1 - 18:05:27.381484 [22377/22377]: Health check time delta in seconds set to 20 (in health_init() at health.c:73)
DEBUG3 - 18:05:27.381701 [22377/22377]: Creating LTTng run directory: /var/run/lttng (in create_lttng_rundir() at main.c:5210)
DEBUG1 - 18:05:27.381710 [22377/22379]: [ht-thread] startup. (in thread_ht_cleanup() at ht-cleanup.c:101)
DEBUG1 - 18:05:27.381897 [22377/22379]: epoll set max size is 3310100 (in compat_epoll_set_max_size() at compat-epoll.c:337)
DEBUG3 - 18:05:27.382077 [22377/22379]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:124)
DEBUG3 - 18:05:27.382263 [22377/22377]: Created hashtable size 4 at 0x55f372b5deb0 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 18:05:27.382296 [22377/22377]: Created hashtable size 4 at 0x55f372b5e3f0 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 18:05:27.382315 [22377/22377]: Created hashtable size 4 at 0x55f372b5f020 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 18:05:27.382335 [22377/22377]: Created hashtable size 4 at 0x55f372b5f4d0 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG2 - 18:05:27.382352 [22377/22377]: Creating consumer directory: /var/run/lttng/kconsumerd (in set_consumer_sockets() at main.c:5252)
DEBUG1 - 18:05:27.419656 [22377/22377]: Modprobe successfully lttng-ring-buffer-client-discard (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.421357 [22377/22377]: Modprobe successfully lttng-ring-buffer-client-overwrite (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.422774 [22377/22377]: Modprobe successfully lttng-ring-buffer-metadata-client (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.424052 [22377/22377]: Modprobe successfully lttng-ring-buffer-client-mmap-discard (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.425375 [22377/22377]: Modprobe successfully lttng-ring-buffer-client-mmap-overwrite (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.426545 [22377/22377]: Modprobe successfully lttng-ring-buffer-metadata-mmap-client (in modprobe_lttng() at modprobe.c:201)
DEBUG2 - 18:05:27.426624 [22377/22377]: Kernel tracer version validated (2.10, ABI 2.3) (in kernel_validate_version() at kernel.c:848)
DEBUG1 - 18:05:27.427761 [22377/22377]: Modprobe successfully lttng-probe-asoc (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.428940 [22377/22377]: Modprobe successfully lttng-probe-block (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.429010 [22377/22377]: libkmod: could not find module by name='lttng_probe_btrfs'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.429018 [22377/22377]: Unable to load optional module lttng-probe-btrfs; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.429835 [22377/22377]: Modprobe successfully lttng-probe-compaction (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.429889 [22377/22377]: libkmod: could not find module by name='lttng_probe_ext3'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.429897 [22377/22377]: Unable to load optional module lttng-probe-ext3; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.429923 [22377/22377]: libkmod: could not find module by name='lttng_probe_ext4'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.429933 [22377/22377]: Unable to load optional module lttng-probe-ext4; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.430685 [22377/22377]: Modprobe successfully lttng-probe-gpio (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.430737 [22377/22377]: libkmod: could not find module by name='lttng_probe_i2c'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.430746 [22377/22377]: Unable to load optional module lttng-probe-i2c; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.431422 [22377/22377]: Modprobe successfully lttng-probe-irq (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.431459 [22377/22377]: libkmod: could not find module by name='lttng_probe_jbd'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.431464 [22377/22377]: Unable to load optional module lttng-probe-jbd; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.432181 [22377/22377]: Modprobe successfully lttng-probe-jbd2 (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.432974 [22377/22377]: Modprobe successfully lttng-probe-kmem (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.433908 [22377/22377]: Modprobe successfully lttng-probe-kvm (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.433964 [22377/22377]: libkmod: could not find module by name='lttng_probe_kvm_x86'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.433975 [22377/22377]: Unable to load optional module lttng-probe-kvm-x86; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.434006 [22377/22377]: libkmod: could not find module by name='lttng_probe_kvm_x86_mmu'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.434013 [22377/22377]: Unable to load optional module lttng-probe-kvm-x86-mmu; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.434031 [22377/22377]: libkmod: could not find module by name='lttng_probe_lock'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.434035 [22377/22377]: Unable to load optional module lttng-probe-lock; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.434741 [22377/22377]: Modprobe successfully lttng-probe-module (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.435544 [22377/22377]: Modprobe successfully lttng-probe-napi (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.436413 [22377/22377]: Modprobe successfully lttng-probe-net (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.437204 [22377/22377]: Modprobe successfully lttng-probe-power (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.437989 [22377/22377]: Modprobe successfully lttng-probe-printk (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.438819 [22377/22377]: Modprobe successfully lttng-probe-random (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.439578 [22377/22377]: Modprobe successfully lttng-probe-rcu (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.439630 [22377/22377]: libkmod: could not find module by name='lttng_probe_regmap'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.439645 [22377/22377]: Unable to load optional module lttng-probe-regmap; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.440500 [22377/22377]: Modprobe successfully lttng-probe-regulator (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.440550 [22377/22377]: libkmod: could not find module by name='lttng_probe_rpm'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.440558 [22377/22377]: Unable to load optional module lttng-probe-rpm; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.441420 [22377/22377]: Modprobe successfully lttng-probe-sched (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.442171 [22377/22377]: Modprobe successfully lttng-probe-scsi (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.443029 [22377/22377]: Modprobe successfully lttng-probe-signal (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.443933 [22377/22377]: Modprobe successfully lttng-probe-skb (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.444788 [22377/22377]: Modprobe successfully lttng-probe-sock (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.445679 [22377/22377]: Modprobe successfully lttng-probe-statedump (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.446560 [22377/22377]: Modprobe successfully lttng-probe-sunrpc (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.447429 [22377/22377]: Modprobe successfully lttng-probe-timer (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.448318 [22377/22377]: Modprobe successfully lttng-probe-udp (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.449354 [22377/22377]: Modprobe successfully lttng-probe-vmscan (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.450139 [22377/22377]: Modprobe successfully lttng-probe-v4l2 (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.450913 [22377/22377]: Modprobe successfully lttng-probe-workqueue (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.451872 [22377/22377]: Modprobe successfully lttng-probe-writeback (in modprobe_lttng() at modprobe.c:201)
DEBUG1 - 18:05:27.451919 [22377/22377]: libkmod: could not find module by name='lttng_probe_x86_irq_vectors'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.451925 [22377/22377]: Unable to load optional module lttng-probe-x86-irq-vectors; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.451943 [22377/22377]: libkmod: could not find module by name='lttng_probe_x86_exceptions'
 (in log_kmod() at modprobe.c:117)
DEBUG1 - 18:05:27.451947 [22377/22377]: Unable to load optional module lttng-probe-x86-exceptions; continuing (in modprobe_lttng() at modprobe.c:197)
DEBUG1 - 18:05:27.451999 [22377/22377]: Kernel tracer fd 19 (in init_kernel_tracer() at main.c:2639)
DEBUG3 - 18:05:27.452005 [22377/22377]: Syscall init system call table (in syscall_init_table() at syscall.c:53)
DEBUG1 - 18:05:27.452294 [22377/22377]: Reallocating syscall table from 256 to 512 entries (in syscall_init_table() at syscall.c:99)
DEBUG1 - 18:05:27.452651 [22377/22377]: Reallocating syscall table from 512 to 1024 entries (in syscall_init_table() at syscall.c:99)
DEBUG2 - 18:05:27.452860 [22377/22377]: Creating consumer directory: /var/run/lttng/ustconsumerd64 (in set_consumer_sockets() at main.c:5252)
DEBUG2 - 18:05:27.452933 [22377/22377]: Creating consumer directory: /var/run/lttng/ustconsumerd32 (in set_consumer_sockets() at main.c:5252)
DEBUG3 - 18:05:27.452984 [22377/22377]: Session daemon client socket 22 and application socket 23 created (in init_daemon_socket() at main.c:5118)
DEBUG1 - 18:05:27.453016 [22377/22377]: All permissions are set (in set_permissions() at main.c:5198)
DEBUG3 - 18:05:27.453035 [22377/22377]: Created hashtable size 4 at 0x55f372b64870 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 18:05:27.453039 [22377/22377]: Global buffer per UID registry initialized (in buffer_reg_init_uid_registry() at buffer-registry.c:99)
DEBUG3 - 18:05:27.453047 [22377/22377]: Created hashtable size 4 at 0x55f372b6a680 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 18:05:27.453050 [22377/22377]: Global buffer per PID registry initialized (in buffer_reg_init_pid_registry() at buffer-registry.c:228)
DEBUG1 - 18:05:27.453056 [22377/22377]: Command subsystem initialized (in cmd_init() at cmd.c:4115)
DEBUG1 - 18:05:27.453074 [22377/22377]: Pid 22377 written in file /var/run/lttng/lttng-sessiond.pid (in utils_create_pid_file() at utils.c:507)
DEBUG1 - 18:05:27.453087 [22377/22377]: Pid 5345 written in file /var/run/lttng/agent.port (in utils_create_pid_file() at utils.c:507)
DEBUG1 - 18:05:27.453108 [22377/22377]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:547)
DEBUG1 - 18:05:27.453225 [22377/22412]: [thread] Manage client started (in thread_manage_clients() at main.c:4275)
DEBUG1 - 18:05:27.453254 [22377/22411]: [notification-thread] Started notification thread (in thread_notification() at notification-thread.c:482)
DEBUG1 - 18:05:27.453270 [22377/22412]: Accepting client command ... (in thread_manage_clients() at main.c:4322)
DEBUG1 - 18:05:27.453280 [22377/22413]: [thread] Dispatch UST command started (in thread_dispatch_ust_registration() at main.c:1771)
DEBUG1 - 18:05:27.453287 [22377/22411]: [notification-thread] Creating notification channel UNIX socket at /var/run/lttng/sessiond-notification (in notification_channel_socket_create() at notification-thread.c:221)
DEBUG1 - 18:05:27.453288 [22377/22413]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 18:05:27.453301 [22377/22413]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1798)
DEBUG1 - 18:05:27.453309 [22377/22414]: [thread] Manage application registration started (in thread_registration_apps() at main.c:2016)
DEBUG1 - 18:05:27.453329 [22377/22415]: [thread] Manage application started (in thread_manage_apps() at main.c:1457)
DEBUG1 - 18:05:27.453340 [22377/22414]: Notifying applications of session daemon state: 1 (in notify_ust_apps() at main.c:676)
DEBUG1 - 18:05:27.453373 [22377/22410]: [thread] Manage health check started (in thread_manage_health() at main.c:4084)
DEBUG1 - 18:05:27.453544 [22377/22415]: Apps thread polling (in thread_manage_apps() at main.c:1487)
DEBUG1 - 18:05:27.453555 [22377/22418]: [thread] Thread manage kernel started (in thread_manage_kernel() at main.c:925)
DEBUG1 - 18:05:27.453574 [22377/22418]: Updating kernel poll set (in update_kernel_poll() at main.c:760)
DEBUG1 - 18:05:27.453581 [22377/22418]: Thread kernel polling (in thread_manage_kernel() at main.c:970)
DEBUG1 - 18:05:27.453593 [22377/22410]: Health check ready (in thread_manage_health() at main.c:4146)
DEBUG1 - 18:05:27.453602 [22377/22419]: [load-session-thread] Load session (in thread_load_session() at load-session-thread.c:91)
DEBUG1 - 18:05:27.453388 [22377/22411]: [notification-thread] Notification channel UNIX socket created (fd = 33) (in notification_channel_socket_create() at notification-thread.c:248)
DEBUG1 - 18:05:27.453690 [22377/22411]: [notification-thread] Listening on notification channel socket (in init_thread_state() at notification-thread.c:387)
DEBUG1 - 18:05:27.453707 [22377/22411]: [notification-thread] Entering poll wait (in thread_notification() at notification-thread.c:507)
DEBUG1 - 18:05:27.453392 [22377/22416]: [ust-thread] Manage application notify command (in ust_thread_manage_notify() at ust-thread.c:40)
DEBUG1 - 18:05:27.453395 [22377/22417]: [agent-thread] Manage agent application registration. (in agent_thread_manage_registration() at agent-thread.c:236)
DEBUG1 - 18:05:27.453401 [22377/22414]: Got the wait shm fd 34 (in get_wait_shm() at shm.c:115)
DEBUG1 - 18:05:27.453847 [22377/22414]: Futex wait update active 1 (in futex_wait_update() at futex.c:65)
DEBUG1 - 18:05:27.453854 [22377/22414]: Accepting application registration (in thread_registration_apps() at main.c:2053)
DEBUG3 - 18:05:27.453932 [22377/22416]: [ust-thread] Manage notify polling (in ust_thread_manage_notify() at ust-thread.c:69)
DEBUG3 - 18:05:27.453955 [22377/22417]: URI string: tcp://localhost (in uri_parse() at uri.c:324)
DEBUG2 - 18:05:27.454078 [22377/22417]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:165)
DEBUG3 - 18:05:27.454086 [22377/22417]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 0, data: 0 (in uri_parse() at uri.c:538)
DEBUG1 - 18:05:27.454104 [22377/22417]: [agent-thread] Listening on TCP port 5345 and socket 34 (in init_tcp_socket() at agent-thread.c:119)
DEBUG3 - 18:05:27.454111 [22377/22417]: [agent-thread] Manage agent polling (in agent_thread_manage_registration() at agent-thread.c:263)
DEBUG1 - 18:05:38.825876 [22377/22414]: UST registration received with pid:22424 ppid:18101 uid:1002 gid:1002 sock:41 name:ros2-ust (version 7.2) (in thread_registration_apps() at main.c:2169)
DEBUG1 - 18:05:38.825910 [22377/22414]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 18:05:38.825916 [22377/22413]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 18:05:38.825936 [22377/22413]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 18:05:38.825941 [22377/22413]: Dispatching UST registration pid:22424 ppid:18101 uid:1002 gid:1002 sock:41 name:ros2-ust (version 7.2) (in thread_dispatch_ust_registration() at main.c:1810)
DEBUG3 - 18:05:38.825958 [22377/22413]: UST app creating application for socket 41 (in ust_app_create() at ust-app.c:3365)
DEBUG3 - 18:05:38.825967 [22377/22413]: Created hashtable size 4 at 0x7f16cc006b60 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 18:05:38.825972 [22377/22413]: Created hashtable size 4 at 0x7f16cc009f30 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 18:05:38.825977 [22377/22413]: Created hashtable size 4 at 0x7f16cc00c860 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 18:05:38.825999 [22377/22413]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1798)
DEBUG1 - 18:05:38.825927 [22377/22414]: Accepting application registration (in thread_registration_apps() at main.c:2053)
DEBUG1 - 18:05:38.826018 [22377/22414]: UST registration received with pid:22424 ppid:18101 uid:1002 gid:1002 sock:42 name:ros2-ust (version 7.2) (in thread_registration_apps() at main.c:2169)
DEBUG1 - 18:05:38.826025 [22377/22414]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 18:05:38.826028 [22377/22414]: Accepting application registration (in thread_registration_apps() at main.c:2053)
DEBUG1 - 18:05:38.826028 [22377/22413]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 18:05:38.826040 [22377/22413]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 18:05:38.826053 [22377/22413]: Dispatching UST registration pid:22424 ppid:18101 uid:1002 gid:1002 sock:42 name:ros2-ust (version 7.2) (in thread_dispatch_ust_registration() at main.c:1810)
DEBUG3 - 18:05:38.826060 [22377/22413]: UST app notify socket 42 is set (in thread_dispatch_ust_registration() at main.c:1867)
DEBUG1 - 18:05:38.826068 [22377/22413]: App registered with pid:22424 ppid:18101 uid:1002 gid:1002 sock:41 name:ros2-ust notify_sock:42 (version 7.2) (in ust_app_add() at ust-app.c:3454)
DEBUG3 - 18:05:38.826100 [22377/22416]: [ust-thread] Manage notify return from poll on 2 fds (in ust_thread_manage_notify() at ust-thread.c:76)
DEBUG3 - 18:05:38.826111 [22377/22416]: UST thread notify added sock 42 to pollset (in ust_thread_manage_notify() at ust-thread.c:137)
DEBUG3 - 18:05:38.826117 [22377/22416]: [ust-thread] Manage notify polling (in ust_thread_manage_notify() at ust-thread.c:69)
DEBUG1 - 18:05:38.826121 [22377/22413]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1798)
DEBUG1 - 18:05:38.826130 [22377/22415]: Apps thread return from poll on 2 fds (in thread_manage_apps() at main.c:1494)
DEBUG1 - 18:05:38.826136 [22377/22415]: Apps with sock 41 added to poll set (in thread_manage_apps() at main.c:1551)
DEBUG1 - 18:05:38.826140 [22377/22415]: Apps thread polling (in thread_manage_apps() at main.c:1487)
DEBUG1 - 18:05:39.501357 [22377/22412]: Wait for client response (in thread_manage_clients() at main.c:4374)
DEBUG1 - 18:05:39.501388 [22377/22412]: Receiving data from client ... (in thread_manage_clients() at main.c:4419)
DEBUG1 - 18:05:39.501395 [22377/22412]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:4423)
DEBUG1 - 18:05:39.501417 [22377/22412]: Clean command context structure (in clean_command_ctx() at main.c:656)
DEBUG1 - 18:05:39.501422 [22377/22412]: Accepting client command ... (in thread_manage_clients() at main.c:4322)
DEBUG1 - 18:05:39.501428 [22377/22412]: Wait for client response (in thread_manage_clients() at main.c:4374)
DEBUG1 - 18:05:39.501436 [22377/22412]: Receiving data from client ... (in thread_manage_clients() at main.c:4419)
DEBUG1 - 18:05:39.501447 [22377/22412]: Processing client command 8 (in process_client_msg() at main.c:2891)
DEBUG1 - 18:05:39.501458 [22377/22412]: Waiting for 1 URIs from client ... (in process_client_msg() at main.c:3674)
DEBUG2 - 18:05:39.501466 [22377/22412]: Trying to find session by name a-random-session (in session_find_by_name() at session.c:276)
DEBUG3 - 18:05:39.501488 [22377/22412]: Created hashtable size 4 at 0x7f16ec0071c0 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 18:05:39.501493 [22377/22412]: Allocating ltt_sessions_ht_by_id (in ltt_sessions_ht_alloc() at session.c:153)
DEBUG3 - 18:05:39.501499 [22377/22412]: Created hashtable size 4 at 0x7f16ec007700 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 18:05:39.501504 [22377/22412]: Tracing session a-random-session created with ID 0 by UID 1002 GID 1002 (in session_create() at session.c:420)
DEBUG2 - 18:05:39.501509 [22377/22412]: Trying to find session by name a-random-session (in session_find_by_name() at session.c:276)
DEBUG3 - 18:05:39.501524 [22377/22412]: Created hashtable size 4 at 0x7f16ec00ccb0 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG2 - 18:05:39.501528 [22377/22412]: Setting trace directory path from URI to /tmp (in add_uri_to_consumer() at cmd.c:786)
DEBUG1 - 18:05:39.501534 [22377/22412]: Sending response (size: 20, retcode: Success (10)) (in thread_manage_clients() at main.c:4469)
DEBUG1 - 18:05:39.501544 [22377/22412]: Clean command context structure (in clean_command_ctx() at main.c:656)
DEBUG1 - 18:05:39.501548 [22377/22412]: Accepting client command ... (in thread_manage_clients() at main.c:4322)
DEBUG1 - 18:05:39.645421 [22377/22415]: Apps thread return from poll on 3 fds (in thread_manage_apps() at main.c:1494)
DEBUG1 - 18:05:39.645454 [22377/22415]: PID 22424 unregistering with sock 41 (in ust_app_unregister() at ust-app.c:3508)
DEBUG1 - 18:05:39.645500 [22377/22415]: Apps thread polling (in thread_manage_apps() at main.c:1487)
DEBUG3 - 18:05:39.645420 [22377/22416]: [ust-thread] Manage notify return from poll on 3 fds (in ust_thread_manage_notify() at ust-thread.c:76)
DEBUG3 - 18:05:39.645798 [22377/22416]: UST app receiving notify from sock 42 (in ust_app_recv_notify() at ust-app.c:5699)
DEBUG3 - 18:05:39.645810 [22377/22416]: UST app recv notify failed. Application died (in ust_app_recv_notify() at ust-app.c:5706)
DEBUG1 - 18:05:39.645821 [22377/22416]: UST app notify socket unregister 42 (in ust_app_notify_sock_unregister() at ust-app.c:5856)
DEBUG2 - 18:05:39.645826 [22377/22416]: UST app find by notify sock 42 not found (in find_app_by_notify_sock() at ust-app.c:1235)
DEBUG3 - 18:05:39.645834 [22377/22416]: [ust-thread] Manage notify polling (in ust_thread_manage_notify() at ust-thread.c:69)
DEBUG3 - 18:05:39.692904 [22377/22432]: Call RCU deleting app PID 22424 (in delete_ust_app_rcu() at ust-app.c:944)
DEBUG3 - 18:05:39.692934 [22377/22379]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:128)
DEBUG2 - 18:05:39.692964 [22377/22432]: UST app pid 22424 deleted (in delete_ust_app() at ust-app.c:928)
DEBUG3 - 18:05:39.703021 [22377/22379]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:124)
DEBUG3 - 18:05:39.703036 [22377/22379]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:128)
DEBUG3 - 18:05:39.713180 [22377/22379]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:124)
DEBUG3 - 18:05:39.713207 [22377/22379]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:128)
DEBUG3 - 18:05:39.723300 [22377/22379]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:124)