Re: Can't get any event output from the demo exe

Re: Can't get any event output from the demo exe

[Jonathan Rajotte Julien]

Hi Wolfgang,

See inline edit.

On 2015-03-16 02:50 PM, Wolfgang Rostek wrote:
> Hi group,
> I'm a bit frustrated not passing the hello world level.
> But I'm eager to get access to this nice tooling.
We'll make sure this happen :).

> For now I'm assuming problems in access rights or
> may be a 32/64bit library mismatch. Here my stackoverflow

On session creation you can use -o to specify an output PATH.

> post which wasn't responded. Hope I'll get a push here.
> thanks
> Wolfgang R.
> ========================================================
> LTTng at the very beginning
> just at step zero of using lttng but can't pass it. Hope a small hint 
> gives me a push.
> Trying the run the most simple demo of the distribution 
> lttng-ust/doc/examples/demo.

Okai so just to be sure I tested the demo and everything work as 
expected let's see what is missing on your side.
Did you build from source or you got binary from a package manager ?

> cat README
> ...
> The simplest command to trace the demo program are:
> lttng create
> lttng enable-event -u -a
> lttng start
> ./demo-trace
> lttng destroy
> That will create a trace in your $HOME/lttng-traces directory.
> ...
> Put my user lttng-sessiond in verbose mode and can talk to it. Here 
> the state when the demo-trace starts

What do you mean by talk to it?

> row@cobra7:/$ lttng list auto-20150313-091148
> Tracing session auto-20150313-091148: [active]
>     Trace path: /home/local/ESG_GMBH/row/lttng-traces/auto-20150313-091148
> === Domain: UST global ===
> Buffer type: per UID
> Channels:
> -------------
> - channel0: [enabled]
>     Attributes:
>       overwrite mode: 0
>       subbufers size: 131072
>       number of subbufers: 4
>       switch timer interval: 0
>       read timer interval: 0
>       output: mmap()
>     Events:
>       * (type: tracepoint) [enabled]

Looks fine so far.

> Executing the demo-trace shows no reaction in the daemon shell and no 
> output file created. Below the client log.
> I can't see any problem reported and don't know what to check next.

Are you sure you got nothing ? Try lttng view if you have babeltrace 
installed.
Also did you start the daemon in verbose mode ? Otherwise it is expected 
to have no output.

> thanks in advance for feedback
> Wolfgang R.
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ export 
> LD_LIBRARY_PATH=/lib:/usr/lib:/usr/local/lib

Any particuliar reason to do an export LD_LIBRARY_PATH?

> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ ./demo-trace

If you want to have more time to test you might want to add a sleep() 
into the for loop in demo.c and put a higher loop threshold.
You will be able to test lttng list -u. Even if it looks like the events 
got registered, it is a good start to pinpoint the problem.
Also you can start the sessiond with -vvv for a more verbose mode as you 
probably know it.


