Project

General

Profile

Bug #653 ยป lttng-sessiond.log

lttng-sessiond log - Daniel U. Thibault, 10/21/2013 09:32 AM

 
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Processing client command 5 (in process_client_msg() at main.c:2578)
DEBUG1 [14579/14680]: Getting session test by name (in process_client_msg() at main.c:2655)
DEBUG2 [14579/14680]: Trying to find session by name test (in session_find_by_name() at session.c:133)
DEBUG1 [14579/14680]: Creating UST session (in create_ust_session() at main.c:2459)
DEBUG3 [14579/14680]: Created hashtable size 4 at 0x7fe720003d60 of type 0 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14680]: Created hashtable size 4 at 0x7fe720004120 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [14579/14680]: UST trace session create successful (in trace_ust_create_session() at trace-ust.c:235)
DEBUG3 [14579/14680]: Copying tracing session consumer output in UST session (in copy_session_consumer() at main.c:2409)
DEBUG3 [14579/14680]: Created hashtable size 4 at 0x7fe7200044e0 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14680]: Copy session consumer subdir /ust (in copy_session_consumer() at main.c:2427)
DEBUG1 [14579/14680]: Spawning consumerd (in spawn_consumerd() at main.c:2086)
DEBUG2 [14579/14680]: Consumer pid 14690 (in start_consumerd() at main.c:2269)
DEBUG2 [14579/14680]: Spawning consumer control thread (in start_consumerd() at main.c:2272)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG1 [14579/14691]: [thread] Manage consumer started (in thread_manage_consumer() at main.c:922)
DEBUG1 [14690/14690]: Using 64-bit UST consumer at: /usr/local/lib/lttng/libexec/lttng-consumerd (in spawn_consumerd() at main.c:2161)
DEBUG2 [14579/14691]: Receiving code from consumer err_sock (in thread_manage_consumer() at main.c:1007)
DEBUG3 [14579/14680]: Consumer socket created (fd: 30) and added to output (in consumer_create_socket() at consumer.c:298)
DEBUG1 [14579/14680]: Spawning consumerd (in spawn_consumerd() at main.c:2086)
DEBUG2 [14579/14680]: Consumer pid 14697 (in start_consumerd() at main.c:2269)
DEBUG2 [14579/14680]: Spawning consumer control thread (in start_consumerd() at main.c:2272)
DEBUG1 [14579/14691]: Consumer command socket ready (fd: 30 (in thread_manage_consumer() at main.c:1042)
DEBUG1 [14579/14691]: Consumer metadata socket ready (fd: 31) (in thread_manage_consumer() at main.c:1044)
DEBUG1 [14697/14697]: Using 32-bit UST consumer at: /usr/local/lib32/lttng/libexec/lttng-consumerd (in spawn_consumerd() at main.c:2206)
DEBUG1 [14579/14698]: [thread] Manage consumer started (in thread_manage_consumer() at main.c:922)
DEBUG2 [14579/14698]: Receiving code from consumer err_sock (in thread_manage_consumer() at main.c:1007)
DEBUG3 [14579/14680]: Consumer socket created (fd: 34) and added to output (in consumer_create_socket() at consumer.c:298)
DEBUG1 [14579/14680]: Setting relayd for session test (in cmd_setup_relayd() at cmd.c:665)
DEBUG1 [14579/14680]: Enabling channel ch for session test (in cmd_enable_channel() at cmd.c:849)
DEBUG2 [14579/14680]: Trace UST channel ch not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:149)
DEBUG3 [14579/14680]: Created hashtable size 4 at 0x7fe720004ca0 of type 0 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14680]: Created hashtable size 4 at 0x7fe720004ff0 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [14579/14680]: Trace UST channel ch created (in trace_ust_create_channel() at trace-ust.c:302)
DEBUG2 [14579/14680]: Channel ch is being created for UST with buffer 1 and id 0 (in channel_ust_create() at channel.c:343)
DEBUG2 [14579/14680]: UST app adding channel ch to UST domain for session id 0 (in ust_app_create_channel_glb() at ust-app.c:3511)
DEBUG2 [14579/14680]: Channel ch created successfully (in channel_ust_create() at channel.c:367)
DEBUG1 [14579/14680]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14698]: Consumer command socket ready (fd: 34 (in thread_manage_consumer() at main.c:1042)
DEBUG1 [14579/14698]: Consumer metadata socket ready (fd: 35) (in thread_manage_consumer() at main.c:1044)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Processing client command 6 (in process_client_msg() at main.c:2578)
DEBUG1 [14579/14680]: Getting session test by name (in process_client_msg() at main.c:2655)
DEBUG2 [14579/14680]: Trying to find session by name test (in session_find_by_name() at session.c:133)
DEBUG3 [14579/14680]: Consumer socket created (fd: 30) and added to output (in consumer_create_socket() at consumer.c:298)
DEBUG3 [14579/14680]: Consumer socket created (fd: 34) and added to output (in consumer_create_socket() at consumer.c:298)
DEBUG1 [14579/14680]: Setting relayd for session test (in cmd_setup_relayd() at cmd.c:665)
DEBUG2 [14579/14680]: Trace UST channel ch found by name (in trace_ust_find_channel_by_name() at trace-ust.c:144)
DEBUG2 [14579/14680]: Trace UST event * NOT found (in trace_ust_find_event() at trace-ust.c:183)
DEBUG2 [14579/14680]: Trace UST event *, loglevel (0,-1) created (in trace_ust_create_event() at trace-ust.c:374)
DEBUG1 [14579/14680]: UST app creating event * for all apps for session id 0 (in ust_app_create_event_glb() at ust-app.c:3664)
DEBUG1 [14579/14680]: Event UST * created in channel ch (in event_ust_enable_tracepoint() at event.c:467)
DEBUG1 [14579/14680]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Processing client command 16 (in process_client_msg() at main.c:2578)
DEBUG1 [14579/14680]: Getting session test by name (in process_client_msg() at main.c:2655)
DEBUG2 [14579/14680]: Trying to find session by name test (in session_find_by_name() at session.c:133)
DEBUG1 [14579/14680]: Starting all UST traces (in ust_app_start_trace_all() at ust-app.c:4011)
DEBUG1 [14579/14680]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14682]: UST registration received with pid:14706 ppid:13645 uid:1000 gid:1000 sock:27 name:sample_static (version 5.0) (in thread_registration_apps() at main.c:1877)
DEBUG1 [14579/14682]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
DEBUG1 [14579/14682]: Accepting application registration (in thread_registration_apps() at main.c:1781)
DEBUG1 [14579/14681]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:90)
DEBUG1 [14579/14681]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:75)
DEBUG1 [14579/14681]: Dispatching UST registration pid:14706 ppid:13645 uid:1000 gid:1000 sock:27 name:sample_static (version 5.0) (in thread_dispatch_ust_registration() at main.c:1556)
DEBUG3 [14579/14681]: UST app creating application for socket 27 (in ust_app_create() at ust-app.c:2763)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c000a70 of type 2 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c000e30 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG1 [14579/14681]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1544)
DEBUG1 [14579/14682]: UST registration received with pid:14706 ppid:13645 uid:1000 gid:1000 sock:36 name:sample_static (version 5.0) (in thread_registration_apps() at main.c:1877)
DEBUG1 [14579/14682]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
DEBUG1 [14579/14682]: Accepting application registration (in thread_registration_apps() at main.c:1781)
DEBUG1 [14579/14681]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:90)
DEBUG1 [14579/14681]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:75)
DEBUG1 [14579/14681]: Dispatching UST registration pid:14706 ppid:13645 uid:1000 gid:1000 sock:36 name:sample_static (version 5.0) (in thread_dispatch_ust_registration() at main.c:1556)
DEBUG3 [14579/14681]: UST app notify socket 36 is set (in thread_dispatch_ust_registration() at main.c:1613)
DEBUG1 [14579/14681]: App registered with pid:14706 ppid:13645 uid:1000 gid:1000 sock:27 name:sample_static notify_sock:36 (version 5.0) (in ust_app_add() at ust-app.c:2851)
DEBUG2 [14579/14681]: UST app global update for app sock 27 for session id 0 (in ust_app_global_update() at ust-app.c:4150)
DEBUG2 [14579/14681]: UST app pid: 14706 session id 0 not found, creating it (in create_ust_app_session() at ust-app.c:1735)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c0022a0 of type 0 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [14579/14681]: Shadow copy of session handle -1 (in shadow_copy_session() at ust-app.c:1494)
DEBUG2 [14579/14681]: Channel ch not found on shadow session copy, creating it (in shadow_copy_session() at ust-app.c:1541)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c003380 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c003620 of type 0 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14681]: UST app channel ch allocated (in alloc_ust_app_channel() at ust-app.c:844)
DEBUG2 [14579/14681]: UST app shadow copy of channel ch started (in shadow_copy_channel() at ust-app.c:1421)
DEBUG2 [14579/14681]: UST event * not found on shadow copy channel (in shadow_copy_channel() at ust-app.c:1461)
DEBUG3 [14579/14681]: UST app event * allocated (in alloc_ust_app_event() at ust-app.c:900)
DEBUG3 [14579/14681]: UST app shadow copy of channel ch done (in shadow_copy_channel() at ust-app.c:1471)
DEBUG3 [14579/14681]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:195)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c003d90 of type 2 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14681]: Buffer registry per UID created id: 0, ABI: 64, uid: 1000, domain: 2 (in buffer_reg_uid_create() at buffer-registry.c:144)
DEBUG3 [14579/14681]: Buffer registry per UID adding to global registry with id: 0 (in buffer_reg_uid_add() at buffer-registry.c:166)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c0041f0 of type 2 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [14579/14681]: Append to metadata: "typealias integer { size = 8; align = 8; signed = false; } := uint8_t;
typealias integer { size = 16; align = 8; signed = false; } := uint16_t;
typealias integer { size = 32; align = 8; signed = false; } := uint32_t;
typealias integer { size = 64; align = 8; signed = false; } := uint64_t;
typealias integer { size = 64; align = 8; signed = false; } := unsigned long;
typealias integer { size = 5; align = 1; signed = false; } := uint5_t;
typealias integer { size = 27; align = 1; signed = false; } := uint27_t;

