{ "qlog_version": "draft-00", "title": "picoquic", "traces": [
{ "vantage_point": { "name": "backend-67", "type": "server" },
"title": "picoquic", "description": "ed7efe6fda11082b8cf5","event_fields": ["relative_time", "path_id", "category", "event", "data"],
"configuration": {"time_units": "us"},
"common_fields": { "protocol_type": "QUIC_HTTP3", "reference_time": "449784854101"},
"events": [
[0, 0, "transport", "datagram_received", { "byte_length": 1280, "addr_from" : {"ip_v4": "121.99.175.17", "port_v4":53701}, "addr_to" : {"ip_v4": "172.31.61.32", "port_v4":20753}}],
[0, 0, "transport", "packet_received", { "packet_type": "initial", "header": { "packet_size": 1280, "packet_number": 0, "version": "00000001", "payload_length": 1238, "scid": "876624a8", "dcid": "ed7efe6fda11082b8cf5" }, "frames": [{ 
    "frame_type": "crypto", "offset": 83, "length": 1158}, { 
    "frame_type": "crypto", "offset": 0, "length": 71}]}],
[226, 0, "transport", "datagram_received", { "byte_length": 1280}],
[226, 0, "transport", "packet_received", { "packet_type": "initial", "header": { "packet_size": 1280, "packet_number": 1, "payload_length": 1238, "scid": "876624a8", "dcid": "ed7efe6fda11082b8cf5" }, "frames": [{ 
    "frame_type": "crypto", "offset": 1241, "length": 307}, { 
    "frame_type": "crypto", "offset": 71, "length": 12}]}],
[242, 0, "transport", "parameters_set", {
    "owner": "remote",
    "sni": "test.privateoctopus.com",
    "proposed_alpn": ["hq-interop"],
    "alpn": "hq-interop"}],
[245, 0, "transport", "parameters_set", {
    "owner": "remote",
    "c37": "6942abf7506aa80f",
    "initial_max_stream_data_bidi_local" : 524288,
    "initial_max_stream_data_bidi_remote" : 524288,
    "initial_max_stream_data_uni" : 524288,
    "initial_max_data" : 786432,
    "initial_max_streams_bidi" : 100,
    "initial_max_streams_uni" : 100,
    "idle_timeout" : 30000,
    "max_packet_size" : 1452,
    "max_ack_delay" : 26,
    "active_connection_id_limit" : 4,
    "handshake_connection_id": "876624a8"}],
[254, 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": "52f5e1d2df5a66da",
    "original_connection_id": "ed7efe6fda11082b8cf5",
    "stateless_reset_token": "bb53bec9f4ec311931bfd590027cc325",
    "max_datagram_frame_size" : 1536,
    "address_discovery" : 0,
    "reset_stream_at" : "invalid"}],
[498, 0, "transport", "packet_sent", { "packet_type": "initial", "header": { "packet_size": 125, "packet_number": 86731, "payload_length": 99, "scid": "52f5e1d2df5a66da", "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 34, "acked_ranges": [[0, 1]]}, { 
    "frame_type": "crypto", "offset": 0, "length": 90}]}],
[498, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 1095, "packet_number": 0, "payload_length": 1070, "scid": "52f5e1d2df5a66da", "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 1066}]}],
[498, 0, "transport", "datagram_sent", { "byte_length": 1252}],
[515, 0, "info", "message", { "message": "Server CB, Stream: 0, 0 bytes, fin=9\u000a"}],
[498, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 164, "packet_number": 1, "payload_length": 139, "scid": "52f5e1d2df5a66da", "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "crypto", "offset": 1066, "length": 134}]}],
[498, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 167, "packet_number": 0, "dcid": "876624a8", "key_phase": 0 }, "frames": [{ 
    "frame_type": "new_token", "new_token": "68c3ad4ac40ed6c7d04192a5be13b6f7aa0a5a9223eb986c10e46051b7d3e75fba3b200b7af60a98d855a6e23c0b45c23dcb"}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "ab5f9c3350f839e7", "reset_token": "9b21af82050c43ff24719975b27e2cef"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "c9b90526bc572f8d", "reset_token": "2f17739bc9863f369a88d470e709014c"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "80555935075c876c", "reset_token": "bca73db69250f308cb48d79370dd8371"}, { 
    "frame_type": "padding"}]}],
[498, 0, "recovery", "metrics_updated", {"cwnd": 15360,"bytes_in_flight": 1615}],
[498, 0, "transport", "datagram_sent", { "byte_length": 363}],
[498, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 1436, "packet_number": 1, "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "ping"}, { 
    "frame_type": "padding"}]}],
