<div dir="ltr">Adding Lttng-dev <br><div><br>We
are running running a 32 bit application on a 64 bit machine. We have
built 32bit versions of both the sessiond and the consumerd.<div class="gmail_default" style="font-family:verdana,sans-serif;font-size:small;color:rgb(0,0,255)">Could anyone please point us on how do go about debugging this ?<br><br><i><b>lttng (LTTng Trace Control) 2.7.1 - Herbe à Détourne - v2.7.1<br></b></i><br><br>Facing some issues when we do "lttng start". <br><i><br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457697</span></span> [9524/9556]: Wait for client response (in thread_manage_clients() at main.c:4362)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457754</span></span> [9524/9556]: Receiving data from client ... (in thread_manage_clients() at main.c:4407)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457776</span></span> [9524/9556]: Processing client command 16 (in process_client_msg() at main.c:2989)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457782</span></span> [9524/9556]: Getting session auto-20160323-142131 by name (in process_client_msg() at main.c:3076)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:22:18.457788</span></span> [9524/9556]: Trying to find session by name auto-20160323-142131 (in session_find_by_name() at session.c:172)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457797</span></span> [9524/9556]: Starting all UST traces (in ust_app_start_trace_all() at ust-app.c:4592)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457804</span></span> [9524/9556]: Starting tracing for ust app pid 11876 (in ust_app_start_trace() at ust-app.c:4205)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.457811</span></span> [9524/9556]: mkdir() recursive /root/lttng-traces/auto-20160323-142131 with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:468)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457821</span></span> [9524/9556]: Using run_as worker (in run_as() at runas.c:449)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.457871</span></span> [9524/9556]: Buffer registry per UID find id: 2, ABI: 32, uid: 499 (in buffer_reg_uid_find() at buffer-registry.c:204)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.457885</span></span> [9524/9556]: Created hashtable size 4 at 0xf4233240 of type 1 (in lttng_ht_new() at hashtable.c:136)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.457893</span></span> [9524/9556]: Created hashtable size 4 at 0xf4233318 of type 0 (in lttng_ht_new() at hashtable.c:136)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.457899</span></span> [9524/9556]: UST app channel metadata allocated (in alloc_ust_app_channel() at ust-app.c:967)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:22:18.457906</span></span> [9524/9556]: Asking UST consumer for channel (in ask_channel_creation() at ust-consumer.c:121)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.457914</span></span> [9524/9556]: mkdir() recursive /root/lttng-traces/auto-20160323-142131/ust/uid/499/32-bit with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:468)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:18.457921</span></span> [9524/9556]: Using run_as worker (in run_as() at runas.c:449)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:22:18.458130</span></span> [9524/9556]: UST ask channel 6 successfully done with 1 stream(s) (in ask_channel_creation() at ust-consumer.c:216)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:22:18.458139</span></span> [9524/9556]: Consumer setup metadata channel key 6 (in consumer_setup_metadata() at consumer.c:1236)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:22:18.458176</span></span> [9524/9556]: UST metadata with key 6 created for app pid 11876 (in create_ust_app_metadata() at ust-app.c:3133)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.458183</span></span> [9524/9556]: UST app deleting channel metadata (in delete_ust_app_channel() at ust-app.c:391)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.458276</span></span> [9524/9560]: [ust-thread] Manage notify return from poll on 3 fds (in ust_thread_manage_notify() at ust-thread.c:76)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.458289</span></span> [9524/9560]: UST app receiving notify from sock 33 (in ust_app_recv_notify() at ust-app.c:5289)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:22:18.458297</span></span> [9524/9560]: UST app ustctl register channel received (in ust_app_recv_notify() at ust-app.c:5343)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:18.458309</span></span> [9524/9560]: Buffer registry per UID find id: 2, ABI: 32, uid: 499 (in buffer_reg_uid_find() at buffer-registry.c:204)<br>Error: Error starting tracing for app pid: 11876 (ret: -1024)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453769</span></span> [9524/9560]: UST registry channel finding key 0 (in ust_registry_channel_find() at ust-registry.c:490)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:22.453780</span></span> [9524/9556]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:4456)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453787</span></span> [9524/9560]: Append to metadata: "stream {<br> id = 0;<br> event.header := struct event_header_compact;<br> packet.context := struct packet_context;<br>" (in lttng_metadata_printf() at ust-metadata.c:175)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:22.453811</span></span> [9524/9556]: Clean command context structure (in clean_command_ctx() at main.c:829)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453813</span></span> [9524/9560]: Append to metadata: "};<br><br>" (in lttng_metadata_printf() at ust-metadata.c:175)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:22.453821</span></span> [9524/9556]: Accepting client command ... (in thread_manage_clients() at main.c:4310)<br><b>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453829</span></span>
[9524/9560]: UST app replying to register channel key 0 with id 0,
type: 1, ret: 0 (in reply_ust_register_channel() at ust-app.c:5155)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453839</span></span> [9524/9560]: UST app reply channel failed. Application died (in reply_ust_register_channel() at ust-app.c:5162)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:22:22.453851</span></span> [9524/9560]: UST app notify socket unregister 33 (in ust_app_notify_sock_unregister() at ust-app.c:5417)<br>Tracing started for session auto-20160323-142131</b><br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453860</span></span> [9524/9560]: [ust-thread] Manage notify polling (in ust_thread_manage_notify() at ust-thread.c:69)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453865</span></span> [9524/9526]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:74)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453882</span></span> [9524/9526]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:67)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453888</span></span> [9524/9526]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:74)<br>DEBUG3 - <span tabindex="0" class=""><span class="">14:22:22.453895</span></span> [9524/9526]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:67)<br></i><br><br><br>We believe that due to the above issue, no traces are created.<br><br><br><i><b>lttng view<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.807884</span></span> [9524/9556]: Wait for client response (in thread_manage_clients() at main.c:4362)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.807934</span></span> [9524/9556]: Receiving data from client ... (in thread_manage_clients() at main.c:4407)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.807955</span></span> [9524/9556]: Processing client command 13 (in process_client_msg() at main.c:2989)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.807962</span></span> [9524/9556]: Counting number of available session for UID 0 GID 0 (in lttng_sessions_count() at main.c:2958)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.807970</span></span> [9524/9556]: Getting all available session for UID 0 GID 0 (in cmd_list_lttng_sessions() at cmd.c:2783)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.807978</span></span> [9524/9556]: Sending response (size: 4392, retcode: Success) (in thread_manage_clients() at main.c:4456)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.807999</span></span> [9524/9556]: Clean command context structure (in clean_command_ctx() at main.c:829)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:24:26.808006</span></span> [9524/9556]: Accepting client command ... (in thread_manage_clients() at main.c:4310)<br>Trace directory: /root/lttng-traces/auto-20160323-142131<br></b></i><br><br><br><br>We are using tracef() in our application.<br><br><br><i><b>#include <lttng/tracef.h><br><br>tracef("my message, my integer: %d", 0x1234);<br></b></i><br><br><i>lttng list --userspace<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:21:17.747867</span></span> [9524/9556]: Wait for client response (in thread_manage_clients() at main.c:4362)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:21:17.747921</span></span> [9524/9556]: Receiving data from client ... (in thread_manage_clients() at main.c:4407)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:21:17.747943</span></span> [9524/9556]: Processing client command 14 (in process_client_msg() at main.c:2989)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:21:17.748521</span></span> [9524/9556]: Reallocating event list from 32 to 64 entries (in ust_app_list_events() at ust-app.c:3502)<br>DEBUG2 - <span tabindex="0" class=""><span class="">14:21:17.748623</span></span> [9524/9556]: UST app list events done (35 events) (in ust_app_list_events() at ust-app.c:3543)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:21:17.748638</span></span> [9524/9556]: Sending response (size: 20456, retcode: Success) (in thread_manage_clients() at main.c:4456)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:21:17.748663</span></span> [9524/9556]: Clean command context structure (in clean_command_ctx() at main.c:829)<br>DEBUG1 - <span tabindex="0" class=""><span class="">14:21:17.748670</span></span> [9524/9556]: Accepting client command ... (in thread_manage_clients() at main.c:4310)<br>UST events:<br>-------------<br><br>PID: 11876 - Name: <process_name><br> lttng_ust_cyg_profile_fast:func_exit (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_cyg_profile_fast:func_entry (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_cyg_profile:func_exit (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_cyg_profile:func_entry (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_dl:dlclose (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_dl:dlopen (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:posix_memalign (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:memalign (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:realloc (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:calloc (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:free (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:malloc (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_unlock (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_trylock (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_lock_acq (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_lock_req (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG (loglevel: TRACE_DEBUG (14)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_LINE (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_FUNCTION (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_UNIT (loglevel: TRACE_DEBUG_UNIT (11)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_MODULE (loglevel: TRACE_DEBUG_MODULE (10)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_PROCESS (loglevel: TRACE_DEBUG_PROCESS (9)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_PROGRAM (loglevel: TRACE_DEBUG_PROGRAM (8)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_SYSTEM (loglevel: TRACE_DEBUG_SYSTEM (7)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_INFO (loglevel: TRACE_INFO (6)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_NOTICE (loglevel: TRACE_NOTICE (5)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_WARNING (loglevel: TRACE_WARNING (4)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_ERR (loglevel: TRACE_ERR (3)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_CRIT (loglevel: TRACE_CRIT (2)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_ALERT (loglevel: TRACE_ALERT (1)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_EMERG (loglevel: TRACE_EMERG (0)) (type: tracepoint)<br> lttng_ust_tracef:event (loglevel: TRACE_DEBUG (14)) (type: tracepoint)<br> lttng_ust_statedump:end (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_statedump:soinfo (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_statedump:start (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br><br></i><br><br><br><br>Kindly help us in fixing this issue.<br></div></div></div><div class="gmail_extra"><br clear="all"><div><div class="gmail_signature"><div dir="ltr"><font face="courier new, monospace"><font size="2"><span lang="hi"><span title="Click for alternate translations">Regards,<br>Aditya Dogra<br></span></span>"When the going gets tough, the tough get going"</font></font><br></div></div></div>
<br><div class="gmail_quote">On Mon, Mar 28, 2016 at 3:38 PM, Vijay Anand <span dir="ltr"><<a href="mailto:vjanandr85@gmail.com" target="_blank">vjanandr85@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div class="gmail_default" style="font-family:verdana,sans-serif;font-size:small;color:#0000ff">Just to add, we are running running a 32 bit application on a 64 bit machine. We have built 32bit versions of both the sessiond and the consumerd.</div><div class="gmail_default" style="font-family:verdana,sans-serif;font-size:small;color:#0000ff">Could anyone please point us on how do go about debugging this ?</div></div><div class="gmail_extra"><br clear="all"><div><div><div dir="ltr"><font face="verdana, sans-serif" color="#0000ff">Regards,</font><div><font face="verdana, sans-serif" color="#0000ff">Vijay</font></div></div></div></div><div><div class="h5">
<br><div class="gmail_quote">On Wed, Mar 23, 2016 at 6:10 PM, Prashanth Raghavendra <span dir="ltr"><<a href="mailto:prashanth.raghavendra@gmail.com" target="_blank">prashanth.raghavendra@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div><div><div><div><div><div>Hi Jeremie,<br><br></div>We upgraded to LTTNG 2.7.<br><br><i><b>lttng (LTTng Trace Control) 2.7.1 - Herbe à Détourne - v2.7.1<br></b></i><br><br></div>Facing some issues when we do "lttng start". <br><i><br>DEBUG1 - 14:22:18.457697 [9524/9556]: Wait for client response (in thread_manage_clients() at main.c:4362)<br>DEBUG1 - 14:22:18.457754 [9524/9556]: Receiving data from client ... (in thread_manage_clients() at main.c:4407)<br>DEBUG1 - 14:22:18.457776 [9524/9556]: Processing client command 16 (in process_client_msg() at main.c:2989)<br>DEBUG1 - 14:22:18.457782 [9524/9556]: Getting session auto-20160323-142131 by name (in process_client_msg() at main.c:3076)<br>DEBUG2 - 14:22:18.457788 [9524/9556]: Trying to find session by name auto-20160323-142131 (in session_find_by_name() at session.c:172)<br>DEBUG1 - 14:22:18.457797 [9524/9556]: Starting all UST traces (in ust_app_start_trace_all() at ust-app.c:4592)<br>DEBUG1 - 14:22:18.457804 [9524/9556]: Starting tracing for ust app pid 11876 (in ust_app_start_trace() at ust-app.c:4205)<br>DEBUG3 - 14:22:18.457811 [9524/9556]: mkdir() recursive /root/lttng-traces/auto-20160323-142131 with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:468)<br>DEBUG1 - 14:22:18.457821 [9524/9556]: Using run_as worker (in run_as() at runas.c:449)<br>DEBUG3 - 14:22:18.457871 [9524/9556]: Buffer registry per UID find id: 2, ABI: 32, uid: 499 (in buffer_reg_uid_find() at buffer-registry.c:204)<br>DEBUG3 - 14:22:18.457885 [9524/9556]: Created hashtable size 4 at 0xf4233240 of type 1 (in lttng_ht_new() at hashtable.c:136)<br>DEBUG3 - 14:22:18.457893 [9524/9556]: Created hashtable size 4 at 0xf4233318 of type 0 (in lttng_ht_new() at hashtable.c:136)<br>DEBUG3 - 14:22:18.457899 [9524/9556]: UST app channel metadata allocated (in alloc_ust_app_channel() at ust-app.c:967)<br>DEBUG2 - 14:22:18.457906 [9524/9556]: Asking UST consumer for channel (in ask_channel_creation() at ust-consumer.c:121)<br>DEBUG3 - 14:22:18.457914 [9524/9556]: mkdir() recursive /root/lttng-traces/auto-20160323-142131/ust/uid/499/32-bit with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:468)<br>DEBUG1 - 14:22:18.457921 [9524/9556]: Using run_as worker (in run_as() at runas.c:449)<br>DEBUG2 - 14:22:18.458130 [9524/9556]: UST ask channel 6 successfully done with 1 stream(s) (in ask_channel_creation() at ust-consumer.c:216)<br>DEBUG2 - 14:22:18.458139 [9524/9556]: Consumer setup metadata channel key 6 (in consumer_setup_metadata() at consumer.c:1236)<br>DEBUG2 - 14:22:18.458176 [9524/9556]: UST metadata with key 6 created for app pid 11876 (in create_ust_app_metadata() at ust-app.c:3133)<br>DEBUG3 - 14:22:18.458183 [9524/9556]: UST app deleting channel metadata (in delete_ust_app_channel() at ust-app.c:391)<br>DEBUG3 - 14:22:18.458276 [9524/9560]: [ust-thread] Manage notify return from poll on 3 fds (in ust_thread_manage_notify() at ust-thread.c:76)<br>DEBUG3 - 14:22:18.458289 [9524/9560]: UST app receiving notify from sock 33 (in ust_app_recv_notify() at ust-app.c:5289)<br>DEBUG2 - 14:22:18.458297 [9524/9560]: UST app ustctl register channel received (in ust_app_recv_notify() at ust-app.c:5343)<br>DEBUG3 - 14:22:18.458309 [9524/9560]: Buffer registry per UID find id: 2, ABI: 32, uid: 499 (in buffer_reg_uid_find() at buffer-registry.c:204)<br>Error: Error starting tracing for app pid: 11876 (ret: -1024)<br>DEBUG3 - 14:22:22.453769 [9524/9560]: UST registry channel finding key 0 (in ust_registry_channel_find() at ust-registry.c:490)<br>DEBUG1 - 14:22:22.453780 [9524/9556]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:4456)<br>DEBUG3 - 14:22:22.453787 [9524/9560]: Append to metadata: "stream {<br> id = 0;<br> event.header := struct event_header_compact;<br> packet.context := struct packet_context;<br>" (in lttng_metadata_printf() at ust-metadata.c:175)<br>DEBUG1 - 14:22:22.453811 [9524/9556]: Clean command context structure (in clean_command_ctx() at main.c:829)<br>DEBUG3 - 14:22:22.453813 [9524/9560]: Append to metadata: "};<br><br>" (in lttng_metadata_printf() at ust-metadata.c:175)<br>DEBUG1 - 14:22:22.453821 [9524/9556]: Accepting client command ... (in thread_manage_clients() at main.c:4310)<br><b>DEBUG3 - 14:22:22.453829 [9524/9560]: UST app replying to register channel key 0 with id 0, type: 1, ret: 0 (in reply_ust_register_channel() at ust-app.c:5155)<br>DEBUG3 - 14:22:22.453839 [9524/9560]: UST app reply channel failed. Application died (in reply_ust_register_channel() at ust-app.c:5162)<br>DEBUG1 - 14:22:22.453851 [9524/9560]: UST app notify socket unregister 33 (in ust_app_notify_sock_unregister() at ust-app.c:5417)<br>Tracing started for session auto-20160323-142131</b><br>DEBUG3 - 14:22:22.453860 [9524/9560]: [ust-thread] Manage notify polling (in ust_thread_manage_notify() at ust-thread.c:69)<br>DEBUG3 - 14:22:22.453865 [9524/9526]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:74)<br>DEBUG3 - 14:22:22.453882 [9524/9526]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:67)<br>DEBUG3 - 14:22:22.453888 [9524/9526]: [ht-thread] Returning from poll on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:74)<br>DEBUG3 - 14:22:22.453895 [9524/9526]: [ht-thread] Polling. (in thread_ht_cleanup() at ht-cleanup.c:67)<br></i><br><br><br></div>We believe that due to the above issue, no traces are created.<br><br><br><i><b>lttng view<br>DEBUG1 - 14:24:26.807884 [9524/9556]: Wait for client response (in thread_manage_clients() at main.c:4362)<br>DEBUG1 - 14:24:26.807934 [9524/9556]: Receiving data from client ... (in thread_manage_clients() at main.c:4407)<br>DEBUG1 - 14:24:26.807955 [9524/9556]: Processing client command 13 (in process_client_msg() at main.c:2989)<br>DEBUG1 - 14:24:26.807962 [9524/9556]: Counting number of available session for UID 0 GID 0 (in lttng_sessions_count() at main.c:2958)<br>DEBUG1 - 14:24:26.807970 [9524/9556]: Getting all available session for UID 0 GID 0 (in cmd_list_lttng_sessions() at cmd.c:2783)<br>DEBUG1 - 14:24:26.807978 [9524/9556]: Sending response (size: 4392, retcode: Success) (in thread_manage_clients() at main.c:4456)<br>DEBUG1 - 14:24:26.807999 [9524/9556]: Clean command context structure (in clean_command_ctx() at main.c:829)<br>DEBUG1 - 14:24:26.808006 [9524/9556]: Accepting client command ... (in thread_manage_clients() at main.c:4310)<br>Trace directory: /root/lttng-traces/auto-20160323-142131<br></b></i><br><br><br><br></div>We are using tracef() in our application.<br><br><br><i><b>#include <lttng/tracef.h><br><br>tracef("my message, my integer: %d", 0x1234);<br></b></i><br><br><i>lttng list --userspace<br>DEBUG1 - 14:21:17.747867 [9524/9556]: Wait for client response (in thread_manage_clients() at main.c:4362)<br>DEBUG1 - 14:21:17.747921 [9524/9556]: Receiving data from client ... (in thread_manage_clients() at main.c:4407)<br>DEBUG1 - 14:21:17.747943 [9524/9556]: Processing client command 14 (in process_client_msg() at main.c:2989)<br>DEBUG2 - 14:21:17.748521 [9524/9556]: Reallocating event list from 32 to 64 entries (in ust_app_list_events() at ust-app.c:3502)<br>DEBUG2 - 14:21:17.748623 [9524/9556]: UST app list events done (35 events) (in ust_app_list_events() at ust-app.c:3543)<br>DEBUG1 - 14:21:17.748638 [9524/9556]: Sending response (size: 20456, retcode: Success) (in thread_manage_clients() at main.c:4456)<br>DEBUG1 - 14:21:17.748663 [9524/9556]: Clean command context structure (in clean_command_ctx() at main.c:829)<br>DEBUG1 - 14:21:17.748670 [9524/9556]: Accepting client command ... (in thread_manage_clients() at main.c:4310)<br>UST events:<br>-------------<br><br>PID: 11876 - Name: <process_name><br> lttng_ust_cyg_profile_fast:func_exit (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_cyg_profile_fast:func_entry (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_cyg_profile:func_exit (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_cyg_profile:func_entry (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_dl:dlclose (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_dl:dlopen (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:posix_memalign (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:memalign (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:realloc (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:calloc (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:free (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_libc:malloc (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_unlock (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_trylock (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_lock_acq (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_pthread:pthread_mutex_lock_req (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG (loglevel: TRACE_DEBUG (14)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_LINE (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_FUNCTION (loglevel: TRACE_DEBUG_FUNCTION (12)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_UNIT (loglevel: TRACE_DEBUG_UNIT (11)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_MODULE (loglevel: TRACE_DEBUG_MODULE (10)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_PROCESS (loglevel: TRACE_DEBUG_PROCESS (9)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_PROGRAM (loglevel: TRACE_DEBUG_PROGRAM (8)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_DEBUG_SYSTEM (loglevel: TRACE_DEBUG_SYSTEM (7)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_INFO (loglevel: TRACE_INFO (6)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_NOTICE (loglevel: TRACE_NOTICE (5)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_WARNING (loglevel: TRACE_WARNING (4)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_ERR (loglevel: TRACE_ERR (3)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_CRIT (loglevel: TRACE_CRIT (2)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_ALERT (loglevel: TRACE_ALERT (1)) (type: tracepoint)<br> lttng_ust_tracelog:TRACE_EMERG (loglevel: TRACE_EMERG (0)) (type: tracepoint)<br> lttng_ust_tracef:event (loglevel: TRACE_DEBUG (14)) (type: tracepoint)<br> lttng_ust_statedump:end (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_statedump:soinfo (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br> lttng_ust_statedump:start (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)<br><br></i><br><br><br><br></div><div>Kindly help us in fixing this issue.<br><br></div><div><br><br><br></div>Regards,<br></div>Prashanth <br></div><div class="gmail_extra"><br><div class="gmail_quote">On Mon, Feb 15, 2016 at 6:20 PM, Prashanth Raghavendra <span dir="ltr"><<a href="mailto:prashanth.raghavendra@gmail.com" target="_blank">prashanth.raghavendra@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div><div><div><div>Hi Jeremie,<br><br></div>Thanks for the clarification.<br></div>We will try with the latest version.<br><br><br></div>Regards,<br></div>Prashanth<br></div><div><div><div class="gmail_extra"><br><div class="gmail_quote">On Fri, Feb 12, 2016 at 10:10 PM, Jérémie Galarneau <span dir="ltr"><<a href="mailto:jeremie.galarneau@efficios.com" target="_blank">jeremie.galarneau@efficios.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><span>On Fri, Feb 12, 2016 at 8:57 AM, Prashanth Raghavendra<br>
<<a href="mailto:prashanth.raghavendra@gmail.com" target="_blank">prashanth.raghavendra@gmail.com</a>> wrote:<br>
> Greetings,<br>
><br>
> We are trying to use lttng for tracing our application code (userspace).<br>
><br>
> Host OS: WR5.0.1.13 Linux<br>
> Lttng-tools version 2.1.0, 32-bit (lttng: ELF 32-bit LSB executable, Intel<br>
> 80386)<br>
> Babeltrace version 1.0.0<br>
><br>
<br>
</span>Hi,<br>
<br>
Unfortunately, both of these versions have been unsupported for a long time.<br>
Let us know if the problem still occurs with the latest LTTng and<br>
Babeltrace releases.<br>
<br>
Regards,<br>
Jérémie<br>
<div><div><br>
> Logged in as root.<br>
><br>
><br>
><br>
> When we start the lttng-sessiond, it gets stuck at "Accepting application<br>
> registration”.<br>
><br>
> Linux(debug)# /isan/lib/lttng-sessiond -vvv --no-kernel<br>
> DEBUG3: Creating LTTng run directory: /var/run/lttng [in<br>
> create_lttng_rundir() at main.c:3683]<br>
> DEBUG2: Kernel consumer err path: /var/run/lttng/kconsumerd/error [in main()<br>
> at main.c:3931]<br>
> DEBUG2: Kernel consumer cmd path: /var/run/lttng/kconsumerd/command [in<br>
> main() at main.c:3933]<br>
> DEBUG1: Client socket path /var/run/lttng/client-lttng-sessiond [in main()<br>
> at main.c:3986]<br>
> DEBUG1: Application socket path /var/run/lttng/apps-lttng-sessiond [in<br>
> main() at main.c:3987]<br>
> DEBUG1: LTTng run directory path: /var/run/lttng [in main() at main.c:3988]<br>
> DEBUG2: UST consumer 32 bits err path: /var/run/lttng/ustconsumerd32/error<br>
> [in main() at main.c:3997]<br>
> DEBUG2: UST consumer 32 bits cmd path: /var/run/lttng/ustconsumerd32/command<br>
> [in main() at main.c:3999]<br>
> DEBUG2: UST consumer 64 bits err path: /var/run/lttng/ustconsumerd64/error<br>
> [in main() at main.c:4008]<br>
> DEBUG2: UST consumer 64 bits cmd path: /var/run/lttng/ustconsumerd64/command<br>
> [in main() at main.c:4010]<br>
> DEBUG3: Created hashtable size 4 at 0x88d7080 of type 1 [in lttng_ht_new()<br>
> at hashtable.c:96]<br>
> DEBUG3: Created hashtable size 4 at 0x88d7370 of type 1 [in lttng_ht_new()<br>
> at hashtable.c:96]<br>
> DEBUG2: Creating consumer directory: /var/run/lttng/kconsumerd [in<br>
> set_consumer_sockets() at main.c:3725]<br>
> DEBUG2: Creating consumer directory: /var/run/lttng/ustconsumerd64 [in<br>
> set_consumer_sockets() at main.c:3725]<br>
> DEBUG2: Creating consumer directory: /var/run/lttng/ustconsumerd32 [in<br>
> set_consumer_sockets() at main.c:3725]<br>
> DEBUG1: Signal handler set for SIGTERM, SIGPIPE and SIGINT [in<br>
> set_signal_handler() at main.c:3817]<br>
> DEBUG3: Session daemon client socket 8 and application socket 9 created [in<br>
> init_daemon_socket() at main.c:3587]<br>
> Warning: No tracing group detected<br>
> DEBUG1: epoll set max size is 3243642 [in compat_epoll_set_max_size() at<br>
> compat-epoll.c:224]<br>
> DEBUG1: Command subsystem initialized [in cmd_init() at cmd.c:2423]<br>
> DEBUG1: [thread] Manage health check started [in thread_manage_health() at<br>
> main.c:2944]<br>
> DEBUG1: [thread] Manage client started [in thread_manage_clients() at<br>
> main.c:3138]<br>
> DEBUG1: [thread] Dispatch UST command started [in<br>
> thread_dispatch_ust_registration() at main.c:1295]<br>
> DEBUG1: Futex n to 1 prepare done [in futex_nto1_prepare() at futex.c:75]<br>
> DEBUG1: Woken up but nothing in the UST command queue [in<br>
> thread_dispatch_ust_registration() at main.c:1305]<br>
> DEBUG1: [thread] Manage application registration started [in<br>
> thread_registration_apps() at main.c:1371]<br>
> DEBUG1: [thread] Manage application started [in thread_manage_apps() at<br>
> main.c:1101]<br>
> DEBUG1: Health check ready [in thread_manage_health() at main.c:2983]<br>
> DEBUG1: Accepting client command ... [in thread_manage_clients() at<br>
> main.c:3182]<br>
> DEBUG1: Notifying applications of session daemon state: 1 [in<br>
> notify_ust_apps() at main.c:496]<br>
> DEBUG1: Apps thread polling on 2 fds [in thread_manage_apps() at<br>
> main.c:1134]<br>
> DEBUG1: Got the wait shm fd 16 [in get_wait_shm() at shm.c:117]<br>
> DEBUG1: Futex wait update active 1 [in futex_wait_update() at futex.c:63]<br>
> DEBUG1: Accepting application registration [in thread_registration_apps() at<br>
> main.c:1406] ===è Remain struck here<br>
><br>
><br>
><br>
> Because of this, we suspect that the traces from our application are not<br>
> getting generated. And we get error when we run lttng view:<br>
> (lttng enable-event, start etc went through fine)<br>
><br>
> Linux(debug)# lttng stop<br>
> Waiting for data availability<br>
> Warning: Tracing already stopped for session auto-20160211-133851<br>
> Linux(debug)# lttng view<br>
> Trace directory: /home/<br>
><br>
> [error] Cannot open any trace for reading.<br>
><br>
> [error] opening trace “/home/" for reading.<br>
><br>
> [error] none of the specified trace paths could be opened.<br>
><br>
><br>
><br>
> Please help us in fixing this issue.<br>
><br>
> Thank you.<br>
><br>
><br>
><br>
><br>
> Best Regards,<br>
><br>
> Prashanth Raghavendra<br>
> Aditya Dogra<br>
><br>
><br>
><br>
><br>
><br>
</div></div>> _______________________________________________<br>
> lttng-dev mailing list<br>
> <a href="mailto:lttng-dev@lists.lttng.org" target="_blank">lttng-dev@lists.lttng.org</a><br>
> <a href="http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev" rel="noreferrer" target="_blank">http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev</a><br>
><br>
<span><font color="#888888"><br>
<br><span><font color="#888888">
<br>
--<br>
Jérémie Galarneau<br>
EfficiOS Inc.<br>
<a href="http://www.efficios.com" rel="noreferrer" target="_blank">http://www.efficios.com</a><br>
</font></span></font></span></blockquote></div><span><font color="#888888"><br></font></span></div>
</div></div></blockquote></div><br></div>
</blockquote></div><br></div></div></div>
</blockquote></div><br></div>