Project

General

Profile

Bug #363 ยป terminal.log

terminal log + test instruction - Tan le tran, 10/03/2012 03:32 PM

 

/home/test_apps/demo-trace 60 &
lttng list -u
lttng create $SESSION_NAME -o /cluster/temp/sessiondir/$SESSION_NAME
lttng enable-event "ust_tests_demo2:l*" -u --filter ' intfield > 3'
lttng enable-event "ust_tests_demo2:loop" -u --filter ' intfield < 3'
lttng start $SESSION_NAME;
lttng list $SESSION_NAME
#--- wait for demo to finish.
sleep 1
babeltrace --no-delta /cluster/temp/sessiondir/$SESSION_NAME
more /cluster/temp/sessiondir/$SESSION_NAME/
lttng destroy $SESSION_NAME
sleep 1
rm -rf /cluster/temp/sessiondir/$SESSION_NAME
dir /cluster/temp/sessiondir/





[SC-1:Wed Oct 03 14:44:20/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:44:20/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:44:20/cluster/temp/sessiondir] # /home/test_apps/demo-trace 60 &
[2] 20615
[SC-1:Wed Oct 03 14:44:31/cluster/temp/sessiondir] # DEBUG1: UST registration received with pid:20616 ppid:20615 uid:0 gid:0 sock:20 name:demo (version 2.0) [in thread_registration_apps() at main.c:1421]
DEBUG1: Futex n to 1 wake done [in futex_nto1_wake() at futex.c:104]
DEBUG1: Accepting application registration [in thread_registration_apps() at main.c:1327]
DEBUG1: Futex n to 1 wait done [in futex_nto1_wait() at futex.c:90]
DEBUG1: Futex n to 1 prepare done [in futex_nto1_prepare() at futex.c:75]
DEBUG1: Dispatching UST registration pid:20616 ppid:20615 uid:0 gid:0 sock:20 name:demo (version 2.0) [in thread_dispatch_ust_registration() at main.c:1250]
DEBUG1: Woken up but nothing in the UST command queue [in thread_dispatch_ust_registration() at main.c:1238]
DEBUG3: Created hashtable size 4 at 0x640450 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG1: App registered with pid:20616 ppid:20615 uid:0 gid:0 sock:20 name:demo (version 2.0) [in ust_app_register() at ust-app.c:1474]
DEBUG2: UST app PID 20616 is compatible with internal major version 3 (supporting == 3) [in ust_app_validate_version() at ust-app.c:2934]
DEBUG1: Apps with sock 20 added to poll set [in thread_manage_apps() at main.c:1174]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
Demo program starting.

