
ARG__ 
0x1
721

ARG__ 
0xb
770

ARG__ 
3
3

ARG__ 
301
301
"http_request_file.txt"
PROOTPATH 5b /app
"/app/panther-ivy/protocol-testing/apt/apt_protocols/quic/quic_utils/http_request_file.txt"
File opening: 3
Reading file: 3
Reading 17 bytes from file
C time = start
insertBreakpoint: name = "0"
insertBreakpoint: now.tv_sec = 1724516947
insertBreakpoint: now.tv_usec = 715979
insertBreakpoint: name false = "0"
insertBreakpoint: end_ms false = 715979
insertBreakpoint: breakpoints_ms.count(name) false = 1
open SOCKET 4
open SOCKET addr 167772162
open SOCKET htonl(addr.addr) 33554442
open SOCKET port 4991
open SOCKET dev veth_ivy
source port number 0
binding client id: client addr: 0 port: 4991
source port number 4991
installing reader 2
between random: 57236219
between random: 57236219
between random: 26786
between random: 26786
create cid = 1
is_server  = 0
PROUT 3 
PROUT 4 
TEST_ALPN hq-interop
INITIAL_VERSION 1
INITIAL_VERSION 1
tls_tags_map[quic_transport_parameters] 57
etype =
ser-open_tag: bytes : 2
ser-open_tag: tag : 57
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "quic_transport_parameters"
ser-open_field: flb : 2
ser-open_field: field_start.push_back(res.size()) : 4
ser-open_field: ffield_length_bytes.push_back(flb) : 2
ser-open_field: name_field_bytes 2: 0
ser-open_field: field_bytes: 0
ser-open_field: res.size() : 4
ser-open_field: name 1: "transport_parameters"
ser-open_field: field_start.push_back(res.size()) : 4
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 2: 0
ser-open_field: field_bytes: 0
ser-open_field: res.size() : 4
ser-open_tag: bytes : 1
ser-open_tag: tag : 5
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "initial_max_stream_data_bidi_local"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 6
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: 0
ser-open_field: field_bytes: 0
ser-open_field: res.size() : 6
ser-open_field: name 1: "stream_pos_32"
ser-open_field: field_start.push_back(res.size()) : 6
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 6
ser-close_field: start : 6
ser-close_field: end : 8
ser-close_field: len : 2
ser-close_field: flb : 0
-------------------
ser-close_field: start : 6
ser-close_field: end : 8
ser-close_field: len : 2
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 4
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "initial_max_data"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 10
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 10
ser-open_field: name 1: "stream_pos_32"
ser-open_field: field_start.push_back(res.size()) : 10
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 10
ser-close_field: start : 10
ser-close_field: end : 14
ser-close_field: len : 4
ser-close_field: flb : 0
-------------------
ser-close_field: start : 10
ser-close_field: end : 14
ser-close_field: len : 4
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 1
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "max_idle_timeout"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 16
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 16
ser-open_field: name 1: "seconds_16"
ser-open_field: field_start.push_back(res.size()) : 16
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 16
ser-close_field: start : 16
ser-close_field: end : 20
ser-close_field: len : 4
ser-close_field: flb : 0
-------------------
ser-close_field: start : 16
ser-close_field: end : 20
ser-close_field: len : 4
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 6
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "initial_max_stream_data_bidi_remote"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 22
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 22
ser-open_field: name 1: "stream_pos_32"
ser-open_field: field_start.push_back(res.size()) : 22
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 22
ser-close_field: start : 22
ser-close_field: end : 24
ser-close_field: len : 2
ser-close_field: flb : 0
-------------------
ser-close_field: start : 22
ser-close_field: end : 24
ser-close_field: len : 2
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 7
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "initial_max_stream_data_uni"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 26
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 26
ser-open_field: name 1: "stream_pos_32"
ser-open_field: field_start.push_back(res.size()) : 26
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 26
ser-close_field: start : 26
ser-close_field: end : 30
ser-close_field: len : 4
ser-close_field: flb : 0
-------------------
ser-close_field: start : 26
ser-close_field: end : 30
ser-close_field: len : 4
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 15
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "initial_source_connection_id"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 32
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 32
ser-open_field: name 1: "scid"
ser-open_field: field_start.push_back(res.size()) : 32
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: 8
ser-open_field: field_bytes: 8
ser-open_field: res.size() : 32
ser-close_field: start : 32
ser-close_field: end : 40
ser-close_field: len : 8
ser-close_field: flb : 0
-------------------
ser-close_field: start : 32
ser-close_field: end : 40
ser-close_field: len : 8
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 9
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "initial_max_stream_id_uni"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 42
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: 8
ser-open_field: field_bytes: 8
ser-open_field: res.size() : 42
ser-open_field: name 1: "stream_id_16"
ser-open_field: field_start.push_back(res.size()) : 42
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 42
ser-close_field: start : 42
ser-close_field: end : 46
ser-close_field: len : 4
ser-close_field: flb : 0
-------------------
ser-close_field: start : 42
ser-close_field: end : 46
ser-close_field: len : 4
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 11
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "max_ack_delay"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 48
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 48
ser-open_field: name 1: "exponent_8"
ser-open_field: field_start.push_back(res.size()) : 48
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 48
ser-close_field: start : 48
ser-close_field: end : 50
ser-close_field: len : 2
ser-close_field: flb : 0
-------------------
ser-close_field: start : 48
ser-close_field: end : 50
ser-close_field: len : 2
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 8
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "initial_max_stream_id_bidi"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 52
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 52
ser-open_field: name 1: "stream_id_16"
ser-open_field: field_start.push_back(res.size()) : 52
ser-open_field: ffield_length_bytes.push_back(flb) : 0
ser-open_field: name_field_bytes 1: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 52
ser-close_field: start : 52
ser-close_field: end : 56
ser-close_field: len : 4
ser-close_field: flb : 0
-------------------
ser-close_field: start : 52
ser-close_field: end : 56
ser-close_field: len : 4
ser-close_field: flb : 1
-------------------
ser-open_tag: bytes : 1
ser-open_tag: tag : 12
ser-open_tag: tag != -1: 
TEST_TYPE attacker_server
ser-open_field: name 1: "disable_active_migration"
ser-open_field: flb : 1
ser-open_field: field_start.push_back(res.size()) : 58
ser-open_field: ffield_length_bytes.push_back(flb) : 1
ser-open_field: name_field_bytes 2: -1
ser-open_field: field_bytes: -1
ser-open_field: res.size() : 58
ser-close_field: start : 58
ser-close_field: end : 58
ser-close_field: len : 0
ser-close_field: flb : 1
-------------------
ser-close_field: start : 4
ser-close_field: end : 58
ser-close_field: len : 54
ser-close_field: flb : 0
-------------------
ser-close_field: start : 4
ser-close_field: end : 58
ser-close_field: len : 54
ser-close_field: flb : 2
-------------------
etype =57
len =54
ptls_exts[i].type = 57
ptls_exts[i].len = 54
ptls_set_negotiated_protocol
ptls_is_psk_handshake= 0
picotls_do_handshake ptls_handle_message end 
PICOTLS RETURNED PTLS_ERROR_IN_PROGRESS 514
[1,0,0,220,3,... (224) ...,0,104,162,12,0]
0

