[2025-04-30T12:40:28.774142349Z INFO quiche_server] listening on [::]:443 [2025-04-30T12:40:28.774163558Z TRACE mio::poll] registering event source with poller: token=Token(0), interests=READABLE [2025-04-30T12:40:28.774170010Z TRACE quiche_server] GSO detected: false [2025-04-30T12:40:29.626334206Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38825 to [::]:443 [2025-04-30T12:40:29.626353712Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T12:40:29.626360625Z WARN quiche_server] Doing version negotiation [2025-04-30T12:40:29.626440664Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38825 to [::]:443 [2025-04-30T12:40:29.626447076Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T12:40:29.626451074Z WARN quiche_server] Doing version negotiation [2025-04-30T12:40:29.626472774Z TRACE quiche_server] got 1207 bytes from [::ffff:193.167.100.2]:38825 to [::]:443 [2025-04-30T12:40:29.626476832Z TRACE quiche_server] got packet Initial version=57414954 dcid= scid= token= [2025-04-30T12:40:29.626481360Z WARN quiche_server] Doing version negotiation [2025-04-30T12:40:29.626510214Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.813639212Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.813660192Z TRACE quiche_server] got packet Initial version=1 dcid=5bd6f3369c2c4b8d scid=14361c7c8025bf86 token= [2025-04-30T12:40:29.813669038Z WARN quiche_server] Doing stateless retry [2025-04-30T12:40:29.813709263Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.845305212Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.845315391Z TRACE quiche_server] got packet Initial version=1 dcid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 scid=14361c7c8025bf86 token=71756963686500000000000000000000ffffc1a700645bd6f3369c2c4b8d [2025-04-30T12:40:29.845321713Z DEBUG quiche_server] New connection: dcid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 scid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 [2025-04-30T12:40:29.845487131Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 rx pkt Initial version=1 dcid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 scid=14361c7c8025bf86 token=71756963686500000000000000000000ffffc1a700645bd6f3369c2c4b8d len=1132 pn=1 [2025-04-30T12:40:29.845499966Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 rx frm CRYPTO off=0 len=305 [2025-04-30T12:40:29.845525874Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("h3") [2025-04-30T12:40:29.845529751Z TRACE quiche::tls] checking peer ALPN Ok("hq-interop") against Ok("hq-interop") [2025-04-30T12:40:29.845623045Z TRACE quiche::tls] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 write message lvl=Initial len=123 [2025-04-30T12:40:29.845646930Z TRACE quiche::tls] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 set write secret lvl=Handshake [2025-04-30T12:40:29.845701772Z TRACE quiche::tls] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 write message lvl=Handshake len=720 [2025-04-30T12:40:29.845704577Z TRACE quiche::tls] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 set write secret lvl=OneRTT [2025-04-30T12:40:29.845710849Z TRACE quiche::tls] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 set read secret lvl=Handshake [2025-04-30T12:40:29.845727660Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 rx frm PADDING len=803 [2025-04-30T12:40:29.845744201Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 processed 1200 bytes [2025-04-30T12:40:29.845761133Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.845772023Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Initial version=1 dcid=14361c7c8025bf86 scid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 len=132 pn=0 [2025-04-30T12:40:29.845775359Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm ACK delay=37 blocks=[1..1] ecn_counts=None [2025-04-30T12:40:29.845779657Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm CRYPTO off=0 len=123 [2025-04-30T12:40:29.845788484Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=998.974292ms 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: 417, tv_nsec: 644578457 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 644578457 }, 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: 417, tv_nsec: 644578457 }, next_time: Instant { tv_sec: 417, tv_nsec: 644578457 }, 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-30T12:40:29.845814242Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Handshake version=1 dcid=14361c7c8025bf86 scid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 len=724 pn=1 [2025-04-30T12:40:29.845817047Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm CRYPTO off=0 len=720 [2025-04-30T12:40:29.845822367Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=998.94083ms latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=965 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 644578457 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 644578457 }, 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: 417, tv_nsec: 644578457 }, next_time: Instant { tv_sec: 417, tv_nsec: 644578457 }, 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-30T12:40:29.845860298Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 1200 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 417, tv_nsec: 644578457 } }) [2025-04-30T12:40:29.845866059Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:29.845870146Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.845875607Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:29.845879634Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:29.879250465Z TRACE quiche_server] got 96 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.879263189Z TRACE quiche_server] got packet Handshake version=1 dcid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 scid=14361c7c8025bf86 [2025-04-30T12:40:29.879272927Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 rx pkt Handshake version=1 dcid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 scid=14361c7c8025bf86 len=59 pn=0 [2025-04-30T12:40:29.879278878Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 rx frm CRYPTO off=0 len=36 [2025-04-30T12:40:29.879288526Z TRACE quiche::tls] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 set read secret lvl=OneRTT [2025-04-30T12:40:29.879315196Z TRACE quiche::tls] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 write message lvl=OneRTT len=396 [2025-04-30T12:40:29.879328340Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 dropped epoch 1 state [2025-04-30T12:40:29.879332077Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 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(14361c7c8025bf86), retry_source_connection_id: None, max_datagram_frame_size: None, unknown_params: None } [2025-04-30T12:40:29.879351544Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 dropped epoch 0 state [2025-04-30T12:40:29.879354419Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 processed 96 bytes [2025-04-30T12:40:29.879359789Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.879364818Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=12000 out_len=1200 left=1174 [2025-04-30T12:40:29.879372393Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=681 pn=2 [2025-04-30T12:40:29.879375218Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:29.879382481Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:29.879387821Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:29.879392851Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:29.879398050Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:29.879403090Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:29.879408049Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:29.879412938Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm HANDSHAKE_DONE [2025-04-30T12:40:29.879415262Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm CRYPTO off=0 len=396 [2025-04-30T12:40:29.879425351Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=1.023936271s 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: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 417, tv_nsec: 678176992 }, next_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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-30T12:40:29.879467560Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 707 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 417, tv_nsec: 678176992 } }) [2025-04-30T12:40:29.879472920Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:29.879477509Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.879482218Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:29.879486055Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:29.880178437Z TRACE quiche_server] got 1200 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.880201269Z TRACE quiche_server] got packet Initial version=1 dcid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 scid=14361c7c8025bf86 token=71756963686500000000000000000000ffffc1a700645bd6f3369c2c4b8d [2025-04-30T12:40:29.880212140Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 dropped invalid packet [2025-04-30T12:40:29.880215005Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 processed 1200 bytes [2025-04-30T12:40:29.880219093Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.880222048Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:29.880224332Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:29.880300024Z TRACE quiche_server] got 63 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.880307518Z TRACE quiche_server] got packet Handshake version=1 dcid=8a11135524f69c5dfc83d5b72cc7797373e9ed07 scid=14361c7c8025bf86 [2025-04-30T12:40:29.880316214Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 dropped invalid packet [2025-04-30T12:40:29.880320181Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 processed 63 bytes [2025-04-30T12:40:29.880327214Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.880332394Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:29.880337443Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:29.880340479Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.880343354Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:29.880345649Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:29.910822630Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.910838810Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:29.910848879Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:29.910856744Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.910860591Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:29.910864728Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:29.910876470Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.910880638Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:29.910883604Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:29.935540885Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:29.935554500Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:29.935560732Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:29.935565170Z TRACE quiche_server] recv() would block [2025-04-30T12:40:29.935569268Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:29.935572223Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:30.035504240Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:30.035520580Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:30.035530378Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:30.035538654Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:30.035542491Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:30.035546789Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:30.035550606Z TRACE quiche_server] recv() would block [2025-04-30T12:40:30.035554583Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:30.035557519Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:30.283157236Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:30.283173275Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:30.283182904Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:30.283210716Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:30.283214793Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:30.283218981Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:30.283222758Z TRACE quiche_server] recv() would block [2025-04-30T12:40:30.283226906Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:30.283229931Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:30.777935788Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:30.777950656Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:30.777957298Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:30.777961796Z TRACE quiche_server] recv() would block [2025-04-30T12:40:30.777965954Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:30.777968990Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:30.778011759Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:30.778016519Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:30.778020876Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:30.778024453Z TRACE quiche_server] recv() would block [2025-04-30T12:40:30.778027539Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:30.778030264Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:30.904204479Z TRACE quiche_server] timed out [2025-04-30T12:40:30.904217924Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 loss detection timeout expired [2025-04-30T12:40:30.904224957Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=1.023136023s 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: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 417, tv_nsec: 678176992 }, next_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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-30T12:40:30.904256987Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1200 left=1174 [2025-04-30T12:40:30.904266846Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=681 pn=3 [2025-04-30T12:40:30.904270402Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:30.904280070Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:30.904286853Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:30.904293315Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:30.904299527Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:30.904305648Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:30.904311669Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:30.904317971Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm HANDSHAKE_DONE [2025-04-30T12:40:30.904320876Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm CRYPTO off=0 len=396 [2025-04-30T12:40:30.904338309Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=2.047911204s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1414 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 418, tv_nsec: 703064963 }, next_time: Instant { tv_sec: 418, tv_nsec: 703064963 }, 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-30T12:40:30.904393592Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 707 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 418, tv_nsec: 703064963 } }) [2025-04-30T12:40:30.904400716Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:30.904406075Z TRACE quiche_server] recv() would block [2025-04-30T12:40:30.904409492Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:30.904412247Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:30.961184526Z TRACE quiche_server] got 47 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:30.961221314Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:30.961228117Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:30.961232716Z TRACE quiche_server] recv() would block [2025-04-30T12:40:30.961236843Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:30.961239839Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:31.766075480Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:31.766088063Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:31.766093503Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:31.766098242Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:31.766101268Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:31.766104704Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:31.766107730Z TRACE quiche_server] recv() would block [2025-04-30T12:40:31.766111196Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:31.766113561Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:32.954347920Z TRACE quiche_server] timed out [2025-04-30T12:40:32.954363299Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 loss detection timeout expired [2025-04-30T12:40:32.954373618Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=2.045875164s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=1414 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 418, tv_nsec: 703064963 }, next_time: Instant { tv_sec: 418, tv_nsec: 703064963 }, 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-30T12:40:32.954408603Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1200 left=1174 [2025-04-30T12:40:32.954419704Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=961 pn=4 [2025-04-30T12:40:32.954423491Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:32.954432478Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:32.954439270Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:32.954445833Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:32.954452195Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:32.954458306Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:32.954464357Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:32.954470539Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:32.954476801Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:32.954482802Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:32.954488843Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:32.954494804Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:32.954504853Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:32.954510964Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:32.954517045Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm HANDSHAKE_DONE [2025-04-30T12:40:32.954519921Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm CRYPTO off=0 len=396 [2025-04-30T12:40:32.954563632Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=4.095837026s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=2401 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, end_of_app_limited: 3, 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: 420, tv_nsec: 753216199 }, next_time: Instant { tv_sec: 420, tv_nsec: 753216199 }, 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-30T12:40:32.954622753Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 987 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 420, tv_nsec: 753216199 } }) [2025-04-30T12:40:32.954629776Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:32.954635086Z TRACE quiche_server] recv() would block [2025-04-30T12:40:32.954640426Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1200 left=1174 [2025-04-30T12:40:32.954644864Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=4 pn=5 [2025-04-30T12:40:32.954648080Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm PING mtu_probe=None [2025-04-30T12:40:32.954651306Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm PADDING len=3 [2025-04-30T12:40:32.954658028Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=4.095979261s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=2431 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 420, tv_nsec: 753452660 }, next_time: Instant { tv_sec: 420, tv_nsec: 753452660 }, 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-30T12:40:32.954696671Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 30 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 420, tv_nsec: 753452660 } }) [2025-04-30T12:40:32.954702331Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:32.954706739Z TRACE quiche_server] recv() would block [2025-04-30T12:40:32.954710005Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:32.954712731Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:32.986219052Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:32.986233308Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:32.986239770Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:32.986244309Z TRACE quiche_server] recv() would block [2025-04-30T12:40:32.986248516Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:32.986251522Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:33.742105388Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:33.742121318Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:33.742128081Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:33.742133841Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:33.742137488Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:33.742141646Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:33.742145553Z TRACE quiche_server] recv() would block [2025-04-30T12:40:33.742149911Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:33.742152917Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:37.051568266Z TRACE quiche_server] timed out [2025-04-30T12:40:37.051592411Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 loss detection timeout expired [2025-04-30T12:40:37.051605756Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=4.095030712s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=2431 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 420, tv_nsec: 753452660 }, next_time: Instant { tv_sec: 420, tv_nsec: 753452660 }, 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-30T12:40:37.051636083Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1200 left=1174 [2025-04-30T12:40:37.051649037Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=961 pn=6 [2025-04-30T12:40:37.051653586Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:37.051672431Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:37.051678031Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:37.051683111Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:37.051688250Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:37.051693090Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:37.051697798Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:37.051702667Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:37.051707617Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:37.051712386Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:37.051717174Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:37.051721973Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:37.051726712Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:37.051731431Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:37.051739236Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm HANDSHAKE_DONE [2025-04-30T12:40:37.051741590Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm CRYPTO off=0 len=396 [2025-04-30T12:40:37.051763671Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=8.191862163s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=3418 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 424, tv_nsec: 850441164 }, next_time: Instant { tv_sec: 424, tv_nsec: 850441164 }, 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-30T12:40:37.051840224Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 987 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 424, tv_nsec: 850441164 } }) [2025-04-30T12:40:37.051850523Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:37.051857566Z TRACE quiche_server] recv() would block [2025-04-30T12:40:37.051863698Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1200 left=1174 [2025-04-30T12:40:37.051869509Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=4 pn=7 [2025-04-30T12:40:37.051873667Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm PING mtu_probe=None [2025-04-30T12:40:37.051877604Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm PADDING len=3 [2025-04-30T12:40:37.051886330Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=8.191973791s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=3448 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 424, tv_nsec: 850675511 }, next_time: Instant { tv_sec: 424, tv_nsec: 850675511 }, 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-30T12:40:37.051920904Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 30 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 424, tv_nsec: 850675511 } }) [2025-04-30T12:40:37.051928709Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:37.051934059Z TRACE quiche_server] recv() would block [2025-04-30T12:40:37.051944519Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:37.051947314Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:37.083540166Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:37.083549684Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:37.083555515Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:37.083558971Z TRACE quiche_server] recv() would block [2025-04-30T12:40:37.083562097Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:37.083564562Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:37.693013698Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:37.693024378Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:37.693029728Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:37.693034266Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:37.693037312Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:37.693040658Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:37.693043654Z TRACE quiche_server] recv() would block [2025-04-30T12:40:37.693047100Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:37.693049505Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:45.244106799Z TRACE quiche_server] timed out [2025-04-30T12:40:45.244135473Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 loss detection timeout expired [2025-04-30T12:40:45.244146944Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=8.191712465s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=3448 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, 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: 12000, used: 0, rate: 0, last_update: Instant { tv_sec: 424, tv_nsec: 850675511 }, next_time: Instant { tv_sec: 424, tv_nsec: 850675511 }, 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-30T12:40:45.244177892Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=false cwnd_avail=18446744073709551615 out_len=1200 left=1174 [2025-04-30T12:40:45.244204452Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=961 pn=8 [2025-04-30T12:40:45.244209661Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:45.244219800Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:45.244226493Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:45.244242442Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:45.244247482Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:45.244252361Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:45.244257110Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:45.244262019Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=1 retire_prior_to=0 conn_id=[ae, 60, 5e, 85, 3d, de, 23, 1f, 01, 90, 5e, d3, ce, 9d, 8a, 8b, 38, 14, 32, bd] reset_token=[0b, bf, a6, 0a, 7f, b8, 50, 76, 08, 37, 13, 0f, 4b, b4, 50, 68] [2025-04-30T12:40:45.244266948Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=2 retire_prior_to=0 conn_id=[f5, d0, e1, 13, 36, 1d, 63, 5b, b1, ec, 2e, b7, 4c, 06, b9, 56, 84, e4, 67, 90] reset_token=[e9, 24, ee, 79, e4, 9a, 4e, ef, 07, c5, f0, 40, 75, 77, 64, 26] [2025-04-30T12:40:45.244271707Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=3 retire_prior_to=0 conn_id=[24, 3c, c3, 7c, 2b, 4b, c2, 4a, f9, 51, 11, c5, 0f, 72, c4, 83, 98, 50, 6a, ff] reset_token=[cf, 11, 15, 0e, 00, 83, e7, 50, 7f, 91, 59, 39, f7, 9c, fb, 26] [2025-04-30T12:40:45.244276466Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=4 retire_prior_to=0 conn_id=[90, 6c, 1f, 49, 97, cd, be, 25, a5, 64, 92, d7, 8b, e9, ab, bb, 34, 0d, d4, 2a] reset_token=[29, 2d, 14, ce, cc, d6, a7, 73, 45, 5a, 21, f8, cd, ed, 05, 12] [2025-04-30T12:40:45.244281195Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=5 retire_prior_to=0 conn_id=[87, 68, 93, 2b, 09, 1c, 8b, 35, ab, bc, 7a, 5a, 53, d3, 14, 59, a1, a5, b6, 6d] reset_token=[78, 9e, 4f, 00, f4, 3c, d3, 50, 4e, 22, 67, a9, 98, df, 53, c7] [2025-04-30T12:40:45.244285953Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=6 retire_prior_to=0 conn_id=[ba, 4c, 38, 1c, 72, 63, f1, ee, b9, f0, ca, 0f, 23, e8, be, f4, a3, 1b, 3d, 84] reset_token=[c9, b1, ec, f6, 45, c7, db, 89, 28, c5, 48, db, 13, 5d, 69, a4] [2025-04-30T12:40:45.244290672Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm NEW_CONNECTION_ID seq_num=7 retire_prior_to=0 conn_id=[19, a0, 07, 15, 49, 39, 62, 6f, ae, 6e, 2a, da, 5b, 20, de, ee, 00, 99, 39, 0a] reset_token=[fb, b7, 33, d8, 8e, 68, b4, 51, fe, 3b, 9f, fd, 7c, d3, 36, ab] [2025-04-30T12:40:45.244295441Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm HANDSHAKE_DONE [2025-04-30T12:40:45.244297735Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm CRYPTO off=0 len=396 [2025-04-30T12:40:45.244332791Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=16.38383412s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=4435 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, end_of_app_limited: 7, last_sent_packet: 8, 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: 433, tv_nsec: 42982311 }, next_time: Instant { tv_sec: 433, tv_nsec: 42982311 }, 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-30T12:40:45.244410596Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 987 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 433, tv_nsec: 42982311 } }) [2025-04-30T12:40:45.244418180Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:45.244424081Z TRACE quiche_server] recv() would block [2025-04-30T12:40:45.244428950Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 pmtud probe status false hs_con=true hs_sent=true cwnd_avail=18446744073709551615 out_len=1200 left=1174 [2025-04-30T12:40:45.244432938Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx pkt Short dcid=14361c7c8025bf86 key_phase=false len=4 pn=9 [2025-04-30T12:40:45.244435603Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm PING mtu_probe=None [2025-04-30T12:40:45.244438117Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 tx frm PADDING len=3 [2025-04-30T12:40:45.244443888Z TRACE quiche::recovery::congestion::recovery] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 timer=16.383982057s latest_rtt=0ns srtt=333ms min_rtt=333ms rttvar=166.5ms cwnd=12000 ssthresh=18446744073709551615 bytes_in_flight=4465 app_limited=true congestion_recovery_start_time=None Rate { delivered: 0, delivered_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, first_sent_time: Instant { tv_sec: 417, tv_nsec: 678176992 }, end_of_app_limited: 8, last_sent_packet: 9, 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: 433, tv_nsec: 43241225 }, next_time: Instant { tv_sec: 433, tv_nsec: 43241225 }, 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-30T12:40:45.244480847Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 written 30 bytes with Some(SendInfo { from: [::]:443, to: [::ffff:193.167.0.100]:41141, at: Instant { tv_sec: 433, tv_nsec: 43241225 } }) [2025-04-30T12:40:45.244487961Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:45.244492559Z TRACE quiche_server] recv() would block [2025-04-30T12:40:45.244496005Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:45.244498981Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:45.276025741Z TRACE quiche_server] got 46 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:45.276036140Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:45.276042051Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:45.276045437Z TRACE quiche_server] recv() would block [2025-04-30T12:40:45.276048794Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:45.276057350Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:45.594719308Z TRACE quiche_server] got 62 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:45.594732763Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:45.594740257Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:45.594745798Z TRACE quiche_server] got 69 bytes from [::ffff:193.167.0.100]:41141 to [::]:443 [2025-04-30T12:40:45.594748813Z TRACE quiche_server] got packet Short dcid=5bd6f3369c2c4b8dfc83d5b72cc7797373e9ed07 key_phase=false [2025-04-30T12:40:45.594752250Z ERROR quiche_server] Packet is not Initial [2025-04-30T12:40:45.594755225Z TRACE quiche_server] recv() would block [2025-04-30T12:40:45.594758411Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:45.594760695Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:59.893207321Z TRACE quiche_server] timed out [2025-04-30T12:40:59.893229783Z TRACE quiche] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 idle timeout expired [2025-04-30T12:40:59.893260160Z TRACE quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 done writing [2025-04-30T12:40:59.893264688Z TRACE quiche_server] Collecting garbage [2025-04-30T12:40:59.893269307Z INFO quiche_server] 8a11135524f69c5dfc83d5b72cc7797373e9ed07 connection collected recv=2 sent=10 lost=0 retrans=7 sent_bytes=5430 recv_bytes=1296 lost_bytes=0 [local_addr=[::]:443 peer_addr=[::ffff:193.167.0.100]:41141 validation_state=Validated active=true recv=2 sent=10 lost=0 retrans=7 rtt=333ms min_rtt=Some(333ms) rttvar=166.5ms cwnd=12000 sent_bytes=5430 recv_bytes=1296 lost_bytes=0 stream_retrans_bytes=2772 pmtu=1200 delivery_rate=0]