Project

General

Profile

Bug #343 ยป log.txt

Terminal log with "sessiond -vvv" - Tan le tran, 09/18/2012 05:08 PM

 
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps # #--- Bug3
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps # export SESSION_NAME=Bug3
SC-1:/home/test_apps # lttng create $SESSION_NAME -o /cluster/temp/sessiondir/$SESSION_NAME
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3034]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Processing client command 8 [in process_client_msg() at main.c:1998]
DEBUG1: Waiting for 1 URIs from client ... [in process_client_msg() at main.c:2501]
DEBUG2: Trying to find session by name Bug3 [in session_find_by_name() at session.c:122]
DEBUG1: Tracing session Bug3 created in (null) with ID 28 by UID 0 GID 0 [in session_create() at session.c:236]
DEBUG2: Trying to find session by name Bug3 [in session_find_by_name() at session.c:122]
DEBUG3: Created hashtable size 4 at 0x65ed00 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Setting trace directory path from URI to /cluster/temp/sessiondir/Bug3 [in add_uri_to_consumer() at cmd.c:396]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3081]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
Session Bug3 created.
Traces will be written in /cluster/temp/sessiondir/Bug3
SC-1:/home/test_apps # lttng enable-event ust_tests_demo2:loop -u --filter ' intfield => 2 '
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3034]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Processing client command 6 [in process_client_msg() at main.c:1998]
DEBUG1: Getting session Bug3 by name [in process_client_msg() at main.c:2068]
DEBUG2: Trying to find session by name Bug3 [in session_find_by_name() at session.c:122]
DEBUG1: Creating UST session [in create_ust_session() at main.c:1885]
DEBUG3: Created hashtable size 4 at 0x640380 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x641e30 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x6477c0 of type 0 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x647a60 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: UST trace session create successful [in trace_ust_create_session() at trace-ust.c:143]
DEBUG3: Copying tracing session consumer output in UST session [in copy_session_consumer() at main.c:1840]
DEBUG3: Created hashtable size 4 at 0x6854d0 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x685790 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Copy session consumer subdir /ust [in copy_session_consumer() at main.c:1855]
DEBUG3: Consumer socket created (fd: 23) and added to output [in consumer_create_socket() at consumer.c:141]
DEBUG2: Trace UST channel channel0 not found by name [in trace_ust_find_channel_by_name() at trace-ust.c:52]
DEBUG1: Enabling channel channel0 for session Bug3 [in cmd_enable_channel() at cmd.c:744]
DEBUG2: Trace UST channel channel0 not found by name [in trace_ust_find_channel_by_name() at trace-ust.c:52]
DEBUG3: Created hashtable size 4 at 0x685cf0 of type 0 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x685fb0 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Trace UST channel channel0 created [in trace_ust_create_channel() at trace-ust.c:205]
DEBUG2: Channel channel0 being created in UST global domain [in channel_ust_create() at channel.c:267]
DEBUG2: UST app adding channel channel0 to global domain for session id 28 [in ust_app_create_channel_glb() at ust-app.c:1985]
DEBUG2: Channel channel0 created successfully [in channel_ust_create() at channel.c:292]
DEBUG2: Trace UST channel channel0 found by name [in trace_ust_find_channel_by_name() at trace-ust.c:47]
DEBUG2: Trace UST event NOT found by name ust_tests_demo2:loop [in trace_ust_find_event_by_name() at trace-ust.c:79]
DEBUG3: Created hashtable size 4 at 0x660500 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Trace UST event ust_tests_demo2:loop, loglevel (0,-1) created [in trace_ust_create_event() at trace-ust.c:284]
DEBUG1: UST app creating event ust_tests_demo2:loop for all apps for session id 28 [in ust_app_create_event_glb() at ust-app.c:2109]
DEBUG1: Event UST ust_tests_demo2:loop created in channel channel0 [in event_ust_enable_tracepoint() at event.c:486]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3081]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
UST event ust_tests_demo2:loop created in channel channel0
error syntax error
Parse error
Error: Error setting filter
LTTng Trace Control 2.1.0-rc3 - Basse Messe

usage: lttng [OPTIONS] <COMMAND> [<ARGS>]

Options:
-h, --help Show this help
--list-options Simple listing of lttng options
--list-commands Simple listing of lttng commands
-v, --verbose Increase verbosity
-q, --quiet Quiet mode
-g, --group NAME Unix tracing group name. (default: tracing)
-n, --no-sessiond Don't spawn a session daemon
--sessiond-path PATH Session daemon full path

