
ARG__ 
0x1
721

ARG__ 
0xb
770

ARG__ 
2
2

ARG__ 
201
201
"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 = 1724486867
insertBreakpoint: now.tv_usec = 52455
insertBreakpoint: name false = "0"
insertBreakpoint: end_ms false = 52455
insertBreakpoint: breakpoints_ms.count(name) false = 1
open SOCKET 4
open SOCKET addr 167772162
open SOCKET htonl(addr.addr) 33554442
open SOCKET port 4989
open SOCKET dev veth_ivy
source port number 0
binding client id: client addr: 0 port: 4989
source port number 4989
installing reader 2
between random: 203073680
between random: 203073680
between random: 39164
between random: 39164
create cid = 1
is_server  = 0
PROUT 3 
PROUT 4 
TEST_ALPN hq-27
INITIAL_VERSION 27
INITIAL_VERSION 27
tls_tags_map[quic_transport_parameters] 65445
etype =
ser-open_tag: bytes : 2
ser-open_tag: tag : 65445
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 =65445
len =54
ptls_exts[i].type = 65445
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,215,3,... (219) ...,0,152,252,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 : 215
deser-open_field: name : "client_version"
4) pkt-pos =  219
deser-open_field: field_bytes : 2
deser-open_field: name : "rand_info"
4) pkt-pos =  219
deser-open_field: name : "gmt_unix_time"
4) pkt-pos =  219
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 : 168
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 =  219
deser-open_field: name : "etype"
4) pkt-pos =  219
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 =  219
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 =  219
deser-open_field: name : "etype"
4) pkt-pos =  219
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 =  219
deser-open_field: name : "etype"
4) pkt-pos =  219
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 8
deser-open_field: tls_field_bytes_map["content"] : 8
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 =  219
deser-open_field: name : "etype"
4) pkt-pos =  219
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 =  219
deser-open_field: name : "etype"
4) pkt-pos =  219
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 =  219
deser-open_field: name : "etype"
4) pkt-pos =  219
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: 65445
bytes v1: 2
is_unknow: 1
Tag size: 4
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 65445
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 =  219
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 =  169
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 =  175
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 =  181
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 =  185
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 =  191
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 =  201
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 =  207
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 =  211
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 =  217
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 = 479262
insertBreakpoint: name = "1"
insertBreakpoint: now.tv_sec = 1724486867
insertBreakpoint: now.tv_usec = 531732
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
[195,238,247,18,199,46,187,90,17,167,210,67,43,180,99,101,190,249,245,2][0,0,0,0,0,0,0,201]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: [195,238,247,18,199,46,187,90,17,167,210,67,43,180,99,101,190,249,245,2]
setup_initial_traffic_keys ikm: [0,0,0,0,0,0,0,201]
ser res init 0
ser res dcid 1 201
ser res dcid 2 201
ser res scid_h 1 2
ser res scid_h 1 2
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 0x555559886550
encrypt_aead encrypted  1
encrypt_aead encrypted 1202
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [128,27,199,195,97,45,175,208,210,150,0,110,140,68,85,231]
encrypt_cipher pn_enc 0x55555995e7a0
encrypt_cipher end [195,73,88,3,234]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 480471
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 480494
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 480527
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 480607
sending from socket: 4
source port number 4989
destination sending to id: [[192,255,0,0,27,... (1232) ...,192,201,151,243,69]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
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) 145
pyld_len (net_prot) 119
total_data_remaining (net_prot) 1107
quic_prot_deser imp size 1252
quic_prot_deser a long packet of type 32
data_remaining (net_prot) 1107
pyld_len (net_prot) 1082
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 [157,77,44,144,55,31,150,18,127,100,171,73,0,237,167,96]
encrypt_cipher pn_dec 0x5555595eb690
encrypt_cipher end [222,40,177,129,176]
decrypt_aead start 
decrypt_aead ad 30
aead == 0x555559972590
checksum_length == 16
seq == 0
bytes.size() == 30
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 2
sourceID size 8
sourceID res 8602196728938538197
sourceID token length 0
payload_length = 103
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 = 486238
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 486287
[2,0,0,86,3,... (90) ...,81,78,216,117,22]
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 = 8284
insertBreakpoint: name = "2"
insertBreakpoint: now.tv_sec = 1724486867
insertBreakpoint: now.tv_usec = 540025
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 487641
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 [234,251,13,117,220,139,128,6,20,48,31,90,67,97,109,101]
encrypt_cipher pn_dec 0x555555f04c40
encrypt_cipher end [196,61,148,190,103]
decrypt_aead start 
decrypt_aead ad 29
aead == 0x555559472f50
checksum_length == 16
seq == 0
bytes.size() == 29
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 2
sourceID size 8
sourceID res 8602196728938538197
sourceID token length 0
payload_length = 1066
pkt_num res 0
recv frame_type = 6
quic_crypto_length 
[8,0,0,110,0,... (1058) ...,117,12,209,101,245]
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 : 110
deser-open_field: name : "extensions"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 108
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 =  114
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 =  114
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 =  114
deser-open_field: name : "etype"
4) pkt-pos =  114
deser-open_field: field_bytes : 2
deser-open_field: name : "content"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 8
deser-open_field: tls_field_bytes_map["content"] : 8
deser-open_field: field_bytes : 1
tag v1: 65445
bytes v1: 2
is_unknow: 1
Tag size: 4
tags[0]: "tls.unknown_extension"
value_size: 0
tag v2: 65445
deser-open_field: name : "quic_transport_parameters"
deser-open_field: length_field_bytes : 2
deser-open_field: flength : 88
deser-open_field: name : "transport_parameters"
4) pkt-pos =  114
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 =  32
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 =  38
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 =  42
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 : 2
deser-open_field: name : "seconds_16"
4) pkt-pos =  46
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 =  50
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 =  54
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 =  60
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 =  66
deser-open_field: field_bytes : -1
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 =  69
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 =  72
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 =  82
deser-open_field: field_bytes : 8
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 =  92
deser-open_field: field_bytes : 8
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 =  110
deser-open_field: field_bytes : 16
tag v1: 80
bytes v1: 1
is_unknow: 1
Tag size: 2
tags[0]: "original_destination_connection_id"
tag: 80
value_size: 1
value_size: 1
tag v2: 4183
deser-open_field: name : "loss_bits"
deser-open_field: length_field_bytes : 1
deser-open_field: flength : 1
deser-open_field: name : "unknown"
4) pkt-pos =  114
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 : 824
lower.recv c 1
lower.recv s 0x555555ecd480
ptls_is_psk_handshake= 0
tls_on_extension_cb 
tls_on_extension_cb 0
tls_on_extension_cb 0