[SC-1:Wed Oct 03 14:44:32/cluster/temp/sessiondir] # lttng list -u
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 14 [in process_client_msg() at main.c:2080]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG2: UST app list events done (4 events) [in ust_app_list_events() at ust-app.c:1604]
DEBUG1: Sending response (size: 2352, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
UST events:
-------------

PID: 20616 - Name: /home/test_apps/demo
ust_tests_demo2:loop (loglevel: TRACE_WARNING (4)) (type: tracepoint)
ust_tests_demo:done (loglevel: TRACE_CRIT (2)) (type: tracepoint)
ust_tests_demo:starting (loglevel: TRACE_CRIT (2)) (type: tracepoint)
ust_tests_demo3:done (loglevel: TRACE_WARNING (4)) (type: tracepoint)

[SC-1:Wed Oct 03 14:44:36/cluster/temp/sessiondir] # lttng create $SESSION_NAME -o /cluster/temp/sessiondir/$SESSION_NAME
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 8 [in process_client_msg() at main.c:2080]
DEBUG1: Waiting for 1 URIs from client ... [in process_client_msg() at main.c:2583]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG1: Tracing session s1 created in (null) with ID 0 by UID 0 GID 0 [in session_create() at session.c:236]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG3: Created hashtable size 4 at 0x64ab90 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Setting trace directory path from URI to /cluster/temp/sessiondir/s1 [in add_uri_to_consumer() at cmd.c:396]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
Session s1 created.
Traces will be written in /cluster/temp/sessiondir/s1
[SC-1:Wed Oct 03 14:44:41/cluster/temp/sessiondir] # lttng enable-event "ust_tests_demo2:l*" -u --filter ' intfield > 3'
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 6 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG1: Creating UST session [in create_ust_session() at main.c:1967]
DEBUG3: Created hashtable size 4 at 0x64be90 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x64c150 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x64c410 of type 0 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x651730 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:1922]
DEBUG3: Created hashtable size 4 at 0x656a50 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x656d10 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Copy session consumer subdir /ust [in copy_session_consumer() at main.c:1937]
DEBUG1: Spawning consumerd [in spawn_consumerd() at main.c:1629]
DEBUG2: Consumer pid 20634 [in start_consumerd() at main.c:1800]
DEBUG2: Spawning consumer control thread [in start_consumerd() at main.c:1803]
DEBUG1: [thread] Manage consumer started [in thread_manage_consumer() at main.c:833]
DEBUG1: Using 64-bit UST consumer at: /usr/lib64/lttng/libexec/lttng-consumerd [in spawn_consumerd() at main.c:1703]
DEBUG2: Receiving code from consumer err_sock [in thread_manage_consumer() at main.c:905]
DEBUG1: Consumer command socket ready [in thread_manage_consumer() at main.c:926]
DEBUG3: Consumer socket created (fd: 24) 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 s1 [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 0x6586b0 of type 0 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x658970 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 0 [in ust_app_create_channel_glb() at ust-app.c:1985]
DEBUG2: UST app pid: 20616 session id 0 not found, creating it [in create_ust_app_session() at ust-app.c:964]
DEBUG3: Created hashtable size 4 at 0x659c90 of type 0 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Shadow copy of session handle -1 [in shadow_copy_session() at ust-app.c:873]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG2: UST app session created successfully with handle 2 [in create_ust_app_session() at ust-app.c:990]
DEBUG3: Created hashtable size 4 at 0x65ad00 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x65afa0 of type 0 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: UST app channel channel0 allocated [in alloc_ust_app_channel() at ust-app.c:301]
DEBUG2: UST app shadow copy of channel channel0 started [in shadow_copy_channel() at ust-app.c:813]
DEBUG3: UST app shadow copy of channel channel0 done [in shadow_copy_channel() at ust-app.c:850]
DEBUG2: UST app channel channel0 created successfully for pid:20616 and sock:20 [in create_ust_channel() at ust-app.c:682]
DEBUG2: UST app create channel channel0 for PID 20616 completed [in create_ust_app_channel() at ust-app.c:1240]
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:l* [in trace_ust_find_event_by_name() at trace-ust.c:79]
DEBUG3: Created hashtable size 4 at 0x65b4c0 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Trace UST event ust_tests_demo2:l*, loglevel (0,-1) created [in trace_ust_create_event() at trace-ust.c:284]
DEBUG1: UST app creating event ust_tests_demo2:l* for all apps for session id 0 [in ust_app_create_event_glb() at ust-app.c:2109]
DEBUG3: Created hashtable size 4 at 0x65baf0 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: UST app event ust_tests_demo2:l* allocated [in alloc_ust_app_event() at ust-app.c:336]
DEBUG2: UST app event ust_tests_demo2:l* created successfully for pid:20616 [in create_ust_event() at ust-app.c:726]
DEBUG2: UST app create event ust_tests_demo2:l* for PID 20616 completed [in create_ust_app_event() at ust-app.c:1291]
DEBUG1: Event UST ust_tests_demo2:l* 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:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
UST event ust_tests_demo2:l* created in channel channel0
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 29 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 24) and added to output [in consumer_create_socket() at consumer.c:141]
DEBUG1: Receiving var len data from client ... [in process_client_msg() at main.c:2754]
DEBUG2: Trace UST channel channel0 found by name [in trace_ust_find_channel_by_name() at trace-ust.c:47]
DEBUG2: Trace UST event found by name ust_tests_demo2:l* [in trace_ust_find_event_by_name() at trace-ust.c:74]
DEBUG2: UST app adding context to event ust_tests_demo2:l* [in set_ust_app_event_filter() at ust-app.c:1096]
DEBUG2: UST filter set successfully for event ust_tests_demo2:l* [in set_ust_event_filter() at ust-app.c:464]
DEBUG1: Filter UST added to event ust_tests_demo2:l* [in add_ufilter_to_event() at filter.c:63]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
Filter ' intfield > 3' successfully set
[SC-1:Wed Oct 03 14:44:45/cluster/temp/sessiondir] # lttng enable-event "ust_tests_demo2:loop" -u --filter ' intfield < 3'
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 6 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 24) and added to output [in consumer_create_socket() at consumer.c:141]
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 0x65c010 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 0 [in ust_app_create_event_glb() at ust-app.c:2109]
DEBUG3: Created hashtable size 4 at 0x65c640 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG3: UST app event ust_tests_demo2:loop allocated [in alloc_ust_app_event() at ust-app.c:336]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG2: UST app create event ust_tests_demo2:loop for PID 20616 completed [in create_ust_app_event() at ust-app.c:1291]
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:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
UST event ust_tests_demo2:loop created in channel channel0
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 29 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 24) and added to output [in consumer_create_socket() at consumer.c:141]
DEBUG1: Receiving var len data from client ... [in process_client_msg() at main.c:2754]
DEBUG2: Trace UST channel channel0 found by name [in trace_ust_find_channel_by_name() at trace-ust.c:47]
DEBUG2: Trace UST event found by name ust_tests_demo2:loop [in trace_ust_find_event_by_name() at trace-ust.c:74]
DEBUG2: UST app adding context to event ust_tests_demo2:loop [in set_ust_app_event_filter() at ust-app.c:1096]
DEBUG1: Sending response (size: 16, retcode: Invalid filter bytecode) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
Error: Invalid filter bytecode
Error: Setting filter for event ust_tests_demo2:loop: ' intfield < 3'
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 3 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 24) and added to output [in consumer_create_socket() at consumer.c:141]
DEBUG2: Trace UST channel channel0 found by name [in trace_ust_find_channel_by_name() at trace-ust.c:47]
DEBUG2: Trace UST event found by name ust_tests_demo2:loop [in trace_ust_find_event_by_name() at trace-ust.c:74]
DEBUG1: UST app disabling event ust_tests_demo2:loop for all apps in channel channel0 for session id 0 [in ust_app_disable_event_glb() at ust-app.c:1862]
Error: UST app event ust_tests_demo2:loop disable failed for app (pid: 20616) and session handle 2 with ret -22
DEBUG1: Sending response (size: 16, retcode: Disable UST event failed) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
Error: Disabling event ust_tests_demo2:loop after filter error: Disable UST event failed
[SC-1:Wed Oct 03 14:44:49/cluster/temp/sessiondir] # lttng start $SESSION_NAME;
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 16 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG2: Setting relayd for session s1 [in setup_relayd() at cmd.c:630]
DEBUG1: Starting all UST traces [in ust_app_start_trace_all() at ust-app.c:2452]
DEBUG1: Starting tracing for ust app pid 20616 [in ust_app_start_trace() at ust-app.c:2163]
DEBUG3: mkdir() recursive /cluster/temp/sessiondir/s1 with mode 504 for uid 0 and gid 0 [in run_as_mkdir_recursive() at runas.c:342]
DEBUG1: Using run_as_clone [in run_as() at runas.c:325]
DEBUG2: UST metadata opened for app pid 20616 [in create_ust_app_metadata() at ust-app.c:1332]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1083]
DEBUG2: UST metadata stream object created for app pid 20616 [in create_ust_app_metadata() at ust-app.c:1350]
DEBUG2: UST stream 1 ready (handle: 7) [in ust_app_start_trace() at ust-app.c:2253]
DEBUG2: UST stream 2 ready (handle: 8) [in ust_app_start_trace() at ust-app.c:2253]
DEBUG2: UST stream 3 ready (handle: 9) [in ust_app_start_trace() at ust-app.c:2253]
DEBUG2: UST stream 4 ready (handle: 10) [in ust_app_start_trace() at ust-app.c:2253]
DEBUG1: Sending metadata stream fd to consumer on 24 [in ust_consumer_send_session() at ust-consumer.c:301]
DEBUG1: UST consumer sending metadata stream fd [in send_metadata() at ust-consumer.c:205]
DEBUG3: mkdir() recursive /cluster/temp/sessiondir/s1//ust/demo-20616-20121003-144445/ with mode 504 for uid 0 and gid 0 [in run_as_mkdir_recursive() at runas.c:342]
DEBUG1: Using run_as_clone [in run_as() at runas.c:325]
DEBUG1: Sending streams of channel channel0 to UST consumer [in send_channel_streams() at ust-consumer.c:130]
DEBUG2: Sending channel channel0 to UST consumer [in send_channel() at ust-consumer.c:48]
DEBUG3: UST local consumer tracefile path: /cluster/temp/sessiondir/s1//ust/demo-20616-20121003-144445/ [in send_channel_streams() at ust-consumer.c:147]
DEBUG2: Sending stream 31 of channel channel0 to kernel consumer [in send_channel_stream() at ust-consumer.c:90]
DEBUG2: Sending stream 33 of channel channel0 to kernel consumer [in send_channel_stream() at ust-consumer.c:90]
DEBUG2: Sending stream 35 of channel channel0 to kernel consumer [in send_channel_stream() at ust-consumer.c:90]
DEBUG2: Sending stream 37 of channel channel0 to kernel consumer [in send_channel_stream() at ust-consumer.c:90]
DEBUG1: UST consumer channel streams sent [in send_channel_streams() at ust-consumer.c:171]
DEBUG1: consumer fds (metadata and channel streams) sent [in ust_consumer_send_session() at ust-consumer.c:331]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
Tracing started for session s1
[SC-1:Wed Oct 03 14:44:53/cluster/temp/sessiondir] # lttng list $SESSION_NAME
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 13 [in process_client_msg() at main.c:2080]
DEBUG1: Counting number of available session for UID 0 GID 0 [in lttng_sessions_count() at main.c:2051]
DEBUG1: Getting all available session for UID 0 GID 0 [in cmd_list_lttng_sessions() at cmd.c:2024]
DEBUG1: Sending response (size: 4388, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
Tracing session s1: [active]
Trace path: /cluster/temp/sessiondir/s1

DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 11 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [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:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
=== Domain: UST global ===

DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 10 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 24) 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:1940]
DEBUG1: Listing channels for session s1 [in list_lttng_channels() at cmd.c:124]
DEBUG1: Sending response (size: 624, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
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:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 12 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [in session_find_by_name() at session.c:122]
DEBUG3: Consumer socket created (fd: 24) 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 2 events [in list_lttng_ust_global_events() at cmd.c:204]
DEBUG1: Sending response (size: 1184, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]

Events:
ust_tests_demo2:l* (type: tracepoint) [enabled] [with filter]
ust_tests_demo2:loop (type: tracepoint) [enabled] [with filter]

[SC-1:Wed Oct 03 14:44:58/cluster/temp/sessiondir] # #--- wait for demo to finish.
[SC-1:Wed Oct 03 14:45:05/cluster/temp/sessiondir] # DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 8 [in process_client_msg() at main.c:2080]
DEBUG1: Waiting for 1 URIs from client ... [in process_client_msg() at main.c:2583]
DEBUG2: Trying to find session by name _Trace_EA [in session_find_by_name() at session.c:122]
DEBUG1: Tracing session _Trace_EA created in (null) with ID 1 by UID 0 GID 0 [in session_create() at session.c:236]
DEBUG2: Trying to find session by name _Trace_EA [in session_find_by_name() at session.c:122]
DEBUG3: Created hashtable size 4 at 0x66a890 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Setting trace directory path from URI to /tmp/ [in add_uri_to_consumer() at cmd.c:396]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 9 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session _Trace_EA by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name _Trace_EA [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: Destroying session _Trace_EA [in session_destroy() at session.c:149]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Updating kernel poll set [in update_kernel_poll() at main.c:554]
DEBUG1: Thread kernel polling on 2 fds [in thread_manage_kernel() at main.c:722]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]

[SC-1:Wed Oct 03 14:45:07/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:45:07/cluster/temp/sessiondir] # ps -ef |grep demo
root 20615 25076 0 14:44 pts/0 00:00:00 /bin/sh /home/test_apps/demo-trace 60
root 20616 20615 0 14:44 pts/0 00:00:00 /home/test_apps/demo 60
root 20692 25076 0 14:45 pts/0 00:00:00 grep demo
[SC-1:Wed Oct 03 14:45:14/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:45:15/cluster/temp/sessiondir] # Tracing... done.
DEBUG1: PID 20616 unregistering with sock 20 [in ust_app_unregister() at ust-app.c:1504]
DEBUG1: Apps thread polling on 2 fds [in thread_manage_apps() at main.c:1083]
DEBUG3: Call RCU deleting app PID 20616 [in delete_ust_app_rcu() at ust-app.c:240]
DEBUG2: Trace UST destroy metadata 5 [in trace_ust_destroy_metadata() at trace-ust.c:492]
DEBUG2: UST app pid 20616 deleted [in delete_ust_app() at ust-app.c:223]

[2]+ Done /home/test_apps/demo-trace 60
[SC-1:Wed Oct 03 14:45:32/cluster/temp/sessiondir] # ps -ef |grep demo
root 20721 25076 0 14:45 pts/0 00:00:00 grep demo
[SC-1:Wed Oct 03 14:45:36/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:45:37/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:45:37/cluster/temp/sessiondir] # babeltrace --no-delta /cluster/temp/sessiondir/$SESSION_NAME
[14:34:20.832515142] SC-1:demo:19916 ust_tests_demo2:loop: { cpu_id = 0 }, { intfield = 0, intfield2 = 0x0, longfield = 0, netintfield = 0, netintfieldhex = 0x0, arrfield1 = [ [0] = 1, [1] = 2, [2] = 3 ], arrfield2 = "test", _seqfield1_length = 4, seqfield1 = [ [0] = 116, [1] = 101, [2] = 115, [3] = 116 ], _seqfield2_length = 4, seqfield2 = "test", stringfield = "test", floatfield = 2222, doublefield = 2 }
[14:34:20.832525115] SC-1:demo:19916 ust_tests_demo2:loop: { cpu_id = 0 }, { intfield = 1, intfield2 = 0x1, longfield = 1, netintfield = 1, netintfieldhex = 0x1, arrfield1 = [ [0] = 1, [1] = 2, [2] = 3 ], arrfield2 = "test", _seqfield1_length = 4, seqfield1 = [ [0] = 116, [1] = 101, [2] = 115, [3] = 116 ], _seqfield2_length = 4, seqfield2 = "test", stringfield = "test", floatfield = 2222, doublefield = 2 }
[14:34:20.832526618] SC-1:demo:19916 ust_tests_demo2:loop: { cpu_id = 0 }, { intfield = 2, intfield2 = 0x2, longfield = 2, netintfield = 2, netintfieldhex = 0x2, arrfield1 = [ [0] = 1, [1] = 2, [2] = 3 ], arrfield2 = "test", _seqfield1_length = 4, seqfield1 = [ [0] = 116, [1] = 101, [2] = 115, [3] = 116 ], _seqfield2_length = 4, seqfield2 = "test", stringfield = "test", floatfield = 2222, doublefield = 2 }
[14:34:20.832527675] SC-1:demo:19916 ust_tests_demo2:loop: { cpu_id = 0 }, { intfield = 4, intfield2 = 0x4, longfield = 4, netintfield = 4, netintfieldhex = 0x4, arrfield1 = [ [0] = 1, [1] = 2, [2] = 3 ], arrfield2 = "test", _seqfield1_length = 4, seqfield1 = [ [0] = 116, [1] = 101, [2] = 115, [3] = 116 ], _seqfield2_length = 4, seqfield2 = "test", stringfield = "test", floatfield = 2222, doublefield = 2 }
[14:45:31.168708536] SC-1:demo:20616 ust_tests_demo2:loop: { cpu_id = 0 }, { intfield = 4, intfield2 = 0x4, longfield = 4, netintfield = 4, netintfieldhex = 0x4, arrfield1 = [ [0] = 1, [1] = 2, [2] = 3 ], arrfield2 = "test", _seqfield1_length = 4, seqfield1 = [ [0] = 116, [1] = 101, [2] = 115, [3] = 116 ], _seqfield2_length = 4, seqfield2 = "test", stringfield = "test", floatfield = 2222, doublefield = 2 }
[SC-1:Wed Oct 03 14:45:42/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:45:44/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:45:44/cluster/temp/sessiondir] # DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 8 [in process_client_msg() at main.c:2080]
DEBUG1: Waiting for 1 URIs from client ... [in process_client_msg() at main.c:2583]
DEBUG2: Trying to find session by name _Trace_EA [in session_find_by_name() at session.c:122]
DEBUG1: Tracing session _Trace_EA created in (null) with ID 2 by UID 0 GID 0 [in session_create() at session.c:236]
DEBUG2: Trying to find session by name _Trace_EA [in session_find_by_name() at session.c:122]
DEBUG3: Created hashtable size 4 at 0x640340 of type 1 [in lttng_ht_new() at hashtable.c:96]
DEBUG2: Setting trace directory path from URI to /tmp/ [in add_uri_to_consumer() at cmd.c:396]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 9 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session _Trace_EA by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name _Trace_EA [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: Destroying session _Trace_EA [in session_destroy() at session.c:149]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Updating kernel poll set [in update_kernel_poll() at main.c:554]
DEBUG1: Thread kernel polling on 2 fds [in thread_manage_kernel() at main.c:722]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]

[SC-1:Wed Oct 03 14:46:08/cluster/temp/sessiondir] # more /cluster/temp/sessiondir/s1/ust/demo-
demo-19916-20121003-143415/ demo-20616-20121003-144445/
[SC-1:Wed Oct 03 14:46:08/cluster/temp/sessiondir] # more /cluster/temp/sessiondir/s1/ust/demo-
demo-19916-20121003-143415/ demo-20616-20121003-144445/
[SC-1:Wed Oct 03 14:46:08/cluster/temp/sessiondir] # more /cluster/temp/sessiondir/s1/ust/demo-20616-20121003-144445/metadata
\?u0???M?C?
typealias integer { size = 16; align = 8; signed = false; } := uint16_t;
typealias integer { size = 32; align = 8; signed = false; } := uint32_t;
typealias integer { size = 64; align = 8; signed = false; } := uint64_t;
typealias integer { size = 64; align = 8; signed = false; } := unsigned long;
typealias integer { size = 5; align = 1; signed = false; } := uint5_t;
typealias integer { size = 27; align = 1; signed = false; } := uint27_t;

trace {
major = 1;
minor = 8;
uuid = "30e9a78e-c0e3-4da4-9a92-43fc160d5c13";
byte_order = le;
packet.header := struct {
uint32_t magic;
uint8_t uuid[16];
uint32_t stream_id;
};
};

env {
hostname = "SC-1";
vpid = 20616;
procname = "demo";
domain = "ust";
tracer_name = "lttng-ust";
tracer_major = 2;
tracer_minor = 1;
tracer_patchlevel = 0;
};

clock {
name = monotonic;
uuid = "3c061ab1-5e8e-4096-b414-19d38aab4489";
description = "Monotonic Clock";
freq = 1000000000; /* Frequency, in Hz */
/* clock value offset from Epoch is: offset * (1/freq) */
offset = 1349119606332266577;
};

typealias integer {
size = 27; align = 1; signed = false;
map = clock.monotonic.value;
} := uint27_clock_monotonic_t;

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

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

struct packet_context {
uint64_clock_monotonic_t timestamp_begin;
uint64_clock_monotonic_t timestamp_end;
uint64_t content_size;
uint64_t packet_size;
unsigned long events_discarded;
uint32_t cpu_id;
};

struct event_header_compact {
enum : uint5_t { compact = 0 ... 30, extended = 31 } id;
variant <id> {
struct {
uint27_clock_monotonic_t timestamp;
} compact;
struct {
uint32_t id;
uint64_clock_monotonic_t timestamp;
} extended;
} v;
} align(8);

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

stream {
id = 0;
event.header := struct event_header_compact;
packet.context := struct packet_context;
};

event {
name = "ust_tests_demo2:loop";
id = 0;
stream_id = 0;
loglevel = 4;
fields := struct {
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _intfield;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 16; } _intfield2;
integer { size = 64; align = 8; signed = 1; encoding = none; base = 10; } _longfield;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; byte_order = be; } _netintfield;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 16; byte_order = be; } _netintfieldhex;
integer { size = 64; align = 8; signed = 1; encoding = none; base = 10; } _arrfield1[3];
integer { size = 8; align = 8; signed = 1; encoding = UTF8; base = 10; } _arrfield2[10];
integer { size = 64; align = 8; signed = 0; encoding = none; base = 10; } __seqfield1_length;
integer { size = 8; align = 8; signed = 1; encoding = none; base = 10; } _seqfield1[ __seqfield1_length ];
integer { size = 64; align = 8; signed = 0; encoding = none; base = 10; } __seqfield2_length;
integer { size = 8; align = 8; signed = 1; encoding = UTF8; base = 10; } _seqfield2[ __seqfield2_length ];
string _stringfield;
floating_point { exp_dig = 8; mant_dig = 24; align = 8; } _floatfield;
floating_point { exp_dig = 11; mant_dig = 53; align = 8; } _doublefield;
};
};