Commands:
add-context Add context to event and/or channel
calibrate Quantify LTTng overhead
create Create tracing session
destroy Tear down tracing session
enable-channel Enable tracing channel
enable-event Enable tracing event
disable-channel Disable tracing channel
disable-event Disable tracing event
enable-consumer Enable local or streaming consumer
disable-consumer Disable consumer
list List possible tracing options
set-session Set current session name
start Start tracing
stop Stop tracing
version Show version information
view Start trace viewer

Each command also has its own -h, --help option.

Please see the lttng(1) man page for full documentation.
See http://lttng.org for updates, bug reports and news.
SC-1:/home/test_apps # lttng list $SESSION_NAME
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3034]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Processing client command 13 [in process_client_msg() at main.c:1998]
DEBUG1: Counting number of available session for UID 0 GID 0 [in lttng_sessions_count() at main.c:1969]
DEBUG1: Getting all available session for UID 0 GID 0 [in cmd_list_lttng_sessions() at cmd.c:2016]
DEBUG1: Sending response (size: 4388, retcode: Success) [in thread_manage_clients() at main.c:3081]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
Tracing session Bug3: [inactive]
Trace path: /cluster/temp/sessiondir/Bug3

DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Processing client command 11 [in process_client_msg() at main.c:1998]
DEBUG1: Getting session Bug3 by name [in process_client_msg() at main.c:2068]
DEBUG2: Trying to find session by name Bug3 [in session_find_by_name() at session.c:122]
DEBUG3: Listing domains found UST global domain [in cmd_list_domains() at cmd.c:1888]
DEBUG1: Sending response (size: 324, retcode: Success) [in thread_manage_clients() at main.c:3081]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
=== Domain: UST global ===

DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Processing client command 10 [in process_client_msg() at main.c:1998]
DEBUG1: Getting session Bug3 by name [in process_client_msg() at main.c:2068]
DEBUG2: Trying to find session by name Bug3 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 23) and added to output [in consumer_create_socket() at consumer.c:141]
DEBUG3: Number of UST global channels 1 [in cmd_list_channels() at cmd.c:1937]
DEBUG1: Listing channels for session Bug3 [in list_lttng_channels() at cmd.c:124]
DEBUG1: Sending response (size: 624, retcode: Success) [in thread_manage_clients() at main.c:3081]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
Channels:
-------------
- channel0: [enabled]

Attributes:
overwrite mode: 0
subbufers size: 4096
number of subbufers: 4
switch timer interval: 0
read timer interval: 200
output: mmap()
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Processing client command 12 [in process_client_msg() at main.c:1998]
DEBUG1: Getting session Bug3 by name [in process_client_msg() at main.c:2068]
DEBUG2: Trying to find session by name Bug3 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 23) and added to output [in consumer_create_socket() at consumer.c:141]
DEBUG1: Listing UST global events for channel channel0 [in list_lttng_ust_global_events() at cmd.c:184]
DEBUG3: Listing UST global 1 events [in list_lttng_ust_global_events() at cmd.c:204]
DEBUG1: Sending response (size: 600, retcode: Success) [in thread_manage_clients() at main.c:3081]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]

Events:
ust_tests_demo2:loop (type: tracepoint) [enabled]

SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps #
SC-1:/home/test_apps # lttng destroy $SESSION_NAME
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3034]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:2991]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3030]
DEBUG1: Processing client command 9 [in process_client_msg() at main.c:1998]
DEBUG1: Getting session Bug3 by name [in process_client_msg() at main.c:2068]
DEBUG2: Trying to find session by name Bug3 [in session_find_by_name() at session.c:122]
DEBUG3: No kernel session when tearing down session [in kernel_destroy_session() at kernel.c:729]
DEBUG1: Destroy all UST traces [in ust_app_destroy_trace_all() at ust-app.c:2503]
DEBUG2: Trace UST destroy session 28 [in trace_ust_destroy_session() at trace-ust.c:574]
DEBUG1: Destroying session Bug3 [in session_destroy() at session.c:149]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3081]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:465]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:2949]
Session Bug3 destroyed
DEBUG1: Updating kernel poll set [in update_kernel_poll() at main.c:546]
DEBUG1: Thread kernel polling on 2 fds [in thread_manage_kernel() at main.c:714]
SC-1:/home/test_apps # DEBUG2: Trace destroy UST channel channel0 [in trace_ust_destroy_channel() at trace-ust.c:457]
DEBUG2: Trace destroy UST event ust_tests_demo2:loop [in trace_ust_destroy_event() at trace-ust.c:415]

SC-1:/home/test_apps # sleep 1
SC-1:/home/test_apps # rm -rf /cluster/temp/sessiondir/$SESSION_NAME
SC-1:/home/test_apps # dir /cluster/temp/sessiondir/
total 0
SC-1:/home/test_apps #
    (1-1/1)