Project

General

Profile

Bug #487 » Terminal.log

Terminal log when TC is executed - Tan le tran, 03/27/2013 11:46 AM

 
[SC-1:Xen43 Tue Mar 26 17:51:20/cluster/temp/tdlt/SDP/EA_GB_Mar26_PM] # cd /cluster/temp/log
[SC-1:Xen43 Tue Mar 26 17:53:03/cluster/temp/log] # dir
total 0
[SC-1:Xen43 Tue Mar 26 17:53:04/cluster/temp/log] # ps -ef |grep lttng
root 14216 1 0 17:49 ? 00:00:00 lttng-sessiond -d
root 14664 21812 0 17:53 pts/3 00:00:00 grep lttng
[SC-1:Xen43 Tue Mar 26 17:53:11/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:53:12/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:53:12/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:53:12/cluster/temp/log] # net
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
[SC-1:Xen43 Tue Mar 26 17:53:14/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:53:14/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:53:15/cluster/temp/log] # lttng list
Currently no available tracing session
[SC-1:Xen43 Tue Mar 26 17:53:19/cluster/temp/log] # export TC=ses1
[SC-1:Xen43 Tue Mar 26 17:53:26/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:53:26/cluster/temp/log] # ps -ef |egrep 'lttng'
netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
lttng-relayd -C tcp://0.0.0.0:53000 -D tcp://0.0.0.0:53001 -o /cluster/temp/log/$TC &
netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
root 14216 1 0 17:49 ? 00:00:00 lttng-sessiond -d
root 14685 21812 0 17:53 pts/3 00:00:00 egrep lttng
[SC-1:Xen43 Tue Mar 26 17:53:26/cluster/temp/log] # netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
[SC-1:Xen43 Tue Mar 26 17:53:26/cluster/temp/log] # lttng-relayd -C tcp://0.0.0.0:53000 -D tcp://0.0.0.0:53001 -o /cluster/temp/log/$TC &
[1] 14690
[SC-1:Xen43 Tue Mar 26 17:53:26/cluster/temp/log] # netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
tcp 0 0 0.0.0.0:53000 0.0.0.0:* LISTEN 0 312536239 14690/lttng-relayd
tcp 0 0 0.0.0.0:53001 0.0.0.0:* LISTEN 0 312536240 14690/lttng-relayd
[SC-1:Xen43 Tue Mar 26 17:53:27/cluster/temp/log] # time lttng list
time lttng list
Currently no available tracing session

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:54:25/cluster/temp/log] # time lttng create $TC -U net://192.168.0.1:53000:53001/./
sleep 1
time lttng enable-channel -u --buffers-uid chan1
sleep 1
time lttng enable-event -s $TC "com*" -u -c chan1
Session ses1 created.
Traces will be written in net://192.168.0.1:53000:53001/./

real 0m0.002s
user 0m0.000s
sys 0m0.004s
[SC-1:Xen43 Tue Mar 26 17:54:25/cluster/temp/log] # time lttng list
sleep 1
time lttng list $TC
sleep 1
time lttng start;
Available tracing sessions:
1) ses1 (net://192.168.0.1:53000/./ [data: 5343]) [inactive]

Use lttng list <session_name> for more details

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:54:25/cluster/temp/log] # sleep 1
sleep 1
time lttng list
[SC-1:Xen43 Tue Mar 26 17:54:26/cluster/temp/log] # time lttng enable-channel -u --buffers-uid chan1
UST channel chan1 enabled for session ses1

real 0m0.005s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:54:26/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:54:27/cluster/temp/log] # time lttng enable-event -s $TC "com*" -u -c chan1
UST event com* created in channel chan1

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:54:27/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:54:28/cluster/temp/log] # time lttng list $TC
Tracing session ses1: [inactive]
Trace path: net://192.168.0.1:53000/./ [data: 53001]

=== Domain: UST global ===

Channels:
-------------
- chan1: [enabled]

Attributes:
overwrite mode: 0
subbufers size: 4096
number of subbufers: 4
switch timer interval: 0
read timer interval: 200000
output: mmap()

Events:
com* (type: tracepoint) [enabled]