[498, 0, "recovery", "metrics_updated", {"bytes_in_flight": 3067}],
[498, 0, "transport", "datagram_sent", { "byte_length": 1452}],
[196593, 0, "transport", "datagram_received", { "byte_length": 1280}],
[196593, 0, "transport", "packet_received", { "packet_type": "initial", "header": { "packet_size": 1036, "packet_number": 2, "payload_length": 996, "scid": "876624a8", "dcid": "ab5f9c3350f839e7" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 0, "acked_ranges": [[86731, 86731]], "ect0": 1, "ect1": 0, "ce": 0}]}],
[197036, 0, "transport", "datagram_received", { "byte_length": 40, "ecn" : "ECT(0)"}],
[197036, 0, "transport", "packet_dropped", {
    "packet_type" : "1RTT",
    "packet_size" : 40,
    "trigger": "aead not ready"}],
[250498, 0, "recovery", "packet_lost", {
    "packet_type" : "initial",
    "packet_number" : 86731,
    "trigger": "timer",
    "header": {
        "packet_type" : "initial",
        "packet_number" : 86731,
        "dcid" : "876624a8",
        "packet_size" : 125}}],
[250498, 0, "transport", "packet_sent", { "packet_type": "initial", "header": { "packet_size": 167, "packet_number": 86732, "payload_length": 141, "scid": "52f5e1d2df5a66da", "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 90}, { 
    "frame_type": "ack", "ack_delay": 31284, "acked_ranges": [[0, 1]]}]}],
[250498, 0, "recovery", "metrics_updated", {"bytes_in_flight": 3109}],
[250498, 0, "transport", "packet_sent", { "packet_type": "handshake", "header": { "packet_size": 1053, "packet_number": 2, "payload_length": 1028, "scid": "52f5e1d2df5a66da", "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "ping"}, { 
    "frame_type": "padding"}]}],
[250498, 0, "recovery", "metrics_updated", {"bytes_in_flight": 4178}],
[250498, 0, "transport", "datagram_sent", { "byte_length": 1252}],
[445661, 0, "transport", "datagram_received", { "byte_length": 47, "ecn" : "Not-ECT"}],
[445661, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 47, "packet_number": 1, "payload_length": 8, "scid": "876624a8", "dcid": "ab5f9c3350f839e7" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 0, "acked_ranges": [[0, 2]], "ect0": 3, "ect1": 0, "ce": 0}]}],
[784510, 0, "transport", "datagram_received", { "byte_length": 78}],
[784510, 0, "transport", "packet_received", { "packet_type": "handshake", "header": { "packet_size": 78, "packet_number": 2, "payload_length": 39, "scid": "876624a8", "dcid": "ab5f9c3350f839e7" }, "frames": [{ 
    "frame_type": "crypto", "offset": 0, "length": 36}]}],
[784591, 0, "info", "message", { "message": "Server CB, Stream: 0, 0 bytes, fin=10\u000a"}],
[784599, 0, "transport", "datagram_received", { "byte_length": 78}],
[784599, 0, "transport", "packet_dropped", {
    "packet_type" : "handshake",
    "packet_size" : 78,
    "trigger": "aead not ready"}],
[784605, 0, "recovery", "packet_lost", {
    "packet_type" : "1RTT",
    "packet_number" : 0,
    "trigger": "timer",
    "header": {
        "packet_type" : "1RTT",
        "packet_number" : 0,
        "dcid" : "876624a8",
        "packet_size" : 167}}],
[784605, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 167, "packet_number": 2, "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "new_token", "new_token": "68c3ad4ac40ed6c7d04192a5be13b6f7aa0a5a9223eb986c10e46051b7d3e75fba3b200b7af60a98d855a6e23c0b45c23dcb"}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "ab5f9c3350f839e7", "reset_token": "9b21af82050c43ff24719975b27e2cef"}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "c9b90526bc572f8d", "reset_token": "2f17739bc9863f369a88d470e709014c"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "80555935075c876c", "reset_token": "bca73db69250f308cb48d79370dd8371"}, { 
    "frame_type": "padding"}]}],
[784605, 0, "recovery", "congestion_state_updated", {"old": "0","new": "393216","cc_param": 11846}],
[784605, 0, "recovery", "metrics_updated", {"cwnd": 2887,"pacing_rate": 1361424,"bytes_in_flight": 1635,"smoothed_rtt": 195163,"min_rtt": 195163,"latest_rtt": 195163}],
[784605, 0, "transport", "datagram_sent", { "byte_length": 183}],
[784605, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 39, "packet_number": 3, "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "handshake_done"}, { 
    "frame_type": "padding"}]}],
[784605, 0, "recovery", "metrics_updated", {"bytes_in_flight": 1690,"smoothed_rtt": 195163}],
[784605, 0, "transport", "datagram_sent", { "byte_length": 55}],
[784605, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 1436, "packet_number": 4, "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "ping"}, { 
    "frame_type": "padding"}]}],