tag v1: 1
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "tls.client_hello"
value_size: 0
tag v2: 1
deser-open_field: name : "tls.client_hello"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 220
deser-open_field: name : "client_version"
4) pkt-pos =  224
deser-open_field: field_bytes : 2
deser-open_field: name : "rand_info"
4) pkt-pos =  224
deser-open_field: name : "gmt_unix_time"
4) pkt-pos =  224
deser-open_field: field_bytes : 4
deser-open_field: name : "random_bytes"
deser-open_field: name : "session_id"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 0
deser-open_field: tls_field_bytes_map["session_id"] : 0
deser-open_field: field_bytes : 1
deser-open_field: name : "cipher_suites"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 6
deser-open_field: tls_field_bytes_map["cipher_suites"] : 6
deser-open_field: field_bytes : 2
deser-open_field: name : "compression_methods"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 1
deser-open_field: tls_field_bytes_map["compression_methods"] : 1
deser-open_field: field_bytes : 1
deser-open_field: name : "extensions"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 173
tag v1: 51
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 51
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  224
deser-open_field: name : "etype"
4) pkt-pos =  224
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 38
deser-open_field: tls_field_bytes_map["content"] : 38
deser-open_field: field_bytes : 1
tag v1: 0
bytes v1: 2
is_unknow: 0
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 0
deser-open_field: name : "tls.end_of_early_data"
4) pkt-pos =  224
tag v1: 15
bytes v1: 2
is_unknow: 0
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 15
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  224
deser-open_field: name : "etype"
4) pkt-pos =  224
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 13
deser-open_field: tls_field_bytes_map["content"] : 13
deser-open_field: field_bytes : 1
tag v1: 16
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 16
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  224
deser-open_field: name : "etype"
4) pkt-pos =  224
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 13
deser-open_field: tls_field_bytes_map["content"] : 13
deser-open_field: field_bytes : 1
tag v1: 43
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 43
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  224
deser-open_field: name : "etype"
4) pkt-pos =  224
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 9
deser-open_field: tls_field_bytes_map["content"] : 9
deser-open_field: field_bytes : 1
tag v1: 13
bytes v1: 2
is_unknow: 0
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 13
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  224
deser-open_field: name : "etype"
4) pkt-pos =  224
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 10
deser-open_field: tls_field_bytes_map["content"] : 10
deser-open_field: field_bytes : 1
tag v1: 10
bytes v1: 2
is_unknow: 0
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 10
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  224
deser-open_field: name : "etype"
4) pkt-pos =  224
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 6
deser-open_field: tls_field_bytes_map["content"] : 6
deser-open_field: field_bytes : 1
tag v1: 57
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 57
deser-open_field: name : "quic_transport_parameters"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 54
deser-open_field: name : "transport_parameters"
4) pkt-pos =  224
tag v1: 5
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 5
deser-open_field: name : "initial_max_stream_data_bidi_local"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 2
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  174
deser-open_field: field_bytes : -1
tag v1: 4
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 4
deser-open_field: name : "initial_max_data"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  180
deser-open_field: field_bytes : -1
tag v1: 1
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 1
deser-open_field: name : "max_idle_timeout"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "seconds_16"
4) pkt-pos =  186
deser-open_field: field_bytes : -1
tag v1: 6
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 6
deser-open_field: name : "initial_max_stream_data_bidi_remote"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 2
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  190
deser-open_field: field_bytes : -1
tag v1: 7
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 7
deser-open_field: name : "initial_max_stream_data_uni"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  196
deser-open_field: field_bytes : -1
tag v1: 15
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
tag: 15
value_size: 8
value_size: 8
tag v2: 15
deser-open_field: name : "initial_source_connection_id"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 8
deser-open_field: name : "scid"
4) pkt-pos =  206
deser-open_field: field_bytes : 8
tag v1: 9
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 9
deser-open_field: name : "initial_max_stream_id_uni"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_id_16"
4) pkt-pos =  212
deser-open_field: field_bytes : -1
tag v1: 11
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 11
deser-open_field: name : "max_ack_delay"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 2
deser-open_field: name : "exponent_8"
4) pkt-pos =  216
deser-open_field: field_bytes : -1
tag v1: 8
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 8
deser-open_field: name : "initial_max_stream_id_bidi"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_id_16"
4) pkt-pos =  222
deser-open_field: field_bytes : -1
tag v1: 12
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 12
deser-open_field: name : "disable_active_migration"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 0
PICOTLS handshake handle message finish
action: ext:client_send_event
varname(actname): ext__client_send_event
index : 0
action: ext:packet_event
varname(actname): ext__packet_event
index : 1
action: ext:quic_frame.ack.handle
varname(actname): ext__quic_frame__ack__handle
index : 2
action: ext:quic_frame.crypto.handle
varname(actname): ext__quic_frame__crypto__handle
index : 3
action: ext:quic_frame.new_connection_id.handle_maliciously
varname(actname): ext__quic_frame__new_connection_id__handle_maliciously
index : 4
action: ext:quic_frame.path_response.handle
varname(actname): ext__quic_frame__path_response__handle
index : 5
action: ext:quic_frame.stream.handle
varname(actname): ext__quic_frame__stream__handle
index : 6
action: ext:tls_recv_event
varname(actname): ext__tls_recv_event
index : 7
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 349865
insertBreakpoint: name = "1"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 65865
scid_size_pos size: 8
dcid_size_cl size: 8
setup_initial_traffic_keys cid: 1
setup_initial_traffic_keys session: 0
setup_initial_traffic_keys
[56,118,44,247,245,89,52,179,77,23,154,230,164,200,12,173,204,187,127,10][0,0,0,0,0,0,1,45]setup_initial_traffic_keys 2 
setup_initial_traffic_keys 3
setup_initial_traffic_keys 4
setup_initial_traffic_keys 5
quic_set_key_from_secret 1
quic_set_key_from_secret 2v
quic_set_key_from_secret 3v
setup_initial_traffic_keys 6
quic_set_key_from_secret 1
quic_set_key_from_secret 2q
quic_set_key_from_secret 3q
setup_initial_traffic_keys salt: [56,118,44,247,245,89,52,179,77,23,154,230,164,200,12,173,204,187,127,10]
setup_initial_traffic_keys ikm: [0,0,0,0,0,0,1,45]
ser res init 0
ser res dcid 1 301
ser res dcid 2 301
ser res scid_h 1 3
ser res scid_h 1 3
open_list len 0
quic_ser open_tag 5
quic_ser open_tag 6
ser: quic_crypto_offset   = 0
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 1216
ser: PACKET_SIZE   = 1216
ser: PACKET_SIZE   = 1190
\x44
ser: PACKET_SIZE   = D
\xa6
ser: PACKET_SIZE   = 
iv_size start 
iv_size cid 1
iv_size level 0
iv_size end 16
encrypt_aead start l 0
encrypt_aead start c 1
encrypt_aead start seq 1
encrypt_aead aead 0x5555590f9240
encrypt_aead encrypted  1
encrypt_aead encrypted 1202
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [249,114,196,232,2,146,135,137,131,246,13,132,236,148,221,247]
encrypt_cipher pn_enc 0x555559116b20
encrypt_cipher end [251,110,6,96,54]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 351258
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 351283
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 351320
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 351405
sending from socket: 4
source port number 4991
destination sending to id: [[200,0,0,0,1,... (1232) ...,228,16,172,204,36]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 1232
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 1252
quic_prot_deser imp size 1252
quic_prot_deser imp size 1252
quic_prot_deser a long packet of type 0
pnum_pos (net_prot) 24
data_remaining (net_prot) 143
pyld_len (net_prot) 117
total_data_remaining (net_prot) 1109
quic_prot_deser imp size 1252
quic_prot_deser a long packet of type 32
data_remaining (net_prot) 1109
pyld_len (net_prot) 1084
total_data_remaining (net_prot) 0
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 0
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [72,224,137,123,44,27,169,102,130,32,186,5,209,166,27,128]
encrypt_cipher pn_dec 0x5555591ce5a0
encrypt_cipher end [186,42,163,254,128]
decrypt_aead start 
decrypt_aead ad 28
aead == 0x55555909f7a0
checksum_length == 16
seq == 0
bytes.size() == 28
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 3
sourceID size 8
sourceID res 9849898363486289715
sourceID token length 0
payload_length = 101
pkt_num res 0
recv frame_type = 2
recv frame_type = 6
quic_crypto_length 
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 356665
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 356754
[2,0,0,86,3,... (90) ...,189,76,129,100,8]
0

tag v1: 2
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "tls.client_hello"
value_size: 0
tag v2: 2
deser-open_field: name : "tls.server_hello"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 86
deser-open_field: name : "server_version"
4) pkt-pos =  90
deser-open_field: field_bytes : 2
deser-open_field: name : "rand_info"
4) pkt-pos =  90
deser-open_field: name : "gmt_unix_time"
4) pkt-pos =  90
deser-open_field: field_bytes : 4
deser-open_field: name : "random_bytes"
deser-open_field: name : "session_id"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 0
deser-open_field: tls_field_bytes_map["session_id"] : 0
deser-open_field: field_bytes : 1
deser-open_field: name : "the_cipher_suite"
4) pkt-pos =  90
deser-open_field: field_bytes : 2
deser-open_field: name : "the_compression_method"
4) pkt-pos =  90
deser-open_field: field_bytes : 1
deser-open_field: name : "extensions"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 46
tag v1: 43
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 43
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  90
deser-open_field: name : "etype"
4) pkt-pos =  90
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 2
deser-open_field: tls_field_bytes_map["content"] : 2
deser-open_field: field_bytes : 1
tag v1: 51
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 51
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  90
deser-open_field: name : "etype"
4) pkt-pos =  90
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 36
deser-open_field: tls_field_bytes_map["content"] : 36
deser-open_field: field_bytes : 1
lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
tls_on_extension_cb 
tls_on_extension_cb 43
tls_on_extension_cb 2

