|
DBG3 - 09:02:56.129715485 [168299/168299]: URI string: tcp://0.0.0.0:5342 (in uri_parse() at uri.c:293)
|
|
DBG2 - 09:02:56.130281407 [168299/168299]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:154)
|
|
DBG3 - 09:02:56.131450781 [168299/168299]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5342, data: 0 (in uri_parse() at uri.c:505)
|
|
DBG3 - 09:02:56.131509628 [168299/168299]: URI string: tcp://0.0.0.0:5343 (in uri_parse() at uri.c:293)
|
|
DBG2 - 09:02:56.131627163 [168299/168299]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:154)
|
|
DBG3 - 09:02:56.131676962 [168299/168299]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5343, data: 0 (in uri_parse() at uri.c:505)
|
|
DBG3 - 09:02:56.131729792 [168299/168299]: URI string: tcp://localhost:5344 (in uri_parse() at uri.c:293)
|
|
DBG2 - 09:02:56.133004140 [168299/168299]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:154)
|
|
DBG3 - 09:02:56.133064415 [168299/168299]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 5344, data: 0 (in uri_parse() at uri.c:505)
|
|
DBG1 - 09:02:56.133112442 [168299/168299]: File descriptor count limits are 1024 (soft) and 1048576 (hard) (in set_fd_pool_size() at main.c:480)
|
|
DBG1 - 09:02:56.133208331 [168299/168299]: Signal handler set for SIGTERM, SIGUSR1, SIGPIPE and SIGINT (in set_signal_handler() at main.c:871)
|
|
DBG1 - 09:02:56.133250221 [168299/168299]: LTTng-relayd 2.14.0-pre - O-Beer - v2.12.0-rc1-1000-g3d982af2a-dirty (in relayd_config_log() at main.c:205)
|
|
DBG1 - 09:02:56.133299590 [168299/168299]: Clear command allowed (in main() at main.c:4200)
|
|
DBG1 - 09:02:56.133982785 [168299/168299]: Unlinked file pool created at: /home/frdeso/lttng-traces//.unlinked (in lttng_unlinked_file_pool_create() at inode.c:268)
|
|
DBG1 - 09:02:56.134041126 [168299/168299]: File descriptor tracker created with a limit of 1014 simultaneously-opened FDs (in fd_tracker_create() at fd-tracker.c:399)
|
|
DBG1 - 09:02:56.134114665 [168299/168299]: Health check time delta in seconds set to 20 (in health_init() at health.c:61)
|
|
DBG1 - 09:02:56.134409706 [168299/168299]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:535)
|
|
DBG3 - 09:02:56.134475395 [168299/168299]: Created hashtable size 4 at 0x555d082e4490 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG3 - 09:02:56.134529377 [168299/168299]: Created hashtable size 4 at 0x555d082e49d0 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG3 - 09:02:56.134595085 [168299/168299]: Created hashtable size 4 at 0x555d082e4f10 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:02:56.135439295 [168299/168299]: epoll set max size is 3220070 (in compat_epoll_set_max_size() at poll.c:343)
|
|
DBG1 - 09:02:56.135950169 [168299/168307]: [thread] Relay live listener started (in thread_listener() at live.c:673)
|
|
DBG1 - 09:02:56.136260983 [168299/168307]: Listening on Live listener socket 10 (in init_socket() at live.c:637)
|
|
DBG1 - 09:02:56.136419115 [168299/168307]: Listener accepting live viewers connections (in thread_listener() at live.c:707)
|
|
DBG1 - 09:02:56.136508584 [168299/168302]: [thread] Relay dispatcher started (in relay_thread_dispatcher() at main.c:1290)
|
|
DBG1 - 09:02:56.136593130 [168299/168302]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:02:56.136636063 [168299/168302]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1317)
|
|
DBG1 - 09:02:56.136707710 [168299/168301]: [thread] Manage health check started (in thread_manage_health() at health-relayd.c:272)
|
|
DBG3 - 09:02:56.136937019 [168299/168301]: Creating LTTng run directory: /home/frdeso/.lttng (in create_lttng_rundir_with_perm() at health-relayd.c:84)
|
|
DBG3 - 09:02:56.137020652 [168299/168301]: Creating LTTng run directory: /home/frdeso/.lttng/relayd (in create_lttng_rundir_with_perm() at health-relayd.c:84)
|
|
DBG1 - 09:02:56.137427015 [168299/168301]: Health check ready (in thread_manage_health() at health-relayd.c:363)
|
|
DBG1 - 09:02:56.137569347 [168299/168304]: [thread] Relay listener started (in relay_thread_listener() at main.c:1088)
|
|
DBG1 - 09:02:56.137784746 [168299/168304]: Listening on Control listener socket 14 (in relay_socket_create() at main.c:1032)
|
|
DBG1 - 09:02:56.137941275 [168299/168304]: Listening on Data listener socket 15 (in relay_socket_create() at main.c:1032)
|
|
DBG1 - 09:02:56.138083318 [168299/168304]: Listener accepting connections (in relay_thread_listener() at main.c:1135)
|
|
DBG1 - 09:02:56.138819670 [168299/168306]: [thread] Live viewer relay worker started (in thread_worker() at live.c:2401)
|
|
DBG3 - 09:02:56.139215457 [168299/168306]: Created hashtable size 4 at 0x7fa898000b80 of type ULONG (in lttng_ht_new() at hashtable.c:151)
|
|
DBG3 - 09:02:56.139469446 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:02:56.143521157 [168299/168303]: [thread] Relay worker started (in relay_thread_worker() at main.c:3819)
|
|
DBG3 - 09:02:56.143929475 [168299/168303]: Created hashtable size 4 at 0x7fa89c000b80 of type ULONG (in lttng_ht_new() at hashtable.c:151)
|
|
DBG3 - 09:02:56.144141988 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:02:56.146807312 [168299/168305]: [thread] Live viewer relay dispatcher started (in thread_dispatcher() at live.c:836)
|
|
DBG1 - 09:02:56.147164481 [168299/168305]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:02:56.147509740 [168299/168305]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:863)
|
|
DBG1 - 09:03:08.309135713 [168299/168307]: Relay new viewer connection received (in thread_listener() at live.c:724)
|
|
DBG1 - 09:03:08.309270128 [168299/168307]: Live socket to client accepted, socket 19 (in accept_live_sock() at live.c:593)
|
|
DBG1 - 09:03:08.309317170 [168299/168307]: Relay viewer connection accepted socket 19 (in thread_listener() at live.c:756)
|
|
DBG1 - 09:03:08.309403249 [168299/168307]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:108)
|
|
DBG1 - 09:03:08.309456160 [168299/168307]: Listener accepting live viewers connections (in thread_listener() at live.c:707)
|
|
DBG1 - 09:03:08.309521368 [168299/168305]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:92)
|
|
DBG1 - 09:03:08.309559900 [168299/168305]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:03:08.309597501 [168299/168305]: Dispatching viewer request waiting on sock 19 (in thread_dispatcher() at live.c:869)
|
|
DBG1 - 09:03:08.309670036 [168299/168305]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:863)
|
|
DBG1 - 09:03:08.309786532 [168299/168306]: Connection socket 19 added to poll (in thread_worker() at live.c:2488)
|
|
DBG3 - 09:03:08.309828829 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:08.310470732 [168299/168306]: Viewer control conn closed with 19 (in thread_worker() at live.c:2513)
|
|
DBG3 - 09:03:08.310536999 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:08.343769133 [168299/168304]: Relay new connection received (in relay_thread_listener() at main.c:1153)
|
|
DBG1 - 09:03:08.343881262 [168299/168304]: Control socket to relayd accepted, socket 19 (in accept_relayd_sock() at main.c:1073)
|
|
DBG1 - 09:03:08.343943405 [168299/168304]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG1 - 09:03:08.343986653 [168299/168304]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:108)
|
|
DBG1 - 09:03:08.344011090 [168299/168304]: Listener accepting connections (in relay_thread_listener() at main.c:1135)
|
|
DBG1 - 09:03:08.344052249 [168299/168302]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:92)
|
|
DBG1 - 09:03:08.344077773 [168299/168302]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:03:08.344113316 [168299/168302]: Dispatching request waiting on sock 19 (in relay_thread_dispatcher() at main.c:1323)
|
|
DBG1 - 09:03:08.344158259 [168299/168302]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1317)
|
|
DBG1 - 09:03:08.344234824 [168299/168303]: Connection socket 19 added (in relay_thread_worker() at main.c:3906)
|
|
DBG3 - 09:03:08.344263317 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.344308925 [168299/168303]: Done receiving control command header: fd = 19, cmd = 5, cmd_version = 0, payload size = 8 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.344341175 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.344382012 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 8 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.344406614 [168299/168303]: Processing "RELAYD_VERSION" command for socket 19 (in relay_process_control_command() at main.c:3243)
|
|
DBG1 - 09:03:08.344553748 [168299/168303]: Version check done using protocol 2.14 (in relay_send_version() at main.c:2049)
|
|
DBG1 - 09:03:08.344584095 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.344606582 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.344849622 [168299/168303]: Done receiving control command header: fd = 19, cmd = 22, cmd_version = 0, payload size = 8 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.344875716 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.344909485 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 8 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.344930418 [168299/168303]: Processing "RELAYD_GET_CONFIGURATION" command for socket 19 (in relay_process_control_command() at main.c:3295)
|
|
DBG1 - 09:03:08.345012092 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.345027336 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.345172627 [168299/168303]: Done receiving control command header: fd = 19, cmd = 2, cmd_version = 0, payload size = 78 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.345189867 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.345211302 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 78 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.345225700 [168299/168303]: Processing "RELAYD_CREATE_SESSION" command for socket 19 (in relay_process_control_command() at main.c:3227)
|
|
DBG3 - 09:03:08.345277002 [168299/168303]: Created hashtable size 4 at 0x7fa89c0034e0 of type STRING (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.345327680 [168299/168303]: Created trace chunk registry for sessiond {d1541fe5-4a8c-4dd5-8f14-4f6e8d825ab6} (in trace_chunk_registry_ht_element_create() at sessiond-trace-chunks.c:225)
|
|
DBG1 - 09:03:08.345525869 [168299/168303]: Created session 1 (in relay_create_session() at main.c:1443)
|
|
DBG1 - 09:03:08.345600101 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.345617675 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.346360373 [168299/168304]: Relay new connection received (in relay_thread_listener() at main.c:1153)
|
|
DBG1 - 09:03:08.346433765 [168299/168304]: Data socket to relayd accepted, socket 21 (in accept_relayd_sock() at main.c:1073)
|
|
DBG1 - 09:03:08.346455889 [168299/168304]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG1 - 09:03:08.346476844 [168299/168304]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:108)
|
|
DBG1 - 09:03:08.346511198 [168299/168304]: Listener accepting connections (in relay_thread_listener() at main.c:1135)
|
|
DBG1 - 09:03:08.346484143 [168299/168302]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:92)
|
|
DBG1 - 09:03:08.346632554 [168299/168302]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:03:08.346648262 [168299/168302]: Dispatching request waiting on sock 21 (in relay_thread_dispatcher() at main.c:1323)
|
|
DBG1 - 09:03:08.346673322 [168299/168302]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1317)
|
|
DBG1 - 09:03:08.346757971 [168299/168303]: Connection socket 21 added (in relay_thread_worker() at main.c:3906)
|
|
DBG3 - 09:03:08.346843654 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.368904992 [168299/168304]: Relay new connection received (in relay_thread_listener() at main.c:1153)
|
|
DBG1 - 09:03:08.368944213 [168299/168304]: Control socket to relayd accepted, socket 22 (in accept_relayd_sock() at main.c:1073)
|
|
DBG1 - 09:03:08.368980921 [168299/168304]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG1 - 09:03:08.369014130 [168299/168304]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:108)
|
|
DBG1 - 09:03:08.369022991 [168299/168304]: Listener accepting connections (in relay_thread_listener() at main.c:1135)
|
|
DBG1 - 09:03:08.369037922 [168299/168302]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:92)
|
|
DBG1 - 09:03:08.369048051 [168299/168302]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:03:08.369057651 [168299/168302]: Dispatching request waiting on sock 22 (in relay_thread_dispatcher() at main.c:1323)
|
|
DBG1 - 09:03:08.369073755 [168299/168302]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1317)
|
|
DBG1 - 09:03:08.369090370 [168299/168303]: Connection socket 22 added (in relay_thread_worker() at main.c:3906)
|
|
DBG3 - 09:03:08.369099685 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.369115307 [168299/168303]: Done receiving control command header: fd = 22, cmd = 5, cmd_version = 0, payload size = 8 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.369123626 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.369136583 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 8 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.369146270 [168299/168303]: Processing "RELAYD_VERSION" command for socket 22 (in relay_process_control_command() at main.c:3243)
|
|
DBG1 - 09:03:08.369217089 [168299/168303]: Version check done using protocol 2.14 (in relay_send_version() at main.c:2049)
|
|
DBG1 - 09:03:08.369281858 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.369297547 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.369318066 [168299/168303]: Done receiving control command header: fd = 22, cmd = 22, cmd_version = 0, payload size = 8 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.369332688 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.369358266 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 8 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.369369538 [168299/168303]: Processing "RELAYD_GET_CONFIGURATION" command for socket 22 (in relay_process_control_command() at main.c:3295)
|
|
DBG1 - 09:03:08.369410140 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.369423719 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.369700413 [168299/168303]: Done receiving control command header: fd = 22, cmd = 2, cmd_version = 0, payload size = 78 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.369723386 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.369737968 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 78 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.369747310 [168299/168303]: Processing "RELAYD_CREATE_SESSION" command for socket 22 (in relay_process_control_command() at main.c:3227)
|
|
DBG3 - 09:03:08.369801859 [168299/168303]: Created hashtable size 4 at 0x7fa89c0065e0 of type STRING (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.369848078 [168299/168303]: Acquired reference to trace chunk registry of sessiond {d1541fe5-4a8c-4dd5-8f14-4f6e8d825ab6} (in sessiond_trace_chunk_registry_session_created() at sessiond-trace-chunks.c:309)
|
|
DBG1 - 09:03:08.369964190 [168299/168303]: Created session 2 (in relay_create_session() at main.c:1443)
|
|
DBG1 - 09:03:08.370072213 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.370114865 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.370766806 [168299/168304]: Relay new connection received (in relay_thread_listener() at main.c:1153)
|
|
DBG1 - 09:03:08.370852738 [168299/168304]: Data socket to relayd accepted, socket 24 (in accept_relayd_sock() at main.c:1073)
|
|
DBG1 - 09:03:08.370872567 [168299/168304]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG1 - 09:03:08.370918162 [168299/168304]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:108)
|
|
DBG1 - 09:03:08.370929442 [168299/168302]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:92)
|
|
DBG1 - 09:03:08.370947273 [168299/168304]: Listener accepting connections (in relay_thread_listener() at main.c:1135)
|
|
DBG1 - 09:03:08.370970311 [168299/168302]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:03:08.371005487 [168299/168302]: Dispatching request waiting on sock 24 (in relay_thread_dispatcher() at main.c:1323)
|
|
DBG1 - 09:03:08.371030415 [168299/168302]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1317)
|
|
DBG1 - 09:03:08.371058176 [168299/168303]: Connection socket 24 added (in relay_thread_worker() at main.c:3906)
|
|
DBG3 - 09:03:08.371084135 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.515445781 [168299/168303]: Done receiving control command header: fd = 19, cmd = 19, cmd_version = 0, payload size = 20 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.515600330 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.515730528 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 20 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.515871510 [168299/168303]: Processing "RELAYD_CREATE_TRACE_CHUNK" command for socket 19 (in relay_process_control_command() at main.c:3283)
|
|
DBG1 - 09:03:08.515990404 [168299/168303]: Creating trace chunk: chunk_id = 0, creation time = 20211130-090308 (in lttng_trace_chunk_create() at trace-chunk.c:425)
|
|
DBG1 - 09:03:08.516082794 [168299/168303]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:456)
|
|
DBG1 - 09:03:08.516167814 [168299/168303]: Attempting to publish trace chunk: sessiond {d1541fe5-4a8c-4dd5-8f14-4f6e8d825ab6}, session_id = 1, chunk_id = 0 (in sessiond_trace_chunk_registry_publish_chunk() at sessiond-trace-chunks.c:385)
|
|
DBG1 - 09:03:08.517393131 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.517595238 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.518210922 [168299/168303]: Done receiving control command header: fd = 22, cmd = 19, cmd_version = 0, payload size = 20 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.518311434 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.518400159 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 20 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.518495433 [168299/168303]: Processing "RELAYD_CREATE_TRACE_CHUNK" command for socket 22 (in relay_process_control_command() at main.c:3283)
|
|
DBG1 - 09:03:08.518570455 [168299/168303]: Creating trace chunk: chunk_id = 0, creation time = 20211130-090308 (in lttng_trace_chunk_create() at trace-chunk.c:425)
|
|
DBG1 - 09:03:08.518669431 [168299/168303]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:456)
|
|
DBG1 - 09:03:08.518750431 [168299/168303]: Attempting to publish trace chunk: sessiond {d1541fe5-4a8c-4dd5-8f14-4f6e8d825ab6}, session_id = 2, chunk_id = 0 (in sessiond_trace_chunk_registry_publish_chunk() at sessiond-trace-chunks.c:385)
|
|
DBG1 - 09:03:08.519029443 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.519553808 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.526050412 [168299/168303]: Done receiving control command header: fd = 22, cmd = 1, cmd_version = 0, payload size = 48 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.526577050 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.527888772 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 48 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.528079410 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 22 (in relay_process_control_command() at main.c:3231)
|
|
DBG1 - 09:03:08.528544856 [168299/168303]: CTF Trace path kernel not found (in ctf_trace_get_by_path_or_create() at ctf-trace.c:139)
|
|
DBG1 - 09:03:08.528780544 [168299/168303]: Created ctf_trace 1of session "py_syscalls" from host "raton" with path: kernel (in ctf_trace_create() at ctf-trace.c:112)
|
|
DBG3 - 09:03:08.528994047 [168299/168303]: Created hashtable size 4 at 0x7fa89c007460 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.529202873 [168299/168303]: Creating trace chunk subdirectory "kernel" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.529781330 [168299/168303]: Adding new top-level directory "kernel" to trace chunk "(unnamed)" (in add_top_level_directory_unique() at trace-chunk.c:1180)
|
|
DBG1 - 09:03:08.530054721 [168299/168303]: Opening trace chunk file "kernel/metadata" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.530241284 [168299/168303]: Adding new file "kernel/metadata" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.530545851 [168299/168303]: Relay new stream added metadata with ID 1 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.530968073 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.534060421 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.534826730 [168299/168303]: Done receiving control command header: fd = 22, cmd = 1, cmd_version = 0, payload size = 50 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.535136365 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.535269295 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 50 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.535453147 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 22 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.535611561 [168299/168303]: Created hashtable size 4 at 0x7fa89c007d80 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.535745356 [168299/168303]: Creating trace chunk subdirectory "kernel" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.535915989 [168299/168303]: Opening trace chunk file "kernel/channel0_3" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.536041958 [168299/168303]: Adding new file "kernel/channel0_3" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.536288815 [168299/168303]: Relay new stream added channel0_3 with ID 2 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.536499898 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.536880443 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.536990216 [168299/168303]: Done receiving control command header: fd = 22, cmd = 1, cmd_version = 0, payload size = 50 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.537089348 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.537184919 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 50 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.537278890 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 22 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.537386723 [168299/168303]: Created hashtable size 4 at 0x7fa89c008660 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.537479418 [168299/168303]: Creating trace chunk subdirectory "kernel" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.537585904 [168299/168303]: Opening trace chunk file "kernel/channel0_2" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.537683708 [168299/168303]: Adding new file "kernel/channel0_2" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.537846658 [168299/168303]: Relay new stream added channel0_2 with ID 3 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.538061049 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.538595319 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.538727050 [168299/168303]: Done receiving control command header: fd = 22, cmd = 1, cmd_version = 0, payload size = 50 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.538870939 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.539020793 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 50 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.540612449 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 22 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.541141375 [168299/168303]: Created hashtable size 4 at 0x7fa89c008f50 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.541321385 [168299/168303]: Creating trace chunk subdirectory "kernel" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.541476655 [168299/168303]: Opening trace chunk file "kernel/channel0_1" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.541589362 [168299/168303]: Adding new file "kernel/channel0_1" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.541769923 [168299/168303]: Relay new stream added channel0_1 with ID 4 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.542037396 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.542064516 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.542339064 [168299/168303]: Done receiving control command header: fd = 22, cmd = 1, cmd_version = 0, payload size = 50 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.542453289 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.542579002 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 50 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.542694034 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 22 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.542823992 [168299/168303]: Created hashtable size 4 at 0x7fa89c009830 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.542928499 [168299/168303]: Creating trace chunk subdirectory "kernel" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.542973322 [168299/168303]: Opening trace chunk file "kernel/channel0_0" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.542996680 [168299/168303]: Adding new file "kernel/channel0_0" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.543085930 [168299/168303]: Relay new stream added channel0_0 with ID 5 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.543251795 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.543277313 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.543302717 [168299/168303]: Done receiving control command header: fd = 22, cmd = 16, cmd_version = 0, payload size = 0 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG1 - 09:03:08.543320433 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 0 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.543336013 [168299/168303]: Processing "RELAYD_STREAMS_SENT" command for socket 22 (in relay_process_control_command() at main.c:3271)
|
|
DBG1 - 09:03:08.543350956 [168299/168303]: Relay receiving streams_sent (in relay_streams_sent() at main.c:2478)
|
|
DBG1 - 09:03:08.543423711 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.543440358 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.569198548 [168299/168303]: Done receiving control command header: fd = 22, cmd = 6, cmd_version = 0, payload size = 317745 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.569570306 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:08.569690415 [168299/168303]: Partial reception of control connection protocol payload (received 65536 bytes, 252209 bytes left to receive, fd = 22) (in relay_process_control_receive_payload() at main.c:3353)
|
|
DBG3 - 09:03:08.569724894 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:08.569778371 [168299/168303]: Partial reception of control connection protocol payload (received 294912 bytes, 22833 bytes left to receive, fd = 22) (in relay_process_control_receive_payload() at main.c:3353)
|
|
DBG3 - 09:03:08.569799627 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.611772070 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 317745 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.611801124 [168299/168303]: Processing "RELAYD_SEND_METADATA" command for socket 22 (in relay_process_control_command() at main.c:3239)
|
|
DBG1 - 09:03:08.612393824 [168299/168303]: Wrote to metadata stream 1: data_length = 317733, padding_length = 1755 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:08.612438938 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.612456858 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.632169870 [168299/168303]: Done receiving control command header: fd = 19, cmd = 1, cmd_version = 0, payload size = 75 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.632281618 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.632317568 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 75 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.632336407 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 19 (in relay_process_control_command() at main.c:3231)
|
|
DBG1 - 09:03:08.632356690 [168299/168303]: CTF Trace path ust/uid/1000/64-bit not found (in ctf_trace_get_by_path_or_create() at ctf-trace.c:139)
|
|
DBG1 - 09:03:08.632410177 [168299/168303]: Created ctf_trace 2of session "py_syscalls" from host "raton" with path: ust/uid/1000/64-bit (in ctf_trace_create() at ctf-trace.c:112)
|
|
DBG3 - 09:03:08.632436833 [168299/168303]: Created hashtable size 4 at 0x7fa89c00a250 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.632452595 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.632864696 [168299/168303]: Adding new top-level directory "ust/uid/1000/64-bit" to trace chunk "(unnamed)" (in add_top_level_directory_unique() at trace-chunk.c:1180)
|
|
DBG1 - 09:03:08.632943924 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/lttng_python_channel_0" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.632960084 [168299/168303]: Adding new file "ust/uid/1000/64-bit/lttng_python_channel_0" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.633136089 [168299/168303]: Relay new stream added lttng_python_channel_0 with ID 6 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.633243314 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.633282560 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.633307355 [168299/168303]: Done receiving control command header: fd = 19, cmd = 1, cmd_version = 0, payload size = 75 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.633319736 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.633332810 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 75 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.633341503 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 19 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.633365894 [168299/168303]: Created hashtable size 4 at 0x7fa89c00ab90 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.633377876 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.633413453 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/lttng_python_channel_1" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.633422196 [168299/168303]: Adding new file "ust/uid/1000/64-bit/lttng_python_channel_1" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.633467700 [168299/168303]: Relay new stream added lttng_python_channel_1 with ID 7 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.633500818 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.633508506 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.633548574 [168299/168303]: Done receiving control command header: fd = 19, cmd = 1, cmd_version = 0, payload size = 75 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.633562612 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.633575372 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 75 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.633583948 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 19 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.633597552 [168299/168303]: Created hashtable size 4 at 0x7fa89c00b4b0 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.633606080 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.633644810 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/lttng_python_channel_2" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.633672768 [168299/168303]: Adding new file "ust/uid/1000/64-bit/lttng_python_channel_2" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.633715948 [168299/168303]: Relay new stream added lttng_python_channel_2 with ID 8 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.633755938 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.633767311 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.633782053 [168299/168303]: Done receiving control command header: fd = 19, cmd = 1, cmd_version = 0, payload size = 75 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.633791555 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.633804748 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 75 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.633816007 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 19 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.633837908 [168299/168303]: Created hashtable size 4 at 0x7fa89c00bde0 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.633847359 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.633870197 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/lttng_python_channel_3" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.633879812 [168299/168303]: Adding new file "ust/uid/1000/64-bit/lttng_python_channel_3" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.633936797 [168299/168303]: Relay new stream added lttng_python_channel_3 with ID 9 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.633997050 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.634008542 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.637604851 [168299/168303]: Done receiving control command header: fd = 19, cmd = 1, cmd_version = 0, payload size = 61 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:08.637639751 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.637714635 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 61 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.637728487 [168299/168303]: Processing "RELAYD_ADD_STREAM" command for socket 19 (in relay_process_control_command() at main.c:3231)
|
|
DBG3 - 09:03:08.637771146 [168299/168303]: Created hashtable size 4 at 0x7fa89c00c720 of type U64 (in lttng_ht_new() at hashtable.c:151)
|
|
DBG1 - 09:03:08.637779033 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:08.637818648 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/metadata" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:08.637827122 [168299/168303]: Adding new file "ust/uid/1000/64-bit/metadata" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:08.637937099 [168299/168303]: Relay new stream added metadata with ID 10 (in stream_create() at stream.c:659)
|
|
DBG1 - 09:03:08.637989252 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.638002458 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:08.638075203 [168299/168303]: Done receiving control command header: fd = 19, cmd = 16, cmd_version = 0, payload size = 0 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG1 - 09:03:08.638093739 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 0 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:08.638108661 [168299/168303]: Processing "RELAYD_STREAMS_SENT" command for socket 19 (in relay_process_control_command() at main.c:3271)
|
|
DBG1 - 09:03:08.638119055 [168299/168303]: Relay receiving streams_sent (in relay_streams_sent() at main.c:2478)
|
|
DBG1 - 09:03:08.638161751 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:08.638172795 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.632387152 [168299/168303]: Received data connection header on fd 21: circuit_id = 0, stream_id = 6, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.632493401 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.632554465 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.632604372 [168299/168303]: Receiving data for stream id 6 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.632932670 [168299/168303]: Wrote to stream 6: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.633051552 [168299/168303]: Wrote to stream 6: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.633097538 [168299/168303]: handle_index_data: stream 6 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.633163609 [168299/168303]: Finding index for stream id 6 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.633209787 [168299/168303]: Creating relay index for stream id 6 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.633253631 [168299/168303]: Adding relay index with stream id 6 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.633303769 [168299/168303]: Index found or created in HT for stream ID 6 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.633354733 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.633684149 [168299/168303]: Unlinking trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_0.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.633779749 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_0.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.633837874 [168299/168303]: Adding new file "ust/uid/1000/64-bit/index/lttng_python_channel_0.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:09.634267436 [168299/168303]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.634380667 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.638979126 [168299/168303]: Done receiving control command header: fd = 19, cmd = 6, cmd_version = 0, payload size = 3049 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.639199422 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.656140860 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 2, data_size = 4096, net_seq_num = 0, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.656294059 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.656905134 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.657124915 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.657285928 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.657458660 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.657531420 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.657655444 [168299/168303]: stream_add_index for stream 2 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.657746868 [168299/168303]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.657832901 [168299/168303]: Creating relay index for stream id 2 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.658676685 [168299/168303]: Adding relay index with stream id 2 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.658814376 [168299/168303]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.659055724 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.659279472 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.660240631 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.660334086 [168299/168303]: Receiving data for stream id 2 seqnum 0, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.660515002 [168299/168303]: Wrote to stream 2: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.660592636 [168299/168303]: Wrote to stream 2: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.660656086 [168299/168303]: handle_index_data: stream 2 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.660700888 [168299/168303]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.660749983 [168299/168303]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.660799385 [168299/168303]: Creating trace chunk subdirectory "kernel/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.663650403 [168299/168303]: Unlinking trace chunk file "kernel/index/channel0_3.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.663757125 [168299/168303]: Opening trace chunk file "kernel/index/channel0_3.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.664813551 [168299/168303]: Adding new file "kernel/index/channel0_3.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG2 - 09:03:09.665125875 [168299/168303]: Writing index for stream ID 2 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.665191632 [168299/168303]: index put for stream id 2 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.665273756 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.665324079 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.665401755 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.665438411 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.665498646 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.665534093 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.665568271 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.665604003 [168299/168303]: stream_add_index for stream 5 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.665644478 [168299/168303]: Finding index for stream id 5 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.665679794 [168299/168303]: Creating relay index for stream id 5 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.665718225 [168299/168303]: Adding relay index with stream id 5 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.665754085 [168299/168303]: Index found or created in HT for stream ID 5 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.665920801 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.665967032 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.666005756 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.666055386 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 5, data_size = 4096, net_seq_num = 0, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.666091108 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.668275231 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.668356698 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.668420605 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.668462911 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.668497961 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.668533377 [168299/168303]: stream_add_index for stream 4 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.668569760 [168299/168303]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.668606365 [168299/168303]: Creating relay index for stream id 4 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.668656168 [168299/168303]: Adding relay index with stream id 4 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.668802102 [168299/168303]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.669222074 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.669359296 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.669472938 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.669580127 [168299/168303]: Receiving data for stream id 5 seqnum 0, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.669896885 [168299/168303]: Wrote to stream 5: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.670077103 [168299/168303]: Wrote to stream 5: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.670157138 [168299/168303]: handle_index_data: stream 5 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.670233922 [168299/168303]: Finding index for stream id 5 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.670393928 [168299/168303]: Index found or created in HT for stream ID 5 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.670492713 [168299/168303]: Creating trace chunk subdirectory "kernel/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.670729598 [168299/168303]: Unlinking trace chunk file "kernel/index/channel0_0.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.670934043 [168299/168303]: Opening trace chunk file "kernel/index/channel0_0.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.671059526 [168299/168303]: Adding new file "kernel/index/channel0_0.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG2 - 09:03:09.671614816 [168299/168303]: Writing index for stream ID 5 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.671843216 [168299/168303]: index put for stream id 5 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.672015907 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.672105581 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.672268388 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.672377982 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.672498803 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.672563071 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.672646654 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.672700885 [168299/168303]: stream_add_index for stream 3 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.672762236 [168299/168303]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.672822675 [168299/168303]: Creating relay index for stream id 3 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.672891069 [168299/168303]: Adding relay index with stream id 3 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.672959599 [168299/168303]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.673265049 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.673343688 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.673422379 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.673495480 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 4, data_size = 200704, net_seq_num = 0, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.673582491 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.673658987 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.673720365 [168299/168303]: Receiving data for stream id 4 seqnum 0, 0 bytes received, 200704 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.674636163 [168299/168303]: Wrote to stream 4: data_length = 65536, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.678758921 [168299/168303]: Wrote to stream 4: data_length = 65536, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.679204475 [168299/168303]: Wrote to stream 4: data_length = 65536, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.679311158 [168299/168303]: Wrote to stream 4: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.679350521 [168299/168303]: Wrote to stream 4: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.679432032 [168299/168303]: handle_index_data: stream 4 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.679512673 [168299/168303]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.679577603 [168299/168303]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.679630730 [168299/168303]: Creating trace chunk subdirectory "kernel/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.679728386 [168299/168303]: Unlinking trace chunk file "kernel/index/channel0_1.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.679813484 [168299/168303]: Opening trace chunk file "kernel/index/channel0_1.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.679862773 [168299/168303]: Adding new file "kernel/index/channel0_1.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG2 - 09:03:09.680101916 [168299/168303]: Writing index for stream ID 4 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.680161481 [168299/168303]: index put for stream id 4 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.680254942 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.680315081 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.680380833 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.680435262 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 3, data_size = 4096, net_seq_num = 0, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.680477579 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.680523089 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.680563567 [168299/168303]: Receiving data for stream id 3 seqnum 0, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.680679878 [168299/168303]: Wrote to stream 3: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.680721343 [168299/168303]: Wrote to stream 3: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.680760004 [168299/168303]: handle_index_data: stream 3 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.680794170 [168299/168303]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.680829896 [168299/168303]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.680867493 [168299/168303]: Creating trace chunk subdirectory "kernel/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.680934745 [168299/168303]: Unlinking trace chunk file "kernel/index/channel0_2.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.680989558 [168299/168303]: Opening trace chunk file "kernel/index/channel0_2.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.681023135 [168299/168303]: Adding new file "kernel/index/channel0_2.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG2 - 09:03:09.681173340 [168299/168303]: Writing index for stream ID 3 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.681225826 [168299/168303]: index put for stream id 3 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.681283517 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.681325206 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.683815624 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 3049 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.683891512 [168299/168303]: Processing "RELAYD_SEND_METADATA" command for socket 19 (in relay_process_control_command() at main.c:3239)
|
|
DBG1 - 09:03:09.684062277 [168299/168303]: Wrote to metadata stream 10: data_length = 3037, padding_length = 1059 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.684188809 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.684238648 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.684304973 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.684346295 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.684406497 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.684442229 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.684473388 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.684503973 [168299/168303]: stream_add_index for stream 6 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.684535091 [168299/168303]: Finding index for stream id 6 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.684570987 [168299/168303]: Index found or created in HT for stream ID 6 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG2 - 09:03:09.684605362 [168299/168303]: Writing index for stream ID 6 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.684661381 [168299/168303]: index put for stream id 6 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.684882785 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.684929511 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.688949645 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.689052644 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.689166952 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.689219927 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.689265252 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.689315023 [168299/168303]: stream_add_index for stream 8 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.689360340 [168299/168303]: Finding index for stream id 8 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.689420855 [168299/168303]: Creating relay index for stream id 8 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.689510974 [168299/168303]: Adding relay index with stream id 8 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.689598937 [168299/168303]: Index found or created in HT for stream ID 8 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.689970118 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.690046273 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.690260610 [168299/168303]: Received data connection header on fd 21: circuit_id = 0, stream_id = 8, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.690330351 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.690386581 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.690455013 [168299/168303]: Receiving data for stream id 8 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.690718478 [168299/168303]: Wrote to stream 8: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.690867646 [168299/168303]: Wrote to stream 8: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.690928435 [168299/168303]: handle_index_data: stream 8 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.690967837 [168299/168303]: Finding index for stream id 8 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.691008104 [168299/168303]: Index found or created in HT for stream ID 8 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.691052198 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.691160908 [168299/168303]: Unlinking trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_2.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.691234286 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_2.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.691283715 [168299/168303]: Adding new file "ust/uid/1000/64-bit/index/lttng_python_channel_2.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG2 - 09:03:09.691584049 [168299/168303]: Writing index for stream ID 8 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.691648165 [168299/168303]: index put for stream id 8 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.691774164 [168299/168303]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.691826061 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.691898413 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.691988521 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.692079878 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.692155142 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.692279493 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.692365968 [168299/168303]: stream_add_index for stream 9 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.692456067 [168299/168303]: Finding index for stream id 9 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.692512103 [168299/168303]: Creating relay index for stream id 9 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.692559266 [168299/168303]: Adding relay index with stream id 9 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.692655346 [168299/168303]: Index found or created in HT for stream ID 9 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.692970570 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.693029579 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.693103403 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.693175360 [168299/168303]: Received data connection header on fd 21: circuit_id = 0, stream_id = 9, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.693267207 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.693523469 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.693576213 [168299/168303]: Receiving data for stream id 9 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.693744554 [168299/168303]: Wrote to stream 9: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.693819372 [168299/168303]: Wrote to stream 9: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.693859896 [168299/168303]: handle_index_data: stream 9 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.693914988 [168299/168303]: Finding index for stream id 9 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.693965374 [168299/168303]: Index found or created in HT for stream ID 9 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.694013945 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.694155053 [168299/168303]: Unlinking trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_3.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.694239091 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_3.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.694287999 [168299/168303]: Adding new file "ust/uid/1000/64-bit/index/lttng_python_channel_3.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG2 - 09:03:09.694570964 [168299/168303]: Writing index for stream ID 9 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.694644924 [168299/168303]: index put for stream id 9 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.694708118 [168299/168303]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.694749606 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.694830150 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:09.694874928 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.695026240 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:09.695111570 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:09.695197158 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:09.695277855 [168299/168303]: stream_add_index for stream 7 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:09.695318157 [168299/168303]: Finding index for stream id 7 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.695358957 [168299/168303]: Creating relay index for stream id 7 and seqnum 0 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:09.695435298 [168299/168303]: Adding relay index with stream id 7 and seqnum 0 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:09.695480058 [168299/168303]: Index found or created in HT for stream ID 7 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.695661899 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.695706923 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.695759673 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:09.695821422 [168299/168303]: Received data connection header on fd 21: circuit_id = 0, stream_id = 7, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:09.695872522 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.695919796 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:09.695969783 [168299/168303]: Receiving data for stream id 7 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:09.696098470 [168299/168303]: Wrote to stream 7: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.696165919 [168299/168303]: Wrote to stream 7: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:09.696210216 [168299/168303]: handle_index_data: stream 7 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:09.696252508 [168299/168303]: Finding index for stream id 7 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:09.696295438 [168299/168303]: Index found or created in HT for stream ID 7 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:09.696404015 [168299/168303]: Creating trace chunk subdirectory "ust/uid/1000/64-bit/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:1206)
|
|
DBG1 - 09:03:09.696519347 [168299/168303]: Unlinking trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_1.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:1456)
|
|
DBG1 - 09:03:09.696616332 [168299/168303]: Opening trace chunk file "ust/uid/1000/64-bit/index/lttng_python_channel_1.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:09.696660274 [168299/168303]: Adding new file "ust/uid/1000/64-bit/index/lttng_python_channel_1.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG2 - 09:03:09.696858247 [168299/168303]: Writing index for stream ID 7 and seq num 0 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:09.696919413 [168299/168303]: index put for stream id 7 and seqnum 0 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:09.696972204 [168299/168303]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:09.697013126 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.531777594 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.536413615 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.536537938 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.536592983 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.536639063 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.536687585 [168299/168303]: stream_add_index for stream 2 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:10.536727582 [168299/168303]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:10.536937116 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.536984277 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.538621594 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.538690468 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.538768167 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.538815576 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.538880661 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.538926291 [168299/168303]: stream_add_index for stream 3 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:10.538966236 [168299/168303]: Received live beacon for stream 3 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:10.539156831 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.539204283 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.539503378 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.539570244 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.539676141 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.539723743 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.539756298 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.539782206 [168299/168303]: stream_add_index for stream 4 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:10.539821417 [168299/168303]: Received live beacon for stream 4 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:10.539990680 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.540041109 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.632285074 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.632472131 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.632605607 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.632711306 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.632783150 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.632860763 [168299/168303]: stream_add_index for stream 6 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:10.632933230 [168299/168303]: Received live beacon for stream 6 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:10.633166219 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.633265899 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.633365842 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.633418201 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.633523125 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.633571539 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.633613733 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.633657721 [168299/168303]: stream_add_index for stream 7 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:10.633697029 [168299/168303]: Received live beacon for stream 7 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:10.633851297 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.633903296 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.634042521 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.634100859 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.634167175 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.634212183 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.634254802 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.634296241 [168299/168303]: stream_add_index for stream 8 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:10.634339357 [168299/168303]: Received live beacon for stream 8 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:10.634499189 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.634546514 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.634609477 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.634652915 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.634712420 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.634753062 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.634792456 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.634834372 [168299/168303]: stream_add_index for stream 9 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:10.634873361 [168299/168303]: Received live beacon for stream 9 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:10.634997674 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.635040768 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.680106409 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 5, data_size = 4096, net_seq_num = 1, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:10.680243674 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.680351565 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:10.680406170 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:10.680481481 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:10.680532728 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:10.680585810 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:10.680784920 [168299/168303]: stream_add_index for stream 5 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:10.680910660 [168299/168303]: Finding index for stream id 5 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:10.681010076 [168299/168303]: Creating relay index for stream id 5 and seqnum 1 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:10.681114767 [168299/168303]: Adding relay index with stream id 5 and seqnum 1 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:10.681210927 [168299/168303]: Index found or created in HT for stream ID 5 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:10.681617788 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.681773484 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:10.887606336 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:10.887712836 [168299/168303]: Receiving data for stream id 5 seqnum 1, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:10.887922542 [168299/168303]: Wrote to stream 5: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:10.887991612 [168299/168303]: Wrote to stream 5: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:10.888043922 [168299/168303]: handle_index_data: stream 5 net_seq_num 1 data offset 4096 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:10.888097416 [168299/168303]: Finding index for stream id 5 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:10.888149457 [168299/168303]: Index found or created in HT for stream ID 5 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG2 - 09:03:10.888200863 [168299/168303]: Writing index for stream ID 5 and seq num 1 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:10.888283140 [168299/168303]: index put for stream id 5 and seqnum 1 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:10.888388830 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:10.888437580 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.531720601 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.531793170 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.531864383 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.531913210 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.531954491 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.531998269 [168299/168303]: stream_add_index for stream 2 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:11.532037652 [168299/168303]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:11.532259189 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.532315205 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.533290305 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.533414995 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.533500572 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.533540589 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.533577905 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.533636477 [168299/168303]: stream_add_index for stream 5 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:11.533681910 [168299/168303]: Received live beacon for stream 5 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:11.533846256 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.533883345 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.632236331 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.632325045 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.632517335 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.632575453 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.632619175 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.632666905 [168299/168303]: stream_add_index for stream 6 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:11.632708966 [168299/168303]: Received live beacon for stream 6 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:11.632877921 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.632928820 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.633370152 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.633435771 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.633507926 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.633580892 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.633637325 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.633692186 [168299/168303]: stream_add_index for stream 7 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:11.633763018 [168299/168303]: Received live beacon for stream 7 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:11.633946383 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.633995403 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.634417666 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.634581640 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.634665089 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.634718049 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.634761796 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.634807016 [168299/168303]: stream_add_index for stream 8 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:11.634849262 [168299/168303]: Received live beacon for stream 8 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:11.635021514 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.635075794 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.635356824 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.635500557 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.635578265 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.635623167 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.635665900 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.635709274 [168299/168303]: stream_add_index for stream 9 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:11.635750116 [168299/168303]: Received live beacon for stream 9 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:11.635911959 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.635950022 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:11.699773774 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.700353424 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.700418693 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.700500223 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.700539062 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.700579658 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.700624458 [168299/168303]: stream_add_index for stream 4 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:11.700658841 [168299/168303]: Finding index for stream id 4 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:11.700699822 [168299/168303]: Creating relay index for stream id 4 and seqnum 1 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:11.700768596 [168299/168303]: Adding relay index with stream id 4 and seqnum 1 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:11.700808165 [168299/168303]: Index found or created in HT for stream ID 4 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:11.701007394 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.701441621 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.701509501 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:11.701546384 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.701601814 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:11.701635222 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:11.701668555 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:11.701705866 [168299/168303]: stream_add_index for stream 3 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:11.701736213 [168299/168303]: Finding index for stream id 3 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:11.701770957 [168299/168303]: Creating relay index for stream id 3 and seqnum 1 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:11.701805815 [168299/168303]: Adding relay index with stream id 3 and seqnum 1 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:11.701841814 [168299/168303]: Index found or created in HT for stream ID 3 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:11.701989444 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.702049180 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.702117329 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 4, data_size = 4096, net_seq_num = 1, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:11.702161478 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:11.702204534 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:11.702247334 [168299/168303]: Receiving data for stream id 4 seqnum 1, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:11.702378616 [168299/168303]: Wrote to stream 4: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:11.702420743 [168299/168303]: Wrote to stream 4: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:11.702459632 [168299/168303]: handle_index_data: stream 4 net_seq_num 1 data offset 200704 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:11.702493718 [168299/168303]: Finding index for stream id 4 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:11.702551475 [168299/168303]: Index found or created in HT for stream ID 4 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG2 - 09:03:11.702591111 [168299/168303]: Writing index for stream ID 4 and seq num 1 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:11.702646797 [168299/168303]: index put for stream id 4 and seqnum 1 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:11.702859079 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.702921917 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:11.702977789 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:11.703060188 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 3, data_size = 4096, net_seq_num = 1, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:11.703119460 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:11.907689857 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:11.908165017 [168299/168303]: Receiving data for stream id 3 seqnum 1, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:11.909055143 [168299/168303]: Wrote to stream 3: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:11.909150101 [168299/168303]: Wrote to stream 3: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:11.909232175 [168299/168303]: handle_index_data: stream 3 net_seq_num 1 data offset 4096 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:11.909288642 [168299/168303]: Finding index for stream id 3 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:11.909338618 [168299/168303]: Index found or created in HT for stream ID 3 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG2 - 09:03:11.909385576 [168299/168303]: Writing index for stream ID 3 and seq num 1 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:11.909468373 [168299/168303]: index put for stream id 3 and seqnum 1 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:11.909573306 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:11.909628502 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.531694822 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.531795821 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.531934817 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.532032560 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.532117293 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.532241300 [168299/168303]: stream_add_index for stream 2 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:12.532381427 [168299/168303]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:12.532836429 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.532955791 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.533101465 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.533276261 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.533458918 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.533583125 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.533702130 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.533791032 [168299/168303]: stream_add_index for stream 3 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:12.533887345 [168299/168303]: Received live beacon for stream 3 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:12.534235028 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.534324435 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.534638283 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.534749208 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.534845223 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.534919853 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.535029053 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.535103429 [168299/168303]: stream_add_index for stream 4 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:12.535172895 [168299/168303]: Received live beacon for stream 4 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:12.535512101 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.535617111 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.632191282 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.632266648 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.632383397 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.632432732 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.632476996 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.632526543 [168299/168303]: stream_add_index for stream 6 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:12.632568670 [168299/168303]: Received live beacon for stream 6 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:12.632839646 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.633827885 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.633909284 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.633958533 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.634022600 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.634065538 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.634105563 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.634151499 [168299/168303]: stream_add_index for stream 7 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:12.634189531 [168299/168303]: Received live beacon for stream 7 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:12.634352863 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.634401130 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.634585126 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.634641575 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.634705817 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.634748860 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.634788927 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.635001900 [168299/168303]: stream_add_index for stream 8 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:12.635082399 [168299/168303]: Received live beacon for stream 8 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:12.635327730 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.635474937 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.636834676 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.636913216 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.636984617 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.637032897 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.637079108 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.637128452 [168299/168303]: stream_add_index for stream 9 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:12.637172352 [168299/168303]: Received live beacon for stream 9 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:12.637408647 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.637461503 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:12.731819755 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.731947093 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 5, data_size = 4096, net_seq_num = 2, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:12.732013859 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.732084053 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:12.732134072 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:12.732198466 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:12.732244174 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:12.732290601 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:12.732339809 [168299/168303]: stream_add_index for stream 5 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:12.732379626 [168299/168303]: Finding index for stream id 5 and seq_num 2 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:12.732418641 [168299/168303]: Creating relay index for stream id 5 and seqnum 2 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:12.732460256 [168299/168303]: Adding relay index with stream id 5 and seqnum 2 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:12.732500800 [168299/168303]: Index found or created in HT for stream ID 5 and seqnum 2 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:12.732732787 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.732794503 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:12.939634632 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:12.939739231 [168299/168303]: Receiving data for stream id 5 seqnum 2, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:12.939944354 [168299/168303]: Wrote to stream 5: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:12.940008020 [168299/168303]: Wrote to stream 5: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:12.940080837 [168299/168303]: handle_index_data: stream 5 net_seq_num 2 data offset 8192 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:12.940133134 [168299/168303]: Finding index for stream id 5 and seq_num 2 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:12.940186375 [168299/168303]: Index found or created in HT for stream ID 5 and seqnum 2 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG2 - 09:03:12.940231268 [168299/168303]: Writing index for stream ID 5 and seq num 2 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:12.940339493 [168299/168303]: index put for stream id 5 and seqnum 2 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:12.940462064 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:12.940516164 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.531713454 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.531798328 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.531891713 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.531957202 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.532007635 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.532049771 [168299/168303]: stream_add_index for stream 2 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:13.532091801 [168299/168303]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:13.532304272 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.532356507 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.536072907 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.536167651 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.536247383 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.536302636 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.536347638 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.536393605 [168299/168303]: stream_add_index for stream 3 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:13.536436366 [168299/168303]: Received live beacon for stream 3 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:13.536652969 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.536696471 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.536950844 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.537245767 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.537346163 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.537398046 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.537439796 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.537482412 [168299/168303]: stream_add_index for stream 5 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:13.537524348 [168299/168303]: Received live beacon for stream 5 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:13.537695433 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.537743163 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.541689356 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.541794352 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.542041022 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.542772433 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.542846760 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.542902987 [168299/168303]: stream_add_index for stream 4 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:13.542955887 [168299/168303]: Finding index for stream id 4 and seq_num 2 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:13.543003217 [168299/168303]: Creating relay index for stream id 4 and seqnum 2 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:13.543050694 [168299/168303]: Adding relay index with stream id 4 and seqnum 2 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:13.543102291 [168299/168303]: Index found or created in HT for stream ID 4 and seqnum 2 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:13.543302306 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.543349908 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:13.543437916 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.543507190 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 4, data_size = 4096, net_seq_num = 2, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:13.543559085 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.632472543 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.632578042 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.632696380 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.634093064 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.634185962 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.634262242 [168299/168303]: stream_add_index for stream 6 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:13.634330628 [168299/168303]: Received live beacon for stream 6 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:13.634584484 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.635092730 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.635255590 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.635338084 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.635466425 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.635544776 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.635857848 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.635943139 [168299/168303]: stream_add_index for stream 7 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:13.636007203 [168299/168303]: Received live beacon for stream 7 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:13.636206752 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.636261695 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.636505068 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.636557226 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.636676800 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.636733225 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.636770216 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.636812388 [168299/168303]: stream_add_index for stream 8 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:13.636853163 [168299/168303]: Received live beacon for stream 8 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:13.637292045 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.637339295 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.637402861 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:13.637451674 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:13.637510501 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:13.637552876 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:13.637596113 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:13.637635726 [168299/168303]: stream_add_index for stream 9 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:13.637675784 [168299/168303]: Received live beacon for stream 9 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:13.637804924 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.637851999 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:13.747696855 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:13.747812011 [168299/168303]: Receiving data for stream id 4 seqnum 2, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:13.748025956 [168299/168303]: Wrote to stream 4: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:13.748081641 [168299/168303]: Wrote to stream 4: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:13.748167885 [168299/168303]: handle_index_data: stream 4 net_seq_num 2 data offset 204800 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:13.748216431 [168299/168303]: Finding index for stream id 4 and seq_num 2 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:13.748259737 [168299/168303]: Index found or created in HT for stream ID 4 and seqnum 2 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG2 - 09:03:13.748303431 [168299/168303]: Writing index for stream ID 4 and seq num 2 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:13.748660909 [168299/168303]: index put for stream id 4 and seqnum 2 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:13.748907345 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:13.749409708 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.531762243 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.531977414 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.532079966 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.532139230 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.532185521 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.535793469 [168299/168303]: stream_add_index for stream 2 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:14.536184774 [168299/168303]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:14.537715020 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.538132056 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.538405753 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.540482303 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.540860391 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.541134609 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.541344238 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.541569302 [168299/168303]: stream_add_index for stream 4 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:14.541755462 [168299/168303]: Received live beacon for stream 4 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:14.542065456 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.542356929 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.542699602 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.542948366 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.543133003 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.543304710 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.543538422 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.543618636 [168299/168303]: stream_add_index for stream 5 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:14.544394181 [168299/168303]: Received live beacon for stream 5 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:14.546012774 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.546088501 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:14.575630217 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.575671749 [168299/168303]: Done receiving control command header: fd = 22, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.575686759 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.575704906 [168299/168303]: Done receiving control command payload: fd = 22, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.575717600 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 22 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.575729834 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.575742523 [168299/168303]: stream_add_index for stream 3 (in stream_add_index() at stream.c:1234)
|
|
DBG3 - 09:03:14.575754410 [168299/168303]: Finding index for stream id 3 and seq_num 2 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:14.575771341 [168299/168303]: Creating relay index for stream id 3 and seqnum 2 (in relay_index_create() at index.c:34)
|
|
DBG2 - 09:03:14.575784507 [168299/168303]: Adding relay index with stream id 3 and seqnum 2 (in relay_index_add_unique() at index.c:70)
|
|
DBG2 - 09:03:14.575798511 [168299/168303]: Index found or created in HT for stream ID 3 and seqnum 2 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG1 - 09:03:14.575870564 [168299/168303]: Reset communication state of relay connection (fd = 22) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.575890807 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.575922568 [168299/168303]: Received data connection header on fd 24: circuit_id = 0, stream_id = 3, data_size = 4096, net_seq_num = 2, padding_size = 0 (in relay_process_data_receive_header() at main.c:3559)
|
|
DBG3 - 09:03:14.575945945 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632031931 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.632076353 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632121403 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.632149383 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.632166679 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.632185781 [168299/168303]: stream_add_index for stream 6 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:14.632204354 [168299/168303]: Received live beacon for stream 6 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:14.632300854 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.632322270 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632377419 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.632400924 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632429487 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.632447621 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.632463803 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.632481181 [168299/168303]: stream_add_index for stream 7 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:14.632496996 [168299/168303]: Received live beacon for stream 7 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:14.632572445 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.632592940 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632611204 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.632629519 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632651179 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.632666392 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.632681241 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.632696698 [168299/168303]: stream_add_index for stream 8 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:14.632711313 [168299/168303]: Received live beacon for stream 8 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:14.632762549 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.632780577 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632827402 [168299/168303]: Done receiving control command header: fd = 19, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3443)
|
|
DBG3 - 09:03:14.632844999 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:14.632867817 [168299/168303]: Done receiving control command payload: fd = 19, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:3360)
|
|
DBG3 - 09:03:14.632883821 [168299/168303]: Processing "RELAYD_SEND_INDEX" command for socket 19 (in relay_process_control_command() at main.c:3267)
|
|
DBG1 - 09:03:14.632901585 [168299/168303]: Relay receiving index (in relay_recv_index() at main.c:2394)
|
|
DBG1 - 09:03:14.632916974 [168299/168303]: stream_add_index for stream 9 (in stream_add_index() at stream.c:1234)
|
|
DBG1 - 09:03:14.632931502 [168299/168303]: Received live beacon for stream 9 (in stream_add_index() at stream.c:1238)
|
|
DBG1 - 09:03:14.632987306 [168299/168303]: Reset communication state of relay connection (fd = 19) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.633002339 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:14.783614377 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG3 - 09:03:14.783696851 [168299/168303]: Receiving data for stream id 3 seqnum 2, 0 bytes received, 4096 bytes left to receive (in relay_process_data_receive_payload() at main.c:3605)
|
|
DBG1 - 09:03:14.783898941 [168299/168303]: Wrote to stream 3: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:14.783959307 [168299/168303]: Wrote to stream 3: data_length = 0, padding_length = 0 (in stream_write() at stream.c:1114)
|
|
DBG1 - 09:03:14.784013590 [168299/168303]: handle_index_data: stream 3 net_seq_num 2 data offset 8192 (in stream_update_index() at stream.c:1141)
|
|
DBG3 - 09:03:14.784061356 [168299/168303]: Finding index for stream id 3 and seq_num 2 (in relay_index_get_by_id_or_create() at index.c:112)
|
|
DBG2 - 09:03:14.784107353 [168299/168303]: Index found or created in HT for stream ID 3 and seqnum 2 (in relay_index_get_by_id_or_create() at index.c:144)
|
|
DBG2 - 09:03:14.784154505 [168299/168303]: Writing index for stream ID 3 and seq num 2 (in relay_index_try_flush() at index.c:275)
|
|
DBG2 - 09:03:14.784228741 [168299/168303]: index put for stream id 3 and seqnum 2 refcount 1 (in relay_index_put() at index.c:237)
|
|
DBG1 - 09:03:14.784333377 [168299/168303]: Reset communication state of relay connection (fd = 24) (in connection_reset_protocol_state() at connection.c:82)
|
|
DBG3 - 09:03:14.784449294 [168299/168303]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3854)
|
|
DBG1 - 09:03:15.136465014 [168299/168307]: Relay new viewer connection received (in thread_listener() at live.c:724)
|
|
DBG1 - 09:03:15.136609138 [168299/168307]: Live socket to client accepted, socket 43 (in accept_live_sock() at live.c:593)
|
|
DBG1 - 09:03:15.136659535 [168299/168307]: Relay viewer connection accepted socket 43 (in thread_listener() at live.c:756)
|
|
DBG1 - 09:03:15.136771009 [168299/168307]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:108)
|
|
DBG1 - 09:03:15.136815486 [168299/168307]: Listener accepting live viewers connections (in thread_listener() at live.c:707)
|
|
DBG1 - 09:03:15.136895142 [168299/168305]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:92)
|
|
DBG1 - 09:03:15.136956066 [168299/168305]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:65)
|
|
DBG1 - 09:03:15.137001852 [168299/168305]: Dispatching viewer request waiting on sock 43 (in thread_dispatcher() at live.c:869)
|
|
DBG1 - 09:03:15.137085051 [168299/168305]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:863)
|
|
DBG1 - 09:03:15.137224880 [168299/168306]: Connection socket 43 added to poll (in thread_worker() at live.c:2488)
|
|
DBG3 - 09:03:15.137278005 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:15.137352243 [168299/168306]: Viewer is establishing a connection to the relayd. (in viewer_connect() at live.c:924)
|
|
DBG1 - 09:03:15.137533050 [168299/168306]: Version check done using protocol 2.4 (in viewer_connect() at live.c:986)
|
|
DBG3 - 09:03:15.137578889 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:15.138658413 [168299/168306]: Viewer create session received (in viewer_create_session() at live.c:2201)
|
|
DBG3 - 09:03:15.138818386 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:15.139214789 [168299/168306]: List sessions received (in viewer_list_sessions() at live.c:1012)
|
|
DBG3 - 09:03:15.139367404 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:15.140270333 [168299/168306]: Session find by ID 2 id found (in session_get_by_id() at session.c:468)
|
|
DBG1 - 09:03:15.140326188 [168299/168306]: Attach session ID 2 received (in viewer_attach_session() at live.c:1265)
|
|
DBG1 - 09:03:15.140408515 [168299/168306]: Copying relay session's current trace chunk to the viewer session (in viewer_session_set_trace_chunk_copy() at viewer-session.c:43)
|
|
DBG1 - 09:03:15.140494412 [168299/168306]: Relay viewer stream 1 not found (in viewer_stream_get_by_id() at viewer-stream.c:264)
|
|
DBG1 - 09:03:15.140573594 [168299/168306]: Opening trace chunk file "kernel/metadata" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.140667453 [168299/168306]: Adding new file "kernel/metadata" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.140808554 [168299/168306]: Relay viewer stream 2 not found (in viewer_stream_get_by_id() at viewer-stream.c:264)
|
|
DBG1 - 09:03:15.140914900 [168299/168306]: Opening trace chunk file "kernel/index/channel0_3.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.141009457 [168299/168306]: Adding new file "kernel/index/channel0_3.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.141170081 [168299/168306]: Opening trace chunk file "kernel/channel0_3" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.141247741 [168299/168306]: Adding new file "kernel/channel0_3" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.141414129 [168299/168306]: Relay viewer stream 3 not found (in viewer_stream_get_by_id() at viewer-stream.c:264)
|
|
DBG1 - 09:03:15.141548470 [168299/168306]: Opening trace chunk file "kernel/index/channel0_2.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.141629147 [168299/168306]: Adding new file "kernel/index/channel0_2.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.142104060 [168299/168306]: Opening trace chunk file "kernel/channel0_2" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.142219673 [168299/168306]: Adding new file "kernel/channel0_2" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.142632211 [168299/168306]: Relay viewer stream 4 not found (in viewer_stream_get_by_id() at viewer-stream.c:264)
|
|
DBG1 - 09:03:15.142749288 [168299/168306]: Opening trace chunk file "kernel/index/channel0_1.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.142816929 [168299/168306]: Adding new file "kernel/index/channel0_1.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.142960934 [168299/168306]: Opening trace chunk file "kernel/channel0_1" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.143033202 [168299/168306]: Adding new file "kernel/channel0_1" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.143148385 [168299/168306]: Relay viewer stream 5 not found (in viewer_stream_get_by_id() at viewer-stream.c:264)
|
|
DBG1 - 09:03:15.143225218 [168299/168306]: Opening trace chunk file "kernel/index/channel0_0.idx" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.143292242 [168299/168306]: Adding new file "kernel/index/channel0_0.idx" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.143476004 [168299/168306]: Opening trace chunk file "kernel/channel0_0" (in _lttng_trace_chunk_open_fs_handle_locked() at trace-chunk.c:1344)
|
|
DBG1 - 09:03:15.143546956 [168299/168306]: Adding new file "kernel/channel0_0" to trace chunk "(unnamed)" (in lttng_trace_chunk_add_file() at trace-chunk.c:1294)
|
|
DBG1 - 09:03:15.143830001 [168299/168306]: Sending stream 4 to viewer (in send_viewer_streams() at live.c:241)
|
|
DBG1 - 09:03:15.144036044 [168299/168306]: Sending stream 3 to viewer (in send_viewer_streams() at live.c:241)
|
|
DBG1 - 09:03:15.144196217 [168299/168306]: Sending stream 2 to viewer (in send_viewer_streams() at live.c:241)
|
|
DBG1 - 09:03:15.144334155 [168299/168306]: Sending stream 5 to viewer (in send_viewer_streams() at live.c:241)
|
|
DBG1 - 09:03:15.144472860 [168299/168306]: Sending stream 1 to viewer (in send_viewer_streams() at live.c:241)
|
|
DBG3 - 09:03:15.144641433 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:15.146223354 [168299/168306]: Relay get metadata (in viewer_get_metadata() at live.c:1946)
|
|
DBG1 - 09:03:15.148199591 [168299/168306]: Sent 319488 bytes of metadata for stream 1 (in viewer_get_metadata() at live.c:2176)
|
|
DBG1 - 09:03:15.148265073 [168299/168306]: Metadata sent (in viewer_get_metadata() at live.c:2179)
|
|
DBG3 - 09:03:15.148466675 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:15.155135133 [168299/168306]: Relay get metadata (in viewer_get_metadata() at live.c:1946)
|
|
DBG1 - 09:03:15.155727914 [168299/168306]: Sent 0 bytes of metadata for stream 1 (in viewer_get_metadata() at live.c:2176)
|
|
DBG1 - 09:03:15.155844996 [168299/168306]: Metadata sent (in viewer_get_metadata() at live.c:2179)
|
|
DBG3 - 09:03:15.155927694 [168299/168306]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2435)
|
|
DBG1 - 09:03:15.280816393 [168299/168306]: Session find by ID 1 id found (in session_get_by_id() at session.c:468)
|
|
DBG1 - 09:03:15.280840306 [168299/168306]: Attach session ID 1 received (in viewer_attach_session() at live.c:1265)
|
|
lttng-relayd: viewer-session.c:80: viewer_session_attach: Assertion `!vsession->current_trace_chunk' failed.
|