Project

General

Profile

Bug #1206 » relayd.log

Francis Deslauriers, 10/29/2019 02:55 PM

 
[0]frdeso@raton ~ % lttng-relayd -vvv
DEBUG3 - 14:47:46.225850090 [13799/13799]: URI string: tcp://0.0.0.0:5342 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.226060392 [13799/13799]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.226099150 [13799/13799]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5342, data: 0 (in uri_parse() at uri.c:538)
DEBUG3 - 14:47:46.226161423 [13799/13799]: URI string: tcp://0.0.0.0:5343 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.226235225 [13799/13799]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.226269337 [13799/13799]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5343, data: 0 (in uri_parse() at uri.c:538)
DEBUG3 - 14:47:46.226300259 [13799/13799]: URI string: tcp://localhost:5344 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.227198608 [13799/13799]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.227233964 [13799/13799]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 5344, data: 0 (in uri_parse() at uri.c:538)
DEBUG1 - 14:47:46.227272628 [13799/13799]: Signal handler set for SIGTERM, SIGUSR1, SIGPIPE and SIGINT (in set_signal_handler() at main.c:720)
DEBUG1 - 14:47:46.227313024 [13799/13799]: LTTng-relayd 2.12.0-pre - Codename TBD - v2.11.0-rc1-549-g93d0d1f7d (in relayd_config_log() at main.c:201)
DEBUG1 - 14:47:46.227726663 [13799/13799]: Health check time delta in seconds set to 20 (in health_init() at health.c:73)
DEBUG1 - 14:47:46.227922655 [13799/13799]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:555)
DEBUG3 - 14:47:46.228097932 [13799/13799]: Created hashtable size 4 at 0x559330d4c700 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 14:47:46.228157729 [13799/13799]: Created hashtable size 4 at 0x559330d4cc40 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 14:47:46.228190357 [13799/13799]: Created hashtable size 4 at 0x559330d4d180 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.228497432 [13799/13802]: [thread] Relay dispatcher started (in relay_thread_dispatcher() at main.c:1035)
DEBUG1 - 14:47:46.228508157 [13799/13801]: [thread] Manage health check started (in thread_manage_health() at health-relayd.c:247)
DEBUG1 - 14:47:46.228653199 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:46.228831213 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:46.228912788 [13799/13799]: epoll set max size is 3226132 (in compat_epoll_set_max_size() at compat-epoll.c:356)
DEBUG1 - 14:47:46.228884532 [13799/13803]: [thread] Relay worker started (in relay_thread_worker() at main.c:3377)
DEBUG1 - 14:47:46.228980424 [13799/13804]: [thread] Relay listener started (in relay_thread_listener() at main.c:843)
DEBUG3 - 14:47:46.229607478 [13799/13801]: Creating LTTng run directory: /home/frdeso/.lttng (in create_lttng_rundir_with_perm() at health-relayd.c:94)
DEBUG3 - 14:47:46.229735978 [13799/13801]: Creating LTTng run directory: /home/frdeso/.lttng/relayd (in create_lttng_rundir_with_perm() at health-relayd.c:94)
DEBUG1 - 14:47:46.230021704 [13799/13801]: Health check ready (in thread_manage_health() at health-relayd.c:325)
DEBUG1 - 14:47:46.230094478 [13799/13804]: Listening on sock 11 (in relay_socket_create() at main.c:810)
DEBUG3 - 14:47:46.230156898 [13799/13803]: Created hashtable size 4 at 0x7fc878000b40 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.230260614 [13799/13804]: Listening on sock 15 (in relay_socket_create() at main.c:810)
DEBUG1 - 14:47:46.230427430 [13799/13806]: [thread] Live viewer relay worker started (in thread_worker() at live.c:1980)
DEBUG3 - 14:47:46.230267978 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:46.230042120 [13799/13805]: [thread] Live viewer relay dispatcher started (in thread_dispatcher() at live.c:644)
DEBUG1 - 14:47:46.230596067 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:46.230812364 [13799/13805]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:46.230634409 [13799/13807]: [thread] Relay live listener started (in thread_listener() at live.c:490)
DEBUG3 - 14:47:46.230870430 [13799/13806]: Created hashtable size 4 at 0x7fc870000b40 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.230916253 [13799/13805]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:672)
DEBUG1 - 14:47:46.231070420 [13799/13807]: Listening on sock 18 for lttng-live (in init_socket() at live.c:456)
DEBUG3 - 14:47:46.231071355 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:46.231189564 [13799/13807]: Listener accepting live viewers connections (in thread_listener() at live.c:522)
DEBUG1 - 14:47:48.476280993 [13799/13807]: Relay new viewer connection received (in thread_listener() at live.c:539)
DEBUG1 - 14:47:48.476584713 [13799/13807]: Relay viewer connection accepted socket 20 (in thread_listener() at live.c:570)
DEBUG1 - 14:47:48.476717963 [13799/13807]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.476798236 [13799/13805]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.476892754 [13799/13805]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.477120110 [13799/13805]: Dispatching viewer request waiting on sock 20 (in thread_dispatcher() at live.c:678)
DEBUG1 - 14:47:48.477226102 [13799/13805]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:672)
DEBUG1 - 14:47:48.476756585 [13799/13807]: Listener accepting live viewers connections (in thread_listener() at live.c:522)
DEBUG1 - 14:47:48.477914787 [13799/13806]: Connection socket 20 added to poll (in thread_worker() at live.c:2066)
DEBUG3 - 14:47:48.477966070 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:48.478206687 [13799/13806]: Viewer control conn closed with 20 (in thread_worker() at live.c:2091)
DEBUG3 - 14:47:48.478495301 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:48.607674256 [13799/13804]: Relay new connection received (in relay_thread_listener() at main.c:907)
DEBUG1 - 14:47:48.607772367 [13799/13804]: Relay control connection accepted, socket 20 (in relay_thread_listener() at main.c:944)
DEBUG1 - 14:47:48.607827700 [13799/13804]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG1 - 14:47:48.607886336 [13799/13804]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.607919036 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:48.608108295 [13799/13802]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.608151944 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.608191203 [13799/13802]: Dispatching request waiting on sock 20 (in relay_thread_dispatcher() at main.c:1068)
DEBUG1 - 14:47:48.608256383 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:48.608322494 [13799/13803]: Connection socket 20 added (in relay_thread_worker() at main.c:3464)
DEBUG3 - 14:47:48.608371619 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.608432494 [13799/13803]: Done receiving control command header: fd = 20, cmd = 5, cmd_version = 0, payload size = 8 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.608465059 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.608511300 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 8 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.608542033 [13799/13803]: Processing "RELAYD_VERSION" command for socket 20 (in relay_process_control_command() at main.c:2806)
DEBUG1 - 14:47:48.608661744 [13799/13803]: Version check done using protocol 2.12 (in relay_send_version() at main.c:1749)
DEBUG1 - 14:47:48.608693425 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.608727485 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.609017927 [13799/13803]: Done receiving control command header: fd = 20, cmd = 2, cmd_version = 0, payload size = 84 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.609062469 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.609116466 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 84 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.609152475 [13799/13803]: Processing "RELAYD_CREATE_SESSION" command for socket 20 (in relay_process_control_command() at main.c:2790)
DEBUG3 - 14:47:48.609274369 [13799/13803]: Created hashtable size 4 at 0x7fc8780033f0 of type 0 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:48.609385552 [13799/13803]: Created trace chunk registry for sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in trace_chunk_registry_ht_element_create() at sessiond-trace-chunks.c:236)
DEBUG1 - 14:47:48.609427052 [13799/13803]: Created session 1 (in relay_create_session() at main.c:1188)
DEBUG1 - 14:47:48.609567151 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.609603275 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.610675599 [13799/13804]: Relay new connection received (in relay_thread_listener() at main.c:907)
DEBUG1 - 14:47:48.610761651 [13799/13804]: Relay data connection accepted, socket 21 (in relay_thread_listener() at main.c:938)
DEBUG1 - 14:47:48.610905507 [13799/13804]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG1 - 14:47:48.610986307 [13799/13804]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.611021018 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:48.611067817 [13799/13802]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.611145900 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.611185268 [13799/13802]: Dispatching request waiting on sock 21 (in relay_thread_dispatcher() at main.c:1068)
DEBUG1 - 14:47:48.611242781 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:48.611302201 [13799/13803]: Connection socket 21 added (in relay_thread_worker() at main.c:3464)
DEBUG3 - 14:47:48.611340804 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.646605812 [13799/13803]: Done receiving control command header: fd = 20, cmd = 19, cmd_version = 0, payload size = 20 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.646868183 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.646961914 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 20 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.646989997 [13799/13803]: Processing "RELAYD_CREATE_TRACE_CHUNK" command for socket 20 (in relay_process_control_command() at main.c:2846)
DEBUG1 - 14:47:48.647036053 [13799/13803]: Creating trace chunk: chunk_id = 0, creation time = 20191029-144748 (in lttng_trace_chunk_create() at trace-chunk.c:283)
DEBUG1 - 14:47:48.647111122 [13799/13803]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:300)
DEBUG1 - 14:47:48.647542904 [13799/13803]: Attempting to publish trace chunk: sessiond {2370232e-4e54-48a8-b8be-5f707ae66270}, session_id = 1, chunk_id = 0 (in sessiond_trace_chunk_registry_publish_chunk() at sessiond-trace-chunks.c:398)
DEBUG1 - 14:47:48.647806942 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.647844668 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.748969029 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.749796416 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.749872181 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.749901327 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG1 - 14:47:51.749940353 [13799/13803]: CTF Trace path ust/pid/main-ust-13827-20191029-144751 not found (in ctf_trace_get_by_path_or_create() at ctf-trace.c:150)
DEBUG1 - 14:47:51.749984869 [13799/13803]: Created ctf_trace 1of session "claudia_normand_2" from host "raton" with path: ust/pid/main-ust-13827-20191029-144751 (in ctf_trace_create() at ctf-trace.c:125)
DEBUG3 - 14:47:51.750047666 [13799/13803]: Created hashtable size 4 at 0x7fc878004430 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.750078630 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.750671419 [13799/13803]: Adding new top-level directory "ust/pid/main-ust-13827-20191029-144751" to trace chunk "(unnamed)" (in add_top_level_directory_unique() at trace-chunk.c:734)
DEBUG1 - 14:47:51.750758575 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_0" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.750914257 [13799/13803]: Relay new stream added chan1_0 with ID 1 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.751187450 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.751245716 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.751666652 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.751729626 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.751797243 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.751840501 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.752263540 [13799/13803]: Created hashtable size 4 at 0x7fc878004c10 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.752310949 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.752413521 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_1" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.752573343 [13799/13803]: Relay new stream added chan1_1 with ID 2 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.752770801 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.752823518 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.753209700 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.753288450 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.753354450 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.753399734 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.753475448 [13799/13803]: Created hashtable size 4 at 0x7fc8780053b0 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.753511740 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.753606075 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_2" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.753781595 [13799/13803]: Relay new stream added chan1_2 with ID 3 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.753972625 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.754015288 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.754417558 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.754490657 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.754569472 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.754603014 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.754698922 [13799/13803]: Created hashtable size 4 at 0x7fc878005b50 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.754730513 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.754824324 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_3" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.755033413 [13799/13803]: Relay new stream added chan1_3 with ID 4 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.755218490 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.755253757 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761101611 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.761167712 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761220536 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.761256337 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.761321318 [13799/13803]: Created hashtable size 4 at 0x7fc8780062f0 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.761357956 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.761437611 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/metadata" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.761577575 [13799/13803]: Relay new stream added metadata with ID 5 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.761715786 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.761751767 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761958795 [13799/13803]: Done receiving control command header: fd = 20, cmd = 16, cmd_version = 0, payload size = 0 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG1 - 14:47:51.762029152 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 0 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.762064211 [13799/13803]: Processing "RELAYD_STREAMS_SENT" command for socket 20 (in relay_process_control_command() at main.c:2834)
DEBUG1 - 14:47:51.762097869 [13799/13803]: Relay receiving streams_sent (in relay_streams_sent() at main.c:2174)
DEBUG1 - 14:47:51.762199486 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.762235508 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.749694532 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 1, data_size = 1560, net_seq_num = 0, padding_size = 2536 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.749786216 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.749855288 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.749933318 [13799/13803]: Receiving data for stream id 1 seqnum 0, 0 bytes received, 1560 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.750963465 [13799/13803]: Wrote to stream 1: data_length = 1560, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.751240009 [13799/13803]: Wrote to stream 1: data_length = 0, padding_length = 2536 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.751360745 [13799/13803]: handle_index_data: stream 1 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.751400823 [13799/13803]: Finding index for stream id 1 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.751444212 [13799/13803]: Creating relay index for stream id 1 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.751523845 [13799/13803]: Adding relay index with stream id 1 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.751574081 [13799/13803]: Index found or created in HT for stream ID 1 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.751629187 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.752166411 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_0.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.752278572 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_0.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.752490956 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.752549300 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.752639775 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4108 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.752734511 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796304634 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4108 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796366238 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.796488995 [13799/13803]: Wrote to metadata stream 5: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.796526173 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.796557893 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796606545 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4105 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.796643929 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796690420 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4105 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796716167 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.796788328 [13799/13803]: Wrote to metadata stream 5: data_length = 4093, padding_length = 3 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.796815681 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.796842859 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796883453 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4105 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.796911339 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796952770 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4105 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796977029 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.797035753 [13799/13803]: Wrote to metadata stream 5: data_length = 4093, padding_length = 3 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.797061773 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797089273 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797129911 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 779 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.797160034 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797198195 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 779 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.797221920 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.797280820 [13799/13803]: Wrote to metadata stream 5: data_length = 767, padding_length = 3329 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.797306529 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797333520 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797373773 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.797399922 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797443286 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.797467795 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.797495786 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.797528403 [13799/13803]: Finding index for stream id 1 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.797557381 [13799/13803]: Index found or created in HT for stream ID 1 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.797588270 [13799/13803]: Writing index for stream ID 1 and seq num 0 on fd 29 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.797638919 [13799/13803]: index put for stream id 1 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.797828666 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797859648 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798542702 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.798609975 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 4, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.798647660 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798682775 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798869177 [13799/13803]: Receiving data for stream id 4 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.799127124 [13799/13803]: Wrote to stream 4: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.799208952 [13799/13803]: Wrote to stream 4: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.799261736 [13799/13803]: handle_index_data: stream 4 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.799302632 [13799/13803]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.799332066 [13799/13803]: Creating relay index for stream id 4 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.799384035 [13799/13803]: Adding relay index with stream id 4 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.799416201 [13799/13803]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.799454935 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.799564307 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_3.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.799646971 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_3.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.799824513 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.799867415 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800089152 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.800145449 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800195976 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.800218635 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.800237815 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.800261507 [13799/13803]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.800281551 [13799/13803]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.800301540 [13799/13803]: Writing index for stream ID 4 and seq num 0 on fd 30 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.800341623 [13799/13803]: index put for stream id 4 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.800454018 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.800486346 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800729367 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800790809 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 2, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.800832450 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800868131 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800902409 [13799/13803]: Receiving data for stream id 2 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.800994548 [13799/13803]: Wrote to stream 2: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.801031273 [13799/13803]: Wrote to stream 2: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.801051148 [13799/13803]: handle_index_data: stream 2 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.801070429 [13799/13803]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.801090575 [13799/13803]: Creating relay index for stream id 2 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.801112440 [13799/13803]: Adding relay index with stream id 2 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.801135693 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.801158851 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.801228450 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_1.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.801283589 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_1.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.801423550 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.801462259 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.801512142 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.801538917 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.801571264 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.801591247 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.801610092 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.801632184 [13799/13803]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.801651467 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.801671026 [13799/13803]: Writing index for stream ID 2 and seq num 0 on fd 31 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.801704450 [13799/13803]: index put for stream id 2 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.801807223 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.801839609 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802060467 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802120092 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 3, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.802159734 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802194919 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802228439 [13799/13803]: Receiving data for stream id 3 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.802315800 [13799/13803]: Wrote to stream 3: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.802350283 [13799/13803]: Wrote to stream 3: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.802369955 [13799/13803]: handle_index_data: stream 3 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.802388958 [13799/13803]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.802409272 [13799/13803]: Creating relay index for stream id 3 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.802430500 [13799/13803]: Adding relay index with stream id 3 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.802454241 [13799/13803]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.802486041 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.802568999 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_2.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.802623413 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_2.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.802753341 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.802785141 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802824366 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.802846333 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802878032 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.802897680 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.802916308 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.802938049 [13799/13803]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.802957317 [13799/13803]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.802976999 [13799/13803]: Writing index for stream ID 3 and seq num 0 on fd 32 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.803008477 [13799/13803]: index put for stream id 3 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.803105016 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.803138605 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.748986440 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.749070643 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.749141720 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.749187665 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.749322315 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.749417335 [13799/13803]: Received live beacon for stream 1 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.749611701 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.749655043 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.749963987 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.750031507 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.750093445 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.750142481 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.750184898 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.750233219 [13799/13803]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.750386886 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.750425373 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.750871844 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.750950617 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.751121308 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.751190427 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.751238552 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.751294230 [13799/13803]: Received live beacon for stream 3 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.751483447 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.751536558 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.751865010 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.751911616 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.752154535 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.752202551 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.752247515 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.752295410 [13799/13803]: Received live beacon for stream 4 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.752457760 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.752495630 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794345402 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794414042 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 2, data_size = 84, net_seq_num = 1, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:53.794484803 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794509155 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794528467 [13799/13803]: Receiving data for stream id 2 seqnum 1, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:53.794612409 [13799/13803]: Wrote to stream 2: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:53.794647181 [13799/13803]: Wrote to stream 2: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:53.794666689 [13799/13803]: handle_index_data: stream 2 net_seq_num 1 data offset 4096 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:53.794688698 [13799/13803]: Finding index for stream id 2 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:53.794706646 [13799/13803]: Creating relay index for stream id 2 and seqnum 1 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:53.794727416 [13799/13803]: Adding relay index with stream id 2 and seqnum 1 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:53.794749454 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:53.794768283 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.794784214 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794811984 [13799/13803]: Done receiving control command header: fd = 20, cmd = 7, cmd_version = 0, payload size = 16 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.794831383 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794855229 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 16 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.794869992 [13799/13803]: Processing "RELAYD_CLOSE_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2810)
DEBUG1 - 14:47:53.794885495 [13799/13803]: Close stream received (in relay_close_stream() at main.c:1411)
DEBUG1 - 14:47:53.794903064 [13799/13803]: Trying to close stream 5 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.794919530 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.794941422 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.794960639 [13799/13803]: Succeeded in closing stream 5 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.794976614 [13799/13803]: Relay viewer stream 5 not found (in viewer_stream_get_by_id() at viewer-stream.c:240)
DEBUG1 - 14:47:53.794991383 [13799/13803]: Releasing stream id 5 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.795016152 [13799/13803]: Rotation completed for stream 5 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.795097016 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.795114350 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.939466539 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.939509685 [13799/13803]: Socket 20 performed an orderly shutdown (received EOF) (in relay_process_control_receive_header() at main.c:2969)
DEBUG1 - 14:47:53.939558822 [13799/13803]: Control connection closed with 20 (in relay_thread_close_connection() at main.c:3362)
DEBUG1 - 14:47:53.939569484 [13799/13803]: closing session 1: is conn already closed 0 (in session_close() at session.c:413)
DEBUG1 - 14:47:53.939578113 [13799/13803]: Trying to close stream 1 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939583759 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939593341 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939601743 [13799/13803]: Succeeded in closing stream 1 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939605710 [13799/13803]: Releasing stream id 1 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939615404 [13799/13803]: Rotation completed for stream 1 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939624720 [13799/13803]: Trying to close stream 2 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939629419 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG2 - 14:47:53.939634774 [13799/13803]: index put for stream id 2 and seqnum 1 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:53.939642577 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939648095 [13799/13803]: Succeeded in closing stream 2 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939651484 [13799/13803]: Releasing stream id 2 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939657668 [13799/13803]: Rotation completed for stream 2 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939661737 [13799/13803]: Trying to close stream 3 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939664911 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939668412 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939672187 [13799/13803]: Succeeded in closing stream 3 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939675713 [13799/13803]: Releasing stream id 3 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939683169 [13799/13803]: Rotation completed for stream 3 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939688073 [13799/13803]: Trying to close stream 4 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939692884 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939698339 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939703412 [13799/13803]: Succeeded in closing stream 4 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939706807 [13799/13803]: Releasing stream id 4 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939714283 [13799/13803]: Rotation completed for stream 4 (in stream_complete_rotation() at stream.c:74)
DEBUG3 - 14:47:53.939718809 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.939726504 [13799/13803]: Socket 21 performed an orderly shutdown (received EOF) (in relay_process_data_receive_header() at main.c:3080)
DEBUG1 - 14:47:53.939761182 [13799/13803]: Data connection closed with 21 (in relay_thread_close_connection() at main.c:3362)
DEBUG1 - 14:47:53.939766447 [13799/13803]: closing session 1: is conn already closed 1 (in session_close() at session.c:413)
DEBUG1 - 14:47:53.939775294 [13799/13803]: Releasing reference to trace chunk registry of sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in sessiond_trace_chunk_registry_session_destroyed() at sessiond-trace-chunks.c:345)
DEBUG1 - 14:47:53.939780421 [13799/13803]: Destroying trace chunk registry associated to sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in trace_chunk_registry_ht_element_release() at sessiond-trace-chunks.c:128)
DEBUG3 - 14:47:53.939819918 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
dd[0]frdeso@raton ~ % lttng-relayd -vvv [14:47]
DEBUG3 - 14:47:46.225850090 [13799/13799]: URI string: tcp://0.0.0.0:5342 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.226060392 [13799/13799]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.226099150 [13799/13799]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5342, data: 0 (in uri_parse() at uri.c:538)
DEBUG3 - 14:47:46.226161423 [13799/13799]: URI string: tcp://0.0.0.0:5343 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.226235225 [13799/13799]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.226269337 [13799/13799]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5343, data: 0 (in uri_parse() at uri.c:538)
DEBUG3 - 14:47:46.226300259 [13799/13799]: URI string: tcp://localhost:5344 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.227198608 [13799/13799]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.227233964 [13799/13799]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 5344, data: 0 (in uri_parse() at uri.c:538)
DEBUG1 - 14:47:46.227272628 [13799/13799]: Signal handler set for SIGTERM, SIGUSR1, SIGPIPE and SIGINT (in set_signal_handler() at main.c:720)
DEBUG1 - 14:47:46.227313024 [13799/13799]: LTTng-relayd 2.12.0-pre - Codename TBD - v2.11.0-rc1-549-g93d0d1f7d (in relayd_config_log() at main.c:201)
DEBUG1 - 14:47:46.227726663 [13799/13799]: Health check time delta in seconds set to 20 (in health_init() at health.c:73)
DEBUG1 - 14:47:46.227922655 [13799/13799]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:555)
DEBUG3 - 14:47:46.228097932 [13799/13799]: Created hashtable size 4 at 0x559330d4c700 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 14:47:46.228157729 [13799/13799]: Created hashtable size 4 at 0x559330d4cc40 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 14:47:46.228190357 [13799/13799]: Created hashtable size 4 at 0x559330d4d180 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.228497432 [13799/13802]: [thread] Relay dispatcher started (in relay_thread_dispatcher() at main.c:1035)
DEBUG1 - 14:47:46.228508157 [13799/13801]: [thread] Manage health check started (in thread_manage_health() at health-relayd.c:247)
DEBUG1 - 14:47:46.228653199 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:46.228831213 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:46.228912788 [13799/13799]: epoll set max size is 3226132 (in compat_epoll_set_max_size() at compat-epoll.c:356)
DEBUG1 - 14:47:46.228884532 [13799/13803]: [thread] Relay worker started (in relay_thread_worker() at main.c:3377)
DEBUG1 - 14:47:46.228980424 [13799/13804]: [thread] Relay listener started (in relay_thread_listener() at main.c:843)
DEBUG3 - 14:47:46.229607478 [13799/13801]: Creating LTTng run directory: /home/frdeso/.lttng (in create_lttng_rundir_with_perm() at health-relayd.c:94)
DEBUG3 - 14:47:46.229735978 [13799/13801]: Creating LTTng run directory: /home/frdeso/.lttng/relayd (in create_lttng_rundir_with_perm() at health-relayd.c:94)
DEBUG1 - 14:47:46.230021704 [13799/13801]: Health check ready (in thread_manage_health() at health-relayd.c:325)
DEBUG1 - 14:47:46.230094478 [13799/13804]: Listening on sock 11 (in relay_socket_create() at main.c:810)
DEBUG3 - 14:47:46.230156898 [13799/13803]: Created hashtable size 4 at 0x7fc878000b40 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.230260614 [13799/13804]: Listening on sock 15 (in relay_socket_create() at main.c:810)
DEBUG1 - 14:47:46.230427430 [13799/13806]: [thread] Live viewer relay worker started (in thread_worker() at live.c:1980)
DEBUG3 - 14:47:46.230267978 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:46.230042120 [13799/13805]: [thread] Live viewer relay dispatcher started (in thread_dispatcher() at live.c:644)
DEBUG1 - 14:47:46.230596067 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:46.230812364 [13799/13805]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:46.230634409 [13799/13807]: [thread] Relay live listener started (in thread_listener() at live.c:490)
DEBUG3 - 14:47:46.230870430 [13799/13806]: Created hashtable size 4 at 0x7fc870000b40 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.230916253 [13799/13805]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:672)
DEBUG1 - 14:47:46.231070420 [13799/13807]: Listening on sock 18 for lttng-live (in init_socket() at live.c:456)
DEBUG3 - 14:47:46.231071355 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:46.231189564 [13799/13807]: Listener accepting live viewers connections (in thread_listener() at live.c:522)
DEBUG1 - 14:47:48.476280993 [13799/13807]: Relay new viewer connection received (in thread_listener() at live.c:539)
DEBUG1 - 14:47:48.476584713 [13799/13807]: Relay viewer connection accepted socket 20 (in thread_listener() at live.c:570)
DEBUG1 - 14:47:48.476717963 [13799/13807]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.476798236 [13799/13805]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.476892754 [13799/13805]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.477120110 [13799/13805]: Dispatching viewer request waiting on sock 20 (in thread_dispatcher() at live.c:678)
DEBUG1 - 14:47:48.477226102 [13799/13805]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:672)
DEBUG1 - 14:47:48.476756585 [13799/13807]: Listener accepting live viewers connections (in thread_listener() at live.c:522)
DEBUG1 - 14:47:48.477914787 [13799/13806]: Connection socket 20 added to poll (in thread_worker() at live.c:2066)
DEBUG3 - 14:47:48.477966070 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:48.478206687 [13799/13806]: Viewer control conn closed with 20 (in thread_worker() at live.c:2091)
DEBUG3 - 14:47:48.478495301 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:48.607674256 [13799/13804]: Relay new connection received (in relay_thread_listener() at main.c:907)
DEBUG1 - 14:47:48.607772367 [13799/13804]: Relay control connection accepted, socket 20 (in relay_thread_listener() at main.c:944)
DEBUG1 - 14:47:48.607827700 [13799/13804]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG1 - 14:47:48.607886336 [13799/13804]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.607919036 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:48.608108295 [13799/13802]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.608151944 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.608191203 [13799/13802]: Dispatching request waiting on sock 20 (in relay_thread_dispatcher() at main.c:1068)
DEBUG1 - 14:47:48.608256383 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:48.608322494 [13799/13803]: Connection socket 20 added (in relay_thread_worker() at main.c:3464)
DEBUG3 - 14:47:48.608371619 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.608432494 [13799/13803]: Done receiving control command header: fd = 20, cmd = 5, cmd_version = 0, payload size = 8 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.608465059 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.608511300 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 8 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.608542033 [13799/13803]: Processing "RELAYD_VERSION" command for socket 20 (in relay_process_control_command() at main.c:2806)
DEBUG1 - 14:47:48.608661744 [13799/13803]: Version check done using protocol 2.12 (in relay_send_version() at main.c:1749)
DEBUG1 - 14:47:48.608693425 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.608727485 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.609017927 [13799/13803]: Done receiving control command header: fd = 20, cmd = 2, cmd_version = 0, payload size = 84 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.609062469 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.609116466 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 84 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.609152475 [13799/13803]: Processing "RELAYD_CREATE_SESSION" command for socket 20 (in relay_process_control_command() at main.c:2790)
DEBUG3 - 14:47:48.609274369 [13799/13803]: Created hashtable size 4 at 0x7fc8780033f0 of type 0 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:48.609385552 [13799/13803]: Created trace chunk registry for sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in trace_chunk_registry_ht_element_create() at sessiond-trace-chunks.c:236)
DEBUG1 - 14:47:48.609427052 [13799/13803]: Created session 1 (in relay_create_session() at main.c:1188)
DEBUG1 - 14:47:48.609567151 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.609603275 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.610675599 [13799/13804]: Relay new connection received (in relay_thread_listener() at main.c:907)
DEBUG1 - 14:47:48.610761651 [13799/13804]: Relay data connection accepted, socket 21 (in relay_thread_listener() at main.c:938)
DEBUG1 - 14:47:48.610905507 [13799/13804]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG1 - 14:47:48.610986307 [13799/13804]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.611021018 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:48.611067817 [13799/13802]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.611145900 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.611185268 [13799/13802]: Dispatching request waiting on sock 21 (in relay_thread_dispatcher() at main.c:1068)
DEBUG1 - 14:47:48.611242781 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:48.611302201 [13799/13803]: Connection socket 21 added (in relay_thread_worker() at main.c:3464)
DEBUG3 - 14:47:48.611340804 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.646605812 [13799/13803]: Done receiving control command header: fd = 20, cmd = 19, cmd_version = 0, payload size = 20 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.646868183 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.646961914 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 20 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.646989997 [13799/13803]: Processing "RELAYD_CREATE_TRACE_CHUNK" command for socket 20 (in relay_process_control_command() at main.c:2846)
DEBUG1 - 14:47:48.647036053 [13799/13803]: Creating trace chunk: chunk_id = 0, creation time = 20191029-144748 (in lttng_trace_chunk_create() at trace-chunk.c:283)
DEBUG1 - 14:47:48.647111122 [13799/13803]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:300)
DEBUG1 - 14:47:48.647542904 [13799/13803]: Attempting to publish trace chunk: sessiond {2370232e-4e54-48a8-b8be-5f707ae66270}, session_id = 1, chunk_id = 0 (in sessiond_trace_chunk_registry_publish_chunk() at sessiond-trace-chunks.c:398)
DEBUG1 - 14:47:48.647806942 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.647844668 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.748969029 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.749796416 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.749872181 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.749901327 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG1 - 14:47:51.749940353 [13799/13803]: CTF Trace path ust/pid/main-ust-13827-20191029-144751 not found (in ctf_trace_get_by_path_or_create() at ctf-trace.c:150)
DEBUG1 - 14:47:51.749984869 [13799/13803]: Created ctf_trace 1of session "claudia_normand_2" from host "raton" with path: ust/pid/main-ust-13827-20191029-144751 (in ctf_trace_create() at ctf-trace.c:125)
DEBUG3 - 14:47:51.750047666 [13799/13803]: Created hashtable size 4 at 0x7fc878004430 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.750078630 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.750671419 [13799/13803]: Adding new top-level directory "ust/pid/main-ust-13827-20191029-144751" to trace chunk "(unnamed)" (in add_top_level_directory_unique() at trace-chunk.c:734)
DEBUG1 - 14:47:51.750758575 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_0" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.750914257 [13799/13803]: Relay new stream added chan1_0 with ID 1 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.751187450 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.751245716 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.751666652 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.751729626 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.751797243 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.751840501 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.752263540 [13799/13803]: Created hashtable size 4 at 0x7fc878004c10 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.752310949 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.752413521 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_1" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.752573343 [13799/13803]: Relay new stream added chan1_1 with ID 2 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.752770801 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.752823518 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.753209700 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.753288450 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.753354450 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.753399734 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.753475448 [13799/13803]: Created hashtable size 4 at 0x7fc8780053b0 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.753511740 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.753606075 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_2" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.753781595 [13799/13803]: Relay new stream added chan1_2 with ID 3 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.753972625 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.754015288 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.754417558 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.754490657 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.754569472 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.754603014 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.754698922 [13799/13803]: Created hashtable size 4 at 0x7fc878005b50 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.754730513 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.754824324 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_3" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.755033413 [13799/13803]: Relay new stream added chan1_3 with ID 4 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.755218490 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.755253757 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761101611 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.761167712 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761220536 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.761256337 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.761321318 [13799/13803]: Created hashtable size 4 at 0x7fc8780062f0 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.761357956 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.761437611 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/metadata" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.761577575 [13799/13803]: Relay new stream added metadata with ID 5 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.761715786 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.761751767 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761958795 [13799/13803]: Done receiving control command header: fd = 20, cmd = 16, cmd_version = 0, payload size = 0 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG1 - 14:47:51.762029152 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 0 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.762064211 [13799/13803]: Processing "RELAYD_STREAMS_SENT" command for socket 20 (in relay_process_control_command() at main.c:2834)
DEBUG1 - 14:47:51.762097869 [13799/13803]: Relay receiving streams_sent (in relay_streams_sent() at main.c:2174)
DEBUG1 - 14:47:51.762199486 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.762235508 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.749694532 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 1, data_size = 1560, net_seq_num = 0, padding_size = 2536 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.749786216 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.749855288 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.749933318 [13799/13803]: Receiving data for stream id 1 seqnum 0, 0 bytes received, 1560 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.750963465 [13799/13803]: Wrote to stream 1: data_length = 1560, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.751240009 [13799/13803]: Wrote to stream 1: data_length = 0, padding_length = 2536 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.751360745 [13799/13803]: handle_index_data: stream 1 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.751400823 [13799/13803]: Finding index for stream id 1 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.751444212 [13799/13803]: Creating relay index for stream id 1 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.751523845 [13799/13803]: Adding relay index with stream id 1 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.751574081 [13799/13803]: Index found or created in HT for stream ID 1 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.751629187 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.752166411 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_0.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.752278572 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_0.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.752490956 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.752549300 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.752639775 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4108 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.752734511 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796304634 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4108 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796366238 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.796488995 [13799/13803]: Wrote to metadata stream 5: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.796526173 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.796557893 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796606545 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4105 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.796643929 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796690420 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4105 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796716167 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.796788328 [13799/13803]: Wrote to metadata stream 5: data_length = 4093, padding_length = 3 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.796815681 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.796842859 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796883453 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4105 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.796911339 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796952770 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4105 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796977029 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.797035753 [13799/13803]: Wrote to metadata stream 5: data_length = 4093, padding_length = 3 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.797061773 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797089273 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797129911 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 779 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.797160034 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797198195 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 779 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.797221920 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.797280820 [13799/13803]: Wrote to metadata stream 5: data_length = 767, padding_length = 3329 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.797306529 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797333520 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797373773 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.797399922 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797443286 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.797467795 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.797495786 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.797528403 [13799/13803]: Finding index for stream id 1 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.797557381 [13799/13803]: Index found or created in HT for stream ID 1 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.797588270 [13799/13803]: Writing index for stream ID 1 and seq num 0 on fd 29 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.797638919 [13799/13803]: index put for stream id 1 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.797828666 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797859648 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798542702 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.798609975 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 4, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.798647660 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798682775 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798869177 [13799/13803]: Receiving data for stream id 4 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.799127124 [13799/13803]: Wrote to stream 4: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.799208952 [13799/13803]: Wrote to stream 4: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.799261736 [13799/13803]: handle_index_data: stream 4 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.799302632 [13799/13803]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.799332066 [13799/13803]: Creating relay index for stream id 4 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.799384035 [13799/13803]: Adding relay index with stream id 4 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.799416201 [13799/13803]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.799454935 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.799564307 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_3.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.799646971 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_3.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.799824513 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.799867415 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800089152 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.800145449 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800195976 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.800218635 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.800237815 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.800261507 [13799/13803]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.800281551 [13799/13803]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.800301540 [13799/13803]: Writing index for stream ID 4 and seq num 0 on fd 30 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.800341623 [13799/13803]: index put for stream id 4 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.800454018 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.800486346 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800729367 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800790809 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 2, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.800832450 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800868131 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800902409 [13799/13803]: Receiving data for stream id 2 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.800994548 [13799/13803]: Wrote to stream 2: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.801031273 [13799/13803]: Wrote to stream 2: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.801051148 [13799/13803]: handle_index_data: stream 2 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.801070429 [13799/13803]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.801090575 [13799/13803]: Creating relay index for stream id 2 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.801112440 [13799/13803]: Adding relay index with stream id 2 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.801135693 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.801158851 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.801228450 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_1.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.801283589 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_1.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.801423550 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.801462259 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.801512142 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.801538917 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.801571264 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.801591247 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.801610092 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.801632184 [13799/13803]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.801651467 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.801671026 [13799/13803]: Writing index for stream ID 2 and seq num 0 on fd 31 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.801704450 [13799/13803]: index put for stream id 2 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.801807223 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.801839609 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802060467 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802120092 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 3, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.802159734 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802194919 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802228439 [13799/13803]: Receiving data for stream id 3 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.802315800 [13799/13803]: Wrote to stream 3: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.802350283 [13799/13803]: Wrote to stream 3: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.802369955 [13799/13803]: handle_index_data: stream 3 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.802388958 [13799/13803]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.802409272 [13799/13803]: Creating relay index for stream id 3 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.802430500 [13799/13803]: Adding relay index with stream id 3 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.802454241 [13799/13803]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.802486041 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.802568999 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_2.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.802623413 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_2.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.802753341 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.802785141 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802824366 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.802846333 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802878032 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.802897680 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.802916308 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.802938049 [13799/13803]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.802957317 [13799/13803]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.802976999 [13799/13803]: Writing index for stream ID 3 and seq num 0 on fd 32 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.803008477 [13799/13803]: index put for stream id 3 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.803105016 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.803138605 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.748986440 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.749070643 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.749141720 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.749187665 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.749322315 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.749417335 [13799/13803]: Received live beacon for stream 1 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.749611701 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.749655043 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.749963987 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.750031507 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.750093445 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.750142481 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.750184898 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.750233219 [13799/13803]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.750386886 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.750425373 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.750871844 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.750950617 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.751121308 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.751190427 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.751238552 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.751294230 [13799/13803]: Received live beacon for stream 3 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.751483447 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.751536558 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.751865010 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.751911616 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.752154535 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.752202551 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.752247515 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.752295410 [13799/13803]: Received live beacon for stream 4 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.752457760 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.752495630 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794345402 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794414042 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 2, data_size = 84, net_seq_num = 1, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:53.794484803 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794509155 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794528467 [13799/13803]: Receiving data for stream id 2 seqnum 1, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:53.794612409 [13799/13803]: Wrote to stream 2: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:53.794647181 [13799/13803]: Wrote to stream 2: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:53.794666689 [13799/13803]: handle_index_data: stream 2 net_seq_num 1 data offset 4096 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:53.794688698 [13799/13803]: Finding index for stream id 2 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:53.794706646 [13799/13803]: Creating relay index for stream id 2 and seqnum 1 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:53.794727416 [13799/13803]: Adding relay index with stream id 2 and seqnum 1 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:53.794749454 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:53.794768283 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.794784214 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794811984 [13799/13803]: Done receiving control command header: fd = 20, cmd = 7, cmd_version = 0, payload size = 16 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.794831383 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794855229 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 16 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.794869992 [13799/13803]: Processing "RELAYD_CLOSE_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2810)
DEBUG1 - 14:47:53.794885495 [13799/13803]: Close stream received (in relay_close_stream() at main.c:1411)
DEBUG1 - 14:47:53.794903064 [13799/13803]: Trying to close stream 5 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.794919530 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.794941422 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.794960639 [13799/13803]: Succeeded in closing stream 5 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.794976614 [13799/13803]: Relay viewer stream 5 not found (in viewer_stream_get_by_id() at viewer-stream.c:240)
DEBUG1 - 14:47:53.794991383 [13799/13803]: Releasing stream id 5 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.795016152 [13799/13803]: Rotation completed for stream 5 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.795097016 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.795114350 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.939466539 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.939509685 [13799/13803]: Socket 20 performed an orderly shutdown (received EOF) (in relay_process_control_receive_header() at main.c:2969)
DEBUG1 - 14:47:53.939558822 [13799/13803]: Control connection closed with 20 (in relay_thread_close_connection() at main.c:3362)
DEBUG1 - 14:47:53.939569484 [13799/13803]: closing session 1: is conn already closed 0 (in session_close() at session.c:413)
DEBUG1 - 14:47:53.939578113 [13799/13803]: Trying to close stream 1 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939583759 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939593341 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939601743 [13799/13803]: Succeeded in closing stream 1 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939605710 [13799/13803]: Releasing stream id 1 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939615404 [13799/13803]: Rotation completed for stream 1 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939624720 [13799/13803]: Trying to close stream 2 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939629419 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG2 - 14:47:53.939634774 [13799/13803]: index put for stream id 2 and seqnum 1 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:53.939642577 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939648095 [13799/13803]: Succeeded in closing stream 2 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939651484 [13799/13803]: Releasing stream id 2 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939657668 [13799/13803]: Rotation completed for stream 2 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939661737 [13799/13803]: Trying to close stream 3 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939664911 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939668412 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939672187 [13799/13803]: Succeeded in closing stream 3 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939675713 [13799/13803]: Releasing stream id 3 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939683169 [13799/13803]: Rotation completed for stream 3 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939688073 [13799/13803]: Trying to close stream 4 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939692884 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939698339 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939703412 [13799/13803]: Succeeded in closing stream 4 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939706807 [13799/13803]: Releasing stream id 4 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939714283 [13799/13803]: Rotation completed for stream 4 (in stream_complete_rotation() at stream.c:74)
DEBUG3 - 14:47:53.939718809 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.939726504 [13799/13803]: Socket 21 performed an orderly shutdown (received EOF) (in relay_process_data_receive_header() at main.c:3080)
DEBUG1 - 14:47:53.939761182 [13799/13803]: Data connection closed with 21 (in relay_thread_close_connection() at main.c:3362)
DEBUG1 - 14:47:53.939766447 [13799/13803]: closing session 1: is conn already closed 1 (in session_close() at session.c:413)
DEBUG1 - 14:47:53.939775294 [13799/13803]: Releasing reference to trace chunk registry of sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in sessiond_trace_chunk_registry_session_destroyed() at sessiond-trace-chunks.c:345)
DEBUG1 - 14:47:53.939780421 [13799/13803]: Destroying trace chunk registry associated to sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in trace_chunk_registry_ht_element_release() at sessiond-trace-chunks.c:128)
DEBUG3 - 14:47:53.939819918 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
dd[0]frdeso@raton ~ % lttng-relayd -vvv [14:47]
DEBUG3 - 14:47:46.225850090 [13799/13799]: URI string: tcp://0.0.0.0:5342 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.226060392 [13799/13799]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.226099150 [13799/13799]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5342, data: 0 (in uri_parse() at uri.c:538)
DEBUG3 - 14:47:46.226161423 [13799/13799]: URI string: tcp://0.0.0.0:5343 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.226235225 [13799/13799]: IP address resolved to 0.0.0.0 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.226269337 [13799/13799]: URI dtype: 1, proto: 1, host: 0.0.0.0, subdir: , ctrl: 5343, data: 0 (in uri_parse() at uri.c:538)
DEBUG3 - 14:47:46.226300259 [13799/13799]: URI string: tcp://localhost:5344 (in uri_parse() at uri.c:324)
DEBUG2 - 14:47:46.227198608 [13799/13799]: IP address resolved to 127.0.0.1 (in set_ip_address() at uri.c:165)
DEBUG3 - 14:47:46.227233964 [13799/13799]: URI dtype: 1, proto: 1, host: localhost, subdir: , ctrl: 5344, data: 0 (in uri_parse() at uri.c:538)
DEBUG1 - 14:47:46.227272628 [13799/13799]: Signal handler set for SIGTERM, SIGUSR1, SIGPIPE and SIGINT (in set_signal_handler() at main.c:720)
DEBUG1 - 14:47:46.227313024 [13799/13799]: LTTng-relayd 2.12.0-pre - Codename TBD - v2.11.0-rc1-549-g93d0d1f7d (in relayd_config_log() at main.c:201)
DEBUG1 - 14:47:46.227726663 [13799/13799]: Health check time delta in seconds set to 20 (in health_init() at health.c:73)
DEBUG1 - 14:47:46.227922655 [13799/13799]: TCP inet operation timeout set to 216 sec (in lttcomm_inet_init() at inet.c:555)
DEBUG3 - 14:47:46.228097932 [13799/13799]: Created hashtable size 4 at 0x559330d4c700 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 14:47:46.228157729 [13799/13799]: Created hashtable size 4 at 0x559330d4cc40 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG3 - 14:47:46.228190357 [13799/13799]: Created hashtable size 4 at 0x559330d4d180 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.228497432 [13799/13802]: [thread] Relay dispatcher started (in relay_thread_dispatcher() at main.c:1035)
DEBUG1 - 14:47:46.228508157 [13799/13801]: [thread] Manage health check started (in thread_manage_health() at health-relayd.c:247)
DEBUG1 - 14:47:46.228653199 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:46.228831213 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:46.228912788 [13799/13799]: epoll set max size is 3226132 (in compat_epoll_set_max_size() at compat-epoll.c:356)
DEBUG1 - 14:47:46.228884532 [13799/13803]: [thread] Relay worker started (in relay_thread_worker() at main.c:3377)
DEBUG1 - 14:47:46.228980424 [13799/13804]: [thread] Relay listener started (in relay_thread_listener() at main.c:843)
DEBUG3 - 14:47:46.229607478 [13799/13801]: Creating LTTng run directory: /home/frdeso/.lttng (in create_lttng_rundir_with_perm() at health-relayd.c:94)
DEBUG3 - 14:47:46.229735978 [13799/13801]: Creating LTTng run directory: /home/frdeso/.lttng/relayd (in create_lttng_rundir_with_perm() at health-relayd.c:94)
DEBUG1 - 14:47:46.230021704 [13799/13801]: Health check ready (in thread_manage_health() at health-relayd.c:325)
DEBUG1 - 14:47:46.230094478 [13799/13804]: Listening on sock 11 (in relay_socket_create() at main.c:810)
DEBUG3 - 14:47:46.230156898 [13799/13803]: Created hashtable size 4 at 0x7fc878000b40 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.230260614 [13799/13804]: Listening on sock 15 (in relay_socket_create() at main.c:810)
DEBUG1 - 14:47:46.230427430 [13799/13806]: [thread] Live viewer relay worker started (in thread_worker() at live.c:1980)
DEBUG3 - 14:47:46.230267978 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:46.230042120 [13799/13805]: [thread] Live viewer relay dispatcher started (in thread_dispatcher() at live.c:644)
DEBUG1 - 14:47:46.230596067 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:46.230812364 [13799/13805]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:46.230634409 [13799/13807]: [thread] Relay live listener started (in thread_listener() at live.c:490)
DEBUG3 - 14:47:46.230870430 [13799/13806]: Created hashtable size 4 at 0x7fc870000b40 of type 1 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:46.230916253 [13799/13805]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:672)
DEBUG1 - 14:47:46.231070420 [13799/13807]: Listening on sock 18 for lttng-live (in init_socket() at live.c:456)
DEBUG3 - 14:47:46.231071355 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:46.231189564 [13799/13807]: Listener accepting live viewers connections (in thread_listener() at live.c:522)
DEBUG1 - 14:47:48.476280993 [13799/13807]: Relay new viewer connection received (in thread_listener() at live.c:539)
DEBUG1 - 14:47:48.476584713 [13799/13807]: Relay viewer connection accepted socket 20 (in thread_listener() at live.c:570)
DEBUG1 - 14:47:48.476717963 [13799/13807]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.476798236 [13799/13805]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.476892754 [13799/13805]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.477120110 [13799/13805]: Dispatching viewer request waiting on sock 20 (in thread_dispatcher() at live.c:678)
DEBUG1 - 14:47:48.477226102 [13799/13805]: Woken up but nothing in the live-viewer relay command queue (in thread_dispatcher() at live.c:672)
DEBUG1 - 14:47:48.476756585 [13799/13807]: Listener accepting live viewers connections (in thread_listener() at live.c:522)
DEBUG1 - 14:47:48.477914787 [13799/13806]: Connection socket 20 added to poll (in thread_worker() at live.c:2066)
DEBUG3 - 14:47:48.477966070 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:48.478206687 [13799/13806]: Viewer control conn closed with 20 (in thread_worker() at live.c:2091)
DEBUG3 - 14:47:48.478495301 [13799/13806]: Relayd live viewer worker thread polling... (in thread_worker() at live.c:2013)
DEBUG1 - 14:47:48.607674256 [13799/13804]: Relay new connection received (in relay_thread_listener() at main.c:907)
DEBUG1 - 14:47:48.607772367 [13799/13804]: Relay control connection accepted, socket 20 (in relay_thread_listener() at main.c:944)
DEBUG1 - 14:47:48.607827700 [13799/13804]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG1 - 14:47:48.607886336 [13799/13804]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.607919036 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:48.608108295 [13799/13802]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.608151944 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.608191203 [13799/13802]: Dispatching request waiting on sock 20 (in relay_thread_dispatcher() at main.c:1068)
DEBUG1 - 14:47:48.608256383 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:48.608322494 [13799/13803]: Connection socket 20 added (in relay_thread_worker() at main.c:3464)
DEBUG3 - 14:47:48.608371619 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.608432494 [13799/13803]: Done receiving control command header: fd = 20, cmd = 5, cmd_version = 0, payload size = 8 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.608465059 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.608511300 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 8 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.608542033 [13799/13803]: Processing "RELAYD_VERSION" command for socket 20 (in relay_process_control_command() at main.c:2806)
DEBUG1 - 14:47:48.608661744 [13799/13803]: Version check done using protocol 2.12 (in relay_send_version() at main.c:1749)
DEBUG1 - 14:47:48.608693425 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.608727485 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.609017927 [13799/13803]: Done receiving control command header: fd = 20, cmd = 2, cmd_version = 0, payload size = 84 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.609062469 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.609116466 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 84 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.609152475 [13799/13803]: Processing "RELAYD_CREATE_SESSION" command for socket 20 (in relay_process_control_command() at main.c:2790)
DEBUG3 - 14:47:48.609274369 [13799/13803]: Created hashtable size 4 at 0x7fc8780033f0 of type 0 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:48.609385552 [13799/13803]: Created trace chunk registry for sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in trace_chunk_registry_ht_element_create() at sessiond-trace-chunks.c:236)
DEBUG1 - 14:47:48.609427052 [13799/13803]: Created session 1 (in relay_create_session() at main.c:1188)
DEBUG1 - 14:47:48.609567151 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.609603275 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.610675599 [13799/13804]: Relay new connection received (in relay_thread_listener() at main.c:907)
DEBUG1 - 14:47:48.610761651 [13799/13804]: Relay data connection accepted, socket 21 (in relay_thread_listener() at main.c:938)
DEBUG1 - 14:47:48.610905507 [13799/13804]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG1 - 14:47:48.610986307 [13799/13804]: Futex n to 1 wake done (in futex_nto1_wake() at futex.c:122)
DEBUG1 - 14:47:48.611021018 [13799/13804]: Listener accepting connections (in relay_thread_listener() at main.c:889)
DEBUG1 - 14:47:48.611067817 [13799/13802]: Futex n to 1 wait done (in futex_nto1_wait() at futex.c:105)
DEBUG1 - 14:47:48.611145900 [13799/13802]: Futex n to 1 prepare done (in futex_nto1_prepare() at futex.c:77)
DEBUG1 - 14:47:48.611185268 [13799/13802]: Dispatching request waiting on sock 21 (in relay_thread_dispatcher() at main.c:1068)
DEBUG1 - 14:47:48.611242781 [13799/13802]: Woken up but nothing in the relay command queue (in relay_thread_dispatcher() at main.c:1062)
DEBUG1 - 14:47:48.611302201 [13799/13803]: Connection socket 21 added (in relay_thread_worker() at main.c:3464)
DEBUG3 - 14:47:48.611340804 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.646605812 [13799/13803]: Done receiving control command header: fd = 20, cmd = 19, cmd_version = 0, payload size = 20 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:48.646868183 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:48.646961914 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 20 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:48.646989997 [13799/13803]: Processing "RELAYD_CREATE_TRACE_CHUNK" command for socket 20 (in relay_process_control_command() at main.c:2846)
DEBUG1 - 14:47:48.647036053 [13799/13803]: Creating trace chunk: chunk_id = 0, creation time = 20191029-144748 (in lttng_trace_chunk_create() at trace-chunk.c:283)
DEBUG1 - 14:47:48.647111122 [13799/13803]: Chunk name set to "(none)" (in lttng_trace_chunk_create() at trace-chunk.c:300)
DEBUG1 - 14:47:48.647542904 [13799/13803]: Attempting to publish trace chunk: sessiond {2370232e-4e54-48a8-b8be-5f707ae66270}, session_id = 1, chunk_id = 0 (in sessiond_trace_chunk_registry_publish_chunk() at sessiond-trace-chunks.c:398)
DEBUG1 - 14:47:48.647806942 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:48.647844668 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.748969029 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.749796416 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.749872181 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.749901327 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG1 - 14:47:51.749940353 [13799/13803]: CTF Trace path ust/pid/main-ust-13827-20191029-144751 not found (in ctf_trace_get_by_path_or_create() at ctf-trace.c:150)
DEBUG1 - 14:47:51.749984869 [13799/13803]: Created ctf_trace 1of session "claudia_normand_2" from host "raton" with path: ust/pid/main-ust-13827-20191029-144751 (in ctf_trace_create() at ctf-trace.c:125)
DEBUG3 - 14:47:51.750047666 [13799/13803]: Created hashtable size 4 at 0x7fc878004430 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.750078630 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.750671419 [13799/13803]: Adding new top-level directory "ust/pid/main-ust-13827-20191029-144751" to trace chunk "(unnamed)" (in add_top_level_directory_unique() at trace-chunk.c:734)
DEBUG1 - 14:47:51.750758575 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_0" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.750914257 [13799/13803]: Relay new stream added chan1_0 with ID 1 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.751187450 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.751245716 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.751666652 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.751729626 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.751797243 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.751840501 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.752263540 [13799/13803]: Created hashtable size 4 at 0x7fc878004c10 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.752310949 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.752413521 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_1" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.752573343 [13799/13803]: Relay new stream added chan1_1 with ID 2 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.752770801 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.752823518 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.753209700 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.753288450 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.753354450 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.753399734 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.753475448 [13799/13803]: Created hashtable size 4 at 0x7fc8780053b0 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.753511740 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.753606075 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_2" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.753781595 [13799/13803]: Relay new stream added chan1_2 with ID 3 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.753972625 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.754015288 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.754417558 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 79 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.754490657 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.754569472 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 79 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.754603014 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.754698922 [13799/13803]: Created hashtable size 4 at 0x7fc878005b50 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.754730513 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.754824324 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/chan1_3" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.755033413 [13799/13803]: Relay new stream added chan1_3 with ID 4 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.755218490 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.755253757 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761101611 [13799/13803]: Done receiving control command header: fd = 20, cmd = 1, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:51.761167712 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761220536 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.761256337 [13799/13803]: Processing "RELAYD_ADD_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2794)
DEBUG3 - 14:47:51.761321318 [13799/13803]: Created hashtable size 4 at 0x7fc8780062f0 of type 2 (in lttng_ht_new() at hashtable.c:145)
DEBUG1 - 14:47:51.761357956 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:51.761437611 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/metadata" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:51.761577575 [13799/13803]: Relay new stream added metadata with ID 5 (in stream_create() at stream.c:635)
DEBUG1 - 14:47:51.761715786 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.761751767 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:51.761958795 [13799/13803]: Done receiving control command header: fd = 20, cmd = 16, cmd_version = 0, payload size = 0 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG1 - 14:47:51.762029152 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 0 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:51.762064211 [13799/13803]: Processing "RELAYD_STREAMS_SENT" command for socket 20 (in relay_process_control_command() at main.c:2834)
DEBUG1 - 14:47:51.762097869 [13799/13803]: Relay receiving streams_sent (in relay_streams_sent() at main.c:2174)
DEBUG1 - 14:47:51.762199486 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:51.762235508 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.749694532 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 1, data_size = 1560, net_seq_num = 0, padding_size = 2536 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.749786216 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.749855288 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.749933318 [13799/13803]: Receiving data for stream id 1 seqnum 0, 0 bytes received, 1560 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.750963465 [13799/13803]: Wrote to stream 1: data_length = 1560, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.751240009 [13799/13803]: Wrote to stream 1: data_length = 0, padding_length = 2536 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.751360745 [13799/13803]: handle_index_data: stream 1 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.751400823 [13799/13803]: Finding index for stream id 1 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.751444212 [13799/13803]: Creating relay index for stream id 1 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.751523845 [13799/13803]: Adding relay index with stream id 1 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.751574081 [13799/13803]: Index found or created in HT for stream ID 1 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.751629187 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.752166411 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_0.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.752278572 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_0.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.752490956 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.752549300 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.752639775 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4108 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.752734511 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796304634 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4108 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796366238 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.796488995 [13799/13803]: Wrote to metadata stream 5: data_length = 4096, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.796526173 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.796557893 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796606545 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4105 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.796643929 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796690420 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4105 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796716167 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.796788328 [13799/13803]: Wrote to metadata stream 5: data_length = 4093, padding_length = 3 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.796815681 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.796842859 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796883453 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 4105 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.796911339 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.796952770 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 4105 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.796977029 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.797035753 [13799/13803]: Wrote to metadata stream 5: data_length = 4093, padding_length = 3 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.797061773 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797089273 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797129911 [13799/13803]: Done receiving control command header: fd = 20, cmd = 6, cmd_version = 0, payload size = 779 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.797160034 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797198195 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 779 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.797221920 [13799/13803]: Processing "RELAYD_SEND_METADATA" command for socket 20 (in relay_process_control_command() at main.c:2802)
DEBUG1 - 14:47:52.797280820 [13799/13803]: Wrote to metadata stream 5: data_length = 767, padding_length = 3329 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.797306529 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797333520 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797373773 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.797399922 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.797443286 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.797467795 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.797495786 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.797528403 [13799/13803]: Finding index for stream id 1 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.797557381 [13799/13803]: Index found or created in HT for stream ID 1 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.797588270 [13799/13803]: Writing index for stream ID 1 and seq num 0 on fd 29 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.797638919 [13799/13803]: index put for stream id 1 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.797828666 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.797859648 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798542702 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.798609975 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 4, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.798647660 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798682775 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.798869177 [13799/13803]: Receiving data for stream id 4 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.799127124 [13799/13803]: Wrote to stream 4: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.799208952 [13799/13803]: Wrote to stream 4: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.799261736 [13799/13803]: handle_index_data: stream 4 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.799302632 [13799/13803]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.799332066 [13799/13803]: Creating relay index for stream id 4 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.799384035 [13799/13803]: Adding relay index with stream id 4 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.799416201 [13799/13803]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.799454935 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.799564307 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_3.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.799646971 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_3.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.799824513 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.799867415 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800089152 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.800145449 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800195976 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.800218635 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.800237815 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.800261507 [13799/13803]: Finding index for stream id 4 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.800281551 [13799/13803]: Index found or created in HT for stream ID 4 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.800301540 [13799/13803]: Writing index for stream ID 4 and seq num 0 on fd 30 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.800341623 [13799/13803]: index put for stream id 4 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.800454018 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.800486346 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800729367 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.800790809 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 2, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.800832450 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800868131 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.800902409 [13799/13803]: Receiving data for stream id 2 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.800994548 [13799/13803]: Wrote to stream 2: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.801031273 [13799/13803]: Wrote to stream 2: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.801051148 [13799/13803]: handle_index_data: stream 2 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.801070429 [13799/13803]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.801090575 [13799/13803]: Creating relay index for stream id 2 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.801112440 [13799/13803]: Adding relay index with stream id 2 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.801135693 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.801158851 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.801228450 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_1.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.801283589 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_1.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.801423550 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.801462259 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.801512142 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.801538917 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.801571264 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.801591247 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.801610092 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.801632184 [13799/13803]: Finding index for stream id 2 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.801651467 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.801671026 [13799/13803]: Writing index for stream ID 2 and seq num 0 on fd 31 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.801704450 [13799/13803]: index put for stream id 2 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.801807223 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.801839609 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802060467 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802120092 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 3, data_size = 84, net_seq_num = 0, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:52.802159734 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802194919 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:52.802228439 [13799/13803]: Receiving data for stream id 3 seqnum 0, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:52.802315800 [13799/13803]: Wrote to stream 3: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.802350283 [13799/13803]: Wrote to stream 3: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:52.802369955 [13799/13803]: handle_index_data: stream 3 net_seq_num 0 data offset 0 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:52.802388958 [13799/13803]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.802409272 [13799/13803]: Creating relay index for stream id 3 and seqnum 0 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:52.802430500 [13799/13803]: Adding relay index with stream id 3 and seqnum 0 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:52.802454241 [13799/13803]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:52.802486041 [13799/13803]: Creating trace chunk subdirectory "ust/pid/main-ust-13827-20191029-144751/index" (in lttng_trace_chunk_create_subdirectory() at trace-chunk.c:760)
DEBUG1 - 14:47:52.802568999 [13799/13803]: Unlinking trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_2.idx" (in lttng_trace_chunk_unlink_file() at trace-chunk.c:861)
DEBUG1 - 14:47:52.802623413 [13799/13803]: Opening trace chunk file "ust/pid/main-ust-13827-20191029-144751/index/chan1_2.idx" (in lttng_trace_chunk_open_file() at trace-chunk.c:820)
DEBUG1 - 14:47:52.802753341 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.802785141 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802824366 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:52.802846333 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:52.802878032 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:52.802897680 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:52.802916308 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG3 - 14:47:52.802938049 [13799/13803]: Finding index for stream id 3 and seq_num 0 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:52.802957317 [13799/13803]: Index found or created in HT for stream ID 3 and seqnum 0 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG2 - 14:47:52.802976999 [13799/13803]: Writing index for stream ID 3 and seq num 0 on fd 32 (in relay_index_try_flush() at index.c:289)
DEBUG2 - 14:47:52.803008477 [13799/13803]: index put for stream id 3 and seqnum 0 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:52.803105016 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:52.803138605 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.748986440 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.749070643 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.749141720 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.749187665 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.749322315 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.749417335 [13799/13803]: Received live beacon for stream 1 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.749611701 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.749655043 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.749963987 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.750031507 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.750093445 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.750142481 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.750184898 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.750233219 [13799/13803]: Received live beacon for stream 2 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.750386886 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.750425373 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.750871844 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.750950617 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.751121308 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.751190427 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.751238552 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.751294230 [13799/13803]: Received live beacon for stream 3 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.751483447 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.751536558 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.751865010 [13799/13803]: Done receiving control command header: fd = 20, cmd = 13, cmd_version = 0, payload size = 80 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.751911616 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.752154535 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 80 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.752202551 [13799/13803]: Processing "RELAYD_SEND_INDEX" command for socket 20 (in relay_process_control_command() at main.c:2830)
DEBUG1 - 14:47:53.752247515 [13799/13803]: Relay receiving index (in relay_recv_index() at main.c:2093)
DEBUG1 - 14:47:53.752295410 [13799/13803]: Received live beacon for stream 4 (in stream_add_index() at stream.c:1153)
DEBUG1 - 14:47:53.752457760 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.752495630 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794345402 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794414042 [13799/13803]: Received data connection header on fd 21: circuit_id = 0, stream_id = 2, data_size = 84, net_seq_num = 1, padding_size = 4012 (in relay_process_data_receive_header() at main.c:3121)
DEBUG3 - 14:47:53.794484803 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794509155 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.794528467 [13799/13803]: Receiving data for stream id 2 seqnum 1, 0 bytes received, 84 bytes left to receive (in relay_process_data_receive_payload() at main.c:3166)
DEBUG1 - 14:47:53.794612409 [13799/13803]: Wrote to stream 2: data_length = 84, padding_length = 0 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:53.794647181 [13799/13803]: Wrote to stream 2: data_length = 0, padding_length = 4012 (in stream_write() at stream.c:1036)
DEBUG1 - 14:47:53.794666689 [13799/13803]: handle_index_data: stream 2 net_seq_num 1 data offset 4096 (in stream_update_index() at stream.c:1061)
DEBUG3 - 14:47:53.794688698 [13799/13803]: Finding index for stream id 2 and seq_num 1 (in relay_index_get_by_id_or_create() at index.c:124)
DEBUG2 - 14:47:53.794706646 [13799/13803]: Creating relay index for stream id 2 and seqnum 1 (in relay_index_create() at index.c:46)
DEBUG2 - 14:47:53.794727416 [13799/13803]: Adding relay index with stream id 2 and seqnum 1 (in relay_index_add_unique() at index.c:82)
DEBUG2 - 14:47:53.794749454 [13799/13803]: Index found or created in HT for stream ID 2 and seqnum 1 (in relay_index_get_by_id_or_create() at index.c:156)
DEBUG1 - 14:47:53.794768283 [13799/13803]: Reset communication state of relay connection (fd = 21) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.794784214 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794811984 [13799/13803]: Done receiving control command header: fd = 20, cmd = 7, cmd_version = 0, payload size = 16 bytes (in relay_process_control_receive_header() at main.c:3004)
DEBUG3 - 14:47:53.794831383 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.794855229 [13799/13803]: Done receiving control command payload: fd = 20, payload size = 16 bytes (in relay_process_control_receive_payload() at main.c:2920)
DEBUG3 - 14:47:53.794869992 [13799/13803]: Processing "RELAYD_CLOSE_STREAM" command for socket 20 (in relay_process_control_command() at main.c:2810)
DEBUG1 - 14:47:53.794885495 [13799/13803]: Close stream received (in relay_close_stream() at main.c:1411)
DEBUG1 - 14:47:53.794903064 [13799/13803]: Trying to close stream 5 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.794919530 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.794941422 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.794960639 [13799/13803]: Succeeded in closing stream 5 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.794976614 [13799/13803]: Relay viewer stream 5 not found (in viewer_stream_get_by_id() at viewer-stream.c:240)
DEBUG1 - 14:47:53.794991383 [13799/13803]: Releasing stream id 5 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.795016152 [13799/13803]: Rotation completed for stream 5 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.795097016 [13799/13803]: Reset communication state of relay connection (fd = 20) (in connection_reset_protocol_state() at connection.c:93)
DEBUG3 - 14:47:53.795114350 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG3 - 14:47:53.939466539 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.939509685 [13799/13803]: Socket 20 performed an orderly shutdown (received EOF) (in relay_process_control_receive_header() at main.c:2969)
DEBUG1 - 14:47:53.939558822 [13799/13803]: Control connection closed with 20 (in relay_thread_close_connection() at main.c:3362)
DEBUG1 - 14:47:53.939569484 [13799/13803]: closing session 1: is conn already closed 0 (in session_close() at session.c:413)
DEBUG1 - 14:47:53.939578113 [13799/13803]: Trying to close stream 1 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939583759 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939593341 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939601743 [13799/13803]: Succeeded in closing stream 1 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939605710 [13799/13803]: Releasing stream id 1 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939615404 [13799/13803]: Rotation completed for stream 1 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939624720 [13799/13803]: Trying to close stream 2 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939629419 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG2 - 14:47:53.939634774 [13799/13803]: index put for stream id 2 and seqnum 1 refcount 1 (in relay_index_put() at index.c:250)
DEBUG1 - 14:47:53.939642577 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939648095 [13799/13803]: Succeeded in closing stream 2 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939651484 [13799/13803]: Releasing stream id 2 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939657668 [13799/13803]: Rotation completed for stream 2 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939661737 [13799/13803]: Trying to close stream 3 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939664911 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939668412 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939672187 [13799/13803]: Succeeded in closing stream 3 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939675713 [13799/13803]: Releasing stream id 3 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939683169 [13799/13803]: Rotation completed for stream 3 (in stream_complete_rotation() at stream.c:74)
DEBUG1 - 14:47:53.939688073 [13799/13803]: Trying to close stream 4 (in try_stream_close() at stream.c:846)
DEBUG1 - 14:47:53.939692884 [13799/13803]: relay_index_close_partial_fd (in try_stream_close() at stream.c:882)
DEBUG1 - 14:47:53.939698339 [13799/13803]: Updating stream->last_net_seq_num to 18446744073709551615 (in try_stream_close() at stream.c:890)
DEBUG1 - 14:47:53.939703412 [13799/13803]: Succeeded in closing stream 4 (in try_stream_close() at stream.c:925)
DEBUG1 - 14:47:53.939706807 [13799/13803]: Releasing stream id 4 (in stream_release() at stream.c:751)
DEBUG1 - 14:47:53.939714283 [13799/13803]: Rotation completed for stream 4 (in stream_complete_rotation() at stream.c:74)
DEBUG3 - 14:47:53.939718809 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
DEBUG1 - 14:47:53.939726504 [13799/13803]: Socket 21 performed an orderly shutdown (received EOF) (in relay_process_data_receive_header() at main.c:3080)
DEBUG1 - 14:47:53.939761182 [13799/13803]: Data connection closed with 21 (in relay_thread_close_connection() at main.c:3362)
DEBUG1 - 14:47:53.939766447 [13799/13803]: closing session 1: is conn already closed 1 (in session_close() at session.c:413)
DEBUG1 - 14:47:53.939775294 [13799/13803]: Releasing reference to trace chunk registry of sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in sessiond_trace_chunk_registry_session_destroyed() at sessiond-trace-chunks.c:345)
DEBUG1 - 14:47:53.939780421 [13799/13803]: Destroying trace chunk registry associated to sessiond {2370232e-4e54-48a8-b8be-5f707ae66270} (in trace_chunk_registry_ht_element_release() at sessiond-trace-chunks.c:128)
DEBUG3 - 14:47:53.939819918 [13799/13803]: Relayd worker thread polling... (in relay_thread_worker() at main.c:3412)
(1-1/2)