[2025-09-12T02:32:57.178624169Z INFO quiche_server] listening on [::]:443 [2025-09-12T02:32:57.178647863Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-09-12T02:32:57.178653994Z TRACE quiche_server] GSO detected: false [2025-09-12T02:32:57.490924703Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:55487 to [::]:443 [2025-09-12T02:32:57.490967353Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-09-12T02:32:57.490975678Z WARN quiche_server] Doing version negotiation [2025-09-12T02:32:57.491050338Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.206598883Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.206618379Z TRACE quiche_server] got packet Initial version=1 dcid=39b345233351f279ce320b97f0 scid= token= [2025-09-12T02:32:58.206632646Z WARN quiche_server] Doing stateless retry [2025-09-12T02:32:58.206672841Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.206677209Z TRACE quiche_server] got packet Initial version=1 dcid=39b345233351f279ce320b97f0 scid= token= [2025-09-12T02:32:58.206680796Z WARN quiche_server] Doing stateless retry [2025-09-12T02:32:58.206695423Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.238610852Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.238624277Z TRACE quiche_server] got packet Initial version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= token=71756963686500000000000000000000ffffc1a7006439b345233351f279ce320b97f0 [2025-09-12T02:32:58.238631520Z DEBUG quiche_server] New connection: dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a [2025-09-12T02:32:58.238816656Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Initial version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= token=71756963686500000000000000000000ffffc1a7006439b345233351f279ce320b97f0 len=1187 pn=12 [2025-09-12T02:32:58.238829761Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm CRYPTO off=0 len=1166 [2025-09-12T02:32:58.238859426Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 1252 bytes [2025-09-12T02:32:58.238867912Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.238877921Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Initial version=1 dcid= scid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a len=5 pn=0 [2025-09-12T02:32:58.238881117Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm ACK delay=9 blocks=[12..12] ecn_counts=None [2025-09-12T02:32:58.238891546Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=none latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=false congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1124, tv_nsec: 984359836 }, first_sent_time: Instant { tv_sec: 1124, tv_nsec: 984359836 }, end_of_app_limited: 0, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 1124, tv_nsec: 984359836 }, next_time: Instant { tv_sec: 1124, tv_nsec: 984359836 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=None last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.238933675Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:47503, at: Instant { tv_sec: 1124, tv_nsec: 984359836 } }) [2025-09-12T02:32:58.238946799Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.238950847Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.238954584Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.238956968Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.239590071Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.239598918Z TRACE quiche_server] got packet Initial version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= token=71756963686500000000000000000000ffffc1a7006439b345233351f279ce320b97f0 [2025-09-12T02:32:58.239606542Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Initial version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= token=71756963686500000000000000000000ffffc1a7006439b345233351f279ce320b97f0 len=396 pn=13 [2025-09-12T02:32:58.239613565Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm CRYPTO off=1166 len=374 [2025-09-12T02:32:58.239635666Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3") [2025-09-12T02:32:58.239639493Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop") [2025-09-12T02:32:58.239724773Z TRACE quiche::tls] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a write message lvl=Initial len=90 [2025-09-12T02:32:58.239754929Z TRACE quiche::tls] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a set write secret lvl=Handshake [2025-09-12T02:32:58.239818117Z TRACE quiche::tls] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a write message lvl=Handshake len=725 [2025-09-12T02:32:58.239821093Z TRACE quiche::tls] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a set write secret lvl=OneRTT [2025-09-12T02:32:58.239827264Z TRACE quiche::tls] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a set read secret lvl=Handshake [2025-09-12T02:32:58.239848744Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a dropped invalid packet [2025-09-12T02:32:58.239851579Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 1252 bytes [2025-09-12T02:32:58.239865275Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.239874061Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Initial version=1 dcid= scid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a len=99 pn=1 [2025-09-12T02:32:58.239876807Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm ACK delay=33 blocks=[12..13] ecn_counts=None [2025-09-12T02:32:58.239879983Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm CRYPTO off=0 len=90 [2025-09-12T02:32:58.239887206Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=998.980013ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=146 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1124, tv_nsec: 985356879 }, first_sent_time: Instant { tv_sec: 1124, tv_nsec: 985356879 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 1124, tv_nsec: 985356879 }, next_time: Instant { tv_sec: 1124, tv_nsec: 985356879 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.239901343Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Handshake version=1 dcid= scid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a len=729 pn=2 [2025-09-12T02:32:58.239904008Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm CRYPTO off=0 len=725 [2025-09-12T02:32:58.239913886Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=998.953393ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=921 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 1124, tv_nsec: 985356879 }, first_sent_time: Instant { tv_sec: 1124, tv_nsec: 985356879 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 1124, tv_nsec: 985356879 }, next_time: Instant { tv_sec: 1124, tv_nsec: 985356879 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.239946136Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:47503, at: Instant { tv_sec: 1124, tv_nsec: 985356879 } }) [2025-09-12T02:32:58.239950935Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.239954632Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.239958239Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.239960493Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.272647213Z TRACE quiche_server] got 140 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.272660818Z TRACE quiche_server] got packet Initial version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= token=71756963686500000000000000000000ffffc1a7006439b345233351f279ce320b97f0 [2025-09-12T02:32:58.272670787Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Initial version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= token=71756963686500000000000000000000ffffc1a7006439b345233351f279ce320b97f0 len=23 pn=14 [2025-09-12T02:32:58.272679734Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=150 blocks=[0..1] ecn_counts=None [2025-09-12T02:32:58.272684873Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 0 [2025-09-12T02:32:58.272688360Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 1 [2025-09-12T02:32:58.272704260Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Handshake version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= len=23 pn=0 [2025-09-12T02:32:58.272709259Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=150 blocks=[2..2] ecn_counts=None [2025-09-12T02:32:58.272712535Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 2 [2025-09-12T02:32:58.272722383Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a dropped epoch 0 state [2025-09-12T02:32:58.272725790Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 140 bytes [2025-09-12T02:32:58.272730729Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.272736700Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.272739806Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.273155442Z TRACE quiche_server] got 143 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.273160442Z TRACE quiche_server] got packet Handshake version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= [2025-09-12T02:32:58.273165772Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Handshake version=1 dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a scid= len=56 pn=1 [2025-09-12T02:32:58.273178345Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm CRYPTO off=0 len=36 [2025-09-12T02:32:58.273189917Z TRACE quiche::tls] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a set read secret lvl=OneRTT [2025-09-12T02:32:58.273223129Z TRACE quiche::tls] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a write message lvl=OneRTT len=396 [2025-09-12T02:32:58.273230202Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a dropped epoch 1 state [2025-09-12T02:32:58.273234300Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 4611686018427387903, initial_max_stream_data_bidi_local: 1048576, initial_max_stream_data_bidi_remote: 1048576, initial_max_stream_data_uni: 1048576, initial_max_streams_bidi: 16, initial_max_streams_uni: 16, ack_delay_exponent: 3, max_ack_delay: 20, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(), retry_source_connection_id: None, max_datagram_frame_size: Some(1200), unknown_params: None } [2025-09-12T02:32:58.273254197Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false len=37 pn=0 [2025-09-12T02:32:58.273259697Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm STREAM id=0 off=0 len=17 fin=true [2025-09-12T02:32:58.273267051Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 143 bytes [2025-09-12T02:32:58.273271159Z DEBUG quiche_apps::common] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a response stream 0 is writable with capacity Ok(13500) [2025-09-12T02:32:58.273277911Z TRACE quiche_apps::common] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a received 17 bytes [2025-09-12T02:32:58.273280867Z TRACE quiche_apps::common] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a stream 0 has 17 bytes (fin? true) [2025-09-12T02:32:58.273285756Z INFO quiche_apps::common] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a got GET request for "/www/fzwrzqmhig" on stream 0 [2025-09-12T02:32:58.273310462Z INFO quiche_apps::common] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a sending response of size 10240 on stream 0 [2025-09-12T02:32:58.273320030Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.273331992Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=3 [2025-09-12T02:32:58.273335148Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm ACK delay=9 blocks=[0..0] ecn_counts=None [2025-09-12T02:32:58.273338244Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[23, 0f, 3d, bf, f5, 1d, 3d, 92, 77, 6d, e3, d0, 3f, 83, 71, 17, 56, ed, 74, a8] reset_token=[3d, d4, e3, c2, ea, d5, fe, 28, 6d, 56, 98, 9f, 81, 03, 11, 30] [2025-09-12T02:32:58.273344956Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[02, 79, f6, 95, cb, 7d, 32, a8, 3a, b6, b0, 6b, d7, 4b, 61, e2, f2, 93, 55, 16] reset_token=[c4, c0, 2b, 00, bd, 25, ba, 5e, a3, 27, 0e, a7, 9b, 59, 72, 29] [2025-09-12T02:32:58.273350567Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[ea, 44, 04, 30, 5d, 9c, 01, c1, 79, 04, f0, c4, 7a, 63, 8e, a9, 2f, a9, 01, 65] reset_token=[df, e3, 69, 63, 1f, 93, 2e, 46, f0, 52, ef, 4d, 8e, 10, 53, bc] [2025-09-12T02:32:58.273356027Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[ab, 8d, 3d, 16, c6, 94, 10, aa, de, 00, 50, 87, c9, 5f, 6e, bf, 93, 90, 47, 59] reset_token=[e6, 51, a9, 12, c0, 01, 86, 59, 3e, 6e, 4b, 4f, 30, 70, 26, ea] [2025-09-12T02:32:58.273361397Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[a9, d4, c6, f0, 07, 89, 0a, 17, a2, a9, 7d, 41, ec, 15, 34, 70, 75, 76, f3, b0] reset_token=[b3, a4, f6, 7c, 5b, 5b, 3d, 9b, bc, e3, 10, 33, a4, 95, 90, 12] [2025-09-12T02:32:58.273371125Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ea, fd, 4c, 51, af, bf, 66, 10, 19, 31, 1b, 32, c4, 71, b5, 79, 21, 82, 62, e2] reset_token=[c8, 68, f4, 11, 41, 4a, d9, f4, 7d, 56, 04, e5, af, 59, 54, 03] [2025-09-12T02:32:58.273376465Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[ec, 72, e2, 58, e9, c7, 27, 6c, b8, ff, 23, 87, e2, 3d, 8c, 57, 17, f9, 8a, 46] reset_token=[af, 19, 7b, 90, ab, f5, 69, c4, cc, 10, 3c, b9, f7, d7, ce, 29] [2025-09-12T02:32:58.273381585Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm HANDSHAKE_DONE [2025-09-12T02:32:58.273383779Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm CRYPTO off=0 len=396 [2025-09-12T02:32:58.273386093Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=0 len=641 fin=false [2025-09-12T02:32:58.273398356Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=101.967019ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273415148Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=4 [2025-09-12T02:32:58.273417882Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=641 len=1326 fin=false [2025-09-12T02:32:58.273422852Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.03198ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2700 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273438200Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=5 [2025-09-12T02:32:58.273440725Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=1967 len=1326 fin=false [2025-09-12T02:32:58.273445043Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.033644ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4050 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273455964Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=6 [2025-09-12T02:32:58.273458418Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=3293 len=1326 fin=false [2025-09-12T02:32:58.273477885Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.018755ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 6, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273489516Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=7 [2025-09-12T02:32:58.273492031Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=4619 len=1326 fin=false [2025-09-12T02:32:58.273497000Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.033062ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6750 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 7, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273510546Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=8 [2025-09-12T02:32:58.273536765Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=5945 len=1326 fin=false [2025-09-12T02:32:58.273542435Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.008827ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8100 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 8, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273554448Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=9 [2025-09-12T02:32:58.273556912Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=7271 len=1326 fin=false [2025-09-12T02:32:58.273561120Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.033593ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9450 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 9, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273571780Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=1332 pn=10 [2025-09-12T02:32:58.273577721Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=8597 len=1326 fin=false [2025-09-12T02:32:58.273582149Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.030598ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10800 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 10, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273592829Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=323 pn=11 [2025-09-12T02:32:58.273595294Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm STREAM id=0 off=9923 len=317 fin=true [2025-09-12T02:32:58.273599672Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=102.034034ms latest_rtt=32.836089ms srtt=32.805678ms min_rtt=32.801334ms rttvar=12.309188ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11141 app_limited=true congestion_recovery_start_time=None Rate { delivered: 921, delivered_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 11, largest_acked: 2, rate_sample: RateSample { bandwidth: 224.38 Kbps, is_app_limited: true, interval: 32.836089ms, delivered: 921, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 1124, tv_nsec: 985356879 }), send_elapsed: 0ns, ack_elapsed: 32.836089ms, rtt: 32.836089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514392, last_update: Instant { tv_sec: 1125, tv_nsec: 18812645 }, next_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.273687176Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a written 11141 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:47503, at: Instant { tv_sec: 1125, tv_nsec: 18812645 } }) [2025-09-12T02:32:58.273692486Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.273696313Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.273699559Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.273701823Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.306563066Z TRACE quiche_server] got 1352 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.306574908Z TRACE quiche_server] got packet Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false [2025-09-12T02:32:58.306581370Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false len=1331 pn=1 [2025-09-12T02:32:58.306594765Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=38 blocks=[3..3] ecn_counts=None [2025-09-12T02:32:58.306598913Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 3 [2025-09-12T02:32:58.306603822Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm PING mtu_probe=None [2025-09-12T02:32:58.306610184Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm PADDING len=1308 [2025-09-12T02:32:58.306621345Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 1352 bytes [2025-09-12T02:32:58.306625793Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.306632415Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx pkt Short dcid= key_phase=false len=5 pn=12 [2025-09-12T02:32:58.306635231Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a tx frm ACK delay=6 blocks=[1..1] ecn_counts=None [2025-09-12T02:32:58.306641553Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a timer=56.848269ms latest_rtt=33.256802ms srtt=32.824068ms min_rtt=32.801334ms rttvar=9.268672ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9791 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2271, delivered_time: Instant { tv_sec: 1125, tv_nsec: 52069447 }, first_sent_time: Instant { tv_sec: 1125, tv_nsec: 18812645 }, end_of_app_limited: 4, last_sent_packet: 12, largest_acked: 3, rate_sample: RateSample { bandwidth: 324.74 Kbps, is_app_limited: true, interval: 33.256802ms, delivered: 1350, prior_delivered: 921, prior_time: Some(Instant { tv_sec: 1125, tv_nsec: 18812645 }), send_elapsed: 0ns, ack_elapsed: 33.256802ms, rtt: 33.256802ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 514104, last_update: Instant { tv_sec: 1125, tv_nsec: 52117397 }, next_time: Instant { tv_sec: 1125, tv_nsec: 52117397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(1) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } [2025-09-12T02:32:58.306678802Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:47503, at: Instant { tv_sec: 1125, tv_nsec: 52117397 } }) [2025-09-12T02:32:58.306683942Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.306687608Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.306690614Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.306692848Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.307325550Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.307329548Z TRACE quiche_server] got packet Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false [2025-09-12T02:32:58.307334477Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false len=22 pn=2 [2025-09-12T02:32:58.307339326Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=2 blocks=[3..5] ecn_counts=None [2025-09-12T02:32:58.307342522Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 4 [2025-09-12T02:32:58.307344976Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 5 [2025-09-12T02:32:58.307354124Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 43 bytes [2025-09-12T02:32:58.307357921Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.307360656Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.307362880Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.309522023Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.309532122Z TRACE quiche_server] got packet Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false [2025-09-12T02:32:58.309537041Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false len=22 pn=3 [2025-09-12T02:32:58.309541580Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=3 blocks=[3..7] ecn_counts=None [2025-09-12T02:32:58.309544756Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 6 [2025-09-12T02:32:58.309547080Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 7 [2025-09-12T02:32:58.309555917Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 43 bytes [2025-09-12T02:32:58.309559714Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.309562599Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.309564883Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.311740868Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.311748492Z TRACE quiche_server] got packet Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false [2025-09-12T02:32:58.311753121Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false len=22 pn=4 [2025-09-12T02:32:58.311757559Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=3 blocks=[3..9] ecn_counts=None [2025-09-12T02:32:58.311760896Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 8 [2025-09-12T02:32:58.311763270Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 9 [2025-09-12T02:32:58.311772146Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 43 bytes [2025-09-12T02:32:58.311775934Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.311778849Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.311781143Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.313131866Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.313137407Z TRACE quiche_server] got packet Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false [2025-09-12T02:32:58.313141475Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false len=22 pn=5 [2025-09-12T02:32:58.313145452Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=2 blocks=[3..11] ecn_counts=None [2025-09-12T02:32:58.313148387Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 10 [2025-09-12T02:32:58.313150692Z TRACE quiche::recovery::congestion::recovery] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a packet newly acked 11 [2025-09-12T02:32:58.313159168Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 43 bytes [2025-09-12T02:32:58.313162764Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.313165439Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.313167673Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.313274934Z TRACE quiche_server] got 54 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.313278340Z TRACE quiche_server] got packet Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false [2025-09-12T02:32:58.313282197Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx pkt Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false len=33 pn=6 [2025-09-12T02:32:58.313286145Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm ACK delay=19 blocks=[3..11] ecn_counts=None [2025-09-12T02:32:58.313299760Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a rx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65, 21] [2025-09-12T02:32:58.313306142Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 54 bytes [2025-09-12T02:32:58.313309609Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.313311863Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.313314007Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.337186813Z TRACE quiche_server] got 55 bytes from [::ffff:193.167.0.100]:47503 to [::]:443 [2025-09-12T02:32:58.337202171Z TRACE quiche_server] got packet Short dcid=72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a key_phase=false [2025-09-12T02:32:58.337209264Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a processed 55 bytes [2025-09-12T02:32:58.337214605Z TRACE quiche_server] recv() would block [2025-09-12T02:32:58.337217880Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.337220896Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.489428005Z TRACE quiche_server] timed out [2025-09-12T02:32:58.489454254Z TRACE quiche] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a draining timeout expired [2025-09-12T02:32:58.489499669Z TRACE quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a done writing [2025-09-12T02:32:58.489503887Z TRACE quiche_server] Collecting garbage [2025-09-12T02:32:58.489509487Z INFO quiche_server] 72aab1d243b77e2f3bb1cd3a527a26a32b3bb08a connection collected recv=12 sent=13 lost=0 retrans=0 sent_bytes=12137 recv_bytes=3574 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:47503 validation_state=Validated active=true recv=12 sent=13 lost=0 retrans=0 rtt=34.726705ms min_rtt=Some(32.801334ms) rttvar=5.902245ms cwnd=13500 sent_bytes=12137 recv_bytes=3574 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=279801]