trace {
major = 1;
minor = 8;
uuid = "06ec320d-9eda-4072-bdc9-8325bef39320";
byte_order = le;
packet.header := struct {
uint32_t magic;
uint8_t uuid[16];
uint32_t stream_id;
};
};

" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: "env {
hostname = "sds-dut-vb";
domain = "ust";
tracer_name = "lttng-ust";
tracer_major = 2;
tracer_minor = 3;
" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: "};

" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: "clock {
name = monotonic;
" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: " uuid = "e8efe582-a1a1-440b-afc3-fa4b02f45f79";
" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: " description = "Monotonic Clock";
freq = 1000000000; /* Frequency, in Hz */
/* clock value offset from Epoch is: offset * (1/freq) */
offset = 1382014868119395982;
};

" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: "typealias integer {
size = 27; align = 1; signed = false;
map = clock.monotonic.value;
} := uint27_clock_monotonic_t;

typealias integer {
size = 32; align = 8; signed = false;
map = clock.monotonic.value;
} := uint32_clock_monotonic_t;

typealias integer {
size = 64; align = 8; signed = false;
map = clock.monotonic.value;
} := uint64_clock_monotonic_t;

" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: "struct packet_context {
uint64_clock_monotonic_t timestamp_begin;
uint64_clock_monotonic_t timestamp_end;
uint64_t content_size;
uint64_t packet_size;
unsigned long events_discarded;
uint32_t cpu_id;
};

" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: Append to metadata: "struct event_header_compact {
enum : uint5_t { compact = 0 ... 30, extended = 31 } id;
variant <id> {
struct {
uint27_clock_monotonic_t timestamp;
} compact;
struct {
uint32_t id;
uint64_clock_monotonic_t timestamp;
} extended;
} v;
} align(8);

struct event_header_large {
enum : uint16_t { compact = 0 ... 65534, extended = 65535 } id;
variant <id> {
struct {
uint32_clock_monotonic_t timestamp;
} compact;
struct {
uint32_t id;
uint64_clock_monotonic_t timestamp;
} extended;
} v;
} align(8);

" (in lttng_metadata_printf() at ust-metadata.c:144)
DEBUG3 [14579/14681]: UST app buffer registry per UID created successfully (in setup_buffer_reg_uid() at ust-app.c:1697)
DEBUG3 [14579/14684]: UST thread notify added sock 36 to pollset (in ust_thread_manage_notify() at ust-thread.c:131)
DEBUG3 [14579/14684]: [ust-thread] Manage notify polling on 3 fds (in ust_thread_manage_notify() at ust-thread.c:61)
DEBUG2 [14579/14681]: UST app session created successfully with handle 1 (in create_ust_app_session() at ust-app.c:1803)
DEBUG1 [14579/14681]: UST app creating channel ch with per UID buffers (in create_channel_per_uid() at ust-app.c:2330)
DEBUG3 [14579/14681]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:195)
DEBUG2 [14579/14681]: UST app creating buffer registry channel for ch (in create_buffer_reg_channel() at ust-app.c:2183)
DEBUG3 [14579/14681]: Buffer registry channel create with key: 0 (in buffer_reg_channel_create() at buffer-registry.c:326)
DEBUG3 [14579/14681]: Created hashtable size 4 at 0x7fe70c0046c0 of type 0 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [14579/14681]: Asking UST consumer for channel (in ask_channel_creation() at ust-consumer.c:118)
DEBUG3 [14579/14681]: mkdir() recursive /home/daniel/lttng-traces/test-20131018-130302/ust/uid/1000/64-bit with mode 504 for uid 1000 and gid 1000 (in run_as_mkdir_recursive() at runas.c:310)
DEBUG1 [14579/14681]: Using run_as_clone (in run_as() at runas.c:293)
DEBUG3 [14579/14681]: UST registry channel finding key 0 (in ust_registry_channel_find() at ust-registry.c:489)
libringbuffer[14690/14695]: Error: zero_file: No space left on device (in _shm_object_table_alloc_shm() at shm.c:173)
DEBUG3 [14579/14681]: UST registry channel finding key 0 (in ust_registry_channel_find() at ust-registry.c:489)
DEBUG3 [14579/14681]: Buffer registry channel destroy with key 0 (in buffer_reg_channel_destroy() at buffer-registry.c:498)
DEBUG3 [14579/14681]: Buffer registry per UID find id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_find() at buffer-registry.c:195)
DEBUG3 [14579/14681]: UST app deleting channel ch (in delete_ust_app_channel() at ust-app.c:357)
DEBUG1 [14579/14681]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1544)
DEBUG1 [14579/14683]: Apps with sock 27 added to poll set (in thread_manage_apps() at main.c:1323)
DEBUG1 [14579/14683]: Apps thread polling on 3 fds (in thread_manage_apps() at main.c:1248)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG1 [14579/14683]: PID 14706 unregistering with sock 27 (in ust_app_unregister() at ust-app.c:2902)
DEBUG1 [14579/14683]: Apps thread polling on 2 fds (in thread_manage_apps() at main.c:1248)
DEBUG1 [14579/14684]: UST app notify socket unregister 36 (in ust_app_notify_sock_unregister() at ust-app.c:4914)
DEBUG2 [14579/14684]: UST app find by notify sock 36 not found (in find_app_by_notify_sock() at ust-app.c:992)
DEBUG3 [14579/14684]: [ust-thread] Manage notify polling on 2 fds (in ust_thread_manage_notify() at ust-thread.c:61)
DEBUG3 [14579/14710]: Call RCU deleting app PID 14706 (in delete_ust_app_rcu() at ust-app.c:743)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG2 [14579/14710]: UST app pid 14706 deleted (in delete_ust_app() at ust-app.c:728)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [14579/14680]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [14579/14680]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [14579/14680]: Processing client command 9 (in process_client_msg() at main.c:2578)
DEBUG1 [14579/14680]: Getting session test by name (in process_client_msg() at main.c:2655)
DEBUG2 [14579/14680]: Trying to find session by name test (in session_find_by_name() at session.c:133)
DEBUG3 [14579/14680]: No kernel session when tearing down session (in kernel_destroy_session() at kernel.c:751)
DEBUG1 [14579/14680]: Destroy all UST traces (in ust_app_destroy_trace_all() at ust-app.c:4116)
DEBUG2 [14579/14680]: Trace UST destroy session 0 (in trace_ust_destroy_session() at trace-ust.c:676)
DEBUG3 [14579/14680]: Buffer registry per UID destroy with id: 0, ABI: 64, uid: 1000 (in buffer_reg_uid_destroy() at buffer-registry.c:624)
DEBUG1 [14579/14680]: Destroying session test (in session_destroy() at session.c:158)
DEBUG1 [14579/14680]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [14579/14680]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [14579/14680]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG1 [14579/14685]: Updating kernel poll set (in update_kernel_poll() at main.c:616)
DEBUG1 [14579/14685]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:801)
DEBUG2 [14579/14710]: Trace destroy UST channel ch (in _trace_ust_destroy_channel() at trace-ust.c:573)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14710]: Buffer registry session destroy (in buffer_reg_session_destroy() at buffer-registry.c:543)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [14579/14678]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG2 [14579/14710]: Trace destroy UST event * (in trace_ust_destroy_event() at trace-ust.c:524)
    (1-1/1)