tls_on_extension_cb 
tls_on_extension_cb 51
tls_on_extension_cb 36

quic_update_traffic_key_cb 0x555555eb88c8
quic_update_traffic_key_cb SERVER_HANDSHAKE_TRAFFIC_SECRET
quic_set_key_from_secret 1
quic_set_key_from_secret 2v
quic_set_key_from_secret 3v
quic_update_traffic_key_cb 0x555555eb8920
quic_update_traffic_key_cb CLIENT_HANDSHAKE_TRAFFIC_SECRET
quic_set_key_from_secret 1
quic_set_key_from_secret 2q
quic_set_key_from_secret 3q
picotls_do_handshake ptls_handle_message end 
PICOTLS RETURNED PTLS_ERROR_IN_PROGRESS 514
PICOTLS handshake handle message finish
before clear
after clear
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 9414
insertBreakpoint: name = "2"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 75292
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 359426
iv_size start 
iv_size cid 1
iv_size level 2
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [154,70,188,79,71,124,249,142,74,75,73,201,123,104,217,143]
encrypt_cipher pn_dec 0x555555f04c40
encrypt_cipher end [236,223,200,81,191]
decrypt_aead start 
decrypt_aead ad 27
aead == 0x5555590f8e30
checksum_length == 16
seq == 0
bytes.size() == 27
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 3
sourceID size 8
sourceID res 9849898363486289715
sourceID token length 0
payload_length = 1068
pkt_num res 0
recv frame_type = 6
quic_crypto_length 
[8,0,0,130,0,... (1062) ...,69,122,159,188,21]
0

