{ "qlog_version": "draft-00", "title": "picoquic", "traces": [
{ "vantage_point": { "name": "backend-67", "type": "server" },
"title": "picoquic", "description": "2a0cd39265e031a9","event_fields": ["relative_time", "path_id", "category", "event", "data"],
"configuration": {"time_units": "us"},
"common_fields": { "protocol_type": "QUIC_HTTP3", "reference_time": "400567304827"},
"events": [
[0, 0, "transport", "datagram_received", { "byte_length": 1200, "addr_from" : {"ip_v4": "20.29.191.2", "port_v4":6940}, "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": "10fb30b751f0539e", "dcid": "2a0cd39265e031a9" }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 415}]}],
[221, 0, "transport", "parameters_set", {
    "owner": "remote",
    "sni": "test.privateoctopus.com",
    "proposed_alpn": ["h3", "h3-29"],
    "alpn": "h3"}],
[224, 0, "transport", "parameters_set", {
    "owner": "remote",
    "handshake_connection_id": "10fb30b751f0539e",
    "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}],
[248, 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": "b621b7b1b216e672",
    "original_connection_id": "2a0cd39265e031a9",
    "stateless_reset_token": "409c7f5a8de206fecd3ee59926486a47",
    "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"}],
[523, 0, "transport", "packet_sent", { "packet_type": "initial", "header": { "packet_size": 163, "packet_number": 82192, "version": "6b3343cf", "payload_length": 133, "scid": "b621b7b1b216e672", "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 65, "acked_ranges": [[0, 0]]}, { 
    "frame_type": "crypto", "offset": 0, "length": 123}]}],
[523, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 1057, "packet_number": 0, "payload_length": 1028, "scid": "b621b7b1b216e672", "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 1024}]}],
[523, 0, "transport", "datagram_sent", { "byte_length": 1252}],
[523, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 260, "packet_number": 1, "payload_length": 231, "scid": "b621b7b1b216e672", "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 1024, "length": 226}]}],
[523, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 551, "packet_number": 0, "dcid": "10fb30b751f0539e", "key_phase": 0, "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 141}, { 
    "frame_type": "new_token", "new_token": "67650649fba6072a60dd92c0f44acae49de13cbaeb74a78319ab510b2917b0916a34ec930830147f3068bdcc105a775a5c78"}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "8b8e4ab3e32a3296", "reset_token": "807b0d77e9176d0d16342c4904ba26ad"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "30406a4306822f37", "reset_token": "30fdff36a31b64598b82b4708a4e7d08"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "b611bf9336a952c0", "reset_token": "30294980627028ff6ee8bbbc81a8a859"}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "348d4096508110c1", "reset_token": "9d769d8430fdb8ba962f86f1f401fee1"}, { 
    "frame_type": "new_connection_id", "sequence_number": 5, "retire_before": 0, "connection_id": "8bfad5570a2f34ec", "reset_token": "72d302da76063afc904fb91ad3de2932"}, { 
    "frame_type": "new_connection_id", "sequence_number": 6, "retire_before": 0, "connection_id": "9f8905b911924d41", "reset_token": "14037b16a5b636fb2d9aab782f7f110d"}, { 
    "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"}]}],
[523, 0, "recovery", "metrics_updated", {"cwnd": 15360,"bytes_in_flight": 2095}],
[523, 0, "transport", "datagram_sent", { "byte_length": 843}],
[523, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 1424, "packet_number": 1, "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ping"}, { 
    "frame_type": "padding"}]}],
[523, 0, "recovery", "metrics_updated", {"bytes_in_flight": 3535}],
[523, 0, "transport", "datagram_sent", { "byte_length": 1440}],
[90225, 0, "transport", "datagram_received", { "byte_length": 1256}],
[90225, 0, "transport", "packet_received", { "packet_type": "initial", "header": { "packet_size": 1200, "packet_number": 1, "payload_length": 1152, "scid": "10fb30b751f0539e", "dcid": "b621b7b1b216e672" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3182, "acked_ranges": [[82192, 82192]], "ect0": 1, "ect1": 0, "ce": 0}]}],
[90225, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 56, "packet_number": 0, "payload_length": 9, "scid": "10fb30b751f0539e", "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3182, "acked_ranges": [[0, 0]], "ect0": 1, "ect1": 0, "ce": 0}]}],
[216444, 0, "recovery", "packet_lost", {
    "packet_type" : "handshake",
    "packet_number" : 1,
    "trigger": "timer",
    "header": {
        "packet_type" : "handshake",
        "packet_number" : 1,
        "dcid" : "10fb30b751f0539e",
        "packet_size" : 260}}],
[216444, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 295, "packet_number": 2, "payload_length": 266, "scid": "b621b7b1b216e672", "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 1024, "length": 226}, { 
    "frame_type": "ack", "ack_delay": 15777, "acked_ranges": [[0, 0]]}, { 
    "frame_type": "padding"}]}],
