[2025-04-30T21:40:50.071093331Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T21:40:50.071448210Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:38194 with scid 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 [2025-04-30T21:40:50.071491761Z TRACE quiche::tls] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 write message lvl=Initial len=266 [2025-04-30T21:40:50.071510796Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx pkt Initial version=1 dcid=dbcc3b342033f1d3a25a2d40a9db2ecc scid=8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 len=270 pn=0 [2025-04-30T21:40:50.071514353Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm CRYPTO off=0 len=266 [2025-04-30T21:40:50.071528799Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 timer=998.928056ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=333 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 181, tv_nsec: 111140496 }, first_sent_time: Instant { tv_sec: 181, tv_nsec: 111140496 }, 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: 181, tv_nsec: 111140496 }, next_time: Instant { tv_sec: 181, tv_nsec: 111140496 }, 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-30T21:40:50.071624688Z TRACE quiche_apps::client] written 1200 [2025-04-30T21:40:50.111259947Z TRACE quiche_apps::client] got 1232 bytes from 193.167.100.100:443 to 0.0.0.0:38194 [2025-04-30T21:40:50.111279673Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx pkt Initial version=1 dcid=8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 scid=97e461aedc35c857ee3c44fcb7b5563c token= len=117 pn=0 [2025-04-30T21:40:50.111298719Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm ACK delay=1573 blocks=[0..0] ecn_counts=None [2025-04-30T21:40:50.111305261Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 packet newly acked 0 [2025-04-30T21:40:50.111311492Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm CRYPTO off=0 len=90 [2025-04-30T21:40:50.111434862Z TRACE quiche::tls] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 set write secret lvl=Handshake [2025-04-30T21:40:50.111447876Z TRACE quiche::tls] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 set read secret lvl=Handshake [2025-04-30T21:40:50.111478302Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx pkt Handshake version=1 dcid=8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 scid=97e461aedc35c857ee3c44fcb7b5563c len=1024 pn=0 [2025-04-30T21:40:50.111485516Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm CRYPTO off=0 len=692 [2025-04-30T21:40:50.111723738Z TRACE quiche::tls] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 write message lvl=Handshake len=36 [2025-04-30T21:40:50.111729088Z TRACE quiche::tls] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 set write secret lvl=OneRTT [2025-04-30T21:40:50.111737313Z TRACE quiche::tls] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 set read secret lvl=OneRTT [2025-04-30T21:40:50.111754625Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=Some("ecdsa_secp256r1_sha256") resumed=false TransportParams { original_destination_connection_id: Some(dbcc3b342033f1d3a25a2d40a9db2ecc), max_idle_timeout: 300000, stateless_reset_token: None, max_udp_payload_size: 65527, initial_max_data: 3750000, initial_max_stream_data_bidi_local: 3750000, initial_max_stream_data_bidi_remote: 3750000, initial_max_stream_data_uni: 3750000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 3, initial_source_connection_id: Some(97e461aedc35c857ee3c44fcb7b5563c), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-04-30T21:40:50.111776316Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm PADDING len=311 [2025-04-30T21:40:50.111791975Z TRACE quiche_apps::client] 0.0.0.0:38194: processed 1232 bytes [2025-04-30T21:40:50.111797625Z TRACE quiche_apps::client] 0.0.0.0:38194: recv() would block [2025-04-30T21:40:50.111801102Z TRACE quiche_apps::client] done reading [2025-04-30T21:40:50.111814496Z DEBUG quiche_apps::common] sending HTTP request "GET /qlzpwlixyf\r\n" [2025-04-30T21:40:50.111876442Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx pkt Initial version=1 dcid=97e461aedc35c857ee3c44fcb7b5563c scid=8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 len=6 pn=1 [2025-04-30T21:40:50.111881060Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm ACK delay=74 blocks=[0..0] ecn_counts=None [2025-04-30T21:40:50.111891049Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 timer=118.83827ms latest_rtt=39.818048ms srtt=39.818048ms min_rtt=39.818048ms rttvar=19.909024ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, first_sent_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8363, is_app_limited: true, interval: 39.818048ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 181, tv_nsec: 111140496 }), send_elapsed: 0ns, ack_elapsed: 39.818048ms, rtt: 39.818048ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423802, last_update: Instant { tv_sec: 181, tv_nsec: 151435601 }, next_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, 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-30T21:40:50.111919602Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx pkt Handshake version=1 dcid=97e461aedc35c857ee3c44fcb7b5563c scid=8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 len=45 pn=2 [2025-04-30T21:40:50.111923168Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm ACK delay=55 blocks=[0..0] ecn_counts=None [2025-04-30T21:40:50.111926504Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm CRYPTO off=0 len=36 [2025-04-30T21:40:50.111933798Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 timer=119.38754ms latest_rtt=39.818048ms srtt=39.818048ms min_rtt=39.818048ms rttvar=19.909024ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=107 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, first_sent_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8363, is_app_limited: true, interval: 39.818048ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 181, tv_nsec: 111140496 }), send_elapsed: 0ns, ack_elapsed: 39.818048ms, rtt: 39.818048ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423802, last_update: Instant { tv_sec: 181, tv_nsec: 151550263 }, next_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, max_datagram_size: 1350, last_packet_size: None, iv: 252.476µ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-30T21:40:50.111952222Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 dropped epoch 0 state [2025-04-30T21:40:50.111958033Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13393 out_len=1174 left=1140 [2025-04-30T21:40:50.111971278Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx pkt Short dcid=97e461aedc35c857ee3c44fcb7b5563c key_phase=false len=1140 pn=3 [2025-04-30T21:40:50.111974484Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[62, 6d, d4, b9, 11, 49, 92, 48, 89, 95, de, 2e, e0, 89, 6e, 1c, 10, 7c, 03, bf] reset_token=[5d, 35, 70, 6f, aa, c1, fc, dd, 56, e8, 59, fd, d8, 35, f6, 2f] [2025-04-30T21:40:50.111982428Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[cf, b5, db, 5e, 59, 28, d0, 96, 36, 06, 45, 59, ea, c5, 7d, 30, 59, d5, 3a, 91] reset_token=[09, 03, 07, 1e, d0, f7, 19, ef, 3d, 27, 9d, 6a, 79, 27, f7, f9] [2025-04-30T21:40:50.111989151Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm STREAM id=0 off=0 len=17 fin=true [2025-04-30T21:40:50.111992387Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm PADDING len=1038 [2025-04-30T21:40:50.112001263Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 timer=119.320085ms latest_rtt=39.818048ms srtt=39.818048ms min_rtt=39.818048ms rttvar=19.909024ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1281 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, first_sent_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8363, is_app_limited: true, interval: 39.818048ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 181, tv_nsec: 111140496 }), send_elapsed: 0ns, ack_elapsed: 39.818048ms, rtt: 39.818048ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1174, rate: 423802, last_update: Instant { tv_sec: 181, tv_nsec: 151550263 }, next_time: Instant { tv_sec: 181, tv_nsec: 151802739 }, max_datagram_size: 1350, last_packet_size: Some(1174), 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-30T21:40:50.112049042Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:38194 to 193.167.100.100:443 [2025-04-30T21:40:50.112054893Z TRACE quiche_apps::client] 0.0.0.0:38194 -> 193.167.100.100:443: done writing [2025-04-30T21:40:50.146728307Z TRACE quiche_apps::client] got 1452 bytes from 193.167.100.100:443 to 0.0.0.0:38194 [2025-04-30T21:40:50.146757861Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx pkt Short dcid=626dd4b9114992488995de2ee0896e1c107c03bf key_phase=false len=1431 pn=0 [2025-04-30T21:40:50.146766477Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 path ID 0 now see SCID with seq num 1 [2025-04-30T21:40:50.146771326Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm PING mtu_probe=None [2025-04-30T21:40:50.146781265Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm PADDING len=1413 [2025-04-30T21:40:50.146790973Z TRACE quiche_apps::client] 0.0.0.0:38194: processed 1452 bytes [2025-04-30T21:40:50.146795952Z TRACE quiche_apps::client] 0.0.0.0:38194: recv() would block [2025-04-30T21:40:50.146799379Z TRACE quiche_apps::client] done reading [2025-04-30T21:40:50.146817051Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12219 out_len=1350 left=1311 [2025-04-30T21:40:50.146822010Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx pkt Short dcid=97e461aedc35c857ee3c44fcb7b5563c key_phase=false len=5 pn=4 [2025-04-30T21:40:50.146825387Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm ACK delay=7 blocks=[0..0] ecn_counts=None [2025-04-30T21:40:50.146835265Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 timer=84.485772ms latest_rtt=39.818048ms srtt=39.818048ms min_rtt=39.818048ms rttvar=19.909024ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1281 app_limited=true congestion_recovery_start_time=None Rate { delivered: 333, delivered_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, first_sent_time: Instant { tv_sec: 181, tv_nsec: 151435641 }, end_of_app_limited: 2, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 8363, is_app_limited: true, interval: 39.818048ms, delivered: 333, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 181, tv_nsec: 111140496 }), send_elapsed: 0ns, ack_elapsed: 39.818048ms, rtt: 39.818048ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 423802, last_update: Instant { tv_sec: 181, tv_nsec: 186494259 }, next_time: Instant { tv_sec: 181, tv_nsec: 186494259 }, 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-30T21:40:50.146886741Z TRACE quiche_apps::client] written 39 bytes from 0.0.0.0:38194 to 193.167.100.100:443 [2025-04-30T21:40:50.146892852Z TRACE quiche_apps::client] 0.0.0.0:38194 -> 193.167.100.100:443: done writing [2025-04-30T21:40:50.146903612Z TRACE quiche_apps::client] got 155 bytes from 193.167.100.100:443 to 0.0.0.0:38194 [2025-04-30T21:40:50.146908020Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx pkt Short dcid=626dd4b9114992488995de2ee0896e1c107c03bf key_phase=false len=134 pn=1 [2025-04-30T21:40:50.146914572Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm ACK delay=175 blocks=[3..3] ecn_counts=None [2025-04-30T21:40:50.146918750Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 packet newly acked 3 [2025-04-30T21:40:50.146923790Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm HANDSHAKE_DONE [2025-04-30T21:40:50.146928628Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 dropped epoch 1 state [2025-04-30T21:40:50.146934369Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=1 conn_id=[25, df, 26, 57, 95, 1e, 68, 75, ae, 35, b1, aa, f5, d5, 56, d1] reset_token=[1d, f9, b4, 80, d9, 18, fa, da, 44, 4d, 0e, 78, a1, f2, 0d, 5f] [2025-04-30T21:40:50.146941572Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 path ID 0 changed DCID: old seq num 0 new seq num 1 [2025-04-30T21:40:50.146946301Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=1 conn_id=[cd, f7, f2, 98, 5a, c3, ec, dc, c5, 79, 2e, 5e, ee, 79, 85, 8f] reset_token=[9a, 84, 1e, e3, f1, 7c, 75, 7e, e7, 95, db, b1, c5, 6a, e3, 4e] [2025-04-30T21:40:50.146953645Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[58, be, e6, 44, 6b, 1a, 4e, 00, 37, 1e, bf, 30, 59, 99, bf, a8] reset_token=[c5, b9, 12, 2c, f1, e1, 83, 27, 0d, 78, 57, c3, ba, 0a, 4a, c9] [2025-04-30T21:40:50.146958885Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm RETIRE_CONNECTION_ID seq_num=0 [2025-04-30T21:40:50.146973502Z TRACE quiche_apps::client] 0.0.0.0:38194: processed 155 bytes [2025-04-30T21:40:50.146978240Z TRACE quiche_apps::client] 0.0.0.0:38194: recv() would block [2025-04-30T21:40:50.146987077Z TRACE quiche_apps::client] done reading [2025-04-30T21:40:50.146990403Z INFO quiche_apps::client] Retiring source CID 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 [2025-04-30T21:40:50.147001444Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1311 [2025-04-30T21:40:50.147007365Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx pkt Short dcid=25df2657951e6875ae35b1aaf5d556d1 key_phase=false len=47 pn=5 [2025-04-30T21:40:50.147010811Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm ACK delay=11 blocks=[0..1] ecn_counts=None [2025-04-30T21:40:50.147014678Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=1 conn_id=[91, b9, 51, f3, 67, 38, 94, e6, 25, cf, 4c, 90, 00, 81, 18, 7c, cf, 43, a4, a7] reset_token=[fa, 25, 63, 9c, 0f, 71, ed, 9c, f1, 65, b8, e8, 7e, f6, 07, 2e] [2025-04-30T21:40:50.147021832Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm RETIRE_CONNECTION_ID seq_num=0 [2025-04-30T21:40:50.147030277Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 timer=128.914611ms latest_rtt=34.787198ms srtt=39.189191ms min_rtt=34.787198ms rttvar=16.18948ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=81 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1507, delivered_time: Instant { tv_sec: 181, tv_nsec: 186494259 }, first_sent_time: Instant { tv_sec: 181, tv_nsec: 186494259 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 3, rate_sample: RateSample { delivery_rate: 33395, is_app_limited: true, interval: 35.154296ms, delivered: 1174, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 181, tv_nsec: 151435641 }), send_elapsed: 367.098µs, ack_elapsed: 35.154296ms, rtt: 34.787198ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 430603, last_update: Instant { tv_sec: 181, tv_nsec: 186680946 }, next_time: Instant { tv_sec: 181, tv_nsec: 186494259 }, max_datagram_size: 1350, last_packet_size: None, iv: 188.108µ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-30T21:40:50.147073197Z TRACE quiche_apps::client] written 81 bytes from 0.0.0.0:38194 to 193.167.100.100:443 [2025-04-30T21:40:50.147078817Z TRACE quiche_apps::client] 0.0.0.0:38194 -> 193.167.100.100:443: done writing [2025-04-30T21:40:50.147720710Z TRACE quiche_apps::client] got 1067 bytes from 193.167.100.100:443 to 0.0.0.0:38194 [2025-04-30T21:40:50.147728204Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx pkt Short dcid=626dd4b9114992488995de2ee0896e1c107c03bf key_phase=false len=1046 pn=2 [2025-04-30T21:40:50.147733583Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm HANDSHAKE_DONE [2025-04-30T21:40:50.147736549Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm STREAM id=0 off=0 len=1024 fin=false [2025-04-30T21:40:50.147743452Z TRACE quiche_apps::client] 0.0.0.0:38194: processed 1067 bytes [2025-04-30T21:40:50.147747950Z TRACE quiche_apps::client] got 57 bytes from 193.167.100.100:443 to 0.0.0.0:38194 [2025-04-30T21:40:50.147750835Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx pkt Short dcid=626dd4b9114992488995de2ee0896e1c107c03bf key_phase=false len=36 pn=3 [2025-04-30T21:40:50.147754512Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm HANDSHAKE_DONE [2025-04-30T21:40:50.147756907Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm STREAM id=0 off=1024 len=0 fin=true [2025-04-30T21:40:50.147760032Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 rx frm PADDING len=13 [2025-04-30T21:40:50.147764230Z TRACE quiche_apps::client] 0.0.0.0:38194: processed 57 bytes [2025-04-30T21:40:50.147767597Z TRACE quiche_apps::client] 0.0.0.0:38194: recv() would block [2025-04-30T21:40:50.147774490Z TRACE quiche_apps::client] done reading [2025-04-30T21:40:50.147780140Z TRACE quiche_apps::common] received 1024 bytes [2025-04-30T21:40:50.147782725Z TRACE quiche_apps::common] stream 0 has 1024 bytes (fin? true) [2025-04-30T21:40:50.147785069Z DEBUG quiche_apps::common] 1/1 responses received [2025-04-30T21:40:50.147787403Z INFO quiche_apps::common] 1/1 response(s) received in 76.158758ms, closing... [2025-04-30T21:40:50.147793485Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13419 out_len=1350 left=1316 [2025-04-30T21:40:50.147796741Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx pkt Short dcid=25df2657951e6875ae35b1aaf5d556d1 key_phase=false len=10 pn=6 [2025-04-30T21:40:50.147799145Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-04-30T21:40:50.147825414Z TRACE quiche::recovery::congestion::recovery] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 timer=128.912557ms latest_rtt=34.787198ms srtt=39.189191ms min_rtt=34.787198ms rttvar=16.18948ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=125 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1507, delivered_time: Instant { tv_sec: 181, tv_nsec: 186494259 }, first_sent_time: Instant { tv_sec: 181, tv_nsec: 186494259 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 3, rate_sample: RateSample { delivery_rate: 33395, is_app_limited: true, interval: 35.154296ms, delivered: 1174, prior_delivered: 333, prior_time: Some(Instant { tv_sec: 181, tv_nsec: 151435641 }), send_elapsed: 367.098µs, ack_elapsed: 35.154296ms, rtt: 34.787198ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 44, rate: 430603, last_update: Instant { tv_sec: 181, tv_nsec: 186680946 }, next_time: Instant { tv_sec: 181, tv_nsec: 187662077 }, max_datagram_size: 1350, last_packet_size: Some(44), 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-30T21:40:50.147859617Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:38194 to 193.167.100.100:443 [2025-04-30T21:40:50.147863555Z TRACE quiche_apps::client] 0.0.0.0:38194 -> 193.167.100.100:443: done writing [2025-04-30T21:40:50.460234794Z TRACE quiche_apps::client] timed out [2025-04-30T21:40:50.460258668Z TRACE quiche] 8a6c27ad32e5abcc6ddf5a543c4d8e7946dd72f4 draining timeout expired [2025-04-30T21:40:50.460295446Z TRACE quiche_apps::client] done reading [2025-04-30T21:40:50.460300115Z INFO quiche_apps::client] connection closed, recv=6 sent=7 lost=0 retrans=0 sent_bytes=1847 recv_bytes=3963 lost_bytes=0 [local_addr=0.0.0.0:38194 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=6 sent=7 lost=0 retrans=0 rtt=39.189191ms min_rtt=Some(34.787198ms) rttvar=16.18948ms cwnd=13500 sent_bytes=1847 recv_bytes=3963 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=33395]