tls_on_extension_cb 
tls_on_extension_cb 16
tls_on_extension_cb 8

tls_on_extension_cb 
tls_on_extension_cb 65445
tls_on_extension_cb 88

tls_collect_extensions_cb 65445
tls_collected_extensions_cb 65445
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 = 9982
insertBreakpoint: name = "3"
insertBreakpoint: now.tv_sec = 1724486867
insertBreakpoint: now.tv_usec = 550019
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 497645
RECEIVING finsh
Performance recv packet measurement: 12 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 312
quic_prot_deser imp size 312
quic_prot_deser imp size 312
quic_prot_deser a long packet of type 32
data_remaining (net_prot) 234
pyld_len (net_prot) 209
total_data_remaining (net_prot) 78
quic_prot_deser imp size 312
quic_prot_deser a short packet
data_remaining (net_prot) 78
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 [184,156,171,226,36,144,119,215,189,177,181,210,100,74,17,206]
encrypt_cipher pn_dec 0x555555f04c40
encrypt_cipher end [47,48,107,0,98]
decrypt_aead start 
decrypt_aead ad 29
aead == 0x555559472f50
checksum_length == 16
seq == 1
bytes.size() == 29
decrypted <= input.size() == 1
decrypt_aead finish 
dstID size 8
dstID res 2
sourceID size 8
sourceID res 8602196728938538197
sourceID token length 0
payload_length = 193
pkt_num res 1
recv frame_type = 6
quic_crypto_length 
[15,0,1,4,8,... (300) ...,200,16,26,130,248]
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 : 8030822
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,175,... (36) ...,43,249,85,235,55]
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 : 1533943
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 = 8853
insertBreakpoint: name = "4"
insertBreakpoint: now.tv_sec = 1724486867
insertBreakpoint: now.tv_usec = 558881
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 506497
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 [149,194,183,145,230,171,55,147,70,234,69,35,110,104,73,62]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [226,254,248,226,32]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 0
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
sourceID res 0
sourceID token length 0
payload_length = 0
RECEIVING finsh
Performance recv packet measurement: 4 milliseconds
RECEIVING start on socket 4
RECEIVING __deser
quic_prot_deser size 1440
quic_prot_deser imp size 1440
quic_prot_deser imp size 1440
quic_prot_deser a short packet
data_remaining (net_prot) 1440
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 [101,222,255,164,155,161,165,180,70,0,181,38,160,52,219,209]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [127,171,3,85,126]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 1
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 = 1684154
insertBreakpoint: name = "5"
insertBreakpoint: now.tv_sec = 1724486869
insertBreakpoint: now.tv_usec = 243051
ser res init 2
ser res dcid 1 8602196728938538197
ser res dcid 2 8602196728938538197
ser res scid_h 1 2
ser res scid_h 1 2
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 0x5555596f0cb0
encrypt_aead encrypted  1
encrypt_aead encrypted 71
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [9,113,126,173,51,126,191,234,40,218,170,242,146,23,100,181]
encrypt_cipher pn_enc 0x55555995f8f0
encrypt_cipher end [236,53,22,169,106]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2191117
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2191132
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2191154
sending from socket: 4
source port number 4989
destination sending to id: [[239,255,0,0,27,... (100) ...,9,218,73,146,215]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
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 78
quic_prot_deser imp size 78
quic_prot_deser imp size 78
quic_prot_deser a short packet
data_remaining (net_prot) 78
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 [163,83,40,50,30,176,16,39,169,157,15,114,129,81,132,246]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [155,193,116,210,84]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 2
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 55
quic_prot_deser imp size 55
quic_prot_deser imp size 55
quic_prot_deser a short packet
data_remaining (net_prot) 55
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 [241,252,147,155,124,175,116,160,203,136,52,59,226,40,133,220]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [61,135,228,120,80]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 3
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 1400
quic_prot_deser imp size 1400
quic_prot_deser imp size 1400
quic_prot_deser a short packet
data_remaining (net_prot) 1400
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 [222,251,24,187,2,12,237,162,105,48,199,127,185,151,14,135]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [59,110,131,163,146]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 4
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 = 586036
insertBreakpoint: name = "6"
insertBreakpoint: now.tv_sec = 1724486869
insertBreakpoint: now.tv_usec = 829108
ser res init 3
ser res dcid 1 8602196728938538197
ser res dcid 2 8602196728938538197
ser res scid_h 1 12
ser res scid_h 1 12
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   = 153
ser: PACKET_SIZE   = 153
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 0x5555599b4a50
encrypt_aead encrypted  1
encrypt_aead encrypted 156
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [175,50,65,22,245,60,47,46,135,57,61,183,61,190,212,206]
encrypt_cipher pn_enc 0x555559446f80
encrypt_cipher end [4,65,234,207,124]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2777492
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2777519
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 2777557
sending from socket: 4
source port number 4989
destination sending to id: [[71,119,97,36,89,... (169) ...,34,85,220,235,223]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
not eavesdrop
sending id start
sr.res.size() 169
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 78
quic_prot_deser imp size 78
quic_prot_deser imp size 78
quic_prot_deser a short packet
data_remaining (net_prot) 78
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 [151,129,149,3,196,250,123,120,89,136,46,74,50,190,80,27]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [210,16,177,210,54]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 5
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 30
quic_prot_deser imp size 30
quic_prot_deser imp size 30
quic_prot_deser a short packet
data_remaining (net_prot) 30
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 [139,36,96,81,200,130,68,190,50,120,152,92,207,44,137,169]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [17,249,78,185,255]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 6
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 = 266936
insertBreakpoint: name = "7"
insertBreakpoint: now.tv_sec = 1724486870
insertBreakpoint: now.tv_usec = 96068
ser res init 3
ser res dcid 1 8602196728938538197
ser res dcid 2 8602196728938538197
ser res scid_h 1 12
ser res scid_h 1 12
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 0x5555599b4a50
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [1,67,141,120,100,181,166,199,241,199,46,117,172,167,216,69]
encrypt_cipher pn_enc 0x555559446f80
encrypt_cipher end [199,168,74,141,196]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3044448
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3044479
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3044527
sending from socket: 4
source port number 4989
destination sending to id: [[68,119,97,36,89,... (76) ...,79,138,134,10,49]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
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 30
quic_prot_deser imp size 30
quic_prot_deser imp size 30
quic_prot_deser a short packet
data_remaining (net_prot) 30
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 [15,158,44,59,70,242,218,26,242,213,201,122,48,172,110,96]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [131,130,8,36,36]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 7
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 = 294407
insertBreakpoint: name = "8"
insertBreakpoint: now.tv_sec = 1724486870
insertBreakpoint: now.tv_usec = 390491
ser res init 3
ser res dcid 1 8602196728938538197
ser res dcid 2 8602196728938538197
ser res scid_h 1 12
ser res scid_h 1 12
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 3
encrypt_aead aead 0x5555599b4a50
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [177,204,101,95,133,2,82,19,95,62,47,153,24,172,69,11]
encrypt_cipher pn_enc 0x555559446f80
encrypt_cipher end [20,226,45,54,15]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3338474
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3338503
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3338529
sending from socket: 4
source port number 4989
destination sending to id: [[87,119,97,36,89,... (76) ...,59,123,77,30,88]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
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 30
quic_prot_deser imp size 30
quic_prot_deser imp size 30
quic_prot_deser a short packet
data_remaining (net_prot) 30
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 [71,212,137,67,113,124,40,99,88,103,191,75,214,37,8,10]
encrypt_cipher pn_dec 0x555559446ce0
encrypt_cipher end [148,121,219,68,53]
decrypt_aead start 
decrypt_aead ad 10
aead == 0x55555969d570
checksum_length == 16
seq == 8
bytes.size() == 10
decrypted <= input.size() == 1
decrypt_aead finish 
dstID res 2
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 = 571510
insertBreakpoint: name = "9"
insertBreakpoint: now.tv_sec = 1724486870
insertBreakpoint: now.tv_usec = 962016
ser res init 3
ser res dcid 1 8602196728938538197
ser res dcid 2 8602196728938538197
ser res scid_h 1 12
ser res scid_h 1 12
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 4
encrypt_aead aead 0x5555599b4a50
encrypt_aead encrypted  1
encrypt_aead encrypted 63
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [164,192,253,10,201,133,19,94,38,242,92,200,75,239,71,52]
encrypt_cipher pn_enc 0x555559446f80
encrypt_cipher end [118,216,239,87,123]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3910129
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3910151
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 3910184
sending from socket: 4
source port number 4989
destination sending to id: [[85,119,97,36,89,... (76) ...,72,243,146,179,252]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
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 56
quic_prot_deser imp size 56
quic_prot_deser imp size 56
quic_prot_deser a short packet
data_remaining (net_prot) 56
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 = 993848
insertBreakpoint: name = "10"
insertBreakpoint: now.tv_sec = 1724486871
insertBreakpoint: now.tv_usec = 955946
ser res init 3
ser res dcid 1 8602196728938538197
ser res dcid 2 8602196728938538197
ser res scid_h 1 12
ser res scid_h 1 12
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   = 184
ser: PACKET_SIZE   = 184
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 0x5555599b4a50
encrypt_aead encrypted  1
encrypt_aead encrypted 187
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [112,160,89,76,217,93,111,85,219,202,238,146,83,102,210,196]
encrypt_cipher pn_enc 0x555559446f80
encrypt_cipher end [200,161,159,127,22]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 4905230
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 4905258
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 4905318
sending from socket: 4
source port number 4989
destination sending to id: [[75,119,97,36,89,... (200) ...,178,118,203,87,129]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
not eavesdrop
sending id start
sr.res.size() 200
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 159
quic_prot_deser imp size 159
quic_prot_deser imp size 159
quic_prot_deser a short packet
data_remaining (net_prot) 159
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 = 532764
insertBreakpoint: name = "11"
insertBreakpoint: now.tv_sec = 1724486872
insertBreakpoint: now.tv_usec = 488732
ser res init 3
ser res dcid 1 8602196728938538197
ser res dcid 2 8602196728938538197
ser res scid_h 1 12
ser res scid_h 1 12
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 6
encrypt_aead aead 0x5555599b4a50
encrypt_aead encrypted  1
encrypt_aead encrypted 94
encrypt_aead finish 
encrypt_cipher start 
encrypt_cipher c 1
encrypt_cipher iv [78,10,128,221,209,36,89,37,65,25,46,143,235,250,152,151]
encrypt_cipher pn_enc 0x555559446f80
encrypt_cipher end [125,247,71,84,120]
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5437033
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5437059
C time = now_micros
C time = now_micros - timer started
C time = now_micros - time = 5437120
sending from socket: 4
source port number 4989
destination sending to id: [[94,119,97,36,89,... (107) ...,137,173,197,37,194]]
destination sending to id: client addr: 167772163 port: 4443
source addr number 0
source port number 4989
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 48
quic_prot_deser imp size 48
quic_prot_deser imp size 48
quic_prot_deser a short packet
data_remaining (net_prot) 48
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