Cheers!
> liblttng_ust_tracepoint[17268/17268]: just registered a tracepoints 
> section from 0xb754f780 and having 2 tracepoints (in 
> tracepoint_register_lib() at tracepoint.c:767)
> liblttng_ust_tracepoint[17268/17268]: registered tracepoint: 
> ust_baddr_statedump:soinfo (in tracepoint_register_lib() at 
> tracepoint.c:772)
> liblttng_ust_tracepoint[17268/17268]: registered tracepoint: 
> lttng_ust_tracef:event (in tracepoint_register_lib() at tracepoint.c:772)
> libust[17268/17268]: Provider "ust_baddr_statedump" accepted, version 
> 1.0 is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: adding probe ust_baddr_statedump containing 1 
> events to lazy registration list (in lttng_probe_register() at 
> lttng-probes.c:216)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-metadata-mmap" init
> (in lttng_ring_buffer_metadata_client_init() at 
> lttng-ring-buffer-metadata-client.h:330)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-overwrite-mmap" init
> (in lttng_ring_buffer_client_overwrite_init() at 
> lttng-ring-buffer-client.h:681)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-overwrite-rt-mmap" init
> (in lttng_ring_buffer_client_overwrite_rt_init() at 
> lttng-ring-buffer-client.h:681)
> libust[17268/17268]: LTT : ltt ring buffer client "relay-discard-mmap" 
> init
> (in lttng_ring_buffer_client_discard_init() at 
> lttng-ring-buffer-client.h:681)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-discard-rt-mmap" init
> (in lttng_ring_buffer_client_discard_rt_init() at 
> lttng-ring-buffer-client.h:681)
> libust[17268/17269]: Info: sessiond not accepting connections to 
> global apps socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
> libust[17268/17269]: Waiting for global apps sessiond (in 
> wait_for_sessiond() at lttng-ust-comm.c:1132)
> libust[17268/17270]: Info: sessiond not accepting connections to local 
> apps socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
> libust[17268/17270]: Waiting for local apps sessiond (in 
> wait_for_sessiond() at lttng-ust-comm.c:1132)
> libust[17268/17268]: Provider "lttng_ust_tracef" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just registered probe ust_baddr_statedump 
> containing 1 events (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[17268/17268]: adding probe lttng_ust_tracef containing 1 events 
> to lazy registration list (in lttng_probe_register() at 
> lttng-probes.c:216)
> libust[17268/17268]: Provider "ust_tests_demo3" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just registered probe lttng_ust_tracef containing 
> 1 events (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[17268/17268]: adding probe ust_tests_demo3 containing 1 events 
> to lazy registration list (in lttng_probe_register() at 
> lttng-probes.c:216)
> libust[17268/17268]: Provider "ust_tests_demo" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just registered probe ust_tests_demo3 containing 
> 1 events (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[17268/17268]: adding probe ust_tests_demo containing 2 events 
> to lazy registration list (in lttng_probe_register() at 
> lttng-probes.c:216)
> libust[17268/17268]: Provider "ust_tests_demo2" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just registered probe ust_tests_demo containing 2 
> events (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[17268/17268]: adding probe ust_tests_demo2 containing 1 events 
> to lazy registration list (in lttng_probe_register() at 
> lttng-probes.c:216)
> liblttng_ust_tracepoint[17268/17268]: just registered a tracepoints 
> section from 0x804b144 and having 4 tracepoints (in 
> tracepoint_register_lib() at tracepoint.c:767)
> liblttng_ust_tracepoint[17268/17268]: registered tracepoint: 
> ust_tests_demo:starting (in tracepoint_register_lib() at tracepoint.c:772)
> liblttng_ust_tracepoint[17268/17268]: registered tracepoint: 
> ust_tests_demo:done (in tracepoint_register_lib() at tracepoint.c:772)
> liblttng_ust_tracepoint[17268/17268]: registered tracepoint: 
> ust_tests_demo2:loop (in tracepoint_register_lib() at tracepoint.c:772)
> liblttng_ust_tracepoint[17268/17268]: registered tracepoint: 
> ust_tests_demo3:done (in tracepoint_register_lib() at tracepoint.c:772)
> Demo program starting.
> Tracing...  done.
> liblttng_ust_tracepoint[17268/17268]: just unregistered a tracepoints 
> section from 0x804b144 (in tracepoint_unregister_lib() at 
> tracepoint.c:797)
> libust[17268/17268]: Provider "ust_tests_demo2" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just unregistered probe ust_tests_demo2 (in 
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[17268/17268]: Provider "ust_tests_demo" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just unregistered probe ust_tests_demo (in 
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[17268/17268]: Provider "ust_tests_demo3" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just unregistered probe ust_tests_demo3 (in 
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[17268/17268]: Provider "lttng_ust_tracef" accepted, version 1.0 
> is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just unregistered probe lttng_ust_tracef (in 
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-discard-rt-mmap" exit
> (in lttng_ring_buffer_client_discard_rt_exit() at 
> lttng-ring-buffer-client.h:688)
> libust[17268/17268]: LTT : ltt ring buffer client "relay-discard-mmap" 
> exit
> (in lttng_ring_buffer_client_discard_exit() at 
> lttng-ring-buffer-client.h:688)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-overwrite-rt-mmap" exit
> (in lttng_ring_buffer_client_overwrite_rt_exit() at 
> lttng-ring-buffer-client.h:688)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-overwrite-mmap" exit
> (in lttng_ring_buffer_client_overwrite_exit() at 
> lttng-ring-buffer-client.h:688)
> libust[17268/17268]: LTT : ltt ring buffer client 
> "relay-metadata-mmap" exit
> (in lttng_ring_buffer_metadata_client_exit() at 
> lttng-ring-buffer-metadata-client.h:337)
> libust[17268/17268]: Provider "ust_baddr_statedump" accepted, version 
> 1.0 is compatible with LTTng UST provider version 1.0. (in 
> check_provider_version() at lttng-probes.c:174)
> libust[17268/17268]: just unregistered probe ust_baddr_statedump (in 
> lttng_probe_unregister() at lttng-probes.c:245)
> liblttng_ust_tracepoint[17268/17268]: just unregistered a tracepoints 
> section from 0xb754f780 (in tracepoint_unregister_lib() at 
> tracepoint.c:797)
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$
>
>
> _______________________________________________
> lttng-dev mailing list
> lttng-dev@lists.lttng.org
> http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev

-- 
Jonathan R. Julien
Efficios

Fw: Re: Can't get any event output from the demo exe

[Wolfgang Rostek]

[-- Attachment #1: Type: text/html, Size: 15695 bytes --]

[-- Attachment #2: Type: text/plain, Size: 155 bytes --]

_______________________________________________
lttng-dev mailing list
lttng-dev@lists.lttng.org
http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev

Fw: Re: Can't get any event output from the demo exe

[Wolfgang Rostek]

[-- Attachment #1: Type: text/html, Size: 18230 bytes --]

[-- Attachment #2: Type: text/plain, Size: 155 bytes --]

_______________________________________________
lttng-dev mailing list
lttng-dev@lists.lttng.org
http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev

Re: Can't get any event output from the demo exe

[Wolfgang Rostek]

Hi Jonathan,
 
as I'm struggling with the Internet style response I'll recap it here
 
> On session creation you can use -o to specify an output PATH.
Have tried to redirect to /tmp but no output there as well
 
> Did you build from source or you got binary from a package manager ?
yes, via git clone etc.
 
> What do you mean by talk to it?
I see daemon logs in response to the lttng commands. The exception is
that there is no reaction for the demo-trace run.
 
> Any particuliar reason to do an export LD_LIBRARY_PATH?
Ok, is no longer needed.
 
I've added the sleep but provider output is still like in my original post.
 
Below the steps I run again. Is it possible to get more info from the
demo-trace provider? Is the marked line ok?
 
thanks
Wolfgang R.
 
 
row@cobra7:~$ lsb_release -sd
Ubuntu 14.04.1 LTS

row@cobra7:~$ file /sbin/init
/sbin/init: ELF 32-bit LSB  shared object, Intel 80386, version 1 (SYSV), dynamically linked (uses shared libs), for GNU/Linux 2.6.24, BuildID[sha1]=c394677bccc720a3bb4f4c42a48e008ff33e39b1, stripped
=> 32bit


row@cobra7:~$ lttng-sessiond -vvv
...

row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ export LTTNG_UST_DEBUG=1
row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ lttng create
Session auto-20150317-075322 created.
Traces will be written in /home/local/ESG_GMBH/row/lttng-traces/auto-20150317-075322                       ####### not found
row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ lttng enable-event -u -a
All UST events are enabled in channel channel0

row@cobra7:~$ ps -aux | grep ltt
root      4664  0.0  0.0  84416  3376 ?        Ssl  07:51   0:00 lttng-sessiond
row       4683  0.0  0.1  76220 13788 pts/1    Sl+  07:52   0:00 lttng-sessiond -vvv
row       4709  0.0  0.0  56096   672 pts/1    Sl+  07:53   0:00 lttng-consumerd --quiet -u --consumerd-cmd-sock /home/local/ESG_GMBH/row/.lttng/ustconsumerd32/command --consumerd-err-sock /home/local/ESG_GMBH/row/.lttng/ustconsumerd32/error --group tracing

row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ lttng start
Tracing started for session auto-20150317-075322

row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ ./demo-trace
liblttng_ust_tracepoint[5381/5381]: just registered a tracepoints section from 0xb7589780 and having 2 tracepoints (in tracepoint_register_lib() at tracepoint.c:767)
...
libust[5389/5390]: Info: sessiond not accepting connections to global apps socket (in ust_listener_thread() at lttng-ust-comm.c:1221)                #########  is this OK ?
libust[5389/5390]: Waiting for global apps sessiond (in wait_for_sessiond() at lttng-ust-comm.c:1132)
libust[5389/5391]: Info: sessiond not accepting connections to local apps socket (in ust_listener_thread() at lttng-ust-comm.c:1221)               #########  is this OK ?
libust[5389/5391]: Waiting for local apps sessiond (in wait_for_sessiond() at lttng-ust-comm.c:1132)
libust[5389/5389]: Provider "lttng_ust_tracef" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-
...


row@cobra7:~$ lttng list  -u
UST events:
-------------
None
 
 

_______________________________________________
lttng-dev mailing list
lttng-dev@lists.lttng.org
http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev

Re: Can't get any event output from the demo exe

[Mathieu Desnoyers]

----- Original Message -----
> Hi Jonathan,
>  
> as I'm struggling with the Internet style response I'll recap it here
>  
> > On session creation you can use -o to specify an output PATH.
> Have tried to redirect to /tmp but no output there as well
>  
> > Did you build from source or you got binary from a package manager ?
> yes, via git clone etc.
>  
> > What do you mean by talk to it?
> I see daemon logs in response to the lttng commands. The exception is
> that there is no reaction for the demo-trace run.

I see that UST debug tells us this:

"libust[5389/5391]: Info: sessiond not accepting connections to local apps
socket (in ust_listener_thread() at lttng-ust-comm.c:1221)"

and also for global apps socket.

So it means UST cannot talk to the session daemon.

Are you UST and sessiond versions compatible ? The output of
lttng-sessiond -vvv should help us understand why UST cannot
connect to it.

Thanks,

Mathieu


>  
> > Any particuliar reason to do an export LD_LIBRARY_PATH?
> Ok, is no longer needed.
>  
> I've added the sleep but provider output is still like in my original post.
>  
> Below the steps I run again. Is it possible to get more info from the
> demo-trace provider? Is the marked line ok?
>  
> thanks
> Wolfgang R.
>  
>  
> row@cobra7:~$ lsb_release -sd
> Ubuntu 14.04.1 LTS
> 
> row@cobra7:~$ file /sbin/init
> /sbin/init: ELF 32-bit LSB  shared object, Intel 80386, version 1 (SYSV),
> dynamically linked (uses shared libs), for GNU/Linux 2.6.24,
> BuildID[sha1]=c394677bccc720a3bb4f4c42a48e008ff33e39b1, stripped
> => 32bit
> 
> 
> row@cobra7:~$ lttng-sessiond -vvv
> ...
> 
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ export
> LTTNG_UST_DEBUG=1
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ lttng create
> Session auto-20150317-075322 created.
> Traces will be written in
> /home/local/ESG_GMBH/row/lttng-traces/auto-20150317-075322
> ####### not found
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ lttng enable-event -u
> -a
> All UST events are enabled in channel channel0
> 
> row@cobra7:~$ ps -aux | grep ltt
> root      4664  0.0  0.0  84416  3376 ?        Ssl  07:51   0:00
> lttng-sessiond
> row       4683  0.0  0.1  76220 13788 pts/1    Sl+  07:52   0:00
> lttng-sessiond -vvv
> row       4709  0.0  0.0  56096   672 pts/1    Sl+  07:53   0:00
> lttng-consumerd --quiet -u --consumerd-cmd-sock
> /home/local/ESG_GMBH/row/.lttng/ustconsumerd32/command --consumerd-err-sock
> /home/local/ESG_GMBH/row/.lttng/ustconsumerd32/error --group tracing
> 
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ lttng start
> Tracing started for session auto-20150317-075322
> 
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ ./demo-trace
> liblttng_ust_tracepoint[5381/5381]: just registered a tracepoints section
> from 0xb7589780 and having 2 tracepoints (in tracepoint_register_lib() at
> tracepoint.c:767)
> ...
> libust[5389/5390]: Info: sessiond not accepting connections to global apps
> socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
> #########  is this OK ?
> libust[5389/5390]: Waiting for global apps sessiond (in wait_for_sessiond()
> at lttng-ust-comm.c:1132)
> libust[5389/5391]: Info: sessiond not accepting connections to local apps
> socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
> #########  is this OK ?
> libust[5389/5391]: Waiting for local apps sessiond (in wait_for_sessiond() at
> lttng-ust-comm.c:1132)
> libust[5389/5389]: Provider "lttng_ust_tracef" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-
> ...
> 
> 
> row@cobra7:~$ lttng list  -u
> UST events:
> -------------
> None
>  
>  
> 
> _______________________________________________
> lttng-dev mailing list
> lttng-dev@lists.lttng.org
> http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev
> 

-- 
Mathieu Desnoyers
EfficiOS Inc.
http://www.efficios.com

_______________________________________________
lttng-dev mailing list
lttng-dev@lists.lttng.org
http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev

Re: Can't get any event output from the demo exe

[Wolfgang Rostek]

Hi Mathieu,

thanks for looking at it.

Here the complete log of both sides



row@cobra7:~$ lttng create
Session auto-20150323-162735 created.
Traces will be written in /home/local/ESG_GMBH/row/lttng-traces/auto-20150323-162735


DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at main.c:3815)
DEBUG1 [2915/2921]: Receiving data from client ... (in thread_manage_clients() at main.c:3860)
DEBUG1 [2915/2921]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3864)
DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx() at main.c:608)
DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients() at main.c:3773)
DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at main.c:3815)
DEBUG1 [2915/2921]: Receiving data from client ... (in thread_manage_clients() at main.c:3860)
DEBUG1 [2915/2921]: Processing client command 8 (in process_client_msg() at main.c:2637)
DEBUG1 [2915/2921]: Waiting for 1 URIs from client ... (in process_client_msg() at main.c:3201)
DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in session_find_by_name() at session.c:169)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a05a28 of type 1 (in lttng_ht_new() at hashtable.c:127)
DEBUG1 [2915/2921]: Tracing session auto-20150323-162735 created with ID 0 by UID 1548223785 GID 1548222977 (in session_create() at session.c:276)
DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in session_find_by_name() at session.c:169)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a0ad80 of type 1 (in lttng_ht_new() at hashtable.c:127)
DEBUG2 [2915/2921]: Setting trace directory path from URI to /home/local/ESG_GMBH/row/lttng-traces/auto-20150323-162735 (in add_uri_to_consumer() at cmd.c:481)
DEBUG1 [2915/2921]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3909)
DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx() at main.c:608)
DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients() at main.c:3773)

row@cobra7:~$ lttng enable-event -u -a
All UST events are enabled in channel channel0

DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at main.c:3815)
DEBUG1 [2915/2921]: Receiving data from client ... (in thread_manage_clients() at main.c:3860)
DEBUG1 [2915/2921]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3864)
DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx() at main.c:608)
DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients() at main.c:3773)
DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at main.c:3815)
DEBUG1 [2915/2921]: Receiving data from client ... (in thread_manage_clients() at main.c:3860)
DEBUG1 [2915/2921]: Processing client command 6 (in process_client_msg() at main.c:2637)
DEBUG1 [2915/2921]: Getting session auto-20150323-162735 by name (in process_client_msg() at main.c:2716)
DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in session_find_by_name() at session.c:169)
DEBUG1 [2915/2921]: Creating UST session (in create_ust_session() at main.c:2517)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a039f0 of type 0 (in lttng_ht_new() at hashtable.c:127)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a03d18 of type 0 (in lttng_ht_new() at hashtable.c:127)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04040 of type 1 (in lttng_ht_new() at hashtable.c:127)
DEBUG2 [2915/2921]: UST trace session create successful (in trace_ust_create_session() at trace-ust.c:263)
DEBUG3 [2915/2921]: Copying tracing session consumer output in UST session (in copy_session_consumer() at main.c:2466)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04368 of type 1 (in lttng_ht_new() at hashtable.c:127)
DEBUG3 [2915/2921]: Copy session consumer subdir /ust (in copy_session_consumer() at main.c:2484)
DEBUG1 [2915/2921]: Spawning consumerd (in spawn_consumerd() at main.c:2158)
DEBUG2 [2915/2921]: Consumer pid 2987 (in start_consumerd() at main.c:2340)
DEBUG2 [2915/2921]: Spawning consumer control thread (in start_consumerd() at main.c:2343)
DEBUG3 [2915/2918]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at ht-cleanup.c:64)
DEBUG1 [2987/2987]: Using 32-bit UST consumer at: /usr/lib/i386-linux-gnu/lttng/libexec/lttng-consumerd (in spawn_consumerd() at main.c:2278)
DEBUG1 [2915/2988]: [thread] Manage consumer started (in thread_manage_consumer() at main.c:994)
DEBUG2 [2915/2988]: Receiving code from consumer err_sock (in thread_manage_consumer() at main.c:1079)
DEBUG1 [2915/2988]: Consumer command socket ready (fd: 26 (in thread_manage_consumer() at main.c:1113)
DEBUG1 [2915/2988]: Consumer metadata socket ready (fd: 27) (in thread_manage_consumer() at main.c:1115)
DEBUG3 [2915/2921]: Consumer socket created (fd: 26) and added to output (in consumer_create_socket() at consumer.c:301)
DEBUG1 [2915/2921]: Setting relayd for session auto-20150323-162735 (in cmd_setup_relayd() at cmd.c:722)
DEBUG2 [2915/2921]: Trace UST channel channel0 not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:162)
DEBUG1 [2915/2921]: Enabling channel  for session auto-20150323-162735 (in cmd_enable_channel() at cmd.c:910)
DEBUG2 [2915/2921]: Trace UST channel channel0 not found by name (in trace_ust_find_channel_by_name() at trace-ust.c:162)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04910 of type 0 (in lttng_ht_new() at hashtable.c:127)
DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04bf0 of type 1 (in lttng_ht_new() at hashtable.c:127)
DEBUG2 [2915/2921]: Trace UST channel channel0 created (in trace_ust_create_channel() at trace-ust.c:331)
DEBUG2 [2915/2921]: Channel channel0 is being created for UST with buffer 1 and id 0 (in channel_ust_create() at channel.c:348)
DEBUG2 [2915/2921]: UST app adding channel channel0 to UST domain for session id 0 (in ust_app_create_channel_glb() at ust-app.c:3536)
DEBUG2 [2915/2921]: Channel channel0 created successfully (in channel_ust_create() at channel.c:383)
DEBUG2 [2915/2921]: Trace UST channel channel0 found by name (in trace_ust_find_channel_by_name() at trace-ust.c:157)
DEBUG2 [2915/2921]: Trace UST event * NOT found (in trace_ust_find_event() at trace-ust.c:198)
DEBUG2 [2915/2921]: Trace UST event *, loglevel (0,-1) created (in trace_ust_create_event() at trace-ust.c:405)
DEBUG1 [2915/2921]: UST app creating event * for all apps for session id 0 (in ust_app_create_event_glb() at ust-app.c:3687)
DEBUG1 [2915/2921]: Event UST * created in channel channel0 (in event_ust_enable_tracepoint() at event.c:469)
DEBUG1 [2915/2921]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3909)
DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx() at main.c:608)
DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients() at main.c:3773)

