[2025-04-30T09:11:51.853068318Z INFO  quiche_server] listening on [::]:443
[2025-04-30T09:11:51.853090349Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:11:51.853096671Z TRACE quiche_server] GSO detected: false
[2025-04-30T09:11:52.661798420Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:34143 to [::]:443
[2025-04-30T09:11:52.661834998Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:11:52.661843393Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:11:52.661950101Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:34143 to [::]:443
[2025-04-30T09:11:52.661959318Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:11:52.661964959Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:11:52.661997349Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:34143 to [::]:443
[2025-04-30T09:11:52.662004011Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:11:52.662009080Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:11:52.662040889Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.906954594Z TRACE quiche_server] got 1220 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:52.906987986Z TRACE quiche_server] got packet Initial version=1 dcid=4b5c4036b491f261 scid= token=
[2025-04-30T09:11:52.906996372Z DEBUG quiche_server] New connection: dcid=4b5c4036b491f261 scid=4345b7aee3c76455d5d7b7a33ce30742aee00df6
[2025-04-30T09:11:52.907218103Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Initial version=1 dcid=4b5c4036b491f261 scid= token= len=1202 pn=0 
[2025-04-30T09:11:52.907235986Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm CRYPTO off=0 len=297
[2025-04-30T09:11:52.907266532Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T09:11:52.907271131Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T09:11:52.907376346Z TRACE quiche::tls] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 write message lvl=Initial len=90
[2025-04-30T09:11:52.907411581Z TRACE quiche::tls] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 set write secret lvl=Handshake
[2025-04-30T09:11:52.907492531Z TRACE quiche::tls] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 write message lvl=Handshake len=714
[2025-04-30T09:11:52.907496037Z TRACE quiche::tls] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 set write secret lvl=OneRTT
[2025-04-30T09:11:52.907505465Z TRACE quiche::tls] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 set read secret lvl=Handshake
[2025-04-30T09:11:52.907528698Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm PADDING len=881
[2025-04-30T09:11:52.907547192Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1220 bytes
[2025-04-30T09:11:52.907560677Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.907573872Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Initial version=1 dcid= scid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 len=99 pn=0 
[2025-04-30T09:11:52.907620258Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=46 blocks=[0..0] ecn_counts=None
[2025-04-30T09:11:52.907628303Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=90
[2025-04-30T09:11:52.907644763Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=998.917578ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=146 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 419, tv_nsec: 162988550 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 162988550 }, 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: 419, tv_nsec: 162988550 }, next_time: Instant { tv_sec: 419, tv_nsec: 162988550 }, 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:11:52.907680890Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Handshake version=1 dcid= scid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 len=718 pn=1 
[2025-04-30T09:11:52.907684417Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=714
[2025-04-30T09:11:52.907691540Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=998.871512ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=910 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 419, tv_nsec: 162988550 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 162988550 }, 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: 419, tv_nsec: 162988550 }, next_time: Instant { tv_sec: 419, tv_nsec: 162988550 }, 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:11:52.907753094Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 419, tv_nsec: 162988550 } })
[2025-04-30T09:11:52.907760107Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.907768723Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.907775606Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:52.907780334Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.940073654Z TRACE quiche_server] got 109 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:52.940104992Z TRACE quiche_server] got packet Handshake version=1 dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 scid=
[2025-04-30T09:11:52.940119439Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Handshake version=1 dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 scid= len=80 pn=1 
[2025-04-30T09:11:52.940142011Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm ACK delay=2 blocks=[1..1] ecn_counts=None
[2025-04-30T09:11:52.940151058Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 packet newly acked 1
[2025-04-30T09:11:52.940157840Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm CRYPTO off=0 len=52
[2025-04-30T09:11:52.940180221Z TRACE quiche::tls] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 set read secret lvl=OneRTT
[2025-04-30T09:11:52.940234312Z TRACE quiche::tls] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 write message lvl=OneRTT len=428
[2025-04-30T09:11:52.940249910Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped epoch 1 state
[2025-04-30T09:11:52.940256533Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 connection established: proto=Ok("hq-interop") cipher=Some(AES256_GCM) curve=Some("X25519") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 10000, stateless_reset_token: None, max_udp_payload_size: 1472, initial_max_data: 16777216, initial_max_stream_data_bidi_local: 65536, initial_max_stream_data_bidi_remote: 65536, initial_max_stream_data_uni: 65536, initial_max_streams_bidi: 0, initial_max_streams_uni: 3, ack_delay_exponent: 8, max_ack_delay: 26, disable_active_migration: false, active_conn_id_limit: 4, initial_source_connection_id: Some(), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T09:11:52.940303901Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped epoch 0 state
[2025-04-30T09:11:52.940307788Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 109 bytes
[2025-04-30T09:11:52.940323847Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.940331211Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1332
[2025-04-30T09:11:52.940341009Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=553 pn=2 
[2025-04-30T09:11:52.940344606Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:52.940354695Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:52.940362980Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:52.940371095Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm HANDSHAKE_DONE
[2025-04-30T09:11:52.940374051Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=428
[2025-04-30T09:11:52.940386904Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=123.595813ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 195752171 }, next_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, 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:11:52.940452857Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 571 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 419, tv_nsec: 195752171 } })
[2025-04-30T09:11:52.940459819Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.940465380Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.940468896Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:52.940471692Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.941013315Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:52.941024135Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:52.941030367Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false len=1231 pn=2 
[2025-04-30T09:11:52.941036889Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm PING mtu_probe=None
[2025-04-30T09:11:52.941044212Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm PADDING len=1210
[2025-04-30T09:11:52.941051135Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1252 bytes
[2025-04-30T09:11:52.941055333Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.941060192Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12929 out_len=1350 left=1327
[2025-04-30T09:11:52.941063658Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=5 pn=3 
[2025-04-30T09:11:52.941066263Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=3 blocks=[2..2] ecn_counts=None
[2025-04-30T09:11:52.941073587Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=122.909481ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 195752171 }, next_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, 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:11:52.941112519Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 419, tv_nsec: 195752171 } })
[2025-04-30T09:11:52.941117989Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.941121736Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.941126124Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:52.941129981Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.972667063Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:52.972693632Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:52.972705444Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false len=1311 pn=3 
[2025-04-30T09:11:52.972716985Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm PING mtu_probe=None
[2025-04-30T09:11:52.972726834Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx frm PADDING len=1290
[2025-04-30T09:11:52.972739367Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:52.972746230Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.972755256Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12929 out_len=1350 left=1327
[2025-04-30T09:11:52.972771847Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=5 pn=4 
[2025-04-30T09:11:52.972775464Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=6 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:52.972786765Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=91.196012ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 228174233 }, next_time: Instant { tv_sec: 419, tv_nsec: 228174233 }, 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:11:52.972843009Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 419, tv_nsec: 228174233 } })
[2025-04-30T09:11:52.972849731Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.972854981Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.972858658Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:52.972861493Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:52.996899240Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:52.996918716Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:52.996929035Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=26 pn=4 
[2025-04-30T09:11:52.996934215Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:52.996958450Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:52.996964571Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 47 bytes
[2025-04-30T09:11:52.996977044Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:52.996981242Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:52.996984588Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.004387445Z TRACE quiche_server] got 1350 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.004398084Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.004404306Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1329 pn=5 
[2025-04-30T09:11:53.004408454Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.004423642Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.004426357Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1350 bytes
[2025-04-30T09:11:53.004430364Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.004441555Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.004444009Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.054665400Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.054693011Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.054706236Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=6 
[2025-04-30T09:11:53.054711115Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.054735069Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.054738195Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.054743986Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.054749146Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.054751951Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.064843718Z TRACE quiche_server] timed out
[2025-04-30T09:11:53.064859848Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 loss detection timeout expired
[2025-04-30T09:11:53.064866821Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=122.77172ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 1, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 228174233 }, next_time: Instant { tv_sec: 419, tv_nsec: 228174233 }, 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:11:53.064899151Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1350 left=1326
[2025-04-30T09:11:53.064910131Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=559 pn=5 
[2025-04-30T09:11:53.064913307Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=11524 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:53.064918256Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:53.064928105Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:53.064935478Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:53.064953702Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm HANDSHAKE_DONE
[2025-04-30T09:11:53.064956257Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=428
[2025-04-30T09:11:53.064974861Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=247.228113ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1148 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 320315652 }, next_time: Instant { tv_sec: 419, tv_nsec: 320315652 }, 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:11:53.065042837Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 577 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 419, tv_nsec: 320315652 } })
[2025-04-30T09:11:53.065049720Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.065054488Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.065057334Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.065059688Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.121343130Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.121371292Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.121384657Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=26 pn=7 
[2025-04-30T09:11:53.121390478Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.121424070Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.121427877Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 47 bytes
[2025-04-30T09:11:53.121434960Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.121439769Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.121443175Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.147600580Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.147619545Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.147629544Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=8 
[2025-04-30T09:11:53.147635245Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.147663196Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.147666612Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.147672443Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.147677242Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.147680538Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.197745598Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.197768911Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.197782526Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=9 
[2025-04-30T09:11:53.197788327Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.197814105Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.197818112Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.197825216Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.197830756Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.197834292Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.248001785Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.248017825Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.248027683Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=10 
[2025-04-30T09:11:53.248033544Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.248055414Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.248058881Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.248064181Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.248068399Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.248071514Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.298226192Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.298240598Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.298249655Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=11 
[2025-04-30T09:11:53.298255446Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.298276164Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.298279700Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.298284910Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.298288938Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.298292144Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.312388420Z TRACE quiche_server] timed out
[2025-04-30T09:11:53.312401655Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 loss detection timeout expired
[2025-04-30T09:11:53.312411553Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=247.102858ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1148 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 320315652 }, next_time: Instant { tv_sec: 419, tv_nsec: 320315652 }, 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:11:53.312457298Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:53.312468378Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=681 pn=6 
[2025-04-30T09:11:53.312472326Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=42469 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:53.312478277Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:53.312490199Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:53.312499436Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:53.312508382Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:53.312517169Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:53.312526075Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:53.312534781Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm HANDSHAKE_DONE
[2025-04-30T09:11:53.312537797Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=428
[2025-04-30T09:11:53.312560359Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=494.512571ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1847 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 567873028 }, next_time: Instant { tv_sec: 419, tv_nsec: 567873028 }, 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:11:53.312650716Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 699 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 419, tv_nsec: 567873028 } })
[2025-04-30T09:11:53.312659062Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.312664462Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.312670333Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:53.312675312Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=9 pn=7 
[2025-04-30T09:11:53.312678758Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=42495 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:53.312682826Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm PING mtu_probe=None
[2025-04-30T09:11:53.312728420Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=494.562593ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1874 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 567873028 }, next_time: Instant { tv_sec: 419, tv_nsec: 567873028 }, 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:11:53.312794483Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 27 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 419, tv_nsec: 567873028 } })
[2025-04-30T09:11:53.312802728Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.312809310Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.312815712Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.312820451Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.343840615Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.343854861Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.343863848Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=25 pn=12 
[2025-04-30T09:11:53.343868968Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.343887973Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.343891419Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 46 bytes
[2025-04-30T09:11:53.343896459Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.343900306Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.343903271Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.395052450Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.395065183Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.395072777Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=13 
[2025-04-30T09:11:53.395077296Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.395094347Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.395097162Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.395101400Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.395104756Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.395109064Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.445448811Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.445481562Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.445495979Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=14 
[2025-04-30T09:11:53.445500968Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.445526605Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.445530172Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.445537756Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.445542775Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.445545620Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.495765617Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.495791996Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.495808867Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=15 
[2025-04-30T09:11:53.495815169Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.495844092Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.495848411Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.495856125Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.495861575Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.495865091Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.546131359Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.546169549Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.546187182Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=16 
[2025-04-30T09:11:53.546193494Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.546222958Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.546227066Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.546235702Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.546241433Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.546244949Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.596516129Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.596568907Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.596618809Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=17 
[2025-04-30T09:11:53.596625451Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.596654055Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.596657922Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.596666458Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.596672268Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.596675454Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.736147819Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.736180139Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.736194476Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=18 
[2025-04-30T09:11:53.736199545Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.736225132Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.736228338Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.736240892Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.736249868Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.736252784Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.747373253Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.747389253Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.747399532Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=19 
[2025-04-30T09:11:53.747405302Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.747431381Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.747438193Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:53.747443864Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.747447861Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.747451027Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.807574739Z TRACE quiche_server] timed out
[2025-04-30T09:11:53.807642264Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 loss detection timeout expired
[2025-04-30T09:11:53.807656230Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=494.260845ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1874 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 6, last_sent_packet: 7, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 419, tv_nsec: 567873028 }, next_time: Instant { tv_sec: 419, tv_nsec: 567873028 }, 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:11:53.807713326Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:53.807725028Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=681 pn=8 
[2025-04-30T09:11:53.807728985Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=104375 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:53.807734866Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:53.807746617Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:53.807755805Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:53.807764691Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:53.807773578Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:53.807782223Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:53.807790850Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm HANDSHAKE_DONE
[2025-04-30T09:11:53.807793815Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=428
[2025-04-30T09:11:53.807818471Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=989.131849ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2573 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 7, last_sent_packet: 8, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 420, tv_nsec: 63124878 }, next_time: Instant { tv_sec: 420, tv_nsec: 63124878 }, 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:11:53.807928104Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 699 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 420, tv_nsec: 63124878 } })
[2025-04-30T09:11:53.807938112Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.807945907Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.807951176Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:53.807955655Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=9 pn=9 
[2025-04-30T09:11:53.807959342Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=104405 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:53.807963169Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm PING mtu_probe=None
[2025-04-30T09:11:53.807970943Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=989.227987ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 420, tv_nsec: 63124878 }, next_time: Instant { tv_sec: 420, tv_nsec: 63124878 }, 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:11:53.808012390Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 27 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 420, tv_nsec: 63124878 } })
[2025-04-30T09:11:53.808018411Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.808023029Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.808026266Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.808029111Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:53.839137157Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:53.839166741Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:53.839180767Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=28 pn=20 
[2025-04-30T09:11:53.839185817Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:53.839208398Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:53.839211544Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 49 bytes
[2025-04-30T09:11:53.839217886Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:53.839222935Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:53.839225701Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.026023164Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:54.026068999Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:54.026083796Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=21 
[2025-04-30T09:11:54.026089096Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:54.026123720Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:54.026129491Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:54.026138527Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.026144128Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:54.026146883Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.041230635Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:54.041247636Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:54.041256773Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=22 
[2025-04-30T09:11:54.041262273Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:54.041283362Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:54.041287069Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:54.041292690Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.041296917Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:54.041300153Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.597745193Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:54.597783043Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:54.597799644Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=23 
[2025-04-30T09:11:54.597805454Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:54.597834749Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:54.597838746Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:54.597846881Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.597853273Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:54.597856980Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.643992510Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:54.644027825Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:54.644042162Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=1311 pn=24 
[2025-04-30T09:11:54.644047632Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:54.644075984Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:54.644079761Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 1332 bytes
[2025-04-30T09:11:54.644086904Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.644092425Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:54.644095781Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.798308724Z TRACE quiche_server] timed out
[2025-04-30T09:11:54.798347897Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 loss detection timeout expired
[2025-04-30T09:11:54.798375027Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=988.074632ms latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2600 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 8, last_sent_packet: 9, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 420, tv_nsec: 63124878 }, next_time: Instant { tv_sec: 420, tv_nsec: 63124878 }, 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:11:54.798413068Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:54.798423066Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=681 pn=10 
[2025-04-30T09:11:54.798426282Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=228213 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:54.798431903Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:54.798441280Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:54.798447942Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:54.798454465Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:54.798460946Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:54.798467268Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:54.798473560Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm HANDSHAKE_DONE
[2025-04-30T09:11:54.798475884Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=428
[2025-04-30T09:11:54.798500159Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=1.978401504s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3299 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 9, last_sent_packet: 10, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 421, tv_nsec: 53824921 }, next_time: Instant { tv_sec: 421, tv_nsec: 53824921 }, 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:11:54.798629409Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 699 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 421, tv_nsec: 53824921 } })
[2025-04-30T09:11:54.798640579Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.798648394Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.798653443Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:54.798657050Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=9 pn=11 
[2025-04-30T09:11:54.798659945Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=228243 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:54.798663101Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm PING mtu_probe=None
[2025-04-30T09:11:54.798670204Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=1.978482133s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3326 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 421, tv_nsec: 53824921 }, next_time: Instant { tv_sec: 421, tv_nsec: 53824921 }, 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:11:54.798715488Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 27 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 421, tv_nsec: 53824921 } })
[2025-04-30T09:11:54.798722371Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.798727190Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.798730516Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:54.798733562Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.830049753Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:54.830100396Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:54.830117929Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=28 pn=25 
[2025-04-30T09:11:54.830124632Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:54.830153104Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:54.830157061Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 49 bytes
[2025-04-30T09:11:54.830164626Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.830170266Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:54.830173632Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:54.940567843Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:45106 to [::]:443
[2025-04-30T09:11:54.940624418Z TRACE quiche_server] got packet Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=false
[2025-04-30T09:11:54.940641720Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 rx pkt Short dcid=4345b7aee3c76455d5d7b7a33ce30742aee00df6 key_phase=true len=28 pn=26 
[2025-04-30T09:11:54.940647781Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 peer-initiated key update
[2025-04-30T09:11:54.940675783Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 dropped invalid packet
[2025-04-30T09:11:54.940680172Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 processed 49 bytes
[2025-04-30T09:11:54.940688717Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:54.940695029Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:54.940698385Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:56.779618153Z TRACE quiche_server] timed out
[2025-04-30T09:11:56.779646816Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 loss detection timeout expired
[2025-04-30T09:11:56.779659340Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=1.975994276s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3326 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 10, last_sent_packet: 11, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 421, tv_nsec: 53824921 }, next_time: Instant { tv_sec: 421, tv_nsec: 53824921 }, 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:11:56.779697781Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:56.779708691Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=681 pn=12 
[2025-04-30T09:11:56.779712398Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=475874 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:56.779718389Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:56.779742253Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:56.779750218Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:56.779757792Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:11:56.779765546Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:11:56.779772890Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:11:56.779780314Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm HANDSHAKE_DONE
[2025-04-30T09:11:56.779783179Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=428
[2025-04-30T09:11:56.779806332Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=3.956882594s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4025 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 11, last_sent_packet: 12, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 423, tv_nsec: 35110104 }, next_time: Instant { tv_sec: 423, tv_nsec: 35110104 }, 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:11:56.779903963Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 699 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 423, tv_nsec: 35110104 } })
[2025-04-30T09:11:56.779912288Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:56.779920223Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:56.779925232Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:11:56.779930392Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=9 pn=13 
[2025-04-30T09:11:56.779935702Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=475902 blocks=[2..3] ecn_counts=None
[2025-04-30T09:11:56.779941973Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm PING mtu_probe=None
[2025-04-30T09:11:56.779960417Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=3.956965969s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4052 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 423, tv_nsec: 35110104 }, next_time: Instant { tv_sec: 423, tv_nsec: 35110104 }, 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:11:56.780011362Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 27 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 423, tv_nsec: 35110104 } })
[2025-04-30T09:11:56.780019907Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:11:56.780026790Z TRACE quiche_server] recv() would block
[2025-04-30T09:11:56.780031259Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:11:56.780034855Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:00.739389150Z TRACE quiche_server] timed out
[2025-04-30T09:12:00.739425497Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 loss detection timeout expired
[2025-04-30T09:12:00.739441627Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=3.954488338s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4052 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 12, last_sent_packet: 13, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 423, tv_nsec: 35110104 }, next_time: Instant { tv_sec: 423, tv_nsec: 35110104 }, 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:12:00.739498502Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:12:00.739510114Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=681 pn=14 
[2025-04-30T09:12:00.739514842Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=970849 blocks=[2..3] ecn_counts=None
[2025-04-30T09:12:00.739521415Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:12:00.739545970Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:12:00.739558894Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:12:00.739572159Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[56, 08, e1, 8e, ff, 19, ef, b4, f8, 23, 5f, a6, 00, 40, f6, f8, 0a, 3d, e9, da] reset_token=[a6, 84, 85, f6, 3d, 4a, 05, 96, f4, 56, 24, 43, bb, 40, f4, 55]
[2025-04-30T09:12:00.739607013Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[51, b5, 24, 51, 58, 72, 1f, aa, 95, 9a, 7d, ea, 3d, e8, 8f, 75, ad, 03, 96, 97] reset_token=[82, 2d, df, 62, a4, 4d, c6, 08, 46, 98, c7, 86, c1, e5, f9, 44]
[2025-04-30T09:12:00.739614257Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[b6, 08, d0, ef, 55, ad, 2a, 25, f0, 24, 25, b0, 21, 46, 01, d9, 70, ec, 8d, 00] reset_token=[4f, ed, 87, db, 77, ba, 40, f2, 60, 15, b5, e6, 9b, 3b, 42, d5]
[2025-04-30T09:12:00.739621530Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm HANDSHAKE_DONE
[2025-04-30T09:12:00.739623985Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm CRYPTO off=0 len=428
[2025-04-30T09:12:00.739646366Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=7.91384633s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4751 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 13, last_sent_packet: 14, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 426, tv_nsec: 994909604 }, next_time: Instant { tv_sec: 426, tv_nsec: 994909604 }, 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:12:00.739794130Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 699 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 426, tv_nsec: 994909604 } })
[2025-04-30T09:12:00.739803798Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:00.739810731Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:00.739816381Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1350 left=1324
[2025-04-30T09:12:00.739820519Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx pkt Short dcid= key_phase=false len=9 pn=15 
[2025-04-30T09:12:00.739823414Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm ACK delay=970889 blocks=[2..3] ecn_counts=None
[2025-04-30T09:12:00.739830647Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 tx frm PING mtu_probe=None
[2025-04-30T09:12:00.739838161Z TRACE quiche::recovery::congestion::recovery] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 timer=7.913983013s latest_rtt=32.552129ms srtt=32.552129ms min_rtt=32.552129ms rttvar=16.276064ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4778 app_limited=true congestion_recovery_start_time=None Rate { delivered: 764, delivered_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, first_sent_time: Instant { tv_sec: 419, tv_nsec: 195752171 }, end_of_app_limited: 14, last_sent_packet: 15, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23470, is_app_limited: true, interval: 32.552129ms, delivered: 764, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 419, tv_nsec: 162988550 }), send_elapsed: 0ns, ack_elapsed: 32.552129ms, rtt: 32.552129ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 518399, last_update: Instant { tv_sec: 426, tv_nsec: 994909604 }, next_time: Instant { tv_sec: 426, tv_nsec: 994909604 }, 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:12:00.739885279Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 written 27 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45106, at: Instant { tv_sec: 426, tv_nsec: 994909604 } })
[2025-04-30T09:12:00.739892112Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:00.739896680Z TRACE quiche_server] recv() would block
[2025-04-30T09:12:00.739900096Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:12:00.739903072Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:03.067288168Z TRACE quiche_server] timed out
[2025-04-30T09:12:03.067323744Z TRACE quiche] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 idle timeout expired
[2025-04-30T09:12:03.067371993Z TRACE quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 done writing
[2025-04-30T09:12:03.067375900Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:12:03.067380408Z INFO  quiche_server] 4345b7aee3c76455d5d7b7a33ce30742aee00df6 connection collected recv=4 sent=16 lost=0 retrans=11 sent_bytes=5734 recv_bytes=3913 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:45106 validation_state=Validated active=true recv=4 sent=16 lost=0 retrans=11 rtt=32.552129ms min_rtt=Some(32.552129ms) rttvar=16.276064ms cwnd=13500 sent_bytes=5734 recv_bytes=3913 lost_bytes=0 stream_retrans_bytes=4708 pmtu=1350 delivery_rate=23470]