[2025-12-13T09:41:35.606836667Z INFO  quiche_server] listening on [::]:443
[2025-12-13T09:41:35.606881029Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-12-13T09:41:35.606891118Z TRACE quiche_server] GSO detected: false
[2025-12-13T09:41:35.933073012Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:44906 to [::]:443
[2025-12-13T09:41:35.933143113Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-12-13T09:41:35.933150997Z WARN  quiche_server] Doing version negotiation
[2025-12-13T09:41:35.933215727Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:37.457383100Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:37.457414448Z TRACE quiche_server] got packet Initial version=1 dcid=cfb83ac9d5f3f908 scid=c0e6e5f20aad1a15311511cf48444a00 token=
[2025-12-13T09:41:37.457442710Z DEBUG quiche_server] New connection: dcid=cfb83ac9d5f3f908 scid=6637ba83754d13640dcbbc24accbddd14681f586
[2025-12-13T09:41:37.457663821Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Initial version=1 dcid=cfb83ac9d5f3f908 scid=c0e6e5f20aad1a15311511cf48444a00 token= len=1218 pn=0 
[2025-12-13T09:41:37.457681214Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm CRYPTO off=0 len=287
[2025-12-13T09:41:37.457713554Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-12-13T09:41:37.457719505Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-12-13T09:41:37.457841952Z TRACE quiche::tls] 6637ba83754d13640dcbbc24accbddd14681f586 write message lvl=Initial len=123
[2025-12-13T09:41:37.457878119Z TRACE quiche::tls] 6637ba83754d13640dcbbc24accbddd14681f586 set write secret lvl=Handshake
[2025-12-13T09:41:37.457942038Z TRACE quiche::tls] 6637ba83754d13640dcbbc24accbddd14681f586 write message lvl=Handshake len=696
[2025-12-13T09:41:37.457945515Z TRACE quiche::tls] 6637ba83754d13640dcbbc24accbddd14681f586 set write secret lvl=OneRTT
[2025-12-13T09:41:37.457952217Z TRACE quiche::tls] 6637ba83754d13640dcbbc24accbddd14681f586 set read secret lvl=Handshake
[2025-12-13T09:41:37.457971203Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PADDING len=910
[2025-12-13T09:41:37.457989426Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 1252 bytes
[2025-12-13T09:41:37.457999515Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:37.458012549Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx pkt Initial version=1 dcid=c0e6e5f20aad1a15311511cf48444a00 scid=6637ba83754d13640dcbbc24accbddd14681f586 len=132 pn=0 
[2025-12-13T09:41:37.458016627Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm ACK delay=46 blocks=[0..0] ecn_counts=None
[2025-12-13T09:41:37.458021646Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm CRYPTO off=0 len=123
[2025-12-13T09:41:37.458031515Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=998.969924ms 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: 223, tv_nsec: 796925966 }, first_sent_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 223, tv_nsec: 796925966 }, next_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, 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-12-13T09:41:37.458062793Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx pkt Handshake version=1 dcid=c0e6e5f20aad1a15311511cf48444a00 scid=6637ba83754d13640dcbbc24accbddd14681f586 len=700 pn=1 
[2025-12-13T09:41:37.458066500Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm CRYPTO off=0 len=696
[2025-12-13T09:41:37.458072561Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=998.929389ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=957 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, first_sent_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 223, tv_nsec: 796925966 }, next_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, 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-12-13T09:41:37.458165193Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43888, at: Instant { tv_sec: 223, tv_nsec: 796925966 } })
[2025-12-13T09:41:37.458173198Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:37.458178297Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:37.458184469Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 done writing
[2025-12-13T09:41:37.458189558Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.451398800Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:38.451431371Z TRACE quiche_server] got packet Initial version=1 dcid=cfb83ac9d5f3f908 scid=c0e6e5f20aad1a15311511cf48444a00 token=
[2025-12-13T09:41:38.451446409Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Initial version=1 dcid=cfb83ac9d5f3f908 scid=c0e6e5f20aad1a15311511cf48444a00 token= len=1166 pn=2 
[2025-12-13T09:41:38.451466997Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm CRYPTO off=0 len=287
[2025-12-13T09:41:38.451486223Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PADDING len=858
[2025-12-13T09:41:38.451506981Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 1200 bytes
[2025-12-13T09:41:38.451514255Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:38.451529092Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx pkt Initial version=1 dcid=c0e6e5f20aad1a15311511cf48444a00 scid=6637ba83754d13640dcbbc24accbddd14681f586 len=7 pn=2 
[2025-12-13T09:41:38.451533721Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm ACK delay=10 blocks=[0..0, 2..2] ecn_counts=None
[2025-12-13T09:41:38.451547887Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=5.453331ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=957 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, first_sent_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 224, tv_nsec: 790441537 }, next_time: Instant { tv_sec: 224, tv_nsec: 790441537 }, 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-12-13T09:41:38.451627094Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43888, at: Instant { tv_sec: 224, tv_nsec: 790441537 } })
[2025-12-13T09:41:38.451635661Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.451641672Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:38.451646741Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 done writing
[2025-12-13T09:41:38.451650298Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.452286911Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:38.452300185Z TRACE quiche_server] got packet Initial version=1 dcid=cfb83ac9d5f3f908 scid=c0e6e5f20aad1a15311511cf48444a00 token=
[2025-12-13T09:41:38.452307800Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Initial version=1 dcid=cfb83ac9d5f3f908 scid=c0e6e5f20aad1a15311511cf48444a00 token= len=1166 pn=4 
[2025-12-13T09:41:38.452315404Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm CRYPTO off=0 len=287
[2025-12-13T09:41:38.452323659Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PADDING len=858
[2025-12-13T09:41:38.452333988Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 1200 bytes
[2025-12-13T09:41:38.452338487Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:38.452347253Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx pkt Initial version=1 dcid=c0e6e5f20aad1a15311511cf48444a00 scid=6637ba83754d13640dcbbc24accbddd14681f586 len=9 pn=3 
[2025-12-13T09:41:38.452350799Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm ACK delay=4 blocks=[0..0, 2..2, 4..4] ecn_counts=None
[2025-12-13T09:41:38.452359115Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=4.641272ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=957 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, first_sent_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 224, tv_nsec: 791264617 }, next_time: Instant { tv_sec: 224, tv_nsec: 791264617 }, 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-12-13T09:41:38.452407034Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43888, at: Instant { tv_sec: 224, tv_nsec: 791264617 } })
[2025-12-13T09:41:38.452413286Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.452417644Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:38.452421451Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 done writing
[2025-12-13T09:41:38.452424276Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.457529304Z TRACE quiche_server] timed out
[2025-12-13T09:41:38.457545053Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 loss detection timeout expired
[2025-12-13T09:41:38.457552197Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=998.448851ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=957 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, first_sent_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 224, tv_nsec: 791264617 }, next_time: Instant { tv_sec: 224, tv_nsec: 791264617 }, 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-12-13T09:41:38.457588434Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx pkt Initial version=1 dcid=c0e6e5f20aad1a15311511cf48444a00 scid=6637ba83754d13640dcbbc24accbddd14681f586 len=137 pn=4 
[2025-12-13T09:41:38.457594175Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm ACK delay=659 blocks=[0..0, 2..2, 4..4] ecn_counts=None
[2025-12-13T09:41:38.457600156Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm CRYPTO off=0 len=123
[2025-12-13T09:41:38.457613500Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=998.388128ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1157 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, first_sent_time: Instant { tv_sec: 223, tv_nsec: 796925966 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 0.00 Kbps, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 224, tv_nsec: 796496150 }, next_time: Instant { tv_sec: 224, tv_nsec: 796496150 }, 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-12-13T09:41:38.457664966Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43888, at: Instant { tv_sec: 224, tv_nsec: 796496150 } })
[2025-12-13T09:41:38.457673432Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.457679804Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:38.457685234Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 done writing
[2025-12-13T09:41:38.457689071Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.963364451Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:38.963401700Z TRACE quiche_server] got packet Initial version=1 dcid=6637ba83754d13640dcbbc24accbddd14681f586 scid=c0e6e5f20aad1a15311511cf48444a00 token=
[2025-12-13T09:41:38.963418161Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Initial version=1 dcid=6637ba83754d13640dcbbc24accbddd14681f586 scid=c0e6e5f20aad1a15311511cf48444a00 token= len=36 pn=5 
[2025-12-13T09:41:38.963458526Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm ACK delay=1838 blocks=[0..0] ecn_counts=None
[2025-12-13T09:41:38.963467462Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 packet newly acked 0
[2025-12-13T09:41:38.963475628Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PADDING len=13
[2025-12-13T09:41:38.963503740Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Handshake version=1 dcid=6637ba83754d13640dcbbc24accbddd14681f586 scid=c0e6e5f20aad1a15311511cf48444a00 len=1125 pn=0 
[2025-12-13T09:41:38.963512096Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm ACK delay=1838 blocks=[1..1] ecn_counts=None
[2025-12-13T09:41:38.963516714Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 packet newly acked 1
[2025-12-13T09:41:38.963522765Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm CRYPTO off=0 len=36
[2025-12-13T09:41:38.963544796Z TRACE quiche::tls] 6637ba83754d13640dcbbc24accbddd14681f586 set read secret lvl=OneRTT
[2025-12-13T09:41:38.963587485Z TRACE quiche::tls] 6637ba83754d13640dcbbc24accbddd14681f586 write message lvl=OneRTT len=396
[2025-12-13T09:41:38.963599237Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 dropped epoch 1 state
[2025-12-13T09:41:38.963604878Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 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(c0e6e5f20aad1a15311511cf48444a00), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-12-13T09:41:38.963622511Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PADDING len=1063
[2025-12-13T09:41:38.963640784Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 dropped epoch 0 state
[2025-12-13T09:41:38.963645183Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 1252 bytes
[2025-12-13T09:41:38.963655302Z TRACE quiche_server] got 74 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:38.963660281Z TRACE quiche_server] got packet Short dcid=6637ba83754d13640dcbbc24accbddd14681f586 key_phase=false
[2025-12-13T09:41:38.963666863Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Short dcid=6637ba83754d13640dcbbc24accbddd14681f586 key_phase=false len=53 pn=0 
[2025-12-13T09:41:38.963673976Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm STREAM id=0 off=0 len=0 fin=false
[2025-12-13T09:41:38.963680819Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm STREAM id=0 off=0 len=30 fin=true
[2025-12-13T09:41:38.963689145Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 74 bytes
[2025-12-13T09:41:38.963693533Z DEBUG quiche_apps::common] 6637ba83754d13640dcbbc24accbddd14681f586 response stream 0 is writable with capacity Ok(13500)
[2025-12-13T09:41:38.963701387Z TRACE quiche_apps::common] 6637ba83754d13640dcbbc24accbddd14681f586 received 30 bytes
[2025-12-13T09:41:38.963705565Z TRACE quiche_apps::common] 6637ba83754d13640dcbbc24accbddd14681f586 stream 0 has 30 bytes (fin? true)
[2025-12-13T09:41:38.963712358Z INFO  quiche_apps::common] 6637ba83754d13640dcbbc24accbddd14681f586 got GET request for "/www/polar-copious-astronaut" on stream 0
[2025-12-13T09:41:38.963748905Z INFO  quiche_apps::common] 6637ba83754d13640dcbbc24accbddd14681f586 sending response of size 1024 on stream 0
[2025-12-13T09:41:38.963755918Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:38.963776537Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx pkt Short dcid=c0e6e5f20aad1a15311511cf48444a00 key_phase=false len=1316 pn=5 
[2025-12-13T09:41:38.963780644Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm ACK delay=12 blocks=[0..0] ecn_counts=None
[2025-12-13T09:41:38.963784431Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[41, ed, bb, 72, 4d, 96, 1d, 53, d4, ee, f1, 43, 9e, 4a, c4, a1, 69, 13, 37, 76] reset_token=[a4, e2, cc, 85, ee, 3c, 90, 79, c2, c9, 34, 06, 97, b5, 8a, bc]
[2025-12-13T09:41:38.963791835Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[e6, a6, b2, 7e, 93, c7, f5, 6f, 6c, b2, e7, 9d, 6a, 52, d3, 36, 6a, e5, 08, a5] reset_token=[84, 30, 1e, 93, 0a, 15, 9b, 87, 0b, ab, dd, 7b, 29, 76, fa, ba]
[2025-12-13T09:41:38.963798177Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm HANDSHAKE_DONE
[2025-12-13T09:41:38.963800962Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm CRYPTO off=0 len=396
[2025-12-13T09:41:38.963803878Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm STREAM id=0 off=0 len=825 fin=false
[2025-12-13T09:41:38.963815840Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=3.788576622s latest_rtt=1.505500438s srtt=1.505423118s min_rtt=1.505412074s rttvar=564.551618ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 957, delivered_time: Instant { tv_sec: 224, tv_nsec: 796496150 }, first_sent_time: Instant { tv_sec: 224, tv_nsec: 796496150 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { bandwidth: 5.08 Kbps, is_app_limited: true, interval: 1.505500438s, delivered: 957, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 223, tv_nsec: 796925966 }), send_elapsed: 0ns, ack_elapsed: 1.505500438s, rtt: 1.505500438s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 11209, last_update: Instant { tv_sec: 224, tv_nsec: 796496150 }, next_time: Instant { tv_sec: 224, tv_nsec: 796496150 }, 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-12-13T09:41:38.963838833Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx pkt Short dcid=c0e6e5f20aad1a15311511cf48444a00 key_phase=false len=205 pn=6 
[2025-12-13T09:41:38.963842169Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 tx frm STREAM id=0 off=825 len=199 fin=true
[2025-12-13T09:41:38.963847819Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 timer=3.788617517s latest_rtt=1.505500438s srtt=1.505423118s min_rtt=1.505412074s rttvar=564.551618ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1589 app_limited=true congestion_recovery_start_time=None Rate { delivered: 957, delivered_time: Instant { tv_sec: 224, tv_nsec: 796496150 }, first_sent_time: Instant { tv_sec: 224, tv_nsec: 796496150 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { bandwidth: 5.08 Kbps, is_app_limited: true, interval: 1.505500438s, delivered: 957, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 223, tv_nsec: 796925966 }), send_elapsed: 0ns, ack_elapsed: 1.505500438s, rtt: 1.505500438s } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 11209, last_update: Instant { tv_sec: 224, tv_nsec: 796496150 }, next_time: Instant { tv_sec: 224, tv_nsec: 796496150 }, 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-12-13T09:41:38.963914483Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 written 1589 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:43888, at: Instant { tv_sec: 224, tv_nsec: 796496150 } })
[2025-12-13T09:41:38.963921577Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:38.963926475Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:38.963930162Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 done writing
[2025-12-13T09:41:38.963932947Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:40.468756399Z TRACE quiche_server] got 1472 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:40.468802585Z TRACE quiche_server] got packet Short dcid=41edbb724d961d53d4eef1439e4ac4a169133776 key_phase=false
[2025-12-13T09:41:40.468818605Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Short dcid=41edbb724d961d53d4eef1439e4ac4a169133776 key_phase=false len=1451 pn=1 
[2025-12-13T09:41:40.468830787Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 path ID 0 now see SCID with seq num 1
[2025-12-13T09:41:40.468838562Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PING mtu_probe=None
[2025-12-13T09:41:40.468849071Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PADDING len=1433
[2025-12-13T09:41:40.468905857Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 1472 bytes
[2025-12-13T09:41:40.468916076Z TRACE quiche_server] got 154 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:40.468921075Z TRACE quiche_server] got packet Short dcid=41edbb724d961d53d4eef1439e4ac4a169133776 key_phase=false
[2025-12-13T09:41:40.468926956Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Short dcid=41edbb724d961d53d4eef1439e4ac4a169133776 key_phase=false len=133 pn=2 
[2025-12-13T09:41:40.468933889Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm ACK delay=140 blocks=[5..6] ecn_counts=None
[2025-12-13T09:41:40.468941163Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 packet newly acked 5
[2025-12-13T09:41:40.468945621Z TRACE quiche::recovery::congestion::recovery] 6637ba83754d13640dcbbc24accbddd14681f586 packet newly acked 6
[2025-12-13T09:41:40.468957824Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=1 conn_id=[3f, 60, 84, 84, 29, 7e, d4, c4, 37, be, 04, 70, 29, ad, 91, 15] reset_token=[40, a3, 3b, 98, 4c, 0b, dd, 9f, cf, 85, 5e, b3, 0b, 9b, 57, 39]
[2025-12-13T09:41:40.468968464Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 path ID 0 changed DCID: old seq num 0 new seq num 1
[2025-12-13T09:41:40.468974605Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=1 conn_id=[ec, 0e, 5e, 32, 61, 6d, 1b, 90, 39, 26, c1, 23, 28, 8c, 20, a5] reset_token=[14, 09, b9, 2f, b6, 2f, 6e, 46, e1, 80, 17, 01, 65, 91, 25, a8]
[2025-12-13T09:41:40.468984393Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[6d, c3, 62, a9, 4d, 81, ae, 7e, fd, f7, f5, 3a, 9c, 2c, 10, aa] reset_token=[e6, 73, 6c, 7b, ea, e3, 7c, 50, 69, ef, 61, d0, 0e, 34, f3, 93]
[2025-12-13T09:41:40.468991015Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm RETIRE_CONNECTION_ID seq_num=0
[2025-12-13T09:41:40.469014309Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 154 bytes
[2025-12-13T09:41:40.469018677Z INFO  quiche_server] Retiring source CID 6637ba83754d13640dcbbc24accbddd14681f586
[2025-12-13T09:41:40.469030228Z TRACE quiche_server] got 57 bytes from [::ffff:193.167.0.100]:43888 to [::]:443
[2025-12-13T09:41:40.469034627Z TRACE quiche_server] got packet Short dcid=41edbb724d961d53d4eef1439e4ac4a169133776 key_phase=false
[2025-12-13T09:41:40.469045477Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx pkt Short dcid=41edbb724d961d53d4eef1439e4ac4a169133776 key_phase=false len=36 pn=3 
[2025-12-13T09:41:40.469052590Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-12-13T09:41:40.469057509Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 rx frm PADDING len=15
[2025-12-13T09:41:40.469064672Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 processed 57 bytes
[2025-12-13T09:41:40.469070353Z TRACE quiche_server] recv() would block
[2025-12-13T09:41:40.469074982Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 done writing
[2025-12-13T09:41:40.469110868Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:51.776060631Z TRACE quiche_server] timed out
[2025-12-13T09:41:51.776126283Z TRACE quiche] 6637ba83754d13640dcbbc24accbddd14681f586 draining timeout expired
[2025-12-13T09:41:51.776189100Z TRACE quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 done writing
[2025-12-13T09:41:51.776196002Z TRACE quiche_server] Collecting garbage
[2025-12-13T09:41:51.776206261Z INFO  quiche_server] 6637ba83754d13640dcbbc24accbddd14681f586 connection collected recv=9 sent=7 lost=0 retrans=1 sent_bytes=2888 recv_bytes=6661 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:43888 validation_state=Validated active=true recv=9 sent=7 lost=0 retrans=1 rtt=1.568524431s min_rtt=Some(1.505412074s) rttvar=549.616341ms cwnd=13500 sent_bytes=2888 recv_bytes=6661 lost_bytes=0 stream_retrans_bytes=123 pmtu=1350 delivery_rate=790]