[2025-04-30T18:04:20.086770240Z INFO  quiche_server] listening on [::]:443
[2025-04-30T18:04:20.086792030Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T18:04:20.086798623Z TRACE quiche_server] GSO detected: false
[2025-04-30T18:04:20.931794686Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:36940 to [::]:443
[2025-04-30T18:04:20.931820113Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:04:20.931828409Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:04:20.931909370Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:36940 to [::]:443
[2025-04-30T18:04:20.931915491Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:04:20.931919108Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:04:20.931940228Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:36940 to [::]:443
[2025-04-30T18:04:20.931944395Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T18:04:20.931947772Z WARN  quiche_server] Doing version negotiation
[2025-04-30T18:04:20.931972478Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.141169724Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.141193428Z TRACE quiche_server] got packet Initial version=1 dcid=ddfd9d47fbf9f3d9 scid=9fda123c4cc92416 token=
[2025-04-30T18:04:21.141202475Z DEBUG quiche_server] New connection: dcid=ddfd9d47fbf9f3d9 scid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9
[2025-04-30T18:04:21.141402869Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx pkt Initial version=1 dcid=ddfd9d47fbf9f3d9 scid=9fda123c4cc92416 token= len=1174 pn=0 
[2025-04-30T18:04:21.141417126Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm CRYPTO off=0 len=305
[2025-04-30T18:04:21.141447443Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T18:04:21.141452081Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T18:04:21.141568048Z TRACE quiche::tls] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 write message lvl=Initial len=123
[2025-04-30T18:04:21.141595489Z TRACE quiche::tls] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 set write secret lvl=Handshake
[2025-04-30T18:04:21.141653568Z TRACE quiche::tls] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 write message lvl=Handshake len=697
[2025-04-30T18:04:21.141656463Z TRACE quiche::tls] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 set write secret lvl=OneRTT
[2025-04-30T18:04:21.141689324Z TRACE quiche::tls] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 set read secret lvl=Handshake
[2025-04-30T18:04:21.141710254Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm PADDING len=845
[2025-04-30T18:04:21.141730241Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 1200 bytes
[2025-04-30T18:04:21.141747553Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.141759736Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx pkt Initial version=1 dcid=9fda123c4cc92416 scid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 len=132 pn=0 
[2025-04-30T18:04:21.141763152Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm ACK delay=46 blocks=[0..0] ecn_counts=None
[2025-04-30T18:04:21.141767470Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm CRYPTO off=0 len=123
[2025-04-30T18:04:21.141776758Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 timer=998.972709ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=187 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 318, tv_nsec: 434092493 }, first_sent_time: Instant { tv_sec: 318, tv_nsec: 434092493 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 318, tv_nsec: 434092493 }, next_time: Instant { tv_sec: 318, tv_nsec: 434092493 }, max_datagram_size: 1200, 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-30T18:04:21.141804991Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx pkt Handshake version=1 dcid=9fda123c4cc92416 scid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 len=701 pn=1 
[2025-04-30T18:04:21.141807956Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm CRYPTO off=0 len=697
[2025-04-30T18:04:21.141813647Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 timer=998.936271ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=942 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 318, tv_nsec: 434092493 }, first_sent_time: Instant { tv_sec: 318, tv_nsec: 434092493 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 318, tv_nsec: 434092493 }, next_time: Instant { tv_sec: 318, tv_nsec: 434092493 }, max_datagram_size: 1200, 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-30T18:04:21.141856016Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59283, at: Instant { tv_sec: 318, tv_nsec: 434092493 } })
[2025-04-30T18:04:21.141862047Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.141867948Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.141873519Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.141877205Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.175209569Z TRACE quiche_server] got 96 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.175223015Z TRACE quiche_server] got packet Handshake version=1 dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 scid=9fda123c4cc92416
[2025-04-30T18:04:21.175231982Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx pkt Handshake version=1 dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 scid=9fda123c4cc92416 len=59 pn=0 
[2025-04-30T18:04:21.175239656Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm CRYPTO off=0 len=36
[2025-04-30T18:04:21.175251207Z TRACE quiche::tls] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 set read secret lvl=OneRTT
[2025-04-30T18:04:21.175284970Z TRACE quiche::tls] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 write message lvl=OneRTT len=396
[2025-04-30T18:04:21.175293036Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 dropped epoch 1 state
[2025-04-30T18:04:21.175297193Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 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: 120000, stateless_reset_token: None, max_udp_payload_size: 1200, initial_max_data: 1048576, initial_max_stream_data_bidi_local: 16777216, initial_max_stream_data_bidi_remote: 16777216, initial_max_stream_data_uni: 16777216, initial_max_streams_bidi: 128, initial_max_streams_uni: 128, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: true, active_conn_id_limit: 8, initial_source_connection_id: Some(9fda123c4cc92416), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None }
[2025-04-30T18:04:21.175327179Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 dropped epoch 0 state
[2025-04-30T18:04:21.175330966Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 96 bytes
[2025-04-30T18:04:21.175337669Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.175344031Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12000 out_len=1200 left=1174
[2025-04-30T18:04:21.175353288Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx pkt Short dcid=9fda123c4cc92416 key_phase=false len=681 pn=2 
[2025-04-30T18:04:21.175356775Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[de, 68, ff, 53, e5, 8f, b2, 72, da, a3, 63, 86, ed, 1f, 39, 9d, 3c, 25, 4c, af] reset_token=[0f, 44, 78, 26, b2, db, 50, f6, 3c, 4d, 88, 9d, af, fd, d9, 55]
[2025-04-30T18:04:21.175366413Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[d4, 81, 87, c3, 6c, c9, 7e, 4d, 2f, 22, 38, a6, a3, 03, 87, 82, 0b, e0, 74, 01] reset_token=[8d, 5d, c6, 4f, 87, 6d, 79, 47, a9, c1, be, dd, 39, 88, c6, 10]
[2025-04-30T18:04:21.175374327Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[ee, 37, fe, 43, f2, 5a, a7, f1, 4e, 33, 32, e2, 33, 6a, 0b, c7, ba, 8f, 96, ec] reset_token=[84, 9c, 51, c3, 04, 0f, 9f, 22, cf, 26, 82, ff, 07, 87, 59, af]
[2025-04-30T18:04:21.175382072Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[66, 6b, 69, 43, 31, 4a, 9f, 18, 0a, 00, 6e, 6c, 7d, 80, 52, 54, e6, 09, d5, c6] reset_token=[51, d5, 0e, 25, 7b, 94, c8, d7, a8, 02, f0, b6, fe, 2f, ab, 78]
[2025-04-30T18:04:21.175389626Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[55, e5, 69, aa, 3d, c6, 2e, b5, 07, 29, 25, f0, f2, 84, f0, 3f, 73, 49, a7, 9f] reset_token=[ca, 70, f2, 26, b2, a0, 9e, 94, c3, 38, 6a, f1, 7e, 6c, e9, 4d]
[2025-04-30T18:04:21.175397240Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[38, 02, 1d, 19, dc, fb, fd, f3, b4, 4f, 48, 28, a5, 54, cc, 33, dd, 60, 70, ae] reset_token=[67, eb, 4a, 7e, a2, 97, 16, 7c, 83, a2, de, 01, 0e, 87, 71, c1]
[2025-04-30T18:04:21.175404905Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[78, 8b, fd, fe, 67, 14, e2, 8d, 4d, cd, 29, be, 8e, 23, d1, f0, f3, 7c, 1d, 44] reset_token=[b8, 6f, 58, 28, ef, 81, 1d, 81, bd, 8d, 13, 56, 8d, a3, 05, a1]
[2025-04-30T18:04:21.175412298Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm HANDSHAKE_DONE
[2025-04-30T18:04:21.175415164Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm CRYPTO off=0 len=396
[2025-04-30T18:04:21.175428358Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 timer=1.023911565s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=707 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, first_sent_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, end_of_app_limited: 1, last_sent_packet: 2, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 318, tv_nsec: 467682769 }, next_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, max_datagram_size: 1200, 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-30T18:04:21.175481658Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 written 707 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59283, at: Instant { tv_sec: 318, tv_nsec: 467682769 } })
[2025-04-30T18:04:21.175488090Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.175494421Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.175500423Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.175504611Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.176188919Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.176201152Z TRACE quiche_server] got packet Initial version=1 dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 scid=9fda123c4cc92416 token=
[2025-04-30T18:04:21.176210890Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 dropped invalid packet
[2025-04-30T18:04:21.176214787Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 1200 bytes
[2025-04-30T18:04:21.176221740Z TRACE quiche_server] got 63 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.176224906Z TRACE quiche_server] got packet Handshake version=1 dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 scid=9fda123c4cc92416
[2025-04-30T18:04:21.176229485Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 dropped invalid packet
[2025-04-30T18:04:21.176231809Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 63 bytes
[2025-04-30T18:04:21.176235075Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.176238081Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.176240355Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.176330122Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.176336424Z TRACE quiche_server] got packet Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false
[2025-04-30T18:04:21.176341904Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx pkt Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false len=41 pn=0 
[2025-04-30T18:04:21.176347936Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T18:04:21.176356762Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 62 bytes
[2025-04-30T18:04:21.176359668Z DEBUG quiche_apps::common] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 response stream 0 is writable with capacity Ok(11293)
[2025-04-30T18:04:21.176366029Z TRACE quiche_apps::common] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 received 17 bytes
[2025-04-30T18:04:21.176368594Z TRACE quiche_apps::common] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 stream 0 has 17 bytes (fin? true)
[2025-04-30T18:04:21.176372922Z INFO  quiche_apps::common] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 got GET request for "/www/ivrcmkfhim" on stream 0
[2025-04-30T18:04:21.176392258Z INFO  quiche_apps::common] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 sending response of size 1024 on stream 0
[2025-04-30T18:04:21.176398741Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.176404401Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=11293 out_len=1200 left=1169
[2025-04-30T18:04:21.176408709Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx pkt Short dcid=9fda123c4cc92416 key_phase=false len=1034 pn=3 
[2025-04-30T18:04:21.176411424Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm ACK delay=7 blocks=[0..0] ecn_counts=None
[2025-04-30T18:04:21.176419860Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm STREAM id=0 off=0 len=1024 fin=true
[2025-04-30T18:04:21.176427344Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 timer=1.02397322s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1767 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, first_sent_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, end_of_app_limited: 2, last_sent_packet: 3, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 318, tv_nsec: 468743290 }, next_time: Instant { tv_sec: 318, tv_nsec: 468743290 }, max_datagram_size: 1200, 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-30T18:04:21.176466337Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 written 1060 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59283, at: Instant { tv_sec: 318, tv_nsec: 468743290 } })
[2025-04-30T18:04:21.176471396Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.176476816Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.176481535Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.176484911Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.206753323Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.206764364Z TRACE quiche_server] got packet Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false
[2025-04-30T18:04:21.206771056Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx pkt Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false len=48 pn=1 
[2025-04-30T18:04:21.206778711Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[33, 1b, 6f, 4d, de, 83, 81, d2] reset_token=[f6, 09, d5, 81, a3, 54, 51, 15, ef, 2b, e5, 59, 28, d2, f2, eb]
[2025-04-30T18:04:21.206789361Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 69 bytes
[2025-04-30T18:04:21.206793679Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.206798758Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10233 out_len=1200 left=1169
[2025-04-30T18:04:21.206802305Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx pkt Short dcid=9fda123c4cc92416 key_phase=false len=5 pn=4 
[2025-04-30T18:04:21.206804860Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm ACK delay=3 blocks=[0..1] ecn_counts=None
[2025-04-30T18:04:21.206811442Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 timer=993.589122ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1767 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, first_sent_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, end_of_app_limited: 2, last_sent_packet: 4, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 318, tv_nsec: 499138158 }, next_time: Instant { tv_sec: 318, tv_nsec: 499138158 }, max_datagram_size: 1200, 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-30T18:04:21.206854082Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 written 31 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59283, at: Instant { tv_sec: 318, tv_nsec: 499138158 } })
[2025-04-30T18:04:21.206859372Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.206865132Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.206868318Z TRACE quiche_server] got packet Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false
[2025-04-30T18:04:21.206873247Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx pkt Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false len=48 pn=2 
[2025-04-30T18:04:21.206879599Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[7d, 3c, ad, 7b, de, 27, e7, b4] reset_token=[e8, 46, 6d, 3b, 86, a2, e1, c0, 55, 23, d1, 7d, 0b, 3d, fc, cf]
[2025-04-30T18:04:21.206887524Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 69 bytes
[2025-04-30T18:04:21.206891061Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.206895148Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=10233 out_len=1200 left=1169
[2025-04-30T18:04:21.206898324Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx pkt Short dcid=9fda123c4cc92416 key_phase=false len=5 pn=5 
[2025-04-30T18:04:21.206900799Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 tx frm ACK delay=2 blocks=[0..2] ecn_counts=None
[2025-04-30T18:04:21.206906008Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 timer=993.494636ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1767 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, first_sent_time: Instant { tv_sec: 318, tv_nsec: 467682769 }, end_of_app_limited: 2, last_sent_packet: 5, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 318, tv_nsec: 499235390 }, next_time: Instant { tv_sec: 318, tv_nsec: 499235390 }, max_datagram_size: 1200, 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-30T18:04:21.206936836Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 written 31 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:59283, at: Instant { tv_sec: 318, tv_nsec: 499235390 } })
[2025-04-30T18:04:21.206941244Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.206944891Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.206947516Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.206949750Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.207987158Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.207993670Z TRACE quiche_server] got packet Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false
[2025-04-30T18:04:21.207998619Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx pkt Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false len=25 pn=3 
[2025-04-30T18:04:21.208008157Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm ACK delay=36 blocks=[2..3] ecn_counts=None
[2025-04-30T18:04:21.208011964Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 packet newly acked 2
[2025-04-30T18:04:21.208015000Z TRACE quiche::recovery::congestion::recovery] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 packet newly acked 3
[2025-04-30T18:04:21.208027583Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 46 bytes
[2025-04-30T18:04:21.208031501Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.208034115Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.208036330Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.583914180Z TRACE quiche_server] got 45 bytes from [::ffff:193.167.0.100]:59283 to [::]:443
[2025-04-30T18:04:21.583929058Z TRACE quiche_server] got packet Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false
[2025-04-30T18:04:21.583937844Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx pkt Short dcid=61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 key_phase=false len=24 pn=10 
[2025-04-30T18:04:21.583946019Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 rx frm CONNECTION_CLOSE err=0 frame=0 reason=[]
[2025-04-30T18:04:21.583956789Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 processed 45 bytes
[2025-04-30T18:04:21.583962390Z TRACE quiche_server] recv() would block
[2025-04-30T18:04:21.583965475Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.583968371Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.869300049Z TRACE quiche_server] timed out
[2025-04-30T18:04:21.869313815Z TRACE quiche] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 draining timeout expired
[2025-04-30T18:04:21.869351585Z TRACE quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 done writing
[2025-04-30T18:04:21.869355392Z TRACE quiche_server] Collecting garbage
[2025-04-30T18:04:21.869358598Z INFO  quiche_server] 61469e0cce30c0dc1c4618fb8dabb06bce9da6f9 connection collected recv=7 sent=6 lost=0 retrans=0 sent_bytes=2771 recv_bytes=1587 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:59283 validation_state=Validated active=true recv=7 sent=6 lost=0 retrans=0 rtt=31.596923ms min_rtt=Some(31.596923ms) rttvar=15.798461ms cwnd=12000 sent_bytes=2771 recv_bytes=1587 lost_bytes=0 stream_retrans_bytes=0 pmtu=1200 delivery_rate=54107]