[2025-04-30T09:10:56.310624911Z INFO quiche_server] listening on [::]:443 [2025-04-30T09:10:56.310646753Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T09:10:56.310653415Z TRACE quiche_server] GSO detected: false [2025-04-30T09:10:57.120691912Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:48302 to [::]:443 [2025-04-30T09:10:57.120723992Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T09:10:57.120731727Z WARN quiche_server] Doing version negotiation [2025-04-30T09:10:57.120812208Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:48302 to [::]:443 [2025-04-30T09:10:57.120818520Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T09:10:57.120822267Z WARN quiche_server] Doing version negotiation [2025-04-30T09:10:57.120854278Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:48302 to [::]:443 [2025-04-30T09:10:57.120862032Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T09:10:57.120866000Z WARN quiche_server] Doing version negotiation [2025-04-30T09:10:57.120892710Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.342680551Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.342705438Z TRACE quiche_server] got packet Initial version=1 dcid=b536721e62d964a55905768a3f2601f8 scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 token= [2025-04-30T09:10:57.342715848Z WARN quiche_server] Doing stateless retry [2025-04-30T09:10:57.342767004Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.374409933Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.374434669Z TRACE quiche_server] got packet Initial version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 token=71756963686500000000000000000000ffffc1a70064b536721e62d964a55905768a3f2601f8 [2025-04-30T09:10:57.374451552Z DEBUG quiche_server] New connection: dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=1a31c35cb507bb280fb5da536c4c24119f73795c [2025-04-30T09:10:57.374716491Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Initial version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 token=71756963686500000000000000000000ffffc1a70064b536721e62d964a55905768a3f2601f8 len=287 pn=1 [2025-04-30T09:10:57.374733403Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm CRYPTO off=0 len=266 [2025-04-30T09:10:57.374760504Z TRACE quiche::tls] checking peer ALPN Ok("h3") against Ok("h3") [2025-04-30T09:10:57.374861004Z TRACE quiche::tls] 1a31c35cb507bb280fb5da536c4c24119f73795c write message lvl=Initial len=90 [2025-04-30T09:10:57.374892182Z TRACE quiche::tls] 1a31c35cb507bb280fb5da536c4c24119f73795c set write secret lvl=Handshake [2025-04-30T09:10:57.374966572Z TRACE quiche::tls] 1a31c35cb507bb280fb5da536c4c24119f73795c write message lvl=Handshake len=720 [2025-04-30T09:10:57.374970290Z TRACE quiche::tls] 1a31c35cb507bb280fb5da536c4c24119f73795c set write secret lvl=OneRTT [2025-04-30T09:10:57.374977874Z TRACE quiche::tls] 1a31c35cb507bb280fb5da536c4c24119f73795c set read secret lvl=Handshake [2025-04-30T09:10:57.375014823Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c dropped invalid packet [2025-04-30T09:10:57.375018541Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 1200 bytes [2025-04-30T09:10:57.375038768Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.375052725Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Initial version=1 dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 scid=1a31c35cb507bb280fb5da536c4c24119f73795c len=99 pn=0 [2025-04-30T09:10:57.375056782Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm ACK delay=44 blocks=[1..1] ecn_counts=None [2025-04-30T09:10:57.375062043Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm CRYPTO off=0 len=90 [2025-04-30T09:10:57.375080257Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=998.960576ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=166 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 266, tv_nsec: 77508801 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 77508801 }, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 266, tv_nsec: 77508801 }, next_time: Instant { tv_sec: 266, tv_nsec: 77508801 }, 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-30T09:10:57.375110173Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Handshake version=1 dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 scid=1a31c35cb507bb280fb5da536c4c24119f73795c len=724 pn=1 [2025-04-30T09:10:57.375113630Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm CRYPTO off=0 len=720 [2025-04-30T09:10:57.375120142Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=998.921182ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=956 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 266, tv_nsec: 77508801 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 77508801 }, 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: false, capacity: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 266, tv_nsec: 77508801 }, next_time: Instant { tv_sec: 266, tv_nsec: 77508801 }, 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-30T09:10:57.375172921Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59876, at: Instant { tv_sec: 266, tv_nsec: 77508801 } }) [2025-04-30T09:10:57.375179945Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.375184974Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.375189142Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.375192017Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.408435797Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.408454372Z TRACE quiche_server] got packet Initial version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 token=71756963686500000000000000000000ffffc1a70064b536721e62d964a55905768a3f2601f8 [2025-04-30T09:10:57.408469671Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Initial version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 token=71756963686500000000000000000000ffffc1a70064b536721e62d964a55905768a3f2601f8 len=23 pn=2 [2025-04-30T09:10:57.408482886Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=79 blocks=[0..0] ecn_counts=None [2025-04-30T09:10:57.408497173Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 0 [2025-04-30T09:10:57.408520237Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Handshake version=1 dcid=1a31c35cb507bb280fb5da536c4c24119f73795c scid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 len=63 pn=3 [2025-04-30T09:10:57.408527120Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=64 blocks=[1..1] ecn_counts=None [2025-04-30T09:10:57.408530727Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 1 [2025-04-30T09:10:57.408560372Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm CRYPTO off=0 len=36 [2025-04-30T09:10:57.408580831Z TRACE quiche::tls] 1a31c35cb507bb280fb5da536c4c24119f73795c set read secret lvl=OneRTT [2025-04-30T09:10:57.408616669Z TRACE quiche::tls] 1a31c35cb507bb280fb5da536c4c24119f73795c write message lvl=OneRTT len=396 [2025-04-30T09:10:57.408625966Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c dropped epoch 1 state [2025-04-30T09:10:57.408630555Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c connection established: proto=Ok("h3") cipher=Some(AES128_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 30000, stateless_reset_token: None, max_udp_payload_size: 1350, initial_max_data: 10000000, initial_max_stream_data_bidi_local: 1000000, initial_max_stream_data_bidi_remote: 1000000, initial_max_stream_data_uni: 1000000, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(7e8bf38231e73c92e203c62c91b59bdfbf3926e5), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-04-30T09:10:57.408654229Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c dropped epoch 0 state [2025-04-30T09:10:57.408658307Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=1106 pn=4 [2025-04-30T09:10:57.408666623Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[09, 0b, d0, fa, b8, 75, 3b, 9a, 1a, ff, bc, 4f, 6d, 35, 65, 70, 97, b9, 7c, 33] reset_token=[8d, 01, a3, 9b, 88, d7, c1, 5a, 86, b9, be, 49, d6, c3, 70, aa] [2025-04-30T09:10:57.408676451Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[04, 67, 47, 5f, 82, 94, 05, 5a, 3f, e2, 91, 42, f7, 19, f3, 5d, 8c, a8, 60, f5] reset_token=[98, 96, de, 8f, 99, de, ba, c9, c3, 37, a1, 85, 22, 01, 1d, 31] [2025-04-30T09:10:57.408683745Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[aa, bd, 5b, ac, ee, 0f, 57, 8c, 94, e1, e8, 29, 1a, 96, f1, e1, f1, aa, 4b, 83] reset_token=[59, 68, 9c, 80, cb, d2, 8c, 3f, d8, c9, fc, 74, 17, b3, 4b, c5] [2025-04-30T09:10:57.408690748Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[4c, 4b, dc, 09, 3b, 66, 2a, d7, 3c, 20, 0c, d6, 82, da, e1, fb, 61, e3, 1a, cf] reset_token=[1d, 79, 6d, 0e, 2b, b0, cb, d1, 78, d2, 47, a3, 8e, 64, 44, 23] [2025-04-30T09:10:57.408698363Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[35, b8, d1, 45, 56, 42, b6, ab, d0, 10, b0, 53, e7, 66, 37, 5d, f1, 30, 31, 41] reset_token=[c9, b7, e1, d6, e1, aa, 95, 28, e7, 7f, 3a, f1, 14, ae, 8f, b9] [2025-04-30T09:10:57.408705486Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[04, ac, 2d, af, 53, 04, 24, 30, 42, 6b, 57, 9b, f9, 30, 60, 2d, 68, 65, a4, 33] reset_token=[16, df, 98, 73, 83, 60, 38, 36, f8, 69, f0, 67, 2b, 42, 21, 53] [2025-04-30T09:10:57.408717839Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[66, d6, 77, fc, bd, 1a, b3, 79, 87, 3b, f6, 67, f4, 07, cf, c1, c5, 2d, 38, 82] reset_token=[aa, 22, f9, 8d, b3, b1, aa, b3, c8, 6b, 47, bc, e8, b4, 6b, 9c] [2025-04-30T09:10:57.408723600Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm STREAM id=2 off=0 len=19 fin=false [2025-04-30T09:10:57.408731565Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm PADDING len=785 [2025-04-30T09:10:57.408742616Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 1350 bytes [2025-04-30T09:10:57.408756733Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm SETTINGS stream=3 len=18 [2025-04-30T09:10:57.408771921Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c open GREASE stream 15 [2025-04-30T09:10:57.408777702Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c stream id 2 is readable [2025-04-30T09:10:57.408783403Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 2 [2025-04-30T09:10:57.408787841Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c open peer's control stream 2 [2025-04-30T09:10:57.408791929Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 2 [2025-04-30T09:10:57.408795776Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 2 [2025-04-30T09:10:57.408799834Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 16 bytes on stream 2 [2025-04-30T09:10:57.408803310Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm SETTINGS max_field_section=None, qpack_max_table=None, qpack_blocked=None raw=Some([(2530872451322579309, 570809068508355242)]), additional_settings=Some([(2530872451322579309, 570809068508355242)]) stream=2 payload_len=16 [2025-04-30T09:10:57.408816085Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.408820212Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.408825913Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=23 pn=5 [2025-04-30T09:10:57.408831133Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm STREAM id=6 off=0 len=1 fin=false [2025-04-30T09:10:57.408858274Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 44 bytes [2025-04-30T09:10:57.408862041Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c stream id 6 is readable [2025-04-30T09:10:57.408866640Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 6 [2025-04-30T09:10:57.408874424Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.408878031Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.408882890Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=23 pn=6 [2025-04-30T09:10:57.408887720Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm STREAM id=10 off=0 len=1 fin=false [2025-04-30T09:10:57.408893250Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 44 bytes [2025-04-30T09:10:57.408896456Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c stream id 10 is readable [2025-04-30T09:10:57.408900413Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 10 [2025-04-30T09:10:57.408906916Z TRACE quiche_server] got 111 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.408910342Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.408914831Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=90 pn=7 [2025-04-30T09:10:57.408923296Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm STREAM id=0 off=0 len=68 fin=true [2025-04-30T09:10:57.408930140Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 111 bytes [2025-04-30T09:10:57.408933326Z DEBUG quiche_apps::common] 1a31c35cb507bb280fb5da536c4c24119f73795c response stream 0 is writable with capacity Ok(13500) [2025-04-30T09:10:57.408937293Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c stream id 0 is readable [2025-04-30T09:10:57.408941621Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 0 [2025-04-30T09:10:57.408945589Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 7 bytes on stream 0 [2025-04-30T09:10:57.408949446Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 0 [2025-04-30T09:10:57.408952602Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm UNKNOWN raw_type=2948377768677961566 stream=0 payload_len=0 [2025-04-30T09:10:57.408958543Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 0 [2025-04-30T09:10:57.408962350Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 7 bytes on stream 0 [2025-04-30T09:10:57.408965997Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 0 [2025-04-30T09:10:57.408970065Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 18 bytes on stream 0 [2025-04-30T09:10:57.408973010Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm UNKNOWN raw_type=856965510090453279 stream=0 payload_len=18 [2025-04-30T09:10:57.408977850Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 0 [2025-04-30T09:10:57.408981657Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 0 [2025-04-30T09:10:57.408985564Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 30 bytes on stream 0 [2025-04-30T09:10:57.408988539Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm HEADERS stream=0 payload_len=30 [2025-04-30T09:10:57.408992076Z TRACE quiche::h3::qpack::decoder] Header count=0 base=0 [2025-04-30T09:10:57.408995122Z TRACE quiche::h3::qpack::decoder] Indexed index=17 static=true [2025-04-30T09:10:57.408998348Z TRACE quiche::h3::qpack::decoder] Indexed index=23 static=true [2025-04-30T09:10:57.409002466Z TRACE quiche::h3::qpack::decoder] Literal name_idx=0 static=true value=[115, 101, 114, 118, 101, 114, 52] [2025-04-30T09:10:57.409007816Z TRACE quiche::h3::qpack::decoder] Literal name_idx=1 static=true value=[47, 119, 122, 121, 122, 106, 97, 107, 105, 99, 112] [2025-04-30T09:10:57.409011834Z TRACE quiche::h3::qpack::decoder] Literal name_idx=95 static=true value=[113, 117, 105, 99, 104, 101] [2025-04-30T09:10:57.409020670Z INFO quiche_apps::common] 1a31c35cb507bb280fb5da536c4c24119f73795c got request [(":method", "GET"), (":scheme", "https"), (":authority", "server4"), (":path", "/wzyzjakicp"), ("user-agent", "quiche")] on stream id 0 [2025-04-30T09:10:57.409070133Z INFO quiche_apps::common] 1a31c35cb507bb280fb5da536c4c24119f73795c prioritizing response on stream 0 as Priority { urgency: 3, incremental: false } [2025-04-30T09:10:57.409079501Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm GREASE stream=0 len=0 [2025-04-30T09:10:57.409086765Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm GREASE stream=0 len=18 [2025-04-30T09:10:57.409094670Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm HEADERS stream=0 len=17 fin=false [2025-04-30T09:10:57.409102424Z DEBUG quiche_apps::common] 1a31c35cb507bb280fb5da536c4c24119f73795c response stream 0 is writable with capacity Ok(13445) [2025-04-30T09:10:57.409110209Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm DATA stream=0 len=10240 fin=true [2025-04-30T09:10:57.409118535Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.409127201Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.409132782Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=48 pn=8 [2025-04-30T09:10:57.409138622Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm STREAM id=14 off=0 len=26 fin=true [2025-04-30T09:10:57.409145245Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 69 bytes [2025-04-30T09:10:57.409148501Z TRACE quiche::h3] 1a31c35cb507bb280fb5da536c4c24119f73795c stream id 14 is readable [2025-04-30T09:10:57.409152839Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 1 bytes on stream 14 [2025-04-30T09:10:57.409156787Z TRACE quiche::h3::stream] 1a31c35cb507bb280fb5da536c4c24119f73795c read 7 bytes on stream 14 [2025-04-30T09:10:57.409163149Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.409171956Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1307 [2025-04-30T09:10:57.409181443Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=710 pn=2 [2025-04-30T09:10:57.409184980Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm ACK delay=4 blocks=[4..8] ecn_counts=None [2025-04-30T09:10:57.409188867Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[76, 47, 94, 3d, ba, d6, 26, eb, 63, af, 66, ee, cf, 96, eb, b5, a1, 29, 8d, 63] reset_token=[c3, e6, 0b, 44, 57, 59, 05, 51, 31, 10, 5c, 7d, 1e, 80, 1e, 80] [2025-04-30T09:10:57.409196662Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[c9, c0, 41, d5, a6, d1, 9d, 15, df, 2a, dd, d8, 94, 64, fe, 34, 03, 74, 3d, d3] reset_token=[91, bd, d3, ab, e3, a7, 73, c1, 52, f3, 93, 74, 11, 3e, 1e, 35] [2025-04-30T09:10:57.409203355Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[8a, 71, 40, e8, 22, b9, 14, 16, f6, 82, 09, 44, dd, b9, e6, 2e, 37, fb, 77, 1f] reset_token=[fc, 93, ed, 53, 9f, ac, c9, 44, 84, f8, 91, 29, c4, cc, 02, 64] [2025-04-30T09:10:57.409210057Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[57, 5a, a6, c3, 29, d8, e4, e2, 93, 2d, 23, 9f, 22, ec, bf, 42, 44, ee, 4b, 43] reset_token=[d5, ec, 4f, 2a, 27, 32, 43, f9, b0, 85, f3, 2f, 0a, 76, a6, e7] [2025-04-30T09:10:57.409216589Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[04, d8, 03, dc, 05, 20, e3, 3c, eb, 40, b6, e9, 4f, 1b, 77, a7, 49, 7a, 82, fa] reset_token=[fa, 7a, 77, 82, 9d, 83, a3, 24, 17, a7, 7f, e1, ec, 6e, c9, a9] [2025-04-30T09:10:57.409223292Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[9c, c1, de, b3, 07, fc, 16, 4c, fc, 4c, 3c, 2a, 07, ce, 35, 61, dc, 10, 04, d1] reset_token=[c6, 41, 61, c4, d6, 7f, 4a, 28, de, 3e, e2, 24, de, 5e, 06, 2e] [2025-04-30T09:10:57.409230075Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[01, fd, e2, a6, fc, f5, 35, d1, ea, 6a, b0, 45, 8f, f0, 30, 20, 4c, 0a, 96, 54] reset_token=[24, 4c, 2f, 83, 22, b5, 66, 84, 3b, 90, 6d, 78, b1, ff, c8, 1a] [2025-04-30T09:10:57.409236687Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm HANDSHAKE_DONE [2025-04-30T09:10:57.409239513Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm CRYPTO off=0 len=396 [2025-04-30T09:10:57.409242489Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=3 off=0 len=19 fin=false [2025-04-30T09:10:57.409256274Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.531188ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=748 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409318211Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c written 748 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59876, at: Instant { tv_sec: 266, tv_nsec: 111633121 } }) [2025-04-30T09:10:57.409324874Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.409330615Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.409336666Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12752 out_len=1350 left=1312 [2025-04-30T09:10:57.409341184Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=6 pn=3 [2025-04-30T09:10:57.409344280Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=7 off=0 len=1 fin=false [2025-04-30T09:10:57.409351243Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.603425ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=792 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409387282Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59876, at: Instant { tv_sec: 266, tv_nsec: 111633121 } }) [2025-04-30T09:10:57.409392772Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.409397250Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.409402580Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12708 out_len=1350 left=1312 [2025-04-30T09:10:57.409406988Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=6 pn=4 [2025-04-30T09:10:57.409414914Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=11 off=0 len=1 fin=false [2025-04-30T09:10:57.409430002Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.591231ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=836 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409465018Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c written 44 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59876, at: Instant { tv_sec: 266, tv_nsec: 111633121 } }) [2025-04-30T09:10:57.409470258Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.409474546Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.409479826Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12664 out_len=1350 left=1312 [2025-04-30T09:10:57.409484345Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1312 pn=5 [2025-04-30T09:10:57.409487450Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=0 len=1307 fin=false [2025-04-30T09:10:57.409494243Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.604316ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2186 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409507949Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11314 out_len=1350 left=1312 [2025-04-30T09:10:57.409511736Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1312 pn=6 [2025-04-30T09:10:57.409519070Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=1307 len=1306 fin=false [2025-04-30T09:10:57.409525282Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.602503ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3536 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409559636Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9964 out_len=1350 left=1312 [2025-04-30T09:10:57.409563804Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1312 pn=7 [2025-04-30T09:10:57.409566810Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=2613 len=1306 fin=false [2025-04-30T09:10:57.409572541Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.606239ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4886 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409585515Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8614 out_len=1350 left=1312 [2025-04-30T09:10:57.409589112Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1312 pn=8 [2025-04-30T09:10:57.409592037Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=3919 len=1306 fin=false [2025-04-30T09:10:57.409597267Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.608223ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6236 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409620401Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7264 out_len=1350 left=1312 [2025-04-30T09:10:57.409624098Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1312 pn=9 [2025-04-30T09:10:57.409627054Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=5225 len=1306 fin=false [2025-04-30T09:10:57.409632384Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.607853ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7586 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409645198Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=5914 out_len=1350 left=1312 [2025-04-30T09:10:57.409648824Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1312 pn=10 [2025-04-30T09:10:57.409651760Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=6531 len=1306 fin=false [2025-04-30T09:10:57.409657060Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.608163ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8936 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409673000Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4564 out_len=1350 left=1312 [2025-04-30T09:10:57.409677469Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1312 pn=11 [2025-04-30T09:10:57.409680404Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=7837 len=1306 fin=false [2025-04-30T09:10:57.409685544Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.607501ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10286 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 11, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409695973Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=3214 out_len=1350 left=1312 [2025-04-30T09:10:57.409698829Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=1161 pn=12 [2025-04-30T09:10:57.409701163Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=0 off=9143 len=1155 fin=true [2025-04-30T09:10:57.409705391Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.611038ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11485 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 12, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409807233Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c written 10649 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59876, at: Instant { tv_sec: 266, tv_nsec: 111633121 } }) [2025-04-30T09:10:57.409812543Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.409816340Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.409820829Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c pmtud probe status false hs_con=true hs_sent=true cwnd_avail=2015 out_len=1350 left=1312 [2025-04-30T09:10:57.409824315Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx pkt Short dcid=7e8bf38231e73c92e203c62c91b59bdfbf3926e5 key_phase=false len=31 pn=13 [2025-04-30T09:10:57.409826860Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c tx frm STREAM id=15 off=0 len=26 fin=true [2025-04-30T09:10:57.409832310Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c timer=108.607642ms latest_rtt=33.477761ms srtt=33.431657ms min_rtt=33.425071ms rttvar=12.547573ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=11554 app_limited=true congestion_recovery_start_time=None Rate { delivered: 956, delivered_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, first_sent_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, end_of_app_limited: 5, last_sent_packet: 13, largest_acked: 1, rate_sample: RateSample { delivery_rate: 28601, is_app_limited: true, interval: 33.425071ms, delivered: 956, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 266, tv_nsec: 77508801 }), send_elapsed: 0ns, ack_elapsed: 33.425071ms, rtt: 33.425071ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 504761, last_update: Instant { tv_sec: 266, tv_nsec: 111633121 }, next_time: Instant { tv_sec: 266, tv_nsec: 111633121 }, 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-30T09:10:57.409858359Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c written 69 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59876, at: Instant { tv_sec: 266, tv_nsec: 111633121 } }) [2025-04-30T09:10:57.409862267Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.409865673Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.409868138Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.409870422Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.440654414Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.440672909Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.440684461Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=9 [2025-04-30T09:10:57.440694340Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=9 blocks=[2..4] ecn_counts=None [2025-04-30T09:10:57.440700401Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 2 [2025-04-30T09:10:57.440705180Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 3 [2025-04-30T09:10:57.440709017Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 4 [2025-04-30T09:10:57.440731961Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.440739856Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.440744144Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.440754834Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.441695988Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.441710756Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.441718450Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=10 [2025-04-30T09:10:57.441724632Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=16 blocks=[2..5] ecn_counts=None [2025-04-30T09:10:57.441728460Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 5 [2025-04-30T09:10:57.441740021Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.441745091Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.441748146Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.441750671Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.442702676Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.442714218Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.442720720Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=11 [2025-04-30T09:10:57.442727423Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=4 blocks=[2..6] ecn_counts=None [2025-04-30T09:10:57.442731210Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 6 [2025-04-30T09:10:57.442742151Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.442747200Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.442750316Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.442752741Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.443821526Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.443833068Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.443839500Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=12 [2025-04-30T09:10:57.443845491Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=8 blocks=[2..7] ecn_counts=None [2025-04-30T09:10:57.443849288Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 7 [2025-04-30T09:10:57.443860239Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.443865198Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.443868244Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.443870619Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.444913334Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.444924836Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.444931278Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=13 [2025-04-30T09:10:57.444937259Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=6 blocks=[2..8] ecn_counts=None [2025-04-30T09:10:57.444941057Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 8 [2025-04-30T09:10:57.444952358Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.444957397Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.444965743Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.444968318Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.446007878Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.446019430Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.446025892Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=14 [2025-04-30T09:10:57.446031934Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=4 blocks=[2..9] ecn_counts=None [2025-04-30T09:10:57.446035690Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 9 [2025-04-30T09:10:57.446046661Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.446051661Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.446054746Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.446057151Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.447107131Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.447118683Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.447125155Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=15 [2025-04-30T09:10:57.447131056Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=4 blocks=[2..10] ecn_counts=None [2025-04-30T09:10:57.447134853Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 10 [2025-04-30T09:10:57.447145763Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.447150753Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.447153849Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.447156233Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.448204049Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.448210251Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.448214619Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=22 pn=16 [2025-04-30T09:10:57.448218987Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm ACK delay=7 blocks=[2..11] ecn_counts=None [2025-04-30T09:10:57.448222033Z TRACE quiche::recovery::congestion::recovery] 1a31c35cb507bb280fb5da536c4c24119f73795c packet newly acked 11 [2025-04-30T09:10:57.448230108Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 43 bytes [2025-04-30T09:10:57.448234066Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.448236680Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.448238784Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.449202852Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:59876 to [::]:443 [2025-04-30T09:10:57.449208463Z TRACE quiche_server] got packet Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false [2025-04-30T09:10:57.449212640Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx pkt Short dcid=1a31c35cb507bb280fb5da536c4c24119f73795c key_phase=false len=28 pn=17 [2025-04-30T09:10:57.449217189Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c rx frm APPLICATION_CLOSE err=100 reason=[6b, 74, 68, 78, 62, 79, 65] [2025-04-30T09:10:57.449223571Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c processed 49 bytes [2025-04-30T09:10:57.449232718Z TRACE quiche_server] recv() would block [2025-04-30T09:10:57.449235063Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.449237207Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.603473353Z TRACE quiche_server] timed out [2025-04-30T09:10:57.603505483Z TRACE quiche] 1a31c35cb507bb280fb5da536c4c24119f73795c draining timeout expired [2025-04-30T09:10:57.603591115Z TRACE quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c done writing [2025-04-30T09:10:57.603595834Z TRACE quiche_server] Collecting garbage [2025-04-30T09:10:57.603599521Z INFO quiche_server] 1a31c35cb507bb280fb5da536c4c24119f73795c connection collected recv=17 sent=14 lost=0 retrans=0 sent_bytes=12510 recv_bytes=2386 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:59876 validation_state=Validated active=true recv=17 sent=14 lost=0 retrans=0 rtt=35.075892ms min_rtt=Some(31.515932ms) rttvar=4.045577ms cwnd=13500 sent_bytes=12510 recv_bytes=2386 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=326374]