I am working on getting LTTng 2.0 working in our 2.6.35 kernel. I have kernel
tracing working but I'm having problems with UST.
I have a program with a dynamic UST tracepoint. When I run the program with
all UST events enabled (lttng enable-event -a -u) the program hangs on the
tracepoint. The last line from the UST debug is:
libust[6184/6185]: Info: sessiond not accepting connections to local apps
socket (in ust_listener_thread() at lttng-ust-comm.c:699)
Any insight into why this is happening? I've attached the UST and
lttng-sessiond debug generated by the program.
I am running the following:
lttng-modules-2.6.32 (found through this mailing list)
lttng-tools-2.0.1
lttng-ust-2.0.2
userspace-rcu-0.7.0
Thanks,
Michael
liblttng_ust_tracepoint[5133/5133]: just registered a tracepoints section from
0xb76fbd44 and having 1 tracepoints (in tracepoint_register_lib() at
tracepoint.c:638)
liblttng_ust_tracepoint[5133/5133]: registered tracepoint: lttng_ust:metadata
(in tracepoint_register_lib() at tracepoint.c:643)
libust[5133/5133]: LTT : ltt ring buffer client init
(in ltt_ring_buffer_metadata_client_init() at
ltt-ring-buffer-metadata-client.h:334)
libust[5133/5133]: LTT : ltt ring buffer client init
(in ltt_ring_buffer_client_overwrite_init() at ltt-ring-buffer-client.h:584)
libust[5133/5133]: LTT : ltt ring buffer client init
(in ltt_ring_buffer_client_discard_init() at ltt-ring-buffer-client.h:584)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
liblttng_ust_tracepoint[5133/5135]: Registering probe to tracepoint
lttng_ust:metadata (in __tracepoint_probe_register() at tracepoint.c:426)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5135]: message received
(in ust_listener_thread() at lttng-ust-comm.c:766)
libust[5133/5135]: message successfully sent (in send_reply() at
lttng-ust-comm.c:202)
libust[5133/5134]: Info: sessiond not accepting connections to local apps
socket (in ust_listener_thread() at lttng-ust-comm.c:699)
DEBUG1: UST registration received with pid:6184 ppid:2158 uid:0 gid:0 sock:19
name:idp (version 2.0) [in thread_registration_apps() at main.c:1510]
DEBUG1: Futex n to 1 wake done [in futex_nto1_wake() at futex.c:100]
DEBUG1: Accepting application registration [in thread_registration_apps() at
main.c:1423]
DEBUG1: Futex n to 1 wait done [in futex_nto1_wait() at futex.c:87]
DEBUG1: Futex n to 1 prepare done [in futex_nto1_prepare() at futex.c:73]
DEBUG1: Dispatching UST registration pid:6184 ppid:2158 uid:0 gid:0 sock:19
name:idp (version 2.0) [in thread_dispatch_ust_registration() at main.c:1346]
DEBUG1: Woken up but nothing in the UST command queue [in
thread_dispatch_ust_registration() at main.c:1334]
DEBUG3: Created hashtable size 4 at 0x80807a0 of type 1 [in lttng_ht_new() at
hashtable.c:96]
DEBUG1: App registered with pid:6184 ppid:2158 uid:0 gid:0 sock:19 name:idp
(version 2.0) [in ust_app_register() at ust-app.c:1365]
DEBUG2: UST app PID 6184 is compatible with major version 2 (supporting <= 2)
[in ust_app_validate_version() at ust-app.c:2625]
DEBUG2: UST app global update for app sock 19 for session id 1 [in
ust_app_global_update() at ust-app.c:2295]
DEBUG2: UST app pid: 6184 session id 1 not found, creating it [in
create_ust_app_session() at ust-app.c:883]
DEBUG3: Created hashtable size 4 at 0x807f1b8 of type 0 [in lttng_ht_new() at
hashtable.c:96]
DEBUG2: Shadow copy of session handle -1 [in shadow_copy_session() at
ust-app.c:794]
DEBUG2: Channel channel0 not found on shadow session copy, creating it [in
shadow_copy_session() at ust-app.c:823]
DEBUG3: Created hashtable size 4 at 0x80802f0 of type 1 [in lttng_ht_new() at
hashtable.c:96]
DEBUG3: Created hashtable size 4 at 0x8080680 of type 0 [in lttng_ht_new() at
hashtable.c:96]
DEBUG3: UST app channel channel0 allocated [in alloc_ust_app_channel() at
ust-app.c:298]
DEBUG2: UST app shadow copy of channel channel0 started [in
shadow_copy_channel() at ust-app.c:734]
DEBUG2: UST event * not found on shadow copy channel [in shadow_copy_channel()
at ust-app.c:761]
DEBUG3: Created hashtable size 4 at 0x8080be8 of type 1 [in lttng_ht_new() at
hashtable.c:96]
DEBUG3: UST app event * allocated [in alloc_ust_app_event() at ust-app.c:333]
DEBUG3: UST app shadow copy of channel channel0 done [in shadow_copy_channel()
at ust-app.c:771]
DEBUG2: UST app session created successfully with handle 1 [in
create_ust_app_session() at ust-app.c:907]
DEBUG2: UST app channel channel0 created successfully for pid:6184 and sock:19
[in create_ust_channel() at ust-app.c:621]
DEBUG2: UST app event * created successfully for pid:6184 [in
create_ust_event() at ust-app.c:660]
DEBUG1: Starting tracing for ust app pid 6184 [in ust_app_start_trace() at
ust-app.c:1970]
DEBUG2: UST metadata opened for app pid 6184 [in create_ust_app_metadata() at
ust-app.c:1218]
DEBUG3: mkdir()
//lttng-traces/auto-20120607-175715/ust/idp-6184-20120607-180232 with mode 504
for uid 0 and gid 0 [in run_as_mkdir() at runas.c:349]
DEBUG1: Using run_as_clone [in run_as() at runas.c:322]
DEBUG2: UST metadata stream object created for app pid 6184 [in
create_ust_app_metadata() at ust-app.c:1243]
DEBUG2: UST stream 1 ready at
//lttng-traces/auto-20120607-175715/ust/idp-6184-20120607-180232/channel0_0 [in
ust_app_start_trace() at ust-app.c:2039]
DEBUG2: UST stream 2 ready at
//lttng-traces/auto-20120607-175715/ust/idp-6184-20120607-180232/channel0_1 [in
ust_app_start_trace() at ust-app.c:2039]
DEBUG1: Sending metadata stream fd [in ust_consumer_send_session() at
ust-consumer.c:126]
DEBUG1: Sending metadata channel 94 to consumer [in ust_consumer_send_session()
at ust-consumer.c:142]
DEBUG1: Sending metadata stream 96 to consumer [in ust_consumer_send_session()
at ust-consumer.c:166]
DEBUG1: Sending streams of channel channel0 to UST consumer [in
send_channel_streams() at ust-consumer.c:43]
DEBUG1: Sending channel 92 to consumer [in send_channel_streams() at
ust-consumer.c:58]
DEBUG1: Sending stream 98 to consumer [in send_channel_streams() at
ust-consumer.c:91]
DEBUG1: Sending stream 100 to consumer [in send_channel_streams() at
ust-consumer.c:91]
DEBUG1: consumer channel streams sent [in send_channel_streams() at
ust-consumer.c:107]
DEBUG1: consumer fds (metadata and channel streams) sent [in
ust_consumer_send_session() at ust-consumer.c:201]
DEBUG2: UST trace started for app pid 6184 [in ust_app_global_update() at
ust-app.c:2364]
DEBUG1: Apps with sock 19 added to poll set [in thread_manage_apps() at
main.c:1280]
DEBUG1: Apps thread polling on 3 fds [in thread_manage_apps() at main.c:1200]
_______________________________________________
lttng-dev mailing list
[email protected]
http://lists.lttng.org/cgi-bin/mailman/listinfo/lttng-dev