[SC-1:Wed Oct 03 14:46:48/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:46:50/cluster/temp/sessiondir] # lttng destroy $SESSION_NAME
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Nothing recv() from client... continuing [in thread_manage_clients() at main.c:3116]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
DEBUG1: Receiving data from client ... [in thread_manage_clients() at main.c:3112]
DEBUG1: Processing client command 9 [in process_client_msg() at main.c:2080]
DEBUG1: Getting session s1 by name [in process_client_msg() at main.c:2150]
DEBUG2: Trying to find session by name s1 [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:2504]
DEBUG2: Trace UST destroy session 0 [in trace_ust_destroy_session() at trace-ust.c:574]
DEBUG1: Destroying session s1 [in session_destroy() at session.c:149]
DEBUG1: Sending response (size: 16, retcode: Success) [in thread_manage_clients() at main.c:3163]
DEBUG1: Updating kernel poll set [in update_kernel_poll() at main.c:554]
DEBUG1: Thread kernel polling on 2 fds [in thread_manage_kernel() at main.c:722]
DEBUG1: Clean command context structure [in clean_command_ctx() at main.c:473]
DEBUG1: Accepting client command ... [in thread_manage_clients() at main.c:3031]
Session s1 destroyed
[SC-1:Wed Oct 03 14:46:56/cluster/temp/sessiondir] # DEBUG2: Trace destroy UST channel channel0 [in trace_ust_destroy_channel() at trace-ust.c:457]
DEBUG2: Trace destroy UST event ust_tests_demo2:l* [in trace_ust_destroy_event() at trace-ust.c:415]
DEBUG2: Trace destroy UST event ust_tests_demo2:loop [in trace_ust_destroy_event() at trace-ust.c:415]