[216444, 0, "recovery", "metrics_updated", {"cwnd": 16580,"pacing_rate": 1361424,"bytes_in_flight": 2318,"smoothed_rtt": 89702,"min_rtt": 89702,"latest_rtt": 89702,"app_limited": 1}],
[216444, 0, "transport", "datagram_sent", { "byte_length": 311}],
[264219, 0, "transport", "datagram_received", { "byte_length": 1216}],
[264219, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 1216, "packet_number": 1, "payload_length": 1169, "scid": "10fb30b751f0539e", "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ping"}, { 
    "frame_type": "padding"}]}],
[264247, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 39, "packet_number": 3, "payload_length": 10, "scid": "b621b7b1b216e672", "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3, "acked_ranges": [[0, 1]]}, { 
    "frame_type": "padding"}]}],
[264247, 0, "recovery", "metrics_updated", {"bytes_in_flight": 2373,"smoothed_rtt": 89702}],
[264247, 0, "transport", "datagram_sent", { "byte_length": 55}],
[280931, 0, "transport", "datagram_received", { "byte_length": 293, "ecn" : "ECT(0)"}],
[280931, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 57, "packet_number": 2, "payload_length": 10, "scid": "10fb30b751f0539e", "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 62, "acked_ranges": [[2, 2], [0, 0]], "ect0": 2, "ect1": 0, "ce": 0}]}],
[280931, 0, "transport", "packet_buffered", {
    "type" : "1RTT",
    "trigger": "keys_unavailable"}],
[280966, 0, "transport", "datagram_received", { "byte_length": 86, "ecn" : "Not-ECT"}],
[280966, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 86, "packet_number": 3, "payload_length": 39, "scid": "10fb30b751f0539e", "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 36}]}],
[281013, 0, "transport", "datagram_received", { "byte_length": 236, "ecn" : "ECT(0)"}],
[281013, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 236, "packet_number": 0, "dcid": "b621b7b1b216e672", "key_phase": 0, "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 27023, "acked_ranges": [[1, 1]], "ect0": 1, "ect1": 0, "ce": 0}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "9d43feb3d1c8cd5c", "reset_token": "41cab1daf8cf3af160e367ac07ca9f48"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "1d5ef74f9ee9117d", "reset_token": "14c2c1d1b671019f63cf623829bc86a5"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "e870b65734a8f0ed", "reset_token": "cc24f8f8bfe50d9e054f31e468992825"}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "5d151677c31ef836", "reset_token": "836bb0dd6183109699989735a13bfb6b"}, { 
    "frame_type": "new_connection_id", "sequence_number": 5, "retire_before": 0, "connection_id": "cdf2eab5cfb89433", "reset_token": "731c60c134005d142154bea8d6a45d47"}, { 
    "frame_type": "new_connection_id", "sequence_number": 6, "retire_before": 0, "connection_id": "580961fd6ec399b0", "reset_token": "400368234e28c397a53efd50fc01acf1"}, { 
    "frame_type": "new_connection_id", "sequence_number": 7, "retire_before": 0, "connection_id": "dfc0bcf70ccc5ee9", "reset_token": "3f24308917a027084ca2c5b781fd7219"}]}],
[281013, 0, "recovery", "metrics_updated", {"cwnd": 18299,"pacing_rate": 1361380,"bytes_in_flight": 567,"smoothed_rtt": 109542,"min_rtt": 64487,"latest_rtt": 270490}],
[281013, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 39, "packet_number": 2, "dcid": "10fb30b751f0539e" }, "frames": [{ 
    "frame_type": "handshake_done"}, { 
    "frame_type": "padding"}]}],
[281013, 0, "recovery", "metrics_updated", {"bytes_in_flight": 622,"smoothed_rtt": 109542}],
[281013, 0, "transport", "datagram_sent", { "byte_length": 55}],
[291155, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 39, "packet_number": 3, "dcid": "10fb30b751f0539e" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 1278, "acked_ranges": [[0, 0]], "ect0": 1, "ect1": 0, "ce": 0}, { 
    "frame_type": "padding"}]}],
[291155, 0, "recovery", "metrics_updated", {"bytes_in_flight": 677,"smoothed_rtt": 109542}],
[291155, 0, "transport", "datagram_sent", { "byte_length": 55}],
[311297, 0, "recovery", "packet_lost", {
    "packet_type" : "1RTT",
    "packet_number" : 0,
    "trigger": "repeat",
    "header": {
        "packet_type" : "1RTT",
        "packet_number" : 0,
        "dcid" : "10fb30b751f0539e",
        "packet_size" : 551}}],
