|
### continue lttng logging ###
|
|
###second run###
|
|
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14267 ppid:1 uid:0 gid:0 sock:22 name:TestApp_Fork1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14266 ppid:1 uid:0 gid:0 sock:23 name:TestApp_type1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14267 ppid:1 uid:0 gid:0 sock:24 name:TestApp_Fork1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG1 [7406/7411]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:90)
|
|
DEBUG1 [7406/7411]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:75)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14267 ppid:1 uid:0 gid:0 sock:22 name:TestApp_Fork1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG3 [7406/7411]: UST app creating application for socket 22 (in ust_app_create() at ust-app.c:2620)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14266 ppid:1 uid:0 gid:0 sock:25 name:TestApp_type1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x66c1c0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14268 ppid:1 uid:0 gid:0 sock:26 name:TestApp_Thread1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x668e10 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14268 ppid:1 uid:0 gid:0 sock:27 name:TestApp_Thread1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14266 ppid:1 uid:0 gid:0 sock:23 name:TestApp_type1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG3 [7406/7411]: UST app creating application for socket 23 (in ust_app_create() at ust-app.c:2620)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x668210 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x683840 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14267 ppid:1 uid:0 gid:0 sock:24 name:TestApp_Fork1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG3 [7406/7411]: UST app notify socket 24 is set (in thread_dispatch_ust_registration() at main.c:1423)
|
|
DEBUG1 [7406/7411]: App registered with pid:14267 ppid:1 uid:0 gid:0 sock:22 name:TestApp_Fork1 notify_sock:24 (version 4.0) (in ust_app_add() at ust-app.c:2708)
|
|
DEBUG3 [7406/7414]: UST thread notify added sock 24 to pollset (in ust_thread_manage_notify() at ust-thread.c:118)
|
|
DEBUG3 [7406/7414]: [ust-thread] Manage notify polling on 3 fds (in ust_thread_manage_notify() at ust-thread.c:54)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14266 ppid:1 uid:0 gid:0 sock:25 name:TestApp_type1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG1 [7406/7413]: Apps with sock 22 added to poll set (in thread_manage_apps() at main.c:1248)
|
|
DEBUG3 [7406/7411]: UST app notify socket 25 is set (in thread_dispatch_ust_registration() at main.c:1423)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 3 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG1 [7406/7411]: App registered with pid:14266 ppid:1 uid:0 gid:0 sock:23 name:TestApp_type1 notify_sock:25 (version 4.0) (in ust_app_add() at ust-app.c:2708)
|
|
DEBUG3 [7406/7414]: UST thread notify added sock 25 to pollset (in ust_thread_manage_notify() at ust-thread.c:118)
|
|
DEBUG3 [7406/7414]: [ust-thread] Manage notify polling on 4 fds (in ust_thread_manage_notify() at ust-thread.c:54)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14268 ppid:1 uid:0 gid:0 sock:26 name:TestApp_Thread1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG3 [7406/7411]: UST app creating application for socket 26 (in ust_app_create() at ust-app.c:2620)
|
|
DEBUG1 [7406/7413]: Apps with sock 23 added to poll set (in thread_manage_apps() at main.c:1248)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x6842f0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 4 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x669950 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14268 ppid:1 uid:0 gid:0 sock:27 name:TestApp_Thread1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG3 [7406/7411]: UST app notify socket 27 is set (in thread_dispatch_ust_registration() at main.c:1423)
|
|
DEBUG1 [7406/7411]: App registered with pid:14268 ppid:1 uid:0 gid:0 sock:26 name:TestApp_Thread1 notify_sock:27 (version 4.0) (in ust_app_add() at ust-app.c:2708)
|
|
DEBUG1 [7406/7411]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1362)
|
|
DEBUG3 [7406/7414]: UST thread notify added sock 27 to pollset (in ust_thread_manage_notify() at ust-thread.c:118)
|
|
DEBUG1 [7406/7413]: Apps with sock 26 added to poll set (in thread_manage_apps() at main.c:1248)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 5 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG3 [7406/7414]: [ust-thread] Manage notify polling on 5 fds (in ust_thread_manage_notify() at ust-thread.c:54)
|
|
DEBUG1 [7406/7411]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:90)
|
|
DEBUG1 [7406/7411]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:75)
|
|
DEBUG1 [7406/7411]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1362)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14275 ppid:14267 uid:0 gid:0 sock:28 name:TestApp_Fork1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG1 [7406/7412]: UST registration received with pid:14275 ppid:14267 uid:0 gid:0 sock:29 name:TestApp_Fork1 (version 4.0) (in thread_registration_apps() at main.c:1661)
|
|
DEBUG1 [7406/7412]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:104)
|
|
DEBUG1 [7406/7412]: Accepting application registration (in thread_registration_apps() at main.c:1565)
|
|
DEBUG1 [7406/7411]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:90)
|
|
DEBUG1 [7406/7411]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:75)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14275 ppid:14267 uid:0 gid:0 sock:28 name:TestApp_Fork1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG3 [7406/7411]: UST app creating application for socket 28 (in ust_app_create() at ust-app.c:2620)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x66a530 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7411]: Created hashtable size 4 at 0x66afe0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7411]: Dispatching UST registration pid:14275 ppid:14267 uid:0 gid:0 sock:29 name:TestApp_Fork1 (version 4.0) (in thread_dispatch_ust_registration() at main.c:1374)
|
|
DEBUG3 [7406/7411]: UST app notify socket 29 is set (in thread_dispatch_ust_registration() at main.c:1423)
|
|
DEBUG1 [7406/7411]: App registered with pid:14275 ppid:14267 uid:0 gid:0 sock:28 name:TestApp_Fork1 notify_sock:29 (version 4.0) (in ust_app_add() at ust-app.c:2708)
|
|
DEBUG1 [7406/7411]: Woken up but nothing in the UST command queue (in thread_dispatch_ust_registration() at main.c:1362)
|
|
DEBUG3 [7406/7414]: UST thread notify added sock 29 to pollset (in ust_thread_manage_notify() at ust-thread.c:118)
|
|
DEBUG3 [7406/7414]: [ust-thread] Manage notify polling on 6 fds (in ust_thread_manage_notify() at ust-thread.c:54)
|
|
DEBUG1 [7406/7413]: Apps with sock 28 added to poll set (in thread_manage_apps() at main.c:1248)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 6 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 18 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 3 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 3, key 18 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 3, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 3 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 3 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 27 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 5 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 5, key 27 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 5, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 5 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 5 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 8 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Waiting for 1 URIs from client ... (in process_client_msg() at main.c:2842)
|
|
DEBUG1 [7406/7410]: Tracing session _TraceCValidator_rvr7Qe created in (null) with ID 6 by UID 0 GID 0 (in session_create() at session.c:238)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6714b0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 5 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Getting session _TraceCValidator_rvr7Qe by name (in process_client_msg() at main.c:2423)
|
|
DEBUG1 [7406/7410]: Creating UST session (in create_ust_session() at main.c:2239)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6821c0 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x71caa0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Copying tracing session consumer output in UST session (in copy_session_consumer() at main.c:2191)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x71caa0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Copy session consumer subdir /ust (in copy_session_consumer() at main.c:2209)
|
|
DEBUG3 [7406/7410]: Consumer socket created (fd: 33) and added to output (in consumer_create_socket() at consumer.c:229)
|
|
DEBUG1 [7406/7410]: Setting relayd for session _TraceCValidator_rvr7Qe (in cmd_setup_relayd() at cmd.c:665)
|
|
DEBUG1 [7406/7410]: Enabling channel channel0 for session _TraceCValidator_rvr7Qe (in cmd_enable_channel() at cmd.c:851)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x71cd40 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x66ba90 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x66bd30 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x67f7e0 of type 2 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID created id: 6, ABI: 64, uid: 0, domain: 2 (in buffer_reg_uid_create() at buffer-registry.c:143)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID adding to global registry with id: 6 (in buffer_reg_uid_add() at buffer-registry.c:165)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x67fa80 of type 2 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Append to metadata: "typealias integer { size = 8; align = 8; signed = false; } := uint8_t;
|
|
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 = "c8f40268-aea8-4294-96ab-d8d49e99a5e8";
|
|
byte_order = le;
|
|
packet.header := struct {
|
|
uint32_t magic;
|
|
uint8_t uuid[16];
|
|
uint32_t stream_id;
|
|
};
|
|
};
|
|
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: "env {
|
|
hostname = "SC-1";
|
|
domain = "ust";
|
|
tracer_name = "lttng-ust";
|
|
tracer_major = 2;
|
|
tracer_minor = 2;
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: "};
|
|
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: "clock {
|
|
name = monotonic;
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: " uuid = "49b63c71-22dc-4b42-8616-3487da37b209";
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: " description = "Monotonic Clock";
|
|
freq = 1000000000; /* Frequency, in Hz */
|
|
/* clock value offset from Epoch is: offset * (1/freq) */
|
|
offset = 1365541849462089583;
|
|
};
|
|
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: "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;
|
|
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: "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;
|
|
};
|
|
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: Append to metadata: "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);
|
|
|
|
" (in lttng_metadata_printf() at ust-metadata.c:144)
|
|
DEBUG3 [7406/7410]: UST app buffer registry per UID created successfully (in setup_buffer_reg_uid() at ust-app.c:1570)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6d11e0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x67fd20 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: UST app channel channel0 allocated (in alloc_ust_app_channel() at ust-app.c:763)
|
|
DEBUG3 [7406/7410]: UST app shadow copy of channel channel0 done (in shadow_copy_channel() at ust-app.c:1345)
|
|
DEBUG1 [7406/7410]: UST app creating channel channel0 with per UID buffers (in create_channel_per_uid() at ust-app.c:2191)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: Buffer registry channel create with key: 0 (in buffer_reg_channel_create() at buffer-registry.c:324)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6d24a0 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: mkdir() recursive /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit with mode 504 for uid 0 and gid 0 (in run_as_mkdir_recursive() at runas.c:306)
|
|
DEBUG1 [7406/7410]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG3 [7406/7410]: UST registry channel finding key 0 (in ust_registry_channel_find() at ust-registry.c:400)
|
|
DEBUG1 [7882/7887]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2874)
|
|
DEBUG1 [7882/7887]: Allocated channel (key 28) (in consumer_allocate_channel() at consumer.c:865)
|
|
DEBUG3 [7882/7887]: Creating channel to ustctl with attr: [overwrite: 0, subbuf_size: 4096, num_subbuf: 4, switch_timer_interval: 0, read_timer_interval: 200000, output: 0, type: 0 (in create_ust_channel() at ust-consumer.c:342)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_0 (key 24, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_0 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_0 (key: 24) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_1 (key 27, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_1 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_1 (key: 27) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_2 (key 30, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_2 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_2 (key: 30) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_3 (key 33, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_3 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_3 (key: 33) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_4 (key 36, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_4 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_4 (key: 36) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_5 (key 39, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_5 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_5 (key: 39) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_6 (key 42, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_6 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_6 (key: 42) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_7 (key 45, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_7 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_7 (key: 45) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_8 (key 48, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_8 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_8 (key: 48) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_9 (key 51, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_9 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_9 (key: 51) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_10 (key 54, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_10 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_10 (key: 54) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_11 (key 57, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_11 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_11 (key: 57) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_12 (key 60, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_12 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_12 (key: 60) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_13 (key 63, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_13 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_13 (key: 63) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_14 (key 66, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_14 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_14 (key: 66) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG3 [7882/7887]: Allocated stream channel0_15 (key 69, chan_key 28 relayd_id 18446744073709551615, session_id 6 (in consumer_allocate_stream() at consumer.c:597)
|
|
DEBUG3 [7882/7887]: open() /tmp/_TraceCValidator_rvr7Qe//ust//uid/0/64-bit/channel0_15 with flags 241 mode 432 for uid 0 and gid 0 (in run_as_open() at runas.c:334)
|
|
DEBUG1 [7882/7887]: Using run_as_clone (in run_as() at runas.c:289)
|
|
DEBUG1 [7882/7887]: UST consumer add stream channel0_15 (key: 69) with relayd id 0 (in create_ust_streams() at ust-consumer.c:304)
|
|
DEBUG1 [7882/7887]: UST consumer channel added (key: 28) (in add_channel() at ust-consumer.c:105)
|
|
DEBUG1 [7882/7884]: Channel event catched in thread (in consumer_thread_channel_poll() at consumer.c:2652)
|
|
DEBUG1 [7882/7884]: Adding channel 20 to poll set (in consumer_thread_channel_poll() at consumer.c:2693)
|
|
DEBUG1 [7882/7887]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2892)
|
|
DEBUG1 [7882/7884]: Channel poll wait with 5 fd(s) (in consumer_thread_channel_poll() at consumer.c:2650)
|
|
DEBUG1 [7882/7887]: Incoming command on sock (in consumer_thread_sessiond_poll() at consumer.c:2874)
|
|
DEBUG1 [7882/7887]: UST consumer sending channel channel0 to sessiond (in send_sessiond_channel() at ust-consumer.c:398)
|
|
DEBUG1 [7882/7887]: UST consumer NULL stream sent to sessiond (in send_sessiond_channel() at ust-consumer.c:439)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG1 [7882/7887]: received command on sock (in consumer_thread_sessiond_poll() at consumer.c:2892)
|
|
DEBUG3 [7406/7410]: UST app consumer has no more stream available (in ust_consumer_get_channel() at ust-consumer.c:274)
|
|
DEBUG3 [7406/7410]: Buffer registry creating stream (in buffer_reg_stream_create() at buffer-registry.c:354)
|
|
DEBUG3 [7406/7410]: Buffer registry creating stream (in buffer_reg_stream_create() at buffer-registry.c:354)
|
|
DEBUG1 [7882/7886]: polling on 2 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 27 (in add_stream() at consumer.c:624)
|
|
DEBUG3 [7406/7410]: Buffer registry creating stream (in buffer_reg_stream_create() at buffer-registry.c:354)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG3 [7406/7410]: Buffer registry creating stream (in buffer_reg_stream_create() at buffer-registry.c:354)
|
|
DEBUG1 [7882/7886]: polling on 3 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 30 (in add_stream() at consumer.c:624)
|
|
DEBUG3 [7406/7410]: Buffer registry creating stream (in buffer_reg_stream_create() at buffer-registry.c:354)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG3 [7406/7410]: Buffer registry creating stream (in buffer_reg_stream_create() at buffer-registry.c:354)
|
|
DEBUG1 [7882/7886]: polling on 4 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 33 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 5 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 36 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 6 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 39 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 7 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 42 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 8 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 45 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 9 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 48 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 10 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 51 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 11 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 54 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 12 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 57 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 13 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 60 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 14 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 63 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 15 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 66 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 16 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 1 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: consumer_data_pipe wake up (in consumer_thread_data_poll() at consumer.c:2385)
|
|
DEBUG3 [7882/7886]: Adding consumer stream 69 (in add_stream() at consumer.c:624)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 17 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x67dc50 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6d51d0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6d54e0 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: UST app channel channel0 allocated (in alloc_ust_app_channel() at ust-app.c:763)
|
|
DEBUG3 [7406/7410]: UST app shadow copy of channel channel0 done (in shadow_copy_channel() at ust-app.c:1345)
|
|
DEBUG1 [7406/7410]: UST app creating channel channel0 with per UID buffers (in create_channel_per_uid() at ust-app.c:2191)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG1 [7406/7410]: UST app sending buffer registry channel to ust sock 23 (in send_channel_uid_to_ust() at ust-app.c:2129)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6753e0 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6761f0 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x676cc0 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: UST app channel channel0 allocated (in alloc_ust_app_channel() at ust-app.c:763)
|
|
DEBUG3 [7406/7410]: UST app shadow copy of channel channel0 done (in shadow_copy_channel() at ust-app.c:1345)
|
|
DEBUG1 [7406/7410]: UST app creating channel channel0 with per UID buffers (in create_channel_per_uid() at ust-app.c:2191)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG1 [7406/7410]: UST app sending buffer registry channel to ust sock 28 (in send_channel_uid_to_ust() at ust-app.c:2129)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x678110 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x678f20 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x6791c0 of type 0 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: UST app channel channel0 allocated (in alloc_ust_app_channel() at ust-app.c:763)
|
|
DEBUG3 [7406/7410]: UST app shadow copy of channel channel0 done (in shadow_copy_channel() at ust-app.c:1345)
|
|
DEBUG1 [7406/7410]: UST app creating channel channel0 with per UID buffers (in create_channel_per_uid() at ust-app.c:2191)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG1 [7406/7410]: UST app sending buffer registry channel to ust sock 26 (in send_channel_uid_to_ust() at ust-app.c:2129)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 9 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Getting session _TraceCValidator_rvr7Qe by name (in process_client_msg() at main.c:2423)
|
|
DEBUG3 [7406/7410]: No kernel session when tearing down session (in kernel_destroy_session() at kernel.c:739)
|
|
DEBUG1 [7406/7410]: Destroy all UST traces (in ust_app_destroy_trace_all() at ust-app.c:3875)
|
|
DEBUG1 [7406/7410]: Destroy tracing for ust app pid 14267 (in destroy_trace() at ust-app.c:3754)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST app deleting channel channel0 (in delete_ust_app_channel() at ust-app.c:325)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST registry channel finding key 28 (in ust_registry_channel_find() at ust-registry.c:400)
|
|
DEBUG1 [7406/7410]: Destroy tracing for ust app pid 14266 (in destroy_trace() at ust-app.c:3754)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST app deleting channel channel0 (in delete_ust_app_channel() at ust-app.c:325)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST registry channel finding key 29 (in ust_registry_channel_find() at ust-registry.c:400)
|
|
DEBUG1 [7406/7410]: Destroy tracing for ust app pid 14275 (in destroy_trace() at ust-app.c:3754)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST app deleting channel channel0 (in delete_ust_app_channel() at ust-app.c:325)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST registry channel finding key 30 (in ust_registry_channel_find() at ust-registry.c:400)
|
|
DEBUG1 [7406/7410]: Destroy tracing for ust app pid 14268 (in destroy_trace() at ust-app.c:3754)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST app deleting channel channel0 (in delete_ust_app_channel() at ust-app.c:325)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID find id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7410]: UST registry channel finding key 31 (in ust_registry_channel_find() at ust-registry.c:400)
|
|
DEBUG3 [7406/7410]: Buffer registry per UID destroy with id: 6, ABI: 64, uid: 0 (in buffer_reg_uid_destroy() at buffer-registry.c:615)
|
|
DEBUG1 [7406/7410]: Destroying session _TraceCValidator_rvr7Qe (in session_destroy() at session.c:156)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7415]: Updating kernel poll set (in update_kernel_poll() at main.c:565)
|
|
DEBUG1 [7406/7415]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:744)
|
|
DEBUG3 [7406/7907]: Buffer registry session destroy (in buffer_reg_session_destroy() at buffer-registry.c:536)
|
|
DEBUG3 [7406/7907]: Buffer registry channel destroy with key 0 and handle -1 (in buffer_reg_channel_destroy() at buffer-registry.c:496)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG3 [7406/7907]: Buffer registry stream destroy with handle -1 (in buffer_reg_stream_destroy() at buffer-registry.c:443)
|
|
DEBUG1 [7882/7884]: Channel event catched in thread (in consumer_thread_channel_poll() at consumer.c:2652)
|
|
DEBUG1 [7882/7884]: Channel fd 20 is hup|err. (in consumer_thread_channel_poll() at consumer.c:2734)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 9 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: fd 33 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7884]: Channel poll wait with 4 fd(s) (in consumer_thread_channel_poll() at consumer.c:2650)
|
|
DEBUG1 [7882/7886]: Polling fd 33 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 39 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 39 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 45 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 45 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 24 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 24 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 42 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 42 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 36 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 36 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 30 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 30 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 27 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 27 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 48 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 48 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: polling on 17 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 16 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: Normal read on fd 33 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 39 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 45 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 24 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 42 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 36 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 30 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 27 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 48 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: fd 63 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 63 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 33 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 60 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 60 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 51 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 51 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 39 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 45 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 24 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 42 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 54 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 54 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 69 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 69 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 36 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 30 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 27 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 57 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 57 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: fd 66 is hup|err|nval. Attempting flush and read. (in consumer_thread_data_poll() at consumer.c:2480)
|
|
DEBUG1 [7882/7886]: Polling fd 66 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 48 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: polling on 17 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 16 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: Normal read on fd 63 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 33 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 60 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 51 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 39 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 45 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 24 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 42 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 54 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 69 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 36 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 30 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 27 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 57 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 66 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Consumer mmap write() ret 4096 (len 4096) (in lttng_consumer_on_read_subbuffer_mmap() at consumer.c:1421)
|
|
DEBUG1 [7882/7886]: Normal read on fd 48 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Polling fd 63 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 33 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 33 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 60 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 51 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 39 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 39 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 45 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 45 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 24 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 24 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 42 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 42 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 54 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 69 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 36 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 36 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 30 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 30 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 27 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 27 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 57 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 66 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Polling fd 48 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 48 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 8 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7886]: poll num_rdy : 7 (in consumer_thread_data_poll() at consumer.c:2361)
|
|
DEBUG1 [7882/7886]: Normal read on fd 63 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 60 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 51 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 54 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 69 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 57 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Normal read on fd 66 (in consumer_thread_data_poll() at consumer.c:2457)
|
|
DEBUG1 [7882/7886]: Reserving sub buffer failed (everything is normal, it is due to concurrency) [ret: -11] (in lttng_ustconsumer_read_subbuffer() at ust-consumer.c:1244)
|
|
DEBUG1 [7882/7886]: Polling fd 63 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 63 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 60 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 60 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 51 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 51 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 54 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 54 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 69 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 69 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 57 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 57 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Polling fd 66 tells it has hung up. (in consumer_thread_data_poll() at consumer.c:2491)
|
|
DEBUG1 [7882/7886]: Consumer del stream 66 (in consumer_del_stream() at consumer.c:436)
|
|
DEBUG1 [7882/7886]: Consumer delete channel key 28 (in consumer_del_channel() at consumer.c:278)
|
|
DEBUG1 [7882/7886]: Updating poll fd array (in update_poll_array() at consumer.c:927)
|
|
DEBUG1 [7882/7886]: polling on 1 fd (in consumer_thread_data_poll() at consumer.c:2359)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 9 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
DEBUG1 [7882/7888]: Sending metadata request to sessiond, session 1 (in lttng_ustconsumer_request_metadata() at ust-consumer.c:1423)
|
|
DEBUG1 [7406/7883]: Metadata request received for session 1, key 9 (in ust_consumer_metadata_request() at ust-consumer.c:443)
|
|
DEBUG3 [7406/7883]: Buffer registry per UID find id: 1, ABI: 64, uid: 0 (in buffer_reg_uid_find() at buffer-registry.c:194)
|
|
DEBUG3 [7406/7883]: Buffer registry per PID find id: 1 (in buffer_reg_pid_find() at buffer-registry.c:299)
|
|
DEBUG1 [7406/7883]: PID registry not found for session id 1 (in ust_consumer_metadata_request() at ust-consumer.c:454)
|
|
DEBUG1 [7882/7888]: Switch timer for channel 0 (in metadata_switch_timer() at consumer-timer.c:64)
|
|
|
|
>>>>>>>>>>>>>>>>>>
|
|
lttng-consumerd: ust-consumer.c:1404: lttng_ustconsumer_request_metadata: Assertion `channel->metadata_cache' failed.
|
|
Error: consumer err socket second poll error
|
|
Error: Health error occurred in thread_manage_consumer
|
|
<<<<<<<<<<<<<<<<<<
|
|
|
|
DEBUG1 [7406/7883]: consumer thread cleanup completed (in thread_manage_consumer() at main.c:1135)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 8 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Waiting for 2 URIs from client ... (in process_client_msg() at main.c:2842)
|
|
DEBUG1 [7406/7410]: Tracing session TRACcin0251_1 created in (null) with ID 7 by UID 0 GID 0 (in session_create() at session.c:238)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x671610 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG3 [7406/7410]: Consumer control URI set with port 59383 (in consumer_set_network_uri() at consumer.c:508)
|
|
DEBUG3 [7406/7410]: Consumer set network uri subdir path SC-1/./ (in consumer_set_network_uri() at consumer.c:560)
|
|
DEBUG3 [7406/7410]: Append domain trace name to subdir SC-1/./ (in add_uri_to_consumer() at cmd.c:418)
|
|
DEBUG3 [7406/7410]: Consumer data URI set with port 50886 (in consumer_set_network_uri() at consumer.c:517)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 5 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Getting session TRACcin0251_1 by name (in process_client_msg() at main.c:2423)
|
|
|
|
>>>>>>>>>>>>>>>>>>
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: No UST consumer detected) (in thread_manage_clients() at main.c:3462)
|
|
<<<<<<<<<<<<<<<<<<
|
|
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 9 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Getting session TRACcin0251_1 by name (in process_client_msg() at main.c:2423)
|
|
DEBUG3 [7406/7410]: No kernel session when tearing down session (in kernel_destroy_session() at kernel.c:739)
|
|
DEBUG1 [7406/7410]: Destroying session TRACcin0251_1 (in session_destroy() at session.c:156)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7415]: Updating kernel poll set (in update_kernel_poll() at main.c:565)
|
|
DEBUG1 [7406/7415]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:744)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 8 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Waiting for 1 URIs from client ... (in process_client_msg() at main.c:2842)
|
|
DEBUG1 [7406/7410]: Tracing session _TraceCValidator_cOf3Ku created in (null) with ID 8 by UID 0 GID 0 (in session_create() at session.c:238)
|
|
DEBUG3 [7406/7410]: Created hashtable size 4 at 0x66ba90 of type 1 (in lttng_ht_new() at hashtable.c:112)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 5 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Getting session _TraceCValidator_cOf3Ku by name (in process_client_msg() at main.c:2423)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: No UST consumer detected) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7410]: Wait for client response (in thread_manage_clients() at main.c:3366)
|
|
DEBUG1 [7406/7410]: Receiving data from client ... (in thread_manage_clients() at main.c:3411)
|
|
DEBUG1 [7406/7410]: Processing client command 9 (in process_client_msg() at main.c:2352)
|
|
DEBUG1 [7406/7410]: Getting session _TraceCValidator_cOf3Ku by name (in process_client_msg() at main.c:2423)
|
|
DEBUG3 [7406/7410]: No kernel session when tearing down session (in kernel_destroy_session() at kernel.c:739)
|
|
DEBUG1 [7406/7410]: Destroying session _TraceCValidator_cOf3Ku (in session_destroy() at session.c:156)
|
|
DEBUG1 [7406/7410]: Sending response (size: 16, retcode: Success) (in thread_manage_clients() at main.c:3462)
|
|
DEBUG1 [7406/7410]: Clean command context structure (in clean_command_ctx() at main.c:484)
|
|
DEBUG1 [7406/7415]: Updating kernel poll set (in update_kernel_poll() at main.c:565)
|
|
DEBUG1 [7406/7415]: Thread kernel polling on 2 fds (in thread_manage_kernel() at main.c:744)
|
|
DEBUG1 [7406/7410]: Accepting client command ... (in thread_manage_clients() at main.c:3324)
|
|
DEBUG1 [7406/7413]: PID 14266 unregistering with sock 23 (in ust_app_unregister() at ust-app.c:2759)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 5 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG1 [7406/7413]: PID 14275 unregistering with sock 28 (in ust_app_unregister() at ust-app.c:2759)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 4 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG1 [7406/7414]: UST app notify socket unregister 25 (in ust_app_notify_sock_unregister() at ust-app.c:4630)
|
|
DEBUG1 [7406/7413]: PID 14268 unregistering with sock 26 (in ust_app_unregister() at ust-app.c:2759)
|
|
DEBUG1 [7406/7414]: UST app notify socket unregister 29 (in ust_app_notify_sock_unregister() at ust-app.c:4630)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 3 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG1 [7406/7414]: UST app notify socket unregister 27 (in ust_app_notify_sock_unregister() at ust-app.c:4630)
|
|
DEBUG1 [7406/7413]: PID 14267 unregistering with sock 22 (in ust_app_unregister() at ust-app.c:2759)
|
|
DEBUG1 [7406/7414]: UST app notify socket unregister 24 (in ust_app_notify_sock_unregister() at ust-app.c:4630)
|
|
DEBUG1 [7406/7413]: Apps thread polling on 2 fds (in thread_manage_apps() at main.c:1179)
|
|
DEBUG3 [7406/7414]: [ust-thread] Manage notify polling on 2 fds (in ust_thread_manage_notify() at ust-thread.c:54)
|
|
DEBUG3 [7406/7907]: Call RCU deleting app PID 14266 (in delete_ust_app_rcu() at ust-app.c:663)
|
|
DEBUG3 [7406/7907]: Call RCU deleting app PID 14275 (in delete_ust_app_rcu() at ust-app.c:663)
|
|
DEBUG3 [7406/7907]: Call RCU deleting app PID 14268 (in delete_ust_app_rcu() at ust-app.c:663)
|
|
DEBUG3 [7406/7907]: Call RCU deleting app PID 14267 (in delete_ust_app_rcu() at ust-app.c:663)
|