[SC-1:Wed Oct 03 14:46:58/cluster/temp/sessiondir] # rm -rf /cluster/temp/sessiondir/$SESSION_NAME
[SC-1:Wed Oct 03 14:47:03/cluster/temp/sessiondir] # dir /cluster/temp/sessiondir/
total 0
[SC-1:Wed Oct 03 14:47:03/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:47:05/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:47:05/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:47:05/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:47:05/cluster/temp/sessiondir] # DEBUG1: Wait for client response [in thread_manage_clients() at main.c:3073]
*** glibc detected *** lttng-sessiond: corrupted double-linked list: 0x0000000000657360 ***
======= Backtrace: =========
/lib64/libc.so.6(+0x75558)[0x7f014f640558]
/lib64/libc.so.6(+0x75b3f)[0x7f014f640b3f]
/lib64/libc.so.6(+0x77f2e)[0x7f014f642f2e]
/lib64/libc.so.6(__libc_calloc+0xc8)[0x7f014f644bc8]
lttng-sessiond[0x418ce6]
/lib64/libpthread.so.0(+0x77b6)[0x7f014f9467b6]
/lib64/libc.so.6(clone+0x6d)[0x7f014f6a2bcd]
======= Memory map: ========
00400000-00434000 r-xp 00000000 00:0e 578732 /usr/bin/lttng-sessiond
00633000-00634000 r--p 00033000 00:0e 578732 /usr/bin/lttng-sessiond
00634000-0063b000 rw-p 00034000 00:0e 578732 /usr/bin/lttng-sessiond
0063b000-00681000 rw-p 00000000 00:00 0 [heap]
7f01381b5000-7f01381b6000 ---p 00000000 00:00 0
7f01381b6000-7f01389b6000 rw-p 00000000 00:00 0
7f013c000000-7f013c021000 rw-p 00000000 00:00 0
7f013c021000-7f0140000000 ---p 00000000 00:00 0
7f01419b6000-7f01419b7000 ---p 00000000 00:00 0
7f01419b7000-7f01421b7000 rw-p 00000000 00:00 0
7f01431b7000-7f01431b8000 rw-p 00000000 00:00 0
7f01431b8000-7f01441b7000 ---p 00000000 00:00 0
7f01441b7000-7f01441b8000 rw-p 00000000 00:00 0
7f01441b8000-7f01451b7000 ---p 00000000 00:00 0
7f0149fa1000-7f0149fb6000 r-xp 00000000 00:0e 23464 /lib64/libgcc_s.so.1
7f0149fb6000-7f014a1b5000 ---p 00015000 00:0e 23464 /lib64/libgcc_s.so.1
7f014a1b5000-7f014a1b6000 r--p 00014000 00:0e 23464 /lib64/libgcc_s.so.1
7f014a1b6000-7f014a1b7000 rw-p 00015000 00:0e 23464 /lib64/libgcc_s.so.1
7f014a1b7000-7f014a1b8000 ---p 00000000 00:00 0
7f014a1b8000-7f014a9b8000 rw-p 00000000 00:00 0
7f014a9b8000-7f014a9b9000 ---p 00000000 00:00 0
7f014a9b9000-7f014b1b9000 rw-p 00000000 00:00 0
7f014b1b9000-7f014b1ba000 ---p 00000000 00:00 0
7f014b1ba000-7f014b9ba000 rw-p 00000000 00:00 0
7f014b9ba000-7f014b9bb000 ---p 00000000 00:00 0
7f014b9bb000-7f014c1bb000 rw-p 00000000 00:00 0
7f014c1bb000-7f014c1bc000 ---p 00000000 00:00 0
7f014c1bc000-7f014c9bc000 rw-p 00000000 00:00 0
7f014c9bc000-7f014c9bd000 ---p 00000000 00:00 0
7f014c9bd000-7f014d1be000 rw-p 00000000 00:00 0
7f014d1be000-7f014e1bd000 ---p 00000000 00:00 0
7f014e1bd000-7f014e1be000 rw-p 00000000 00:00 0
7f014e1be000-7f014f1bd000 ---p 00000000 00:00 0
7f014f1bd000-7f014f1c3000 r-xp 00000000 00:0e 578756 /usr/lib64/liburcu-cds.so.1.0.0
7f014f1c3000-7f014f3c2000 ---p 00006000 00:0e 578756 /usr/lib64/liburcu-cds.so.1.0.0
7f014f3c2000-7f014f3c3000 r--p 00005000 00:0e 578756 /usr/lib64/liburcu-cds.so.1.0.0
7f014f3c3000-7f014f3c4000 rw-p 00006000 00:0e 578756 /usr/lib64/liburcu-cds.so.1.0.0
7f014f3c4000-7f014f3c9000 r-xp 00000000 00:0e 578753 /usr/lib64/liburcu-bp.so.1.0.0
7f014f3c9000-7f014f5c9000 ---p 00005000 00:0e 578753 /usr/lib64/liburcu-bp.so.1.0.0
7f014f5c9000-7f014f5ca000 r--p 00005000 00:0e 578753 /usr/lib64/liburcu-bp.so.1.0.0
7f014f5ca000-7f014f5cb000 rw-p 00006000 00:0e 578753 /usr/lib64/liburcu-bp.so.1.0.0
7f014f5cb000-7f014f736000 r-xp 00000000 00:0e 23599 /lib64/libc-2.11.3.so
7f014f736000-7f014f935000 ---p 0016b000 00:0e 23599 /lib64/libc-2.11.3.so
7f014f935000-7f014f939000 r--p 0016a000 00:0e 23599 /lib64/libc-2.11.3.so
7f014f939000-7f014f93a000 rw-p 0016e000 00:0e 23599 /lib64/libc-2.11.3.so
7f014f93a000-7f014f93f000 rw-p 00000000 00:00 0
7f014f93f000-7f014f956000 r-xp 00000000 00:0e 23549 /lib64/libpthread-2.11.3.so
7f014f956000-7f014fb56000 ---p 00017000 00:0e 23549 /lib64/libpthread-2.11.3.so
7f014fb56000-7f014fb57000 r--p 00017000 00:0e 23549 /lib64/libpthread-2.11.3.so
7f014fb57000-7f014fb58000 rw-p 00018000 00:0e 23549 /lib64/libpthread-2.11.3.so
7f014fb58000-7f014fb5c000 rw-p 00000000 00:00 0
7f014fb5c000-7f014fb64000 r-xp 00000000 00:0e 23474 /lib64/libpopt.so.0.0.0
7f014fb64000-7f014fd63000 ---p 00008000 00:0e 23474 /lib64/libpopt.so.0.0.0
7f014fd63000-7f014fd64000 r--p 00007000 00:0e 23474 /lib64/libpopt.so.0.0.0
7f014fd64000-7f014fd65000 rw-p 00008000 00:0e 23474 /lib64/libpopt.so.0.0.0
7f014fd65000-7f014fd89000 r-xp 00000000 00:0e 578738 /usr/lib64/liblttng-ust-ctl.so.0.0.0
7f014fd89000-7f014ff88000 ---p 00024000 00:0e 578738 /usr/lib64/liblttng-ust-ctl.so.0.0.0
7f014ff88000-7f014ff89000 r--p 00023000 00:0e 578738 /usr/lib64/liblttng-ust-ctl.so.0.0.0
7f014ff89000-7f014ff8a000 rw-p 00024000 00:0e 578738 /usr/lib64/liblttng-ust-ctl.so.0.0.0
7f014ff8a000-7f014ff8f000 r-xp 00000000 00:0e 578771 /usr/lib64/liburcu.so.1.0.0
7f014ff8f000-7f015018e000 ---p 00005000 00:0e 578771 /usr/lib64/liburcu.so.1.0.0
7f015018e000-7f015018f000 r--p 00004000 00:0e 578771 /usr/lib64/liburcu.so.1.0.0
7f015018f000-7f0150190000 rw-p 00005000 00:0e 578771 /usr/lib64/liburcu.so.1.0.0
7f0150190000-7f0150191000 r-xp 00000000 00:0e 578759 /usr/lib64/liburcu-common.so.1.0.0
7f0150191000-7f0150391000 ---p 00001000 00:0e 578759 /usr/lib64/liburcu-common.so.1.0.0
7f0150391000-7f0150392000 r--p 00001000 00:0e 578759 /usr/lib64/liburcu-common.so.1.0.0
7f0150392000-7f0150393000 rw-p 00002000 00:0e 578759 /usr/lib64/liburcu-common.so.1.0.0
7f0150393000-7f01503af000 r-xp 00000000 00:0e 578735 /usr/lib64/liblttng-ctl.so.0.0.0
7f01503af000-7f01505af000 ---p 0001c000 00:0e 578735 /usr/lib64/liblttng-ctl.so.0.0.0
7f01505af000-7f01505b0000 r--p 0001c000 00:0e 578735 /usr/lib64/liblttng-ctl.so.0.0.0
7f01505b0000-7f01505b1000 rw-p 0001d000 00:0e 578735 /usr/lib64/liblttng-ctl.so.0.0.0
7f01505b1000-7f01505b3000 rw-p 00000000 00:00 0
7f01505b3000-7f01505bb000 r-xp 00000000 00:0e 23645 /lib64/librt-2.11.3.so
7f01505bb000-7f01507ba000 ---p 00008000 00:0e 23645 /lib64/librt-2.11.3.so
[SC-1:Wed Oct 03 14:47:05/cluster/temp/sessiondir] #
[1]+ Aborted (core dumped) lttng-sessiond -vvv
[SC-1:Wed Oct 03 14:47:38/cluster/temp/sessiondir] #
[SC-1:Wed Oct 03 14:47:41/cluster/temp/sessiondir] # dir /cluster/dumps/
total 5432
-rw------- 1 root root 68620288 Oct 3 13:33 lttng-sessiond.10775.SC-1.core
-rw------- 1 root root 59498496 Oct 3 13:56 lttng-sessiond.16682.SC-1.core
-rw------- 1 root root 68026368 Oct 3 14:13 lttng-sessiond.17205.SC-1.core
-rw------- 1 root root 67899392 Oct 3 14:29 lttng-sessiond.19029.SC-1.core
-rw------- 1 root root 68018176 Oct 3 14:47 lttng-sessiond.20588.SC-1.core
-rw------- 1 root root 68374528 Oct 3 12:09 lttng-sessiond.7705.SC-1.core
[SC-1:Wed Oct 03 14:47:51/cluster/temp/sessiondir] #
    (1-1/1)