{ "qlog_version": "draft-00", "title": "picoquic", "traces": [
{ "vantage_point": { "name": "backend-67", "type": "server" },
"title": "picoquic", "description": "74e17ce4b1efe27a","event_fields": ["relative_time", "path_id", "category", "event", "data"],
"configuration": {"time_units": "us"},
"common_fields": { "protocol_type": "QUIC_HTTP3", "reference_time": "1971528127559"},
"events": [
[0, 0, "transport", "datagram_received", { "byte_length": 1200, "addr_from" : {"ip_v4": "20.171.3.146", "port_v4":35284}, "addr_to" : {"ip_v4": "172.31.61.32", "port_v4":20753}}],
[0, 0, "transport", "packet_received", { "packet_type": "initial", "header": { "packet_size": 1200, "packet_number": 0, "version": "00000001", "payload_length": 1152, "scid": "95c9a3819082bf84", "dcid": "74e17ce4b1efe27a" }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 415}]}],
[173, 0, "transport", "parameters_set", {
    "owner": "remote",
    "sni": "test.privateoctopus.com",
    "proposed_alpn": ["h3", "h3-29"],
    "alpn": "h3"}],
[176, 0, "transport", "parameters_set", {
    "owner": "remote",
    "handshake_connection_id": "95c9a3819082bf84",
    "initial_max_stream_data_bidi_local" : 1048560,
    "initial_max_stream_data_bidi_remote" : 1048560,
    "initial_max_stream_data_uni" : 1048560,
    "initial_max_data" : 2097120,
    "initial_max_streams_bidi" : 100,
    "initial_max_streams_uni" : 100,
    "version_negotiation": {"chosen": "00000001", "others": ["6b3343cf","00000001"]},
    "grease_quic_bit" : "",
    "idle_timeout" : 30000,
    "active_connection_id_limit" : 7}],
[212, 0, "transport", "parameters_set", {
    "owner": "local",
    "initial_max_stream_data_bidi_local" : 2097152,
    "initial_max_data" : 1048576,
    "initial_max_streams_bidi" : 512,
    "idle_timeout" : 30000,
    "max_packet_size" : 1440,
    "initial_max_streams_uni" : 512,
    "initial_max_stream_data_bidi_remote" : 65635,
    "initial_max_stream_data_uni" : 65535,
    "active_connection_id_limit" : 8,
    "max_ack_delay" : 10,
    "handshake_connection_id": "28e639076bd467e8",
    "original_connection_id": "74e17ce4b1efe27a",
    "stateless_reset_token": "7ae0e881dd09bca0bb47764d1b42b4ab",
    "max_datagram_frame_size" : 1536,
    "grease_quic_bit" : "",
    "version_negotiation": {"chosen": "6b3343cf", "others": ["00000001","6b3343cf","709a50c4","ff000022","ff000021","ff000020","ff00001f","ff00001d","ff00001e","ff00001c","ff00001b","50435131","50435130"]},
    "address_discovery" : 0,
    "reset_stream_at" : "invalid"}],
[487, 0, "transport", "packet_sent", { "packet_type": "initial", "header": { "packet_size": 162, "packet_number": 120506, "version": "6b3343cf", "payload_length": 132, "scid": "28e639076bd467e8", "dcid": "95c9a3819082bf84", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 60, "acked_ranges": [[0, 0]]}, { 
    "frame_type": "crypto", "offset": 0, "length": 123}]}],
[487, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 1058, "packet_number": 0, "payload_length": 1029, "scid": "28e639076bd467e8", "dcid": "95c9a3819082bf84", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 1025}]}],
[487, 0, "transport", "datagram_sent", { "byte_length": 1252}],
[487, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 261, "packet_number": 1, "payload_length": 232, "scid": "28e639076bd467e8", "dcid": "95c9a3819082bf84", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 1025, "length": 227}]}],
[487, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 551, "packet_number": 0, "dcid": "95c9a3819082bf84", "key_phase": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 141}, { 
    "frame_type": "new_token", "new_token": "719d272e9270f7f0b1b8acc1aa6b39cfc7304e61cd2653ee386a0778de42275a0a557253474dd9cdb65892860dc70d904baa"}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "1544317fdd354b2e", "reset_token": "0ca9596622b7ebde366b4de5d5a78c1c"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "9c62a7ba9cd9e587", "reset_token": "e47ee10f210d941d10f4e426c824401b"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "82fad34df23dc8d0", "reset_token": "dcb7f87c0367153e195c8f327aeb80f5"}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "ebd3ddefc059f5e5", "reset_token": "b4c3efe26833d1d7754fd3781494001b"}, { 
    "frame_type": "new_connection_id", "sequence_number": 5, "retire_before": 0, "connection_id": "24f09a9ed738b274", "reset_token": "4c46d0c7253c6d5d269a63a853c3c1cf"}, { 
    "frame_type": "new_connection_id", "sequence_number": 6, "retire_before": 0, "connection_id": "72a751c08bf43fae", "reset_token": "00d37832ba7ba1e1c89dff26d664b4c4"}, { 
    "frame_type": "stream", "id": 3, "offset": 0, "length": 40, "fin": false , "begins_with": "0004250100068001"}, { 
    "frame_type": "stream", "id": 7, "offset": 0, "length": 1, "fin": false , "begins_with": "02"}, { 
    "frame_type": "stream", "id": 11, "offset": 0, "length": 1, "fin": false , "begins_with": "03"}, { 
    "frame_type": "padding"}]}],
