[2025-04-30T19:57:20.265065987Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T19:57:20.265359026Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:47123 with scid 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d
[2025-04-30T19:57:20.265400905Z TRACE quiche::tls] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d write message lvl=Initial len=266
[2025-04-30T19:57:20.265416714Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Initial version=1 dcid=d68f88ec08b9aa487afd4046b2909ee3 scid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d len=270 pn=0 
[2025-04-30T19:57:20.265419800Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm CRYPTO off=0 len=266
[2025-04-30T19:57:20.265433406Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=998.934087ms 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: 237, tv_nsec: 601203156 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 601203156 }, 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: 237, tv_nsec: 601203156 }, next_time: Instant { tv_sec: 237, tv_nsec: 601203156 }, 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-30T19:57:20.265493629Z TRACE quiche_apps::client] written 1200
[2025-04-30T19:57:20.304111784Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.304129348Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Initial version=1 dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d scid=eab85642ddf768f035cdff0b token= len=1158 pn=0 
[2025-04-30T19:57:20.304141701Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm CRYPTO off=0 len=90
[2025-04-30T19:57:20.304277525Z TRACE quiche::tls] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d set write secret lvl=Handshake
[2025-04-30T19:57:20.304288766Z TRACE quiche::tls] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d set read secret lvl=Handshake
[2025-04-30T19:57:20.304303824Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm PADDING len=1044
[2025-04-30T19:57:20.304323662Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 1200 bytes
[2025-04-30T19:57:20.304331596Z TRACE quiche_apps::client] got 211 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.304336606Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Handshake version=1 dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d scid=eab85642ddf768f035cdff0b len=170 pn=0 
[2025-04-30T19:57:20.304343309Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm CRYPTO off=0 len=146
[2025-04-30T19:57:20.304361993Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 211 bytes
[2025-04-30T19:57:20.304367173Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.304370409Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.304396208Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Initial version=1 dcid=eab85642ddf768f035cdff0b scid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d len=5 pn=1 
[2025-04-30T19:57:20.304399975Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=33 blocks=[0..0] ecn_counts=None
[2025-04-30T19:57:20.304410424Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=959.957178ms 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: 237, tv_nsec: 601203156 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 601203156 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 237, tv_nsec: 640223937 }, next_time: Instant { tv_sec: 237, tv_nsec: 640223937 }, 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-30T19:57:20.304438056Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Handshake version=1 dcid=eab85642ddf768f035cdff0b scid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d len=5 pn=2 
[2025-04-30T19:57:20.304441763Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=12 blocks=[0..0] ecn_counts=None
[2025-04-30T19:57:20.304448586Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=959.919187ms 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: 237, tv_nsec: 601203156 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 601203156 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 237, tv_nsec: 640223937 }, next_time: Instant { tv_sec: 237, tv_nsec: 640223937 }, 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-30T19:57:20.304461460Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d dropped epoch 0 state
[2025-04-30T19:57:20.304507687Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.304519339Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.304736716Z TRACE quiche_apps::client] got 536 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.304743669Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Handshake version=1 dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d scid=eab85642ddf768f035cdff0b len=495 pn=1 
[2025-04-30T19:57:20.304751854Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm CRYPTO off=146 len=470
[2025-04-30T19:57:20.304819181Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 536 bytes
[2025-04-30T19:57:20.304825633Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.304828949Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.304840090Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Handshake version=1 dcid=eab85642ddf768f035cdff0b scid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d len=5 pn=3 
[2025-04-30T19:57:20.304843496Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=12 blocks=[0..1] ecn_counts=None
[2025-04-30T19:57:20.304852182Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=998.536291ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 640668570 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 640668570 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 237, tv_nsec: 640668570 }, next_time: Instant { tv_sec: 237, tv_nsec: 640668570 }, 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-30T19:57:20.304907796Z TRACE quiche_apps::client] written 63 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.304918707Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.304932944Z TRACE quiche_apps::client] got 146 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.304940949Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Handshake version=1 dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d scid=eab85642ddf768f035cdff0b len=105 pn=2 
[2025-04-30T19:57:20.304951509Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm CRYPTO off=616 len=80
[2025-04-30T19:57:20.305132438Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 146 bytes
[2025-04-30T19:57:20.305141745Z TRACE quiche_apps::client] got 101 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.305146684Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Handshake version=1 dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d scid=eab85642ddf768f035cdff0b len=60 pn=3 
[2025-04-30T19:57:20.305153337Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm CRYPTO off=696 len=36
[2025-04-30T19:57:20.305219050Z TRACE quiche::tls] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d write message lvl=Handshake len=36
[2025-04-30T19:57:20.305225372Z TRACE quiche::tls] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d set write secret lvl=OneRTT
[2025-04-30T19:57:20.305234860Z TRACE quiche::tls] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d set read secret lvl=OneRTT
[2025-04-30T19:57:20.305250058Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d 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(d68f88ec08b9aa487afd4046b2909ee3), max_idle_timeout: 120000, stateless_reset_token: Some(22846869470098841751440082959037744710), max_udp_payload_size: 1500, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 16777216, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(eab85642ddf768f035cdff0b), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T19:57:20.305274324Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 101 bytes
[2025-04-30T19:57:20.305281427Z TRACE quiche_apps::client] got 68 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.305285865Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d dropped invalid packet
[2025-04-30T19:57:20.305289162Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 68 bytes
[2025-04-30T19:57:20.305293459Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.305303278Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.305316994Z DEBUG quiche_apps::common] sending HTTP request "GET /ppncurioid\r\n"
[2025-04-30T19:57:20.305361497Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Handshake version=1 dcid=eab85642ddf768f035cdff0b scid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d len=45 pn=4 
[2025-04-30T19:57:20.305365134Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=26 blocks=[0..3] ecn_counts=None
[2025-04-30T19:57:20.305368390Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm CRYPTO off=0 len=36
[2025-04-30T19:57:20.305375864Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=998.9787ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=103 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 237, tv_nsec: 641189837 }, next_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, 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-30T19:57:20.305387726Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13397 out_len=1247 left=1217
[2025-04-30T19:57:20.305394138Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Short dcid=eab85642ddf768f035cdff0b key_phase=false len=302 pn=5 
[2025-04-30T19:57:20.305396813Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[a9, 4c, b9, 43, f0, 72, af, e6, 03, df, 28, 96, ba, 2e, d6, 9d, 87, c7, 84, 21] reset_token=[e3, 5d, dc, d7, 96, b9, 3d, 9c, 27, 0a, ae, 34, 3b, 9b, 48, be]
[2025-04-30T19:57:20.305403576Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[a8, 3e, 5d, af, 38, 89, 8f, c1, 1c, 07, 1f, 73, 29, cb, 09, 82, e2, d0, 1e, 9f] reset_token=[28, ca, 53, 7f, b6, 93, 25, 07, 6a, 33, 74, 6a, 20, 02, ef, e6]
[2025-04-30T19:57:20.305408986Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d5, 6f, 61, 88, 2e, 56, 42, 46, 18, a1, f6, c4, 6e, 7e, 44, cb, 49, 7d, 1a, 76] reset_token=[ab, b9, b9, a1, 3e, 28, a3, 25, 7b, 1c, 44, ea, b9, b4, 69, 44]
[2025-04-30T19:57:20.305413835Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[bd, c3, 22, ac, d5, 1d, 13, 45, 35, 37, f4, 40, 16, 6c, f9, 59, 74, 6a, e7, 25] reset_token=[6e, 58, 03, 70, 1d, 48, b4, 42, 9a, 49, 22, 91, fa, 40, 5e, a4]
[2025-04-30T19:57:20.305418684Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[65, 77, 34, 7e, 11, 06, 7a, 6a, 95, 10, 91, a6, 73, 06, 36, 3c, b0, db, 5f, d5] reset_token=[29, 38, fb, 0c, 77, 73, 33, 19, c9, 2c, 3c, 73, a2, 89, a9, 48]
[2025-04-30T19:57:20.305423654Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[e3, 32, ff, 8f, 56, 93, 3b, cf, 79, 59, ae, ec, 7a, 62, ce, 62, d4, 44, 41, 20] reset_token=[81, 58, da, 46, 40, 98, de, 85, 15, 9a, b2, 21, 19, 8e, 11, 14]
[2025-04-30T19:57:20.305428362Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[ef, 40, 49, 0d, e0, 56, 53, 5f, ae, 33, df, b5, 49, a4, 86, a9, 26, 71, 2f, 73] reset_token=[99, a0, 30, e0, 75, 53, d0, 21, e2, 17, 7a, 8f, 5f, f7, a7, 7d]
[2025-04-30T19:57:20.305437319Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T19:57:20.305445975Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=998.908659ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=435 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, end_of_app_limited: 4, last_sent_packet: 5, 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: 237, tv_nsec: 641189837 }, next_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, 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-30T19:57:20.305484137Z TRACE quiche_apps::client] written 435 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.305491561Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.337547717Z TRACE quiche_apps::client] got 42 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.337564248Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Short dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d key_phase=false len=21 pn=0 
[2025-04-30T19:57:20.337573295Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm HANDSHAKE_DONE
[2025-04-30T19:57:20.337578926Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d dropped epoch 1 state
[2025-04-30T19:57:20.337588984Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 42 bytes
[2025-04-30T19:57:20.337595356Z TRACE quiche_apps::client] got 52 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.337599534Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Short dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d key_phase=false len=31 pn=1 
[2025-04-30T19:57:20.337606307Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm NEW_TOKEN len=9
[2025-04-30T19:57:20.337612939Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 52 bytes
[2025-04-30T19:57:20.337617408Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.337620624Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.337629561Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1315
[2025-04-30T19:57:20.337634460Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Short dcid=eab85642ddf768f035cdff0b key_phase=false len=5 pn=6 
[2025-04-30T19:57:20.337637936Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=3 blocks=[0..1] ecn_counts=None
[2025-04-30T19:57:20.337670838Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=991.683185ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, end_of_app_limited: 4, last_sent_packet: 6, 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: 237, tv_nsec: 673460365 }, next_time: Instant { tv_sec: 237, tv_nsec: 673460365 }, 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-30T19:57:20.337730690Z TRACE quiche_apps::client] written 35 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.337741230Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.337916488Z TRACE quiche_apps::client] got 302 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.337923301Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Short dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d key_phase=false len=281 pn=2 
[2025-04-30T19:57:20.337930745Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm CRYPTO off=0 len=257
[2025-04-30T19:57:20.337955401Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 302 bytes
[2025-04-30T19:57:20.337960741Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.337964068Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.337972563Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1315
[2025-04-30T19:57:20.337977172Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Short dcid=eab85642ddf768f035cdff0b key_phase=false len=5 pn=7 
[2025-04-30T19:57:20.337980448Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=6 blocks=[0..2] ecn_counts=None
[2025-04-30T19:57:20.337988744Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=991.36558ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, end_of_app_limited: 4, last_sent_packet: 7, 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: 237, tv_nsec: 673803699 }, next_time: Instant { tv_sec: 237, tv_nsec: 673803699 }, 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-30T19:57:20.338037104Z TRACE quiche_apps::client] written 35 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.338046502Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.338131231Z TRACE quiche_apps::client] got 303 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.338137522Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Short dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d key_phase=false len=282 pn=6 
[2025-04-30T19:57:20.338144756Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm CRYPTO off=257 len=257
[2025-04-30T19:57:20.338165004Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 303 bytes
[2025-04-30T19:57:20.338204478Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.338208085Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.338224055Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T19:57:20.338228573Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Short dcid=eab85642ddf768f035cdff0b key_phase=false len=7 pn=8 
[2025-04-30T19:57:20.338231849Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=10 blocks=[0..2, 6..6] ecn_counts=None
[2025-04-30T19:57:20.338241347Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=991.112997ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, end_of_app_limited: 4, last_sent_packet: 8, 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: 237, tv_nsec: 674054639 }, next_time: Instant { tv_sec: 237, tv_nsec: 674054639 }, 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-30T19:57:20.338289077Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.338298775Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.338313523Z TRACE quiche_apps::client] got 67 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.338320596Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d dropped invalid packet
[2025-04-30T19:57:20.338326006Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 67 bytes
[2025-04-30T19:57:20.338334041Z TRACE quiche_apps::client] got 73 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.338338519Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Short dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d key_phase=false len=52 pn=7 
[2025-04-30T19:57:20.338347366Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[62, 0b, 4f, 35, 0a, 74, e8, 66, f2, e1, 8e, 2a] reset_token=[da, b7, c3, 46, 33, f7, 63, e9, bb, 88, af, c1, 33, 6a, bf, 6e]
[2025-04-30T19:57:20.338359228Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 73 bytes
[2025-04-30T19:57:20.338363757Z TRACE quiche_apps::client] got 73 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.338366552Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Short dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d key_phase=false len=52 pn=8 
[2025-04-30T19:57:20.338371281Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[ed, ff, d8, 99, 36, 84, ce, 33, 5e, eb, 14, 9c] reset_token=[19, f0, 15, d1, d4, c5, b0, 74, c7, 0a, 3b, 7f, 6d, 6d, 3c, bf]
[2025-04-30T19:57:20.338376621Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 73 bytes
[2025-04-30T19:57:20.338379907Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.338382291Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.338387762Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1313
[2025-04-30T19:57:20.338390917Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Short dcid=eab85642ddf768f035cdff0b key_phase=false len=7 pn=9 
[2025-04-30T19:57:20.338398021Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm ACK delay=2 blocks=[0..2, 6..8] ecn_counts=None
[2025-04-30T19:57:20.338404774Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=990.949751ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=332 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, end_of_app_limited: 4, last_sent_packet: 9, 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: 237, tv_nsec: 674220570 }, next_time: Instant { tv_sec: 237, tv_nsec: 674220570 }, 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-30T19:57:20.338439579Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.338446492Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.339295684Z TRACE quiche_apps::client] got 1069 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.339303247Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx pkt Short dcid=69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d key_phase=false len=1048 pn=9 
[2025-04-30T19:57:20.339309559Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d rx frm STREAM id=0 off=0 len=1024 fin=true
[2025-04-30T19:57:20.339316172Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 1069 bytes
[2025-04-30T19:57:20.339319648Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.339322023Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.339326481Z TRACE quiche_apps::common] received 1024 bytes
[2025-04-30T19:57:20.339328855Z TRACE quiche_apps::common] stream 0 has 1024 bytes (fin? true)
[2025-04-30T19:57:20.339331190Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T19:57:20.339333544Z INFO  quiche_apps::common] 1/1 response(s) received in 73.833814ms, closing...
[2025-04-30T19:57:20.339339967Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13168 out_len=1350 left=1320
[2025-04-30T19:57:20.339343363Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx pkt Short dcid=eab85642ddf768f035cdff0b key_phase=false len=10 pn=10 
[2025-04-30T19:57:20.339345858Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T19:57:20.339352560Z TRACE quiche::recovery::congestion::recovery] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d timer=1.023984551s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=372 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, first_sent_time: Instant { tv_sec: 237, tv_nsec: 641189837 }, end_of_app_limited: 9, last_sent_packet: 10, 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: 237, tv_nsec: 675172384 }, next_time: Instant { tv_sec: 237, tv_nsec: 675172384 }, 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-30T19:57:20.339392345Z TRACE quiche_apps::client] written 40 bytes from 0.0.0.0:47123 to 193.167.100.100:443
[2025-04-30T19:57:20.339399247Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.362505738Z TRACE quiche_apps::client] got 47 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.362516829Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 47 bytes
[2025-04-30T19:57:20.362521438Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.362524574Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.362529623Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:20.369923953Z TRACE quiche_apps::client] got 45 bytes from 193.167.100.100:443 to 0.0.0.0:47123
[2025-04-30T19:57:20.369935405Z TRACE quiche_apps::client] 0.0.0.0:47123: processed 45 bytes
[2025-04-30T19:57:20.369939693Z TRACE quiche_apps::client] 0.0.0.0:47123: recv() would block
[2025-04-30T19:57:20.369942217Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:20.369946185Z TRACE quiche_apps::client] 0.0.0.0:47123 -> 193.167.100.100:443: done writing
[2025-04-30T19:57:23.339964642Z TRACE quiche_apps::client] timed out
[2025-04-30T19:57:23.339982575Z TRACE quiche] 69f00fbdcf83aae5a1229f370b1f6e0f145d3d8d draining timeout expired
[2025-04-30T19:57:23.340014796Z TRACE quiche_apps::client] done reading
[2025-04-30T19:57:23.340019344Z INFO  quiche_apps::client] connection closed, recv=12 sent=11 lost=0 retrans=0 sent_bytes=1142 recv_bytes=4108 lost_bytes=0 [local_addr=0.0.0.0:47123 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=12 sent=11 lost=0 retrans=0 rtt=333ms min_rtt=Some(333ms) rttvar=166.5ms cwnd=13500 sent_bytes=1142 recv_bytes=4108 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=0]