tag v1: 8
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "tls.client_hello"
value_size: 0
tag v2: 8
deser-open_field: name : "tls.encrypted_extensions"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 130
deser-open_field: name : "extensions"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 128
tag v1: 16
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 16
deser-open_field: name : "tls.unknown_extension"
4) pkt-pos =  134
deser-open_field: name : "etype"
4) pkt-pos =  134
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 13
deser-open_field: tls_field_bytes_map["content"] : 13
deser-open_field: field_bytes : 1
tag v1: 57
bytes v1: 2
is_unknow: 1
Tag size: 1
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 57
deser-open_field: name : "quic_transport_parameters"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 107
deser-open_field: name : "transport_parameters"
4) pkt-pos =  134
tag v1: 68
bytes v1: 1
is_unknow: 1
Tag size: 2
tags[0]: "original_destination_connection_id"
tag: 68
value_size: 13
value_size: 13
tag v2: 1050
deser-open_field: name : "unknown_transport_parameter"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 13
deser-open_field: name : "unknown"
4) pkt-pos =  43
deser-open_field: field_bytes : 13
tag v1: 5
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 5
deser-open_field: name : "initial_max_stream_data_bidi_local"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  49
deser-open_field: field_bytes : -1
tag v1: 6
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 6
deser-open_field: name : "initial_max_stream_data_bidi_remote"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  55
deser-open_field: field_bytes : -1
tag v1: 7
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 7
deser-open_field: name : "initial_max_stream_data_uni"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  61
deser-open_field: field_bytes : -1
tag v1: 4
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 4
deser-open_field: name : "initial_max_data"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  67
deser-open_field: field_bytes : -1
tag v1: 8
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 8
deser-open_field: name : "initial_max_stream_id_bidi"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 2
deser-open_field: name : "stream_id_16"
4) pkt-pos =  71
deser-open_field: field_bytes : -1
tag v1: 9
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 9
deser-open_field: name : "initial_max_stream_id_uni"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 2
deser-open_field: name : "stream_id_16"
4) pkt-pos =  75
deser-open_field: field_bytes : -1
tag v1: 1
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 1
deser-open_field: name : "max_idle_timeout"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 4
deser-open_field: name : "seconds_16"
4) pkt-pos =  81
deser-open_field: field_bytes : -1
tag v1: 3
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 3
deser-open_field: name : "max_packet_size"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 2
deser-open_field: name : "stream_pos_16"
4) pkt-pos =  85
deser-open_field: field_bytes : -1
tag v1: 11
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 11
deser-open_field: name : "max_ack_delay"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 1
deser-open_field: name : "exponent_8"
4) pkt-pos =  88
deser-open_field: field_bytes : -1
tag v1: 12
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 12
deser-open_field: name : "disable_active_migration"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 0
tag v1: 2
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 2
deser-open_field: name : "stateless_reset_token"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 16
deser-open_field: name : "data_8"
4) pkt-pos =  108
deser-open_field: field_bytes : 16
tag v1: 0
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
tag: 0
value_size: 8
value_size: 8
tag v2: 0
deser-open_field: name : "original_destination_connection_id"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 8
deser-open_field: name : "dcid"
4) pkt-pos =  118
deser-open_field: field_bytes : 8
tag v1: 14
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
value_size: 0
tag v2: 14
deser-open_field: name : "active_connection_id_limit"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 1
deser-open_field: name : "stream_pos_32"
4) pkt-pos =  121
deser-open_field: field_bytes : -1
tag v1: 15
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "original_destination_connection_id"
tag: 15
value_size: 8
value_size: 8
tag v2: 15
deser-open_field: name : "initial_source_connection_id"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 8
deser-open_field: name : "scid"
4) pkt-pos =  131
deser-open_field: field_bytes : 8
tag v1: 32
bytes v1: 1
is_unknow: 1
Tag size: 1
tags[0]: "original_destination_connection_id"
tag: 32
value_size: 1
value_size: 1
tag v2: 32
deser-open_field: name : "unknown_transport_parameter"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 1
deser-open_field: name : "unknown"
4) pkt-pos =  134
deser-open_field: field_bytes : 1
tag v1: 11
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "tls.client_hello"
value_size: 0
tag v2: 11
deser-open_field: name : "tls.unknown_message"
deser-open_field: name : "mtype"
deser-open_field: field_bytes : 1
deser-open_field: name : "unknown_message_bytes"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 738
lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
tls_on_extension_cb 
tls_on_extension_cb 16
tls_on_extension_cb 13