[487, 0, "recovery", "metrics_updated", {"cwnd": 15360,"bytes_in_flight": 2096}],
[487, 0, "transport", "datagram_sent", { "byte_length": 844}],
[487, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 1424, "packet_number": 1, "dcid": "95c9a3819082bf84" }, "frames": [{ 
    "frame_type": "ping"}, { 
    "frame_type": "padding"}]}],
[487, 0, "recovery", "metrics_updated", {"bytes_in_flight": 3536}],
[487, 0, "transport", "datagram_sent", { "byte_length": 1440}],
[76470, 0, "transport", "datagram_received", { "byte_length": 1256}],
[76470, 0, "transport", "packet_received", { "packet_type": "initial", "header": { "packet_size": 1200, "packet_number": 1, "payload_length": 1152, "scid": "95c9a3819082bf84", "dcid": "28e639076bd467e8" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3258, "acked_ranges": [[120506, 120506]], "ect0": 1, "ect1": 0, "ce": 0}]}],
[76470, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 56, "packet_number": 0, "payload_length": 9, "scid": "95c9a3819082bf84", "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3258, "acked_ranges": [[0, 0]], "ect0": 1, "ect1": 0, "ce": 0}]}],
[186949, 0, "recovery", "packet_lost", {
    "packet_type" : "handshake",
    "packet_number" : 1,
    "trigger": "timer",
    "header": {
        "packet_type" : "handshake",
        "packet_number" : 1,
        "dcid" : "95c9a3819082bf84",
        "packet_size" : 261}}],
[186949, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 295, "packet_number": 2, "payload_length": 266, "scid": "28e639076bd467e8", "dcid": "95c9a3819082bf84", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 1025, "length": 227}, { 
    "frame_type": "ack", "ack_delay": 13809, "acked_ranges": [[0, 0]]}, { 
    "frame_type": "padding"}]}],
[186949, 0, "recovery", "metrics_updated", {"cwnd": 16580,"pacing_rate": 1361424,"bytes_in_flight": 2318,"smoothed_rtt": 75983,"min_rtt": 75983,"latest_rtt": 75983,"app_limited": 1}],
[186949, 0, "transport", "datagram_sent", { "byte_length": 311}],
[209490, 0, "transport", "datagram_received", { "byte_length": 1216}],
[209490, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 1216, "packet_number": 1, "payload_length": 1169, "scid": "95c9a3819082bf84", "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ping"}, { 
    "frame_type": "padding"}]}],
[209516, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 39, "packet_number": 3, "payload_length": 10, "scid": "28e639076bd467e8", "dcid": "95c9a3819082bf84", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3, "acked_ranges": [[0, 1]]}, { 
    "frame_type": "padding"}]}],
