[2025-04-30T09:14:03.806893641Z INFO  quiche_server] listening on [::]:443
[2025-04-30T09:14:03.806913879Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:14:03.806920121Z TRACE quiche_server] GSO detected: false
[2025-04-30T09:14:04.608554299Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:44198 to [::]:443
[2025-04-30T09:14:04.608574197Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:14:04.608581510Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:14:04.608669825Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:44198 to [::]:443
[2025-04-30T09:14:04.608676297Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:14:04.608680215Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:14:04.608703107Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:44198 to [::]:443
[2025-04-30T09:14:04.608707536Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:14:04.608711854Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:14:04.608743363Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:04.889858852Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:04.889883448Z TRACE quiche_server] got packet Initial version=1 dcid=e455ee10483b258f scid=3968a66290e3b54df981881b242a158c token=
[2025-04-30T09:14:04.889892766Z DEBUG quiche_server] New connection: dcid=e455ee10483b258f scid=1b13d8ee5896093b52a951237e0faa4e90874c36
[2025-04-30T09:14:04.890086849Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Initial version=1 dcid=e455ee10483b258f scid=3968a66290e3b54df981881b242a158c token= len=1218 pn=0 
[2025-04-30T09:14:04.890101085Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm CRYPTO off=0 len=287
[2025-04-30T09:14:04.890129538Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T09:14:04.890134157Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T09:14:04.890249293Z TRACE quiche::tls] 1b13d8ee5896093b52a951237e0faa4e90874c36 write message lvl=Initial len=123
[2025-04-30T09:14:04.890274309Z TRACE quiche::tls] 1b13d8ee5896093b52a951237e0faa4e90874c36 set write secret lvl=Handshake
[2025-04-30T09:14:04.890354690Z TRACE quiche::tls] 1b13d8ee5896093b52a951237e0faa4e90874c36 write message lvl=Handshake len=9943
[2025-04-30T09:14:04.890358687Z TRACE quiche::tls] 1b13d8ee5896093b52a951237e0faa4e90874c36 set write secret lvl=OneRTT
[2025-04-30T09:14:04.890365199Z TRACE quiche::tls] 1b13d8ee5896093b52a951237e0faa4e90874c36 set read secret lvl=Handshake
[2025-04-30T09:14:04.890412037Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=910
[2025-04-30T09:14:04.890436823Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 1252 bytes
[2025-04-30T09:14:04.890446952Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:04.890459245Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Initial version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=132 pn=0 
[2025-04-30T09:14:04.890462381Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm ACK delay=48 blocks=[0..0] ecn_counts=None
[2025-04-30T09:14:04.890467030Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=0 len=123
[2025-04-30T09:14:04.890476107Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=998.972298ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=195 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, 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: 436, tv_nsec: 464073694 }, next_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, 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:14:04.890503468Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=943 pn=1 
[2025-04-30T09:14:04.890506443Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=0 len=939
[2025-04-30T09:14:04.890511853Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=998.937213ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, 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: 436, tv_nsec: 464073694 }, next_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, 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:14:04.890551979Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 464073694 } })
[2025-04-30T09:14:04.890558651Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:04.890565504Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:04.890578278Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=1138 pn=2 
[2025-04-30T09:14:04.890582726Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=939 len=1133
[2025-04-30T09:14:04.890592825Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=998.85582ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, end_of_app_limited: 1, last_sent_packet: 2, 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: 436, tv_nsec: 464193498 }, next_time: Instant { tv_sec: 436, tv_nsec: 464193498 }, 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:14:04.890641496Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 464193498 } })
[2025-04-30T09:14:04.890648359Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:04.890654470Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:04.890667916Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=1138 pn=3 
[2025-04-30T09:14:04.890672614Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=2072 len=1133
[2025-04-30T09:14:04.890683565Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=998.765061ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, end_of_app_limited: 2, last_sent_packet: 3, 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: 436, tv_nsec: 464282154 }, next_time: Instant { tv_sec: 436, tv_nsec: 464282154 }, 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:14:04.890724562Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 464282154 } })
[2025-04-30T09:14:04.890729250Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:04.890735311Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:04.890748676Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=94 pn=4 
[2025-04-30T09:14:04.890753485Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=3205 len=89
[2025-04-30T09:14:04.890763194Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=998.685582ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3756 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 464073694 }, end_of_app_limited: 3, last_sent_packet: 4, 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: 436, tv_nsec: 464363175 }, next_time: Instant { tv_sec: 436, tv_nsec: 464363175 }, 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:14:04.890800343Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 156 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 464363175 } })
[2025-04-30T09:14:04.890812175Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:04.890818307Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:04.890823767Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:04.890827684Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.273720571Z TRACE quiche_server] got 81 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.273739917Z TRACE quiche_server] got packet Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c
[2025-04-30T09:14:05.273754694Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c len=36 pn=10 
[2025-04-30T09:14:05.273768230Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=349125 blocks=[1..4] ecn_counts=None
[2025-04-30T09:14:05.273776325Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 1
[2025-04-30T09:14:05.273781555Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 2
[2025-04-30T09:14:05.273786163Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 3
[2025-04-30T09:14:05.273790551Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 4
[2025-04-30T09:14:05.273797915Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PING mtu_probe=None
[2025-04-30T09:14:05.273802985Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=10
[2025-04-30T09:14:05.273829143Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 dropped epoch 0 state
[2025-04-30T09:14:05.273835566Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 81 bytes
[2025-04-30T09:14:05.273843320Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.273861735Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=1138 pn=5 
[2025-04-30T09:14:05.273865662Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm ACK delay=13 blocks=[10..10] ecn_counts=None
[2025-04-30T09:14:05.273869950Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=3294 len=1128
[2025-04-30T09:14:05.273880439Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.149006834s latest_rtt=383.013282ms srtt=383.013282ms min_rtt=383.013282ms rttvar=191.506641ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1200 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3561, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 4, rate_sample: RateSample { delivery_rate: 9290, is_app_limited: true, interval: 383.302763ms, delivered: 3561, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 464073694 }), send_elapsed: 0ns, ack_elapsed: 383.302763ms, rtt: 383.302763ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 44058, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.273931685Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.273948817Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.273957754Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.273972702Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=1138 pn=6 
[2025-04-30T09:14:05.273977271Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=4422 len=1133
[2025-04-30T09:14:05.273988261Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.149012925s latest_rtt=383.013282ms srtt=383.013282ms min_rtt=383.013282ms rttvar=191.506641ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3561, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 4, rate_sample: RateSample { delivery_rate: 9290, is_app_limited: true, interval: 383.302763ms, delivered: 3561, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 464073694 }), send_elapsed: 0ns, ack_elapsed: 383.302763ms, rtt: 383.302763ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 44058, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.274032274Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.274037343Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.274041872Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.274054966Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=1138 pn=7 
[2025-04-30T09:14:05.274059695Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=5555 len=1133
[2025-04-30T09:14:05.274070064Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.149013958s latest_rtt=383.013282ms srtt=383.013282ms min_rtt=383.013282ms rttvar=191.506641ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3561, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 4, rate_sample: RateSample { delivery_rate: 9290, is_app_limited: true, interval: 383.302763ms, delivered: 3561, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 464073694 }), send_elapsed: 0ns, ack_elapsed: 383.302763ms, rtt: 383.302763ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 44058, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.274111602Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.274123083Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.274129425Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.274142610Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=1138 pn=8 
[2025-04-30T09:14:05.274147539Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=6688 len=1133
[2025-04-30T09:14:05.274158059Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.149013737s latest_rtt=383.013282ms srtt=383.013282ms min_rtt=383.013282ms rttvar=191.506641ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4800 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3561, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 4, rate_sample: RateSample { delivery_rate: 9290, is_app_limited: true, interval: 383.302763ms, delivered: 3561, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 464073694 }), send_elapsed: 0ns, ack_elapsed: 383.302763ms, rtt: 383.302763ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 44058, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.274193585Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.274198044Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.274202873Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.274215566Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=1138 pn=9 
[2025-04-30T09:14:05.274220526Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=7821 len=1133
[2025-04-30T09:14:05.274231626Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.149013777s latest_rtt=383.013282ms srtt=383.013282ms min_rtt=383.013282ms rttvar=191.506641ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6000 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3561, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 4, rate_sample: RateSample { delivery_rate: 9290, is_app_limited: true, interval: 383.302763ms, delivered: 3561, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 464073694 }), send_elapsed: 0ns, ack_elapsed: 383.302763ms, rtt: 383.302763ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 44058, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.274269798Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.274281379Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.274287531Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.274300285Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=994 pn=10 
[2025-04-30T09:14:05.274305154Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=8954 len=989
[2025-04-30T09:14:05.274315814Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.149014158s latest_rtt=383.013282ms srtt=383.013282ms min_rtt=383.013282ms rttvar=191.506641ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7056 app_limited=true congestion_recovery_start_time=None Rate { delivered: 3561, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 4, rate_sample: RateSample { delivery_rate: 9290, is_app_limited: true, interval: 383.302763ms, delivered: 3561, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 464073694 }), send_elapsed: 0ns, ack_elapsed: 383.302763ms, rtt: 383.302763ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 44058, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.274353023Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 1056 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.274357522Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.274363924Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.274369725Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.274373091Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.306361123Z TRACE quiche_server] got 81 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.306375290Z TRACE quiche_server] got packet Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c
[2025-04-30T09:14:05.306405877Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c len=36 pn=11 
[2025-04-30T09:14:05.306414253Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=595 blocks=[5..5] ecn_counts=None
[2025-04-30T09:14:05.306419262Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 5
[2025-04-30T09:14:05.306424421Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=13
[2025-04-30T09:14:05.306440872Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 81 bytes
[2025-04-30T09:14:05.306446733Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.306452574Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.306455770Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.306840860Z TRACE quiche_server] got 81 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.306846661Z TRACE quiche_server] got packet Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c
[2025-04-30T09:14:05.306853574Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c len=36 pn=12 
[2025-04-30T09:14:05.306868231Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=133 blocks=[5..6] ecn_counts=None
[2025-04-30T09:14:05.306872570Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 6
[2025-04-30T09:14:05.306876787Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=13
[2025-04-30T09:14:05.306911432Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 81 bytes
[2025-04-30T09:14:05.306916862Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.306921551Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.306924577Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.307691812Z TRACE quiche_server] got 81 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.307698364Z TRACE quiche_server] got packet Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c
[2025-04-30T09:14:05.307703694Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c len=36 pn=13 
[2025-04-30T09:14:05.307708814Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=133 blocks=[5..7] ecn_counts=None
[2025-04-30T09:14:05.307711929Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 7
[2025-04-30T09:14:05.307715175Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=13
[2025-04-30T09:14:05.307723722Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 81 bytes
[2025-04-30T09:14:05.307727348Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.307730614Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.307732808Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.308678377Z TRACE quiche_server] got 81 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.308684058Z TRACE quiche_server] got packet Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c
[2025-04-30T09:14:05.308688746Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c len=36 pn=14 
[2025-04-30T09:14:05.308693415Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=133 blocks=[5..8] ecn_counts=None
[2025-04-30T09:14:05.308696310Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 8
[2025-04-30T09:14:05.308699226Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=13
[2025-04-30T09:14:05.308706800Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 81 bytes
[2025-04-30T09:14:05.308710227Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.308713352Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.308715536Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.309676814Z TRACE quiche_server] got 81 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.309682505Z TRACE quiche_server] got packet Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c
[2025-04-30T09:14:05.309687364Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c len=36 pn=15 
[2025-04-30T09:14:05.309692053Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=142 blocks=[5..9] ecn_counts=None
[2025-04-30T09:14:05.309694948Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 9
[2025-04-30T09:14:05.309697713Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PING mtu_probe=None
[2025-04-30T09:14:05.309704596Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=12
[2025-04-30T09:14:05.309712811Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 81 bytes
[2025-04-30T09:14:05.309716448Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.309724313Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Handshake version=1 dcid=3968a66290e3b54df981881b242a158c scid=1b13d8ee5896093b52a951237e0faa4e90874c36 len=5 pn=11 
[2025-04-30T09:14:05.309727038Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm ACK delay=4 blocks=[11..15] ecn_counts=None
[2025-04-30T09:14:05.309733260Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.128737942s latest_rtt=35.838754ms srtt=212.770545ms min_rtt=32.556335ms rttvar=237.852505ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1056 app_limited=true congestion_recovery_start_time=None Rate { delivered: 9561, delivered_time: Instant { tv_sec: 436, tv_nsec: 883311110 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 9, rate_sample: RateSample { delivery_rate: 184295, is_app_limited: true, interval: 32.556335ms, delivered: 6000, prior_delivered: 3561, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 32.556335ms, rtt: 32.556335ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 79310, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.309772122Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 67 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.309777122Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.309780899Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.309784315Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.309786589Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.312681734Z TRACE quiche_server] got 107 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.312692254Z TRACE quiche_server] got packet Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c
[2025-04-30T09:14:05.312699637Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Handshake version=1 dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 scid=3968a66290e3b54df981881b242a158c len=62 pn=16 
[2025-04-30T09:14:05.312706600Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=2181 blocks=[5..10] ecn_counts=None
[2025-04-30T09:14:05.312710969Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 10
[2025-04-30T09:14:05.312715367Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm CRYPTO off=0 len=36
[2025-04-30T09:14:05.312727630Z TRACE quiche::tls] 1b13d8ee5896093b52a951237e0faa4e90874c36 set read secret lvl=OneRTT
[2025-04-30T09:14:05.312762866Z TRACE quiche::tls] 1b13d8ee5896093b52a951237e0faa4e90874c36 write message lvl=OneRTT len=396
[2025-04-30T09:14:05.312770841Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 dropped epoch 1 state
[2025-04-30T09:14:05.312774938Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 300000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 3750000, initial_max_stream_data_bidi_local: 3750000, initial_max_stream_data_bidi_remote: 3750000, initial_max_stream_data_uni: 3750000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 3, initial_source_connection_id: Some(3968a66290e3b54df981881b242a158c), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T09:14:05.312803411Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 107 bytes
[2025-04-30T09:14:05.312810044Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.312816346Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1316
[2025-04-30T09:14:05.312823760Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Short dcid=3968a66290e3b54df981881b242a158c key_phase=false len=481 pn=12 
[2025-04-30T09:14:05.312827015Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[37, 81, 4b, 5b, 69, 1d, a3, 99, 15, 69, 56, 10, b5, 3e, a7, fb, 27, c1, 49, d4] reset_token=[3f, 9b, 5f, 01, ff, c9, 3e, e4, ca, 81, fc, 0d, ec, 38, 97, ff]
[2025-04-30T09:14:05.312835071Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[74, ec, 73, c9, 05, 5e, 90, 50, 1c, aa, 67, 03, a9, c1, e3, 52, 33, 18, b7, 79] reset_token=[b2, 03, 36, 7e, ed, dd, 1c, f8, d9, 1d, 5a, 7d, 90, fd, 75, e6]
[2025-04-30T09:14:05.312841332Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm HANDSHAKE_DONE
[2025-04-30T09:14:05.312844188Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm CRYPTO off=0 len=396
[2025-04-30T09:14:05.312853976Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.103466437s latest_rtt=38.850477ms srtt=191.030535ms min_rtt=32.556335ms rttvar=221.869395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=515 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10617, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 10, rate_sample: RateSample { delivery_rate: 216732, is_app_limited: true, interval: 32.556335ms, delivered: 7056, prior_delivered: 3561, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 32.556335ms, rtt: 32.556335ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 88336, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.312899912Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 515 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.312905693Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.312910391Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.312913818Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.312916483Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.313242132Z TRACE quiche_server] got 61 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.313246079Z TRACE quiche_server] got packet Short dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 key_phase=false
[2025-04-30T09:14:05.313255567Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Short dcid=1b13d8ee5896093b52a951237e0faa4e90874c36 key_phase=false len=40 pn=0 
[2025-04-30T09:14:05.313260947Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm STREAM id=0 off=0 len=0 fin=false
[2025-04-30T09:14:05.313264754Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T09:14:05.313271447Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 61 bytes
[2025-04-30T09:14:05.313274322Z DEBUG quiche_apps::common] 1b13d8ee5896093b52a951237e0faa4e90874c36 response stream 0 is writable with capacity Ok(12985)
[2025-04-30T09:14:05.313280443Z TRACE quiche_apps::common] 1b13d8ee5896093b52a951237e0faa4e90874c36 received 17 bytes
[2025-04-30T09:14:05.313283229Z TRACE quiche_apps::common] 1b13d8ee5896093b52a951237e0faa4e90874c36 stream 0 has 17 bytes (fin? true)
[2025-04-30T09:14:05.313287327Z INFO  quiche_apps::common] 1b13d8ee5896093b52a951237e0faa4e90874c36 got GET request for "/www/sgupuqpavm" on stream 0
[2025-04-30T09:14:05.313306643Z INFO  quiche_apps::common] 1b13d8ee5896093b52a951237e0faa4e90874c36 sending response of size 5120 on stream 0
[2025-04-30T09:14:05.313313415Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.313318705Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12985 out_len=1350 left=1311
[2025-04-30T09:14:05.313322803Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Short dcid=3968a66290e3b54df981881b242a158c key_phase=false len=1316 pn=13 
[2025-04-30T09:14:05.313325267Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm ACK delay=7 blocks=[0..0] ecn_counts=None
[2025-04-30T09:14:05.313328373Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm STREAM id=0 off=0 len=1306 fin=false
[2025-04-30T09:14:05.313335216Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.103488168s latest_rtt=38.850477ms srtt=191.030535ms min_rtt=32.556335ms rttvar=221.869395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1865 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10617, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 10, rate_sample: RateSample { delivery_rate: 216732, is_app_limited: true, interval: 32.556335ms, delivered: 7056, prior_delivered: 3561, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 32.556335ms, rtt: 32.556335ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 88336, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.313347178Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11635 out_len=1350 left=1316
[2025-04-30T09:14:05.313350244Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Short dcid=3968a66290e3b54df981881b242a158c key_phase=false len=1316 pn=14 
[2025-04-30T09:14:05.313352609Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm STREAM id=0 off=1306 len=1310 fin=false
[2025-04-30T09:14:05.313357277Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.103496433s latest_rtt=38.850477ms srtt=191.030535ms min_rtt=32.556335ms rttvar=221.869395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3215 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10617, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 10, rate_sample: RateSample { delivery_rate: 216732, is_app_limited: true, interval: 32.556335ms, delivered: 7056, prior_delivered: 3561, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 32.556335ms, rtt: 32.556335ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 88336, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.313371514Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10285 out_len=1350 left=1316
[2025-04-30T09:14:05.313374600Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Short dcid=3968a66290e3b54df981881b242a158c key_phase=false len=1316 pn=15 
[2025-04-30T09:14:05.313395329Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm STREAM id=0 off=2616 len=1310 fin=false
[2025-04-30T09:14:05.313403333Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.103474893s latest_rtt=38.850477ms srtt=191.030535ms min_rtt=32.556335ms rttvar=221.869395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4565 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10617, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 10, rate_sample: RateSample { delivery_rate: 216732, is_app_limited: true, interval: 32.556335ms, delivered: 7056, prior_delivered: 3561, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 32.556335ms, rtt: 32.556335ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 88336, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.313414073Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8935 out_len=1350 left=1316
[2025-04-30T09:14:05.313417299Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Short dcid=3968a66290e3b54df981881b242a158c key_phase=false len=1200 pn=16 
[2025-04-30T09:14:05.313419624Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm STREAM id=0 off=3926 len=1194 fin=true
[2025-04-30T09:14:05.313424603Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.103495892s latest_rtt=38.850477ms srtt=191.030535ms min_rtt=32.556335ms rttvar=221.869395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5799 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10617, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 10, rate_sample: RateSample { delivery_rate: 216732, is_app_limited: true, interval: 32.556335ms, delivered: 7056, prior_delivered: 3561, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 32.556335ms, rtt: 32.556335ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 88336, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.313491939Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 5284 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.313497069Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.313501056Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.313503591Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.313505755Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.345961993Z TRACE quiche_server] got 1472 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.345976440Z TRACE quiche_server] got packet Short dcid=37814b5b691da39915695610b53ea7fb27c149d4 key_phase=false
[2025-04-30T09:14:05.345984284Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Short dcid=37814b5b691da39915695610b53ea7fb27c149d4 key_phase=false len=1451 pn=1 
[2025-04-30T09:14:05.345991538Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 path ID 0 now see SCID with seq num 1
[2025-04-30T09:14:05.345996006Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PING mtu_probe=None
[2025-04-30T09:14:05.346004883Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=1433
[2025-04-30T09:14:05.346014180Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 1472 bytes
[2025-04-30T09:14:05.346019821Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.346027435Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7701 out_len=1350 left=1311
[2025-04-30T09:14:05.346031973Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Short dcid=3968a66290e3b54df981881b242a158c key_phase=false len=5 pn=17 
[2025-04-30T09:14:05.346035279Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm ACK delay=5 blocks=[0..1] ecn_counts=None
[2025-04-30T09:14:05.346044497Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=1.070875618s latest_rtt=38.850477ms srtt=191.030535ms min_rtt=32.556335ms rttvar=221.869395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5799 app_limited=true congestion_recovery_start_time=None Rate { delivered: 10617, delivered_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 15, last_sent_packet: 17, largest_acked: 10, rate_sample: RateSample { delivery_rate: 216732, is_app_limited: true, interval: 32.556335ms, delivered: 7056, prior_delivered: 3561, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 32.556335ms, rtt: 32.556335ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 88336, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.346093248Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 39 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.346107986Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.346115850Z TRACE quiche_server] got 154 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.346120078Z TRACE quiche_server] got packet Short dcid=37814b5b691da39915695610b53ea7fb27c149d4 key_phase=false
[2025-04-30T09:14:05.346125679Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Short dcid=37814b5b691da39915695610b53ea7fb27c149d4 key_phase=false len=133 pn=2 
[2025-04-30T09:14:05.346131800Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm ACK delay=74 blocks=[12..12] ecn_counts=None
[2025-04-30T09:14:05.346136208Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 packet newly acked 12
[2025-04-30T09:14:05.346143802Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=1 conn_id=[a9, df, 5f, 73, 00, a5, 22, 14, 7b, 71, eb, de, c2, 0d, d2, b9] reset_token=[ad, ec, 8b, 55, b8, 28, 75, fd, 46, 24, c2, 33, 4d, 63, 39, 04]
[2025-04-30T09:14:05.346151307Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 path ID 0 changed DCID: old seq num 0 new seq num 1
[2025-04-30T09:14:05.346156296Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=1 conn_id=[90, 77, 43, d0, e5, da, a6, 5c, 89, 15, 12, d8, 00, e5, 99, 3a] reset_token=[6d, 6a, e1, 1d, 4a, 39, 27, 7f, ce, d5, 7f, da, 4c, 0c, e1, 23]
[2025-04-30T09:14:05.346163599Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[92, 4d, cf, 48, d2, 38, fd, b9, a2, e7, 65, f6, 4b, ae, 37, 38] reset_token=[10, 0f, 4c, f6, b6, cd, c7, 65, 5e, b8, 42, 90, 47, 42, ef, 6a]
[2025-04-30T09:14:05.346169000Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-04-30T09:14:05.346181864Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 154 bytes
[2025-04-30T09:14:05.346185701Z INFO  quiche_server] Retiring source CID 1b13d8ee5896093b52a951237e0faa4e90874c36
[2025-04-30T09:14:05.346194367Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.346200188Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8216 out_len=1350 left=1311
[2025-04-30T09:14:05.346205518Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx pkt Short dcid=a9df5f7300a522147b71ebdec20dd2b9 key_phase=false len=47 pn=18 
[2025-04-30T09:14:05.346209015Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm ACK delay=9 blocks=[0..2] ecn_counts=None
[2025-04-30T09:14:05.346212831Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[f8, 5f, 27, 7d, 21, d4, e2, 62, 9c, 45, 4f, 54, 6b, 36, 2b, e5, 35, 56, 78, 70] reset_token=[28, 1b, 18, e8, 17, 1c, b8, 58, 7a, 3c, b6, a8, 45, e9, f2, 13]
[2025-04-30T09:14:05.346219644Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 tx frm RETIRE_CONNECTION_ID seq_num=0
[2025-04-30T09:14:05.346242156Z TRACE quiche::recovery::congestion::recovery] 1b13d8ee5896093b52a951237e0faa4e90874c36 timer=986.02064ms latest_rtt=72.276938ms srtt=176.112335ms min_rtt=32.556335ms rttvar=196.238445ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5365 app_limited=true congestion_recovery_start_time=None Rate { delivered: 11132, delivered_time: Instant { tv_sec: 436, tv_nsec: 919749294 }, first_sent_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 12, rate_sample: RateSample { delivery_rate: 7125, is_app_limited: true, interval: 72.276938ms, delivered: 515, prior_delivered: 10617, prior_time: Some(Instant { tv_sec: 436, tv_nsec: 847472356 }), send_elapsed: 0ns, ack_elapsed: 72.276938ms, rtt: 72.276938ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 95819, last_update: Instant { tv_sec: 436, tv_nsec: 847472356 }, next_time: Instant { tv_sec: 436, tv_nsec: 847472356 }, 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:14:05.346288744Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 written 81 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:53993, at: Instant { tv_sec: 436, tv_nsec: 847472356 } })
[2025-04-30T09:14:05.346295015Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.346300125Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.346303822Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.346306687Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.348906940Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.348915376Z TRACE quiche_server] got packet Short dcid=37814b5b691da39915695610b53ea7fb27c149d4 key_phase=false
[2025-04-30T09:14:05.348921848Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx pkt Short dcid=37814b5b691da39915695610b53ea7fb27c149d4 key_phase=false len=36 pn=3 
[2025-04-30T09:14:05.348928490Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T09:14:05.348932718Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 rx frm PADDING len=15
[2025-04-30T09:14:05.348940924Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 57 bytes
[2025-04-30T09:14:05.348946033Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.348948959Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.348951844Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:05.409154984Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:53993 to [::]:443
[2025-04-30T09:14:05.409170333Z TRACE quiche_server] got packet Short dcid=37814b5b691da39915695610b53ea7fb27c149d4 key_phase=false
[2025-04-30T09:14:05.409178037Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 processed 57 bytes
[2025-04-30T09:14:05.409184058Z TRACE quiche_server] recv() would block
[2025-04-30T09:14:05.409187465Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:05.409192033Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:08.235072392Z TRACE quiche_server] timed out
[2025-04-30T09:14:08.235099453Z TRACE quiche] 1b13d8ee5896093b52a951237e0faa4e90874c36 draining timeout expired
[2025-04-30T09:14:08.235138045Z TRACE quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 done writing
[2025-04-30T09:14:08.235143836Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:14:08.235149376Z INFO  quiche_server] 1b13d8ee5896093b52a951237e0faa4e90874c36 connection collected recv=12 sent=19 lost=0 retrans=0 sent_bytes=16798 recv_bytes=3589 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:53993 validation_state=Validated active=true recv=12 sent=19 lost=0 retrans=0 rtt=176.112335ms min_rtt=Some(32.556335ms) rttvar=196.238445ms cwnd=13500 sent_bytes=16798 recv_bytes=3589 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=7125]