[784605, 0, "recovery", "metrics_updated", {"bytes_in_flight": 3142,"smoothed_rtt": 195163}],
[784605, 0, "transport", "datagram_sent", { "byte_length": 1452}],
[980009, 0, "transport", "datagram_received", { "byte_length": 157, "ecn" : "ECT(0)"}],
[980009, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 157, "packet_number": 3, "dcid": "ab5f9c3350f839e7", "key_phase": 0 }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 7, "acked_ranges": [[0, 4]], "ect0": 5, "ect1": 0, "ce": 0}, { 
    "frame_type": "new_connection_id", "sequence_number": 2, "retire_before": 0, "connection_id": "36b06c93", "reset_token": "40e621f619bd40f709b226fa4569b27f"}, { 
    "frame_type": "new_connection_id", "sequence_number": 5, "retire_before": 0, "connection_id": "33b4696d", "reset_token": "aad9d57d07d11c4102612179605fc90b"}, { 
    "frame_type": "new_connection_id", "sequence_number": 1, "retire_before": 0, "connection_id": "c6cd9af9", "reset_token": "28d1d7b746fba1b5df8859ce8e898d47"}, { 
    "frame_type": "new_connection_id", "sequence_number": 3, "retire_before": 0, "connection_id": "96b1a525", "reset_token": "b013a05cbefb3c5726ce0e0d7195e419"}, { 
    "frame_type": "retire_connection_id", "sequence_number": 0}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "230e6e67", "reset_token": "ba28b633b8d6992372f734755cea968d"}]}],
[980009, 0, "recovery", "congestion_state_updated", {"old": "393216","new": "0","cc_param": 11846}],
[980009, 0, "recovery", "metrics_updated", {"cwnd": 20917,"pacing_rate": 1361462,"bytes_in_flight": 0,"smoothed_rtt": 195186,"latest_rtt": 195348,"app_limited": 1}],
[980075, 0, "transport", "datagram_received", { "byte_length": 40}],
[980075, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 40, "packet_number": 4, "dcid": "ab5f9c3350f839e7" }, "frames": [{ 
    "frame_type": "stream", "id": 0, "offset": 0, "length": 11, "fin": true , "has_length": false, "begins_with": "474554202f313030"}]}],
[980082, 0, "info", "message", { "message": "Server CB, Stream: 0, 11 bytes, fin=1\u000a"}],
[980087, 0, "info", "message", { "message": "Server CB, Stream: 0, Processing command: GET /1000\u000a"}],
[980090, 0, "info", "message", { "message": "Stream 0 marked active: wake time forced to now, is_output_stream=1"}],
[980151, 0, "info", "message", { "message": "Server CB, Stream: 0, 1392 bytes, fin=8\u000a"}],
[980124, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 1063, "packet_number": 5, "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "ack", "ack_delay": 6, "acked_ranges": [[3, 4]], "ect0": 2, "ect1": 0, "ce": 0}, { 
    "frame_type": "new_connection_id", "sequence_number": 4, "retire_before": 0, "connection_id": "da39c37f21f4634c", "reset_token": "69e9f056d8b746b5393a102b2a7ca006"}, { 
    "frame_type": "stream", "id": 0, "offset": 0, "length": 1000, "fin": true , "begins_with": "5a5a5a5a5a5a5a5a"}, { 
    "frame_type": "padding"}]}],
[980124, 0, "recovery", "metrics_updated", {"bytes_in_flight": 1079,"smoothed_rtt": 195186}],
[980124, 0, "transport", "datagram_sent", { "byte_length": 1079}],
[1175338, 0, "transport", "datagram_received", { "byte_length": 30}],
[1175338, 0, "transport", "packet_received", { "packet_type": "1RTT", "header": { "packet_size": 30, "packet_number": 5, "dcid": "ab5f9c3350f839e7" }, "frames": [{ 
    "frame_type": "connection_close", "error_space": "application", "error_code": 0}]}],
[1175360, 0, "info", "message", { "message": "Server CB, Stream: 0, 0 bytes, fin=6\u000a"}],
[1175371, 0, "transport", "packet_sent", { "packet_type": "1RTT", "header": { "packet_size": 10, "packet_number": 6, "dcid": "876624a8" }, "frames": [{ 
    "frame_type": "connection_close", "error_space": "transport", "error_code": 0}]}],
[1175371, 0, "transport", "datagram_sent", { "byte_length": 26}],
[2302778, 0, "info", "message", { "message": "Closed. Retrans= 2, spurious= 1, max sp gap = 4, max sp delay = 979511, dg-coal: 1.125000"}]]}]}