[lttng-dev] User Space Events Not creating Logs

Mon Jul 28 01:36:48 EDT 2014

Hi All,

        I am using the lttng (LTTng Trace Control) 2.3.1 - Dominus
Vobiscum.  I had attached the log file with lttng-sessiond and sample user
space tracing applivcation with full verbosity option.

       The User space tracing events logs were not available. Same thing
when i tried in ubuntu, there i am able to see some message communications
which was not present in my linux machine having 3.10 kernel.

       I attached the log file for ur reference..

Thanks & Regards,
Rajivgandhi
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.lttng.org/pipermail/lttng-dev/attachments/20140728/90ec02f5/attachment-0001.html>
-------------- next part --------------
root@/ # lttng-sessiond -vvv
DEBUG3 [1157/1157]: Creating LTTng run directory: /var/run/lttng (in create_lttng_rundir() at main.c:4170)
DEBUG2 [1157/1157]: Kernel consumer err path: /var/run/lttng/kconsumerd/error (in main() at main.c:4467)
DEBUG2 [1157/1157]: Kernel consumer cmd path: /var/run/lttng/kconsumerd/command (in main() at main.c:4469)
DEBUG1 [1157/1157]: Client socket path /var/run/lttng/client-lttng-sessiond (in main() at main.c:4522)
DEBUG1 [1157/1157]: Application socket path /var/run/lttng/lttng-ust-sock-5 (in main() at main.c:4523)
DEBUG1 [1157/1157]: Application wait path /lttng-ust-wait-5 (in main() at main.c:4524)
DEBUG1 [1157/1157]: LTTng run directory path: /var/run/lttng (in main() at main.c:4525)
DEBUG2 [1157/1157]: UST consumer 32 bits err path: /var/run/lttng/ustconsumerd32/error (in main() at main.c:4534)
DEBUG2 [1157/1157]: UST consumer 32 bits cmd path: /var/run/lttng/ustconsumerd32/command (in main() at main.c:4536)
DEBUG2 [1157/1157]: UST consumer 64 bits err path: /var/run/lttng/ustconsumerd64/error (in main() at main.c:4545)
DEBUG2 [1157/1157]: UST consumer 64 bits cmd path: /var/run/lttng/ustconsumerd64/command (in main() at main.c:4547)
DEBUG3 [1157/1157]: Created hashtable size 4 at 0x139b0a0 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [1157/1157]: Created hashtable size 4 at 0x139b5c0 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [1157/1157]: Created hashtable size 4 at 0x139bb00 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [1157/1157]: Creating consumer directory: /var/run/lttng/kconsumerd (in set_consumer_sockets() at main.c:4212)
DEBUG1 [1157/1157]: Modprobe successfully lttng-tracer (in modprobe_lttng_control() at modprobe.c:192)
DEBUG2 [1157/1157]: Kernel tracer version validated (major version 2) (in kernel_validate_version() at kernel.c:700)
modprobe: module 'lttng_ftrace' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-ftrace (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-kprobes (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-kretprobes (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-lib-ring-buffer (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-ring-buffer-client-discard (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-ring-buffer-client-overwrite (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-ring-buffer-metadata-client (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-ring-buffer-client-mmap-discard (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-ring-buffer-client-mmap-overwrite (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-ring-buffer-metadata-mmap-client (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-types (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_asoc' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-asoc (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_block' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-block (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_btrfs' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-btrfs (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-compaction (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_ext3' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-ext3 (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-ext4 (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_gpio' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-gpio (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-irq (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_jbd' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-jbd (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-jbd2 (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-kmem (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_kvm' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-kvm (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_kvm_x86' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-kvm-x86 (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_kvm_x86_mmu' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-kvm-x86-mmu (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_lock' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-lock (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-module (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-napi (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-net (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-power (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-printk (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-random (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-rcu (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_regmap' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-regmap (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_regulator' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-regulator (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_rpm' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-rpm (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-sched (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-scsi (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-signal (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-skb (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-sock (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-statedump (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_sunrpc' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-sunrpc (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-timer (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-udp (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_vmscan' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-vmscan (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-workqueue (in modprobe_lttng_data() at modprobe.c:228)
modprobe: module 'lttng_probe_writeback' not found
DEBUG1 [1157/1157]: Modprobe successfully lttng-probe-writeback (in modprobe_lttng_data() at modprobe.c:228)
DEBUG1 [1157/1157]: Kernel tracer fd 6 (in init_kernel_tracer() at main.c:2342)
DEBUG2 [1157/1157]: Creating consumer directory: /var/run/lttng/ustconsumerd64 (in set_consumer_sockets() at main.c:4212)
DEBUG2 [1157/1157]: Creating consumer directory: /var/run/lttng/ustconsumerd32 (in set_consumer_sockets() at main.c:4212)
DEBUG1 [1157/1157]: Signal handler set for SIGTERM, SIGPIPE and SIGINT (in set_signal_handler() at main.c:4314)
DEBUG3 [1157/1157]: Session daemon client socket 9 and application socket 10 created (in init_daemon_socket() at main.c:4075)
DEBUG1 [1157/1157]: All permissions are set (in set_permissions() at main.c:4158)
DEBUG3 [1157/1157]: Created hashtable size 4 at 0x139e000 of type 2 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [1157/1157]: Global buffer per UID registry initialized (in buffer_reg_init_uid_registry() at buffer-registry.c:99)
DEBUG3 [1157/1157]: Created hashtable size 4 at 0x139e540 of type 2 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [1157/1157]: Global buffer per PID registry initialized (in buffer_reg_init_pid_registry() at buffer-registry.c:220)
DEBUG1 [1157/1157]: epoll set max size is 1337405 (in compat_epoll_set_max_size() at compat-epoll.c:274)
DEBUG1 [1157/1157]: Command subsystem initialized (in cmd_init() at cmd.c:2847)
DEBUG1 [1157/1157]: Pid 1157 written in file /var/run/lttng/lttng-sessiond.pid (in utils_create_pid_file() at utils.c:280)
DEBUG1 [1157/1157]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:523)
DEBUG1 [1157/1157]: Health check time delta in seconds set to 236 (in health_init() at health.c:272)
DEBUG1 [1157/1290]: [ht-thread] startup. (in thread_ht_cleanup() at ht-cleanup.c:34)
DEBUG1 [1157/1291]: [thread] Manage health check started (in thread_manage_health() at main.c:3418)
DEBUG3 [1157/1290]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG1 [1157/1292]: [thread] Manage client started (in thread_manage_clients() at main.c:3621)
DEBUG1 [1157/1291]: Health check ready (in thread_manage_health() at main.c:3460)
DEBUG1 [1157/1293]: [thread] Dispatch UST command started (in thread_dispatch_ust_registration() at main.c:1509)
DEBUG1 [1157/1292]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG1 [1157/1293]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:75)
DEBUG1 [1157/1293]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1532)
DEBUG1 [1157/1295]: [thread] Manage application started (in thread_manage_apps() at main.c:1218)
DEBUG1 [1157/1294]: [thread] Manage application registration started (in thread_registration_apps() at main.c:1732)
DEBUG1 [1157/1295]: Apps thread polling on 2 fds (in thread_manage_apps() at main.c:1248)
DEBUG1 [1157/1294]: Notifying applications of session daemon state: 1 (in notify_ust_apps() at main.c:555)
DEBUG1 [1157/1297]: [thread] Thread manage kernel started (in thread_manage_kernel() at main.c:756)
DEBUG1 [1157/1296]: [ust-thread] Manage application notify command (in ust_thread_manage_notify() at ust-thread.c:37)
DEBUG1 [1157/1297]: Updating kernel poll set (in update_kernel_poll() at main.c:616)
DEBUG1 [1157/1297]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:801)
DEBUG3 [1157/1296]: [ust-thread] Manage notify polling on 2 fds (in ust_thread_manage_notify() at ust-thread.c:61)
DEBUG1 [1157/1294]: Got the wait shm fd 26 (in get_wait_shm() at shm.c:115)
DEBUG1 [1157/1294]: Futex wait update active 1 (in futex_wait_update() at futex.c:63)
DEBUG1 [1157/1294]: Accepting application registration (in thread_registration_apps() at main.c:1769)

root@/ #
root@/ # lttng -vvv create
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [3547/3547]: Auto session name set to auto-20140729-122811 (in create_session() at commands/create.c:250)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG3 [3547/3547]: URI string: file:///root/lttng-traces/auto-20140729-122811 (in uri_parse() at uri.c:291)
DEBUG3 [3547/3547]: URI file destination: /root/lttng-traces/auto-20140729-122811 (in uri_parse() at uri.c:328)
DEBUG3 [3547/3547]: URI dtype: 3, proto: 0, host: , subdir: , ctrl: 0, data: 0 (in uri_parse() at uri.c:505)
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [3547/3547]: LSM cmd type : 8 (in send_session_msg() at lttng-ctl.c:130)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Processing client command 8 (in process_client_msg() at main.c:2578)
DEBUG1 [2177/2312]: Waiting for 1 URIs from client ... (in process_client_msg() at main.c:3073)
DEBUG2 [2177/2312]: Trying to find session by name auto-20140729-122811 (in session_find_by_name() at session.c:133)
DEBUG3 [2177/2312]: Created hashtable size 4 at 0x7f8d80005ea0 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG1 [2177/2312]: Tracing session auto-20140729-122811 created with ID 0 by UID 0 GID 0 (in session_create() at session.c:224)
DEBUG2 [2177/2312]: Trying to find session by name auto-20140729-122811 (in session_find_by_name() at session.c:133)
DEBUG3 [2177/2312]: Created hashtable size 4 at 0x7f8d8000b430 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [2177/2312]: Setting trace directory path from URI to /root/lttng-traces/auto-20140729-122811 (in add_uri_to_consumer() at cmd.c:429)
DEBUG1 [2177/2312]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
Session auto-20140729-122811 created.
Traces will be written in /root/lttng-traces/auto-20140729-122811
DEBUG1 [3547/3547]: Init config session in /root (in config_init() at conf.c:290)

root@/ # lttng -vvv enable-event -u -a
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG2 [3657/3657]: Config file path found: /root (in get_session_name() at utils.c:52)
DEBUG1 [3657/3657]: Session name found: auto-20140729-122811 (in get_session_name() at utils.c:53)
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [3657/3657]: LSM cmd type : 6 (in send_session_msg() at lttng-ctl.c:130)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Processing client command 6 (in process_client_msg() at main.c:2578)
DEBUG1 [2177/2312]: Getting session auto-20140729-122811 by name (in process_client_msg() at main.c:2655)
DEBUG2 [2177/2312]: Trying to find session by name auto-20140729-122811 (in session_find_by_name() at session.c:133)
DEBUG1 [2177/2312]: Creating UST session (in create_ust_session() at main.c:2459)
DEBUG3 [2177/2312]: Created hashtable size 4 at 0x7f8d80003d60 of type 0 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [2177/2312]: Created hashtable size 4 at 0x7f8d800042a0 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [2177/2312]: UST trace session create successful (in trace_ust_create_session() at trace-ust.c:235)
DEBUG3 [2177/2312]: Copying tracing session consumer output in UST session (in copy_session_consumer() at main.c:2409)
DEBUG3 [2177/2312]: Created hashtable size 4 at 0x7f8d800047e0 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [2177/2312]: Copy session consumer subdir /ust (in copy_session_consumer() at main.c:2427)
DEBUG3 [2177/2310]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG1 [2177/2312]: Spawning consumerd (in spawn_consumerd() at main.c:2086)
DEBUG2 [2177/2312]: Consumer pid 3658 (in start_consumerd() at main.c:2269)
DEBUG2 [2177/2312]: Spawning consumer control thread (in start_consumerd() at main.c:2272)
DEBUG1 [2177/3659]: [thread] Manage consumer started (in thread_manage_consumer() at main.c:922)
DEBUG1 [3658/3658]: Using 64-bit UST consumer at: /lib/lttng/libexec/lttng-consumerd (in spawn_consumerd() at main.c:2161)
DEBUG2 [2177/3659]: Receiving code from consumer err_sock (in thread_manage_consumer() at main.c:1007)
DEBUG1 [2177/3659]: Consumer command socket ready (fd: 30 (in thread_manage_consumer() at main.c:1042)
DEBUG1 [2177/3659]: Consumer metadata socket ready (fd: 31) (in thread_manage_consumer() at main.c:1044)
DEBUG3 [2177/2312]: Consumer socket created (fd: 30) and added to output (in consumer_create_socket() at consumer.c:298)
DEBUG1 [2177/2312]: Setting relayd for session auto-20140729-122811 (in cmd_setup_relayd() at cmd.c:665)
DEBUG2 [2177/2312]: Trace UST channel channel0 not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:149)
DEBUG1 [2177/2312]: Enabling channel  for session auto-20140729-122811 (in cmd_enable_channel() at cmd.c:849)
DEBUG2 [2177/2312]: Trace UST channel channel0 not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:149)
DEBUG3 [2177/2312]: Created hashtable size 4 at 0x7f8d80004fb0 of type 0 (in lttng_ht_new() at hashtable.c:112)
DEBUG3 [2177/2312]: Created hashtable size 4 at 0x7f8d80005480 of type 1 (in lttng_ht_new() at hashtable.c:112)
DEBUG2 [2177/2312]: Trace UST channel channel0 created (in trace_ust_create_channel() at trace-ust.c:302)
DEBUG2 [2177/2312]: Channel channel0 is being created for UST with buffer 1 and id 0 (in channel_ust_create() at channel.c:343)
DEBUG2 [2177/2312]: UST app adding channel channel0 to UST domain for session id 0 (in ust_app_create_channel_glb() at ust-app.c:3511)
DEBUG2 [2177/2312]: Channel channel0 created successfully (in channel_ust_create() at channel.c:367)
DEBUG2 [2177/2312]: Trace UST channel channel0 found by name (in trace_ust_find_channel_by_name() at trace-ust.c:144)
DEBUG2 [2177/2312]: Trace UST event * NOT found (in trace_ust_find_event() at trace-ust.c:183)
DEBUG2 [2177/2312]: Trace UST event *, loglevel (0,-1) created (in trace_ust_create_event() at trace-ust.c:374)
DEBUG1 [2177/2312]: UST app creating event * for all apps for session id 0 (in ust_app_create_event_glb() at ust-app.c:3664)
DEBUG1 [2177/2312]: Event UST * created in channel channel0 (in event_ust_enable_tracepoint() at event.c:467)
DEBUG1 [2177/2312]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
All UST events are enabled in channel channel0

root@/ # lttng -vvv start
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG2 [3761/3761]: Config file path found: /root (in get_session_name() at utils.c:52)
DEBUG1 [3761/3761]: Session name found: auto-20140729-122811 (in get_session_name() at utils.c:53)
DEBUG1 [3761/3761]: Starting tracing for session auto-20140729-122811 (in start_tracing() at commands/start.c:81)
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [3761/3761]: LSM cmd type : 16 (in send_session_msg() at lttng-ctl.c:130)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Processing client command 16 (in process_client_msg() at main.c:2578)
DEBUG1 [2177/2312]: Getting session auto-20140729-122811 by name (in process_client_msg() at main.c:2655)
DEBUG2 [2177/2312]: Trying to find session by name auto-20140729-122811 (in session_find_by_name() at session.c:133)
DEBUG1 [2177/2312]: Starting all UST traces (in ust_app_start_trace_all() at ust-app.c:4011)
DEBUG1 [2177/2312]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
Tracing started for session auto-20140729-122811

root@/ #

root@/ # LTTNG_UST_DEBUG=1 LD_PRELOAD=/lib/liblttng-ust.so.0 ./ackermann
libust[3867/3867]: LTT : ltt ring buffer client "relay-metadata-mmap" init
 (in lttng_ring_buffer_metadata_client_init() at lttng-ring-buffer-metadata-client.h:314)
libust[3867/3867]: LTT : ltt ring buffer client "relay-overwrite-mmap" init
 (in lttng_ring_buffer_client_overwrite_init() at lttng-ring-buffer-client.h:563)
libust[3867/3867]: LTT : ltt ring buffer client "relay-overwrite-rt-mmap" init
 (in lttng_ring_buffer_client_overwrite_rt_init() at lttng-ring-buffer-client.h:563)
libust[3867/3867]: LTT : ltt ring buffer client "relay-discard-mmap" init
 (in lttng_ring_buffer_client_discard_init() at lttng-ring-buffer-client.h:563)
libust[3867/3867]: LTT : ltt ring buffer client "relay-discard-rt-mmap" init
 (in lttng_ring_buffer_client_discard_rt_init() at lttng-ring-buffer-client.h:563)
libust[3867/3869]: Info: sessiond not accepting connections to local apps socket (in ust_listener_thread() at lttng-ust-comm.c:988)
libust[3867/3868]: Info: sessiond not accepting connections to global apps socket (in ust_listener_thread() at lttng-ust-comm.c:988)
libust[3867/3869]: Waiting for local apps sessiond (in wait_for_sessiond() at lttng-ust-comm.c:899)
libust[3867/3867]: Provider "usttut" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3867/3867]: adding probe usttut containing 1 events to lazy registration list (in lttng_probe_register() at lttng-probes.c:216)
libust[3867/3868]: Waiting for global apps sessiond (in wait_for_sessiond() at lttng-ust-comm.c:899)
liblttng_ust_tracepoint[3867/3867]: just registered a tracepoints section from 0x602138 and having 1 tracepoints (in tracepoint_register_lib() at tracepoint.c:758)
liblttng_ust_tracepoint[3867/3867]: registered tracepoint: usttut:ackermann_entry (in tracepoint_register_lib() at tracepoint.c:763)
ackermann( 2, 2 ) = 7
liblttng_ust_tracepoint[3867/3867]: just unregistered a tracepoints section from 0x602138 (in tracepoint_unregister_lib() at tracepoint.c:788)
libust[3867/3867]: Provider "usttut" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3867/3867]: just unregistered probe usttut (in lttng_probe_unregister() at lttng-probes.c:245)
libust[3867/3868]: Info: sessiond not accepting connections to global apps socket (in ust_listener_thread() at lttng-ust-comm.c:988)
libust[3867/3867]: LTT : ltt ring buffer client "relay-discard-rt-mmap" exit
 (in lttng_ring_buffer_client_discard_rt_exit() at lttng-ring-buffer-client.h:570)
libust[3867/3867]: LTT : ltt ring buffer client "relay-discard-mmap" exit
 (in lttng_ring_buffer_client_discard_exit() at lttng-ring-buffer-client.h:570)
libust[3867/3867]: LTT : ltt ring buffer client "relay-overwrite-rt-mmap" exit
 (in lttng_ring_buffer_client_overwrite_rt_exit() at lttng-ring-buffer-client.h:570)
libust[3867/3867]: LTT : ltt ring buffer client "relay-overwrite-mmap" exit
 (in lttng_ring_buffer_client_overwrite_exit() at lttng-ring-buffer-client.h:570)
libust[3867/3867]: LTT : ltt ring buffer client "relay-metadata-mmap" exit
 (in lttng_ring_buffer_metadata_client_exit() at lttng-ring-buffer-metadata-client.h:321)
root@/ # lttng -vvv stop
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG2 [4075/4075]: Config file path found: /root (in get_session_name() at utils.c:52)
DEBUG1 [4075/4075]: Session name found: auto-20140729-122811 (in get_session_name() at utils.c:53)
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [4075/4075]: LSM cmd type : 17 (in send_session_msg() at lttng-ctl.c:130)
DEBUG1 [2177/2312]: Processing client command 17 (in process_client_msg() at main.c:2578)
DEBUG1 [2177/2312]: Getting session auto-20140729-122811 by name (in process_client_msg() at main.c:2655)
DEBUG2 [2177/2312]: Trying to find session by name auto-20140729-122811 (in session_find_by_name() at session.c:133)
DEBUG1 [2177/2312]: Stopping all UST traces (in ust_app_stop_trace_all() at ust-app.c:4037)
DEBUG1 [2177/2312]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
Waiting for data availabilityDEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [4075/4075]: LSM cmd type : 24 (in send_session_msg() at lttng-ctl.c:130)
DEBUG1 [2177/2312]: Processing client command 24 (in process_client_msg() at main.c:2578)
DEBUG1 [2177/2312]: Getting session auto-20140729-122811 by name (in process_client_msg() at main.c:2655)
DEBUG2 [2177/2312]: Trying to find session by name auto-20140729-122811 (in session_find_by_name() at session.c:133)
DEBUG3 [2177/2312]: Consumer data pending for id 0 (in consumer_is_data_pending() at consumer.c:1053)
DEBUG1 [2177/2312]: Consumer data is NOT pending for session id 0 (in consumer_is_data_pending() at consumer.c:1085)
DEBUG1 [2177/2312]: Sending response (size: 16, retcode: Unknown error code) (in thread_manage_clients() at main.c:3803)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)

Tracing stopped for session auto-20140729-122811

root@/ # lttng -vvv destroy
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3758)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG2 [4137/4137]: Config file path found: /root (in get_session_name() at utils.c:52)
DEBUG1 [4137/4137]: Session name found: auto-20140729-122811 (in get_session_name() at utils.c:53)
DEBUG1 [2177/2312]: Wait for client response (in thread_manage_clients() at main.c:3709)
DEBUG1 [4137/4137]: LSM cmd type : 9 (in send_session_msg() at lttng-ctl.c:130)
DEBUG1 [2177/2312]: Receiving data from client ... (in thread_manage_clients() at main.c:3754)
DEBUG1 [2177/2312]: Processing client command 9 (in process_client_msg() at main.c:2578)
DEBUG1 [2177/2312]: Getting session auto-20140729-122811 by name (in process_client_msg() at main.c:2655)
DEBUG2 [2177/2312]: Trying to find session by name auto-20140729-122811 (in session_find_by_name() at session.c:133)
DEBUG3 [2177/2312]: No kernel session when tearing down session (in kernel_destroy_session() at kernel.c:751)
DEBUG1 [2177/2312]: Destroy all UST traces (in ust_app_destroy_trace_all() at ust-app.c:4116)
DEBUG2 [2177/2312]: Trace UST destroy session 0 (in trace_ust_destroy_session() at trace-ust.c:676)
DEBUG1 [2177/2312]: Destroying session auto-20140729-122811 (in session_destroy() at session.c:158)
DEBUG3 [2177/2310]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG1 [2177/2312]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3803)
DEBUG1 [2177/2317]: Updating kernel poll set (in update_kernel_poll() at main.c:616)
DEBUG1 [2177/2317]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:801)
DEBUG1 [2177/2312]: Clean command context structure (in clean_command_ctx() at main.c:535)
DEBUG1 [2177/2312]: Accepting client command ... (in thread_manage_clients() at main.c:3667)
DEBUG3 [2177/2310]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG2 [2177/4138]: Trace destroy UST channel channel0 (in _trace_ust_destroy_channel() at trace-ust.c:573)
DEBUG3 [2177/2310]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [2177/2310]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
DEBUG3 [2177/2310]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:58)
Session auto-20140729-122811 destroyed
DEBUG1 [4137/4137]: Removing /root/.lttngrc
 (in config_destroy() at conf.c:143)
root@/ # DEBUG2 [2177/4138]: Trace destroy UST event * (in trace_ust_destroy_event() at trace-ust.c:524)

root@/ #