tls_on_extension_cb 
tls_on_extension_cb 57
tls_on_extension_cb 107

tls_collect_extensions_cb 57
tls_collected_extensions_cb 57
picotls_do_handshake ptls_handle_message end 
PICOTLS RETURNED PTLS_ERROR_IN_PROGRESS 514
PICOTLS handshake handle message finish
before clear
after clear
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 20045
insertBreakpoint: name = "3"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 95352
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 379502
RECEIVING finsh
Performance recv packet measurement: 24 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 162
quic_prot_deser imp size 162
quic_prot_deser imp size 162
quic_prot_deser a long packet of type 32
data_remaining (net_prot) 162
pyld_len (net_prot) 137
total_data_remaining (net_prot) 0
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 2
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [86,62,55,211,111,89,223,91,75,155,173,66,132,106,71,227]
encrypt_cipher pn_dec 0x555555f04c40
encrypt_cipher end [236,30,223,69,248]
decrypt_aead start 
decrypt_aead ad 27
aead == 0x5555590f8e30
checksum_length == 16
seq == 1
bytes.size() == 27
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 3
sourceID size 8
sourceID res 9849898363486289715
sourceID token length 0
payload_length = 121
pkt_num res 1
recv frame_type = 6
quic_crypto_length 
[15,0,1,4,8,... (300) ...,65,25,225,10,103]
0

tag v1: 15
bytes v1: 1
is_unknow: 0
Tag size: 1
tags[0]: "tls.client_hello"
value_size: 0
tag v2: 15
deser-open_field: name : "tls.unknown_message"
deser-open_field: name : "mtype"
deser-open_field: field_bytes : 1
deser-open_field: name : "unknown_message_bytes"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 260
tag v1: 20
bytes v1: 1
is_unknow: 1
Tag size: 1
tags[0]: "tls.client_hello"
value_size: 0
tag v2: 20
deser-open_field: name : "tls.finished"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 32
deser-open_field: name : "mtype"
4) pkt-pos =  300
deser-open_field: field_bytes : 1
deser-open_field: name : "unknown_message_bytes"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 13483185
lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
quic_update_traffic_key_cb 0x555555eb88c8
quic_update_traffic_key_cb SERVER_TRAFFIC_SECRET_0
quic_set_key_from_secret 1
quic_set_key_from_secret 2v
quic_set_key_from_secret 3v
quic_update_traffic_key_cb 0x555555eb8920
quic_update_traffic_key_cb CLIENT_TRAFFIC_SECRET_0
quic_set_key_from_secret 1
quic_set_key_from_secret 2q
quic_set_key_from_secret 3q
picotls_do_handshake ptls_handle_message end 
[20,0,0,32,72,... (36) ...,43,31,30,94,137]
0

tag v1: 20
bytes v1: 1
is_unknow: 1
Tag size: 1
tags[0]: "tls.client_hello"
value_size: 0
tag v2: 20
deser-open_field: name : "tls.finished"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 32
deser-open_field: name : "mtype"
4) pkt-pos =  36
deser-open_field: field_bytes : 1
deser-open_field: name : "unknown_message_bytes"
deser-open_field: length_field_bytes : 3
deser-open_field: flength : 7003682
PICOTLS handshake handle message finish
before clear
after clear
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 79428
insertBreakpoint: name = "4"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 174807
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 458946
RECEIVING finsh
Performance recv packet measurement: 5 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 1252
quic_prot_deser imp size 1252
quic_prot_deser imp size 1252
quic_prot_deser a long packet of type 0
pnum_pos (net_prot) 24
data_remaining (net_prot) 1252
pyld_len (net_prot) 1226
total_data_remaining (net_prot) 0
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 0
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [214,26,149,102,11,170,162,209,189,225,166,63,74,59,245,172]
encrypt_cipher pn_dec 0x5555591ce5a0
encrypt_cipher end [175,198,65,228,31]
decrypt_aead start 
decrypt_aead ad 28
aead == 0x55555909f7a0
checksum_length == 16
seq == 1
bytes.size() == 28
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 3
sourceID size 8
sourceID res 9849898363486289715
sourceID token length 0
payload_length = 1210
pkt_num res 1
recv frame_type = 6
quic_crypto_length 
[]
0

lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
picotls_do_handshake ptls_handle_message end 
PICOTLS RETURNED PTLS_ERROR_IN_PROGRESS 514
PICOTLS handshake handle message finish
before clear
after clear
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 131163
insertBreakpoint: name = "5"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 305997
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 590233
RECEIVING finsh
Performance recv packet measurement: 5 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 1252
quic_prot_deser imp size 1252
quic_prot_deser imp size 1252
quic_prot_deser a long packet of type 0
pnum_pos (net_prot) 24
data_remaining (net_prot) 1252
pyld_len (net_prot) 1226
total_data_remaining (net_prot) 0
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 0
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [102,136,132,10,121,98,78,176,172,64,16,173,115,26,66,159]
encrypt_cipher pn_dec 0x5555591ce5a0
encrypt_cipher end [117,112,217,62,187]
decrypt_aead start 
decrypt_aead ad 28
aead == 0x55555909f7a0
checksum_length == 16
seq == 2
bytes.size() == 28
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 3
sourceID size 8
sourceID res 9849898363486289715
sourceID token length 0
payload_length = 1210
pkt_num res 2
recv frame_type = 6
quic_crypto_length 
[]
0

lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
picotls_do_handshake ptls_handle_message end 
PICOTLS RETURNED PTLS_ERROR_IN_PROGRESS 514
PICOTLS handshake handle message finish
before clear
after clear
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 5080
insertBreakpoint: name = "6"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 311096
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 595274
RECEIVING finsh
Performance recv packet measurement: 5 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 238014
insertBreakpoint: name = "7"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 549124
ser res init 2
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 3
ser res scid_h 1 3
quic_ser open_tag 5
quic_ser open_tag 6
ser: quic_crypto_offset   = 0
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 84
ser: PACKET_SIZE   = 84
ser: PACKET_SIZE   = 59
\x40
ser: PACKET_SIZE   = @
\x3b
ser: PACKET_SIZE   = ;
iv_size start 
iv_size cid 1
iv_size level 2
iv_size end 16
encrypt_aead start l 2
encrypt_aead start c 1
encrypt_aead start seq 1
encrypt_aead aead 0x5555590f89b0
encrypt_aead encrypted  1
encrypt_aead encrypted 71
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [224,137,67,109,143,177,177,225,219,123,109,50,28,252,231,152]
encrypt_cipher pn_enc 0x555558efd4b0
encrypt_cipher end [93,136,243,132,3]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 833662
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 833678
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 833698
sending from socket: 4
source port number 4991
destination sending to id: [[238,0,0,0,1,... (100) ...,38,105,22,149,5]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 100
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 1114
quic_prot_deser imp size 1114
quic_prot_deser imp size 1114
quic_prot_deser a long packet of type 32
data_remaining (net_prot) 1114
pyld_len (net_prot) 1089
total_data_remaining (net_prot) 0
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 2
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [91,112,192,3,162,241,44,60,11,203,187,103,103,49,152,110]
encrypt_cipher pn_dec 0x555555f04c40
encrypt_cipher end [78,241,62,206,240]
decrypt_aead start 
decrypt_aead ad 27
aead == 0x5555590f8e30
checksum_length == 16
seq == 2
bytes.size() == 27
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 3
sourceID size 8
sourceID res 9849898363486289715
sourceID token length 0
payload_length = 1073
pkt_num res 2
recv frame_type = 2
recv frame_type = 6
quic_crypto_length 
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 906596
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 906643
[]
0

lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
picotls_do_handshake ptls_handle_message end 
PICOTLS RETURNED PTLS_ERROR_IN_PROGRESS 514
PICOTLS handshake handle message finish
before clear
after clear
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 82786
insertBreakpoint: name = "8"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 631922
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 916059
RECEIVING finsh
Performance recv packet measurement: 10 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 162
quic_prot_deser imp size 162
quic_prot_deser imp size 162
quic_prot_deser a long packet of type 32
data_remaining (net_prot) 162
pyld_len (net_prot) 137
total_data_remaining (net_prot) 0
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 2
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [148,108,176,162,147,48,73,152,248,221,27,231,189,191,51,7]
encrypt_cipher pn_dec 0x555555f04c40
encrypt_cipher end [45,98,55,119,216]
decrypt_aead start 
decrypt_aead ad 27
aead == 0x5555590f8e30
checksum_length == 16
seq == 3
bytes.size() == 27
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 3
sourceID size 8
sourceID res 9849898363486289715
sourceID token length 0
payload_length = 121
pkt_num res 3
recv frame_type = 6
quic_crypto_length 
[]
0

lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
picotls_do_handshake ptls_handle_message end 
PICOTLS RETURNED PTLS_ERROR_IN_PROGRESS 514
PICOTLS handshake handle message finish
before clear
after clear
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 65258
insertBreakpoint: name = "9"
insertBreakpoint: now.tv_sec = 1724516948
insertBreakpoint: now.tv_usec = 697190
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 981299
RECEIVING finsh
Performance recv packet measurement: 3 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 1352
quic_prot_deser imp size 1352
quic_prot_deser imp size 1352
quic_prot_deser a short packet
data_remaining (net_prot) 1352
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [196,201,148,234,86,237,119,233,28,23,236,26,205,253,156,242]
encrypt_cipher pn_dec 0x55555943d100
encrypt_cipher end [201,14,56,116,122]
decrypt_aead start 
decrypt_aead ad 11
aead == 0x5555592d1340
checksum_length == 16
seq == 0
bytes.size() == 11
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 3
sourceID res 0
sourceID token length 0
payload_length = 0
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 282
quic_prot_deser imp size 282
quic_prot_deser imp size 282
quic_prot_deser a short packet
data_remaining (net_prot) 282
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [130,196,252,151,12,193,147,107,40,76,137,133,204,125,63,11]
encrypt_cipher pn_dec 0x55555943d100
encrypt_cipher end [15,126,175,99,118]
decrypt_aead start 
decrypt_aead ad 11
aead == 0x5555592d1340
checksum_length == 16
seq == 1
bytes.size() == 11
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 3
sourceID res 0
sourceID token length 0
payload_length = 0
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 367110
insertBreakpoint: name = "10"
insertBreakpoint: now.tv_sec = 1724516949
insertBreakpoint: now.tv_usec = 64313
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 10
ser res scid_h 1 10
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 60
ser: PACKET_SIZE   = 60
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 1
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [137,245,246,40,224,194,175,86,140,119,81,202,113,10,97,102]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [135,209,59,88,247]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 1348739
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 1348754
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 1348778
sending from socket: 4
source port number 4991
destination sending to id: [[68,136,177,222,95,... (76) ...,38,179,98,219,14]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 76
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 282
quic_prot_deser imp size 282
quic_prot_deser imp size 282
quic_prot_deser a short packet
data_remaining (net_prot) 282
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [16,116,12,98,238,137,252,222,170,230,47,161,73,153,79,246]
encrypt_cipher pn_dec 0x55555943d100
encrypt_cipher end [181,87,93,153,140]
decrypt_aead start 
decrypt_aead ad 11
aead == 0x5555592d1340
checksum_length == 16
seq == 3
bytes.size() == 11
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 3
sourceID res 0
sourceID token length 0
payload_length = 0
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 282
quic_prot_deser imp size 282
quic_prot_deser imp size 282
quic_prot_deser a short packet
data_remaining (net_prot) 282
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [219,146,81,11,157,93,193,63,76,196,49,53,195,246,129,18]
encrypt_cipher pn_dec 0x55555943d100
encrypt_cipher end [181,250,179,37,178]
decrypt_aead start 
decrypt_aead ad 11
aead == 0x5555592d1340
checksum_length == 16
seq == 4
bytes.size() == 11
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 3
sourceID res 0
sourceID token length 0
payload_length = 0
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 34
quic_prot_deser imp size 34
quic_prot_deser imp size 34
quic_prot_deser a short packet
data_remaining (net_prot) 34
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 405275
insertBreakpoint: name = "11"
insertBreakpoint: now.tv_sec = 1724516949
insertBreakpoint: now.tv_usec = 469624
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 11
ser res scid_h 1 11
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 60
ser: PACKET_SIZE   = 60
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 2
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [138,7,179,76,192,31,9,145,185,219,219,210,99,18,114,142]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [43,150,109,17,102]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 1754975
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 1755020
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 1755101
sending from socket: 4
source port number 4991
destination sending to id: [[72,136,177,222,95,... (76) ...,49,169,123,254,32]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 76
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 282
quic_prot_deser imp size 282
quic_prot_deser imp size 282
quic_prot_deser a short packet
data_remaining (net_prot) 282
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 29
quic_prot_deser imp size 29
quic_prot_deser imp size 29
quic_prot_deser a short packet
data_remaining (net_prot) 29
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 33
quic_prot_deser imp size 33
quic_prot_deser imp size 33
quic_prot_deser a short packet
data_remaining (net_prot) 33
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 687261
insertBreakpoint: name = "12"
insertBreakpoint: now.tv_sec = 1724516950
insertBreakpoint: now.tv_usec = 156907
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 10
ser res scid_h 1 10
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 91
ser: PACKET_SIZE   = 91
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 3
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 94
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [24,133,21,66,221,167,248,83,221,200,238,242,27,219,71,232]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [4,251,47,234,57]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2441370
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2441387
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2441412
sending from socket: 4
source port number 4991
destination sending to id: [[71,136,177,222,95,... (107) ...,75,46,19,211,217]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 107
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 33
quic_prot_deser imp size 33
quic_prot_deser imp size 33
quic_prot_deser a short packet
data_remaining (net_prot) 33
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 282
quic_prot_deser imp size 282
quic_prot_deser imp size 282
quic_prot_deser a short packet
data_remaining (net_prot) 282
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 29
quic_prot_deser imp size 29
quic_prot_deser imp size 29
quic_prot_deser a short packet
data_remaining (net_prot) 29
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 795248
insertBreakpoint: name = "13"
insertBreakpoint: now.tv_sec = 1724516950
insertBreakpoint: now.tv_usec = 952174
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 11
ser res scid_h 1 11
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 91
ser: PACKET_SIZE   = 91
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 4
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 94
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [100,244,251,31,192,13,201,249,150,221,76,77,198,125,43,154]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [11,234,103,142,3]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3236634
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3236651
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3236678
sending from socket: 4
source port number 4991
destination sending to id: [[72,136,177,222,95,... (107) ...,189,49,54,248,173]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 107
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 31
quic_prot_deser imp size 31
quic_prot_deser imp size 31
quic_prot_deser a short packet
data_remaining (net_prot) 31
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 507308
insertBreakpoint: name = "14"
insertBreakpoint: now.tv_sec = 1724516951
insertBreakpoint: now.tv_usec = 459506
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 10
ser res scid_h 1 10
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 91
ser: PACKET_SIZE   = 91
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 5
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 94
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [21,188,189,15,96,49,14,155,221,27,203,213,49,115,89,60]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [211,28,132,129,18]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3744357
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3744389
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3744446
sending from socket: 4
source port number 4991
destination sending to id: [[80,136,177,222,95,... (107) ...,155,126,101,112,233]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 107
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 31
quic_prot_deser imp size 31
quic_prot_deser imp size 31
quic_prot_deser a short packet
data_remaining (net_prot) 31
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 459307
insertBreakpoint: name = "15"
insertBreakpoint: now.tv_sec = 1724516951
insertBreakpoint: now.tv_usec = 918830
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 11
ser res scid_h 1 11
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 60
ser: PACKET_SIZE   = 60
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 6
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [5,132,85,249,54,143,210,35,110,252,171,67,27,183,207,67]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [58,123,236,123,194]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 4203403
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 4203423
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 4203460
sending from socket: 4
source port number 4991
destination sending to id: [[89,136,177,222,95,... (76) ...,103,210,116,146,4]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 76
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 31
quic_prot_deser imp size 31
quic_prot_deser imp size 31
quic_prot_deser a short packet
data_remaining (net_prot) 31
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 808756
insertBreakpoint: name = "16"
insertBreakpoint: now.tv_sec = 1724516952
insertBreakpoint: now.tv_usec = 727608
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 11
ser res scid_h 1 11
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 60
ser: PACKET_SIZE   = 60
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 7
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [226,40,105,116,68,249,23,58,151,0,247,126,28,169,130,27]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [229,234,123,55,138]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5012406
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5012432
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5012494
sending from socket: 4
source port number 4991
destination sending to id: [[70,136,177,222,95,... (76) ...,0,179,248,201,0]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 76
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 524156
insertBreakpoint: name = "17"
insertBreakpoint: now.tv_sec = 1724516953
insertBreakpoint: now.tv_usec = 251784
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 11
ser res scid_h 1 11
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 60
ser: PACKET_SIZE   = 60
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 8
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [181,246,16,94,109,207,197,100,3,201,161,159,167,16,164,183]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [194,5,150,21,7]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5536354
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5536382
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5536431
sending from socket: 4
source port number 4991
destination sending to id: [[65,136,177,222,95,... (76) ...,41,5,181,47,20]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 76
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 31
quic_prot_deser imp size 31
quic_prot_deser imp size 31
quic_prot_deser a short packet
data_remaining (net_prot) 31
RECEIVING from 10.0.0.3:4443
veth_ivy
handle RECEIVED 4
iv_size start 
iv_size cid 0
iv_size level 3
cid 0 not found in cid_map
RECEIVING finsh
Performance recv packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 493200
insertBreakpoint: name = "18"
insertBreakpoint: now.tv_sec = 1724516953
insertBreakpoint: now.tv_usec = 745004
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 11
ser res scid_h 1 11
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 91
ser: PACKET_SIZE   = 91
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 9
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 94
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [135,99,98,14,184,159,250,98,204,170,69,215,254,126,41,186]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [56,40,10,13,195]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 6029477
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 6029493
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 6029543
sending from socket: 4
source port number 4991
destination sending to id: [[91,136,177,222,95,... (107) ...,14,144,3,11,137]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 107
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 482187
insertBreakpoint: name = "19"
insertBreakpoint: now.tv_sec = 1724516954
insertBreakpoint: now.tv_usec = 227207
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 10
ser res scid_h 1 10
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 60
ser: PACKET_SIZE   = 60
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 10
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [254,93,160,157,33,226,77,121,54,111,242,191,150,73,39,178]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [41,248,136,87,63]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 6511696
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 6511713
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 6511756
sending from socket: 4
source port number 4991
destination sending to id: [[74,136,177,222,95,... (76) ...,34,108,186,7,184]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 76
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
C time = now_micros_last_bp
C time = now_micros_last_bp - timer started
C time = now_micros_last_bp - time = 1050748
insertBreakpoint: name = "20"
insertBreakpoint: now.tv_sec = 1724516955
insertBreakpoint: now.tv_usec = 277972
ser res init 3
ser res dcid 1 9849898363486289715
ser res dcid 2 9849898363486289715
ser res scid_h 1 11
ser res scid_h 1 11
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
quic_ser open_tag 16
quic_ser open_tag 24
ser: NOT_COALESCED_PACKET   = 
ser: PACKET_SIZE   = 308
ser: PACKET_SIZE   = 308
iv_size start 
iv_size cid 1
iv_size level 3
iv_size end 16
encrypt_aead start l 3
encrypt_aead start c 1
encrypt_aead start seq 11
encrypt_aead aead 0x555556fd5fa0
encrypt_aead encrypted  1
encrypt_aead encrypted 311
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [112,173,41,65,65,66,95,87,3,205,140,230,41,21,155,180]
encrypt_cipher pn_enc 0x5555592d03b0
encrypt_cipher end [248,214,114,235,4]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 7562583
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 7562601
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 7562649
sending from socket: 4
source port number 4991
destination sending to id: [[91,136,177,222,95,... (324) ...,108,97,56,0,50]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4991
not eavesdrop
sending id start
sr.res.size() 324
dstaddr.sin_addr.s_addr 50331658
dstaddr.sin_port 23313
socket 4
sending id finish
Performance sending packet measurement: 0 milliseconds