[311297, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 423, "packet_number": 4, "dcid": "10fb30b751f0539e" }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 141}, { 
    "frame_type": "new_token", "new_token": "67650649fba6072a60dd92c0f44acae49de13cbaeb74a78319ab510b2917b0916a34ec930830147f3068bdcc105a775a5c78"}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "8b8e4ab3e32a3296", "reset_token": "807b0d77e9176d0d16342c4904ba26ad"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "30406a4306822f37", "reset_token": "30fdff36a31b64598b82b4708a4e7d08"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "b611bf9336a952c0", "reset_token": "30294980627028ff6ee8bbbc81a8a859"}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "348d4096508110c1", "reset_token": "9d769d8430fdb8ba962f86f1f401fee1"}, { 
    "frame_type": "new_connection_id", "sequence_number": 5, "retire_before": 0, "connection_id": "8bfad5570a2f34ec", "reset_token": "72d302da76063afc904fb91ad3de2932"}, { 
    "frame_type": "new_connection_id", "sequence_number": 6, "retire_before": 0, "connection_id": "9f8905b911924d41", "reset_token": "14037b16a5b636fb2d9aab782f7f110d"}, { 
    "frame_type": "ack", "ack_delay": 3795, "acked_ranges": [[0, 0]], "ect0": 1, "ect1": 0, "ce": 0}, { 
    "frame_type": "padding"}]}],
[311297, 0, "recovery", "metrics_updated", {"bytes_in_flight": 549,"smoothed_rtt": 109542}],
[311297, 0, "transport", "datagram_sent", { "byte_length": 439}],
[311297, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 167, "packet_number": 5, "dcid": "10fb30b751f0539e", "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"}]}],
[311297, 0, "recovery", "metrics_updated", {"bytes_in_flight": 732,"smoothed_rtt": 109542}],
[311297, 0, "transport", "datagram_sent", { "byte_length": 183}],
[344954, 0, "transport", "datagram_received", { "byte_length": 111}],
[344954, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 111, "packet_number": 1, "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 0, "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"}]}],
[344990, 0, "info", "message", { "message": "H3 control frame: 06ffffffffffffffff015000074064"}],
[344954, 0, "recovery", "metrics_updated", {"cwnd": 18338,"bytes_in_flight": 677,"smoothed_rtt": 103841,"min_rtt": 63941,"latest_rtt": 63941}],
[345014, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 551, "packet_number": 6, "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "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"}]}],
[345014, 0, "recovery", "metrics_updated", {"bytes_in_flight": 1244,"smoothed_rtt": 103841}],
[345014, 0, "transport", "datagram_sent", { "byte_length": 567}],
[375300, 0, "transport", "datagram_received", { "byte_length": 37}],
[375300, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 37, "packet_number": 2, "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 1, "acked_ranges": [[1, 4]], "ect0": 4, "ect1": 0, "ce": 0}]}],
[375300, 0, "recovery", "metrics_updated", {"cwnd": 18800,"bytes_in_flight": 750,"smoothed_rtt": 98860,"latest_rtt": 63995}],
[401198, 0, "transport", "datagram_received", { "byte_length": 38}],
[401198, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 38, "packet_number": 3, "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 3235, "acked_ranges": [[1, 5]], "ect0": 5, "ect1": 0, "ce": 0}]}],
[401198, 0, "recovery", "metrics_updated", {"cwnd": 18967,"bytes_in_flight": 567,"smoothed_rtt": 96490,"latest_rtt": 79901}],
[409024, 0, "transport", "datagram_received", { "byte_length": 37}],
[409024, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 37, "packet_number": 4, "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 2, "acked_ranges": [[1, 6]], "ect0": 6, "ect1": 0, "ce": 0}]}],
[409024, 0, "recovery", "metrics_updated", {"cwnd": 19518,"bytes_in_flight": 0,"smoothed_rtt": 92428,"latest_rtt": 63994}],
[409101, 0, "transport", "datagram_received", { "byte_length": 32}],
[409101, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 32, "packet_number": 5, "dcid": "b621b7b1b216e672", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "connection_close", "error_space": "application", "error_code": 0}]}],
[409106, 0, "info", "message", { "message": "Clearing context on connection close (6)"}],
[409112, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 14, "packet_number": 7, "dcid": "10fb30b751f0539e", "quic_bit": 0 }, "frames": [{ 
    "frame_type": "connection_close", "error_space": "transport", "error_code": 0}]}],
[409112, 0, "transport", "datagram_sent", { "byte_length": 30}],
[1094883, 0, "info", "message", { "message": "Closed. Retrans= 2, spurious= 0, max sp gap = 0, max sp delay = 0, dg-coal: 1.100000"}]]}]}