[2025-12-14T03:32:42.835538852Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-12-14T03:32:42.835826878Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:54041 with scid 49bddeeb77ec83dd5f801fb923f02cf93cf625d5
[2025-12-14T03:32:42.835868656Z TRACE quiche::tls] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 write message lvl=Initial len=266
[2025-12-14T03:32:42.835886218Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Initial version=1 dcid=1c636deabb2cd62ccf931ddcec10ded9 scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=270 pn=0 
[2025-12-14T03:32:42.835890376Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm CRYPTO off=0 len=266
[2025-12-14T03:32:42.835905494Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=998.928668ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 340, tv_nsec: 900690728 }, first_sent_time: Instant { tv_sec: 340, tv_nsec: 900690728 }, 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: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 340, tv_nsec: 900690728 }, next_time: Instant { tv_sec: 340, tv_nsec: 900690728 }, 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-14T03:32:42.835969183Z TRACE quiche_apps::client] written 1200
[2025-12-14T03:32:43.107566336Z TRACE quiche_apps::client] got 1246 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:43.107608274Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Initial version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc token= len=116 pn=0 
[2025-12-14T03:32:43.107630626Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-12-14T03:32:43.107640925Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 packet newly acked 0
[2025-12-14T03:32:43.107651545Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=0 len=90
[2025-12-14T03:32:43.107810250Z TRACE quiche::tls] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 set write secret lvl=Handshake
[2025-12-14T03:32:43.107827062Z TRACE quiche::tls] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 set read secret lvl=Handshake
[2025-12-14T03:32:43.107871735Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1055 pn=0 
[2025-12-14T03:32:43.107883176Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=0 len=1034
[2025-12-14T03:32:43.107909575Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1246 bytes
[2025-12-14T03:32:43.107917189Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:43.107921528Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:43.107945993Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Initial version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=1 
[2025-12-14T03:32:43.107951553Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=42 blocks=[0..0] ecn_counts=None
[2025-12-14T03:32:43.107964477Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=814.935527ms latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 341, tv_nsec: 172758267 }, next_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, 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-14T03:32:43.108011415Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=2 
[2025-12-14T03:32:43.108016815Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=17 blocks=[0..0] ecn_counts=None
[2025-12-14T03:32:43.108025731Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=814.874934ms latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 341, tv_nsec: 172758267 }, next_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, 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-14T03:32:43.108048103Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 dropped epoch 0 state
[2025-12-14T03:32:43.108092706Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:43.108103576Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:43.114904948Z TRACE quiche_apps::client] got 1247 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:43.114923192Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1210 pn=1 
[2025-12-14T03:32:43.114935705Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=1034 len=1188
[2025-12-14T03:32:43.114957636Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1247 bytes
[2025-12-14T03:32:43.114964519Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:43.114969157Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:43.114983885Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=3 
[2025-12-14T03:32:43.114989375Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=7 blocks=[0..1] ecn_counts=None
[2025-12-14T03:32:43.115009042Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=808.224353ms latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 341, tv_nsec: 172758267 }, next_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, 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-14T03:32:43.115129406Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:43.115142009Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:43.125997334Z TRACE quiche_apps::client] got 1102 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:43.126013775Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1065 pn=2 
[2025-12-14T03:32:43.126024685Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=2222 len=1043
[2025-12-14T03:32:43.126045053Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1102 bytes
[2025-12-14T03:32:43.126050032Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:43.126053168Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:43.126063718Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=4 
[2025-12-14T03:32:43.126067405Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=6 blocks=[0..2] ecn_counts=None
[2025-12-14T03:32:43.126076301Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=797.157675ms latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 341, tv_nsec: 172758267 }, next_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, 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-14T03:32:43.126124030Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:43.126130342Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:43.925010850Z TRACE quiche_apps::client] timed out
[2025-12-14T03:32:43.925043281Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 loss detection timeout expired
[2025-12-14T03:32:43.925050184Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=1.630590543s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 341, tv_nsec: 172758267 }, next_time: Instant { tv_sec: 341, tv_nsec: 172758307 }, 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-14T03:32:43.925080280Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:43.925114042Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=9 pn=5 
[2025-12-14T03:32:43.925119162Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=99887 blocks=[0..2] ecn_counts=None
[2025-12-14T03:32:43.925125824Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm PING mtu_probe=None
[2025-12-14T03:32:43.925170047Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=1.630519701s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 62093, last_update: Instant { tv_sec: 341, tv_nsec: 989946464 }, next_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-14T03:32:43.925248303Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:43.925262289Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:45.557984934Z TRACE quiche_apps::client] timed out
[2025-12-14T03:32:45.558020991Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 loss detection timeout expired
[2025-12-14T03:32:45.558027974Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=1.628261133s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=63 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 62093, last_update: Instant { tv_sec: 341, tv_nsec: 989946464 }, next_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-14T03:32:45.558076203Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:45.558112381Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=9 pn=6 
[2025-12-14T03:32:45.558117831Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=304012 blocks=[0..2] ecn_counts=None
[2025-12-14T03:32:45.558124533Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm PING mtu_probe=None
[2025-12-14T03:32:45.558177582Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=3.261109361s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=126 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 62093, last_update: Instant { tv_sec: 343, tv_nsec: 622943690 }, next_time: Instant { tv_sec: 343, tv_nsec: 622943690 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-14T03:32:45.558270706Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:45.558290813Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=9 pn=7 
[2025-12-14T03:32:45.558297135Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=304034 blocks=[0..2] ecn_counts=None
[2025-12-14T03:32:45.558303226Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm PING mtu_probe=None
[2025-12-14T03:32:45.558316351Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=3.26116218s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=189 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 62093, last_update: Instant { tv_sec: 343, tv_nsec: 622943690 }, next_time: Instant { tv_sec: 343, tv_nsec: 622943690 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-14T03:32:45.558376192Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:45.558388625Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.822207912Z TRACE quiche_apps::client] timed out
[2025-12-14T03:32:48.822247055Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 loss detection timeout expired
[2025-12-14T03:32:48.822255410Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=3.25842317s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=189 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 62093, last_update: Instant { tv_sec: 343, tv_nsec: 622943690 }, next_time: Instant { tv_sec: 343, tv_nsec: 622943690 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-14T03:32:48.822287490Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.822323457Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=9 pn=8 
[2025-12-14T03:32:48.822330340Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=712038 blocks=[0..2] ecn_counts=None
[2025-12-14T03:32:48.822338425Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm PING mtu_probe=None
[2025-12-14T03:32:48.822359714Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.522339027s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=252 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 63, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 887155187 }, next_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-14T03:32:48.822467585Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.822487382Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=9 pn=9 
[2025-12-14T03:32:48.822493824Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=712059 blocks=[0..2] ecn_counts=None
[2025-12-14T03:32:48.822501117Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm PING mtu_probe=None
[2025-12-14T03:32:48.822515555Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.522359083s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=315 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 126, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 887155187 }, next_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, max_datagram_size: 1350, last_packet_size: Some(63), 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-14T03:32:48.822563704Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.822575887Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.859775720Z TRACE quiche_apps::client] got 1247 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.859818209Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1210 pn=3 
[2025-12-14T03:32:48.859839900Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=3265 len=1188
[2025-12-14T03:32:48.859880926Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1247 bytes
[2025-12-14T03:32:48.859891115Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.859896305Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.859916863Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=10 
[2025-12-14T03:32:48.859923906Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=12 blocks=[0..3] ecn_counts=None
[2025-12-14T03:32:48.859943272Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.484931466s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=315 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 8, last_sent_packet: 10, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 924759513 }, next_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.029214ms, 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-14T03:32:48.860023341Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.860036175Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.863715833Z TRACE quiche_apps::client] got 1247 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.863733807Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1210 pn=4 
[2025-12-14T03:32:48.863744486Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=4453 len=1188
[2025-12-14T03:32:48.863764213Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1247 bytes
[2025-12-14T03:32:48.863769112Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.863772208Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.863783940Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=11 
[2025-12-14T03:32:48.863787737Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=6 blocks=[0..4] ecn_counts=None
[2025-12-14T03:32:48.863826549Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.48104883s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=315 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 8, last_sent_packet: 11, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 924759513 }, next_time: Instant { tv_sec: 346, tv_nsec: 930661304 }, 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-14T03:32:48.863876342Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.863884236Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.864692669Z TRACE quiche_apps::client] got 1247 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.864705653Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1210 pn=5 
[2025-12-14T03:32:48.864713748Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=5641 len=1188
[2025-12-14T03:32:48.864726712Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1247 bytes
[2025-12-14T03:32:48.864731100Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.864739836Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.864749043Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=12 
[2025-12-14T03:32:48.864752520Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=5 blocks=[0..5] ecn_counts=None
[2025-12-14T03:32:48.864760344Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.480115646s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=315 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 8, last_sent_packet: 12, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 924759513 }, next_time: Instant { tv_sec: 346, tv_nsec: 930661304 }, 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-14T03:32:48.864796682Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.864802283Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.865714280Z TRACE quiche_apps::client] got 1247 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.865726333Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1210 pn=6 
[2025-12-14T03:32:48.865733977Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=6829 len=1188
[2025-12-14T03:32:48.865747302Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1247 bytes
[2025-12-14T03:32:48.865751690Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.865754545Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.865763202Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=13 
[2025-12-14T03:32:48.865766518Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=4 blocks=[0..6] ecn_counts=None
[2025-12-14T03:32:48.865773841Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.479102179s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=315 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 8, last_sent_packet: 13, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 924759513 }, next_time: Instant { tv_sec: 346, tv_nsec: 930661304 }, 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-14T03:32:48.865816040Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.865822662Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.866878167Z TRACE quiche_apps::client] got 1247 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.866889688Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=1210 pn=7 
[2025-12-14T03:32:48.866897182Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=8017 len=1188
[2025-12-14T03:32:48.866909165Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1247 bytes
[2025-12-14T03:32:48.866913323Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.866916158Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.866924343Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=14 
[2025-12-14T03:32:48.866927599Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=4 blocks=[0..7] ecn_counts=None
[2025-12-14T03:32:48.866934903Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.477941168s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=315 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 8, last_sent_packet: 14, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 924759513 }, next_time: Instant { tv_sec: 346, tv_nsec: 930661304 }, 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-14T03:32:48.866968255Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.866974326Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.868046011Z TRACE quiche_apps::client] got 809 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.868057212Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=772 pn=8 
[2025-12-14T03:32:48.868064115Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=9205 len=750
[2025-12-14T03:32:48.868609310Z TRACE quiche::tls] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 write message lvl=Handshake len=36
[2025-12-14T03:32:48.868615662Z TRACE quiche::tls] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 set write secret lvl=OneRTT
[2025-12-14T03:32:48.868624969Z TRACE quiche::tls] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 set read secret lvl=OneRTT
[2025-12-14T03:32:48.868639286Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(1c636deabb2cd62ccf931ddcec10ded9), max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1500, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 0, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 3, initial_source_connection_id: Some(da2fac87660727fc), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-12-14T03:32:48.868667518Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 809 bytes
[2025-12-14T03:32:48.868672507Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.868675333Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.868687626Z DEBUG quiche_apps::common] sending HTTP request "GET /aquamarine-black-ssd\r\n"
[2025-12-14T03:32:48.868750703Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=46 pn=15 
[2025-12-14T03:32:48.868755252Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=86 blocks=[0..8] ecn_counts=None
[2025-12-14T03:32:48.868759119Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm CRYPTO off=0 len=36
[2025-12-14T03:32:48.868769117Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.52237316s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=415 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 933598463 }, next_time: Instant { tv_sec: 346, tv_nsec: 930661304 }, max_datagram_size: 1350, last_packet_size: None, iv: 1.610487ms, 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-14T03:32:48.868789746Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Short dcid=da2fac87660727fc key_phase=false len=112 pn=16 
[2025-12-14T03:32:48.868793092Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[57, f7, 17, c5, 89, df, 57, df, b0, 47, 48, 2e, 9b, 4f, 8e, 98, 68, b6, d0, bc] reset_token=[3d, 40, f5, 06, ce, f5, 74, cc, c6, 83, 8e, a9, aa, 19, 4f, 03]
[2025-12-14T03:32:48.868799935Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[28, 62, 5b, 25, 8d, 08, 7f, 77, 53, 41, d0, 2e, 72, 82, 21, a6, 1f, 08, 9f, a2] reset_token=[72, 9a, c3, b8, 8b, e8, fb, fa, 0d, 8e, 7a, 89, 97, 57, 34, a8]
[2025-12-14T03:32:48.868806247Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm STREAM id=0 off=0 len=27 fin=true
[2025-12-14T03:32:48.868813440Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=6.522328878s latest_rtt=271.766669ms srtt=271.766669ms min_rtt=271.766669ms rttvar=135.883334ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=553 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, first_sent_time: Instant { tv_sec: 341, tv_nsec: 989946464 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 0, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 271.766669ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 340, tv_nsec: 900690728 }), send_elapsed: 0ns, ack_elapsed: 271.766669ms, rtt: 271.766669ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 138, rate: 62093, last_update: Instant { tv_sec: 346, tv_nsec: 933598463 }, next_time: Instant { tv_sec: 346, tv_nsec: 935208950 }, max_datagram_size: 1350, last_packet_size: Some(138), 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-14T03:32:48.868853224Z TRACE quiche_apps::client] written 238 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.868858864Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.872252943Z TRACE quiche_apps::client] got 58 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.872267330Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=22 pn=9 
[2025-12-14T03:32:48.872275826Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm ACK delay=0 blocks=[8..8] ecn_counts=None
[2025-12-14T03:32:48.872281716Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 packet newly acked 8
[2025-12-14T03:32:48.872287768Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 packet 5 lost on epoch 1
[2025-12-14T03:32:48.872291695Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 packet 6 lost on epoch 1
[2025-12-14T03:32:48.872295792Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 packet 7 lost on epoch 1
[2025-12-14T03:32:48.872321931Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 58 bytes
[2025-12-14T03:32:48.872327381Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.872330948Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.872342450Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Handshake version=1 dcid=da2fac87660727fc scid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 len=5 pn=17 
[2025-12-14T03:32:48.872346577Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=9 blocks=[3..9] ecn_counts=None
[2025-12-14T03:32:48.872355023Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=869.879495ms latest_rtt=49.965703ms srtt=244.041547ms min_rtt=49.965703ms rttvar=157.362741ms cwnd=9450 ssthresh=9450 bytes_in_flight=301 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 346, tv_nsec: 937120890 }) Rate { delivered: 396, delivered_time: Instant { tv_sec: 346, tv_nsec: 937120890 }, first_sent_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, end_of_app_limited: 16, last_sent_packet: 17, largest_acked: 8, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 4.947174426s, delivered: 63, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 341, tv_nsec: 989946464 }), send_elapsed: 4.897208723s, ack_elapsed: 4.947174426s, rtt: 49.965703ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 48403, last_update: Instant { tv_sec: 346, tv_nsec: 937191361 }, next_time: Instant { tv_sec: 346, tv_nsec: 935208950 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.851063ms, 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=1.9574338205844317 w_max=13500 } 
[2025-12-14T03:32:48.872406098Z TRACE quiche_apps::client] written 59 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.872414513Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.872844815Z TRACE quiche_apps::client] got 58 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.872855454Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Handshake version=1 dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 scid=da2fac87660727fc len=22 pn=10 
[2025-12-14T03:32:48.872863399Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm ACK delay=0 blocks=[8..9] ecn_counts=None
[2025-12-14T03:32:48.872868369Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 packet newly acked 9
[2025-12-14T03:32:48.872882575Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 58 bytes
[2025-12-14T03:32:48.872887754Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.872891241Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.872897002Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.908124341Z TRACE quiche_apps::client] got 224 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.908184403Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Short dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 key_phase=false len=203 pn=0 
[2025-12-14T03:32:48.908207185Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm HANDSHAKE_DONE
[2025-12-14T03:32:48.908220340Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 dropped epoch 1 state
[2025-12-14T03:32:48.908229357Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[fd, d2, 4f, 41, c6, 43, 30, fb] reset_token=[f2, b5, 76, 99, ef, ae, 63, 88, e3, ff, 99, 6f, 5f, 32, 05, 58]
[2025-12-14T03:32:48.908242030Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f9, a7, 0c, 5b, cb, 3c, 35, 17] reset_token=[db, 2c, 5a, 00, f3, ba, 56, bb, 5a, d3, ff, 66, 9d, 18, f1, 6d]
[2025-12-14T03:32:48.908251558Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[2d, 14, 25, 6e, dd, 73, 64, df] reset_token=[ee, 9f, c6, 0d, ad, 53, ab, cf, d8, 51, 5c, 85, 0d, 8d, 18, 66]
[2025-12-14T03:32:48.908260545Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 14, 6c, 84, fd, 71, 59, 8e] reset_token=[3b, d3, 70, 10, e3, ad, 57, 98, 79, f6, bd, 5c, 03, 73, 6a, 0c]
[2025-12-14T03:32:48.908270333Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[89, a2, 35, 0e, 96, ef, e7, 7c] reset_token=[b2, de, f5, f4, 0c, c8, 87, 65, 77, 8f, 5c, 49, 33, 3f, 7e, 7c]
[2025-12-14T03:32:48.908279169Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm CRYPTO off=0 len=42
[2025-12-14T03:32:48.908339552Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 224 bytes
[2025-12-14T03:32:48.908347927Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.908352896Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.908372613Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Short dcid=da2fac87660727fc key_phase=false len=5 pn=18 
[2025-12-14T03:32:48.908379296Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=23 blocks=[0..0] ecn_counts=None
[2025-12-14T03:32:48.908394294Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=870.778743ms latest_rtt=50.554008ms srtt=219.855604ms min_rtt=49.965703ms rttvar=166.393939ms cwnd=9450 ssthresh=9450 bytes_in_flight=138 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 346, tv_nsec: 937120890 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 346, tv_nsec: 937709195 }, first_sent_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 9, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 4.947762731s, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 341, tv_nsec: 989946464 }), send_elapsed: 4.897208723s, ack_elapsed: 4.947762731s, rtt: 50.554008ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 53728, last_update: Instant { tv_sec: 346, tv_nsec: 937191361 }, next_time: Instant { tv_sec: 346, tv_nsec: 976067419 }, 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=1.9574338205844317 w_max=13500 } 
[2025-12-14T03:32:48.908456740Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.908463001Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.916220162Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.916236463Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Short dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 key_phase=false len=1231 pn=1 
[2025-12-14T03:32:48.916247393Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm STREAM id=0 off=0 len=1209 fin=false
[2025-12-14T03:32:48.916259345Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1252 bytes
[2025-12-14T03:32:48.916264795Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.916268462Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.916275515Z TRACE quiche_apps::common] received 1209 bytes
[2025-12-14T03:32:48.916279312Z TRACE quiche_apps::common] stream 0 has 1209 bytes (fin? false)
[2025-12-14T03:32:48.916291335Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Short dcid=da2fac87660727fc key_phase=false len=5 pn=19 
[2025-12-14T03:32:48.916295863Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=6 blocks=[0..1] ecn_counts=None
[2025-12-14T03:32:48.916324086Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=862.849041ms latest_rtt=50.554008ms srtt=219.855604ms min_rtt=49.965703ms rttvar=166.393939ms cwnd=9450 ssthresh=9450 bytes_in_flight=138 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 346, tv_nsec: 937120890 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 346, tv_nsec: 937709195 }, first_sent_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, end_of_app_limited: 17, last_sent_packet: 19, largest_acked: 9, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 4.947762731s, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 341, tv_nsec: 989946464 }), send_elapsed: 4.897208723s, ack_elapsed: 4.947762731s, rtt: 50.554008ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 53728, last_update: Instant { tv_sec: 346, tv_nsec: 937191361 }, next_time: Instant { tv_sec: 346, tv_nsec: 976067419 }, 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=1.9574338205844317 w_max=13500 } 
[2025-12-14T03:32:48.916375592Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.916383717Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.917202020Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.917219893Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Short dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 key_phase=false len=1231 pn=2 
[2025-12-14T03:32:48.917227668Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm STREAM id=0 off=1209 len=1208 fin=false
[2025-12-14T03:32:48.917236164Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1252 bytes
[2025-12-14T03:32:48.917240421Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.917243257Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.917247926Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:32:48.917250731Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:32:48.917258886Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Short dcid=da2fac87660727fc key_phase=false len=5 pn=20 
[2025-12-14T03:32:48.917262082Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=4 blocks=[0..2] ecn_counts=None
[2025-12-14T03:32:48.917269255Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=861.904222ms latest_rtt=50.554008ms srtt=219.855604ms min_rtt=49.965703ms rttvar=166.393939ms cwnd=9450 ssthresh=9450 bytes_in_flight=138 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 346, tv_nsec: 937120890 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 346, tv_nsec: 937709195 }, first_sent_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, end_of_app_limited: 17, last_sent_packet: 20, largest_acked: 9, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 4.947762731s, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 341, tv_nsec: 989946464 }), send_elapsed: 4.897208723s, ack_elapsed: 4.947762731s, rtt: 50.554008ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 53728, last_update: Instant { tv_sec: 346, tv_nsec: 937191361 }, next_time: Instant { tv_sec: 346, tv_nsec: 976067419 }, 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=1.9574338205844317 w_max=13500 } 
[2025-12-14T03:32:48.917308608Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.917317315Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.918242657Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.918256192Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Short dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 key_phase=false len=1231 pn=3 
[2025-12-14T03:32:48.918264458Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm STREAM id=0 off=2417 len=1208 fin=false
[2025-12-14T03:32:48.918273355Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1252 bytes
[2025-12-14T03:32:48.918277663Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.918280538Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.918285267Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:32:48.918288122Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:32:48.918296708Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Short dcid=da2fac87660727fc key_phase=false len=5 pn=21 
[2025-12-14T03:32:48.918299984Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=5 blocks=[0..3] ecn_counts=None
[2025-12-14T03:32:48.918307879Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=860.865629ms latest_rtt=50.554008ms srtt=219.855604ms min_rtt=49.965703ms rttvar=166.393939ms cwnd=9450 ssthresh=9450 bytes_in_flight=138 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 346, tv_nsec: 937120890 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 346, tv_nsec: 937709195 }, first_sent_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, end_of_app_limited: 17, last_sent_packet: 21, largest_acked: 9, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 4.947762731s, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 341, tv_nsec: 989946464 }), send_elapsed: 4.897208723s, ack_elapsed: 4.947762731s, rtt: 50.554008ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 53728, last_update: Instant { tv_sec: 346, tv_nsec: 937191361 }, next_time: Instant { tv_sec: 346, tv_nsec: 976067419 }, 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=1.9574338205844317 w_max=13500 } 
[2025-12-14T03:32:48.918353454Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.918362611Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.919243510Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.919252207Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Short dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 key_phase=false len=1231 pn=4 
[2025-12-14T03:32:48.919259099Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm STREAM id=0 off=3625 len=1208 fin=false
[2025-12-14T03:32:48.919266864Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 1252 bytes
[2025-12-14T03:32:48.919271032Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.919273847Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.919278275Z TRACE quiche_apps::common] received 1208 bytes
[2025-12-14T03:32:48.919280950Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-12-14T03:32:48.919288394Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Short dcid=da2fac87660727fc key_phase=false len=5 pn=22 
[2025-12-14T03:32:48.919291580Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm ACK delay=4 blocks=[0..4] ecn_counts=None
[2025-12-14T03:32:48.919298202Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=859.875405ms latest_rtt=50.554008ms srtt=219.855604ms min_rtt=49.965703ms rttvar=166.393939ms cwnd=9450 ssthresh=9450 bytes_in_flight=138 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 346, tv_nsec: 937120890 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 346, tv_nsec: 937709195 }, first_sent_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, end_of_app_limited: 17, last_sent_packet: 22, largest_acked: 9, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 4.947762731s, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 341, tv_nsec: 989946464 }), send_elapsed: 4.897208723s, ack_elapsed: 4.947762731s, rtt: 50.554008ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 53728, last_update: Instant { tv_sec: 346, tv_nsec: 937191361 }, next_time: Instant { tv_sec: 346, tv_nsec: 976067419 }, 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=1.9574338205844317 w_max=13500 } 
[2025-12-14T03:32:48.919333678Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.919342314Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.919505216Z TRACE quiche_apps::client] got 334 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.919516627Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx pkt Short dcid=49bddeeb77ec83dd5f801fb923f02cf93cf625d5 key_phase=false len=313 pn=5 
[2025-12-14T03:32:48.919524572Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm STREAM id=0 off=4833 len=287 fin=true
[2025-12-14T03:32:48.919529230Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 rx frm MAX_STREAMS type=bidi max=101
[2025-12-14T03:32:48.919538147Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 334 bytes
[2025-12-14T03:32:48.919542415Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.919545291Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.919550310Z TRACE quiche_apps::common] received 287 bytes
[2025-12-14T03:32:48.919553315Z TRACE quiche_apps::common] stream 0 has 287 bytes (fin? true)
[2025-12-14T03:32:48.919556171Z DEBUG quiche_apps::common] 1/1 responses received
[2025-12-14T03:32:48.919559026Z INFO  quiche_apps::common] 1/1 response(s) received in 6.083583231s, closing...
[2025-12-14T03:32:48.919567442Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx pkt Short dcid=da2fac87660727fc key_phase=false len=10 pn=23 
[2025-12-14T03:32:48.919570908Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-12-14T03:32:48.919579735Z TRACE quiche::recovery::congestion::recovery] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 timer=910.414499ms latest_rtt=50.554008ms srtt=219.855604ms min_rtt=49.965703ms rttvar=166.393939ms cwnd=9450 ssthresh=9450 bytes_in_flight=174 app_limited=true congestion_recovery_start_time=Some(Instant { tv_sec: 346, tv_nsec: 937120890 }) Rate { delivered: 459, delivered_time: Instant { tv_sec: 346, tv_nsec: 937709195 }, first_sent_time: Instant { tv_sec: 346, tv_nsec: 887155187 }, end_of_app_limited: 22, last_sent_packet: 23, largest_acked: 9, rate_sample: RateSample { bandwidth: 9.80 Kbps, is_app_limited: true, interval: 4.947762731s, delivered: 126, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 341, tv_nsec: 989946464 }), send_elapsed: 4.897208723s, ack_elapsed: 4.947762731s, rtt: 50.554008ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 53728, last_update: Instant { tv_sec: 346, tv_nsec: 937191361 }, next_time: Instant { tv_sec: 346, tv_nsec: 976067419 }, 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=1.9574338205844317 w_max=13500 } 
[2025-12-14T03:32:48.919619128Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:54041 to 193.167.100.100:443
[2025-12-14T03:32:48.919623847Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.932534784Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.932547597Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 44 bytes
[2025-12-14T03:32:48.932552056Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.932555041Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.932560541Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:48.955756992Z TRACE quiche_apps::client] got 42 bytes from 193.167.100.100:443 to 0.0.0.0:54041
[2025-12-14T03:32:48.955787188Z TRACE quiche_apps::client] 0.0.0.0:54041: processed 42 bytes
[2025-12-14T03:32:48.955793911Z TRACE quiche_apps::client] 0.0.0.0:54041: recv() would block
[2025-12-14T03:32:48.955797988Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:48.955806495Z TRACE quiche_apps::client] 0.0.0.0:54041 -> 193.167.100.100:443: done writing
[2025-12-14T03:32:51.579495955Z TRACE quiche_apps::client] timed out
[2025-12-14T03:32:51.579530199Z TRACE quiche] 49bddeeb77ec83dd5f801fb923f02cf93cf625d5 draining timeout expired
[2025-12-14T03:32:51.579575654Z TRACE quiche_apps::client] done reading
[2025-12-14T03:32:51.579583198Z INFO  quiche_apps::client] connection closed, recv=18 sent=24 lost=6 retrans=0 sent_bytes=1668 recv_bytes=16321 lost_bytes=189 [local_addr=0.0.0.0:54041 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=18 sent=24 lost=6 retrans=0 rtt=219.855604ms min_rtt=Some(49.965703ms) rttvar=166.393939ms cwnd=9450 sent_bytes=1668 recv_bytes=16321 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=1225]