[2025-04-30T09:08:13.513530390Z INFO  quiche_server] listening on [::]:443
[2025-04-30T09:08:13.513551830Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T09:08:13.513558101Z TRACE quiche_server] GSO detected: false
[2025-04-30T09:08:14.357821929Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:50826 to [::]:443
[2025-04-30T09:08:14.357864378Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:08:14.357874025Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:08:14.357998556Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:50826 to [::]:443
[2025-04-30T09:08:14.358008004Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:08:14.358013504Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:08:14.358043530Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:50826 to [::]:443
[2025-04-30T09:08:14.358050363Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T09:08:14.358055472Z WARN  quiche_server] Doing version negotiation
[2025-04-30T09:08:14.358081029Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.552075658Z TRACE quiche_server] got 1220 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.552111364Z TRACE quiche_server] got packet Initial version=1 dcid=9ae1586ee2117b44 scid= token=
[2025-04-30T09:08:14.552119760Z DEBUG quiche_server] New connection: dcid=9ae1586ee2117b44 scid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02
[2025-04-30T09:08:14.552357521Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Initial version=1 dcid=9ae1586ee2117b44 scid= token= len=1202 pn=0 
[2025-04-30T09:08:14.552373440Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm CRYPTO off=0 len=297
[2025-04-30T09:08:14.552404618Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T09:08:14.552409297Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T09:08:14.552525723Z TRACE quiche::tls] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 write message lvl=Initial len=90
[2025-04-30T09:08:14.552563673Z TRACE quiche::tls] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 set write secret lvl=Handshake
[2025-04-30T09:08:14.552702832Z TRACE quiche::tls] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 write message lvl=Handshake len=713
[2025-04-30T09:08:14.552709564Z TRACE quiche::tls] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 set write secret lvl=OneRTT
[2025-04-30T09:08:14.552720484Z TRACE quiche::tls] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 set read secret lvl=Handshake
[2025-04-30T09:08:14.552748065Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm PADDING len=881
[2025-04-30T09:08:14.552771930Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 1220 bytes
[2025-04-30T09:08:14.552787018Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.552801535Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx pkt Initial version=1 dcid= scid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 len=99 pn=0 
[2025-04-30T09:08:14.552805703Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm ACK delay=58 blocks=[0..0] ecn_counts=None
[2025-04-30T09:08:14.552811233Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm CRYPTO off=0 len=90
[2025-04-30T09:08:14.552822914Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 timer=998.966278ms 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: 200, tv_nsec: 808215151 }, first_sent_time: Instant { tv_sec: 200, tv_nsec: 808215151 }, 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: 200, tv_nsec: 808215151 }, next_time: Instant { tv_sec: 200, tv_nsec: 808215151 }, 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:08:14.552862297Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx pkt Handshake version=1 dcid= scid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 len=717 pn=1 
[2025-04-30T09:08:14.552866305Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm CRYPTO off=0 len=713
[2025-04-30T09:08:14.552874360Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 timer=998.915193ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=909 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 200, tv_nsec: 808215151 }, first_sent_time: Instant { tv_sec: 200, tv_nsec: 808215151 }, 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: 200, tv_nsec: 808215151 }, next_time: Instant { tv_sec: 200, tv_nsec: 808215151 }, 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:08:14.552954068Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45206, at: Instant { tv_sec: 200, tv_nsec: 808215151 } })
[2025-04-30T09:08:14.552965409Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.552973995Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.552981378Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 done writing
[2025-04-30T09:08:14.552984534Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.585816331Z TRACE quiche_server] got 109 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.585855885Z TRACE quiche_server] got packet Handshake version=1 dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 scid=
[2025-04-30T09:08:14.585873347Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Handshake version=1 dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 scid= len=80 pn=1 
[2025-04-30T09:08:14.585888535Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm ACK delay=3 blocks=[1..1] ecn_counts=None
[2025-04-30T09:08:14.585897181Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 packet newly acked 1
[2025-04-30T09:08:14.585904204Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm CRYPTO off=0 len=52
[2025-04-30T09:08:14.585930473Z TRACE quiche::tls] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 set read secret lvl=OneRTT
[2025-04-30T09:08:14.585981447Z TRACE quiche::tls] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 write message lvl=OneRTT len=428
[2025-04-30T09:08:14.585994903Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 dropped epoch 1 state
[2025-04-30T09:08:14.585999962Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 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:08:14.586051397Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 dropped epoch 0 state
[2025-04-30T09:08:14.586055304Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 109 bytes
[2025-04-30T09:08:14.586063940Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.586071304Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1332
[2025-04-30T09:08:14.586081724Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx pkt Short dcid= key_phase=false len=553 pn=2 
[2025-04-30T09:08:14.586085280Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[a2, a9, fa, 2d, c9, 76, 23, ae, 72, f9, b6, b4, 4f, f6, 61, b7, 80, cb, fc, 39] reset_token=[48, 7a, 30, 0a, 1a, 89, d5, 79, ec, 29, c0, 21, 13, 01, ea, 9f]
[2025-04-30T09:08:14.586095479Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[9a, 54, 49, b1, a8, 50, 9c, ee, d5, 14, 04, d7, 95, bb, 5e, d2, d8, c6, a1, 1d] reset_token=[e2, a7, 40, f6, 92, c8, 4b, 61, 0f, 5a, 64, 1d, 53, 55, 4f, 74]
[2025-04-30T09:08:14.586103754Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[f5, f4, 2b, 90, 8a, 10, 9f, 31, 6b, fd, c0, 04, ea, e7, 79, 50, 6b, 90, 82, 51] reset_token=[38, 9f, 31, cf, f0, 56, 8a, c7, 31, 70, e2, 2a, 51, 5d, bb, 08]
[2025-04-30T09:08:14.586111779Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm HANDSHAKE_DONE
[2025-04-30T09:08:14.586114875Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm CRYPTO off=0 len=428
[2025-04-30T09:08:14.586129572Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 timer=125.172916ms latest_rtt=33.078785ms srtt=33.078785ms min_rtt=33.078785ms rttvar=16.539392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 763, delivered_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, first_sent_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23066, is_app_limited: true, interval: 33.078785ms, delivered: 763, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 200, tv_nsec: 808215151 }), send_elapsed: 0ns, ack_elapsed: 33.078785ms, rtt: 33.078785ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 510145, last_update: Instant { tv_sec: 200, tv_nsec: 841492034 }, next_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, 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:08:14.586226402Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 written 571 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45206, at: Instant { tv_sec: 200, tv_nsec: 841492034 } })
[2025-04-30T09:08:14.586237793Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.586245457Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.586250947Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 done writing
[2025-04-30T09:08:14.586255656Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.586732020Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.586741528Z TRACE quiche_server] got packet Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false
[2025-04-30T09:08:14.586748962Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false len=1231 pn=2 
[2025-04-30T09:08:14.586756255Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm PING mtu_probe=None
[2025-04-30T09:08:14.586763138Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm PADDING len=1210
[2025-04-30T09:08:14.586771153Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 1252 bytes
[2025-04-30T09:08:14.586776763Z TRACE quiche_server] got 45 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.586779699Z TRACE quiche_server] got packet Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false
[2025-04-30T09:08:14.586783816Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false len=24 pn=3 
[2025-04-30T09:08:14.586788595Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm STREAM id=0 off=0 len=0 fin=false
[2025-04-30T09:08:14.586794807Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 45 bytes
[2025-04-30T09:08:14.586797452Z DEBUG quiche_apps::common] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 response stream 0 is writable with capacity Ok(12929)
[2025-04-30T09:08:14.586802221Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.586807210Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12929 out_len=1350 left=1327
[2025-04-30T09:08:14.586810696Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx pkt Short dcid= key_phase=false len=5 pn=3 
[2025-04-30T09:08:14.586813241Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm ACK delay=2 blocks=[2..3] ecn_counts=None
[2025-04-30T09:08:14.586820314Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 timer=124.482405ms latest_rtt=33.078785ms srtt=33.078785ms min_rtt=33.078785ms rttvar=16.539392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=571 app_limited=true congestion_recovery_start_time=None Rate { delivered: 763, delivered_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, first_sent_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, end_of_app_limited: 1, last_sent_packet: 3, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23066, is_app_limited: true, interval: 33.078785ms, delivered: 763, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 200, tv_nsec: 808215151 }), send_elapsed: 0ns, ack_elapsed: 33.078785ms, rtt: 33.078785ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 510145, last_update: Instant { tv_sec: 200, tv_nsec: 841492034 }, next_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, 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:08:14.586871118Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45206, at: Instant { tv_sec: 200, tv_nsec: 841492034 } })
[2025-04-30T09:08:14.586879684Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.586887779Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.586893029Z TRACE quiche_server] got packet Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false
[2025-04-30T09:08:14.586901465Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false len=41 pn=4 
[2025-04-30T09:08:14.586914629Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T09:08:14.586921712Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 62 bytes
[2025-04-30T09:08:14.586924487Z DEBUG quiche_apps::common] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 response stream 0 is writable with capacity Ok(12929)
[2025-04-30T09:08:14.586930378Z TRACE quiche_apps::common] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 received 17 bytes
[2025-04-30T09:08:14.586933043Z TRACE quiche_apps::common] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 stream 0 has 17 bytes (fin? true)
[2025-04-30T09:08:14.586937672Z INFO  quiche_apps::common] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 got GET request for "/www/aomlcocfdd" on stream 0
[2025-04-30T09:08:14.586973218Z INFO  quiche_apps::common] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 sending response of size 1024 on stream 0
[2025-04-30T09:08:14.586980080Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.586985009Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12929 out_len=1350 left=1327
[2025-04-30T09:08:14.586989237Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx pkt Short dcid= key_phase=false len=1034 pn=4 
[2025-04-30T09:08:14.586991832Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm ACK delay=10 blocks=[2..4] ecn_counts=None
[2025-04-30T09:08:14.586995179Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm STREAM id=0 off=0 len=1024 fin=true
[2025-04-30T09:08:14.587002793Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 timer=125.215324ms latest_rtt=33.078785ms srtt=33.078785ms min_rtt=33.078785ms rttvar=16.539392ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1623 app_limited=true congestion_recovery_start_time=None Rate { delivered: 763, delivered_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, first_sent_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 1, rate_sample: RateSample { delivery_rate: 23066, is_app_limited: true, interval: 33.078785ms, delivered: 763, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 200, tv_nsec: 808215151 }), send_elapsed: 0ns, ack_elapsed: 33.078785ms, rtt: 33.078785ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 510145, last_update: Instant { tv_sec: 200, tv_nsec: 841492034 }, next_time: Instant { tv_sec: 200, tv_nsec: 841492034 }, 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:08:14.587052054Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 written 1052 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45206, at: Instant { tv_sec: 200, tv_nsec: 841492034 } })
[2025-04-30T09:08:14.587060099Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.587065750Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.587070218Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 done writing
[2025-04-30T09:08:14.587073714Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.618412924Z TRACE quiche_server] got 1332 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.618441136Z TRACE quiche_server] got packet Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false
[2025-04-30T09:08:14.618453699Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false len=1311 pn=5 
[2025-04-30T09:08:14.618466303Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm PING mtu_probe=None
[2025-04-30T09:08:14.618482623Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm PADDING len=1290
[2025-04-30T09:08:14.618497230Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 1332 bytes
[2025-04-30T09:08:14.618505005Z TRACE quiche_server] got 51 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.618508381Z TRACE quiche_server] got packet Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false
[2025-04-30T09:08:14.618512779Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false len=30 pn=6 
[2025-04-30T09:08:14.618517999Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm ACK delay=0 blocks=[2..4] ecn_counts=None
[2025-04-30T09:08:14.618524030Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 packet newly acked 2
[2025-04-30T09:08:14.618529510Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 packet newly acked 3
[2025-04-30T09:08:14.618534990Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 packet newly acked 4
[2025-04-30T09:08:14.618541853Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm MAX_DATA max=16778240
[2025-04-30T09:08:14.618564716Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 51 bytes
[2025-04-30T09:08:14.618571889Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.618604840Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=13500 out_len=1350 left=1327
[2025-04-30T09:08:14.618616501Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx pkt Short dcid= key_phase=false len=5 pn=5 
[2025-04-30T09:08:14.618619567Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 tx frm ACK delay=11 blocks=[5..6] ecn_counts=None
[2025-04-30T09:08:14.618628844Z TRACE quiche::recovery::congestion::recovery] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 timer=none latest_rtt=32.445221ms srtt=32.999588ms min_rtt=32.445221ms rttvar=12.562935ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=0 app_limited=true congestion_recovery_start_time=None Rate { delivered: 2386, delivered_time: Instant { tv_sec: 200, tv_nsec: 874023585 }, first_sent_time: Instant { tv_sec: 200, tv_nsec: 874023585 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 4, rate_sample: RateSample { delivery_rate: 50022, is_app_limited: true, interval: 32.445221ms, delivered: 1623, prior_delivered: 763, prior_time: Some(Instant { tv_sec: 200, tv_nsec: 841492034 }), send_elapsed: 0ns, ack_elapsed: 32.445221ms, rtt: 32.445221ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 511370, last_update: Instant { tv_sec: 200, tv_nsec: 874023585 }, next_time: Instant { tv_sec: 200, tv_nsec: 874023585 }, 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:08:14.618686922Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 written 23 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:45206, at: Instant { tv_sec: 200, tv_nsec: 874023585 } })
[2025-04-30T09:08:14.618694827Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.618704174Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:45206 to [::]:443
[2025-04-30T09:08:14.618709514Z TRACE quiche_server] got packet Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false
[2025-04-30T09:08:14.618717519Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx pkt Short dcid=393cb3799fdfbad6c44882a86f0a36b59fb6eb02 key_phase=false len=23 pn=7 
[2025-04-30T09:08:14.618725394Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 rx frm APPLICATION_CLOSE err=0 reason=[]
[2025-04-30T09:08:14.618741964Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 processed 44 bytes
[2025-04-30T09:08:14.618748286Z TRACE quiche_server] recv() would block
[2025-04-30T09:08:14.618752273Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 done writing
[2025-04-30T09:08:14.618755910Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.869059946Z TRACE quiche_server] timed out
[2025-04-30T09:08:14.869084191Z TRACE quiche] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 draining timeout expired
[2025-04-30T09:08:14.869139884Z TRACE quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 done writing
[2025-04-30T09:08:14.869144082Z TRACE quiche_server] Collecting garbage
[2025-04-30T09:08:14.869148110Z INFO  quiche_server] 393cb3799fdfbad6c44882a86f0a36b59fb6eb02 connection collected recv=8 sent=6 lost=0 retrans=0 sent_bytes=2578 recv_bytes=4115 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:45206 validation_state=Validated active=true recv=8 sent=6 lost=0 retrans=0 rtt=32.999588ms min_rtt=Some(32.445221ms) rttvar=12.562935ms cwnd=13500 sent_bytes=2578 recv_bytes=4115 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=50022]