row@cobra7:~$ lttng start
Tracing started for session auto-20150323-162735

DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at main.c:3815)
DEBUG1 [2915/2921]: Receiving data from client ... (in thread_manage_clients() at main.c:3860)
DEBUG1 [2915/2921]: Nothing recv() from client... continuing (in thread_manage_clients() at main.c:3864)
DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx() at main.c:608)
DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients() at main.c:3773)
DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at main.c:3815)
DEBUG1 [2915/2921]: Receiving data from client ... (in thread_manage_clients() at main.c:3860)
DEBUG1 [2915/2921]: Processing client command 16 (in process_client_msg() at main.c:2637)
DEBUG1 [2915/2921]: Getting session auto-20150323-162735 by name (in process_client_msg() at main.c:2716)
DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in session_find_by_name() at session.c:169)
DEBUG1 [2915/2921]: Starting all UST traces (in ust_app_start_trace_all() at ust-app.c:4034)
DEBUG1 [2915/2921]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3909)
DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx() at main.c:608)
DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients() at main.c:3773)


row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ ./demo-trace
liblttng_ust_tracepoint[3581/3581]: just registered a tracepoints section from 0xb757c780 and having 2 tracepoints (in tracepoint_register_lib() at tracepoint.c:767)
liblttng_ust_tracepoint[3581/3581]: registered tracepoint: ust_baddr_statedump:soinfo (in tracepoint_register_lib() at tracepoint.c:772)
liblttng_ust_tracepoint[3581/3581]: registered tracepoint: lttng_ust_tracef:event (in tracepoint_register_lib() at tracepoint.c:772)
libust[3581/3581]: Provider "ust_baddr_statedump" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: adding probe ust_baddr_statedump containing 1 events to lazy registration list (in lttng_probe_register() at lttng-probes.c:216)
libust[3581/3581]: LTT : ltt ring buffer client "relay-metadata-mmap" init
(in lttng_ring_buffer_metadata_client_init() at lttng-ring-buffer-metadata-client.h:330)
libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-mmap" init
(in lttng_ring_buffer_client_overwrite_init() at lttng-ring-buffer-client.h:681)
libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-rt-mmap" init
(in lttng_ring_buffer_client_overwrite_rt_init() at lttng-ring-buffer-client.h:681)
libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-mmap" init
(in lttng_ring_buffer_client_discard_init() at lttng-ring-buffer-client.h:681)
libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-rt-mmap" init
(in lttng_ring_buffer_client_discard_rt_init() at lttng-ring-buffer-client.h:681)
libust[3581/3582]: Info: sessiond not accepting connections to global apps socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
libust[3581/3583]: Info: sessiond not accepting connections to local apps socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
libust[3581/3582]: Waiting for global apps sessiond (in wait_for_sessiond() at lttng-ust-comm.c:1132)
libust[3581/3581]: Provider "lttng_ust_tracef" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3583]: Waiting for local apps sessiond (in wait_for_sessiond() at lttng-ust-comm.c:1132)
libust[3581/3581]: just registered probe ust_baddr_statedump containing 1 events (in lttng_lazy_probe_register() at lttng-probes.c:115)
libust[3581/3581]: adding probe lttng_ust_tracef containing 1 events to lazy registration list (in lttng_probe_register() at lttng-probes.c:216)
libust[3581/3581]: Provider "ust_tests_demo3" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just registered probe lttng_ust_tracef containing 1 events (in lttng_lazy_probe_register() at lttng-probes.c:115)
libust[3581/3581]: adding probe ust_tests_demo3 containing 1 events to lazy registration list (in lttng_probe_register() at lttng-probes.c:216)
libust[3581/3581]: Provider "ust_tests_demo" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just registered probe ust_tests_demo3 containing 1 events (in lttng_lazy_probe_register() at lttng-probes.c:115)
libust[3581/3581]: adding probe ust_tests_demo containing 2 events to lazy registration list (in lttng_probe_register() at lttng-probes.c:216)
libust[3581/3581]: Provider "ust_tests_demo2" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just registered probe ust_tests_demo containing 2 events (in lttng_lazy_probe_register() at lttng-probes.c:115)
libust[3581/3581]: adding probe ust_tests_demo2 containing 1 events to lazy registration list (in lttng_probe_register() at lttng-probes.c:216)
liblttng_ust_tracepoint[3581/3581]: just registered a tracepoints section from 0x804b144 and having 4 tracepoints (in tracepoint_register_lib() at tracepoint.c:767)
liblttng_ust_tracepoint[3581/3581]: registered tracepoint: ust_tests_demo:starting (in tracepoint_register_lib() at tracepoint.c:772)
liblttng_ust_tracepoint[3581/3581]: registered tracepoint: ust_tests_demo:done (in tracepoint_register_lib() at tracepoint.c:772)
liblttng_ust_tracepoint[3581/3581]: registered tracepoint: ust_tests_demo2:loop (in tracepoint_register_lib() at tracepoint.c:772)
liblttng_ust_tracepoint[3581/3581]: registered tracepoint: ust_tests_demo3:done (in tracepoint_register_lib() at tracepoint.c:772)
Demo program starting.
Tracing...  done.
liblttng_ust_tracepoint[3581/3581]: just unregistered a tracepoints section from 0x804b144 (in tracepoint_unregister_lib() at tracepoint.c:797)
libust[3581/3581]: Provider "ust_tests_demo2" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just unregistered probe ust_tests_demo2 (in lttng_probe_unregister() at lttng-probes.c:245)
libust[3581/3581]: Provider "ust_tests_demo" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just unregistered probe ust_tests_demo (in lttng_probe_unregister() at lttng-probes.c:245)
libust[3581/3581]: Provider "ust_tests_demo3" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just unregistered probe ust_tests_demo3 (in lttng_probe_unregister() at lttng-probes.c:245)
libust[3581/3581]: Provider "lttng_ust_tracef" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just unregistered probe lttng_ust_tracef (in lttng_probe_unregister() at lttng-probes.c:245)
libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-rt-mmap" exit
(in lttng_ring_buffer_client_discard_rt_exit() at lttng-ring-buffer-client.h:688)
libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-mmap" exit
(in lttng_ring_buffer_client_discard_exit() at lttng-ring-buffer-client.h:688)
libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-rt-mmap" exit
(in lttng_ring_buffer_client_overwrite_rt_exit() at lttng-ring-buffer-client.h:688)
libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-mmap" exit
(in lttng_ring_buffer_client_overwrite_exit() at lttng-ring-buffer-client.h:688)
libust[3581/3581]: LTT : ltt ring buffer client "relay-metadata-mmap" exit
(in lttng_ring_buffer_metadata_client_exit() at lttng-ring-buffer-metadata-client.h:337)
libust[3581/3581]: Provider "ust_baddr_statedump" accepted, version 1.0 is compatible with LTTng UST provider version 1.0. (in check_provider_version() at lttng-probes.c:174)
libust[3581/3581]: just unregistered probe ust_baddr_statedump (in lttng_probe_unregister() at lttng-probes.c:245)
liblttng_ust_tracepoint[3581/3581]: just unregistered a tracepoints section from 0xb757c780 (in tracepoint_unregister_lib() at tracepoint.c:797)
row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$


