gistfile1.txt

➜  test  ./run 
Session auto-20130915-214809 created.
Traces will be written in /home/zifeitong/lttng-traces/auto-20130915-214809
UST event test:call_foo created in channel channel0
Tracing started for session auto-20130915-214809
Hello World!
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
./run: line 6:  9765 Bus error               ./a.out
Waiting for data availability
Tracing stopped for session auto-20130915-214809
Trace directory: /home/zifeitong/lttng-traces/auto-20130915-214809

[21:48:10.027983582] (+?.?????????) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.027992454] (+0.000008872) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.027994888] (+0.000002434) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.027996715] (+0.000001827) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.027998740] (+0.000002025) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.028000637] (+0.000001897) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.028002440] (+0.000001803) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.028004360] (+0.000001920) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.028006121] (+0.000001761) localhost test:call_foo: { cpu_id = 2 }, { }
[21:48:10.028007910] (+0.000001789) localhost test:call_foo: { cpu_id = 2 }, { }

gistfile2.txt

Spawning a session daemon
Session auto-20130923-233918 created.
Traces will be written in /home/zifeitong/lttng-traces/auto-20130923-233918
UST event test:call_foo created in channel channel0
Tracing started for session auto-20130923-233918
Hello World!
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
I am foo()
Waiting for data availability.
Tracing stopped for session auto-20130923-233918
Trace directory: /home/zifeitong/lttng-traces/auto-20130923-233918

[23:39:20.532657537] (+?.?????????) localhost test:call_foo_entry: { cpu_id = 2 }, { }
[23:39:21.532754457] (+1.000096920) localhost test:call_foo_exit: { cpu_id = 3 }, { }
[23:39:21.532760289] (+0.000005832) localhost test:call_foo_entry: { cpu_id = 3 }, { }
[23:39:22.532848166] (+1.000087877) localhost test:call_foo_exit: { cpu_id = 3 }, { }
[23:39:22.532851235] (+0.000003069) localhost test:call_foo_entry: { cpu_id = 3 }, { }
[23:39:23.532936283] (+1.000085048) localhost test:call_foo_exit: { cpu_id = 3 }, { }
[23:39:23.532939040] (+0.000002757) localhost test:call_foo_entry: { cpu_id = 3 }, { }
[23:39:24.533033391] (+1.000094351) localhost test:call_foo_exit: { cpu_id = 2 }, { }
[23:39:24.533037037] (+0.000003646) localhost test:call_foo_entry: { cpu_id = 2 }, { }
[23:39:25.533123069] (+1.000086032) localhost test:call_foo_exit: { cpu_id = 2 }, { }
[23:39:25.533126223] (+0.000003154) localhost test:call_foo_entry: { cpu_id = 2 }, { }
[23:39:26.533212264] (+1.000086041) localhost test:call_foo_exit: { cpu_id = 2 }, { }
[23:39:26.533215238] (+0.000002974) localhost test:call_foo_entry: { cpu_id = 2 }, { }
[23:39:27.533306851] (+1.000091613) localhost test:call_foo_exit: { cpu_id = 2 }, { }
[23:39:27.533310410] (+0.000003559) localhost test:call_foo_entry: { cpu_id = 2 }, { }
[23:39:28.533397817] (+1.000087407) localhost test:call_foo_exit: { cpu_id = 2 }, { }
[23:39:28.533400931] (+0.000003114) localhost test:call_foo_entry: { cpu_id = 2 }, { }
[23:39:29.533491360] (+1.000090429) localhost test:call_foo_exit: { cpu_id = 2 }, { }
[23:39:29.533494346] (+0.000002986) localhost test:call_foo_entry: { cpu_id = 2 }, { }
[23:39:30.533581583] (+1.000087237) localhost test:call_foo_exit: { cpu_id = 2 }, { }

run

#!/bin/sh
gcc test.c -llttng-ust
lttng create
lttng enable-event test:call_foo -u --function=a.out@foo
lttng start
./a.out
lttng stop
sleep 1
lttng view

test.c

#include <stdio.h>

void foo() {
    printf("I am foo()\n");
    return;
}

void bar() {
    printf("I am bar()\n");
    return;
}

int main()
{
    int i;
    printf("Hello World!\n");
    for (i = 0; i < 10; ++i)
        foo();
    return 0;
}