[2025-04-30T08:43:15.105853705Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T08:43:15.106164505Z INFO quiche_apps::client] connecting to 193.167.100.100:443 from 0.0.0.0:56733 with scid e1719066debf030cb20c4842a37fba1c51c0ac3e [2025-04-30T08:43:15.106209539Z TRACE quiche::tls] e1719066debf030cb20c4842a37fba1c51c0ac3e write message lvl=Initial len=266 [2025-04-30T08:43:15.106228665Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Initial version=1 dcid=e6afc07748e61a146a56f0c7b4b36dbe scid=e1719066debf030cb20c4842a37fba1c51c0ac3e len=270 pn=0 [2025-04-30T08:43:15.106231891Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm CRYPTO off=0 len=266 [2025-04-30T08:43:15.106246128Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=998.927104ms 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: 329, tv_nsec: 474299441 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 474299441 }, 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: 329, tv_nsec: 474299441 }, next_time: Instant { tv_sec: 329, tv_nsec: 474299441 }, 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-30T08:43:15.106314185Z TRACE quiche_apps::client] written 1200 [2025-04-30T08:43:15.143232239Z TRACE quiche_apps::client] got 151 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.143258939Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Retry version=1 dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e scid=35caec552581a8454bfc1f4eda5ea9d8c7c1 token=b775ea39f1a30f43e97503ee3ab0e5fea217f6bcadabed65ed06a9f8b1c098ab8a4f9e5fcb16c740b85ae13311f0345e2ca732cd242496d401404cce40d36b1ea941b251cb1623d10c0ada0bb345f7464c2686d3c2360d61acc3 [2025-04-30T08:43:15.143292481Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e dropped epoch 0 state [2025-04-30T08:43:15.143304103Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 151 bytes [2025-04-30T08:43:15.143309002Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.143312348Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.143358184Z TRACE quiche::tls] e1719066debf030cb20c4842a37fba1c51c0ac3e write message lvl=Initial len=266 [2025-04-30T08:43:15.143379303Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Initial version=1 dcid=35caec552581a8454bfc1f4eda5ea9d8c7c1 scid=e1719066debf030cb20c4842a37fba1c51c0ac3e token=b775ea39f1a30f43e97503ee3ab0e5fea217f6bcadabed65ed06a9f8b1c098ab8a4f9e5fcb16c740b85ae13311f0345e2ca732cd242496d401404cce40d36b1ea941b251cb1623d10c0ada0bb345f7464c2686d3c2360d61acc3 len=270 pn=1 [2025-04-30T08:43:15.143383251Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm CRYPTO off=0 len=266 [2025-04-30T08:43:15.143399561Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=998.921163ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=426 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 329, tv_nsec: 511446943 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 511446943 }, end_of_app_limited: 1, last_sent_packet: 1, largest_acked: 0, rate_sample: RateSample { delivery_rate: 0, is_app_limited: false, interval: 0ns, delivered: 0, prior_delivered: 0, prior_time: None, send_elapsed: 0ns, ack_elapsed: 0ns, rtt: 0ns } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 329, tv_nsec: 511446943 }, next_time: Instant { tv_sec: 329, tv_nsec: 511446943 }, 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-30T08:43:15.143468039Z TRACE quiche_apps::client] written 1200 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.143474871Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.177655049Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.177675698Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Initial version=1 dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e scid=0a7502f42b592f74e45a8ddb6f1896ca96b5 token= len=119 pn=748858384 [2025-04-30T08:43:15.177690706Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm ACK delay=0 blocks=[1..1] ecn_counts=None [2025-04-30T08:43:15.177697588Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e packet newly acked 1 [2025-04-30T08:43:15.177703660Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm CRYPTO off=0 len=90 [2025-04-30T08:43:15.177810559Z TRACE quiche::tls] e1719066debf030cb20c4842a37fba1c51c0ac3e set write secret lvl=Handshake [2025-04-30T08:43:15.177822111Z TRACE quiche::tls] e1719066debf030cb20c4842a37fba1c51c0ac3e set read secret lvl=Handshake [2025-04-30T08:43:15.177855994Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Handshake version=1 dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e scid=0a7502f42b592f74e45a8ddb6f1896ca96b5 len=754 pn=748858384 [2025-04-30T08:43:15.177863318Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm CRYPTO off=0 len=730 [2025-04-30T08:43:15.178111941Z TRACE quiche::tls] e1719066debf030cb20c4842a37fba1c51c0ac3e write message lvl=Handshake len=36 [2025-04-30T08:43:15.178116390Z TRACE quiche::tls] e1719066debf030cb20c4842a37fba1c51c0ac3e set write secret lvl=OneRTT [2025-04-30T08:43:15.178123283Z TRACE quiche::tls] e1719066debf030cb20c4842a37fba1c51c0ac3e set read secret lvl=OneRTT [2025-04-30T08:43:15.178138751Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e 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(e6afc07748e61a146a56f0c7b4b36dbe), max_idle_timeout: 30000, stateless_reset_token: Some(177687995240187434505195593278769936343), max_udp_payload_size: 65527, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 0, initial_max_stream_data_bidi_remote: 262144, initial_max_stream_data_uni: 262144, initial_max_streams_bidi: 100, initial_max_streams_uni: 0, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 7, initial_source_connection_id: Some(0a7502f42b592f74e45a8ddb6f1896ca96b5), retry_source_connection_id: Some(35caec552581a8454bfc1f4eda5ea9d8c7c1), max_datagram_frame_size: None, unknown_params: None } [2025-04-30T08:43:15.178162496Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=207 pn=748858384 [2025-04-30T08:43:15.178169639Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[2f, ec, 38, e1, a1, b1, 01, 64, 6e, 1a, da, 22, e4, 28, 6b, 1b, 3d, 76] reset_token=[46, 98, 86, 16, a9, a2, 60, 1f, 63, 59, dd, f1, 91, ca, c9, f0] [2025-04-30T08:43:15.178177303Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[64, 06, c0, dd, 15, 64, ae, 65, 0c, 6d, 76, 2b, 8d, e2, 56, f4, 4d, 50] reset_token=[c1, b4, 29, 60, 82, 3a, 1a, 8a, b6, ea, 71, 95, ce, bb, d3, 95] [2025-04-30T08:43:15.178188564Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[ed, 5d, ae, 42, 41, 27, ea, 39, 9b, bd, 63, f7, b7, 9b, 2c, cf, 6d, db] reset_token=[c9, b5, 7f, a3, 58, 1e, d2, 02, 74, ae, ca, d6, 90, ee, 6d, f2] [2025-04-30T08:43:15.178194285Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[55, e0, d6, b6, c7, f0, 13, be, 33, d3, 22, 5b, 99, 59, 14, cd, d6, 27] reset_token=[cd, 0d, aa, af, 7c, b4, bd, 32, 16, 5c, 36, 30, 1c, 52, 0d, 70] [2025-04-30T08:43:15.178198994Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm PADDING len=35 [2025-04-30T08:43:15.178206057Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.178210796Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.178213401Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.178221676Z DEBUG quiche_apps::common] sending HTTP request "GET /qjpxfwisjt\r\n" [2025-04-30T08:43:15.178289663Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Initial version=1 dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 scid=e1719066debf030cb20c4842a37fba1c51c0ac3e token=b775ea39f1a30f43e97503ee3ab0e5fea217f6bcadabed65ed06a9f8b1c098ab8a4f9e5fcb16c740b85ae13311f0345e2ca732cd242496d401404cce40d36b1ea941b251cb1623d10c0ada0bb345f7464c2686d3c2360d61acc3 len=9 pn=2 [2025-04-30T08:43:15.178293560Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=77 blocks=[748858384..748858384] ecn_counts=None [2025-04-30T08:43:15.178302216Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=102.419405ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 546262316 }, next_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, 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-30T08:43:15.178322525Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Handshake version=1 dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 scid=e1719066debf030cb20c4842a37fba1c51c0ac3e len=48 pn=3 [2025-04-30T08:43:15.178325360Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=58 blocks=[748858384..748858384] ecn_counts=None [2025-04-30T08:43:15.178328075Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm CRYPTO off=0 len=36 [2025-04-30T08:43:15.178333986Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=102.994338ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=112 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 546403630 }, next_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, max_datagram_size: 1350, last_packet_size: None, iv: 227.983µ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-30T08:43:15.178349324Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e dropped epoch 0 state [2025-04-30T08:43:15.178354184Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=false hs_sent=false cwnd_avail=13388 out_len=1073 left=1029 [2025-04-30T08:43:15.178364363Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=1037 pn=4 [2025-04-30T08:43:15.178367028Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=23 blocks=[748858384..748858384] ecn_counts=None [2025-04-30T08:43:15.178369682Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[b8, bf, 02, db, 83, d5, 4d, d6, fe, 95, ea, a6, 36, e4, 88, b5, 24, 39, 74, 8e] reset_token=[51, 0e, e7, ac, 53, 71, 12, 69, 4f, 1e, 37, 71, a0, 7a, e2, ad] [2025-04-30T08:43:15.178375633Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[70, 71, fe, 2c, 16, d5, d3, f7, 74, 89, 26, f3, f8, 48, 3b, ff, 74, 34, b4, 0f] reset_token=[c1, 01, f7, 3c, f8, 0d, 23, dc, 95, 56, cd, da, 7d, a2, 1b, 54] [2025-04-30T08:43:15.178381134Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[61, 22, 80, 3e, d3, 97, ed, c6, e7, 22, 82, 0c, 48, 36, fa, e3, 76, be, 46, de] reset_token=[e9, e2, 21, 18, f3, a8, dd, b7, 84, 36, ee, e0, 3f, 76, 48, 5e] [2025-04-30T08:43:15.178386424Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 1f, c7, dd, d1, 4e, be, c4, 5f, 54, 44, fd, 6b, bf, 29, 48, 11, 1b, d2, b2] reset_token=[e4, 3c, d2, 46, b0, 12, 6a, c4, 9c, d7, 0e, 33, 80, 70, 3f, 61] [2025-04-30T08:43:15.178391694Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[44, cb, 00, 1c, e5, 4a, 52, 02, 91, e7, 4c, b5, ad, 84, 3d, 30, e8, 04, 04, f7] reset_token=[47, 90, 80, 19, 99, 33, 5b, 81, de, 6b, e4, 01, 04, 15, 28, cf] [2025-04-30T08:43:15.178397164Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[1b, 27, 13, cc, 48, 0b, 18, 9b, b5, c8, 37, c7, 95, b7, 75, 74, 92, d7, cd, a6] reset_token=[85, b6, 79, ce, 86, 32, 6d, 4a, 3a, 1f, 00, ff, 4e, e4, 52, 23] [2025-04-30T08:43:15.178402374Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm STREAM id=0 off=0 len=17 fin=true [2025-04-30T08:43:15.178404908Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm PADDING len=767 [2025-04-30T08:43:15.178413414Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=102.91491ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1185 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 1073, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 546403630 }, next_time: Instant { tv_sec: 329, tv_nsec: 546631613 }, max_datagram_size: 1350, last_packet_size: Some(1073), 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-30T08:43:15.178459550Z TRACE quiche_apps::client] written 1350 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.178464730Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.178548747Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.178553465Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858385 [2025-04-30T08:43:15.178561180Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[74, c0, 56, 29, b8, 54, 80, 86, 58, 7e, 73, 65, 61, 5b, e0, 77, 5a, 51] reset_token=[7b, 4e, 8e, 9b, 6f, 9c, 64, c5, 47, ff, ea, 85, be, 91, b9, b8] [2025-04-30T08:43:15.178568043Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[ad, 67, 59, 2f, ca, 42, 2d, 74, 87, ff, de, c1, 48, a5, ef, dd, be, 6f] reset_token=[c7, 13, 0a, 1e, 9f, 79, 78, d4, a2, bc, 47, 6a, f5, 18, 4c, 50] [2025-04-30T08:43:15.178573944Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[90, 82, 0c, bd, 0e, 18, a9, 36, 78, a9, 0b, 08, 76, 53, 6d, 93, 0f, 5a] reset_token=[65, 02, 2c, ea, 4b, 0e, 3e, 34, 94, 44, 9f, cc, 77, 22, c2, 88] [2025-04-30T08:43:15.178581949Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm PADDING len=1045 [2025-04-30T08:43:15.178606234Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.178610282Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.178612656Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.178619188Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12315 out_len=1350 left=1306 [2025-04-30T08:43:15.178622454Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=5 [2025-04-30T08:43:15.178624999Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=8 blocks=[748858384..748858385] ecn_counts=None [2025-04-30T08:43:15.178631451Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=102.696743ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1185 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 546741791 }, next_time: Instant { tv_sec: 329, tv_nsec: 546631613 }, max_datagram_size: 1350, last_packet_size: None, iv: 2.184162ms, 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-30T08:43:15.178668239Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.178672668Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.212018790Z TRACE quiche_apps::client] got 1342 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.212034850Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1321 pn=748858386 [2025-04-30T08:43:15.212045239Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm PING mtu_probe=None [2025-04-30T08:43:15.212054396Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm PADDING len=1303 [2025-04-30T08:43:15.212064776Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1342 bytes [2025-04-30T08:43:15.212069194Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.212072200Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.212082689Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=false hs_sent=false cwnd_avail=12315 out_len=1350 left=1306 [2025-04-30T08:43:15.212087678Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=6 [2025-04-30T08:43:15.212090804Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=6 blocks=[748858384..748858386] ecn_counts=None [2025-04-30T08:43:15.212100913Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=69.22714ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1185 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.212147771Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.212153291Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.212985814Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.212994290Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858387 [2025-04-30T08:43:15.213000622Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm HANDSHAKE_DONE [2025-04-30T08:43:15.213006122Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e dropped epoch 1 state [2025-04-30T08:43:15.213012514Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm NEW_TOKEN len=41 [2025-04-30T08:43:15.213016091Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm CRYPTO off=0 len=237 [2025-04-30T08:43:15.213045716Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=0 len=873 fin=false [2025-04-30T08:43:15.213058029Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.213062848Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.213065924Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.213071164Z TRACE quiche_apps::common] received 873 bytes [2025-04-30T08:43:15.213074179Z TRACE quiche_apps::common] stream 0 has 873 bytes (fin? false) [2025-04-30T08:43:15.213082525Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.213086472Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=7 [2025-04-30T08:43:15.213089558Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=11 blocks=[748858384..748858387] ecn_counts=None [2025-04-30T08:43:15.213097192Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=93.230862ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.213138229Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.213143358Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.213958269Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.213965422Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858388 [2025-04-30T08:43:15.213971393Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=873 len=1156 fin=false [2025-04-30T08:43:15.213978006Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.213981512Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.213983836Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.213987664Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.213989848Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.213995919Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.213998835Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=8 [2025-04-30T08:43:15.214001179Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=3 blocks=[748858384..748858388] ecn_counts=None [2025-04-30T08:43:15.214007340Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=92.320924ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.214043588Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.214047866Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.214940902Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.214947785Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858389 [2025-04-30T08:43:15.214953596Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=2029 len=1156 fin=false [2025-04-30T08:43:15.214959938Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.214963364Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.214965729Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.214969516Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.214971760Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.214977982Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.214981017Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=9 [2025-04-30T08:43:15.214983472Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=3 blocks=[748858384..748858389] ecn_counts=None [2025-04-30T08:43:15.214988902Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=91.339332ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.215024088Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.215028145Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.215924668Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.215931361Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858390 [2025-04-30T08:43:15.215937051Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=3185 len=1156 fin=false [2025-04-30T08:43:15.215943423Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.215946890Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.215949284Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.215953031Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.215955255Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.215961286Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.215964292Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=10 [2025-04-30T08:43:15.215966647Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=3 blocks=[748858384..748858390] ecn_counts=None [2025-04-30T08:43:15.215972217Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=90.356037ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.216002363Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.216006160Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.216925756Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.216932438Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858391 [2025-04-30T08:43:15.216937959Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=4341 len=1156 fin=false [2025-04-30T08:43:15.216944070Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.216947486Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.216949871Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.216953488Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.216955692Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.216966662Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.216969688Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=11 [2025-04-30T08:43:15.216972022Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=4 blocks=[748858384..748858391] ecn_counts=None [2025-04-30T08:43:15.216977462Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=89.350772ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 11, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.217007899Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.217011726Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.217891578Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.217898291Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858392 [2025-04-30T08:43:15.217903841Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=5497 len=1156 fin=false [2025-04-30T08:43:15.217909942Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.217913369Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.217915743Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.217919440Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.217921644Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.217927455Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.217930360Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=12 [2025-04-30T08:43:15.217932675Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=3 blocks=[748858384..748858392] ecn_counts=None [2025-04-30T08:43:15.217938375Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=88.389899ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 12, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.217973511Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.217977519Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.218892135Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.218899709Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858393 [2025-04-30T08:43:15.218905460Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=6653 len=1156 fin=false [2025-04-30T08:43:15.218911982Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.218915559Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.218917923Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.218921690Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.218923894Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.218929986Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.218932971Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=13 [2025-04-30T08:43:15.218935336Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=3 blocks=[748858384..748858393] ecn_counts=None [2025-04-30T08:43:15.218941026Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=87.387258ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 13, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.218971634Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.218975430Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.219859029Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.219863327Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858394 [2025-04-30T08:43:15.219873537Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=7809 len=1156 fin=false [2025-04-30T08:43:15.219897361Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.219901348Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.219903733Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.219907570Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.219909794Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.219930623Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.219933849Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=14 [2025-04-30T08:43:15.219936294Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=8 blocks=[748858384..748858394] ecn_counts=None [2025-04-30T08:43:15.219942285Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=86.385929ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 14, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.219973864Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.219977791Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.220845219Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.220849778Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858395 [2025-04-30T08:43:15.220855589Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=8965 len=1156 fin=false [2025-04-30T08:43:15.220862051Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.220865477Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.220867801Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.220871528Z TRACE quiche_apps::common] received 1156 bytes [2025-04-30T08:43:15.220873712Z TRACE quiche_apps::common] stream 0 has 1156 bytes (fin? false) [2025-04-30T08:43:15.220898779Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12427 out_len=1350 left=1306 [2025-04-30T08:43:15.220902015Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=8 pn=15 [2025-04-30T08:43:15.220904440Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm ACK delay=6 blocks=[748858384..748858395] ecn_counts=None [2025-04-30T08:43:15.220910331Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=85.417923ms latest_rtt=34.350165ms srtt=34.350165ms min_rtt=34.350165ms rttvar=17.175082ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1073 app_limited=true congestion_recovery_start_time=None Rate { delivered: 426, delivered_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 546262356 }, end_of_app_limited: 3, last_sent_packet: 15, largest_acked: 1, rate_sample: RateSample { delivery_rate: 12401, is_app_limited: true, interval: 34.350165ms, delivered: 426, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 511446943 }), send_elapsed: 0ns, ack_elapsed: 34.350165ms, rtt: 34.350165ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 491264, last_update: Instant { tv_sec: 329, tv_nsec: 580202366 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, 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-30T08:43:15.220945917Z TRACE quiche_apps::client] written 44 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.220949905Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.221827412Z TRACE quiche_apps::client] got 1200 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.221831981Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=1179 pn=748858396 [2025-04-30T08:43:15.221837281Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm STREAM id=0 off=10121 len=119 fin=true [2025-04-30T08:43:15.221844263Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm PADDING len=1037 [2025-04-30T08:43:15.221850405Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 1200 bytes [2025-04-30T08:43:15.221854813Z TRACE quiche_apps::client] got 44 bytes from 193.167.100.100:443 to 0.0.0.0:56733 [2025-04-30T08:43:15.221857669Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx pkt Short dcid=e1719066debf030cb20c4842a37fba1c51c0ac3e key_phase=false len=23 pn=748858397 [2025-04-30T08:43:15.221861997Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e rx frm ACK delay=486 blocks=[4..5] ecn_counts=None [2025-04-30T08:43:15.221865363Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e packet newly acked 4 [2025-04-30T08:43:15.221869120Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e packet newly acked 5 [2025-04-30T08:43:15.221894577Z TRACE quiche_apps::client] 0.0.0.0:56733: processed 44 bytes [2025-04-30T08:43:15.221901360Z TRACE quiche_apps::client] 0.0.0.0:56733: recv() would block [2025-04-30T08:43:15.221903825Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.221909135Z TRACE quiche_apps::common] received 119 bytes [2025-04-30T08:43:15.221911419Z TRACE quiche_apps::common] stream 0 has 119 bytes (fin? true) [2025-04-30T08:43:15.221913633Z DEBUG quiche_apps::common] 1/1 responses received [2025-04-30T08:43:15.221915877Z INFO quiche_apps::common] 1/1 response(s) received in 115.597184ms, closing... [2025-04-30T08:43:15.221922389Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1314 [2025-04-30T08:43:15.221925605Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx pkt Short dcid=0a7502f42b592f74e45a8ddb6f1896ca96b5 key_phase=false len=10 pn=16 [2025-04-30T08:43:15.221928040Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e tx frm APPLICATION_CLOSE err=0 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-04-30T08:43:15.221947296Z TRACE quiche::recovery::congestion::recovery] e1719066debf030cb20c4842a37fba1c51c0ac3e timer=116.6ms latest_rtt=43.351253ms srtt=34.9893ms min_rtt=34.350165ms rttvar=14.159583ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=46 app_limited=true congestion_recovery_start_time=None Rate { delivered: 1499, delivered_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, first_sent_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, end_of_app_limited: 15, last_sent_packet: 16, largest_acked: 5, rate_sample: RateSample { delivery_rate: 24542, is_app_limited: true, interval: 43.72051ms, delivered: 1073, prior_delivered: 426, prior_time: Some(Instant { tv_sec: 329, tv_nsec: 546262356 }), send_elapsed: 369.257µs, ack_elapsed: 43.72051ms, rtt: 43.351253ms } } pacer=Pacer { enabled: true, capacity: 13500, used: 0, rate: 482290, last_update: Instant { tv_sec: 329, tv_nsec: 590045563 }, next_time: Instant { tv_sec: 329, tv_nsec: 582386528 }, max_datagram_size: 1350, last_packet_size: None, iv: 95.378µ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-30T08:43:15.221982862Z TRACE quiche_apps::client] written 46 bytes from 0.0.0.0:56733 to 193.167.100.100:443 [2025-04-30T08:43:15.221986800Z TRACE quiche_apps::client] 0.0.0.0:56733 -> 193.167.100.100:443: done writing [2025-04-30T08:43:15.497313582Z TRACE quiche_apps::client] timed out [2025-04-30T08:43:15.497338799Z TRACE quiche] e1719066debf030cb20c4842a37fba1c51c0ac3e draining timeout expired [2025-04-30T08:43:15.497376600Z TRACE quiche_apps::client] done reading [2025-04-30T08:43:15.497381529Z INFO quiche_apps::client] connection closed, recv=16 sent=17 lost=0 retrans=0 sent_bytes=2639 recv_bytes=15786 lost_bytes=0 [local_addr=0.0.0.0:56733 peer_addr=193.167.100.100:443 validation_state=Validated active=true recv=16 sent=17 lost=0 retrans=0 rtt=34.9893ms min_rtt=Some(34.350165ms) rttvar=14.159583ms cwnd=13500 sent_bytes=2639 recv_bytes=15786 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=24542]