real 0m0.003s
user 0m0.000s
sys 0m0.004s
[SC-1:Xen43 Tue Mar 26 17:54:28/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:54:29/cluster/temp/log] # time lttng start;
Tracing started for session ses1

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:54:29/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:54:30/cluster/temp/log] # time lttng list
Available tracing sessions:
1) ses1 (net://192.168.0.1:53000/./ [data: 53001]) [active]

Use lttng list <session_name> for more details

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:54:30/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:54:34/cluster/temp/log] # lttng list -u
UST events:
-------------
None

[SC-1:Xen43 Tue Mar 26 17:54:37/cluster/temp/log] # ps -ef |grep TestApp
root 14793 21812 0 17:54 pts/3 00:00:00 grep TestApp
[SC-1:Xen43 Tue Mar 26 17:54:43/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:54:44/cluster/temp/log] # /home/test_apps/TestApp_type1 50000 np &
[2] 14808
[SC-1:Xen43 Tue Mar 26 17:54:58/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:54:59/cluster/temp/log] # lttng list -u
UST events:
-------------

PID: 14808 - Name: /home/test_apps/TestApp_type1
com_ericsson_cba_trace_testapp_hightraf:TenKiloPerSec (loglevel: TRACE_DEBUG (14)) (type: tracepoint)
com_ericsson_cba_trace_testapp_hightraf:KiloPerSec (loglevel: TRACE_DEBUG (14)) (type: tracepoint)
com_ericsson_cba_trace_testapp_hightraf:HundredPerSec (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:TenPerSecB (loglevel: TRACE_DEBUG_UNIT (11)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA (loglevel: TRACE_DEBUG_MODULE (10)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:OnePerSecB (loglevel: TRACE_DEBUG_PROCESS (9)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:OnePerSecA (loglevel: TRACE_DEBUG_PROGRAM (8)) (type: tracepoint)
com_ericsson_cba:AfterAllLoops (loglevel: TRACE_ERR (3)) (type: tracepoint)
com_ericsson_cba:cba_domain_event (loglevel: TRACE_ERR (3)) (type: tracepoint)
com_ericsson_cba_trace:trace_domain_event (loglevel: TRACE_WARNING (4)) (type: tracepoint)
com_ericsson_cba_trace_testapp:pointer_to_structure_in_tracepoint (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)
com_ericsson_cba_trace_testapp:testapp_domain_event (loglevel: TRACE_NOTICE (5)) (type: tracepoint)

[SC-1:Xen43 Tue Mar 26 17:55:05/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:55:06/cluster/temp/log] # dir
total 4
drwxr-x--- 3 root root 4096 Mar 26 17:54 ses1
[SC-1:Xen43 Tue Mar 26 17:55:09/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:54 metadata
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_0
[SC-1:Xen43 Tue Mar 26 17:55:12/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:54 metadata
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_0
[SC-1:Xen43 Tue Mar 26 17:55:15/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:54 metadata
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_0
[SC-1:Xen43 Tue Mar 26 17:55:17/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:54 metadata
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_0
[SC-1:Xen43 Tue Mar 26 17:55:19/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:54 metadata
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_0
[SC-1:Xen43 Tue Mar 26 17:55:25/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:54 metadata
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:54 chan1_0
[SC-1:Xen43 Tue Mar 26 17:55:28/cluster/temp/log] # lttng list ses1
Tracing session ses1: [active]
Trace path: net://192.168.0.1:53000/./ [data: 53001]

=== Domain: UST global ===

Channels:
-------------
- chan1: [enabled]

Attributes:
overwrite mode: 0
subbufers size: 4096
number of subbufers: 4
switch timer interval: 0
read timer interval: 200000
output: mmap()

Events:
com* (type: tracepoint) [enabled]

[SC-1:Xen43 Tue Mar 26 17:55:35/cluster/temp/log] # lttng stop
Waiting for data availability
Tracing stopped for session ses1
[SC-1:Xen43 Tue Mar 26 17:55:43/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:55:44/cluster/temp/log] # lttng list ses1
Tracing session ses1: [inactive]
Trace path: net://192.168.0.1:53000/./ [data: 53001]

=== Domain: UST global ===

Channels:
-------------
- chan1: [enabled]

Attributes:
overwrite mode: 0
subbufers size: 4096
number of subbufers: 4
switch timer interval: 0
read timer interval: 200000
output: mmap()

Events:
com* (type: tracepoint) [enabled]

[SC-1:Xen43 Tue Mar 26 17:55:45/cluster/temp/log] # net
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
tcp 0 0 0.0.0.0:53000 0.0.0.0:* LISTEN 0 312536239 14690/lttng-relayd
tcp 0 0 0.0.0.0:53001 0.0.0.0:* LISTEN 0 312536240 14690/lttng-relayd
tcp 0 0 192.168.0.1:42907 192.168.0.1:53001 ESTABLISHED 0 312535456 14761/lttng-consume
tcp 0 0 192.168.0.1:48200 192.168.0.1:53000 ESTABLISHED 0 312535455 14761/lttng-consume
tcp 0 0 192.168.0.1:53000 192.168.0.1:48200 ESTABLISHED 0 312536303 14690/lttng-relayd
tcp 0 0 192.168.0.1:53001 192.168.0.1:42907 ESTABLISHED 0 312536304 14690/lttng-relayd
[SC-1:Xen43 Tue Mar 26 17:55:50/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:55:53/cluster/temp/log] # lttng destroy
Session ses1 destroyed
[SC-1:Xen43 Tue Mar 26 17:55:57/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:55:58/cluster/temp/log] # net
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
tcp 0 0 0.0.0.0:53000 0.0.0.0:* LISTEN 0 312536239 14690/lttng-relayd
tcp 0 0 0.0.0.0:53001 0.0.0.0:* LISTEN 0 312536240 14690/lttng-relayd
[SC-1:Xen43 Tue Mar 26 17:56:00/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:01/cluster/temp/log] # lttng list
Currently no available tracing session
[SC-1:Xen43 Tue Mar 26 17:56:04/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:05/cluster/temp/log] # pkill relayd
[SC-1:Xen43 Tue Mar 26 17:56:10/cluster/temp/log] #
[1]- Done lttng-relayd -C tcp://0.0.0.0:53000 -D tcp://0.0.0.0:53001 -o /cluster/temp/log/$TC
[SC-1:Xen43 Tue Mar 26 17:56:11/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:11/cluster/temp/log] # net
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
[SC-1:Xen43 Tue Mar 26 17:56:13/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:14/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:14/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
chan1_0 chan1_1 chan1_2 chan1_3 metadata
[SC-1:Xen43 Tue Mar 26 17:56:14/cluster/temp/log] # dir ses1/SC-1/ust/uid/0/64-bit/
total 44
-rwxr-xr-x 1 root root 12288 Mar 26 17:55 metadata
-rwxr-xr-x 1 root root 8192 Mar 26 17:55 chan1_3
-rwxr-xr-x 1 root root 8192 Mar 26 17:55 chan1_2
-rwxr-xr-x 1 root root 8192 Mar 26 17:55 chan1_1
-rwxr-xr-x 1 root root 8192 Mar 26 17:55 chan1_0
[SC-V??JF!Tue Mar 26 17:56:34/cluster/temp/log] # more ses1/SC-1/ust/uid/0/64-bit/metadata
typealias integer { size = 8; align = 8; signed = false; } := uint8_t;it/metadata
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 = "c28d56ec-cd4a-4621-80d1-7f9c1dc1b208";
byte_order = le;
packet.header := struct {
uint32_t magic;
uint8_t uuid[16];
uint32_t stream_id;
};
};

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

clock {
name = monotonic;
uuid = "7c002362-f7ce-4efe-a709-602ba5f131fb";
description = "Monotonic Clock";
freq = 1000000000; /* Frequency, in Hz */
/* clock value offset from Epoch is: offset * (1/freq) */
offset = 1362003185089525629;
};

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 = "com_ericsson_cba_trace_testapp_hightraf:HundredPerSec";
id = 0;
stream_id = 0;
loglevel = 13;
fields := struct {
string _my_Time;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _TenPs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _HundredPs;
floating_point { exp_dig = 8; mant_dig = 24; align = 8; } _unit_float;
string _unit_string;
};
};

event {
name = "com_ericsson_cba_trace_testapp_hightraf:KiloPerSec";
id = 1;
stream_id = 0;
loglevel = 14;
fields := struct {
string _my_Time;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _TenPs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _HundredPs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _KiloPs;
floating_point { exp_dig = 8; mant_dig = 24; align = 8; } _unit_float;
string _unit_string;
};
};

event {
name = "com_ericsson_cba_trace_testapp_hightraf:TenKiloPerSec";
id = 2;
stream_id = 0;
loglevel = 14;
fields := struct {
string _my_Time;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _TenPs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _HundredPs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _KiloPs;
integer { size = V??JF!?Ht { exp_dig = 8; mant_dig = 24; align = 8; } _unit_float;Ps;
string _unit_string;
};
};

event {
name = "com_ericsson_cba_trace_testapp_lowtraf:OnePerSecA";
id = 3;
stream_id = 0;
loglevel = 8;
fields := struct {
string _my_Time;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs;
};
};

event {
name = "com_ericsson_cba_trace_testapp_lowtraf:OnePerSecB";
id = 4;
stream_id = 0;
loglevel = 9;
fields := struct {
string _my_Time;
};
};

event {
name = "com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA";
id = 5;
stream_id = 0;
loglevel = 10;
fields := struct {
string _my_Time;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _TenPs;
floating_point { exp_dig = 8; mant_dig = 24; align = 8; } _unit_float;
string _unit_string;
string _stringAllSigns;
};
};

event {
name = "com_ericsson_cba_trace_testapp_lowtraf:TenPerSecB";
id = 6;
stream_id = 0;
loglevel = 11;
fields := struct {
string _my_Time;
};
};

event {
name = "com_ericsson_cba:cba_domain_event";
id = 7;
stream_id = 0;
loglevel = 3;
fields := struct {
string _my_Time;
string _CallingFrom;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs_in_Integer;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 16; } _OnePs_in_Hex;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _incrementMe;
};
};

event {
name = "com_ericsson_cba:AfterAllLoops";
id = 8;
stream_id = 0;
loglevel = 3;
fields := struct {
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _incrementMe;
};
};

event {
name = "com_ericsson_cba_trace:trace_domain_event";
id = 9;
stream_id = 0;
loglevel = 4;
fields := struct {
string _my_Time;
string _CallingFrom;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs;
integer { size = 64; align = 8; signed = 1; encoding = none; base = 10; } _Using_Array[2];
integer { size = 64; align = 8; signed = 0; encoding = none; base = 10; } __Using_sequence_length;
integer { size = 64; align = 8; signed = 1; encoding = none; base = 10; } _Using_sequence[ __Using_sequence_length ];
integer { size = 8; align = 8; signed = 1; encoding = none; base = 10; } _array[2];
integer { size = 8; align = 8; signed = 1; encoding = UTF8; base = 10; } _array_text[2];
};
};

event {
name = "com_ericsson_cba_trace_testapp:testapp_domain_event";
id = 10;
stream_id = 0;
loglevel = 5;
fields := struct {
string _my_Time;
string _CallingFrom;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _OnePs;
floating_point { exp_dig = 8; mant_dig = 24; align = 8; } _ValueOfPi;
};
};

event {
name = "com_ericsson_cba_trace_testapp:pointer_to_structure_in_tracepoint";
id = 11;
stream_id = 0;
loglevel = 13;
fields := struct {
string _my_Time;
string _Sub_Name;
string _sub_IMSI;
floating_point { exp_dig = 8; mant_dig = 24; align = 8; } _Sub_balance;
integer { size = 32; align = 8; signed = 1; encoding = none; base = 10; } _Sub_TimeRemaining;
};
};
V??JF!?[SC-1:Xen43 Tue Mar 26 17:56:35/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:36/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:37/cluster/temp/log] # babeltrace ses1/SC-1/ust/uid/0/64-bit/
chan1_0 chan1_1 chan1_2 chan1_3 metadata
[SC-1:Xen43 Tue Mar 26 17:56:37/cluster/temp/log] # babeltrace ses1/SC-1/ust/uid/0/64-bit/
[SC-1:Xen43 Tue Mar 26 17:56:53/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:56:54/cluster/temp/log] # babeltrace ses1/SC-1/ust/uid/0/
[SC-1:Xen43 Tue Mar 26 17:56:57/cluster/temp/log] # babeltrace ses1/SC-1/ust/
[SC-1:Xen43 Tue Mar 26 17:56:59/cluster/temp/log] # babeltrace ses1/SC-1/
[SC-1:Xen43 Tue Mar 26 17:57:02/cluster/temp/log] # babeltrace ses1/
[SC-1:Xen43 Tue Mar 26 17:57:04/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:57:05/cluster/temp/log] # ps -ef |grep lttng
root 14216 1 0 17:49 ? 00:00:00 lttng-sessiond -d
root 14761 14216 1 17:54 ? 00:00:02 lttng-consumerd --quiet -u --consumerd-cmd-sock /var/run/lttng/ustconsumerd64/command --consumerd-err-sock /var/run/lttng/ustconsumerd64/error
root 14985 21812 0 17:57 pts/3 00:00:00 grep lttng
[SC-1:Xen43 Tue Mar 26 17:57:23/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:57:31/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:57:31/cluster/temp/log] # dir /cluster/dumps/
total 29532
-rw------- 1 root root 21942272 Feb 28 18:41 trace_c.12571.SC-1.core
-rw------- 1 root root 20017152 Mar 5 05:54 trace_c.8873.SC-1.core
-rw------- 1 root root 19673088 Mar 5 08:11 trace_c.12280.SC-1.core
-rw------- 1 root root 37220352 Mar 13 01:17 lttng-consumerd.10182.SC-1.core
-rw------- 1 root root 19460096 Mar 13 01:58 trace_c.19116.SC-1.core
-rw------- 1 root root 39223296 Mar 18 16:17 lttng-consumerd.19578.SC-1.core
-rw------- 1 root root 35909632 Mar 20 10:09 lttng-consumerd.12584.PL-4.core
-rw------- 1 root root 36569088 Mar 20 10:09 lttng-consumerd.1469.SC-2.core
-rw------- 1 root root 36110336 Mar 20 10:09 lttng-consumerd.12627.PL-3.core
-rw------- 1 root root 21610496 Mar 20 12:07 trace_c.19417.SC-1.core
-rw------- 1 root root 1712128 Mar 20 12:28 babeltrace.10017.SC-1.core
-rw------- 1 root root 17747968 Mar 26 13:49 TestApp_100psOn.16792.SC-1.core
-rw------- 1 root root 17747968 Mar 26 14:20 TestApp_100psOn.18478.SC-1.core
-rw------- 1 root root 17747968 Mar 26 14:21 TestApp_100psOn.19818.SC-1.core
-rw------- 1 root root 17747968 Mar 26 14:22 TestApp_type1.19833.SC-1.core
-rw------- 1 root root 17747968 Mar 26 14:28 TestApp_100psOn.20326.SC-1.core
[SC-1:Xen43 Tue Mar 26 17:57:36/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:17/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:17/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:17/cluster/temp/log] # export TC=ses2
[SC-1:Xen43 Tue Mar 26 17:58:18/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:18/cluster/temp/log] # ps -ef |egrep 'lttng'
netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
lttng-relayd -C tcp://0.0.0.0:53000 -D tcp://0.0.0.0:53001 -o /cluster/temp/log/$TC &
netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
root 14216 1 0 17:49 ? 00:00:00 lttng-sessiond -d
root 14761 14216 1 17:54 ? 00:00:02 lttng-consumerd --quiet -u --consumerd-cmd-sock /var/run/lttng/ustconsumerd64/command --consumerd-err-sock /var/run/lttng/ustconsumerd64/error
root 15044 21812 0 17:58 pts/3 00:00:00 egrep lttng
[SC-1:Xen43 Tue Mar 26 17:58:19/cluster/temp/log] # netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
[SC-1:Xen43 Tue Mar 26 17:58:19/cluster/temp/log] # lttng-relayd -C tcp://0.0.0.0:53000 -D tcp://0.0.0.0:53001 -o /cluster/temp/log/$TC &
[3] 15049
[SC-1:Xen43 Tue Mar 26 17:58:19/cluster/temp/log] # netstat -etanp | head -n 2 ; netstat -etanp |egrep lttng
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
tcp 0 0 0.0.0.0:53000 0.0.0.0:* LISTEN 0 312536917 15049/lttng-relayd
tcp 0 0 0.0.0.0:53001 0.0.0.0:* LISTEN 0 312536918 15049/lttng-relayd
[SC-1:Xen43 Tue Mar 26 17:58:19/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:22/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:23/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:23/cluster/temp/log] # lttng list -u
UST events:
-------------

PID: 14808 - Name: /home/test_apps/TestApp_type1
com_ericsson_cba_trace_testapp_hightraf:TenKiloPerSec (loglevel: TRACE_DEBUG (14)) (type: tracepoint)
com_ericsson_cba_trace_testapp_hightraf:KiloPerSec (loglevel: TRACE_DEBUG (14)) (type: tracepoint)
com_ericsson_cba_trace_testapp_hightraf:HundredPerSec (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:TenPerSecB (loglevel: TRACE_DEBUG_UNIT (11)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA (loglevel: TRACE_DEBUG_MODULE (10)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:OnePerSecB (loglevel: TRACE_DEBUG_PROCESS (9)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:OnePerSecA (loglevel: TRACE_DEBUG_PROGRAM (8)) (type: tracepoint)
com_ericsson_cba:AfterAllLoops (loglevel: TRACE_ERR (3)) (type: tracepoint)
com_ericsson_cba:cba_domain_event (loglevel: TRACE_ERR (3)) (type: tracepoint)
com_ericsson_cba_trace:trace_domain_event (loglevel: TRACE_WARNING (4)) (type: tracepoint)
com_ericsson_cba_trace_testapp:pointer_to_structure_in_tracepoint (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)
com_ericsson_cba_trace_testapp:testapp_domain_event (loglevel: TRACE_NOTICE (5)) (type: tracepoint)

[SC-1:Xen43 Tue Mar 26 17:58:43/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:44/cluster/temp/log] # time lttng list
time lttng list
Currently no available tracing session

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:46/cluster/temp/log] # time lttng create $TC -U net://192.168.0.1:53000:53001/./
sleep 1
time lttng enable-channel -u --buffers-uid chan1
Session ses2 created.
Traces will be written in net://192.168.0.1:53000:53001/./

real 0m0.003s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:46/cluster/temp/log] # time lttng list
Available tracing sessions:
sleep 1
1) ses2 (net://192.168.0.1:53000/./ [data: 5343]) [inactive]

Use lttng list <session_name> for more details

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:46/cluster/temp/log] # sleep 1
time lttng enable-event -s $TC -u -c chan1 "com*"
sleep 1
time lttng list $TC
sleep 1
time lttng start;
sleep 1
time lttng list
[SC-1:Xen43 Tue Mar 26 17:58:47/cluster/temp/log] # time lttng enable-channel -u --buffers-uid chan1
UST channel chan1 enabled for session ses2

real 0m0.072s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:47/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:58:48/cluster/temp/log] # time lttng enable-event -s $TC -u -c chan1 "com*"
UST event com* created in channel chan1

real 0m0.002s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:48/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:58:49/cluster/temp/log] # time lttng list $TC
Tracing session ses2: [inactive]
Trace path: net://192.168.0.1:53000/./ [data: 53001]

=== Domain: UST global ===

Channels:
-------------
- chan1: [enabled]

Attributes:
overwrite mode: 0
subbufers size: 4096
number of subbufers: 4
switch timer interval: 0
read timer interval: 200000
output: mmap()

Events:
com* (type: tracepoint) [enabled]


real 0m0.003s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:49/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:58:50/cluster/temp/log] # time lttng start;
Tracing started for session ses2

real 0m0.005s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:50/cluster/temp/log] # sleep 1
[SC-1:Xen43 Tue Mar 26 17:58:51/cluster/temp/log] # time lttng list
Available tracing sessions:
1) ses2 (net://192.168.0.1:53000/./ [data: 53001]) [active]

Use lttng list <session_name> for more details

real 0m0.003s
user 0m0.000s
sys 0m0.000s
[SC-1:Xen43 Tue Mar 26 17:58:51/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 17:58:55/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
chan1_0 chan1_1 chan1_2 chan1_3 metadata
[SC-1:Xen43 Tue Mar 26 17:58:55/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:00/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:04/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:06/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:07/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:43/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:44/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:46/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:47/cluster/temp/log] # dir ses2/SC-1/ust/uid/0/64-bit/
total 0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_3
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_2
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_1
-rwxr-xr-x 1 root root 0 Mar 26 17:58 chan1_0
-rwxr-xr-x 1 root root 0 Mar 26 17:58 metadata
[SC-1:Xen43 Tue Mar 26 17:59:49/cluster/temp/log] # lttng list -u
UST events:
-------------

PID: 14808 - Name: /home/test_apps/TestApp_type1
com_ericsson_cba_trace_testapp_hightraf:TenKiloPerSec (loglevel: TRACE_DEBUG (14)) (type: tracepoint)
com_ericsson_cba_trace_testapp_hightraf:KiloPerSec (loglevel: TRACE_DEBUG (14)) (type: tracepoint)
com_ericsson_cba_trace_testapp_hightraf:HundredPerSec (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:TenPerSecB (loglevel: TRACE_DEBUG_UNIT (11)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA (loglevel: TRACE_DEBUG_MODULE (10)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:OnePerSecB (loglevel: TRACE_DEBUG_PROCESS (9)) (type: tracepoint)
com_ericsson_cba_trace_testapp_lowtraf:OnePerSecA (loglevel: TRACE_DEBUG_PROGRAM (8)) (type: tracepoint)
com_ericsson_cba:AfterAllLoops (loglevel: TRACE_ERR (3)) (type: tracepoint)
com_ericsson_cba:cba_domain_event (loglevel: TRACE_ERR (3)) (type: tracepoint)
com_ericsson_cba_trace:trace_domain_event (loglevel: TRACE_WARNING (4)) (type: tracepoint)
com_ericsson_cba_trace_testapp:pointer_to_structure_in_tracepoint (loglevel: TRACE_DEBUG_LINE (13)) (type: tracepoint)
com_ericsson_cba_trace_testapp:testapp_domain_event (loglevel: TRACE_NOTICE (5)) (type: tracepoint)

[SC-1:Xen43 Tue Mar 26 17:59:54/cluster/temp/log] # lttng list
Available tracing sessions:
1) ses2 (net://192.168.0.1:53000/./ [data: 53001]) [active]

Use lttng list <session_name> for more details
[SC-1:Xen43 Tue Mar 26 18:00:06/cluster/temp/log] # net
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
tcp 0 0 0.0.0.0:53000 0.0.0.0:* LISTEN 0 312536917 15049/lttng-relayd
tcp 0 0 0.0.0.0:53001 0.0.0.0:* LISTEN 0 312536918 15049/lttng-relayd
tcp 0 0 192.168.0.1:42909 192.168.0.1:53001 ESTABLISHED 0 312537502 14761/lttng-consume
tcp 0 0 192.168.0.1:53001 192.168.0.1:42909 ESTABLISHED 0 312536996 15049/lttng-relayd
tcp 0 0 192.168.0.1:53000 192.168.0.1:48202 ESTABLISHED 0 312536995 15049/lttng-relayd
tcp 0 0 192.168.0.1:48202 192.168.0.1:53000 ESTABLISHED 0 312537500 14761/lttng-consume
[SC-1:Xen43 Tue Mar 26 18:00:12/cluster/temp/log] # lttng stop
Waiting for data availability
Tracing stopped for session ses2
[SC-1:Xen43 Tue Mar 26 18:00:17/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 18:00:18/cluster/temp/log] # lttng destroy
Session ses2 destroyed
[SC-1:Xen43 Tue Mar 26 18:00:22/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 18:00:22/cluster/temp/log] # net
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name
tcp 0 0 0.0.0.0:53000 0.0.0.0:* LISTEN 0 312536917 15049/lttng-relayd
tcp 0 0 0.0.0.0:53001 0.0.0.0:* LISTEN 0 312536918 15049/lttng-relayd
[SC-1:Xen43 Tue Mar 26 18:00:35/cluster/temp/log] # pkill relayd
[SC-1:Xen43 Tue Mar 26 18:00:40/cluster/temp/log] #
[3]+ Done lttng-relayd -C tcp://0.0.0.0:53000 -D tcp://0.0.0.0:53001 -o /cluster/temp/log/$TC
[SC-1:Xen43 Tue Mar 26 18:00:40/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 18:00:41/cluster/temp/log] # ps -ef |grep lttng
root 14216 1 0 17:49 ? 00:00:00 lttng-sessiond -d
root 14761 14216 1 17:54 ? 00:00:06 lttng-consumerd --quiet -u --consumerd-cmd-sock /var/run/lttng/ustconsumerd64/command --consumerd-err-sock /var/run/lttng/ustconsumerd64/error
root 15255 21812 0 18:00 pts/3 00:00:00 grep lttng
[SC-1:Xen43 Tue Mar 26 18:00:48/cluster/temp/log] #
[SC-1:Xen43 Tue Mar 26 18:04:57/cluster/temp/log] # lttng list
Currently no available tracing session
[SC-1:Xen43 Tue Mar 26 18:05:00/cluster/temp/log] #
(1-1/7)