[2025-04-30T10:56:13.435372601Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T10:56:13.435646643Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:39488 with scid e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82
[2025-04-30T10:56:13.435693721Z TRACE quiche::tls] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 write message lvl=Initial len=413
[2025-04-30T10:56:13.435711825Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Initial version=1 dcid=3f66a1ba55fa33d4920be6065eacd198 scid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 len=417 pn=0 
[2025-04-30T10:56:13.435714910Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm CRYPTO off=0 len=413
[2025-04-30T10:56:13.435727434Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=998.9252ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=480 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 302, tv_nsec: 336669087 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 336669087 }, 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: 302, tv_nsec: 336669087 }, next_time: Instant { tv_sec: 302, tv_nsec: 336669087 }, 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-30T10:56:13.435817152Z TRACE quiche_apps::client] written 1200
[2025-04-30T10:56:13.468639623Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.468656986Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Initial version=1 dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 scid=bda6c1f6f736d851 token= len=125 pn=85137 
[2025-04-30T10:56:13.468667986Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm ACK delay=51 blocks=[0..0] ecn_counts=None
[2025-04-30T10:56:13.468674158Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 packet newly acked 0
[2025-04-30T10:56:13.468680189Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm CRYPTO off=0 len=96
[2025-04-30T10:56:13.468772121Z TRACE quiche::tls] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 set write secret lvl=Handshake
[2025-04-30T10:56:13.468781649Z TRACE quiche::tls] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 set read secret lvl=Handshake
[2025-04-30T10:56:13.468804171Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Handshake version=1 dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 scid=bda6c1f6f736d851 len=177 pn=0 
[2025-04-30T10:56:13.468810172Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm CRYPTO off=0 len=153
[2025-04-30T10:56:13.468840348Z TRACE quiche::tls] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 write message lvl=Handshake len=36
[2025-04-30T10:56:13.468843915Z TRACE quiche::tls] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 set write secret lvl=OneRTT
[2025-04-30T10:56:13.468851119Z TRACE quiche::tls] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 set read secret lvl=OneRTT
[2025-04-30T10:56:13.468866948Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=true TransportParams { original_destination_connection_id: Some(3f66a1ba55fa33d4920be6065eacd198), max_idle_timeout: 180000, stateless_reset_token: Some(317917491478395425198741844407063509580), max_udp_payload_size: 1440, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 2097152, initial_max_stream_data_bidi_remote: 65635, initial_max_stream_data_uni: 65535, initial_max_streams_bidi: 512, initial_max_streams_uni: 512, ack_delay_exponent: 3, max_ack_delay: 10, disable_active_migration: false, active_conn_id_limit: 8, initial_source_connection_id: Some(bda6c1f6f736d851), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T10:56:13.468896463Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Handshake version=1 dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 scid=bda6c1f6f736d851 len=838 pn=1 
[2025-04-30T10:56:13.468902384Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm PING mtu_probe=None
[2025-04-30T10:56:13.468909207Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm PADDING len=817
[2025-04-30T10:56:13.468916170Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1252 bytes
[2025-04-30T10:56:13.468921480Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.468924646Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.468946116Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm SETTINGS stream=2 len=18
[2025-04-30T10:56:13.468970321Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 open GREASE stream 14
[2025-04-30T10:56:13.469011819Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm GREASE stream=0 len=0
[2025-04-30T10:56:13.469025705Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm GREASE stream=0 len=18
[2025-04-30T10:56:13.469039771Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm HEADERS stream=0 len=30 fin=true
[2025-04-30T10:56:13.469048768Z DEBUG quiche_apps::common] Sent HTTP request [":method: GET", ":scheme: https", ":authority: server4", ":path: /dzwoqwdfvd", "user-agent: quiche"]
[2025-04-30T10:56:13.469121504Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Initial version=1 dcid=bda6c1f6f736d851 scid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 len=8 pn=1 
[2025-04-30T10:56:13.469126032Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=58 blocks=[85137..85137] ecn_counts=None
[2025-04-30T10:56:13.469136061Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.51765ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 369880656 }, next_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, 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-30T10:56:13.469157381Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Handshake version=1 dcid=bda6c1f6f736d851 scid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 len=45 pn=2 
[2025-04-30T10:56:13.469160907Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=32 blocks=[0..1] ecn_counts=None
[2025-04-30T10:56:13.469164253Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm CRYPTO off=0 len=36
[2025-04-30T10:56:13.469171347Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.941411ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=99 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370128028 }, next_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, max_datagram_size: 1350, last_packet_size: None, iv: 193.601µ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-30T10:56:13.469190583Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 dropped epoch 0 state
[2025-04-30T10:56:13.469195772Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13401 out_len=1188 left=1162
[2025-04-30T10:56:13.469209558Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=1162 pn=3 
[2025-04-30T10:56:13.469212814Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[3c, b1, 09, 0f, ee, 29, 69, b4, fa, 3a, 3b, bc, 32, b6, 48, 33, 6a, 9c, e6, ed] reset_token=[b3, f9, 9e, 5a, f0, 23, 2d, e9, a8, fa, c0, 3a, e3, b0, c4, ed]
[2025-04-30T10:56:13.469220919Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[73, e4, a7, 45, 12, 9a, db, a2, 60, e6, 89, d0, 74, 72, 99, 38, 20, 01, 88, 26] reset_token=[31, 42, e1, cf, a9, 51, de, 16, 15, 74, e4, 06, c6, 3f, aa, 1b]
[2025-04-30T10:56:13.469227311Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d2, bd, 64, a4, d7, 15, f2, c4, 30, ca, d5, 5a, ae, dd, 2f, e9, 05, 8a, 6a, 8f] reset_token=[5d, df, 58, 34, 31, 60, 63, 9e, dd, a5, 36, 86, 07, 3c, 37, 42]
[2025-04-30T10:56:13.469233473Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[13, ed, 20, 07, a8, 8c, 54, e9, b0, 54, 95, e2, df, 25, 54, d3, f8, 95, 96, 5a] reset_token=[c2, 37, ec, 4e, cc, 4b, 22, c6, fa, 10, ca, 9a, de, 06, ca, 5d]
[2025-04-30T10:56:13.469239474Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[b0, d2, 98, a2, 84, 03, e9, bd, f0, c0, e5, 50, d7, 39, b1, 30, 9b, 7d, 4f, 79] reset_token=[e4, c2, 4b, bf, 04, 0d, 6b, ec, f2, 40, 33, 3d, 45, db, 94, fb]
[2025-04-30T10:56:13.469245545Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[82, 56, e0, 84, e1, 0b, c3, 54, 8a, 36, 24, e3, 2b, a9, 74, 35, 73, 91, d7, 82] reset_token=[8c, 62, 52, 74, 29, 20, b3, 0c, 98, 2f, 20, 68, 6d, 7b, 1c, 99]
[2025-04-30T10:56:13.469251517Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[50, a0, 05, 92, 4b, 95, 8e, 01, 8d, 4b, 86, 31, b7, 62, b2, 4f, 91, 4d, af, c0] reset_token=[07, ec, 48, f6, 88, 54, 4f, 7d, 91, cc, 4e, 5b, 92, 4d, 3e, 46]
[2025-04-30T10:56:13.469257718Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm STREAM id=2 off=0 len=19 fin=false
[2025-04-30T10:56:13.469260914Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm PADDING len=858
[2025-04-30T10:56:13.469290349Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.822248ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1287 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1188, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370128028 }, next_time: Instant { tv_sec: 302, tv_nsec: 370321629 }, max_datagram_size: 1350, last_packet_size: Some(1188), 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-30T10:56:13.469334231Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.469341044Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12213 out_len=1350 left=1324
[2025-04-30T10:56:13.469344621Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=6 pn=4 
[2025-04-30T10:56:13.469347025Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm STREAM id=6 off=0 len=1 fin=false
[2025-04-30T10:56:13.469355912Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.756636ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1319 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370353961 }, next_time: Instant { tv_sec: 302, tv_nsec: 370321629 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.385795ms, 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-30T10:56:13.469402369Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.469412848Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12181 out_len=1350 left=1324
[2025-04-30T10:56:13.469419240Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=6 pn=5 
[2025-04-30T10:56:13.469423318Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm STREAM id=10 off=0 len=1 fin=false
[2025-04-30T10:56:13.469432284Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.679993ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1351 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 32, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370353961 }, next_time: Instant { tv_sec: 302, tv_nsec: 372810267 }, max_datagram_size: 1350, last_packet_size: Some(32), 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-30T10:56:13.469478581Z TRACE quiche_apps::client] written 32 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.469485364Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12149 out_len=1350 left=1324
[2025-04-30T10:56:13.469490844Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=73 pn=6 
[2025-04-30T10:56:13.469495382Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm STREAM id=0 off=0 len=68 fin=true
[2025-04-30T10:56:13.469504720Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.607708ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1450 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370498150 }, next_time: Instant { tv_sec: 302, tv_nsec: 372810267 }, max_datagram_size: 1350, last_packet_size: None, iv: 256.18µ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-30T10:56:13.469544664Z TRACE quiche_apps::client] written 99 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.469551337Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12050 out_len=1350 left=1324
[2025-04-30T10:56:13.469556406Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=31 pn=7 
[2025-04-30T10:56:13.469560875Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm STREAM id=14 off=0 len=26 fin=true
[2025-04-30T10:56:13.469571124Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.541404ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1507 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 57, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370498150 }, next_time: Instant { tv_sec: 302, tv_nsec: 373066447 }, max_datagram_size: 1350, last_packet_size: Some(57), 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-30T10:56:13.469615717Z TRACE quiche_apps::client] written 57 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.469620726Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.469631857Z TRACE quiche_apps::client] got 439 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.469638209Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=418 pn=0 
[2025-04-30T10:56:13.469647316Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm CRYPTO off=0 len=125
[2025-04-30T10:56:13.469669658Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_TOKEN len=50
[2025-04-30T10:56:13.469674988Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[1c, f7, fa, 25, ba, b8, 06, 40] reset_token=[05, c1, 3f, 64, 71, 8e, 97, 18, 42, fc, fc, 35, 1a, 7a, 90, a0]
[2025-04-30T10:56:13.469681049Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[3f, 41, a6, 15, 0e, 32, 04, bf] reset_token=[59, 95, cc, 3b, d9, 95, 5f, 67, a0, d2, 68, 89, 6c, 61, 48, b0]
[2025-04-30T10:56:13.469686019Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[87, 91, 25, 77, 9e, 47, e6, 47] reset_token=[90, a4, 2b, 9e, bf, 48, d4, ae, f6, f0, 72, 9c, 30, 7f, dc, 74]
[2025-04-30T10:56:13.469690307Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[b8, 2b, f5, ba, 2a, 31, 6e, 31] reset_token=[89, a4, 3e, 38, 63, aa, ba, be, 22, a5, 87, 5e, ec, da, 14, 39]
[2025-04-30T10:56:13.469694845Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[68, 5b, e7, 2a, 31, c4, 70, e4] reset_token=[f4, ce, 84, ff, dc, fe, 59, 72, c7, a7, 4f, 4d, 19, 4b, 0b, 97]
[2025-04-30T10:56:13.469699243Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[5d, dd, 0a, fb, 83, 8d, 4e, 9d] reset_token=[62, b1, 88, 23, a8, 5d, 64, 05, 61, 53, 61, 71, 03, 2e, ce, 33]
[2025-04-30T10:56:13.469703712Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[68, 91, 19, d9, 7f, 04, 1f, 8b] reset_token=[d1, e0, 19, f7, 19, 71, 50, 20, 9f, c6, 92, c6, d9, 37, 66, 51]
[2025-04-30T10:56:13.469707479Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=3 off=0 len=9 fin=false
[2025-04-30T10:56:13.469711256Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=7 off=0 len=1 fin=false
[2025-04-30T10:56:13.469714131Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=11 off=0 len=1 fin=false
[2025-04-30T10:56:13.469717818Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm PADDING len=4
[2025-04-30T10:56:13.469728588Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 439 bytes
[2025-04-30T10:56:13.469732535Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.469735020Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.469737485Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 3 is readable
[2025-04-30T10:56:13.469747253Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 3
[2025-04-30T10:56:13.469751330Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 open peer's control stream 3
[2025-04-30T10:56:13.469754597Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 3
[2025-04-30T10:56:13.469757693Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 3
[2025-04-30T10:56:13.469760899Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 6 bytes on stream 3
[2025-04-30T10:56:13.469763954Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm SETTINGS max_field_section=None, qpack_max_table=Some(0), qpack_blocked=Some(0) raw=Some([(1, 0), (7, 0), (8, 1)]), additional_settings=None stream=3 payload_len=6
[2025-04-30T10:56:13.469770847Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 7 is readable
[2025-04-30T10:56:13.469774083Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 7
[2025-04-30T10:56:13.469777519Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 11 is readable
[2025-04-30T10:56:13.469781136Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 11
[2025-04-30T10:56:13.469788660Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11993 out_len=1350 left=1319
[2025-04-30T10:56:13.469792006Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=8 
[2025-04-30T10:56:13.469794401Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=18 blocks=[0..0] ecn_counts=None
[2025-04-30T10:56:13.469800562Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=98.312246ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1507 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 6, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370801406 }, next_time: Instant { tv_sec: 302, tv_nsec: 373066447 }, max_datagram_size: 1350, last_packet_size: None, iv: 111.467µ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-30T10:56:13.469834195Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.469838123Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.470095103Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.470105462Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1329 pn=1 
[2025-04-30T10:56:13.470111574Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm PING mtu_probe=None
[2025-04-30T10:56:13.470118707Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm PADDING len=1311
[2025-04-30T10:56:13.470124879Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.470133244Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.470136530Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.470143734Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=11993 out_len=1350 left=1319
[2025-04-30T10:56:13.470147140Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=9 
[2025-04-30T10:56:13.470149575Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=4 blocks=[0..1] ecn_counts=None
[2025-04-30T10:56:13.470155886Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=97.956891ms latest_rtt=33.000184ms srtt=33.000184ms min_rtt=33.000184ms rttvar=16.500092ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1507 app_limited=true congestion_recovery_start_time=None Rate { delivered: 480, delivered_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 369880696 }, end_of_app_limited: 6, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 14545, is_app_limited: true, interval: 33.000184ms, delivered: 480, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 336669087 }), send_elapsed: 0ns, ack_elapsed: 33.000184ms, rtt: 33.000184ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 511360, last_update: Instant { tv_sec: 302, tv_nsec: 370801406 }, next_time: Instant { tv_sec: 302, tv_nsec: 373177914 }, 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-30T10:56:13.470188437Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.470192285Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.502074760Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.502088525Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1231 pn=2 
[2025-04-30T10:56:13.502096661Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm ACK delay=2 blocks=[3..7] ecn_counts=None
[2025-04-30T10:56:13.502101269Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 packet newly acked 3
[2025-04-30T10:56:13.502105497Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 packet newly acked 4
[2025-04-30T10:56:13.502109004Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 packet newly acked 5
[2025-04-30T10:56:13.502111789Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 packet newly acked 6
[2025-04-30T10:56:13.502114864Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 packet newly acked 7
[2025-04-30T10:56:13.502119112Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm HANDSHAKE_DONE
[2025-04-30T10:56:13.502123501Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 dropped epoch 1 state
[2025-04-30T10:56:13.502126897Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=0 len=23 fin=false
[2025-04-30T10:56:13.502131476Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=23 len=1179 fin=false
[2025-04-30T10:56:13.502150682Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1252 bytes
[2025-04-30T10:56:13.502155300Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.502158326Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.502161562Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.502172663Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 0
[2025-04-30T10:56:13.502177051Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 0
[2025-04-30T10:56:13.502180828Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 0
[2025-04-30T10:56:13.502195886Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 17 bytes on stream 0
[2025-04-30T10:56:13.502199282Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm HEADERS stream=0 payload_len=17
[2025-04-30T10:56:13.502202859Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0
[2025-04-30T10:56:13.502206005Z TRACE quiche::h3::qpack::decoder] Indexed index=25 static=true
[2025-04-30T10:56:13.502209471Z TRACE quiche::h3::qpack::decoder] Literal name_idx=92 static=true value=[72, 51, 90, 101, 114, 111, 47, 49, 46, 48]
[2025-04-30T10:56:13.502213419Z TRACE quiche::h3::qpack::decoder] Indexed index=53 static=true
[2025-04-30T10:56:13.502220903Z DEBUG quiche_apps::common] got response headers [(":status", "200"), ("server", "H3Zero/1.0"), ("content-type", "text/plain")] on stream id 0
[2025-04-30T10:56:13.502226423Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.502230260Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 0
[2025-04-30T10:56:13.502234057Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 0
[2025-04-30T10:56:13.502238015Z TRACE quiche::h3::stream] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 read 1 bytes on stream 0
[2025-04-30T10:56:13.502240820Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm DATA stream=0 wire_payload_len=10240
[2025-04-30T10:56:13.502246751Z DEBUG quiche_apps::common] got 1179 bytes of response data on stream 0
[2025-04-30T10:56:13.502255988Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.502260146Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=10 
[2025-04-30T10:56:13.502263292Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=20 blocks=[0..2] ecn_counts=None
[2025-04-30T10:56:13.502270696Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 403267572 }, next_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, 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-30T10:56:13.502314087Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.502319126Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.503082904Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.503097691Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1231 pn=3 
[2025-04-30T10:56:13.503104614Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=1202 len=1210 fin=false
[2025-04-30T10:56:13.503112038Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1252 bytes
[2025-04-30T10:56:13.503115655Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.503118069Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.503120694Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.503125283Z DEBUG quiche_apps::common] got 1210 bytes of response data on stream 0
[2025-04-30T10:56:13.503132236Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.503135502Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=11 
[2025-04-30T10:56:13.503137896Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=4 blocks=[0..3] ecn_counts=None
[2025-04-30T10:56:13.503144098Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, end_of_app_limited: 9, last_sent_packet: 11, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 403267572 }, next_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, 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-30T10:56:13.503177270Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.503181167Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.510149879Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.510162432Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1329 pn=4 
[2025-04-30T10:56:13.510170537Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=2412 len=1308 fin=false
[2025-04-30T10:56:13.510179584Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.510183872Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.510186818Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.510189994Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.510195674Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:56:13.510203990Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.510208027Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=12 
[2025-04-30T10:56:13.510217164Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=5 blocks=[0..4] ecn_counts=None
[2025-04-30T10:56:13.510224939Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, end_of_app_limited: 9, last_sent_packet: 12, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 403267572 }, next_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, 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-30T10:56:13.510264453Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.510269252Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.518053578Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.518067834Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1329 pn=5 
[2025-04-30T10:56:13.518076550Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=3720 len=1308 fin=false
[2025-04-30T10:56:13.518086359Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.518090126Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.518092610Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.518095696Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.518100635Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:56:13.518108750Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.518112708Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=13 
[2025-04-30T10:56:13.518115233Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=5 blocks=[0..5] ecn_counts=None
[2025-04-30T10:56:13.518122586Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, end_of_app_limited: 9, last_sent_packet: 13, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 403267572 }, next_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, 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-30T10:56:13.518168622Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.518173271Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.526048006Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.526062643Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1329 pn=6 
[2025-04-30T10:56:13.526074295Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=5028 len=1308 fin=false
[2025-04-30T10:56:13.526085907Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.526091557Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.526095695Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.526100023Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.526107307Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:56:13.526118237Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.526123296Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=14 
[2025-04-30T10:56:13.526126643Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=7 blocks=[0..6] ecn_counts=None
[2025-04-30T10:56:13.526135740Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, end_of_app_limited: 9, last_sent_packet: 14, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 403267572 }, next_time: Instant { tv_sec: 302, tv_nsec: 403267572 }, 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-30T10:56:13.526188799Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.526196173Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.533820099Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.533833834Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1329 pn=7 
[2025-04-30T10:56:13.533844053Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=6336 len=1308 fin=false
[2025-04-30T10:56:13.533854954Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.533860935Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.533873047Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.533877456Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.533884910Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:56:13.533896070Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.533901200Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=15 
[2025-04-30T10:56:13.533904897Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=7 blocks=[0..7] ecn_counts=None
[2025-04-30T10:56:13.533914405Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, end_of_app_limited: 9, last_sent_packet: 15, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 434906973 }, next_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, 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-30T10:56:13.533967344Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.533974918Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.535362922Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.535377409Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1329 pn=8 
[2025-04-30T10:56:13.535387738Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=7644 len=1308 fin=false
[2025-04-30T10:56:13.535398578Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.535404409Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.535408427Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.535412434Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.535419477Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:56:13.535454663Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.535461175Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=16 
[2025-04-30T10:56:13.535464862Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=9 blocks=[0..8] ecn_counts=None
[2025-04-30T10:56:13.535476804Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, end_of_app_limited: 9, last_sent_packet: 16, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 434906973 }, next_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, 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-30T10:56:13.535534602Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.535543028Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.539305749Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.539319395Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=1329 pn=9 
[2025-04-30T10:56:13.539329173Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=8952 len=1308 fin=false
[2025-04-30T10:56:13.539340143Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.539345263Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.539348699Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.539352236Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.539358878Z DEBUG quiche_apps::common] got 1308 bytes of response data on stream 0
[2025-04-30T10:56:13.539368917Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T10:56:13.539373796Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=5 pn=17 
[2025-04-30T10:56:13.539377373Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm ACK delay=6 blocks=[0..9] ecn_counts=None
[2025-04-30T10:56:13.539386309Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=none latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, end_of_app_limited: 9, last_sent_packet: 17, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 434906973 }, next_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, 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-30T10:56:13.539436253Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.539442905Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.542143081Z TRACE quiche_apps::client] got 55 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.542163318Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx pkt Short dcid=e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 key_phase=false len=34 pn=10 
[2025-04-30T10:56:13.542172426Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm STREAM id=0 off=10260 len=3 fin=true
[2025-04-30T10:56:13.542178297Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 rx frm PADDING len=9
[2025-04-30T10:56:13.542187694Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 55 bytes
[2025-04-30T10:56:13.542193335Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.542197362Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.542201480Z TRACE quiche::h3] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 stream id 0 is readable
[2025-04-30T10:56:13.542211158Z DEBUG quiche_apps::common] got 3 bytes of response data on stream 0
[2025-04-30T10:56:13.542215566Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T10:56:13.542219463Z INFO  quiche_apps::common] 1/1 response(s) received in 106.397663ms, closing...
[2025-04-30T10:56:13.542229592Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T10:56:13.542235333Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx pkt Short dcid=bda6c1f6f736d851 key_phase=false len=11 pn=18 
[2025-04-30T10:56:13.542239401Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 tx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T10:56:13.542250992Z TRACE quiche::recovery::congestion::recovery] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 timer=95.068378ms latest_rtt=30.035867ms srtt=32.629644ms min_rtt=30.035867ms rttvar=13.116148ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=37 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1888, delivered_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, first_sent_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, end_of_app_limited: 17, last_sent_packet: 18, largest_acked: 7, rate_sample: RateSample { delivery_rate: 42382, is_app_limited: true, interval: 33.221618ms, delivered: 1408, prior_delivered: 480, prior_time: Some(Instant { tv_sec: 302, tv_nsec: 369880696 }), send_elapsed: 440.933µs, ack_elapsed: 33.221618ms, rtt: 32.780685ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 517167, last_update: Instant { tv_sec: 302, tv_nsec: 443241387 }, next_time: Instant { tv_sec: 302, tv_nsec: 434906973 }, max_datagram_size: 1350, last_packet_size: None, iv: 71.544µ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-30T10:56:13.542305504Z TRACE quiche_apps::client] written 37 bytes from 0.0.0.0:39488 to 193.167.100.100:443
[2025-04-30T10:56:13.542312157Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.543902879Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.543915933Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.543921323Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.543924980Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.543930521Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.547266915Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.547280470Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.547285700Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.547289036Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.547300969Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.551132789Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.551145493Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.551151384Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.551155632Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.551161372Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.554015135Z TRACE quiche_apps::client] got 55 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.554028219Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 55 bytes
[2025-04-30T10:56:13.554034170Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.554038098Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.554044059Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.555172427Z TRACE quiche_apps::client] got 1350 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.555185261Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 1350 bytes
[2025-04-30T10:56:13.555190972Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.555194979Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.555200700Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.564087756Z TRACE quiche_apps::client] got 55 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.564101823Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 55 bytes
[2025-04-30T10:56:13.564107222Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.564111721Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.564118564Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.564343715Z TRACE quiche_apps::client] got 55 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.564352461Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 55 bytes
[2025-04-30T10:56:13.564357761Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.564361328Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.564366197Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.572783064Z TRACE quiche_apps::client] got 43 bytes from 193.167.100.100:443 to 0.0.0.0:39488
[2025-04-30T10:56:13.572798062Z TRACE quiche_apps::client] 0.0.0.0:39488: processed 43 bytes
[2025-04-30T10:56:13.572804264Z TRACE quiche_apps::client] 0.0.0.0:39488: recv() would block
[2025-04-30T10:56:13.572808722Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.572815314Z TRACE quiche_apps::client] 0.0.0.0:39488 -> 193.167.100.100:443: done writing
[2025-04-30T10:56:13.798069137Z TRACE quiche_apps::client] timed out
[2025-04-30T10:56:13.798088464Z TRACE quiche] e5ac6bdf8e07bfc4a6e7c9f6113deeda84b2ff82 draining timeout expired
[2025-04-30T10:56:13.798126946Z TRACE quiche_apps::client] done reading
[2025-04-30T10:56:13.798131464Z INFO  quiche_apps::client] connection closed, recv=14 sent=19 lost=0 retrans=0 sent_bytes=2397 recv_bytes=13700 lost_bytes=0 [local_addr=0.0.0.0:39488 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=14 sent=19 lost=0 retrans=0 rtt=32.629644ms min_rtt=Some(30.035867ms) rttvar=13.116148ms cwnd=13500 sent_bytes=2397 recv_bytes=13700 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=42382]