[209516, 0, "recovery", "metrics_updated", {"bytes_in_flight": 2373,"smoothed_rtt": 75983}],
[209516, 0, "transport", "datagram_sent", { "byte_length": 55}],
[237135, 0, "transport", "datagram_received", { "byte_length": 293, "ecn" : "ECT(0)"}],
[237135, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 57, "packet_number": 2, "payload_length": 10, "scid": "95c9a3819082bf84", "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 52, "acked_ranges": [[2, 2], [0, 0]], "ect0": 2, "ect1": 0, "ce": 0}]}],
[237135, 0, "transport", "packet_buffered", {
    "type" : "1RTT",
    "trigger": "keys_unavailable"}],
[237178, 0, "transport", "datagram_received", { "byte_length": 86, "ecn" : "Not-ECT"}],
[237178, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 86, "packet_number": 3, "payload_length": 39, "scid": "95c9a3819082bf84", "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 36}]}],
[237230, 0, "transport", "datagram_received", { "byte_length": 236, "ecn" : "ECT(0)"}],
[237230, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 236, "packet_number": 0, "dcid": "28e639076bd467e8", "key_phase": 0, "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 23323, "acked_ranges": [[1, 1]], "ect0": 1, "ect1": 0, "ce": 0}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "b1cea2e4b0f5be27", "reset_token": "9f3ab2338c652057f6b41178505c878f"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "e5c71c7ea30a1fcf", "reset_token": "e1ab86655fca89c9655d9497d94d8938"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "ebf55786b296a62d", "reset_token": "3ff66d3563ce808f185685615d9d91b2"}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "2e2a87a101482c07", "reset_token": "4e4702488c3a5b3c4ece88ce93022718"}, { 
    "frame_type": "new_connection_id", "sequence_number": 5, "retire_before": 0, "connection_id": "26ed6d5ec298c213", "reset_token": "8074afbd13240bf750d3c94a5a152d41"}, { 
    "frame_type": "new_connection_id", "sequence_number": 6, "retire_before": 0, "connection_id": "6b07383238d59d24", "reset_token": "2b0ff090f2977420af9a4e49a26b7d02"}, { 
    "frame_type": "new_connection_id", "sequence_number": 7, "retire_before": 0, "connection_id": "028f0af238329f64", "reset_token": "9fa7c33bd3f3be37c7494b51c6ae2704"}]}],
[237230, 0, "recovery", "metrics_updated", {"cwnd": 18338,"pacing_rate": 1361380,"bytes_in_flight": 567,"smoothed_rtt": 92006,"min_rtt": 50186,"latest_rtt": 226743}],
[237230, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 39, "packet_number": 2, "dcid": "95c9a3819082bf84" }, "frames": [{ 
    "frame_type": "handshake_done"}, { 
    "frame_type": "padding"}]}],
[237230, 0, "recovery", "metrics_updated", {"bytes_in_flight": 622,"smoothed_rtt": 92006}],
[237230, 0, "transport", "datagram_sent", { "byte_length": 55}],
[247379, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 39, "packet_number": 3, "dcid": "95c9a3819082bf84" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 1280, "acked_ranges": [[0, 0]], "ect0": 1, "ect1": 0, "ce": 0}, { 
    "frame_type": "padding"}]}],
[247379, 0, "recovery", "metrics_updated", {"bytes_in_flight": 677,"smoothed_rtt": 92006}],
[247379, 0, "transport", "datagram_sent", { "byte_length": 55}],
[267532, 0, "recovery", "packet_lost", {
    "packet_type" : "1RTT",
    "packet_number" : 0,
    "trigger": "repeat",
    "header": {
        "packet_type" : "1RTT",
        "packet_number" : 0,
        "dcid" : "95c9a3819082bf84",
        "packet_size" : 551}}],
[267532, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 423, "packet_number": 4, "dcid": "95c9a3819082bf84" }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 141}, { 
    "frame_type": "new_token", "new_token": "719d272e9270f7f0b1b8acc1aa6b39cfc7304e61cd2653ee386a0778de42275a0a557253474dd9cdb65892860dc70d904baa"}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "1544317fdd354b2e", "reset_token": "0ca9596622b7ebde366b4de5d5a78c1c"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "9c62a7ba9cd9e587", "reset_token": "e47ee10f210d941d10f4e426c824401b"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "82fad34df23dc8d0", "reset_token": "dcb7f87c0367153e195c8f327aeb80f5"}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "ebd3ddefc059f5e5", "reset_token": "b4c3efe26833d1d7754fd3781494001b"}, { 
    "frame_type": "new_connection_id", "sequence_number": 5, "retire_before": 0, "connection_id": "24f09a9ed738b274", "reset_token": "4c46d0c7253c6d5d269a63a853c3c1cf"}, { 
    "frame_type": "new_connection_id", "sequence_number": 6, "retire_before": 0, "connection_id": "72a751c08bf43fae", "reset_token": "00d37832ba7ba1e1c89dff26d664b4c4"}, { 
    "frame_type": "ack", "ack_delay": 3799, "acked_ranges": [[0, 0]], "ect0": 1, "ect1": 0, "ce": 0}, { 
    "frame_type": "padding"}]}],
