[2025-04-30T18:34:24.576885261Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T18:34:24.577173140Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:43158 with scid 8c6e05649d388f484baf2f395d6a1a2638252d91
[2025-04-30T18:34:24.577225969Z TRACE quiche::tls] 8c6e05649d388f484baf2f395d6a1a2638252d91 write message lvl=Initial len=617
[2025-04-30T18:34:24.577246528Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Initial version=1 dcid=53125680adf2c37d71ecee00b396c0cc scid=8c6e05649d388f484baf2f395d6a1a2638252d91 len=621 pn=0 
[2025-04-30T18:34:24.577249674Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm CRYPTO off=0 len=617
[2025-04-30T18:34:24.577264070Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=998.916103ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=684 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 326, tv_nsec: 46613756 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 46613756 }, end_of_app_limited: 1, last_sent_packet: 0, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 326, tv_nsec: 46613756 }, next_time: Instant { tv_sec: 326, tv_nsec: 46613756 }, max_datagram_size: 1350, last_packet_size: None, iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.577367103Z TRACE quiche_apps::client] written 1200
[2025-04-30T18:34:24.610375082Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.610398295Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Initial version=1 dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 scid=0237d78ad14bda73cd token= len=125 pn=0 
[2025-04-30T18:34:24.610410508Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm ACK delay=1 blocks=[0..0] ecn_counts=None
[2025-04-30T18:34:24.610417071Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 packet newly acked 0
[2025-04-30T18:34:24.610422972Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm CRYPTO off=0 len=96
[2025-04-30T18:34:24.610521526Z TRACE quiche::tls] 8c6e05649d388f484baf2f395d6a1a2638252d91 set write secret lvl=Handshake
[2025-04-30T18:34:24.610534400Z TRACE quiche::tls] 8c6e05649d388f484baf2f395d6a1a2638252d91 set read secret lvl=Handshake
[2025-04-30T18:34:24.610562102Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Handshake version=1 dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 scid=0237d78ad14bda73cd len=1018 pn=1 
[2025-04-30T18:34:24.610591788Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm CRYPTO off=0 len=191
[2025-04-30T18:34:24.610629619Z TRACE quiche::tls] 8c6e05649d388f484baf2f395d6a1a2638252d91 write message lvl=Handshake len=52
[2025-04-30T18:34:24.610633095Z TRACE quiche::tls] 8c6e05649d388f484baf2f395d6a1a2638252d91 set write secret lvl=OneRTT
[2025-04-30T18:34:24.610645658Z TRACE quiche::tls] 8c6e05649d388f484baf2f395d6a1a2638252d91 set read secret lvl=OneRTT
[2025-04-30T18:34:24.610665736Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 connection established: proto=Ok("hq-interop") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=true TransportParams { original_destination_connection_id: Some(53125680adf2c37d71ecee00b396c0cc), max_idle_timeout: 30000, stateless_reset_token: Some(269544542130125928001829536050680567252), max_udp_payload_size: 1472, initial_max_data: 16777216, initial_max_stream_data_bidi_local: 65536, initial_max_stream_data_bidi_remote: 65536, initial_max_stream_data_uni: 65536, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 8, max_ack_delay: 26, disable_active_migration: false, active_conn_id_limit: 4, initial_source_connection_id: Some(0237d78ad14bda73cd), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:34:24.610690152Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm PADDING len=803
[2025-04-30T18:34:24.610705390Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.610711071Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.610714307Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.610723284Z DEBUG quiche_apps::common] sending HTTP request "GET /qwpxjmjmkp\r\n"
[2025-04-30T18:34:24.610805337Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Initial version=1 dcid=0237d78ad14bda73cd scid=8c6e05649d388f484baf2f395d6a1a2638252d91 len=5 pn=1 
[2025-04-30T18:34:24.610809525Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=51 blocks=[0..0] ecn_counts=None
[2025-04-30T18:34:24.610817660Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=99.215623ms latest_rtt=33.213223ms srtt=33.213223ms min_rtt=33.213223ms rttvar=16.606611ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 684, delivered_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 20594, is_app_limited: true, interval: 33.213223ms, delivered: 684, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 46613756 }), send_elapsed: 0ns, ack_elapsed: 33.213223ms, rtt: 33.213223ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 508080, last_update: Instant { tv_sec: 326, tv_nsec: 80096403 }, next_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.610836395Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Handshake version=1 dcid=0237d78ad14bda73cd scid=8c6e05649d388f484baf2f395d6a1a2638252d91 len=61 pn=2 
[2025-04-30T18:34:24.610839141Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=34 blocks=[1..1] ecn_counts=None
[2025-04-30T18:34:24.610841796Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm CRYPTO off=0 len=52
[2025-04-30T18:34:24.610847516Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=99.589834ms latest_rtt=33.213223ms srtt=33.213223ms min_rtt=33.213223ms rttvar=16.606611ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=116 app_limited=true congestion_recovery_start_time=None Rate { delivered: 684, delivered_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 20594, is_app_limited: true, interval: 33.213223ms, delivered: 684, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 46613756 }), send_elapsed: 0ns, ack_elapsed: 33.213223ms, rtt: 33.213223ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 508080, last_update: Instant { tv_sec: 326, tv_nsec: 80230725 }, next_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, max_datagram_size: 1350, last_packet_size: None, iv: 228.311µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.610863557Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 dropped epoch 0 state
[2025-04-30T18:34:24.610868005Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13384 out_len=1173 left=1146
[2025-04-30T18:34:24.610879396Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=1146 pn=3 
[2025-04-30T18:34:24.610882091Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[d4, 88, 82, 23, a2, 11, a4, 0c, 9f, cc, 8c, c6, 4b, b1, ff, 0f, 08, bb, 08, 9b] reset_token=[86, ee, 7b, c5, bd, 22, 13, a6, d5, 93, cb, ee, 44, a4, 0b, ed]
[2025-04-30T18:34:24.610889395Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[43, db, 3e, 52, e2, a7, 83, 0e, 5f, 94, e2, 0b, 9f, 0d, b9, ab, e8, 52, 72, 0a] reset_token=[be, 6d, 62, bb, 8f, bd, e8, fc, fd, 7b, 2f, 9b, 0a, bd, cf, 20]
[2025-04-30T18:34:24.610894885Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[35, bd, 36, e2, 5c, 7d, f2, be, a3, 85, e5, 6b, 4a, 93, 6c, 46, 4a, 7c, 38, d5] reset_token=[e5, 36, 3e, 73, a8, db, 38, 87, 76, 6d, ac, 7c, 0b, 22, ce, 79]
[2025-04-30T18:34:24.610900055Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T18:34:24.610902570Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm PADDING len=1004
[2025-04-30T18:34:24.610909913Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=99.527427ms latest_rtt=33.213223ms srtt=33.213223ms min_rtt=33.213223ms rttvar=16.606611ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1289 app_limited=true congestion_recovery_start_time=None Rate { delivered: 684, delivered_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 80096443 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 20594, is_app_limited: true, interval: 33.213223ms, delivered: 684, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 46613756 }), send_elapsed: 0ns, ack_elapsed: 33.213223ms, rtt: 33.213223ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1173, rate: 508080, last_update: Instant { tv_sec: 326, tv_nsec: 80230725 }, next_time: Instant { tv_sec: 326, tv_nsec: 80459036 }, max_datagram_size: 1350, last_packet_size: Some(1173), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.610953274Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.610958314Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.643605036Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.643625044Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=2 
[2025-04-30T18:34:24.643636335Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm ACK delay=0 blocks=[3..3] ecn_counts=None
[2025-04-30T18:34:24.643640893Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 packet newly acked 3
[2025-04-30T18:34:24.643645873Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm HANDSHAKE_DONE
[2025-04-30T18:34:24.643658406Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 dropped epoch 1 state
[2025-04-30T18:34:24.643664037Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[02, 37, 2a, 12, 63, bb, bb, 9f, d2] reset_token=[9f, 9d, 02, a8, 7f, 80, 5e, ae, 5a, 0f, 17, b8, cd, ce, 46, 6d]
[2025-04-30T18:34:24.643671320Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[02, 37, 49, 3c, a6, 3a, 83, 14, fd] reset_token=[bd, 8c, 4d, 21, 9c, f8, 5d, c0, 6e, 2a, 3a, 8c, 29, a6, 5f, cd]
[2025-04-30T18:34:24.643676710Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[02, 37, 8c, a0, 57, 2f, b2, d5, b7] reset_token=[03, 88, e0, 49, 3f, e1, 20, 98, e2, 35, 20, aa, 61, e0, 11, 2f]
[2025-04-30T18:34:24.643684144Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm PADDING len=1086
[2025-04-30T18:34:24.643700605Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.643704913Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.643707688Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.643716034Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.643719911Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=4 
[2025-04-30T18:34:24.643722406Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=11 blocks=[2..2] ecn_counts=None
[2025-04-30T18:34:24.643729669Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.643776127Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.643780986Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.644604477Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.644619575Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=3 
[2025-04-30T18:34:24.644628562Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm PING mtu_probe=None
[2025-04-30T18:34:24.644637058Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm PADDING len=1178
[2025-04-30T18:34:24.644645855Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.644650493Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.644653509Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.644662085Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.644673547Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=5 
[2025-04-30T18:34:24.644676903Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=5 blocks=[2..3] ecn_counts=None
[2025-04-30T18:34:24.644685419Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.644733749Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.644739490Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.644821974Z TRACE quiche_apps::client] got 366 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.644827725Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=345 pn=4 
[2025-04-30T18:34:24.644835019Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm CRYPTO off=0 len=321
[2025-04-30T18:34:24.644863903Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 366 bytes
[2025-04-30T18:34:24.644867930Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.644870375Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.644876296Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.644879482Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=6 
[2025-04-30T18:34:24.644881866Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=6 blocks=[2..4] ecn_counts=None
[2025-04-30T18:34:24.644887948Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.644927412Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.644932140Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.645866029Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.645873533Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=5 
[2025-04-30T18:34:24.645879975Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=0 len=1175 fin=false
[2025-04-30T18:34:24.645886547Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.645889984Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.645892358Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.645896846Z TRACE quiche_apps::common] received 1175 bytes
[2025-04-30T18:34:24.645899461Z TRACE quiche_apps::common] stream 0 has 1175 bytes (fin? false)
[2025-04-30T18:34:24.645905392Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.645908418Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=7 
[2025-04-30T18:34:24.645910783Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=4 blocks=[2..5] ecn_counts=None
[2025-04-30T18:34:24.645916483Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.645947962Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.645951839Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.646869627Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.646877131Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=6 
[2025-04-30T18:34:24.646883333Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=1175 len=1173 fin=false
[2025-04-30T18:34:24.646911416Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.646915172Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.646920232Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.646924510Z TRACE quiche_apps::common] received 1173 bytes
[2025-04-30T18:34:24.646926764Z TRACE quiche_apps::common] stream 0 has 1173 bytes (fin? false)
[2025-04-30T18:34:24.646932926Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.646935891Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=8 
[2025-04-30T18:34:24.646938236Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=7 blocks=[2..6] ecn_counts=None
[2025-04-30T18:34:24.646944578Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.646976287Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.646980134Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.647866143Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.647873076Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=7 
[2025-04-30T18:34:24.647879428Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=2348 len=1173 fin=false
[2025-04-30T18:34:24.647886000Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.647889396Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.647891731Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.647895508Z TRACE quiche_apps::common] received 1173 bytes
[2025-04-30T18:34:24.647897672Z TRACE quiche_apps::common] stream 0 has 1173 bytes (fin? false)
[2025-04-30T18:34:24.647903192Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.647906038Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=9 
[2025-04-30T18:34:24.647908352Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=3 blocks=[2..7] ecn_counts=None
[2025-04-30T18:34:24.647914022Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.647950361Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.647954298Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.648864982Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.648871845Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=8 
[2025-04-30T18:34:24.648877706Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=3521 len=1173 fin=false
[2025-04-30T18:34:24.648883888Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.648887294Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.648889659Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.648893466Z TRACE quiche_apps::common] received 1173 bytes
[2025-04-30T18:34:24.648895600Z TRACE quiche_apps::common] stream 0 has 1173 bytes (fin? false)
[2025-04-30T18:34:24.648901261Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.648904156Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=10 
[2025-04-30T18:34:24.648906530Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=3 blocks=[2..8] ecn_counts=None
[2025-04-30T18:34:24.648912331Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.648943249Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.648946996Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.649844283Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.649858108Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=9 
[2025-04-30T18:34:24.649875180Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=4694 len=1173 fin=false
[2025-04-30T18:34:24.649885570Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.649890900Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.649894476Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.649900217Z TRACE quiche_apps::common] received 1173 bytes
[2025-04-30T18:34:24.649903453Z TRACE quiche_apps::common] stream 0 has 1173 bytes (fin? false)
[2025-04-30T18:34:24.649912340Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.649917469Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=11 
[2025-04-30T18:34:24.649921006Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=6 blocks=[2..9] ecn_counts=None
[2025-04-30T18:34:24.649930333Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.649976840Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.649983323Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.650875302Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.650886483Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=10 
[2025-04-30T18:34:24.650893917Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=5867 len=1173 fin=false
[2025-04-30T18:34:24.650901892Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.650905659Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.650908044Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.650912372Z TRACE quiche_apps::common] received 1173 bytes
[2025-04-30T18:34:24.650914686Z TRACE quiche_apps::common] stream 0 has 1173 bytes (fin? false)
[2025-04-30T18:34:24.650921599Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.650925055Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=12 
[2025-04-30T18:34:24.650927560Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=4 blocks=[2..10] ecn_counts=None
[2025-04-30T18:34:24.650934423Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 12, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.650980619Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.650988845Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.651859435Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.651867851Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=11 
[2025-04-30T18:34:24.651874323Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=7040 len=1173 fin=false
[2025-04-30T18:34:24.651881376Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.651884912Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.651887237Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.651891194Z TRACE quiche_apps::common] received 1173 bytes
[2025-04-30T18:34:24.651893438Z TRACE quiche_apps::common] stream 0 has 1173 bytes (fin? false)
[2025-04-30T18:34:24.651914398Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.651917804Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=13 
[2025-04-30T18:34:24.651920238Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=5 blocks=[2..11] ecn_counts=None
[2025-04-30T18:34:24.651926430Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 13, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.651966665Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.651975512Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.652863284Z TRACE quiche_apps::client] got 1220 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.652872090Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=1199 pn=12 
[2025-04-30T18:34:24.652878612Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=8213 len=1173 fin=false
[2025-04-30T18:34:24.652885626Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1220 bytes
[2025-04-30T18:34:24.652889152Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.652891587Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.652895584Z TRACE quiche_apps::common] received 1173 bytes
[2025-04-30T18:34:24.652897828Z TRACE quiche_apps::common] stream 0 has 1173 bytes (fin? false)
[2025-04-30T18:34:24.652904030Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1318
[2025-04-30T18:34:24.652907066Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=5 pn=14 
[2025-04-30T18:34:24.652909380Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm ACK delay=4 blocks=[2..12] ecn_counts=None
[2025-04-30T18:34:24.652915291Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=none latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 3, last_sent_packet: 14, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 113144397 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: Some(0), iv: 0ns, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.652950517Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.652957801Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.653614019Z TRACE quiche_apps::client] got 901 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.653622024Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx pkt Short dcid=8c6e05649d388f484baf2f395d6a1a2638252d91 key_phase=false len=880 pn=13 
[2025-04-30T18:34:24.653628055Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 rx frm STREAM id=0 off=9386 len=854 fin=true
[2025-04-30T18:34:24.653635039Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 901 bytes
[2025-04-30T18:34:24.653638505Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.653640849Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.653646590Z TRACE quiche_apps::common] received 854 bytes
[2025-04-30T18:34:24.653648824Z TRACE quiche_apps::common] stream 0 has 854 bytes (fin? true)
[2025-04-30T18:34:24.653651149Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T18:34:24.653658563Z INFO  quiche_apps::common] 1/1 response(s) received in 76.287762ms, closing...
[2025-04-30T18:34:24.653665205Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1323
[2025-04-30T18:34:24.653668702Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx pkt Short dcid=0237d78ad14bda73cd key_phase=false len=10 pn=15 
[2025-04-30T18:34:24.653671096Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T18:34:24.653678280Z TRACE quiche::recovery::congestion::recovery] 8c6e05649d388f484baf2f395d6a1a2638252d91 timer=109.557815ms latest_rtt=32.595473ms srtt=33.136004ms min_rtt=32.595473ms rttvar=12.609395ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1857, delivered_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, first_sent_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 3, rate_sample: RateSample { delivery_rate: 35590, is_app_limited: true, interval: 32.958066ms, delivered: 1173, prior_delivered: 684, prior_time: Some(Instant { tv_sec: 326, tv_nsec: 80096443 }), send_elapsed: 362.593µs, ack_elapsed: 32.958066ms, rtt: 32.595473ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 509264, last_update: Instant { tv_sec: 326, tv_nsec: 123095602 }, next_time: Instant { tv_sec: 326, tv_nsec: 113144397 }, max_datagram_size: 1350, last_packet_size: None, iv: 72.654µs, max_pacing_rate: None } hystart=window_end=Some(0) last_round_min_rtt=18446744073709551615.999999999s current_round_min_rtt=18446744073709551615.999999999s css_baseline_min_rtt=18446744073709551615.999999999s rtt_sample_count=0 css_start_time=None css_round_count=0 cubic={ k=0 w_max=0 } 
[2025-04-30T18:34:24.653715169Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:43158 to 193.167.100.100:443
[2025-04-30T18:34:24.653721641Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.674347959Z TRACE quiche_apps::client] got 162 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.674362737Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 162 bytes
[2025-04-30T18:34:24.674367106Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.674370361Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.674375241Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.676079731Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.676086103Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 1252 bytes
[2025-04-30T18:34:24.676090221Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.676093046Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.676096833Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.684116979Z TRACE quiche_apps::client] got 57 bytes from 193.167.100.100:443 to 0.0.0.0:43158
[2025-04-30T18:34:24.684126146Z TRACE quiche_apps::client] 0.0.0.0:43158: processed 57 bytes
[2025-04-30T18:34:24.684130253Z TRACE quiche_apps::client] 0.0.0.0:43158: recv() would block
[2025-04-30T18:34:24.684133179Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.684137417Z TRACE quiche_apps::client] 0.0.0.0:43158 -> 193.167.100.100:443: done writing
[2025-04-30T18:34:24.905414139Z TRACE quiche_apps::client] timed out
[2025-04-30T18:34:24.905447992Z TRACE quiche] 8c6e05649d388f484baf2f395d6a1a2638252d91 draining timeout expired
[2025-04-30T18:34:24.905499880Z TRACE quiche_apps::client] done reading
[2025-04-30T18:34:24.905505400Z INFO  quiche_apps::client] connection closed, recv=14 sent=16 lost=0 retrans=0 sent_bytes=2423 recv_bytes=14687 lost_bytes=0 [local_addr=0.0.0.0:43158 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=14 sent=16 lost=0 retrans=0 rtt=33.136004ms min_rtt=Some(32.595473ms) rttvar=12.609395ms cwnd=13500 sent_bytes=2423 recv_bytes=14687 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=35590]