=> no log in sessiond shell

Re: Can't get any event output from the demo exe

[Mathieu Desnoyers]

It looks like the UST application cannot talk to the session daemon
over the communication unix socket at all.

Normally, there would be activity in the session daemon when the
application is started.

Please double-check that the ust and tools versions used are
compatible. If needed, uninstall and reinstall them, following
the http://lttng.org/docs documentation.

Thanks,

Mathieu

----- Original Message -----
> Hi Mathieu,
> 
> thanks for looking at it.
> 
> Here the complete log of both sides
> 
> 
> 
> row@cobra7:~$ lttng create
> Session auto-20150323-162735 created.
> Traces will be written in
> /home/local/ESG_GMBH/row/lttng-traces/auto-20150323-162735
> 
> 
> DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at
> main.c:3815)
> DEBUG1 [2915/2921]: Receiving data from client ... (in
> thread_manage_clients() at main.c:3860)
> DEBUG1 [2915/2921]: Nothing recv() from client... continuing (in
> thread_manage_clients() at main.c:3864)
> DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx()
> at main.c:608)
> DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients()
> at main.c:3773)
> DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at
> main.c:3815)
> DEBUG1 [2915/2921]: Receiving data from client ... (in
> thread_manage_clients() at main.c:3860)
> DEBUG1 [2915/2921]: Processing client command 8 (in process_client_msg() at
> main.c:2637)
> DEBUG1 [2915/2921]: Waiting for 1 URIs from client ... (in
> process_client_msg() at main.c:3201)
> DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in
> session_find_by_name() at session.c:169)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a05a28 of type 1 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG1 [2915/2921]: Tracing session auto-20150323-162735 created with ID 0 by
> UID 1548223785 GID 1548222977 (in session_create() at session.c:276)
> DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in
> session_find_by_name() at session.c:169)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a0ad80 of type 1 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG2 [2915/2921]: Setting trace directory path from URI to
> /home/local/ESG_GMBH/row/lttng-traces/auto-20150323-162735 (in
> add_uri_to_consumer() at cmd.c:481)
> DEBUG1 [2915/2921]: Sending response (size: 16, retcode: Success) (in
> thread_manage_clients() at main.c:3909)
> DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx()
> at main.c:608)
> DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients()
> at main.c:3773)
> 
> row@cobra7:~$ lttng enable-event -u -a
> All UST events are enabled in channel channel0
> 
> DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at
> main.c:3815)
> DEBUG1 [2915/2921]: Receiving data from client ... (in
> thread_manage_clients() at main.c:3860)
> DEBUG1 [2915/2921]: Nothing recv() from client... continuing (in
> thread_manage_clients() at main.c:3864)
> DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx()
> at main.c:608)
> DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients()
> at main.c:3773)
> DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at
> main.c:3815)
> DEBUG1 [2915/2921]: Receiving data from client ... (in
> thread_manage_clients() at main.c:3860)
> DEBUG1 [2915/2921]: Processing client command 6 (in process_client_msg() at
> main.c:2637)
> DEBUG1 [2915/2921]: Getting session auto-20150323-162735 by name (in
> process_client_msg() at main.c:2716)
> DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in
> session_find_by_name() at session.c:169)
> DEBUG1 [2915/2921]: Creating UST session (in create_ust_session() at
> main.c:2517)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a039f0 of type 0 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a03d18 of type 0 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04040 of type 1 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG2 [2915/2921]: UST trace session create successful (in
> trace_ust_create_session() at trace-ust.c:263)
> DEBUG3 [2915/2921]: Copying tracing session consumer output in UST session
> (in copy_session_consumer() at main.c:2466)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04368 of type 1 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG3 [2915/2921]: Copy session consumer subdir /ust (in
> copy_session_consumer() at main.c:2484)
> DEBUG1 [2915/2921]: Spawning consumerd (in spawn_consumerd() at main.c:2158)
> DEBUG2 [2915/2921]: Consumer pid 2987 (in start_consumerd() at main.c:2340)
> DEBUG2 [2915/2921]: Spawning consumer control thread (in start_consumerd() at
> main.c:2343)
> DEBUG3 [2915/2918]: [ht-thread] Polling on 2 fds. (in thread_ht_cleanup() at
> ht-cleanup.c:64)
> DEBUG1 [2987/2987]: Using 32-bit UST consumer at:
> /usr/lib/i386-linux-gnu/lttng/libexec/lttng-consumerd (in spawn_consumerd()
> at main.c:2278)
> DEBUG1 [2915/2988]: [thread] Manage consumer started (in
> thread_manage_consumer() at main.c:994)
> DEBUG2 [2915/2988]: Receiving code from consumer err_sock (in
> thread_manage_consumer() at main.c:1079)
> DEBUG1 [2915/2988]: Consumer command socket ready (fd: 26 (in
> thread_manage_consumer() at main.c:1113)
> DEBUG1 [2915/2988]: Consumer metadata socket ready (fd: 27) (in
> thread_manage_consumer() at main.c:1115)
> DEBUG3 [2915/2921]: Consumer socket created (fd: 26) and added to output (in
> consumer_create_socket() at consumer.c:301)
> DEBUG1 [2915/2921]: Setting relayd for session auto-20150323-162735 (in
> cmd_setup_relayd() at cmd.c:722)
> DEBUG2 [2915/2921]: Trace UST channel channel0 not found by name (in
> trace_ust_find_channel_by_name() at trace-ust.c:162)
> DEBUG1 [2915/2921]: Enabling channel  for session auto-20150323-162735 (in
> cmd_enable_channel() at cmd.c:910)
> DEBUG2 [2915/2921]: Trace UST channel channel0 not found by name (in
> trace_ust_find_channel_by_name() at trace-ust.c:162)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04910 of type 0 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG3 [2915/2921]: Created hashtable size 4 at 0xb2a04bf0 of type 1 (in
> lttng_ht_new() at hashtable.c:127)
> DEBUG2 [2915/2921]: Trace UST channel channel0 created (in
> trace_ust_create_channel() at trace-ust.c:331)
> DEBUG2 [2915/2921]: Channel channel0 is being created for UST with buffer 1
> and id 0 (in channel_ust_create() at channel.c:348)
> DEBUG2 [2915/2921]: UST app adding channel channel0 to UST domain for session
> id 0 (in ust_app_create_channel_glb() at ust-app.c:3536)
> DEBUG2 [2915/2921]: Channel channel0 created successfully (in
> channel_ust_create() at channel.c:383)
> DEBUG2 [2915/2921]: Trace UST channel channel0 found by name (in
> trace_ust_find_channel_by_name() at trace-ust.c:157)
> DEBUG2 [2915/2921]: Trace UST event * NOT found (in trace_ust_find_event() at
> trace-ust.c:198)
> DEBUG2 [2915/2921]: Trace UST event *, loglevel (0,-1) created (in
> trace_ust_create_event() at trace-ust.c:405)
> DEBUG1 [2915/2921]: UST app creating event * for all apps for session id 0
> (in ust_app_create_event_glb() at ust-app.c:3687)
> DEBUG1 [2915/2921]: Event UST * created in channel channel0 (in
> event_ust_enable_tracepoint() at event.c:469)
> DEBUG1 [2915/2921]: Sending response (size: 16, retcode: Success) (in
> thread_manage_clients() at main.c:3909)
> DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx()
> at main.c:608)
> DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients()
> at main.c:3773)
> 
> row@cobra7:~$ lttng start
> Tracing started for session auto-20150323-162735
> 
> DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at
> main.c:3815)
> DEBUG1 [2915/2921]: Receiving data from client ... (in
> thread_manage_clients() at main.c:3860)
> DEBUG1 [2915/2921]: Nothing recv() from client... continuing (in
> thread_manage_clients() at main.c:3864)
> DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx()
> at main.c:608)
> DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients()
> at main.c:3773)
> DEBUG1 [2915/2921]: Wait for client response (in thread_manage_clients() at
> main.c:3815)
> DEBUG1 [2915/2921]: Receiving data from client ... (in
> thread_manage_clients() at main.c:3860)
> DEBUG1 [2915/2921]: Processing client command 16 (in process_client_msg() at
> main.c:2637)
> DEBUG1 [2915/2921]: Getting session auto-20150323-162735 by name (in
> process_client_msg() at main.c:2716)
> DEBUG2 [2915/2921]: Trying to find session by name auto-20150323-162735 (in
> session_find_by_name() at session.c:169)
> DEBUG1 [2915/2921]: Starting all UST traces (in ust_app_start_trace_all() at
> ust-app.c:4034)
> DEBUG1 [2915/2921]: Sending response (size: 16, retcode: Success) (in
> thread_manage_clients() at main.c:3909)
> DEBUG1 [2915/2921]: Clean command context structure (in clean_command_ctx()
> at main.c:608)
> DEBUG1 [2915/2921]: Accepting client command ... (in thread_manage_clients()
> at main.c:3773)
> 
> 
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$ ./demo-trace
> liblttng_ust_tracepoint[3581/3581]: just registered a tracepoints section
> from 0xb757c780 and having 2 tracepoints (in tracepoint_register_lib() at
> tracepoint.c:767)
> liblttng_ust_tracepoint[3581/3581]: registered tracepoint:
> ust_baddr_statedump:soinfo (in tracepoint_register_lib() at
> tracepoint.c:772)
> liblttng_ust_tracepoint[3581/3581]: registered tracepoint:
> lttng_ust_tracef:event (in tracepoint_register_lib() at tracepoint.c:772)
> libust[3581/3581]: Provider "ust_baddr_statedump" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: adding probe ust_baddr_statedump containing 1 events to
> lazy registration list (in lttng_probe_register() at lttng-probes.c:216)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-metadata-mmap" init
> (in lttng_ring_buffer_metadata_client_init() at
> lttng-ring-buffer-metadata-client.h:330)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-mmap" init
> (in lttng_ring_buffer_client_overwrite_init() at
> lttng-ring-buffer-client.h:681)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-rt-mmap"
> init
> (in lttng_ring_buffer_client_overwrite_rt_init() at
> lttng-ring-buffer-client.h:681)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-mmap" init
> (in lttng_ring_buffer_client_discard_init() at
> lttng-ring-buffer-client.h:681)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-rt-mmap" init
> (in lttng_ring_buffer_client_discard_rt_init() at
> lttng-ring-buffer-client.h:681)
> libust[3581/3582]: Info: sessiond not accepting connections to global apps
> socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
> libust[3581/3583]: Info: sessiond not accepting connections to local apps
> socket (in ust_listener_thread() at lttng-ust-comm.c:1221)
> libust[3581/3582]: Waiting for global apps sessiond (in wait_for_sessiond()
> at lttng-ust-comm.c:1132)
> libust[3581/3581]: Provider "lttng_ust_tracef" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3583]: Waiting for local apps sessiond (in wait_for_sessiond() at
> lttng-ust-comm.c:1132)
> libust[3581/3581]: just registered probe ust_baddr_statedump containing 1
> events (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[3581/3581]: adding probe lttng_ust_tracef containing 1 events to lazy
> registration list (in lttng_probe_register() at lttng-probes.c:216)
> libust[3581/3581]: Provider "ust_tests_demo3" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just registered probe lttng_ust_tracef containing 1 events
> (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[3581/3581]: adding probe ust_tests_demo3 containing 1 events to lazy
> registration list (in lttng_probe_register() at lttng-probes.c:216)
> libust[3581/3581]: Provider "ust_tests_demo" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just registered probe ust_tests_demo3 containing 1 events
> (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[3581/3581]: adding probe ust_tests_demo containing 2 events to lazy
> registration list (in lttng_probe_register() at lttng-probes.c:216)
> libust[3581/3581]: Provider "ust_tests_demo2" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just registered probe ust_tests_demo containing 2 events
> (in lttng_lazy_probe_register() at lttng-probes.c:115)
> libust[3581/3581]: adding probe ust_tests_demo2 containing 1 events to lazy
> registration list (in lttng_probe_register() at lttng-probes.c:216)
> liblttng_ust_tracepoint[3581/3581]: just registered a tracepoints section
> from 0x804b144 and having 4 tracepoints (in tracepoint_register_lib() at
> tracepoint.c:767)
> liblttng_ust_tracepoint[3581/3581]: registered tracepoint:
> ust_tests_demo:starting (in tracepoint_register_lib() at tracepoint.c:772)
> liblttng_ust_tracepoint[3581/3581]: registered tracepoint:
> ust_tests_demo:done (in tracepoint_register_lib() at tracepoint.c:772)
> liblttng_ust_tracepoint[3581/3581]: registered tracepoint:
> ust_tests_demo2:loop (in tracepoint_register_lib() at tracepoint.c:772)
> liblttng_ust_tracepoint[3581/3581]: registered tracepoint:
> ust_tests_demo3:done (in tracepoint_register_lib() at tracepoint.c:772)
> Demo program starting.
> Tracing...  done.
> liblttng_ust_tracepoint[3581/3581]: just unregistered a tracepoints section
> from 0x804b144 (in tracepoint_unregister_lib() at tracepoint.c:797)
> libust[3581/3581]: Provider "ust_tests_demo2" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just unregistered probe ust_tests_demo2 (in
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[3581/3581]: Provider "ust_tests_demo" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just unregistered probe ust_tests_demo (in
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[3581/3581]: Provider "ust_tests_demo3" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just unregistered probe ust_tests_demo3 (in
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[3581/3581]: Provider "lttng_ust_tracef" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just unregistered probe lttng_ust_tracef (in
> lttng_probe_unregister() at lttng-probes.c:245)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-rt-mmap" exit
> (in lttng_ring_buffer_client_discard_rt_exit() at
> lttng-ring-buffer-client.h:688)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-discard-mmap" exit
> (in lttng_ring_buffer_client_discard_exit() at
> lttng-ring-buffer-client.h:688)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-rt-mmap"
> exit
> (in lttng_ring_buffer_client_overwrite_rt_exit() at
> lttng-ring-buffer-client.h:688)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-overwrite-mmap" exit
> (in lttng_ring_buffer_client_overwrite_exit() at
> lttng-ring-buffer-client.h:688)
> libust[3581/3581]: LTT : ltt ring buffer client "relay-metadata-mmap" exit
> (in lttng_ring_buffer_metadata_client_exit() at
> lttng-ring-buffer-metadata-client.h:337)
> libust[3581/3581]: Provider "ust_baddr_statedump" accepted, version 1.0 is
> compatible with LTTng UST provider version 1.0. (in check_provider_version()
> at lttng-probes.c:174)
> libust[3581/3581]: just unregistered probe ust_baddr_statedump (in
> lttng_probe_unregister() at lttng-probes.c:245)
> liblttng_ust_tracepoint[3581/3581]: just unregistered a tracepoints section
> from 0xb757c780 (in tracepoint_unregister_lib() at tracepoint.c:797)
> row@cobra7:~/lttng_source/lttng-ust/doc/examples/demo$
> 
> 
> => no log in sessiond shell
> 
> 

-- 
Mathieu Desnoyers
EfficiOS Inc.
http://www.efficios.com

Re: Can't get any event output from the demo exe [solved]

[Wolfgang Rostek]

Just right before the weekend I could repair it.
Seems that I had some trouble with the installed packages.

Here the steps working for me on Ubuntu 14 LTS

http://installion.co.uk/ubuntu/utopic/universe/l/lttng-tools/uninstall/index.html
sudo apt-get remove --auto-remove lttng-tools

https://launchpad.net/~lttng/+archive/ubuntu/ppa
sudo apt-add-repository ppa:lttng/ppa
sudo apt-get update
sudo apt-get install lttng-tools lttng-modules-dkms babeltrace liblttng-ust-dev python3-babeltrace

thanks for your investigations
Wolfgang R.

Re: Can't get any event output from the demo exe [solved]

[Mathieu Desnoyers]

Alexandre, maintainer of the PPA packages, will be
back from vacation in 2 weeks.

Perhaps he could look into why you had to explicitly
uninstall packages before installing the PPA then.
Normally "conflicts with" should be taking care
of this kind of dependency.

Thanks,

Mathieu

----- Original Message -----
> Just right before the weekend I could repair it.
> Seems that I had some trouble with the installed packages.
> 
> Here the steps working for me on Ubuntu 14 LTS
> 
> http://installion.co.uk/ubuntu/utopic/universe/l/lttng-tools/uninstall/index.html
> sudo apt-get remove --auto-remove lttng-tools
> 
> https://launchpad.net/~lttng/+archive/ubuntu/ppa
> sudo apt-add-repository ppa:lttng/ppa
> sudo apt-get update
> sudo apt-get install lttng-tools lttng-modules-dkms babeltrace
> liblttng-ust-dev python3-babeltrace
> 
> thanks for your investigations
> Wolfgang R.
> 

-- 
Mathieu Desnoyers
EfficiOS Inc.
http://www.efficios.com

Re: Can't get any event output from the demo exe [solved]

[Wolfgang Rostek]

Hi Mathieu,

weeks ago I did a different approach installing packages.
That one I can't recap.

Today I tried this one and simply started to get rid of existing things.

I would see this as a beginners confusion and not worth
to spend time here.

I'm quite happy to see the nice LTTng output ;)

Wolfgang R.


> Gesendet: Freitag, 27. März 2015 um 14:52 Uhr
> Von: "Mathieu Desnoyers" <mathieu.desnoyers@efficios.com>
> An: "Wolfgang Rostek" <wolfgang.rostek@gmx.de>, "Alexandre Montplaisir" <alexmonthy@voxpopuli.im>
> Cc: lttng-dev@lists.lttng.org
> Betreff: Re: Aw: Re:  Re: [lttng-dev] Can't get any event output from the demo exe [solved]
>
> Alexandre, maintainer of the PPA packages, will be
> back from vacation in 2 weeks.
> 
> Perhaps he could look into why you had to explicitly
> uninstall packages before installing the PPA then.
> Normally "conflicts with" should be taking care
> of this kind of dependency.
> 
> Thanks,
> 
> Mathieu
> 
> ----- Original Message -----
> > Just right before the weekend I could repair it.
> > Seems that I had some trouble with the installed packages.
> > 
> > Here the steps working for me on Ubuntu 14 LTS
> > 
> > http://installion.co.uk/ubuntu/utopic/universe/l/lttng-tools/uninstall/index.html
> > sudo apt-get remove --auto-remove lttng-tools
> > 
> > https://launchpad.net/~lttng/+archive/ubuntu/ppa
> > sudo apt-add-repository ppa:lttng/ppa
> > sudo apt-get update
> > sudo apt-get install lttng-tools lttng-modules-dkms babeltrace
> > liblttng-ust-dev python3-babeltrace
> > 
> > thanks for your investigations
> > Wolfgang R.
> > 
> 
> -- 
> Mathieu Desnoyers
> EfficiOS Inc.
> http://www.efficios.com
>

_______________________________________________
lttng-dev mailing list
lttng-dev@lists.lttng.org
http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev

Re: Can't get any event output from the demo exe [solved]

[Alexandre Montplaisir]

On 15-03-27 07:00 AM, Wolfgang Rostek wrote:
> Hi Mathieu,
>
> weeks ago I did a different approach installing packages.
> That one I can't recap.

What I suspect happened here is that you installed some of the packages 
from source using "make install", which copies the libraries in 
/usr/local/lib/* .

*Then* you installed the packaged versions, which go in /usr/lib/*. But 
the tricky part is that on Ubuntu and many other (most?) distros, the 
ld.so.conf is configured to look in /usr/local/lib first for shared 
libraries. If it finds a useable one, it loads that one and stops there.

In such a case, /usr/bin/lttng, installed from packages, would end up 
using /usr/local/lib/liblttng-ust*.so, and so on. If both are not of the 
exact same version, it can cause obscure problems like the one you had.


This is a very common source of problems in fact. I don't know of any 
easy fix unfortunately, short of teaching people about library mangement 
;) or bumping the soname of lttng-tools and UST's shared libraries every 
release, to make sure non-compatible libraries are not loaded.

Cheers,
Alexandre

>
> Today I tried this one and simply started to get rid of existing things.
>
> I would see this as a beginners confusion and not worth
> to spend time here.
>
> I'm quite happy to see the nice LTTng output ;)
>
> Wolfgang R.
>
>
>> Gesendet: Freitag, 27. März 2015 um 14:52 Uhr
>> Von: "Mathieu Desnoyers" <mathieu.desnoyers@efficios.com>
>> An: "Wolfgang Rostek" <wolfgang.rostek@gmx.de>, "Alexandre Montplaisir" <alexmonthy@voxpopuli.im>
>> Cc: lttng-dev@lists.lttng.org
>> Betreff: Re: Aw: Re:  Re: [lttng-dev] Can't get any event output from the demo exe [solved]
>>
>> Alexandre, maintainer of the PPA packages, will be
>> back from vacation in 2 weeks.
>>
>> Perhaps he could look into why you had to explicitly
>> uninstall packages before installing the PPA then.
>> Normally "conflicts with" should be taking care
>> of this kind of dependency.
>>
>> Thanks,
>>
>> Mathieu
>>
>> ----- Original Message -----
>>> Just right before the weekend I could repair it.
>>> Seems that I had some trouble with the installed packages.
>>>
>>> Here the steps working for me on Ubuntu 14 LTS
>>>
>>> http://installion.co.uk/ubuntu/utopic/universe/l/lttng-tools/uninstall/index.html
>>> sudo apt-get remove --auto-remove lttng-tools
>>>
>>> https://launchpad.net/~lttng/+archive/ubuntu/ppa
>>> sudo apt-add-repository ppa:lttng/ppa
>>> sudo apt-get update
>>> sudo apt-get install lttng-tools lttng-modules-dkms babeltrace
>>> liblttng-ust-dev python3-babeltrace
>>>
>>> thanks for your investigations
>>> Wolfgang R.
>>>
>> -- 
>> Mathieu Desnoyers
>> EfficiOS Inc.
>> http://www.efficios.com
>>


_______________________________________________
lttng-dev mailing list
lttng-dev@lists.lttng.org
http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev

Re: Can't get any event output from the demo exe [solved]

[Wolfgang Rostek]

thanks Alexandre for investigating and it seems the way I did it

At least when I start working on the sources I should have better
tracked what make install is going to do. 

Wolfgang R.