[2025-04-30T09:10:13.891175243Z INFO  quiche_server] listening on [::]:443
[2025-04-30T09:10:13.891203916Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:10:13.891210689Z TRACE quiche_server] GSO detected: false
[2025-04-30T09:10:14.677765822Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:33738 to [::]:443
[2025-04-30T09:10:14.677798191Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:10:14.677806096Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:10:14.677903757Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:33738 to [::]:443
[2025-04-30T09:10:14.677908866Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:10:14.677911962Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:10:14.677928272Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:33738 to [::]:443
[2025-04-30T09:10:14.677931609Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:10:14.677935356Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:10:14.677957446Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:14.937920564Z TRACE quiche_server] got 1220 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:14.937942034Z TRACE quiche_server] got packet Initial version=1 dcid=2f2382b9341fe98f scid= token=
[2025-04-30T09:10:14.937949017Z WARN  quiche_server] Doing stateless retry
[2025-04-30T09:10:14.937989031Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:14.969407275Z TRACE quiche_server] got 1220 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:14.969425308Z TRACE quiche_server] got packet Initial version=1 dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 scid= token=71756963686500000000000000000000ffffc1a700642f2382b9341fe98f
[2025-04-30T09:10:14.969436058Z DEBUG quiche_server] New connection: dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 scid=40dda62136fb9834d3086639d6e687070a0ab6f2
[2025-04-30T09:10:14.969687905Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Initial version=1 dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 scid= token=71756963686500000000000000000000ffffc1a700642f2382b9341fe98f len=1160 pn=1 
[2025-04-30T09:10:14.969706469Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm CRYPTO off=0 len=288
[2025-04-30T09:10:14.969738578Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T09:10:14.969743798Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T09:10:14.969857559Z TRACE quiche::tls] 40dda62136fb9834d3086639d6e687070a0ab6f2 write message lvl=Initial len=90
[2025-04-30T09:10:14.969893776Z TRACE quiche::tls] 40dda62136fb9834d3086639d6e687070a0ab6f2 set write secret lvl=Handshake
[2025-04-30T09:10:14.969981438Z TRACE quiche::tls] 40dda62136fb9834d3086639d6e687070a0ab6f2 write message lvl=Handshake len=735
[2025-04-30T09:10:14.969985506Z TRACE quiche::tls] 40dda62136fb9834d3086639d6e687070a0ab6f2 set write secret lvl=OneRTT
[2025-04-30T09:10:14.969995815Z TRACE quiche::tls] 40dda62136fb9834d3086639d6e687070a0ab6f2 set read secret lvl=Handshake
[2025-04-30T09:10:14.970020981Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm PADDING len=848
[2025-04-30T09:10:14.970045006Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 1220 bytes
[2025-04-30T09:10:14.970059122Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:14.970073439Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Initial version=1 dcid= scid=40dda62136fb9834d3086639d6e687070a0ab6f2 len=99 pn=0 
[2025-04-30T09:10:14.970077767Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm ACK delay=50 blocks=[1..1] ecn_counts=None
[2025-04-30T09:10:14.970083157Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm CRYPTO off=0 len=90
[2025-04-30T09:10:14.970095029Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=998.966518ms 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: 321, tv_nsec: 225487546 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 225487546 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 321, tv_nsec: 225487546 }, next_time: Instant { tv_sec: 321, tv_nsec: 225487546 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:14.970130604Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Handshake version=1 dcid= scid=40dda62136fb9834d3086639d6e687070a0ab6f2 len=739 pn=1 
[2025-04-30T09:10:14.970134452Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm CRYPTO off=0 len=735
[2025-04-30T09:10:14.970142046Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=998.919932ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=931 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 321, tv_nsec: 225487546 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 225487546 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, 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: 321, tv_nsec: 225487546 }, next_time: Instant { tv_sec: 321, tv_nsec: 225487546 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:14.970197198Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45560, at: Instant { tv_sec: 321, tv_nsec: 225487546 } })
[2025-04-30T09:10:14.970205102Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:14.970210633Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:14.970218037Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:14.970223557Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.002345603Z TRACE quiche_server] got 109 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.002358998Z TRACE quiche_server] got packet Handshake version=1 dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 scid=
[2025-04-30T09:10:15.002367163Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Handshake version=1 dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 scid= len=80 pn=2 
[2025-04-30T09:10:15.002374527Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm ACK delay=2 blocks=[1..1] ecn_counts=None
[2025-04-30T09:10:15.002379666Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 1
[2025-04-30T09:10:15.002385176Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm CRYPTO off=0 len=52
[2025-04-30T09:10:15.002399052Z TRACE quiche::tls] 40dda62136fb9834d3086639d6e687070a0ab6f2 set read secret lvl=OneRTT
[2025-04-30T09:10:15.002443604Z TRACE quiche::tls] 40dda62136fb9834d3086639d6e687070a0ab6f2 write message lvl=OneRTT len=428
[2025-04-30T09:10:15.002451730Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 dropped epoch 1 state
[2025-04-30T09:10:15.002455947Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 connection established: proto=Ok("hq-interop") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 10000, stateless_reset_token: None, max_udp_payload_size: 1472, initial_max_data: 16777216, initial_max_stream_data_bidi_local: 65536, initial_max_stream_data_bidi_remote: 65536, initial_max_stream_data_uni: 65536, initial_max_streams_bidi: 0, initial_max_streams_uni: 3, ack_delay_exponent: 8, max_ack_delay: 26, disable_active_migration: false, active_conn_id_limit: 4, initial_source_connection_id: Some(), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T09:10:15.002480563Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 dropped epoch 0 state
[2025-04-30T09:10:15.002484099Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 109 bytes
[2025-04-30T09:10:15.002490171Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.002495981Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1332
[2025-04-30T09:10:15.002504047Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=553 pn=2 
[2025-04-30T09:10:15.002507463Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[01, f0, 6d, 89, 0e, 17, 59, e5, ff, 05, c6, 29, 55, aa, 50, af, 07, 87, e4, 84] reset_token=[47, 3b, f9, 23, 1f, b1, 44, 39, d6, 3e, 93, 6a, 85, ec, b1, 7d]
[2025-04-30T09:10:15.002516369Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[6f, db, f2, d5, 31, 05, a7, 2e, 9a, cc, cc, 4a, bc, ea, eb, b7, 00, b4, ff, c8] reset_token=[86, 45, 17, 37, d7, 6f, ee, c8, 8c, 9f, 35, ab, 2c, 1f, fe, 65]
[2025-04-30T09:10:15.002523483Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[c8, 3d, 51, 43, 64, 9c, 10, 33, 8c, c3, 0b, a0, 2f, 29, 12, af, 76, 3d, dc, fa] reset_token=[3a, e7, 3a, be, b7, be, d4, 5e, 63, c8, ea, 00, 6d, 84, a7, 4c]
[2025-04-30T09:10:15.002530415Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm HANDSHAKE_DONE
[2025-04-30T09:10:15.002533201Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm CRYPTO off=0 len=428
[2025-04-30T09:10:15.002543850Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.857565ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.002627585Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 written 571 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45560, at: Instant { tv_sec: 321, tv_nsec: 257917923 } })
[2025-04-30T09:10:15.002642723Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.002650648Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.002656689Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.002661528Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.003368888Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.003377404Z TRACE quiche_server] got packet Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false
[2025-04-30T09:10:15.003384657Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false len=1231 pn=3 
[2025-04-30T09:10:15.003392001Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm PING mtu_probe=None
[2025-04-30T09:10:15.003395868Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T09:10:15.003404785Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm PADDING len=1189
[2025-04-30T09:10:15.003414002Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 1252 bytes
[2025-04-30T09:10:15.003417518Z DEBUG quiche_apps::common] 40dda62136fb9834d3086639d6e687070a0ab6f2 response stream 0 is writable with capacity Ok(12929)
[2025-04-30T09:10:15.003424982Z TRACE quiche_apps::common] 40dda62136fb9834d3086639d6e687070a0ab6f2 received 17 bytes
[2025-04-30T09:10:15.003428088Z TRACE quiche_apps::common] 40dda62136fb9834d3086639d6e687070a0ab6f2 stream 0 has 17 bytes (fin? true)
[2025-04-30T09:10:15.003433418Z INFO  quiche_apps::common] 40dda62136fb9834d3086639d6e687070a0ab6f2 got GET request for "/www/rbwfwuymvc" on stream 0
[2025-04-30T09:10:15.003457833Z INFO  quiche_apps::common] 40dda62136fb9834d3086639d6e687070a0ab6f2 sending response of size 10240 on stream 0
[2025-04-30T09:10:15.003466890Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.003473211Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12929 out_len=1350 left=1327
[2025-04-30T09:10:15.003478681Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=1332 pn=3 
[2025-04-30T09:10:15.003482759Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm ACK delay=11 blocks=[3..3] ecn_counts=None
[2025-04-30T09:10:15.003486747Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=0 len=1322 fin=false
[2025-04-30T09:10:15.003494240Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.884565ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1921 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003514017Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11579 out_len=1350 left=1332
[2025-04-30T09:10:15.003517463Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=1332 pn=4 
[2025-04-30T09:10:15.003519998Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=1322 len=1326 fin=false
[2025-04-30T09:10:15.003525147Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.896928ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3271 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003537550Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10229 out_len=1350 left=1332
[2025-04-30T09:10:15.003540736Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=1332 pn=5 
[2025-04-30T09:10:15.003543111Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=2648 len=1326 fin=false
[2025-04-30T09:10:15.003547780Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.89811ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4621 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003559691Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8879 out_len=1350 left=1332
[2025-04-30T09:10:15.003562757Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=1332 pn=6 
[2025-04-30T09:10:15.003565152Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=3974 len=1326 fin=false
[2025-04-30T09:10:15.003572966Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.895225ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5971 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003620264Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7529 out_len=1350 left=1332
[2025-04-30T09:10:15.003625052Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=1332 pn=7 
[2025-04-30T09:10:15.003627567Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=5300 len=1326 fin=false
[2025-04-30T09:10:15.003632937Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.894293ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7321 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003644809Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6179 out_len=1350 left=1332
[2025-04-30T09:10:15.003647845Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=1332 pn=8 
[2025-04-30T09:10:15.003650229Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=6626 len=1326 fin=false
[2025-04-30T09:10:15.003654767Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.898451ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8671 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003670607Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4829 out_len=1350 left=1332
[2025-04-30T09:10:15.003673743Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=1332 pn=9 
[2025-04-30T09:10:15.003676107Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=7952 len=1326 fin=false
[2025-04-30T09:10:15.003680706Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.898351ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10021 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003692357Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=3479 out_len=1350 left=1332
[2025-04-30T09:10:15.003695373Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=968 pn=10 
[2025-04-30T09:10:15.003697817Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm STREAM id=0 off=9278 len=962 fin=true
[2025-04-30T09:10:15.003702666Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=122.89819ms latest_rtt=32.303121ms srtt=32.303121ms min_rtt=32.303121ms rttvar=16.15156ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11007 app_limited=true congestion_recovery_start_time=None Rate { delivered: 785, delivered_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { delivery_rate: 24301, is_app_limited: true, interval: 32.303121ms, delivered: 785, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 225487546 }), send_elapsed: 0ns, ack_elapsed: 32.303121ms, rtt: 32.303121ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 522395, last_update: Instant { tv_sec: 321, tv_nsec: 257917923 }, next_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.003892237Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 written 10436 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45560, at: Instant { tv_sec: 321, tv_nsec: 257917923 } })
[2025-04-30T09:10:15.003903408Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.003909960Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.003914328Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.003918206Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.035172749Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.035183458Z TRACE quiche_server] got packet Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false
[2025-04-30T09:10:15.035190321Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false len=25 pn=4 
[2025-04-30T09:10:15.035196803Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm ACK delay=0 blocks=[2..3] ecn_counts=None
[2025-04-30T09:10:15.035200720Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 2
[2025-04-30T09:10:15.035203586Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 3
[2025-04-30T09:10:15.035234082Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 46 bytes
[2025-04-30T09:10:15.035238921Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.035242037Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.035244301Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.036269758Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.036280147Z TRACE quiche_server] got packet Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false
[2025-04-30T09:10:15.036286258Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false len=1311 pn=5 
[2025-04-30T09:10:15.036292480Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm PING mtu_probe=None
[2025-04-30T09:10:15.036299453Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm PADDING len=1290
[2025-04-30T09:10:15.036306145Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 1332 bytes
[2025-04-30T09:10:15.036310543Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.036315442Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4414 out_len=1350 left=1327
[2025-04-30T09:10:15.036318878Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=5 pn=11 
[2025-04-30T09:10:15.036321413Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm ACK delay=3 blocks=[4..5] ecn_counts=None
[2025-04-30T09:10:15.036328156Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=74.563159ms latest_rtt=32.696163ms srtt=32.35225ms min_rtt=32.303121ms rttvar=12.21193ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9086 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2706, delivered_time: Instant { tv_sec: 321, tv_nsec: 290614086 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 3, rate_sample: RateSample { delivery_rate: 58753, is_app_limited: true, interval: 32.696163ms, delivered: 1921, prior_delivered: 785, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 257917923 }), send_elapsed: 0ns, ack_elapsed: 32.696163ms, rtt: 32.696163ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 521602, last_update: Instant { tv_sec: 321, tv_nsec: 291737774 }, next_time: Instant { tv_sec: 321, tv_nsec: 291737774 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.036375663Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45560, at: Instant { tv_sec: 321, tv_nsec: 291737774 } })
[2025-04-30T09:10:15.036381414Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.036385211Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.036387946Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.036390110Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.037398909Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.037408616Z TRACE quiche_server] got packet Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false
[2025-04-30T09:10:15.037414658Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false len=36 pn=6 
[2025-04-30T09:10:15.037420599Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm ACK delay=0 blocks=[2..5] ecn_counts=None
[2025-04-30T09:10:15.037424055Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 4
[2025-04-30T09:10:15.037426540Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 5
[2025-04-30T09:10:15.037429936Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm MAX_DATA max=16781190
[2025-04-30T09:10:15.037432581Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm MAX_STREAM_DATA stream=0 max=69510
[2025-04-30T09:10:15.037443301Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 57 bytes
[2025-04-30T09:10:15.037447509Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.037452227Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7114 out_len=1350 left=1327
[2025-04-30T09:10:15.037455503Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=5 pn=12 
[2025-04-30T09:10:15.037457968Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm ACK delay=4 blocks=[4..6] ecn_counts=None
[2025-04-30T09:10:15.037463658Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=64.105306ms latest_rtt=34.92068ms srtt=32.673303ms min_rtt=32.303121ms rttvar=9.801054ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6386 app_limited=true congestion_recovery_start_time=None Rate { delivered: 5406, delivered_time: Instant { tv_sec: 321, tv_nsec: 292838603 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 0, last_sent_packet: 12, largest_acked: 5, rate_sample: RateSample { delivery_rate: 141331, is_app_limited: true, interval: 32.696163ms, delivered: 4621, prior_delivered: 785, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 257917923 }), send_elapsed: 0ns, ack_elapsed: 32.696163ms, rtt: 32.696163ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 516476, last_update: Instant { tv_sec: 321, tv_nsec: 291737774 }, next_time: Instant { tv_sec: 321, tv_nsec: 291737774 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.037507560Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45560, at: Instant { tv_sec: 321, tv_nsec: 291737774 } })
[2025-04-30T09:10:15.037512669Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.037516967Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.037521436Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.037524992Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.039643085Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.039655648Z TRACE quiche_server] got packet Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false
[2025-04-30T09:10:15.039662891Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false len=36 pn=7 
[2025-04-30T09:10:15.039670105Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm ACK delay=0 blocks=[2..7] ecn_counts=None
[2025-04-30T09:10:15.039674643Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 6
[2025-04-30T09:10:15.039677899Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 7
[2025-04-30T09:10:15.039682347Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm MAX_DATA max=16783842
[2025-04-30T09:10:15.039685694Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm MAX_STREAM_DATA stream=0 max=72162
[2025-04-30T09:10:15.039699389Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 57 bytes
[2025-04-30T09:10:15.039704969Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.039711121Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9814 out_len=1350 left=1327
[2025-04-30T09:10:15.039715268Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=5 pn=13 
[2025-04-30T09:10:15.039718444Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm ACK delay=6 blocks=[4..7] ecn_counts=None
[2025-04-30T09:10:15.039725748Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=57.099466ms latest_rtt=37.168814ms srtt=33.235241ms min_rtt=32.303121ms rttvar=8.474667ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3686 app_limited=true congestion_recovery_start_time=None Rate { delivered: 8106, delivered_time: Instant { tv_sec: 321, tv_nsec: 295086737 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 7, rate_sample: RateSample { delivery_rate: 223910, is_app_limited: true, interval: 32.696163ms, delivered: 7321, prior_delivered: 785, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 257917923 }), send_elapsed: 0ns, ack_elapsed: 32.696163ms, rtt: 32.696163ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 507744, last_update: Instant { tv_sec: 321, tv_nsec: 291737774 }, next_time: Instant { tv_sec: 321, tv_nsec: 291737774 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.039771493Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45560, at: Instant { tv_sec: 321, tv_nsec: 291737774 } })
[2025-04-30T09:10:15.039778425Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.039786360Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.039792021Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.039805836Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.041855552Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.041866703Z TRACE quiche_server] got packet Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false
[2025-04-30T09:10:15.041873545Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false len=36 pn=8 
[2025-04-30T09:10:15.041880458Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm ACK delay=0 blocks=[2..9] ecn_counts=None
[2025-04-30T09:10:15.041884716Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 8
[2025-04-30T09:10:15.041887772Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 9
[2025-04-30T09:10:15.041891849Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm MAX_DATA max=16786494
[2025-04-30T09:10:15.041894955Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm MAX_STREAM_DATA stream=0 max=74814
[2025-04-30T09:10:15.041907308Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 57 bytes
[2025-04-30T09:10:15.041912377Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.041918028Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12514 out_len=1350 left=1327
[2025-04-30T09:10:15.041921985Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx pkt Short dcid= key_phase=false len=5 pn=14 
[2025-04-30T09:10:15.041924951Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 tx frm ACK delay=5 blocks=[4..8] ecn_counts=None
[2025-04-30T09:10:15.041931513Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 timer=53.331404ms latest_rtt=39.379548ms srtt=34.003278ms min_rtt=32.303121ms rttvar=7.892076ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=986 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10806, delivered_time: Instant { tv_sec: 321, tv_nsec: 297297471 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 257917923 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 9, rate_sample: RateSample { delivery_rate: 306488, is_app_limited: true, interval: 32.696163ms, delivered: 10021, prior_delivered: 785, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 257917923 }), send_elapsed: 0ns, ack_elapsed: 32.696163ms, rtt: 32.696163ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 496275, last_update: Instant { tv_sec: 321, tv_nsec: 291737774 }, next_time: Instant { tv_sec: 321, tv_nsec: 291737774 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) 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-04-30T09:10:15.041974131Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45560, at: Instant { tv_sec: 321, tv_nsec: 291737774 } })
[2025-04-30T09:10:15.041980022Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.041987466Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.041992465Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.041997114Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.042860974Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:45560 to [::]:443
[2025-04-30T09:10:15.042870391Z TRACE quiche_server] got packet Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false
[2025-04-30T09:10:15.042876352Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx pkt Short dcid=40dda62136fb9834d3086639d6e687070a0ab6f2 key_phase=false len=28 pn=9 
[2025-04-30T09:10:15.042887673Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm ACK delay=0 blocks=[2..10] ecn_counts=None
[2025-04-30T09:10:15.042891210Z TRACE quiche::recovery::congestion::recovery] 40dda62136fb9834d3086639d6e687070a0ab6f2 packet newly acked 10
[2025-04-30T09:10:15.042894907Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 rx frm APPLICATION_CLOSE err=0 reason=[]
[2025-04-30T09:10:15.042906939Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 processed 49 bytes
[2025-04-30T09:10:15.042911137Z TRACE quiche_server] recv() would block
[2025-04-30T09:10:15.042913461Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.042915715Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.238164621Z TRACE quiche_server] timed out
[2025-04-30T09:10:15.238186942Z TRACE quiche] 40dda62136fb9834d3086639d6e687070a0ab6f2 draining timeout expired
[2025-04-30T09:10:15.238228529Z TRACE quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 done writing
[2025-04-30T09:10:15.238232767Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:10:15.238236954Z INFO  quiche_server] 40dda62136fb9834d3086639d6e687070a0ab6f2 connection collected recv=9 sent=15 lost=0 retrans=0 sent_bytes=12030 recv_bytes=4179 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:45560 validation_state=Validated active=true recv=9 sent=15 lost=0 retrans=0 rtt=34.800662ms min_rtt=Some(32.303121ms) rttvar=7.513826ms cwnd=13500 sent_bytes=12030 recv_bytes=4179 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=336645]