[2025-04-30T17:10:26.650263766Z INFO  quiche_server] listening on [::]:443
[2025-04-30T17:10:26.650284495Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T17:10:26.650291027Z TRACE quiche_server] GSO detected: false
[2025-04-30T17:10:27.434203505Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:37785 to [::]:443
[2025-04-30T17:10:27.434239222Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T17:10:27.434247728Z WARN  quiche_server] Doing version negotiation
[2025-04-30T17:10:27.434338316Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:37785 to [::]:443
[2025-04-30T17:10:27.434344418Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T17:10:27.434348325Z WARN  quiche_server] Doing version negotiation
[2025-04-30T17:10:27.434381407Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:37785 to [::]:443
[2025-04-30T17:10:27.434389131Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T17:10:27.434394832Z WARN  quiche_server] Doing version negotiation
[2025-04-30T17:10:27.434419457Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.516116635Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.516152432Z TRACE quiche_server] got packet Initial version=1 dcid=e24c232326152a02 scid=e85214200d60e1c9 token=
[2025-04-30T17:10:28.516163462Z DEBUG quiche_server] New connection: dcid=e24c232326152a02 scid=85d827303084775e352e89d15c745f7883e7318b
[2025-04-30T17:10:28.516408940Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Initial version=1 dcid=e24c232326152a02 scid=e85214200d60e1c9 token= len=1174 pn=0 
[2025-04-30T17:10:28.516424739Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm CRYPTO off=0 len=257
[2025-04-30T17:10:28.516455216Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T17:10:28.516460936Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T17:10:28.516589676Z TRACE quiche::tls] 85d827303084775e352e89d15c745f7883e7318b write message lvl=Initial len=123
[2025-04-30T17:10:28.516630072Z TRACE quiche::tls] 85d827303084775e352e89d15c745f7883e7318b set write secret lvl=Handshake
[2025-04-30T17:10:28.516712185Z TRACE quiche::tls] 85d827303084775e352e89d15c745f7883e7318b write message lvl=Handshake len=697
[2025-04-30T17:10:28.516716413Z TRACE quiche::tls] 85d827303084775e352e89d15c745f7883e7318b set write secret lvl=OneRTT
[2025-04-30T17:10:28.516725961Z TRACE quiche::tls] 85d827303084775e352e89d15c745f7883e7318b set read secret lvl=Handshake
[2025-04-30T17:10:28.516751197Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm PADDING len=896
[2025-04-30T17:10:28.516772748Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 1200 bytes
[2025-04-30T17:10:28.516785161Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.516800990Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx pkt Initial version=1 dcid=e85214200d60e1c9 scid=85d827303084775e352e89d15c745f7883e7318b len=132 pn=0 
[2025-04-30T17:10:28.516805469Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm ACK delay=51 blocks=[0..0] ecn_counts=None
[2025-04-30T17:10:28.516811901Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm CRYPTO off=0 len=123
[2025-04-30T17:10:28.516825386Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b timer=998.9622ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 422, tv_nsec: 627768315 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 627768315 }, 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: 422, tv_nsec: 627768315 }, next_time: Instant { tv_sec: 422, tv_nsec: 627768315 }, 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-30T17:10:28.516863146Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx pkt Handshake version=1 dcid=e85214200d60e1c9 scid=85d827303084775e352e89d15c745f7883e7318b len=701 pn=1 
[2025-04-30T17:10:28.516867354Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm CRYPTO off=0 len=697
[2025-04-30T17:10:28.516876762Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b timer=998.911285ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=942 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 422, tv_nsec: 627768315 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 627768315 }, 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: 422, tv_nsec: 627768315 }, next_time: Instant { tv_sec: 422, tv_nsec: 627768315 }, 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-30T17:10:28.516939779Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:57708, at: Instant { tv_sec: 422, tv_nsec: 627768315 } })
[2025-04-30T17:10:28.516948615Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.516955298Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.516960367Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.516963683Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.600300761Z TRACE quiche_server] got 1201 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.600333632Z TRACE quiche_server] got packet Initial version=1 dcid=85d827303084775e352e89d15c745f7883e7318b scid=e85214200d60e1c9 token=
[2025-04-30T17:10:28.600350804Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Initial version=1 dcid=85d827303084775e352e89d15c745f7883e7318b scid=e85214200d60e1c9 token= len=1163 pn=1 
[2025-04-30T17:10:28.600366944Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T17:10:28.600375810Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b packet newly acked 0
[2025-04-30T17:10:28.600388023Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm PADDING len=1141
[2025-04-30T17:10:28.600417067Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 1201 bytes
[2025-04-30T17:10:28.600424902Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.600436263Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.600440210Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.632077545Z TRACE quiche_server] got 92 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.632103704Z TRACE quiche_server] got packet Handshake version=1 dcid=85d827303084775e352e89d15c745f7883e7318b scid=e85214200d60e1c9
[2025-04-30T17:10:28.632132307Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Handshake version=1 dcid=85d827303084775e352e89d15c745f7883e7318b scid=e85214200d60e1c9 len=56 pn=0 
[2025-04-30T17:10:28.632146544Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm CRYPTO off=0 len=36
[2025-04-30T17:10:28.632171230Z TRACE quiche::tls] 85d827303084775e352e89d15c745f7883e7318b set read secret lvl=OneRTT
[2025-04-30T17:10:28.632218628Z TRACE quiche::tls] 85d827303084775e352e89d15c745f7883e7318b write message lvl=OneRTT len=396
[2025-04-30T17:10:28.632231422Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b dropped epoch 1 state
[2025-04-30T17:10:28.632236341Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=false TransportParams { original_destination_connection_id: None, max_idle_timeout: 60000, stateless_reset_token: None, max_udp_payload_size: 1500, initial_max_data: 2500000, initial_max_stream_data_bidi_local: 250000, initial_max_stream_data_bidi_remote: 250000, initial_max_stream_data_uni: 250000, initial_max_streams_bidi: 3, initial_max_streams_uni: 3, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 2, initial_source_connection_id: Some(e85214200d60e1c9), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T17:10:28.632273160Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b dropped epoch 0 state
[2025-04-30T17:10:28.632277277Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 92 bytes
[2025-04-30T17:10:28.632284791Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.632293307Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T17:10:28.632303917Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx pkt Short dcid=e85214200d60e1c9 key_phase=false len=441 pn=2 
[2025-04-30T17:10:28.632307644Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[4c, a1, 90, 3b, 9a, 7b, 65, ae, 91, 39, ce, d7, 67, 15, 61, cd, f9, d7, 6b, 24] reset_token=[2c, 69, 30, 0f, af, 03, 6f, 9f, 74, a4, b1, 1b, 55, ce, c8, 22]
[2025-04-30T17:10:28.632317933Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm HANDSHAKE_DONE
[2025-04-30T17:10:28.632321310Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm CRYPTO off=0 len=396
[2025-04-30T17:10:28.632334805Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b timer=275.626466ms latest_rtt=83.558089ms srtt=83.558089ms min_rtt=83.558089ms rttvar=41.779044ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=467 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 2237, is_app_limited: true, interval: 83.558089ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 627768315 }), send_elapsed: 0ns, ack_elapsed: 83.558089ms, rtt: 83.558089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 201955, last_update: Instant { tv_sec: 422, tv_nsec: 743267615 }, next_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, 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-30T17:10:28.632402401Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b written 467 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:57708, at: Instant { tv_sec: 422, tv_nsec: 743267615 } })
[2025-04-30T17:10:28.632470938Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.632480025Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.632484323Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.632487609Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.638567549Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.638581135Z TRACE quiche_server] got packet Handshake version=1 dcid=85d827303084775e352e89d15c745f7883e7318b scid=e85214200d60e1c9
[2025-04-30T17:10:28.638590943Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b dropped invalid packet
[2025-04-30T17:10:28.638594860Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 58 bytes
[2025-04-30T17:10:28.638600741Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.638605099Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.638608435Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.661124754Z TRACE quiche_server] got 59 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.661150842Z TRACE quiche_server] got packet Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false
[2025-04-30T17:10:28.661165460Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false len=38 pn=0 
[2025-04-30T17:10:28.661179005Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T17:10:28.661199433Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 59 bytes
[2025-04-30T17:10:28.661204342Z DEBUG quiche_apps::common] 85d827303084775e352e89d15c745f7883e7318b response stream 0 is writable with capacity Ok(13033)
[2025-04-30T17:10:28.661213810Z TRACE quiche_apps::common] 85d827303084775e352e89d15c745f7883e7318b received 17 bytes
[2025-04-30T17:10:28.661217827Z TRACE quiche_apps::common] 85d827303084775e352e89d15c745f7883e7318b stream 0 has 17 bytes (fin? true)
[2025-04-30T17:10:28.661224409Z INFO  quiche_apps::common] 85d827303084775e352e89d15c745f7883e7318b got GET request for "/www/ajgsqsgoii" on stream 0
[2025-04-30T17:10:28.661260887Z INFO  quiche_apps::common] 85d827303084775e352e89d15c745f7883e7318b sending response of size 5120 on stream 0
[2025-04-30T17:10:28.661271307Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.661282418Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b pmtud probe status false hs_con=true hs_sent=true cwnd_avail=13033 out_len=1350 left=1319
[2025-04-30T17:10:28.661291905Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx pkt Short dcid=e85214200d60e1c9 key_phase=false len=1324 pn=3 
[2025-04-30T17:10:28.661296003Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm ACK delay=14 blocks=[0..0] ecn_counts=None
[2025-04-30T17:10:28.661301723Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm STREAM id=0 off=0 len=1314 fin=false
[2025-04-30T17:10:28.661314407Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b timer=275.633659ms latest_rtt=83.558089ms srtt=83.558089ms min_rtt=83.558089ms rttvar=41.779044ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1817 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 2237, is_app_limited: true, interval: 83.558089ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 627768315 }), send_elapsed: 0ns, ack_elapsed: 83.558089ms, rtt: 83.558089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 201955, last_update: Instant { tv_sec: 422, tv_nsec: 743267615 }, next_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, 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-30T17:10:28.661356516Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11683 out_len=1350 left=1324
[2025-04-30T17:10:28.661361785Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx pkt Short dcid=e85214200d60e1c9 key_phase=false len=1324 pn=4 
[2025-04-30T17:10:28.661365302Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm STREAM id=0 off=1314 len=1318 fin=false
[2025-04-30T17:10:28.661372956Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b timer=275.654658ms latest_rtt=83.558089ms srtt=83.558089ms min_rtt=83.558089ms rttvar=41.779044ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3167 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 2237, is_app_limited: true, interval: 83.558089ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 627768315 }), send_elapsed: 0ns, ack_elapsed: 83.558089ms, rtt: 83.558089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 201955, last_update: Instant { tv_sec: 422, tv_nsec: 743267615 }, next_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, 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-30T17:10:28.661390950Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10333 out_len=1350 left=1324
[2025-04-30T17:10:28.661395909Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx pkt Short dcid=e85214200d60e1c9 key_phase=false len=1324 pn=5 
[2025-04-30T17:10:28.661399305Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm STREAM id=0 off=2632 len=1318 fin=false
[2025-04-30T17:10:28.661405868Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b timer=275.656842ms latest_rtt=83.558089ms srtt=83.558089ms min_rtt=83.558089ms rttvar=41.779044ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4517 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 2237, is_app_limited: true, interval: 83.558089ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 627768315 }), send_elapsed: 0ns, ack_elapsed: 83.558089ms, rtt: 83.558089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 201955, last_update: Instant { tv_sec: 422, tv_nsec: 743267615 }, next_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, 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-30T17:10:28.661426766Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8983 out_len=1350 left=1324
[2025-04-30T17:10:28.661431285Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx pkt Short dcid=e85214200d60e1c9 key_phase=false len=1176 pn=6 
[2025-04-30T17:10:28.661434601Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b tx frm STREAM id=0 off=3950 len=1170 fin=true
[2025-04-30T17:10:28.661442295Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b timer=275.656322ms latest_rtt=83.558089ms srtt=83.558089ms min_rtt=83.558089ms rttvar=41.779044ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5719 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, end_of_app_limited: 5, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 2237, is_app_limited: true, interval: 83.558089ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 627768315 }), send_elapsed: 0ns, ack_elapsed: 83.558089ms, rtt: 83.558089ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 201955, last_update: Instant { tv_sec: 422, tv_nsec: 743267615 }, next_time: Instant { tv_sec: 422, tv_nsec: 743267615 }, 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-30T17:10:28.661542432Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b written 5252 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:57708, at: Instant { tv_sec: 422, tv_nsec: 743267615 } })
[2025-04-30T17:10:28.661550918Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.661557089Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.661560776Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.661563662Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.686400023Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.686423657Z TRACE quiche_server] got packet Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false
[2025-04-30T17:10:28.686438945Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false len=23 pn=1 
[2025-04-30T17:10:28.686451709Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm ACK delay=2375 blocks=[2..2] ecn_counts=None
[2025-04-30T17:10:28.686459844Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b packet newly acked 2
[2025-04-30T17:10:28.686483749Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 44 bytes
[2025-04-30T17:10:28.686492275Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.686497465Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.686501172Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.696189826Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.696210344Z TRACE quiche_server] got packet Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false
[2025-04-30T17:10:28.696222487Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false len=22 pn=2 
[2025-04-30T17:10:28.696233597Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm ACK delay=0 blocks=[2..4] ecn_counts=None
[2025-04-30T17:10:28.696239959Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b packet newly acked 3
[2025-04-30T17:10:28.696253955Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b packet newly acked 4
[2025-04-30T17:10:28.696275566Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 43 bytes
[2025-04-30T17:10:28.696283170Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.696287668Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.696291215Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.698107930Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.698124251Z TRACE quiche_server] got packet Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false
[2025-04-30T17:10:28.698133277Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false len=22 pn=3 
[2025-04-30T17:10:28.698141693Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm ACK delay=0 blocks=[2..6] ecn_counts=None
[2025-04-30T17:10:28.698146993Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b packet newly acked 5
[2025-04-30T17:10:28.698150570Z TRACE quiche::recovery::congestion::recovery] 85d827303084775e352e89d15c745f7883e7318b packet newly acked 6
[2025-04-30T17:10:28.698169255Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 43 bytes
[2025-04-30T17:10:28.698175647Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.698179634Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.698182760Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.705848033Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:57708 to [::]:443
[2025-04-30T17:10:28.705864785Z TRACE quiche_server] got packet Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false
[2025-04-30T17:10:28.705874954Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx pkt Short dcid=85d827303084775e352e89d15c745f7883e7318b key_phase=false len=26 pn=4 
[2025-04-30T17:10:28.705884421Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T17:10:28.705890162Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b rx frm ACK delay=0 blocks=[2..6] ecn_counts=None
[2025-04-30T17:10:28.705935557Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b processed 47 bytes
[2025-04-30T17:10:28.705941969Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.705945405Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.705948671Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.713257324Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.713276450Z TRACE quiche_server] got packet Initial version=1 dcid=37e2aa3d34e599c4 scid=919df65cab0ea0ff token=
[2025-04-30T17:10:28.713283713Z DEBUG quiche_server] New connection: dcid=37e2aa3d34e599c4 scid=753a3c5ee6522480f84d699e8c4f5e69132115cf
[2025-04-30T17:10:28.713439914Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Initial version=1 dcid=37e2aa3d34e599c4 scid=919df65cab0ea0ff token= len=1174 pn=0 
[2025-04-30T17:10:28.713451005Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm CRYPTO off=0 len=480
[2025-04-30T17:10:28.713477815Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T17:10:28.713482233Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T17:10:28.713614209Z TRACE quiche::tls] 753a3c5ee6522480f84d699e8c4f5e69132115cf write message lvl=Initial len=129
[2025-04-30T17:10:28.713632944Z TRACE quiche::tls] 753a3c5ee6522480f84d699e8c4f5e69132115cf set write secret lvl=Handshake
[2025-04-30T17:10:28.713661297Z TRACE quiche::tls] 753a3c5ee6522480f84d699e8c4f5e69132115cf write message lvl=Handshake len=148
[2025-04-30T17:10:28.713664463Z TRACE quiche::tls] 753a3c5ee6522480f84d699e8c4f5e69132115cf set write secret lvl=OneRTT
[2025-04-30T17:10:28.713679631Z TRACE quiche::tls] 753a3c5ee6522480f84d699e8c4f5e69132115cf set read secret lvl=Handshake
[2025-04-30T17:10:28.713695210Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm PADDING len=673
[2025-04-30T17:10:28.713710448Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 1200 bytes
[2025-04-30T17:10:28.713720367Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.713732680Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Initial version=1 dcid=919df65cab0ea0ff scid=753a3c5ee6522480f84d699e8c4f5e69132115cf len=138 pn=0 
[2025-04-30T17:10:28.713736637Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm ACK delay=38 blocks=[0..0] ecn_counts=None
[2025-04-30T17:10:28.713740976Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm CRYPTO off=0 len=129
[2025-04-30T17:10:28.713750904Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=998.971668ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=193 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 422, tv_nsec: 824702820 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 824702820 }, 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: 422, tv_nsec: 824702820 }, next_time: Instant { tv_sec: 422, tv_nsec: 824702820 }, 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-30T17:10:28.713773396Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Handshake version=1 dcid=919df65cab0ea0ff scid=753a3c5ee6522480f84d699e8c4f5e69132115cf len=152 pn=1 
[2025-04-30T17:10:28.713776802Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm CRYPTO off=0 len=148
[2025-04-30T17:10:28.713782974Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=998.939848ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=399 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 422, tv_nsec: 824702820 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 824702820 }, 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: 422, tv_nsec: 824702820 }, next_time: Instant { tv_sec: 422, tv_nsec: 824702820 }, 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-30T17:10:28.713841042Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:48462, at: Instant { tv_sec: 422, tv_nsec: 824702820 } })
[2025-04-30T17:10:28.713848195Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.713851050Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.713858955Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.713865878Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.713873422Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.713877991Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.713882399Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.713886246Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.751408086Z TRACE quiche_server] got 1201 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.751434665Z TRACE quiche_server] got packet Initial version=1 dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf scid=919df65cab0ea0ff token=
[2025-04-30T17:10:28.751448802Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Initial version=1 dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf scid=919df65cab0ea0ff token= len=1163 pn=1 
[2025-04-30T17:10:28.751462097Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T17:10:28.751468869Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 0
[2025-04-30T17:10:28.751479159Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm PADDING len=1141
[2025-04-30T17:10:28.751502702Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 1201 bytes
[2025-04-30T17:10:28.751509104Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.751518672Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.751521898Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.751524784Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.751527609Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.751855640Z TRACE quiche_server] got 92 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.751861090Z TRACE quiche_server] got packet Handshake version=1 dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf scid=919df65cab0ea0ff
[2025-04-30T17:10:28.751867102Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Handshake version=1 dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf scid=919df65cab0ea0ff len=56 pn=0 
[2025-04-30T17:10:28.751873423Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm CRYPTO off=0 len=36
[2025-04-30T17:10:28.751888982Z TRACE quiche::tls] 753a3c5ee6522480f84d699e8c4f5e69132115cf set read secret lvl=OneRTT
[2025-04-30T17:10:28.751925450Z TRACE quiche::tls] 753a3c5ee6522480f84d699e8c4f5e69132115cf write message lvl=OneRTT len=396
[2025-04-30T17:10:28.751935218Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf dropped epoch 1 state
[2025-04-30T17:10:28.751939486Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("P-256") sigalg=None resumed=true TransportParams { original_destination_connection_id: None, max_idle_timeout: 60000, stateless_reset_token: None, max_udp_payload_size: 1500, initial_max_data: 2500000, initial_max_stream_data_bidi_local: 250000, initial_max_stream_data_bidi_remote: 250000, initial_max_stream_data_uni: 250000, initial_max_streams_bidi: 3, initial_max_streams_uni: 3, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 2, initial_source_connection_id: Some(919df65cab0ea0ff), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T17:10:28.751960616Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf dropped epoch 0 state
[2025-04-30T17:10:28.751964002Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 92 bytes
[2025-04-30T17:10:28.751969993Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.751976114Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T17:10:28.751994088Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=441 pn=2 
[2025-04-30T17:10:28.751997765Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[e1, a5, c3, b8, 34, af, 69, f0, 3e, 00, f8, c4, 4f, e1, cd, 17, ea, dc, 2f, 0a] reset_token=[68, 44, a8, 75, 4c, 3d, c6, 30, 39, 92, 25, a3, b9, da, b2, 31]
[2025-04-30T17:10:28.752006221Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm HANDSHAKE_DONE
[2025-04-30T17:10:28.752009296Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm CRYPTO off=0 len=396
[2025-04-30T17:10:28.752020237Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=138.094572ms latest_rtt=37.722403ms srtt=37.722403ms min_rtt=37.722403ms rttvar=18.861201ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=467 app_limited=true congestion_recovery_start_time=None Rate { delivered: 193, delivered_time: Instant { tv_sec: 422, tv_nsec: 862952225 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 862952225 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 5116, is_app_limited: true, interval: 37.722403ms, delivered: 193, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 824702820 }), send_elapsed: 0ns, ack_elapsed: 37.722403ms, rtt: 37.722403ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 447346, last_update: Instant { tv_sec: 422, tv_nsec: 862952225 }, next_time: Instant { tv_sec: 422, tv_nsec: 862952225 }, 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-30T17:10:28.752105546Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf written 467 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:48462, at: Instant { tv_sec: 422, tv_nsec: 862952225 } })
[2025-04-30T17:10:28.752113601Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.752116506Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.752119161Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.752124020Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.752127367Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.752130101Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.752132696Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.752135211Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.752928068Z TRACE quiche_server] got 117 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.752932477Z TRACE quiche_server] got packet Handshake version=1 dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf scid=919df65cab0ea0ff
[2025-04-30T17:10:28.752937837Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf dropped invalid packet
[2025-04-30T17:10:28.752940371Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 117 bytes
[2025-04-30T17:10:28.752943998Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.752946453Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.752948617Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.752950661Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.752952705Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.803272790Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.803290543Z TRACE quiche_server] got packet Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false
[2025-04-30T17:10:28.803308005Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false len=23 pn=1 
[2025-04-30T17:10:28.803317262Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm ACK delay=2375 blocks=[2..2] ecn_counts=None
[2025-04-30T17:10:28.803322642Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 2
[2025-04-30T17:10:28.803338241Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 44 bytes
[2025-04-30T17:10:28.803344192Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.803348280Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.803351526Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.803354432Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.803361755Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.877076521Z TRACE quiche_server] got 59 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.877097069Z TRACE quiche_server] got packet Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false
[2025-04-30T17:10:28.877107849Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false len=38 pn=2 
[2025-04-30T17:10:28.877117958Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T17:10:28.877133767Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 59 bytes
[2025-04-30T17:10:28.877137554Z DEBUG quiche_apps::common] 753a3c5ee6522480f84d699e8c4f5e69132115cf response stream 0 is writable with capacity Ok(13500)
[2025-04-30T17:10:28.877146070Z TRACE quiche_apps::common] 753a3c5ee6522480f84d699e8c4f5e69132115cf received 17 bytes
[2025-04-30T17:10:28.877149236Z TRACE quiche_apps::common] 753a3c5ee6522480f84d699e8c4f5e69132115cf stream 0 has 17 bytes (fin? true)
[2025-04-30T17:10:28.877154807Z INFO  quiche_apps::common] 753a3c5ee6522480f84d699e8c4f5e69132115cf got GET request for "/www/swvtgishyx" on stream 0
[2025-04-30T17:10:28.877185965Z INFO  quiche_apps::common] 753a3c5ee6522480f84d699e8c4f5e69132115cf sending response of size 10240 on stream 0
[2025-04-30T17:10:28.877195883Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.877204639Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=13500 out_len=1350 left=1319
[2025-04-30T17:10:28.877212243Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1324 pn=3 
[2025-04-30T17:10:28.877215670Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm ACK delay=12 blocks=[1..2] ecn_counts=None
[2025-04-30T17:10:28.877220349Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=0 len=1314 fin=false
[2025-04-30T17:10:28.877231529Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.585504ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877261876Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12150 out_len=1350 left=1324
[2025-04-30T17:10:28.877266444Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1324 pn=4 
[2025-04-30T17:10:28.877269550Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=1314 len=1318 fin=false
[2025-04-30T17:10:28.877276153Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.601935ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=2700 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877289177Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10800 out_len=1350 left=1324
[2025-04-30T17:10:28.877292974Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1324 pn=5 
[2025-04-30T17:10:28.877295959Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=2632 len=1318 fin=false
[2025-04-30T17:10:28.877301239Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.60496ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4050 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877313111Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=9450 out_len=1350 left=1324
[2025-04-30T17:10:28.877316778Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1324 pn=6 
[2025-04-30T17:10:28.877323551Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=3950 len=1318 fin=false
[2025-04-30T17:10:28.877329141Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.601193ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5400 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 4, last_sent_packet: 6, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877340823Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8100 out_len=1350 left=1324
[2025-04-30T17:10:28.877344490Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1324 pn=7 
[2025-04-30T17:10:28.877347375Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=5268 len=1318 fin=false
[2025-04-30T17:10:28.877353507Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.604609ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=6750 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 4, last_sent_packet: 7, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877364948Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6750 out_len=1350 left=1324
[2025-04-30T17:10:28.877368695Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1324 pn=8 
[2025-04-30T17:10:28.877371590Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=6586 len=1318 fin=false
[2025-04-30T17:10:28.877376850Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.605531ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8100 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 4, last_sent_packet: 8, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877391488Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=5400 out_len=1350 left=1324
[2025-04-30T17:10:28.877395104Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1324 pn=9 
[2025-04-30T17:10:28.877398020Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=7904 len=1318 fin=false
[2025-04-30T17:10:28.877403149Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.605731ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9450 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 4, last_sent_packet: 9, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877414470Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4050 out_len=1350 left=1324
[2025-04-30T17:10:28.877417967Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=1024 pn=10 
[2025-04-30T17:10:28.877420922Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm STREAM id=0 off=9222 len=1018 fin=true
[2025-04-30T17:10:28.877426202Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=134.605711ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10500 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 4, last_sent_packet: 10, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 422, tv_nsec: 988178506 }, next_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, 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-30T17:10:28.877633659Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf written 10500 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:48462, at: Instant { tv_sec: 422, tv_nsec: 988178506 } })
[2025-04-30T17:10:28.877645220Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.877649869Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.877654688Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.877661300Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.877664897Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.877667732Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.877670347Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.877672852Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.916197401Z TRACE quiche_server] got 59 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.916214042Z TRACE quiche_server] got packet Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false
[2025-04-30T17:10:28.916224401Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false len=38 pn=3 
[2025-04-30T17:10:28.916233798Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T17:10:28.916269515Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 59 bytes
[2025-04-30T17:10:28.916275206Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.916283451Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf pmtud probe status false hs_con=true hs_sent=true cwnd_avail=3000 out_len=1350 left=1319
[2025-04-30T17:10:28.916288170Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx pkt Short dcid=919df65cab0ea0ff key_phase=false len=5 pn=11 
[2025-04-30T17:10:28.916290735Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf tx frm ACK delay=7 blocks=[1..3] ecn_counts=None
[2025-04-30T17:10:28.916301344Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf timer=95.730238ms latest_rtt=51.333894ms srtt=39.423838ms min_rtt=37.722403ms rttvar=17.548772ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10500 app_limited=true congestion_recovery_start_time=None Rate { delivered: 660, delivered_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, first_sent_time: Instant { tv_sec: 422, tv_nsec: 988178506 }, end_of_app_limited: 4, last_sent_packet: 11, largest_acked: 2, rate_sample: RateSample { delivery_rate: 9097, is_app_limited: true, interval: 51.333894ms, delivered: 467, prior_delivered: 193, prior_time: Some(Instant { tv_sec: 422, tv_nsec: 862952225 }), send_elapsed: 0ns, ack_elapsed: 51.333894ms, rtt: 51.333894ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 428040, last_update: Instant { tv_sec: 423, tv_nsec: 27257027 }, next_time: Instant { tv_sec: 423, tv_nsec: 27257027 }, 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-30T17:10:28.916349865Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf written 31 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:48462, at: Instant { tv_sec: 423, tv_nsec: 27257027 } })
[2025-04-30T17:10:28.916365023Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.916369982Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.916373318Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.916379350Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.916383207Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.916385732Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.916387876Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.916389940Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.916513299Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.916517517Z TRACE quiche_server] got packet Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false
[2025-04-30T17:10:28.916522587Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false len=23 pn=4 
[2025-04-30T17:10:28.916527536Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm ACK delay=250 blocks=[3..8] ecn_counts=None
[2025-04-30T17:10:28.916531263Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 3
[2025-04-30T17:10:28.916534158Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 4
[2025-04-30T17:10:28.916536482Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 5
[2025-04-30T17:10:28.916538757Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 6
[2025-04-30T17:10:28.916541522Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 7
[2025-04-30T17:10:28.916543706Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 8
[2025-04-30T17:10:28.916559055Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 44 bytes
[2025-04-30T17:10:28.916563072Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.916565587Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.916567831Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.916569925Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.916571979Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.917142522Z TRACE quiche_server] got 43 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.917149004Z TRACE quiche_server] got packet Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false
[2025-04-30T17:10:28.917153302Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false len=22 pn=5 
[2025-04-30T17:10:28.917157440Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm ACK delay=0 blocks=[3..10] ecn_counts=None
[2025-04-30T17:10:28.917160445Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 9
[2025-04-30T17:10:28.917162749Z TRACE quiche::recovery::congestion::recovery] 753a3c5ee6522480f84d699e8c4f5e69132115cf packet newly acked 10
[2025-04-30T17:10:28.917172458Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 43 bytes
[2025-04-30T17:10:28.917176154Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.917178649Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.917180803Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.917182887Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.917184901Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.918104094Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:48462 to [::]:443
[2025-04-30T17:10:28.918110075Z TRACE quiche_server] got packet Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false
[2025-04-30T17:10:28.918114343Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx pkt Short dcid=753a3c5ee6522480f84d699e8c4f5e69132115cf key_phase=false len=26 pn=6 
[2025-04-30T17:10:28.918118751Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T17:10:28.918122308Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf rx frm ACK delay=0 blocks=[3..10] ecn_counts=None
[2025-04-30T17:10:28.918129201Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf processed 47 bytes
[2025-04-30T17:10:28.918132797Z TRACE quiche_server] recv() would block
[2025-04-30T17:10:28.918135011Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:28.918137196Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:28.918139289Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:28.918141313Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:29.157433933Z TRACE quiche_server] timed out
[2025-04-30T17:10:29.157465842Z TRACE quiche] 753a3c5ee6522480f84d699e8c4f5e69132115cf draining timeout expired
[2025-04-30T17:10:29.157509434Z TRACE quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf done writing
[2025-04-30T17:10:29.157513822Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:29.157516938Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:29.157520655Z INFO  quiche_server] 753a3c5ee6522480f84d699e8c4f5e69132115cf connection collected recv=9 sent=12 lost=0 retrans=0 sent_bytes=11397 recv_bytes=2789 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:48462 validation_state=Validated active=true recv=9 sent=12 lost=0 retrans=0 rtt=39.479039ms min_rtt=Some(37.722403ms) rttvar=10.025767ms cwnd=13500 sent_bytes=11397 recv_bytes=2789 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=267020]
[2025-04-30T17:10:29.157555149Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:29.268689923Z TRACE quiche_server] timed out
[2025-04-30T17:10:29.268713247Z TRACE quiche] 85d827303084775e352e89d15c745f7883e7318b draining timeout expired
[2025-04-30T17:10:29.268746629Z TRACE quiche_server] 85d827303084775e352e89d15c745f7883e7318b done writing
[2025-04-30T17:10:29.268750366Z TRACE quiche_server] Collecting garbage
[2025-04-30T17:10:29.268753863Z INFO  quiche_server] 85d827303084775e352e89d15c745f7883e7318b connection collected recv=8 sent=7 lost=0 retrans=0 sent_bytes=6661 recv_bytes=2729 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:57708 validation_state=Validated active=true recv=8 sent=7 lost=0 retrans=0 rtt=76.382473ms min_rtt=Some(54.147311ms) rttvar=27.763087ms cwnd=13500 sent_bytes=6661 recv_bytes=2729 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=105619]