[2025-04-30T11:50:34.739685739Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T11:50:34.739977837Z INFO  quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:40006 with scid 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41
[2025-04-30T11:50:34.740030566Z TRACE quiche::tls] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 write message lvl=Initial len=569
[2025-04-30T11:50:34.740049411Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Initial version=1 dcid=9054e4800f8328e117c2fefe2d1fdcf0 scid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 len=573 pn=0 
[2025-04-30T11:50:34.740052768Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm CRYPTO off=0 len=569
[2025-04-30T11:50:34.740066333Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=998.920632ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=636 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 294, tv_nsec: 508642219 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 508642219 }, 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: 294, tv_nsec: 508642219 }, next_time: Instant { tv_sec: 294, tv_nsec: 508642219 }, 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-30T11:50:34.740157955Z TRACE quiche_apps::client] written 1200
[2025-04-30T11:50:34.774885998Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.774910244Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Initial version=1 dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 scid=8024d66c3f269390 token= len=123 pn=0 
[2025-04-30T11:50:34.774923899Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm ACK delay=250 blocks=[0..0] ecn_counts=None
[2025-04-30T11:50:34.774929830Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 packet newly acked 0
[2025-04-30T11:50:34.774936072Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm CRYPTO off=0 len=96
[2025-04-30T11:50:34.775023145Z TRACE quiche::tls] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 set write secret lvl=Handshake
[2025-04-30T11:50:34.775035038Z TRACE quiche::tls] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 set read secret lvl=Handshake
[2025-04-30T11:50:34.775061648Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Handshake version=1 dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 scid=8024d66c3f269390 len=1002 pn=0 
[2025-04-30T11:50:34.775067168Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm CRYPTO off=0 len=189
[2025-04-30T11:50:34.775097525Z TRACE quiche::tls] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 write message lvl=Handshake len=52
[2025-04-30T11:50:34.775100450Z TRACE quiche::tls] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 set write secret lvl=OneRTT
[2025-04-30T11:50:34.775107574Z TRACE quiche::tls] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 set read secret lvl=OneRTT
[2025-04-30T11:50:34.775123564Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 connection established: proto=Ok("hq-interop") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=true TransportParams { original_destination_connection_id: Some(9054e4800f8328e117c2fefe2d1fdcf0), max_idle_timeout: 30000, stateless_reset_token: Some(154152114784600928173911468683219636967), max_udp_payload_size: 2048, initial_max_data: 1638000, initial_max_stream_data_bidi_local: 16380, initial_max_stream_data_bidi_remote: 1474200, initial_max_stream_data_uni: 16380, initial_max_streams_bidi: 100, initial_max_streams_uni: 3, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(8024d66c3f269390), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T11:50:34.775145114Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm PADDING len=792
[2025-04-30T11:50:34.775157136Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1200 bytes
[2025-04-30T11:50:34.775161655Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.775164430Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.775173126Z DEBUG quiche_apps::common] sending HTTP request "GET /pmvgugfuaf\r\n"
[2025-04-30T11:50:34.775251854Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Initial version=1 dcid=8024d66c3f269390 scid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 len=5 pn=1 
[2025-04-30T11:50:34.775256082Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=43 blocks=[0..0] ecn_counts=None
[2025-04-30T11:50:34.775264899Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=104.390052ms latest_rtt=34.916967ms srtt=34.916967ms min_rtt=34.916967ms rttvar=17.458483ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 636, delivered_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 18214, is_app_limited: true, interval: 34.916967ms, delivered: 636, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 508642219 }), send_elapsed: 0ns, ack_elapsed: 34.916967ms, rtt: 34.916967ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 483289, last_update: Instant { tv_sec: 294, tv_nsec: 543776203 }, next_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, 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-30T11:50:34.775285096Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Handshake version=1 dcid=8024d66c3f269390 scid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 len=61 pn=2 
[2025-04-30T11:50:34.775287802Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=27 blocks=[0..0] ecn_counts=None
[2025-04-30T11:50:34.775290386Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm CRYPTO off=0 len=52
[2025-04-30T11:50:34.775296208Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=104.69777ms latest_rtt=34.916967ms srtt=34.916967ms min_rtt=34.916967ms rttvar=17.458483ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=115 app_limited=true congestion_recovery_start_time=None Rate { delivered: 636, delivered_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 18214, is_app_limited: true, interval: 34.916967ms, delivered: 636, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 508642219 }), send_elapsed: 0ns, ack_elapsed: 34.916967ms, rtt: 34.916967ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 483289, last_update: Instant { tv_sec: 294, tv_nsec: 543897932 }, next_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, max_datagram_size: 1350, last_packet_size: None, iv: 237.953µ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-30T11:50:34.775311576Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 dropped epoch 0 state
[2025-04-30T11:50:34.775316055Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13385 out_len=1175 left=1149
[2025-04-30T11:50:34.775327476Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=1149 pn=3 
[2025-04-30T11:50:34.775329880Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[07, 24, eb, a4, 51, 0e, 97, 1b, 8e, c0, 4f, ab, 5e, 61, 30, 83, 08, 41, 75, c5] reset_token=[60, 92, fd, f0, 6a, bf, 33, 1d, 46, df, 23, 32, f6, cf, bf, 16]
[2025-04-30T11:50:34.775404320Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[b8, b3, ff, 77, 5c, ca, 47, 16, 80, 76, c7, 1c, eb, e2, e8, 1f, 49, ec, 5c, 83] reset_token=[02, 1a, ff, a7, e6, 0e, 01, 54, 3f, a6, 30, 0a, 44, 95, 97, 3a]
[2025-04-30T11:50:34.775412826Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[48, 35, d3, ee, aa, 6e, 00, 7c, f3, f3, 5c, e4, 07, de, 0d, e7, e8, f3, a6, 25] reset_token=[03, 9b, 1d, f1, b6, e2, 8f, 5b, 24, df, 2f, 7f, e0, 25, 02, 09]
[2025-04-30T11:50:34.775420510Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[eb, 1a, 97, 74, fe, 6f, d1, 41, 01, ab, 80, ca, 14, 97, 3d, e3, e4, 76, 4c, 8d] reset_token=[86, c3, d7, b0, b8, 95, a9, 2b, 5f, cd, ad, ea, 01, 10, 74, d2]
[2025-04-30T11:50:34.775427884Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[4f, 92, b2, e5, 5e, e4, 4a, c1, c2, 21, 0c, fc, 72, 45, ad, 80, 9f, d6, 5d, c5] reset_token=[f9, 7b, 3d, a2, 66, 02, b4, c7, 32, ec, 34, 43, dd, 13, 21, 88]
[2025-04-30T11:50:34.775435238Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[5a, 77, b2, b9, 4d, 85, f2, 8b, 68, cf, 47, 7a, 6a, ab, f4, c3, 0f, 6c, 33, 96] reset_token=[4e, 37, 44, 1a, 6e, 52, 05, 68, 09, 5f, 80, 96, b9, 0a, a0, 69]
[2025-04-30T11:50:34.775442662Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[db, 91, cc, de, 11, 82, b2, 44, 95, 8c, 95, 22, db, 92, d2, 41, 51, 44, cc, 2c] reset_token=[57, bf, a5, 25, 4c, c0, 66, 5b, 38, ab, 79, c9, 99, 08, bb, 61]
[2025-04-30T11:50:34.775531509Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T11:50:34.775534174Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm PADDING len=847
[2025-04-30T11:50:34.775544543Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=104.449344ms latest_rtt=34.916967ms srtt=34.916967ms min_rtt=34.916967ms rttvar=17.458483ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1290 app_limited=true congestion_recovery_start_time=None Rate { delivered: 636, delivered_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 18214, is_app_limited: true, interval: 34.916967ms, delivered: 636, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 508642219 }), send_elapsed: 0ns, ack_elapsed: 34.916967ms, rtt: 34.916967ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1175, rate: 483289, last_update: Instant { tv_sec: 294, tv_nsec: 543897932 }, next_time: Instant { tv_sec: 294, tv_nsec: 544135885 }, max_datagram_size: 1350, last_packet_size: Some(1175), 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-30T11:50:34.775690908Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.775698983Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.809188311Z TRACE quiche_apps::client] got 373 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.809210702Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Handshake version=1 dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 scid=8024d66c3f269390 len=22 pn=1 
[2025-04-30T11:50:34.809223026Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm ACK delay=0 blocks=[2..2] ecn_counts=None
[2025-04-30T11:50:34.809229357Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 packet newly acked 2
[2025-04-30T11:50:34.809253333Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=294 pn=0 
[2025-04-30T11:50:34.809260937Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm CRYPTO off=0 len=273
[2025-04-30T11:50:34.809291083Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 373 bytes
[2025-04-30T11:50:34.809296293Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.809299569Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.809309839Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12325 out_len=1350 left=1319
[2025-04-30T11:50:34.809315469Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=4 
[2025-04-30T11:50:34.809318655Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=6 blocks=[0..0] ecn_counts=None
[2025-04-30T11:50:34.809326951Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=78.937175ms latest_rtt=34.083843ms srtt=34.812826ms min_rtt=34.083843ms rttvar=13.302143ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1175 app_limited=true congestion_recovery_start_time=None Rate { delivered: 751, delivered_time: Instant { tv_sec: 294, tv_nsec: 577860076 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 543776233 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 2, rate_sample: RateSample { delivery_rate: 3374, is_app_limited: true, interval: 34.083843ms, delivered: 115, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484735, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.809405327Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.809414805Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.810486646Z TRACE quiche_apps::client] got 168 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.810494581Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=147 pn=1 
[2025-04-30T11:50:34.810500822Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm ACK delay=250 blocks=[3..3] ecn_counts=None
[2025-04-30T11:50:34.810511312Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 packet newly acked 3
[2025-04-30T11:50:34.810515800Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm HANDSHAKE_DONE
[2025-04-30T11:50:34.810520038Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 dropped epoch 1 state
[2025-04-30T11:50:34.810524817Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm NEW_TOKEN len=37
[2025-04-30T11:50:34.810528534Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[eb, 2d, 46, d3, 9c, 45, f6, f8] reset_token=[c8, 8e, e9, 98, c4, 5b, b5, 16, 1e, f4, 0f, f0, f5, d8, 06, 69]
[2025-04-30T11:50:34.810535177Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[a6, 76, 8d, 28, d6, 2b, 51, 25] reset_token=[c9, 3a, 21, 10, 4d, 6b, bf, 43, 8a, 56, 95, d1, d7, ac, c8, 06]
[2025-04-30T11:50:34.810539795Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[d5, 56, 59, 5c, 40, 09, ac, 39] reset_token=[72, 00, 7c, fe, a1, 18, 4d, a1, 5a, 14, dd, e7, 5c, 21, 5d, aa]
[2025-04-30T11:50:34.810574741Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 168 bytes
[2025-04-30T11:50:34.810578839Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.810581223Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.810587605Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.810590931Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=5 
[2025-04-30T11:50:34.810593456Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=11 blocks=[0..1] ecn_counts=None
[2025-04-30T11:50:34.810599728Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.810638400Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.810645514Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.812789514Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.812799263Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=2 
[2025-04-30T11:50:34.812807779Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=0 len=1210 fin=false
[2025-04-30T11:50:34.812816095Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.812826303Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.812829329Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.812835030Z TRACE quiche_apps::common] received 1210 bytes
[2025-04-30T11:50:34.812838306Z TRACE quiche_apps::common] stream 0 has 1210 bytes (fin? false)
[2025-04-30T11:50:34.812846472Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.812850569Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=6 
[2025-04-30T11:50:34.812853675Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=5 blocks=[0..2] ecn_counts=None
[2025-04-30T11:50:34.812861089Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.812904570Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.812913197Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.813800341Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.813807855Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=3 
[2025-04-30T11:50:34.813814166Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=1210 len=1208 fin=false
[2025-04-30T11:50:34.813820879Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.813824315Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.813826660Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.813830467Z TRACE quiche_apps::common] received 1208 bytes
[2025-04-30T11:50:34.813832671Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-04-30T11:50:34.813838572Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.813841688Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=7 
[2025-04-30T11:50:34.813844052Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=3 blocks=[0..3] ecn_counts=None
[2025-04-30T11:50:34.813849743Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 7, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.813889167Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.813897142Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.818442877Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.818454229Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=4 
[2025-04-30T11:50:34.818463707Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=2418 len=1208 fin=false
[2025-04-30T11:50:34.818474437Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.818478835Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.818481951Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.818487481Z TRACE quiche_apps::common] received 1208 bytes
[2025-04-30T11:50:34.818490196Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-04-30T11:50:34.818498361Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.818502930Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=8 
[2025-04-30T11:50:34.818506076Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=5 blocks=[0..4] ecn_counts=None
[2025-04-30T11:50:34.818514712Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 8, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.818558204Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.818566389Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.819459725Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.819474713Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=5 
[2025-04-30T11:50:34.819481335Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=3626 len=1208 fin=false
[2025-04-30T11:50:34.819488308Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.819491835Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.819494169Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.819498187Z TRACE quiche_apps::common] received 1208 bytes
[2025-04-30T11:50:34.819500381Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-04-30T11:50:34.819506623Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.819509778Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=9 
[2025-04-30T11:50:34.819512263Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=4 blocks=[0..5] ecn_counts=None
[2025-04-30T11:50:34.819518174Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 9, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.819554933Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.819561686Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.823142572Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.823151709Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=6 
[2025-04-30T11:50:34.823159193Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=4834 len=1208 fin=false
[2025-04-30T11:50:34.823167289Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.823171466Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.823174462Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.823179131Z TRACE quiche_apps::common] received 1208 bytes
[2025-04-30T11:50:34.823181846Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-04-30T11:50:34.823188939Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.823192806Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=10 
[2025-04-30T11:50:34.823195802Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=4 blocks=[0..6] ecn_counts=None
[2025-04-30T11:50:34.823209668Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 10, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.823252428Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.823260914Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.824156804Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.824164188Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=7 
[2025-04-30T11:50:34.824170330Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=6042 len=1208 fin=false
[2025-04-30T11:50:34.824176892Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.824180338Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.824182663Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.824186370Z TRACE quiche_apps::common] received 1208 bytes
[2025-04-30T11:50:34.824188544Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-04-30T11:50:34.824194375Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.824197501Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=11 
[2025-04-30T11:50:34.824199835Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=3 blocks=[0..7] ecn_counts=None
[2025-04-30T11:50:34.824205436Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 11, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.824243828Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.824250951Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.828809110Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.828818127Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=8 
[2025-04-30T11:50:34.828825741Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=7250 len=1208 fin=false
[2025-04-30T11:50:34.828833545Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.828837844Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.828840739Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.828845478Z TRACE quiche_apps::common] received 1208 bytes
[2025-04-30T11:50:34.828848143Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-04-30T11:50:34.828876746Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.828880914Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=12 
[2025-04-30T11:50:34.828883830Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=7 blocks=[0..8] ecn_counts=None
[2025-04-30T11:50:34.828891344Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 12, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.828933252Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.828941568Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.829831397Z TRACE quiche_apps::client] got 1252 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.829838590Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=1231 pn=9 
[2025-04-30T11:50:34.829844672Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=8458 len=1208 fin=false
[2025-04-30T11:50:34.829851475Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 1252 bytes
[2025-04-30T11:50:34.829854881Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.829857216Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.829860983Z TRACE quiche_apps::common] received 1208 bytes
[2025-04-30T11:50:34.829863137Z TRACE quiche_apps::common] stream 0 has 1208 bytes (fin? false)
[2025-04-30T11:50:34.829874127Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T11:50:34.829877273Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=5 pn=13 
[2025-04-30T11:50:34.829879627Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm ACK delay=4 blocks=[0..9] ecn_counts=None
[2025-04-30T11:50:34.829885789Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=none latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 4, last_sent_packet: 13, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 577959963 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, 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-30T11:50:34.829921897Z TRACE quiche_apps::client] written 31 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.829928579Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.832934237Z TRACE quiche_apps::client] got 618 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.832943173Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx pkt Short dcid=775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 key_phase=false len=597 pn=10 
[2025-04-30T11:50:34.832950117Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 rx frm STREAM id=0 off=9666 len=574 fin=true
[2025-04-30T11:50:34.832958262Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 618 bytes
[2025-04-30T11:50:34.832962570Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.832965485Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.832972018Z TRACE quiche_apps::common] received 574 bytes
[2025-04-30T11:50:34.832974803Z TRACE quiche_apps::common] stream 0 has 574 bytes (fin? true)
[2025-04-30T11:50:34.832977628Z DEBUG quiche_apps::common] 1/1 responses received
[2025-04-30T11:50:34.832980483Z INFO  quiche_apps::common] 1/1 response(s) received in 92.816928ms, closing...
[2025-04-30T11:50:34.832987476Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T11:50:34.832991434Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx pkt Short dcid=8024d66c3f269390 key_phase=false len=10 pn=14 
[2025-04-30T11:50:34.832994380Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65]
[2025-04-30T11:50:34.833003537Z TRACE quiche::recovery::congestion::recovery] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 timer=99.92466ms latest_rtt=35.011966ms srtt=34.837717ms min_rtt=34.083843ms rttvar=10.026392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=36 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1926, delivered_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, first_sent_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 3, rate_sample: RateSample { delivery_rate: 37847, is_app_limited: true, interval: 34.083843ms, delivered: 1290, prior_delivered: 636, prior_time: Some(Instant { tv_sec: 294, tv_nsec: 543776233 }), send_elapsed: 0ns, ack_elapsed: 34.083843ms, rtt: 34.083843ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 484388, last_update: Instant { tv_sec: 294, tv_nsec: 601639906 }, next_time: Instant { tv_sec: 294, tv_nsec: 577959963 }, max_datagram_size: 1350, last_packet_size: None, iv: 74.321µ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-30T11:50:34.833052168Z TRACE quiche_apps::client] written 36 bytes from 0.0.0.0:40006 to 193.167.100.100:443
[2025-04-30T11:50:34.833060053Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:34.863926814Z TRACE quiche_apps::client] got 42 bytes from 193.167.100.100:443 to 0.0.0.0:40006
[2025-04-30T11:50:34.863944948Z TRACE quiche_apps::client] 0.0.0.0:40006: processed 42 bytes
[2025-04-30T11:50:34.863949837Z TRACE quiche_apps::client] 0.0.0.0:40006: recv() would block
[2025-04-30T11:50:34.863953023Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:34.863959275Z TRACE quiche_apps::client] 0.0.0.0:40006 -> 193.167.100.100:443: done writing
[2025-04-30T11:50:35.058179839Z TRACE quiche_apps::client] timed out
[2025-04-30T11:50:35.058202461Z TRACE quiche] 775839e1f2cc0bca99b4bdc756e1f6ca063f9f41 draining timeout expired
[2025-04-30T11:50:35.058243408Z TRACE quiche_apps::client] done reading
[2025-04-30T11:50:35.058247456Z INFO  quiche_apps::client] connection closed, recv=14 sent=15 lost=0 retrans=0 sent_bytes=2332 recv_bytes=12375 lost_bytes=0 [local_addr=0.0.0.0:40006 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=14 sent=15 lost=0 retrans=0 rtt=34.837717ms min_rtt=Some(34.083843ms) rttvar=10.026392ms cwnd=13500 sent_bytes=2332 recv_bytes=12375 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=37847]