[2025-04-30T11:35:36.043173536Z INFO  quiche_server] listening on [::]:443
[2025-04-30T11:35:36.043196548Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T11:35:36.043203491Z TRACE quiche_server] GSO detected: false
[2025-04-30T11:35:36.872484031Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:47382 to [::]:443
[2025-04-30T11:35:36.872519467Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T11:35:36.872529576Z WARN  quiche_server] Doing version negotiation
[2025-04-30T11:35:36.872629593Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:47382 to [::]:443
[2025-04-30T11:35:36.872639191Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T11:35:36.872645002Z WARN  quiche_server] Doing version negotiation
[2025-04-30T11:35:36.872674847Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:47382 to [::]:443
[2025-04-30T11:35:36.872681750Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T11:35:36.872686840Z WARN  quiche_server] Doing version negotiation
[2025-04-30T11:35:36.872711405Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.830403775Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:37.830431638Z TRACE quiche_server] got packet Initial version=1 dcid=566ad63b1821f260 scid=1ab7165d2a8e5552 token=
[2025-04-30T11:35:37.830442027Z WARN  quiche_server] Doing stateless retry
[2025-04-30T11:35:37.830568493Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.885555512Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:37.885573405Z TRACE quiche_server] got packet Initial version=1 dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=1ab7165d2a8e5552 token=71756963686500000000000000000000ffffc1a70064566ad63b1821f260
[2025-04-30T11:35:37.885583625Z DEBUG quiche_server] New connection: dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=3535860ef769973155bf3ce225b0ae312952a9b9
[2025-04-30T11:35:37.885789268Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Initial version=1 dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=1ab7165d2a8e5552 token=71756963686500000000000000000000ffffc1a70064566ad63b1821f260 len=1132 pn=1 
[2025-04-30T11:35:37.885804246Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm CRYPTO off=0 len=257
[2025-04-30T11:35:37.885831226Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T11:35:37.885835705Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T11:35:37.885950459Z TRACE quiche::tls] 3535860ef769973155bf3ce225b0ae312952a9b9 write message lvl=Initial len=123
[2025-04-30T11:35:37.885985004Z TRACE quiche::tls] 3535860ef769973155bf3ce225b0ae312952a9b9 set write secret lvl=Handshake
[2025-04-30T11:35:37.886052239Z TRACE quiche::tls] 3535860ef769973155bf3ce225b0ae312952a9b9 write message lvl=Handshake len=718
[2025-04-30T11:35:37.886055866Z TRACE quiche::tls] 3535860ef769973155bf3ce225b0ae312952a9b9 set write secret lvl=OneRTT
[2025-04-30T11:35:37.886063430Z TRACE quiche::tls] 3535860ef769973155bf3ce225b0ae312952a9b9 set read secret lvl=Handshake
[2025-04-30T11:35:37.886084319Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm PADDING len=854
[2025-04-30T11:35:37.886106991Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 1200 bytes
[2025-04-30T11:35:37.886117000Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.886130435Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Initial version=1 dcid=1ab7165d2a8e5552 scid=3535860ef769973155bf3ce225b0ae312952a9b9 len=132 pn=0 
[2025-04-30T11:35:37.886134332Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm ACK delay=44 blocks=[1..1] ecn_counts=None
[2025-04-30T11:35:37.886139332Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm CRYPTO off=0 len=123
[2025-04-30T11:35:37.886149801Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=998.968992ms 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: 321, tv_nsec: 78840148 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 78840148 }, 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: 321, tv_nsec: 78840148 }, next_time: Instant { tv_sec: 321, tv_nsec: 78840148 }, 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-30T11:35:37.886182161Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Handshake version=1 dcid=1ab7165d2a8e5552 scid=3535860ef769973155bf3ce225b0ae312952a9b9 len=722 pn=1 
[2025-04-30T11:35:37.886185838Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm CRYPTO off=0 len=718
[2025-04-30T11:35:37.886192431Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=998.927084ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=963 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 321, tv_nsec: 78840148 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 78840148 }, 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: 321, tv_nsec: 78840148 }, next_time: Instant { tv_sec: 321, tv_nsec: 78840148 }, 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-30T11:35:37.886238717Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41969, at: Instant { tv_sec: 321, tv_nsec: 78840148 } })
[2025-04-30T11:35:37.886245530Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:37.886250719Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.886254897Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:37.886257782Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:37.946682088Z TRACE quiche_server] got 1201 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:37.946701314Z TRACE quiche_server] got packet Initial version=1 dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=1ab7165d2a8e5552 token=71756963686500000000000000000000ffffc1a70064566ad63b1821f260
[2025-04-30T11:35:37.946713116Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Initial version=1 dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=1ab7165d2a8e5552 token=71756963686500000000000000000000ffffc1a70064566ad63b1821f260 len=1133 pn=2 
[2025-04-30T11:35:37.946722704Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm ACK delay=0 blocks=[0..0] ecn_counts=None
[2025-04-30T11:35:37.946728705Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 0
[2025-04-30T11:35:37.946752680Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm PADDING len=1111
[2025-04-30T11:35:37.946769812Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 1201 bytes
[2025-04-30T11:35:37.946775132Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.946781714Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:37.946784349Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:37.976446051Z TRACE quiche_server] got 92 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:37.976464115Z TRACE quiche_server] got packet Handshake version=1 dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=1ab7165d2a8e5552
[2025-04-30T11:35:37.976476237Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Handshake version=1 dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=1ab7165d2a8e5552 len=56 pn=0 
[2025-04-30T11:35:37.976486877Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm CRYPTO off=0 len=36
[2025-04-30T11:35:37.976507485Z TRACE quiche::tls] 3535860ef769973155bf3ce225b0ae312952a9b9 set read secret lvl=OneRTT
[2025-04-30T11:35:37.976551908Z TRACE quiche::tls] 3535860ef769973155bf3ce225b0ae312952a9b9 write message lvl=OneRTT len=396
[2025-04-30T11:35:37.976563109Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 dropped epoch 1 state
[2025-04-30T11:35:37.976568159Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 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(1ab7165d2a8e5552), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T11:35:37.976601872Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 dropped epoch 0 state
[2025-04-30T11:35:37.976606180Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 92 bytes
[2025-04-30T11:35:37.976613493Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.976620767Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T11:35:37.976631577Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=441 pn=2 
[2025-04-30T11:35:37.976635605Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[79, 2a, ca, 24, 60, 4f, 8f, a1, ab, 06, 5d, 1a, be, 66, 95, 62, 1e, 19, 3c, 5b] reset_token=[75, 3d, 26, f5, 16, 6e, 43, 7f, 3b, 16, 7b, d2, c7, 7d, fa, 80]
[2025-04-30T11:35:37.976645763Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm HANDSHAKE_DONE
[2025-04-30T11:35:37.976649140Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm CRYPTO off=0 len=396
[2025-04-30T11:35:37.976662625Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.725862ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=467 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 1, last_sent_packet: 2, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.976738196Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 written 467 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41969, at: Instant { tv_sec: 321, tv_nsec: 169337023 } })
[2025-04-30T11:35:37.976746942Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:37.976752823Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.976756710Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:37.976759786Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:37.978195320Z TRACE quiche_server] got 58 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:37.978210468Z TRACE quiche_server] got packet Handshake version=1 dcid=3535860ef769973155bf3ce225b0ae312952a9b9 scid=1ab7165d2a8e5552
[2025-04-30T11:35:37.978219735Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 dropped invalid packet
[2025-04-30T11:35:37.978224043Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 58 bytes
[2025-04-30T11:35:37.978229834Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.978234302Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:37.978237959Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:37.983458253Z TRACE quiche_server] got 59 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:37.983471448Z TRACE quiche_server] got packet Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false
[2025-04-30T11:35:37.983479854Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false len=38 pn=0 
[2025-04-30T11:35:37.983488200Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T11:35:37.983500482Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 59 bytes
[2025-04-30T11:35:37.983504299Z DEBUG quiche_apps::common] 3535860ef769973155bf3ce225b0ae312952a9b9 response stream 0 is writable with capacity Ok(13033)
[2025-04-30T11:35:37.983512415Z TRACE quiche_apps::common] 3535860ef769973155bf3ce225b0ae312952a9b9 received 17 bytes
[2025-04-30T11:35:37.983515951Z TRACE quiche_apps::common] 3535860ef769973155bf3ce225b0ae312952a9b9 stream 0 has 17 bytes (fin? true)
[2025-04-30T11:35:37.983521842Z INFO  quiche_apps::common] 3535860ef769973155bf3ce225b0ae312952a9b9 got GET request for "/www/yakfrnqvld" on stream 0
[2025-04-30T11:35:37.983551538Z INFO  quiche_apps::common] 3535860ef769973155bf3ce225b0ae312952a9b9 sending response of size 10240 on stream 0
[2025-04-30T11:35:37.983562388Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.983570894Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=13033 out_len=1350 left=1319
[2025-04-30T11:35:37.983577115Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1324 pn=3 
[2025-04-30T11:35:37.983580852Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm ACK delay=11 blocks=[0..0] ecn_counts=None
[2025-04-30T11:35:37.983585932Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=0 len=1314 fin=false
[2025-04-30T11:35:37.983596872Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.740439ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1817 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 2, last_sent_packet: 3, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.983626207Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11683 out_len=1350 left=1324
[2025-04-30T11:35:37.983631417Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1324 pn=4 
[2025-04-30T11:35:37.983634853Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=1314 len=1318 fin=false
[2025-04-30T11:35:37.983642237Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.753684ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=3167 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 3, last_sent_packet: 4, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.983659319Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10333 out_len=1350 left=1324
[2025-04-30T11:35:37.983663807Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1324 pn=5 
[2025-04-30T11:35:37.983667123Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=2632 len=1318 fin=false
[2025-04-30T11:35:37.983673675Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.755868ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=4517 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 4, last_sent_packet: 5, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.983695176Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=8983 out_len=1350 left=1324
[2025-04-30T11:35:37.983700025Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1324 pn=6 
[2025-04-30T11:35:37.983703431Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=3950 len=1318 fin=false
[2025-04-30T11:35:37.983711296Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.753864ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=5867 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 4, last_sent_packet: 6, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.983727436Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=7633 out_len=1350 left=1324
[2025-04-30T11:35:37.983732115Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1324 pn=7 
[2025-04-30T11:35:37.983735391Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=5268 len=1318 fin=false
[2025-04-30T11:35:37.983742524Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.755157ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=7217 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 4, last_sent_packet: 7, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.983762982Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=6283 out_len=1350 left=1324
[2025-04-30T11:35:37.983767751Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1324 pn=8 
[2025-04-30T11:35:37.983771197Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=6586 len=1318 fin=false
[2025-04-30T11:35:37.983778090Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.755176ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=8567 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 4, last_sent_packet: 8, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.983794741Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=4933 out_len=1350 left=1324
[2025-04-30T11:35:37.983799089Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1324 pn=9 
[2025-04-30T11:35:37.983802516Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=7904 len=1318 fin=false
[2025-04-30T11:35:37.983809148Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.756048ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=9917 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 4, last_sent_packet: 9, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.983824918Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=3583 out_len=1350 left=1324
[2025-04-30T11:35:37.983829096Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx pkt Short dcid=1ab7165d2a8e5552 key_phase=false len=1024 pn=10 
[2025-04-30T11:35:37.983837301Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 tx frm STREAM id=0 off=9222 len=1018 fin=true
[2025-04-30T11:35:37.983844694Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 timer=206.750537ms latest_rtt=60.590877ms srtt=60.590877ms min_rtt=60.590877ms rttvar=30.295438ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=10967 app_limited=true congestion_recovery_start_time=None Rate { delivered: 187, delivered_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, first_sent_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, end_of_app_limited: 4, last_sent_packet: 10, largest_acked: 0, rate_sample: RateSample { delivery_rate: 3086, is_app_limited: true, interval: 60.590877ms, delivered: 187, prior_delivered: 0, prior_time: Some(Instant { tv_sec: 321, tv_nsec: 78840148 }), send_elapsed: 0ns, ack_elapsed: 60.590877ms, rtt: 60.590877ms } } pacer=Pacer { enabled: false, capacity: 13500, used: 0, rate: 278507, last_update: Instant { tv_sec: 321, tv_nsec: 169337023 }, next_time: Instant { tv_sec: 321, tv_nsec: 169337023 }, 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-30T11:35:37.984016735Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 written 10500 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41969, at: Instant { tv_sec: 321, tv_nsec: 169337023 } })
[2025-04-30T11:35:37.984026163Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:37.984031703Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:37.984035881Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:37.984038977Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:38.017871634Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:38.017894456Z TRACE quiche_server] got packet Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false
[2025-04-30T11:35:38.017908603Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false len=23 pn=1 
[2025-04-30T11:35:38.017923280Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm ACK delay=375 blocks=[2..3] ecn_counts=None
[2025-04-30T11:35:38.017931696Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 2
[2025-04-30T11:35:38.017936515Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 3
[2025-04-30T11:35:38.017998751Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 44 bytes
[2025-04-30T11:35:38.018009130Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:38.018014590Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:38.018018087Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:38.022899164Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:38.022911647Z TRACE quiche_server] got packet Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false
[2025-04-30T11:35:38.022920283Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false len=23 pn=2 
[2025-04-30T11:35:38.022928358Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm ACK delay=375 blocks=[2..5] ecn_counts=None
[2025-04-30T11:35:38.022933497Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 4
[2025-04-30T11:35:38.022937265Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 5
[2025-04-30T11:35:38.022952473Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 44 bytes
[2025-04-30T11:35:38.022965698Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:38.022969966Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:38.022973202Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:38.024916853Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:38.024930719Z TRACE quiche_server] got packet Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false
[2025-04-30T11:35:38.024938984Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false len=23 pn=3 
[2025-04-30T11:35:38.024947370Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm ACK delay=125 blocks=[2..7] ecn_counts=None
[2025-04-30T11:35:38.024952349Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 6
[2025-04-30T11:35:38.024955936Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 7
[2025-04-30T11:35:38.024970894Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 44 bytes
[2025-04-30T11:35:38.024977125Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:38.024981383Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:38.024984840Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:38.026648799Z TRACE quiche_server] got 44 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:38.026661583Z TRACE quiche_server] got packet Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false
[2025-04-30T11:35:38.026669818Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false len=23 pn=4 
[2025-04-30T11:35:38.026677523Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm ACK delay=125 blocks=[2..9] ecn_counts=None
[2025-04-30T11:35:38.026682362Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 8
[2025-04-30T11:35:38.026685818Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 9
[2025-04-30T11:35:38.026700636Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 44 bytes
[2025-04-30T11:35:38.026706046Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:38.026709953Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:38.026713149Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:38.046563534Z TRACE quiche_server] got 48 bytes from [::ffff:193.167.0.100]:41969 to [::]:443
[2025-04-30T11:35:38.046580045Z TRACE quiche_server] got packet Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false
[2025-04-30T11:35:38.046589212Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx pkt Short dcid=3535860ef769973155bf3ce225b0ae312952a9b9 key_phase=false len=27 pn=5 
[2025-04-30T11:35:38.046598068Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T11:35:38.046604019Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 rx frm ACK delay=2125 blocks=[2..10] ecn_counts=None
[2025-04-30T11:35:38.046609149Z TRACE quiche::recovery::congestion::recovery] 3535860ef769973155bf3ce225b0ae312952a9b9 packet newly acked 10
[2025-04-30T11:35:38.046630008Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 processed 48 bytes
[2025-04-30T11:35:38.046635879Z TRACE quiche_server] recv() would block
[2025-04-30T11:35:38.046639265Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:38.046642351Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:38.414028544Z TRACE quiche_server] timed out
[2025-04-30T11:35:38.414047049Z TRACE quiche] 3535860ef769973155bf3ce225b0ae312952a9b9 draining timeout expired
[2025-04-30T11:35:38.414083026Z TRACE quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 done writing
[2025-04-30T11:35:38.414085971Z TRACE quiche_server] Collecting garbage
[2025-04-30T11:35:38.414089027Z INFO  quiche_server] 3535860ef769973155bf3ce225b0ae312952a9b9 connection collected recv=9 sent=11 lost=0 retrans=0 sent_bytes=11930 recv_bytes=2776 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:41969 validation_state=Validated active=true recv=9 sent=11 lost=0 retrans=0 rtt=54.244265ms min_rtt=Some(41.288767ms) rttvar=13.065747ms cwnd=13500 sent_bytes=11930 recv_bytes=2776 lost_bytes=0 stream_retrans_bytes=0 pmtu=1350 delivery_rate=265617]