[2025-04-30T19:12:13.195843478Z INFO  quiche_server] listening on [::]:443
[2025-04-30T19:12:13.195864046Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE
[2025-04-30T19:12:13.195870167Z TRACE quiche_server] GSO detected: false
[2025-04-30T19:12:14.008197800Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:33213 to [::]:443
[2025-04-30T19:12:14.008218048Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T19:12:14.008226223Z WARN  quiche_server] Doing version negotiation
[2025-04-30T19:12:14.008300071Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:33213 to [::]:443
[2025-04-30T19:12:14.008308707Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T19:12:14.008314578Z WARN  quiche_server] Doing version negotiation
[2025-04-30T19:12:14.008344674Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:33213 to [::]:443
[2025-04-30T19:12:14.008351657Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token=
[2025-04-30T19:12:14.008356867Z WARN  quiche_server] Doing version negotiation
[2025-04-30T19:12:14.008382314Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:14.973258855Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:14.973279193Z TRACE quiche_server] got packet Initial version=1 dcid=909e41a826e18237 scid= token=
[2025-04-30T19:12:14.973285986Z DEBUG quiche_server] New connection: dcid=909e41a826e18237 scid=dc5e8da95c4fa4868160ec60bd978f35af064f6f
[2025-04-30T19:12:14.973453448Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Initial version=1 dcid=909e41a826e18237 scid= token= len=1234 pn=4794152 
[2025-04-30T19:12:14.973465330Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm CRYPTO off=0 len=310
[2025-04-30T19:12:14.973489856Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3")
[2025-04-30T19:12:14.973493493Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop")
[2025-04-30T19:12:14.973574544Z TRACE quiche::tls] dc5e8da95c4fa4868160ec60bd978f35af064f6f write message lvl=Initial len=90
[2025-04-30T19:12:14.973598309Z TRACE quiche::tls] dc5e8da95c4fa4868160ec60bd978f35af064f6f set write secret lvl=Handshake
[2025-04-30T19:12:14.973661396Z TRACE quiche::tls] dc5e8da95c4fa4868160ec60bd978f35af064f6f write message lvl=Handshake len=696
[2025-04-30T19:12:14.973664181Z TRACE quiche::tls] dc5e8da95c4fa4868160ec60bd978f35af064f6f set write secret lvl=OneRTT
[2025-04-30T19:12:14.973670453Z TRACE quiche::tls] dc5e8da95c4fa4868160ec60bd978f35af064f6f set read secret lvl=Handshake
[2025-04-30T19:12:14.973688226Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm PADDING len=901
[2025-04-30T19:12:14.973703886Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 1252 bytes
[2025-04-30T19:12:14.973716238Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:14.973727389Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Initial version=1 dcid= scid=dc5e8da95c4fa4868160ec60bd978f35af064f6f len=102 pn=0 
[2025-04-30T19:12:14.973730465Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm ACK delay=36 blocks=[4794152..4794152] ecn_counts=None
[2025-04-30T19:12:14.973734753Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm CRYPTO off=0 len=90
[2025-04-30T19:12:14.973743520Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=998.974272ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=149 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, first_sent_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, 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: 218, tv_nsec: 772701147 }, next_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, 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-30T19:12:14.973770310Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Handshake version=1 dcid= scid=dc5e8da95c4fa4868160ec60bd978f35af064f6f len=700 pn=1 
[2025-04-30T19:12:14.973773024Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm CRYPTO off=0 len=696
[2025-04-30T19:12:14.973778485Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=998.939677ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=895 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, first_sent_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, 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: 218, tv_nsec: 772701147 }, next_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, 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-30T19:12:14.973820112Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:35610, at: Instant { tv_sec: 218, tv_nsec: 772701147 } })
[2025-04-30T19:12:14.973826334Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:14.973830742Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:14.973834149Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f done writing
[2025-04-30T19:12:14.973836403Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.086681470Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:15.086697379Z TRACE quiche_server] got packet Initial version=1 dcid=909e41a826e18237 scid= token=
[2025-04-30T19:12:15.086706146Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Initial version=1 dcid=909e41a826e18237 scid= token= len=1234 pn=4794154 
[2025-04-30T19:12:15.086713940Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm CRYPTO off=0 len=310
[2025-04-30T19:12:15.086733387Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm PADDING len=901
[2025-04-30T19:12:15.086745499Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 1252 bytes
[2025-04-30T19:12:15.086750879Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:15.086760778Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Initial version=1 dcid= scid=dc5e8da95c4fa4868160ec60bd978f35af064f6f len=10 pn=2 
[2025-04-30T19:12:15.086764605Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm ACK delay=6 blocks=[4794152..4794152, 4794154..4794154] ecn_counts=None
[2025-04-30T19:12:15.086774824Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=885.943038ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=895 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, first_sent_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, 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: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 218, tv_nsec: 885736539 }, next_time: Instant { tv_sec: 218, tv_nsec: 885736539 }, 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-30T19:12:15.086836369Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:35610, at: Instant { tv_sec: 218, tv_nsec: 885736539 } })
[2025-04-30T19:12:15.086844053Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.086849323Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:15.086853541Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f done writing
[2025-04-30T19:12:15.086856577Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.297118977Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:15.297135097Z TRACE quiche_server] got packet Initial version=1 dcid=909e41a826e18237 scid= token=
[2025-04-30T19:12:15.297143764Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Initial version=1 dcid=909e41a826e18237 scid= token= len=1234 pn=4794156 
[2025-04-30T19:12:15.297151588Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm CRYPTO off=0 len=310
[2025-04-30T19:12:15.297161477Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm PADDING len=901
[2025-04-30T19:12:15.297182777Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 1252 bytes
[2025-04-30T19:12:15.297189810Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:15.297200479Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Initial version=1 dcid= scid=dc5e8da95c4fa4868160ec60bd978f35af064f6f len=12 pn=3 
[2025-04-30T19:12:15.297204257Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm ACK delay=7 blocks=[4794152..4794152, 4794154..4794154, 4794156..4794156] ecn_counts=None
[2025-04-30T19:12:15.297214846Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=675.503015ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=895 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, first_sent_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, end_of_app_limited: 1, 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: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 219, tv_nsec: 96175449 }, next_time: Instant { tv_sec: 219, tv_nsec: 96175449 }, 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-30T19:12:15.297270590Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:35610, at: Instant { tv_sec: 219, tv_nsec: 96175449 } })
[2025-04-30T19:12:15.297286560Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.297292060Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:15.297296308Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f done writing
[2025-04-30T19:12:15.297299334Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.707642939Z TRACE quiche_server] got 1252 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:15.707659490Z TRACE quiche_server] got packet Initial version=1 dcid=909e41a826e18237 scid= token=
[2025-04-30T19:12:15.707668286Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Initial version=1 dcid=909e41a826e18237 scid= token= len=1234 pn=4794158 
[2025-04-30T19:12:15.707676281Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm CRYPTO off=0 len=310
[2025-04-30T19:12:15.707686661Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm PADDING len=901
[2025-04-30T19:12:15.707707730Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 1252 bytes
[2025-04-30T19:12:15.707713140Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:15.707724431Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Initial version=1 dcid= scid=dc5e8da95c4fa4868160ec60bd978f35af064f6f len=14 pn=4 
[2025-04-30T19:12:15.707728269Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm ACK delay=6 blocks=[4794152..4794152, 4794154..4794154, 4794156..4794156, 4794158..4794158] ecn_counts=None
[2025-04-30T19:12:15.707739399Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=264.978452ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=895 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, first_sent_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, end_of_app_limited: 1, 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: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 219, tv_nsec: 506698790 }, next_time: Instant { tv_sec: 219, tv_nsec: 506698790 }, 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-30T19:12:15.707796897Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:35610, at: Instant { tv_sec: 219, tv_nsec: 506698790 } })
[2025-04-30T19:12:15.707804060Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.707809220Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:15.707813237Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f done writing
[2025-04-30T19:12:15.707816263Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.973135616Z TRACE quiche_server] timed out
[2025-04-30T19:12:15.973150263Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f loss detection timeout expired
[2025-04-30T19:12:15.973156024Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=998.561597ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=895 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, first_sent_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, end_of_app_limited: 1, 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: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 219, tv_nsec: 506698790 }, next_time: Instant { tv_sec: 219, tv_nsec: 506698790 }, 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-30T19:12:15.973193524Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Initial version=1 dcid= scid=dc5e8da95c4fa4868160ec60bd978f35af064f6f len=111 pn=5 
[2025-04-30T19:12:15.973198213Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm ACK delay=33190 blocks=[4794152..4794152, 4794154..4794154, 4794156..4794156, 4794158..4794158] ecn_counts=None
[2025-04-30T19:12:15.973203553Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm CRYPTO off=0 len=90
[2025-04-30T19:12:15.973216457Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=998.501485ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1053 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, first_sent_time: Instant { tv_sec: 218, tv_nsec: 772701147 }, end_of_app_limited: 4, 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: 13500, used: 0, rate: 0, last_update: Instant { tv_sec: 219, tv_nsec: 772162954 }, next_time: Instant { tv_sec: 219, tv_nsec: 772162954 }, 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-30T19:12:15.973271620Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:35610, at: Instant { tv_sec: 219, tv_nsec: 772162954 } })
[2025-04-30T19:12:15.973279274Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:15.973285075Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:15.973289393Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f done writing
[2025-04-30T19:12:15.973292419Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:16.476430425Z TRACE quiche_server] got 93 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:16.476447107Z TRACE quiche_server] got packet Handshake version=1 dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f scid=
[2025-04-30T19:12:16.476457626Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Handshake version=1 dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f scid= len=64 pn=4794152 
[2025-04-30T19:12:16.476477273Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm CRYPTO off=0 len=36
[2025-04-30T19:12:16.476493714Z TRACE quiche::tls] dc5e8da95c4fa4868160ec60bd978f35af064f6f set read secret lvl=OneRTT
[2025-04-30T19:12:16.476530282Z TRACE quiche::tls] dc5e8da95c4fa4868160ec60bd978f35af064f6f write message lvl=OneRTT len=396
[2025-04-30T19:12:16.476539880Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f dropped epoch 1 state
[2025-04-30T19:12:16.476544468Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f connection established: proto=Ok("hq-interop") cipher=Some(AES128_GCM) curve=Some("X25519") 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: 107374182, initial_max_stream_data_bidi_local: 107374182, initial_max_stream_data_bidi_remote: 107374182, initial_max_stream_data_uni: 107374182, initial_max_streams_bidi: 100, initial_max_streams_uni: 100, ack_delay_exponent: 3, max_ack_delay: 25, disable_active_migration: false, active_conn_id_limit: 5, initial_source_connection_id: Some(), retry_source_connection_id: None, max_datagram_frame_size: Some(65535), unknown_params: None }
[2025-04-30T19:12:16.476563173Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm ACK delay=122 blocks=[1..1] ecn_counts=None
[2025-04-30T19:12:16.476587669Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f dropped epoch 0 state
[2025-04-30T19:12:16.476591626Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 93 bytes
[2025-04-30T19:12:16.476600943Z TRACE quiche_server] got 68 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:16.476605302Z TRACE quiche_server] got packet Short dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f key_phase=false
[2025-04-30T19:12:16.476611273Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Short dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f key_phase=false len=47 pn=4794152 
[2025-04-30T19:12:16.476617414Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm STREAM id=0 off=0 len=17 fin=true
[2025-04-30T19:12:16.476623025Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm PADDING len=8
[2025-04-30T19:12:16.476630148Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 68 bytes
[2025-04-30T19:12:16.476633685Z DEBUG quiche_apps::common] dc5e8da95c4fa4868160ec60bd978f35af064f6f response stream 0 is writable with capacity Ok(13500)
[2025-04-30T19:12:16.476640928Z TRACE quiche_apps::common] dc5e8da95c4fa4868160ec60bd978f35af064f6f received 17 bytes
[2025-04-30T19:12:16.476644284Z TRACE quiche_apps::common] dc5e8da95c4fa4868160ec60bd978f35af064f6f stream 0 has 17 bytes (fin? true)
[2025-04-30T19:12:16.476649354Z INFO  quiche_apps::common] dc5e8da95c4fa4868160ec60bd978f35af064f6f got GET request for "/www/johogubpta" on stream 0
[2025-04-30T19:12:16.476673309Z INFO  quiche_apps::common] dc5e8da95c4fa4868160ec60bd978f35af064f6f sending response of size 1024 on stream 0
[2025-04-30T19:12:16.476681845Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:16.476690140Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f pmtud probe status false hs_con=true hs_sent=false cwnd_avail=13500 out_len=1350 left=1324
[2025-04-30T19:12:16.476700359Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Short dcid= key_phase=false len=1332 pn=6 
[2025-04-30T19:12:16.476704096Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm ACK delay=9 blocks=[4794152..4794152] ecn_counts=None
[2025-04-30T19:12:16.476713744Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[43, 40, 24, 41, 78, 64, eb, 26, ff, d2, 8f, 28, ba, 9c, eb, 37, 6f, 1b, 36, b3] reset_token=[14, 8c, a0, cb, 91, 1f, fd, 37, a3, 87, 8c, b4, a6, c2, fb, 97]
[2025-04-30T19:12:16.476724324Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[2b, ce, 33, ff, dd, e1, 4f, cf, f4, 27, 5a, 52, 81, f3, 5e, b1, 08, 29, d2, d8] reset_token=[41, 56, 74, ed, 85, a1, 86, 5c, 32, 9a, 74, 6b, e2, b5, fe, ef]
[2025-04-30T19:12:16.476734212Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[4e, 70, 4a, e1, f2, 85, 59, 04, c6, ea, 62, 16, b2, ff, 2b, 30, 85, c5, 87, 5e] reset_token=[59, dc, 9c, 1f, 08, 52, ce, 9a, 58, b1, f0, bd, 1c, 1d, f7, 08]
[2025-04-30T19:12:16.476743970Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[23, 11, cb, cd, a4, f6, c4, a3, 8b, 0c, 12, 09, 76, f0, 78, ea, 46, 43, 7c, 45] reset_token=[c7, c4, 7c, c8, 74, 8f, 39, e9, e4, d7, 6d, da, d7, a3, 67, 09]
[2025-04-30T19:12:16.476759710Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm HANDSHAKE_DONE
[2025-04-30T19:12:16.476763076Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm CRYPTO off=0 len=396
[2025-04-30T19:12:16.476766472Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm STREAM id=0 off=0 len=758 fin=false
[2025-04-30T19:12:16.476782873Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=2.047901275s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1350 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 220, tv_nsec: 275667624 }, first_sent_time: Instant { tv_sec: 220, tv_nsec: 275667624 }, end_of_app_limited: 5, last_sent_packet: 6, 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: 220, tv_nsec: 275667624 }, next_time: Instant { tv_sec: 220, tv_nsec: 275667624 }, 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-30T19:12:16.476807449Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f pmtud probe status false hs_con=true hs_sent=true cwnd_avail=12150 out_len=1350 left=1332
[2025-04-30T19:12:16.476811426Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx pkt Short dcid= key_phase=false len=272 pn=7 
[2025-04-30T19:12:16.476814172Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f tx frm STREAM id=0 off=758 len=266 fin=true
[2025-04-30T19:12:16.476835561Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f timer=2.047969723s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=13500 ssthresh=18446744073709551615 bytes_in_flight=1640 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 220, tv_nsec: 275667624 }, first_sent_time: Instant { tv_sec: 220, tv_nsec: 275667624 }, end_of_app_limited: 6, last_sent_packet: 7, 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: 220, tv_nsec: 275788440 }, next_time: Instant { tv_sec: 220, tv_nsec: 275788440 }, 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-30T19:12:16.476891225Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f written 1640 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:35610, at: Instant { tv_sec: 220, tv_nsec: 275667624 } })
[2025-04-30T19:12:16.476897276Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:16.476901675Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:16.476904781Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f done writing
[2025-04-30T19:12:16.476907145Z TRACE quiche_server] Collecting garbage
[2025-04-30T19:12:17.978717631Z TRACE quiche_server] got 68 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:17.978749150Z TRACE quiche_server] got packet Short dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f key_phase=false
[2025-04-30T19:12:17.978767364Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Short dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f key_phase=false len=47 pn=4794153 
[2025-04-30T19:12:17.978800215Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm ACK delay=12 blocks=[6..6] ecn_counts=None
[2025-04-30T19:12:17.978812298Z TRACE quiche::recovery::congestion::recovery] dc5e8da95c4fa4868160ec60bd978f35af064f6f packet newly acked 6
[2025-04-30T19:12:17.978823028Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm PADDING len=25
[2025-04-30T19:12:17.978855688Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 68 bytes
[2025-04-30T19:12:17.978866429Z TRACE quiche_server] got 49 bytes from [::ffff:193.167.0.100]:35610 to [::]:443
[2025-04-30T19:12:17.978870957Z TRACE quiche_server] got packet Short dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f key_phase=false
[2025-04-30T19:12:17.978877209Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx pkt Short dcid=dc5e8da95c4fa4868160ec60bd978f35af064f6f key_phase=false len=28 pn=4794154 
[2025-04-30T19:12:17.978884092Z TRACE quiche] dc5e8da95c4fa4868160ec60bd978f35af064f6f rx frm APPLICATION_CLOSE err=0 reason=[4e, 6f, 20, 45, 72, 72, 6f, 72]
[2025-04-30T19:12:17.978892918Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f processed 49 bytes
[2025-04-30T19:12:17.978897998Z TRACE quiche_server] recv() would block
[2025-04-30T19:12:17.978901284Z TRACE quiche_server] dc5e8da95c4fa4868160ec60bd978f35af064f6f done writing
[2025-04-30T19:12:17.978904480Z TRACE quiche_server] Collecting garbage