[267532, 0, "recovery", "metrics_updated", {"bytes_in_flight": 549,"smoothed_rtt": 92006}],
[267532, 0, "transport", "datagram_sent", { "byte_length": 439}],
[267532, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 167, "packet_number": 5, "dcid": "95c9a3819082bf84", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "stream", "id": 3, "offset": 0, "length": 40, "fin": false , "begins_with": "0004250100068001"}, { 
    "frame_type": "stream", "id": 7, "offset": 0, "length": 1, "fin": false , "begins_with": "02"}, { 
    "frame_type": "stream", "id": 11, "offset": 0, "length": 1, "fin": false , "begins_with": "03"}, { 
    "frame_type": "padding"}]}],
[267532, 0, "recovery", "metrics_updated", {"bytes_in_flight": 732,"smoothed_rtt": 92006}],
[267532, 0, "transport", "datagram_sent", { "byte_length": 183}],
[286877, 0, "transport", "datagram_received", { "byte_length": 111}],
[286877, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 111, "packet_number": 1, "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 1, "acked_ranges": [[1, 2]], "ect0": 2, "ect1": 0, "ce": 0}, { 
    "frame_type": "stream", "id": 2, "offset": 0, "length": 18, "fin": false , "begins_with": "00040f06ffffffff"}, { 
    "frame_type": "stream", "id": 10, "offset": 0, "length": 1, "fin": false , "begins_with": "03"}, { 
    "frame_type": "stream", "id": 6, "offset": 0, "length": 1, "fin": false , "begins_with": "02"}, { 
    "frame_type": "stream", "id": 0, "offset": 0, "length": 42, "fin": true , "begins_with": "01280000d1d75090"}]}],
[286910, 0, "info", "message", { "message": "H3 control frame: 06ffffffffffffffff015000074064"}],
[286877, 0, "recovery", "metrics_updated", {"cwnd": 18377,"bytes_in_flight": 677,"smoothed_rtt": 86711,"min_rtt": 49647,"latest_rtt": 49647}],
[286934, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 551, "packet_number": 6, "dcid": "95c9a3819082bf84" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 7, "acked_ranges": [[0, 1]], "ect0": 2, "ect1": 0, "ce": 0}, { 
    "frame_type": "stream", "id": 0, "offset": 0, "length": 409, "fin": true , "begins_with": "0140110000d95f4d"}, { 
    "frame_type": "padding"}]}],
[286934, 0, "recovery", "metrics_updated", {"bytes_in_flight": 1244,"smoothed_rtt": 86711}],
[286934, 0, "transport", "datagram_sent", { "byte_length": 567}],
[317148, 0, "transport", "datagram_received", { "byte_length": 37}],
[317148, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 37, "packet_number": 2, "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 1, "acked_ranges": [[1, 4]], "ect0": 4, "ect1": 0, "ce": 0}]}],
[317148, 0, "recovery", "metrics_updated", {"cwnd": 18839,"bytes_in_flight": 750,"smoothed_rtt": 82074,"min_rtt": 49616,"latest_rtt": 49616}],
[336533, 0, "transport", "datagram_received", { "byte_length": 37}],
[336533, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 37, "packet_number": 3, "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3, "acked_ranges": [[1, 6]], "ect0": 6, "ect1": 0, "ce": 0}]}],
[336533, 0, "recovery", "metrics_updated", {"cwnd": 19557,"bytes_in_flight": 0,"smoothed_rtt": 78014,"min_rtt": 49599,"latest_rtt": 49599}],
[336574, 0, "transport", "datagram_received", { "byte_length": 32}],
[336574, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 32, "packet_number": 4, "dcid": "28e639076bd467e8", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "connection_close", "error_space": "application", "error_code": 0}]}],
[336578, 0, "info", "message", { "message": "Clearing context on connection close (6)"}],
[336583, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 14, "packet_number": 7, "dcid": "95c9a3819082bf84" }, "frames": [{ 
    "frame_type": "connection_close", "error_space": "transport", "error_code": 0}]}],
[336583, 0, "transport", "datagram_sent", { "byte_length": 30}],
[1011153, 0, "info", "message", { "message": "Closed. Retrans= 2, spurious= 0, max sp gap = 0, max sp delay = 0, dg-coal: 1.100000"}]]}]}
