DEBUG3 [6238/6238]: Creating LTTng run directory: /var/run/lttng (in create_lttng_rundir() at main.c:4170) DEBUG2 [6238/6238]: Kernel consumer err path: /var/run/lttng/kconsumerd/error (in main() at main.c:4467) DEBUG2 [6238/6238]: Kernel consumer cmd path: /var/run/lttng/kconsumerd/command (in main() at main.c:4469) DEBUG1 [6238/6238]: Client socket path /var/run/lttng/client-lttng-sessiond (in main() at main.c:4522) DEBUG1 [6238/6238]: Application socket path /var/run/lttng/lttng-ust-sock-5 (in main() at main.c:4523) DEBUG1 [6238/6238]: Application wait path /lttng-ust-wait-5 (in main() at main.c:4524) DEBUG1 [6238/6238]: LTTng run directory path: /var/run/lttng (in main() at main.c:4525) DEBUG2 [6238/6238]: UST consumer 32 bits err path: /var/run/lttng/ustconsumerd32/error (in main() at main.c:4534) DEBUG2 [6238/6238]: UST consumer 32 bits cmd path: /var/run/lttng/ustconsumerd32/command (in main() at main.c:4536) DEBUG2 [6238/6238]: UST consumer 64 bits err path: /var/run/lttng/ustconsumerd64/error (in main() at main.c:4545) DEBUG2 [6238/6238]: UST consumer 64 bits cmd path: /var/run/lttng/ustconsumerd64/command (in main() at main.c:4547) DEBUG3 [6238/6238]: Created hashtable size 4 at 0x21a20a0 of type 1 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6238/6238]: Created hashtable size 4 at 0x21a24c0 of type 1 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6238/6238]: Created hashtable size 4 at 0x21a2900 of type 1 (in lttng_ht_new() at hashtable.c:112) DEBUG2 [6238/6238]: Creating consumer directory: /var/run/lttng/kconsumerd (in set_consumer_sockets() at main.c:4212) DEBUG1 [6238/6238]: Modprobe successfully lttng-tracer (in modprobe_lttng_control() at modprobe.c:192) DEBUG2 [6238/6238]: Kernel tracer version validated (major version 2) (in kernel_validate_version() at kernel.c:700) modprobe: module 'lttng_ftrace' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-ftrace (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-kprobes (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-kretprobes (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-lib-ring-buffer (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-ring-buffer-client-discard (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-ring-buffer-client-overwrite (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-ring-buffer-metadata-client (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-ring-buffer-client-mmap-discard (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-ring-buffer-client-mmap-overwrite (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-ring-buffer-metadata-mmap-client (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-types (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_asoc' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-asoc (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-block (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_btrfs' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-btrfs (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-compaction (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_ext3' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-ext3 (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-ext4 (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_gpio' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-gpio (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-irq (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_jbd' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-jbd (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-jbd2 (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-kmem (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_kvm' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-kvm (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_kvm_x86' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-kvm-x86 (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_kvm_x86_mmu' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-kvm-x86-mmu (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_lock' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-lock (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-module (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-napi (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-net (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-power (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-printk (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-random (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-rcu (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_regmap' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-regmap (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_regulator' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-regulator (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_rpm' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-rpm (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-sched (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-scsi (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-signal (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-skb (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-sock (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-statedump (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_sunrpc' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-sunrpc (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-timer (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-udp (in modprobe_lttng_data() at modprobe.c:228) modprobe: module 'lttng_probe_vmscan' not found DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-vmscan (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-workqueue (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Modprobe successfully lttng-probe-writeback (in modprobe_lttng_data() at modprobe.c:228) DEBUG1 [6238/6238]: Kernel tracer fd 6 (in init_kernel_tracer() at main.c:2342) DEBUG2 [6238/6238]: Creating consumer directory: /var/run/lttng/ustconsumerd64 (in set_consumer_sockets() at main.c:4212) DEBUG2 [6238/6238]: Creating consumer directory: /var/run/lttng/ustconsumerd32 (in set_consumer_sockets() at main.c:4212) DEBUG1 [6238/6238]: Signal handler set for SIGTERM, SIGPIPE and SIGINT (in set_signal_handler() at main.c:4314) DEBUG3 [6238/6238]: Session daemon client socket 9 and application socket 10 created (in init_daemon_socket() at main.c:4075) DEBUG1 [6238/6238]: All permissions are set (in set_permissions() at main.c:4158) DEBUG3 [6238/6238]: Created hashtable size 4 at 0x21a4cc0 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6238/6238]: Global buffer per UID registry initialized (in buffer_reg_init_uid_registry() at buffer-registry.c:99) DEBUG3 [6238/6238]: Created hashtable size 4 at 0x21a5100 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6238/6238]: Global buffer per PID registry initialized (in buffer_reg_init_pid_registry() at buffer-registry.c:220) DEBUG1 [6238/6238]: epoll set max size is 1652162 (in compat_epoll_set_max_size() at compat-epoll.c:274) DEBUG1 [6238/6238]: Command subsystem initialized (in cmd_init() at cmd.c:2847) DEBUG1 [6238/6238]: Pid 6238 written in file /var/run/lttng/lttng-sessiond.pid (in utils_create_pid_file() at utils.c:280) DEBUG1 [6238/6238]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:523) DEBUG1 [6238/6238]: Health check time delta in seconds set to 236 (in health_init() at health.c:272) DEBUG1 [6238/6372]: [ht-thread] startup. (in thread_ht_cleanup() at ht-cleanup.c:34) DEBUG1 [6238/6374]: [thread] Manage client started (in thread_manage_clients() at main.c:3621) DEBUG3 [6238/6372]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6376]: [thread] Manage application registration started (in thread_registration_apps() at main.c:1732) DEBUG1 [6238/6377]: [thread] Manage application started (in thread_manage_apps() at main.c:1218) DEBUG1 [6238/6375]: [thread] Dispatch UST command started (in thread_dispatch_ust_registration() at main.c:1509) DEBUG1 [6238/6375]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:75) DEBUG1 [6238/6376]: Notifying applications of session daemon state: 1 (in notify_ust_apps() at main.c:555) DEBUG1 [6238/6375]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1532) DEBUG1 [6238/6377]: Apps thread polling on 2 fds (in thread_manage_apps() at main.c:1248) DEBUG1 [6238/6373]: [thread] Manage health check started (in thread_manage_health() at main.c:3418) DEBUG1 [6238/6373]: Health check ready (in thread_manage_health() at main.c:3460) DEBUG1 [6238/6376]: Got the wait shm fd 23 (in get_wait_shm() at shm.c:115) DEBUG1 [6238/6376]: Futex wait update active 1 (in futex_wait_update() at futex.c:63) DEBUG1 [6238/6376]: Accepting application registration (in thread_registration_apps() at main.c:1769) DEBUG1 [6238/6378]: [ust-thread] Manage application notify command (in ust_thread_manage_notify() at ust-thread.c:37) DEBUG3 [6238/6378]: [ust-thread] Manage notify polling on 2 fds (in ust_thread_manage_notify() at ust-thread.c:61) DEBUG1 [6238/6379]: [thread] Thread manage kernel started (in thread_manage_kernel() at main.c:756) DEBUG1 [6238/6379]: Updating kernel poll set (in update_kernel_poll() at main.c:616) DEBUG1 [6238/6379]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:801) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Processing client command 8 (in process_client_msg() at main.c:2578) DEBUG1 [6238/6374]: Waiting for 1 URIs from client ... (in process_client_msg() at main.c:3073) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG3 [6238/6374]: Created hashtable size 4 at 0x7ff718005ea0 of type 1 (in lttng_ht_new() at hashtable.c:112) DEBUG1 [6238/6374]: Tracing session ksession created with ID 0 by UID 0 GID 0 (in session_create() at session.c:224) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG3 [6238/6374]: Created hashtable size 4 at 0x7ff71800b330 of type 1 (in lttng_ht_new() at hashtable.c:112) DEBUG2 [6238/6374]: Setting trace directory path from URI to /root/lttng-traces/ksession-20131224-122721 (in add_uri_to_consumer() at cmd.c:429) DEBUG1 [6238/6374]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Processing client command 7 (in process_client_msg() at main.c:2578) DEBUG1 [6238/6374]: Getting session ksession by name (in process_client_msg() at main.c:2655) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG1 [6238/6374]: Creating kernel session (in create_kernel_session() at main.c:2494) DEBUG3 [6238/6374]: Created hashtable size 4 at 0x7ff718003d40 of type 1 (in lttng_ht_new() at hashtable.c:112) DEBUG1 [6238/6374]: Kernel session created (fd: 28) (in kernel_create_session() at kernel.c:109) DEBUG3 [6238/6374]: Copying tracing session consumer output in kernel session (in copy_session_consumer() at main.c:2392) DEBUG3 [6238/6374]: Created hashtable size 4 at 0x7ff718004180 of type 1 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6238/6374]: Copy session consumer subdir /kernel (in copy_session_consumer() at main.c:2427) DEBUG3 [6238/6374]: mkdir() recursive /root/lttng-traces/ksession-20131224-122721 with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:310) DEBUG1 [6238/6374]: Using run_as_clone (in run_as() at runas.c:293) DEBUG3 [6238/6372]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58) DEBUG1 [6238/6374]: Spawning consumerd (in spawn_consumerd() at main.c:2086) DEBUG2 [6238/6374]: Consumer pid 6866 (in start_consumerd() at main.c:2269) DEBUG2 [6238/6374]: Spawning consumer control thread (in start_consumerd() at main.c:2272) DEBUG3 [6866/6866]: Looking for a kernel consumer at these locations: (in spawn_consumerd() at main.c:2105) DEBUG3 [6866/6866]: 1) /prj/x86/test/packages/tools/lttng/lttng-build-root/lib/lttng/libexec/lttng-consumerd (in spawn_consumerd() at main.c:2106) DEBUG3 [6866/6866]: 2) /prj/x86/test/packages/tools/lttng/lttng-build-root/lib/lttng/libexec/lttng-consumerd (in spawn_consumerd() at main.c:2107) DEBUG3 [6866/6866]: 3) (in spawn_consumerd() at main.c:2108) DEBUG1 [6238/6867]: [thread] Manage consumer started (in thread_manage_consumer() at main.c:922) DEBUG3 [6866/6866]: Found location #1 (in spawn_consumerd() at main.c:2110) DEBUG1 [6866/6866]: Using kernel consumer at: /prj/x86/test/packages/tools/lttng/lttng-build-root/lib/lttng/libexec/lttng-consumerd (in spawn_consumerd() at main.c:2122) DEBUG1 [6866/6866]: epoll set max size is 1652162 (in compat_epoll_set_max_size() at compat-epoll.c:274) DEBUG3 [6866/6866]: Created hashtable size 4 at 0x20d9030 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6866/6866]: Created hashtable size 4 at 0x20d9470 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6866/6866]: Created hashtable size 4 at 0x20d98b0 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG3 [6866/6866]: Created hashtable size 4 at 0x20d9cf0 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG1 [6866/6866]: Connecting to error socket /var/run/lttng/kconsumerd/error (in main() at lttng-consumerd.c:361) DEBUG2 [6238/6867]: Receiving code from consumer err_sock (in thread_manage_consumer() at main.c:1007) DEBUG1 [6866/6871]: Creating command socket /var/run/lttng/kconsumerd/command (in consumer_thread_sessiond_poll() at consumer.c:2891) DEBUG3 [6866/6870]: Created hashtable size 4 at 0x7f570c0008e0 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG1 [6866/6870]: Updating poll fd array (in update_poll_array() at consumer.c:957) DEBUG1 [6866/6870]: polling on 1 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG3 [6866/6868]: Created hashtable size 4 at 0x7f57040008e0 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG1 [6866/6871]: Sending ready command to lttng-sessiond (in consumer_thread_sessiond_poll() at consumer.c:2904) DEBUG3 [6866/6869]: Created hashtable size 4 at 0x7f57080008e0 of type 2 (in lttng_ht_new() at hashtable.c:112) DEBUG1 [6866/6869]: Thread metadata poll started (in consumer_thread_metadata_poll() at consumer.c:2157) DEBUG1 [6866/6869]: Metadata main loop started (in consumer_thread_metadata_poll() at consumer.c:2173) DEBUG1 [6866/6869]: Metadata poll wait with 1 fd(s) (in consumer_thread_metadata_poll() at consumer.c:2182) DEBUG1 [6866/6868]: Thread channel poll started (in consumer_thread_channel_poll() at consumer.c:2661) DEBUG1 [6238/6867]: Consumer command socket ready (fd: 31 (in thread_manage_consumer() at main.c:1042) DEBUG1 [6866/6868]: Channel main loop started (in consumer_thread_channel_poll() at consumer.c:2676) DEBUG1 [6238/6867]: Consumer metadata socket ready (fd: 32) (in thread_manage_consumer() at main.c:1044) DEBUG1 [6866/6868]: Channel poll wait with 1 fd(s) (in consumer_thread_channel_poll() at consumer.c:2685) DEBUG1 [6866/6871]: Connection on client_socket (in consumer_thread_sessiond_poll() at consumer.c:2921) DEBUG3 [6238/6374]: Consumer socket created (fd: 31) and added to output (in consumer_create_socket() at consumer.c:298) DEBUG1 [6238/6374]: Setting relayd for session ksession (in cmd_setup_relayd() at cmd.c:665) DEBUG1 [6238/6374]: Enabling all events (in process_client_msg() at main.c:2918) DEBUG1 [6866/6871]: Metadata connection on client_socket (in set_metadata_socket() at consumer.c:2860) DEBUG1 [6238/6374]: Trying to find channel channel0 (in trace_kernel_get_channel_by_name() at trace-kernel.c:48) DEBUG1 [6238/6374]: Enabling channel for session ksession (in cmd_enable_channel() at cmd.c:849) DEBUG1 [6238/6374]: Trying to find channel channel0 (in trace_kernel_get_channel_by_name() at trace-kernel.c:48) DEBUG3 [6238/6374]: Kernel create channel with attr: 0, 262144, 4, 0, 200000, 0 (in kernel_create_channel() at kernel.c:143) DEBUG1 [6238/6374]: Kernel channel channel0 created (fd: 33) (in kernel_create_channel() at kernel.c:165) DEBUG1 [6238/6374]: Kernel quiescent wait on 6 (in kernel_wait_quiescent() at kernel.c:429) DEBUG1 [6238/6379]: Updating kernel poll set (in update_kernel_poll() at main.c:616) DEBUG1 [6238/6374]: Trying to find channel channel0 (in trace_kernel_get_channel_by_name() at trace-kernel.c:48) DEBUG1 [6238/6374]: Found channel by name channel0 (in trace_kernel_get_channel_by_name() at trace-kernel.c:52) DEBUG1 [6238/6374]: Reallocating event list from 64 to 128 bytes (in kernel_list_events() at kernel.c:642) DEBUG1 [6238/6374]: Reallocating event list from 128 to 256 bytes (in kernel_list_events() at kernel.c:642) DEBUG1 [6238/6374]: Kernel list events done (233 events) (in kernel_list_events() at kernel.c:663) DEBUG1 [6238/6374]: Event writeback_nothread created (fd: 34) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_queue created (fd: 35) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_exec created (fd: 36) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_start created (fd: 37) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_written created (fd: 38) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_wait created (fd: 39) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_pages_written created (fd: 40) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_nowork created (fd: 41) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_wake_background created (fd: 42) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_wake_thread created (fd: 43) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_wake_forker_thread created (fd: 44) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_bdi_register created (fd: 45) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_bdi_unregister created (fd: 46) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_thread_start created (fd: 47) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_thread_stop created (fd: 48) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_wbc_writepage created (fd: 49) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_queue_io created (fd: 50) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_global_dirty_state created (fd: 51) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_bdi_dirty_ratelimit created (fd: 52) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_balance_dirty_pages created (fd: 53) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_sb_inodes_requeue created (fd: 54) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_congestion_wait created (fd: 55) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_wait_iff_congested created (fd: 56) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event writeback_single_inode created (fd: 57) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event workqueue_queue_work created (fd: 58) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event workqueue_activate_work created (fd: 59) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event workqueue_execute_start created (fd: 60) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event workqueue_execute_end created (fd: 61) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event udp_fail_queue_rcv_skb created (fd: 62) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event timer_init created (fd: 63) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event timer_start created (fd: 64) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event timer_expire_entry created (fd: 65) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event timer_expire_exit created (fd: 66) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event timer_cancel created (fd: 67) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event hrtimer_init created (fd: 68) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event hrtimer_start created (fd: 69) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event hrtimer_expire_entry created (fd: 70) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event hrtimer_expire_exit created (fd: 71) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event hrtimer_cancel created (fd: 72) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event itimer_state created (fd: 73) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event itimer_expire created (fd: 74) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event lttng_statedump_start created (fd: 75) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event lttng_statedump_end created (fd: 76) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event lttng_statedump_process_state created (fd: 77) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event lttng_statedump_file_descriptor created (fd: 78) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event lttng_statedump_vm_map created (fd: 79) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event lttng_statedump_network_interface created (fd: 80) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event lttng_statedump_interrupt created (fd: 81) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sock_rcvqueue_full created (fd: 82) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sock_exceed_buf_limit created (fd: 83) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event skb_kfree created (fd: 84) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event skb_consume created (fd: 85) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event skb_copy_datagram_iovec created (fd: 86) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event signal_generate created (fd: 87) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event signal_deliver created (fd: 88) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event scsi_dispatch_cmd_start created (fd: 89) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event scsi_dispatch_cmd_error created (fd: 90) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event scsi_dispatch_cmd_done created (fd: 91) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event scsi_dispatch_cmd_timeout created (fd: 92) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event scsi_eh_wakeup created (fd: 93) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_kthread_stop created (fd: 94) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_kthread_stop_ret created (fd: 95) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_wakeup created (fd: 96) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_wakeup_new created (fd: 97) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_switch created (fd: 98) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_migrate_task created (fd: 99) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_process_free created (fd: 100) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_process_exit created (fd: 101) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_wait_task created (fd: 102) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_process_wait created (fd: 103) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_process_fork created (fd: 104) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_process_exec created (fd: 105) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_stat_wait created (fd: 106) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_stat_sleep created (fd: 107) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_stat_iowait created (fd: 108) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_stat_blocked created (fd: 109) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_stat_runtime created (fd: 110) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event sched_pi_setprio created (fd: 111) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event rcu_utilization created (fd: 112) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event random_mix_pool_bytes created (fd: 113) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event random_mix_pool_bytes_nolock created (fd: 114) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event random_credit_entropy_bits created (fd: 115) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event random_get_random_bytes created (fd: 116) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event random_extract_entropy created (fd: 117) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event random_extract_entropy_user created (fd: 118) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event console created (fd: 119) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_cpu_idle created (fd: 120) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_cpu_frequency created (fd: 121) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_machine_suspend created (fd: 122) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_wakeup_source_activate created (fd: 123) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_wakeup_source_deactivate created (fd: 124) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_clock_enable created (fd: 125) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_clock_disable created (fd: 126) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_clock_set_rate created (fd: 127) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event power_domain_target created (fd: 128) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event net_dev_xmit created (fd: 129) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event net_dev_queue created (fd: 130) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event netif_receive_skb created (fd: 131) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event netif_rx created (fd: 132) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event napi_poll created (fd: 133) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event module_load created (fd: 134) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event module_free created (fd: 135) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event module_get created (fd: 136) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event module_put created (fd: 137) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event module_request created (fd: 138) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event kmem_kmalloc created (fd: 139) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event kmem_cache_alloc created (fd: 140) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event kmem_kmalloc_node created (fd: 141) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event kmem_cache_alloc_node created (fd: 142) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event kmem_kfree created (fd: 143) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event kmem_cache_free created (fd: 144) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_page_free created (fd: 145) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_page_free_batched created (fd: 146) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_page_alloc created (fd: 147) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_page_alloc_zone_locked created (fd: 148) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_page_pcpu_drain created (fd: 149) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_page_alloc_extfrag created (fd: 150) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_checkpoint created (fd: 151) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_start_commit created (fd: 152) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_commit_locking created (fd: 153) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_commit_flushing created (fd: 154) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_commit_logging created (fd: 155) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_drop_transaction created (fd: 156) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_end_commit created (fd: 157) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_submit_inode_data created (fd: 158) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_run_stats created (fd: 159) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_checkpoint_stats created (fd: 160) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_update_log_tail created (fd: 161) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event jbd2_write_superblock created (fd: 162) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event irq_handler_entry created (fd: 163) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event irq_handler_exit created (fd: 164) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event softirq_entry created (fd: 165) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event softirq_exit created (fd: 166) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event softirq_raise created (fd: 167) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_free_inode created (fd: 168) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_request_inode created (fd: 169) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_allocate_inode created (fd: 170) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_evict_inode created (fd: 171) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_drop_inode created (fd: 172) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mark_inode_dirty created (fd: 173) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_begin_ordered_truncate created (fd: 174) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_write_begin created (fd: 175) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_write_begin created (fd: 176) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ordered_write_end created (fd: 177) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_writeback_write_end created (fd: 178) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_journalled_write_end created (fd: 179) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_write_end created (fd: 180) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_writepages created (fd: 181) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_write_pages created (fd: 182) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_writepages_result created (fd: 183) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_writepage created (fd: 184) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_readpage created (fd: 185) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_releasepage created (fd: 186) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_invalidatepage created (fd: 187) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_discard_blocks created (fd: 188) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mb_new_inode_pa created (fd: 189) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mb_new_group_pa created (fd: 190) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mb_release_inode_pa created (fd: 191) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mb_release_group_pa created (fd: 192) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_discard_preallocations created (fd: 193) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mb_discard_preallocations created (fd: 194) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_request_blocks created (fd: 195) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_allocate_blocks created (fd: 196) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_free_blocks created (fd: 197) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_sync_file_enter created (fd: 198) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_sync_file_exit created (fd: 199) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_sync_fs created (fd: 200) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_alloc_da_blocks created (fd: 201) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mballoc_alloc created (fd: 202) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mballoc_prealloc created (fd: 203) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mballoc_discard created (fd: 204) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mballoc_free created (fd: 205) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_forget created (fd: 206) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_update_reserve_space created (fd: 207) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_reserve_space created (fd: 208) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_da_release_space created (fd: 209) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mb_bitmap_load created (fd: 210) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_mb_buddy_bitmap_load created (fd: 211) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_read_block_bitmap_load created (fd: 212) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_load_inode_bitmap created (fd: 213) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_direct_IO_enter created (fd: 214) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_direct_IO_exit created (fd: 215) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_fallocate_enter created (fd: 216) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_fallocate_exit created (fd: 217) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_unlink_enter created (fd: 218) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_unlink_exit created (fd: 219) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_truncate_enter created (fd: 220) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_truncate_exit created (fd: 221) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_convert_to_initialized_enter created (fd: 222) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_convert_to_initialized_fastpath created (fd: 223) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_map_blocks_enter created (fd: 224) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ind_map_blocks_enter created (fd: 225) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_map_blocks_exit created (fd: 226) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ind_map_blocks_exit created (fd: 227) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_load_extent created (fd: 228) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_load_inode created (fd: 229) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_journal_start created (fd: 230) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_trim_extent created (fd: 231) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_trim_all_free created (fd: 232) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_handle_uninitialized_extents created (fd: 233) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_get_implied_cluster_alloc_exit created (fd: 234) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_put_in_cache created (fd: 235) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_in_cache created (fd: 236) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_find_delalloc_range created (fd: 237) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_get_reserved_cluster_alloc created (fd: 238) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_show_extent created (fd: 239) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_remove_blocks created (fd: 240) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_rm_leaf created (fd: 241) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_rm_idx created (fd: 242) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_remove_space created (fd: 243) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event ext4_ext_remove_space_done created (fd: 244) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_compaction_isolate_migratepages created (fd: 245) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_compaction_isolate_freepages created (fd: 246) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event mm_compaction_migratepages created (fd: 247) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_touch_buffer created (fd: 248) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_dirty_buffer created (fd: 249) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_rq_abort created (fd: 250) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_rq_requeue created (fd: 251) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_rq_complete created (fd: 252) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_rq_insert created (fd: 253) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_rq_issue created (fd: 254) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_bio_bounce created (fd: 255) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_bio_complete created (fd: 256) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_bio_backmerge created (fd: 257) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_bio_frontmerge created (fd: 258) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_bio_queue created (fd: 259) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_getrq created (fd: 260) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_sleeprq created (fd: 261) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_plug created (fd: 262) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_unplug created (fd: 263) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_split created (fd: 264) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_bio_remap created (fd: 265) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Event block_rq_remap created (fd: 266) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Enabling all syscall tracing (in event_kernel_enable_all_syscalls() at event.c:259) DEBUG2 [6238/6374]: Kernel event syscall creation success (in kernel_create_event() at kernel.c:215) DEBUG1 [6238/6374]: Event created (fd: -1) (in kernel_create_event() at kernel.c:236) DEBUG1 [6238/6374]: Kernel quiescent wait on 6 (in kernel_wait_quiescent() at kernel.c:429) DEBUG1 [6238/6379]: Channel fd 33 added to kernel set (in update_kernel_poll() at main.c:634) DEBUG1 [6238/6379]: Thread kernel polling on 3 fds (in thread_manage_kernel() at main.c:801) DEBUG1 [6238/6379]: Updating kernel streams for channel fd 33 (in update_kernel_stream() at main.c:660) DEBUG1 [6238/6379]: Channel found, updating kernel streams (in update_kernel_stream() at main.c:673) DEBUG1 [6238/6379]: Kernel stream channel0_0 created (fd: 267, state: 0) (in kernel_open_channel_stream() at kernel.c:562) DEBUG1 [6238/6379]: Kernel stream channel0_1 created (fd: 268, state: 0) (in kernel_open_channel_stream() at kernel.c:562) DEBUG1 [6238/6379]: Thread kernel polling on 3 fds (in thread_manage_kernel() at main.c:801) DEBUG1 [6238/6374]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Processing client command 16 (in process_client_msg() at main.c:2578) DEBUG1 [6238/6374]: Getting session ksession by name (in process_client_msg() at main.c:2655) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG1 [6238/6374]: Kernel metadata opened (fd: 269) (in kernel_open_metadata() at kernel.c:389) DEBUG1 [6238/6374]: Kernel metadata stream created (fd: 270) (in kernel_open_metadata_stream() at kernel.c:586) DEBUG1 [6238/6374]: Sending session stream to kernel consumer (in kernel_consumer_send_session() at kernel-consumer.c:337) DEBUG1 [6238/6374]: Sending metadata 270 to kernel consumer (in kernel_consumer_add_metadata() at kernel-consumer.c:157) DEBUG3 [6238/6374]: mkdir() recursive /root/lttng-traces/ksession-20131224-122721/kernel with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:310) DEBUG1 [6238/6374]: Using run_as_clone (in run_as() at runas.c:293) DEBUG3 [6238/6374]: Kernel local consumer tracefile path: /root/lttng-traces/ksession-20131224-122721/kernel (in create_channel_path() at kernel-consumer.c:60) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG1 [6866/6871]: consumer_add_channel 269 (in lttng_kconsumer_recv_cmd() at kernel-consumer.c:457) DEBUG1 [6866/6871]: Allocated channel (key 269) (in consumer_allocate_channel() at consumer.c:889) DEBUG1 [6866/6871]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2972) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG3 [6866/6871]: Allocated stream metadata (key 18, chan_key 269 relayd_id 18446744073709551615, session_id 0 (in consumer_allocate_stream() at consumer.c:538) DEBUG3 [6866/6871]: open() /root/lttng-traces/ksession-20131224-122721/kernel/metadata with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:338) DEBUG1 [6866/6871]: Using run_as_clone (in run_as() at runas.c:293) DEBUG1 [6238/6374]: Sending streams of channel channel0 to kernel consumer (in kernel_consumer_send_channel_stream() at kernel-consumer.c:287) DEBUG1 [6238/6374]: Kernel consumer adding channel channel0 to kernel consumer (in kernel_consumer_add_channel() at kernel-consumer.c:98) DEBUG3 [6238/6374]: mkdir() recursive /root/lttng-traces/ksession-20131224-122721/kernel with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:310) DEBUG1 [6238/6374]: Using run_as_clone (in run_as() at runas.c:293) DEBUG3 [6866/6871]: Adding metadata stream 18 to hash table (in consumer_add_metadata_stream() at consumer.c:2025) DEBUG1 [6866/6871]: Kernel consumer ADD_STREAM metadata (fd: 18) with relayd id 0 (in lttng_kconsumer_recv_cmd() at kernel-consumer.c:699) DEBUG1 [6866/6871]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2972) DEBUG1 [6866/6869]: Metadata event catched in thread (in consumer_thread_metadata_poll() at consumer.c:2184) DEBUG1 [6866/6869]: Adding metadata stream 18 to poll set (in consumer_thread_metadata_poll() at consumer.c:2232) DEBUG1 [6866/6869]: Metadata poll wait with 2 fd(s) (in consumer_thread_metadata_poll() at consumer.c:2182) DEBUG3 [6238/6374]: Kernel local consumer tracefile path: /root/lttng-traces/ksession-20131224-122721/kernel (in create_channel_path() at kernel-consumer.c:60) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG1 [6866/6871]: consumer_add_channel 33 (in lttng_kconsumer_recv_cmd() at kernel-consumer.c:457) DEBUG1 [6866/6871]: Allocated channel (key 33) (in consumer_allocate_channel() at consumer.c:889) DEBUG1 [6866/6871]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2972) DEBUG1 [6238/6374]: Sending stream 268 of channel channel0 to kernel consumer (in kernel_consumer_add_stream() at kernel-consumer.c:238) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG3 [6866/6871]: Allocated stream channel0_1 (key 22, chan_key 33 relayd_id 18446744073709551615, session_id 0 (in consumer_allocate_stream() at consumer.c:538) DEBUG3 [6866/6871]: open() /root/lttng-traces/ksession-20131224-122721/kernel/channel0_1 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:338) DEBUG1 [6866/6871]: Using run_as_clone (in run_as() at runas.c:293) DEBUG1 [6238/6374]: Sending stream 267 of channel channel0 to kernel consumer (in kernel_consumer_add_stream() at kernel-consumer.c:238) DEBUG3 [6866/6871]: Adding consumer stream 22 (in consumer_add_data_stream() at consumer.c:564) DEBUG1 [6866/6871]: Kernel consumer ADD_STREAM channel0_1 (fd: 22) with relayd id 0 (in lttng_kconsumer_recv_cmd() at kernel-consumer.c:699) DEBUG1 [6866/6871]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2972) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG1 [6866/6870]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2428) DEBUG1 [6866/6870]: Updating poll fd array (in update_poll_array() at consumer.c:957) DEBUG1 [6866/6870]: polling on 2 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG3 [6866/6871]: Allocated stream channel0_0 (key 23, chan_key 33 relayd_id 18446744073709551615, session_id 0 (in consumer_allocate_stream() at consumer.c:538) DEBUG3 [6866/6871]: open() /root/lttng-traces/ksession-20131224-122721/kernel/channel0_0 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:338) DEBUG1 [6866/6871]: Using run_as_clone (in run_as() at runas.c:293) DEBUG1 [6238/6374]: Kernel consumer FDs of metadata and channel streams sent (in kernel_consumer_send_session() at kernel-consumer.c:358) DEBUG1 [6866/6869]: Metadata event catched in thread (in consumer_thread_metadata_poll() at consumer.c:2184) DEBUG1 [6866/6869]: Metadata available on fd 18 (in consumer_thread_metadata_poll() at consumer.c:2284) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG3 [6866/6871]: Adding consumer stream 23 (in consumer_add_data_stream() at consumer.c:564) DEBUG1 [6866/6871]: Kernel consumer ADD_STREAM channel0_0 (fd: 23) with relayd id 0 (in lttng_kconsumer_recv_cmd() at kernel-consumer.c:699) DEBUG1 [6866/6871]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2972) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2428) DEBUG1 [6866/6870]: Updating poll fd array (in update_poll_array() at consumer.c:957) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6238/6374]: Kernel session started (in kernel_start_session() at kernel.c:414) DEBUG1 [6238/6374]: Kernel quiescent wait on 6 (in kernel_wait_quiescent() at kernel.c:429) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6238/6374]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: splice chan to pipe offset 0 of len 4096 (fd : 18, pipe: 15) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6869]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6869]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: In read_subbuffer (infd : 18) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6869]: Reserving sub buffer failed (everything is normal, it is due to concurrency) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:868) DEBUG1 [6866/6869]: Metadata poll wait with 2 fd(s) (in consumer_thread_metadata_poll() at consumer.c:2182) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 22 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 22) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 22 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 22) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 22 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 22) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 22 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 22) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 262144 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 196608 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 131072 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 196608 of len 65536 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Processing client command 17 (in process_client_msg() at main.c:2578) DEBUG1 [6238/6374]: Getting session ksession by name (in process_client_msg() at main.c:2655) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG1 [6238/6374]: Stop kernel tracing (in cmd_stop_trace() at cmd.c:1655) DEBUG1 [6238/6374]: Kernel flushing metadata buffer on fd 270 (in kernel_metadata_flush_buffer() at kernel.c:464) DEBUG1 [6238/6374]: Flush buffer for channel channel0 (in kernel_flush_buffer() at kernel.c:484) DEBUG1 [6238/6374]: Flushing channel stream 268 (in kernel_flush_buffer() at kernel.c:487) DEBUG1 [6238/6374]: Flushing channel stream 267 (in kernel_flush_buffer() at kernel.c:487) DEBUG1 [6238/6374]: Kernel session stopped (in kernel_stop_session() at kernel.c:513) DEBUG1 [6238/6374]: Kernel quiescent wait on 6 (in kernel_wait_quiescent() at kernel.c:429) DEBUG1 [6238/6374]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Processing client command 24 (in process_client_msg() at main.c:2578) DEBUG1 [6238/6374]: Getting session ksession by name (in process_client_msg() at main.c:2655) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG3 [6238/6374]: Consumer data pending for id 0 (in consumer_is_data_pending() at consumer.c:1053) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG1 [6866/6871]: Kernel consumer data pending command for id 0 (in lttng_kconsumer_recv_cmd() at kernel-consumer.c:748) DEBUG1 [6866/6871]: Consumer data pending command on session id 0 (in consumer_data_pending() at consumer.c:3349) DEBUG1 [6866/6871]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2972) DEBUG1 [6238/6374]: Consumer data is pending for session id 0 (in consumer_is_data_pending() at consumer.c:1085) DEBUG1 [6238/6374]: Sending response (size: 16, retcode: Unknown error code) (in thread_manage_clients() at main.c:3803) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6866/6870]: poll num_rdy : 2 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 184320 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 65536 of len 118784 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 65536 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: splice chan to pipe offset 131072 of len 53248 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 53248 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 53248 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: Normal read on fd 22 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 22) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 57344 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 57344 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 57344 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Processing client command 24 (in process_client_msg() at main.c:2578) DEBUG1 [6238/6374]: Getting session ksession by name (in process_client_msg() at main.c:2655) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG3 [6238/6374]: Consumer data pending for id 0 (in consumer_is_data_pending() at consumer.c:1053) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG1 [6866/6871]: Kernel consumer data pending command for id 0 (in lttng_kconsumer_recv_cmd() at kernel-consumer.c:748) DEBUG1 [6866/6871]: Consumer data pending command on session id 0 (in consumer_data_pending() at consumer.c:3349) DEBUG1 [6866/6871]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2972) DEBUG1 [6238/6374]: Consumer data is NOT pending for session id 0 (in consumer_is_data_pending() at consumer.c:1085) DEBUG1 [6238/6374]: Sending response (size: 16, retcode: Unknown error code) (in thread_manage_clients() at main.c:3803) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6238/6374]: Wait for client response (in thread_manage_clients() at main.c:3709) DEBUG1 [6238/6374]: Receiving data from client ... (in thread_manage_clients() at main.c:3754) DEBUG1 [6238/6374]: Processing client command 9 (in process_client_msg() at main.c:2578) DEBUG1 [6238/6374]: Getting session ksession by name (in process_client_msg() at main.c:2655) DEBUG2 [6238/6374]: Trying to find session by name ksession (in session_find_by_name() at session.c:133) DEBUG1 [6238/6374]: Tearing down kernel session (in kernel_destroy_session() at kernel.c:755) DEBUG1 [6238/6374]: [trace] Closing session fd 28 (in trace_kernel_destroy_session() at trace-kernel.c:450) DEBUG1 [6238/6374]: [trace] Closing metadata stream fd 270 (in trace_kernel_destroy_session() at trace-kernel.c:460) DEBUG1 [6238/6374]: [trace] Closing metadata fd 269 (in trace_kernel_destroy_metadata() at trace-kernel.c:423) DEBUG1 [6238/6374]: [trace] Closing channel fd 33 (in trace_kernel_destroy_channel() at trace-kernel.c:389) DEBUG1 [6238/6374]: [trace] Closing stream fd 268 (in trace_kernel_destroy_stream() at trace-kernel.c:335) DEBUG1 [6238/6374]: [trace] Closing stream fd 267 (in trace_kernel_destroy_stream() at trace-kernel.c:335) DEBUG1 [6238/6374]: [trace] Tearing down event (no associated fd) (in trace_kernel_destroy_event() at trace-kernel.c:368) DEBUG1 [6238/6374]: [trace] Closing event fd 266 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 265 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 264 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 263 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 262 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 261 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 260 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 259 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 258 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 257 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 256 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 255 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 254 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 253 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 252 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 251 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 250 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 249 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 248 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 247 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 246 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 245 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 244 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 243 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 242 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 241 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 240 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 239 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 238 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 237 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 236 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 235 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 234 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 233 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 232 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 231 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 230 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 229 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 228 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 227 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 226 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 225 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 224 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 223 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 222 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 221 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 220 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 219 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 218 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 217 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 216 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 215 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 214 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 213 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 212 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 211 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 210 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 209 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 208 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 207 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 206 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 205 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 204 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 203 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 202 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 201 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 200 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 199 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 198 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 197 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 196 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 195 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 194 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 193 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 192 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 191 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 190 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 189 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 188 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 187 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 186 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 185 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 184 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 183 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 182 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 181 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 180 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 179 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 178 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 177 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 176 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 175 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 174 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 173 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 172 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 171 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 170 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 169 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 168 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 167 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 166 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 165 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 164 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 163 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 162 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 161 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 160 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 159 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 158 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 157 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 156 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 155 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 154 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 153 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 152 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 151 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 150 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 149 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 148 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 147 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 146 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 145 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 144 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 143 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 142 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 141 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 140 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 139 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 138 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 137 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 136 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 135 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 134 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 133 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 132 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 131 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 130 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 129 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 128 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 127 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 126 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 125 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 124 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 123 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 122 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 121 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 120 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 119 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 118 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 117 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 116 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 115 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 114 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 113 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 112 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 111 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 110 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 109 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 108 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 107 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 106 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 105 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 104 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 103 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 102 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 101 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 100 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 99 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 98 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 97 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 96 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 95 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 94 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 93 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 92 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 91 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 90 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 89 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 88 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 87 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 86 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 85 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 84 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 83 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 82 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 81 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 80 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 79 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 78 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 77 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 76 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 75 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 74 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 73 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 72 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 71 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 70 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 69 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 68 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 67 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 66 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 65 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 64 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 63 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 62 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 61 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 60 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 59 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 58 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 57 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 56 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 55 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 54 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 53 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 52 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 51 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 50 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 49 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 48 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 47 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 46 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 45 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 44 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 43 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 42 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 41 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 40 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 39 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 38 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 37 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 36 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 35 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6238/6374]: [trace] Closing event fd 34 (in trace_kernel_destroy_event() at trace-kernel.c:361) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 23 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 23) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 4096 (fd : 23, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG1 [6866/6869]: Metadata event catched in thread (in consumer_thread_metadata_poll() at consumer.c:2184) DEBUG1 [6866/6869]: Metadata fd 18 is hup|err. (in consumer_thread_metadata_poll() at consumer.c:2257) DEBUG3 [6866/6869]: Consumer delete metadata stream 18 (in consumer_del_metadata_stream() at consumer.c:1891) DEBUG1 [6866/6869]: Consumer delete channel key 269 (in consumer_del_channel() at consumer.c:291) DEBUG1 [6866/6869]: Metadata poll wait with 1 fd(s) (in consumer_thread_metadata_poll() at consumer.c:2182) DEBUG1 [6866/6870]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 2 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Normal read on fd 22 (in consumer_thread_data_poll() at consumer.c:2486) DEBUG1 [6866/6870]: In read_subbuffer (infd : 22) (in lttng_kconsumer_read_subbuffer() at kernel-consumer.c:857) DEBUG1 [6866/6870]: splice chan to pipe offset 0 of len 4096 (fd : 22, pipe: 9) (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1629) DEBUG1 [6866/6870]: splice chan to pipe, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1632) DEBUG1 [6866/6870]: Consumer splice pipe to file, ret 4096 (in lttng_consumer_on_read_subbuffer_splice() at consumer.c:1662) DEBUG3 [6238/6372]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58) DEBUG1 [6238/6379]: Updating kernel poll set (in update_kernel_poll() at main.c:616) DEBUG1 [6238/6374]: Destroying session ksession (in session_destroy() at session.c:158) DEBUG3 [6238/6372]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58) DEBUG1 [6238/6379]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:801) DEBUG1 [6866/6870]: Polling fd 23 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2520) DEBUG1 [6238/6374]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803) DEBUG1 [6866/6870]: Consumer stream destroy monitored key: 23 (in destroy_close_stream() at consumer-stream.c:230) DEBUG1 [6866/6870]: Updating poll fd array (in update_poll_array() at consumer.c:957) DEBUG1 [6866/6870]: polling on 2 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: Polling fd 22 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2520) DEBUG1 [6866/6870]: Consumer stream destroy monitored key: 22 (in destroy_close_stream() at consumer-stream.c:230) DEBUG1 [6866/6870]: Consumer delete channel key 33 (in consumer_del_channel() at consumer.c:291) DEBUG1 [6866/6870]: Updating poll fd array (in update_poll_array() at consumer.c:957) DEBUG1 [6866/6870]: polling on 1 fd (in consumer_thread_data_poll() at consumer.c:2402) DEBUG1 [6238/6374]: Clean command context structure (in clean_command_ctx() at main.c:535) DEBUG1 [6238/6374]: Accepting client command ... (in thread_manage_clients() at main.c:3667) DEBUG1 [6866/6871]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2954) DEBUG1 [6866/6871]: Communication interrupted on command socket (in consumer_thread_sessiond_poll() at consumer.c:2965) DEBUG1 [6866/6871]: Consumer thread sessiond poll exiting (in consumer_thread_sessiond_poll() at consumer.c:2975) Killed DEBUG1 [6866/6870]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2404) DEBUG1 [6866/6870]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2428) DEBUG1 [6866/6870]: Consumer delete flagged data stream (in validate_endpoint_status_data_stream() at consumer.c:2089) DEBUG1 [6866/6870]: polling thread exiting (in consumer_thread_data_poll() at consumer.c:2547) DEBUG1 [6866/6868]: Channel event catched in thread (in consumer_thread_channel_poll() at consumer.c:2687) DEBUG1 [6866/6868]: Channel poll thread exiting (in consumer_thread_channel_poll() at consumer.c:2843) DEBUG1 [6866/6869]: Metadata event catched in thread (in consumer_thread_metadata_poll() at consumer.c:2184) DEBUG1 [6866/6869]: Metadata thread pipe hung up (in consumer_thread_metadata_poll() at consumer.c:2202) DEBUG1 [6866/6869]: Metadata poll thread exiting (in consumer_thread_metadata_poll() at consumer.c:2312) PERROR [6866/6866]: sendmsg: Broken pipe (in lttcomm_send_unix_sock() at unix.c:218) DEBUG1 [6866/6866]: Consumer destroying it. Closing everything. (in lttng_consumer_destroy() at consumer.c:1245)