2025-12-13 12:51:02,978 Generated random file: tan-exhilarated-actress of size: 1024 2025-12-13 12:51:02,978 Requests: https://server4:443/tan-exhilarated-actress 2025-12-13 12:51:03,035 2025-12-13 12:51:03,036 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_xgvmzib6/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_ikwxuqtr/ DOWNLOADS=/tmp/download_oxymuhrk/ SERVER_LOGS=/tmp/logs_server_9dh570es CLIENT_LOGS=/tmp/logs_client_eb1ymlsd SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/tan-exhilarated-actress" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 12:51:27,637 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Setting up routes... server | Actual changes: server | tx-checksum-ip-generic: off server | tx-tcp-segmentation: off [not requested] server | tx-tcp-ecn-segmentation: off [not requested] server | tx-tcp-mangleid-segmentation: off [not requested] server | tx-tcp6-segmentation: off [not requested] server | tx-udp-segmentation: off [not requested] server | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 client | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin client | + '[' -n handshake ']' client | + '[' -n /logs/qlog/ ']' client | + case "$ROLE" in client | + /wait-for-it.sh sim:57832 -s -t 30 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 server | >>> Starting nginx server... server | nginx version: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f) server | built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) server | built with OpenSSL 1.1.1 (compatible; BoringSSL) (running with BoringSSL) server | TLS SNI support enabled server | configure arguments: --prefix=/etc/nginx --build=6ed1188411882086e3518eda779ab782d8ab4d3f --sbin-path=/usr/sbin/nginx --modules-path=/usr/lib/nginx/modules --conf-path=/etc/nginx/nginx.conf --error-log-path=/var/log/nginx/error.log --http-log-path=/var/log/nginx/access.log --pid-path=/run/nginx.pid --lock-path=/run/nginx.lock --http-client-body-temp-path=/var/cache/nginx/client_temp --http-proxy-temp-path=/var/cache/nginx/proxy_temp --http-fastcgi-temp-path=/var/cache/nginx/fastcgi_temp --http-uwsgi-temp-path=/var/cache/nginx/uwsgi_temp --http-scgi-temp-path=/var/cache/nginx/scgi_temp --user=nginx --group=nginx --with-compat --with-debug --with-http_ssl_module --with-http_v2_module --with-http_v3_module --with-cc=c++ --with-cc-opt='-I/boringssl/include -O0 -fno-common -fno-omit-frame-pointer -x c' --with-ld-opt=-L/boringssl/build server | >>> Parameters: server | >>> Test case: handshake client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | 2025/12/13 12:51:03 [debug] 21#0: bind() 0.0.0.0:443 #5 server | 2025/12/13 12:51:03 [debug] 21#0: bind() 0.0.0.0:443 #6 server | 2025/12/13 12:51:03 [debug] 21#0: bind() [::]:443 #7 server | 2025/12/13 12:51:03 [debug] 21#0: bind() [::]:443 #8 server | 2025/12/13 12:51:03 [debug] 21#0: add cleanup: 0000564FB0A401C0 server | 2025/12/13 12:51:03 [notice] 21#0: using the "epoll" event method server | 2025/12/13 12:51:03 [debug] 21#0: counter: 00007F1A7B2CE080, 1 server | 2025/12/13 12:51:03 [notice] 21#0: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f) server | 2025/12/13 12:51:03 [notice] 21#0: built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) server | 2025/12/13 12:51:03 [notice] 21#0: OS: Linux 6.11.0-1018-azure server | 2025/12/13 12:51:03 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 65536:65536 server | 2025/12/13 12:51:03 [debug] 21#0: write: 9, 00007FFF21CCFCE0, 3, 0 server | 2025/12/13 12:51:03 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf" server | 2025/12/13 12:51:03 [notice] 21#0: start worker processes server | 2025/12/13 12:51:03 [debug] 21#0: channel 3:9 server | 2025/12/13 12:51:03 [notice] 21#0: start worker process 22 server | 2025/12/13 12:51:03 [debug] 21#0: sigsuspend server | 2025/12/13 12:51:03 [debug] 22#0: add cleanup: 0000564FB0A40248 server | 2025/12/13 12:51:03 [debug] 22#0: malloc: 0000564FB0A42A30:16 server | 2025/12/13 12:51:03 [debug] 22#0: add cleanup: 0000564FB0A40260 server | 2025/12/13 12:51:03 [debug] 22#0: malloc: 0000564FB0A41A20:16 server | 2025/12/13 12:51:03 [debug] 22#0: notify eventfd: 11 server | 2025/12/13 12:51:03 [debug] 22#0: testing the EPOLLRDHUP flag: success server | 2025/12/13 12:51:03 [debug] 22#0: malloc: 0000564FB0A28EF0:6144 server | 2025/12/13 12:51:03 [debug] 22#0: malloc: 00007F1A7ABF2010:253952 server | 2025/12/13 12:51:03 [debug] 22#0: malloc: 0000564FB0A46390:98304 server | 2025/12/13 12:51:03 [debug] 22#0: malloc: 0000564FB0A5E3A0:98304 server | 2025/12/13 12:51:03 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001 server | 2025/12/13 12:51:03 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001 server | 2025/12/13 12:51:03 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001 server | 2025/12/13 12:51:03 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001 server | 2025/12/13 12:51:03 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001 server | 2025/12/13 12:51:03 [debug] 22#0: setproctitle: "nginx: worker process" server | 2025/12/13 12:51:03 [debug] 22#0: worker cycle server | 2025/12/13 12:51:03 [debug] 22#0: epoll timer: -1 server | 2025/12/13 12:51:03 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:03 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:03 [debug] 22#0: posix_memalign: 0000564FB0A40AD0:512 @16 server | 2025/12/13 12:51:03 [debug] 22#0: malloc: 0000564FB0A42A50:1207 server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:36899 fd:6 n:1207 server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic run server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954 server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic packet rx dcid len:0 server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic packet rx scid len:0 server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic address validation token len:0 server | 2025/12/13 12:51:03 [debug] 22#0: *1 sending version negotiation packet server | 2025/12/13 12:51:03 [debug] 22#0: *1 sendmsg: 11 of 11 server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/12/13 12:51:03 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection sim | server:443 is available after 501.512582ms server | 2025/12/13 12:51:03 [debug] 22#0: *1 reusable connection: 0 server | 2025/12/13 12:51:03 [debug] 22#0: *1 free: 0000564FB0A42A50 server | 2025/12/13 12:51:03 [debug] 22#0: *1 free: 0000564FB0A40AD0, unused: 16 server | 2025/12/13 12:51:03 [debug] 22#0: timer delta: 330 server | 2025/12/13 12:51:03 [debug] 22#0: worker cycle server | 2025/12/13 12:51:03 [debug] 22#0: epoll timer: -1 sim | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25 sim | Capturing on 'eth0' sim | Capturing on 'eth1' sim | File: /logs/trace_node_left.pcap sim | File: /logs/trace_node_right.pcap client | wait-for-it.sh: sim:57832 is available after 1 seconds client | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads) client | + '[' https://server4:443/tan-exhilarated-actress ']' client | + mapfile -d ' ' -t URLS client | + OPTIONS+=("${URLS[@]}") client | + RUST_LOG=debug client | + RUST_BACKTRACE=1 client | + neqo-client --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/tan-exhilarated-actress client | ' client | ++ tee -i -a /logs/client.log client | 0.000 DEBUG Logging initialized client | 0.001 DEBUG Default socket send buffer size is Ok(212992) client | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing client | 0.001 INFO hq-interop Client connecting: 0.0.0.0:59620 -> 193.167.100.100:443 client | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=1d5b6566cfd8d9b6 client | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=16 client | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 0.001 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500 client | 0.001 DEBUG [unv-path 0.0.0.0:59620->193.167.100.100:443] Make permanent client | 0.001 DEBUG [unv-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443] set as primary path client | 0.001 DEBUG [pri-unv-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443] Path validated Instant { tv_sec: 229, tv_nsec: 364062656 } client | 0.001 DEBUG [Client 1d5b6566cfd8d9b6] client_start client | 0.002 DEBUG Writing transport parameters, msg=1 client | 0.002 DEBUG [Agent 0x55ac539d2c60] state -> InProgress client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] State change from Init -> WaitInitial client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=0, len=1213 client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] pn=16 type=Initial pri-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.002 DEBUG packet_sent this=0x55ac539e5d40, pn=16, ps=1252 client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.002 DEBUG CRYPTO for in offset=1213, len=325 client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] pn=17 type=Initial pri-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 365 client | TX -> Crypto { offset: 1213, len: 325 } client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] pad Initial from 365 to PLPMTU 1252 client | 0.002 DEBUG packet_sent this=0x55ac539e5d40, pn=17, ps=1252 client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.356151ms client | 0.002 DEBUG Setting timeout of 3.356151ms client | 0.002 DEBUG StateChange(WaitInitial) client | 0.002 WARN Cannot create stream ConnectionState client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } client | 0.002 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.322879ms client | 0.002 DEBUG Setting timeout of 3.322879ms client | 0.006 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.006 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.006 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.006 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 294.767044ms client | 0.006 DEBUG Setting timeout of 294.767044ms client | 0.302 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.302 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 }) client | 0.302 INFO Lost crypto frame space=in offset=1213 length=325 client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.302 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.302 DEBUG CRYPTO for in offset=0, len=1213 client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] pn=18 type=Initial pri-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.302 DEBUG packet_sent this=0x55ac539e5d40, pn=18, ps=1252 client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.302 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.302 DEBUG CRYPTO for in offset=1213, len=325 client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] pn=19 type=Initial pri-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 365 client | TX -> Crypto { offset: 1213, len: 325 } client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] pad Initial from 365 to PLPMTU 1252 client | 0.302 DEBUG packet_sent this=0x55ac539e5d40, pn=19, ps=1252 client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.302 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.302 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.302 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.302 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.302 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 599.847145ms client | 0.302 DEBUG Setting timeout of 599.847145ms server | 2025/12/13 12:51:05 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:05 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:05 [debug] 22#0: posix_memalign: 0000564FB0A40AD0:512 @16 server | 2025/12/13 12:51:05 [debug] 22#0: malloc: 0000564FB0A42A50:1252 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic recvmsg: 193.167.0.100:59620 fd:6 n:1252 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic run server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx long flags:c1 version:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx init len:1234 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx dcid len:8 1d5b6566cfd8d9b6 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic address validation token len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 malloc: 0000564FB0A2A700:2512 server | 2025/12/13 12:51:05 [debug] 22#0: *2 malloc: 0000564FB0A2B0E0:1528 server | 2025/12/13 12:51:05 [debug] 22#0: *2 posix_memalign: 0000564FB0A42F40:512 @16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_set_initial_secret server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic socket seq:0 listening at sid:0000000000000002c754486edbbf172fff0707e8 nsock:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic cid seq:0 received id:0::00000000000000000000000000000000 server | 2025/12/13 12:51:05 [debug] 22#0: *2 posix_memalign: 0000564FB0A2B9D0:512 @16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic path seq:0 created addr:193.167.0.100:59620 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic path seq:0 set active tx:0 rx:0 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 posix_memalign: 0000564FB0A2BBE0:512 @16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic socket seq:-1 listening at sid:1d5b6566cfd8d9b6 nsock:2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 reusable connection: 1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic connection created server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx clearflags:c0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx number:16 len:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet len:1252 via sock seq:0 path seq:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic path seq:0 status tx:0 rx:1252 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic stateless reset token d25bd5b3ee71e54b7159c1c3e9ffca34 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame rx init:16 CRYPTO len:1213 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 posix_memalign: 0000564FB0A1EDD0:512 @16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 malloc: 0000564FB0A1EFE0:4096 server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL_do_handshake: -1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL_get_error: 2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:16 largest -1 fr:0 nranges:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:16 perr:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer add: 6: 75000:305121 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer add: 6: 60000:290121 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: read:75000 close:60000 server | 2025/12/13 12:51:05 [debug] 22#0: timer delta: 1434 server | 2025/12/13 12:51:05 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic output init packet max:1200 min:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx init:0 ACK n:0 delay:0 16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet tx init bytes:5 need_ack:0 number:0 encoded nl:1 trunc:0x0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 sendmsg: 51 of 51 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion send if:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion idle:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: read:75000 close:60000 server | 2025/12/13 12:51:05 [debug] 22#0: worker cycle server | 2025/12/13 12:51:05 [debug] 22#0: epoll timer: 60000 server | 2025/12/13 12:51:05 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:05 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx long flags:cd version:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx init len:347 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx dcid len:8 1d5b6566cfd8d9b6 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic address validation token len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx clearflags:c0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx number:17 len:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic path seq:0 status tx:51 rx:2504 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame rx init:17 CRYPTO len:325 off:1213 server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL server name: "server4" server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL ALPN supported by client: hq-interop server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL ALPN supported by client: ZZ server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL ALPN selected: hq-interop server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_add_handshake_data server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic SSL_get_peer_quic_transport_params(): params_len:78 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic unknown transport param id:0x11, skipped server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic unknown transport param id:0x2ab2, skipped server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic reserved transport param id:0xff02de1a, skipped server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic unknown transport param id:0x20, skipped server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic transport parameters parsed ok server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp disable active migration: 0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp idle_timeout:30000 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp max_udp_payload_size:65527 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp max_data:2097152 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp max_stream_data_bidi_local:1048576 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp max_stream_data_bidi_remote:1048576 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp max_stream_data_uni:1048576 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp initial_max_streams_bidi:16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp initial_max_streams_uni:16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp ack_delay_exponent:3 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp max_ack_delay:20 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp active_connection_id_limit:8 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic tp initial source_connection_id len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 malloc: 0000564FB0A20D80:4096 server | 2025/12/13 12:51:05 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_add_handshake_data server | 2025/12/13 12:51:05 [debug] 22#0: *2 malloc: 0000564FB0A76410:4096 server | 2025/12/13 12:51:05 [debug] 22#0: *2 posix_memalign: 0000564FB0A77420:512 @16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:3 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_flush_flight() server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL_do_handshake: -1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 SSL_get_error: 2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:17 largest 16 fr:0 nranges:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:17 perr:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx short flags:0 server | 2025/12/13 12:51:05 [info] 22#0: *2 quic fixed bit is not set while parsing quic packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet done rc:-5 parse failed server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer del: 6: 305121 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer add: 6: 30000:260122 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: read:30000 close:59999 server | 2025/12/13 12:51:05 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:05 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic output init packet max:1200 min:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx init:1 ACK n:0 delay:0 17-16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx init:1 CRYPTO len:90 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic output hs packet max:1054 min:1054 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx hs:0 CRYPTO len:720 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet tx hs bytes:1008 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 sendmsg: 1200 of 1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion send if:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion idle:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer: 6, old: 260122, new: 260122 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic lost timer pto:997 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer add: 6: 997:231119 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: send:30000 pto:997 close:59999 server | 2025/12/13 12:51:05 [debug] 22#0: worker cycle server | 2025/12/13 12:51:05 [debug] 22#0: epoll timer: 997 client | 0.903 DEBUG [recovery::Loss] PTO timer fired for Initial client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 }) client | 0.903 INFO Lost crypto frame space=in offset=0 length=1213 client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 }) client | 0.903 INFO Lost crypto frame space=in offset=1213 length=325 client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false } client | 0.903 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.903 DEBUG CRYPTO for in offset=0, len=1213 client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] pn=20 type=Initial pri-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1252 client | TX -> Crypto { offset: 0, len: 1213 } client | 0.903 DEBUG packet_sent this=0x55ac539e5d40, pn=20, ps=1252 client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false } client | 0.903 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.903 DEBUG CRYPTO for in offset=1213, len=325 client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] pn=21 type=Initial pri-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 365 client | TX -> Crypto { offset: 1213, len: 325 } client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] pad Initial from 365 to PLPMTU 1252 client | 0.903 DEBUG packet_sent this=0x55ac539e5d40, pn=21, ps=1252 client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.903 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.903 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.903 DEBUG Building Initial dcid Some(CID [8]: 1d5b6566cfd8d9b6) scid Some(CID [0]: ) client | 0.903 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 0.903 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 1.199830545s client | 0.903 DEBUG Setting timeout of 1.199830545s server | 2025/12/13 12:51:05 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:05 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx long flags:c5 version:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx init len:1234 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx dcid len:8 1d5b6566cfd8d9b6 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic address validation token len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx clearflags:c0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx number:18 len:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic path seq:0 status tx:1251 rx:3756 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame rx init:18 CRYPTO len:1213 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic resend packet pnum:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic cubic time:2715 n:4 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion lost t:230416 win:2400 if:1054 server | 2025/12/13 12:51:05 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic resend packet pnum:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion lost rec t:230416 win:2400 if:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:18 largest 17 fr:1 nranges:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:18 perr:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer: 6, old: 260122, new: 260416 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: read:29706 pto:703 close:59705 server | 2025/12/13 12:51:05 [debug] 22#0: timer delta: 294 server | 2025/12/13 12:51:05 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic output init packet max:1200 min:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx init:2 ACK n:0 delay:0 18-16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx init:2 CRYPTO len:90 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:2 encoded nl:1 trunc:0x2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic output hs packet max:1054 min:1054 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx hs:1 CRYPTO len:720 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet tx hs bytes:1008 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 sendmsg: 1200 of 1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion send if:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion idle:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer: 6, old: 260122, new: 260416 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer del: 6: 231119 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic lost timer pto:997 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer add: 6: 997:231413 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: send:29706 pto:997 close:59705 server | 2025/12/13 12:51:05 [debug] 22#0: worker cycle server | 2025/12/13 12:51:05 [debug] 22#0: epoll timer: 997 server | 2025/12/13 12:51:05 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:05 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx long flags:c8 version:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx init len:347 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx dcid len:8 1d5b6566cfd8d9b6 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic address validation token len:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx clearflags:c0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx number:19 len:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic path seq:0 status tx:2451 rx:5008 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame rx init:19 CRYPTO len:325 off:1213 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic resend packet pnum:2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion lost rec t:230417 win:2400 if:1054 server | 2025/12/13 12:51:05 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic resend packet pnum:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion lost rec t:230417 win:2400 if:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:19 largest 18 fr:2 nranges:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:19 perr:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet rx short flags:0 server | 2025/12/13 12:51:05 [info] 22#0: *2 quic fixed bit is not set while parsing quic packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet done rc:-5 parse failed server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer: 6, old: 260122, new: 260417 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: read:29705 pto:996 close:59704 server | 2025/12/13 12:51:05 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:05 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic output init packet max:1200 min:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx init:3 ACK n:0 delay:0 19-16 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx init:3 CRYPTO len:90 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:3 encoded nl:1 trunc:0x3 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic output hs packet max:1054 min:1054 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic frame tx hs:2 CRYPTO len:720 off:0 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic packet tx hs bytes:1008 need_ack:1 number:2 encoded nl:1 trunc:0x2 server | 2025/12/13 12:51:05 [debug] 22#0: *2 sendmsg: 1200 of 1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion send if:1200 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic congestion idle:1 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer: 6, old: 260122, new: 260417 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer del: 6: 231413 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic lost timer pto:997 server | 2025/12/13 12:51:05 [debug] 22#0: *2 event timer add: 6: 997:231414 server | 2025/12/13 12:51:05 [debug] 22#0: *2 quic state: send:29705 pto:997 close:59704 server | 2025/12/13 12:51:05 [debug] 22#0: worker cycle server | 2025/12/13 12:51:05 [debug] 22#0: epoll timer: 997 client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] pn=0 type=Initial pri-path:1d5b6566cfd8d9b6 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 51 client | -> RX Ack { largest_acknowledged: 16, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] Rx ACK space=in, ranges=[16..=16] client | 1.510 DEBUG [recovery::Loss] ACK for Initial - largest_acked=16 client | 1.510 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] slow start += 1252 client | 1.510 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] on_packets_acked this=0x55ac539e5d40, limited=0, bytes_in_flight=6260, cwnd=13772, state=SlowStart, new_acked=1252 client | 1.510 DEBUG Acked crypto frame space=in offset=0 length=1213 client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] Changing to use Server CID=0000000000000002c754486edbbf172fff0707e8 client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] State change from WaitInitial -> WaitVersion client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG Building Initial dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.510 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.918066594s client | 1.510 DEBUG Setting timeout of 3.918066594s client | 1.510 WARN Unhandled event StateChange(WaitVersion) client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG Building Initial dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.510 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.510 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.918030606s client | 1.510 DEBUG Setting timeout of 3.918030606s client | 1.512 DEBUG [Client 1d5b6566cfd8d9b6] pn=1 type=Initial pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 146 client | -> RX Ack { largest_acknowledged: 17, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 90 } client | 1.512 DEBUG [Client 1d5b6566cfd8d9b6] Rx ACK space=in, ranges=[16..=17] client | 1.512 DEBUG [recovery::Loss] ACK for Initial - largest_acked=17 client | 1.512 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 5008/15024 ssthresh 18446744073709551615] slow start += 1252 client | 1.512 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 5008/15024 ssthresh 18446744073709551615] on_packets_acked this=0x55ac539e5d40, limited=0, bytes_in_flight=5008, cwnd=15024, state=SlowStart, new_acked=1252 client | 1.512 DEBUG Acked crypto frame space=in offset=1213 length=325 client | 1.512 DEBUG Write secret available for Handshake: SymKey [32]: 2c83903870ec5c6a142fe5a560c171baf1f34de7be59c9b58826836cc15c2a5a client | 1.512 DEBUG Read secret available for Handshake: SymKey [32]: 7e58b026814854a3359bae96a5fc3ff7f1bfa9fdc3014a9e2391a5c6bc1106ff client | 1.512 DEBUG [Agent 0x55ac539d2c60] state -> InProgress client | 1.512 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.512 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.512 DEBUG [Crypto] Handshake keys installed client | 1.512 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 230, tv_nsec: 875137385 } client | 1.512 DEBUG [Client 1d5b6566cfd8d9b6] State change from WaitVersion -> Handshaking client | 1.512 DEBUG [Client 1d5b6566cfd8d9b6] pn=0 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1054 client | -> RX Crypto { offset: 0, len: 720 } client | -> RX Padding { len: 284 } client | 1.513 DEBUG [Agent 0x55ac539d2c60] state -> AuthenticationPending client | 1.513 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 230, tv_nsec: 875137385 } client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Initial dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] pn=22 type=Initial pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 53 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 167, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 1.513 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] pn=0 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 105 client | TX -> Ack { largest_acknowledged: 0, ack_delay: 167, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] Drop packet number space in client | 1.513 DEBUG [recovery::Loss] Reset loss recovery state for Initial client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] pad Initial from 105 to PLPMTU 1252 client | 1.513 INFO [recovery::Loss] ignoring packet 22 from dropped space in client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.513 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.513 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773201238s client | 1.513 DEBUG Setting timeout of 3.773201238s client | 1.513 DEBUG StateChange(Handshaking) client | 1.513 WARN Cannot create stream ConnectionState client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] Authenticated Ok client | 1.513 DEBUG Write secret available for ApplicationData: SymKey [32]: 288a142fcce3a3190ef8dba0d43f4c68363374ecb2ac18d251942cf810f6528d client | 1.513 DEBUG Read secret available for ApplicationData: SymKey [32]: 9e800bfaf3f7d05f7b2b9c8ca6f5fe2ca578f6a549ad693e680a9bbfc746a9df client | 1.513 DEBUG [Agent 0x55ac539d2c60] SSL_AuthCertificateComplete: Ok(()) client | 1.513 DEBUG [Agent 0x55ac539d2c60] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] TLS connection complete client | 1.513 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.513 DEBUG [Crypto] Application write key installed client | 1.513 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 client | 1.513 DEBUG [Crypto] application read keys installed client | 1.513 DEBUG [Client 1d5b6566cfd8d9b6] State change from Handshaking -> Connected client | 1.513 INFO [Client 1d5b6566cfd8d9b6] Connection established client | 1.513 DEBUG stream BiDi creatable client | 1.514 INFO Created stream 0 for https://server4/tan-exhilarated-actress client | 1.514 INFO Saving https://server4/tan-exhilarated-actress to "/downloads/tan-exhilarated-actress" client | 1.514 DEBUG stream UniDi creatable client | 1.514 DEBUG StateChange(Connected) client | 1.514 DEBUG stream 0 writable client | 1.514 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.514 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.514 DEBUG CRYPTO for hs offset=0, len=36 client | 1.514 DEBUG [Client 1d5b6566cfd8d9b6] pn=1 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 85 client | TX -> Crypto { offset: 0, len: 36 } client | 1.514 DEBUG packet_sent this=0x55ac539e5d40, pn=1, ps=85 client | 1.514 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.514 DEBUG [Client 1d5b6566cfd8d9b6] pn=0 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 156 client | TX -> Stream { stream_id: 0, offset: 0, len: 30, fin: true } client | 1.514 DEBUG packet_sent this=0x55ac539e5d40, pn=0, ps=71 client | 1.514 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.514 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.514 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.514 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.514 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773200457s client | 1.514 DEBUG Setting timeout of 3.773200457s server | 2025/12/13 12:51:06 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:06 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx long flags:cb version:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx init len:1234 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx dcid len:8 1d5b6566cfd8d9b6 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic address validation token len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx clearflags:c0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx number:20 len:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 status tx:3651 rx:6260 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame rx init:20 CRYPTO len:1213 off:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic resend packet pnum:3 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion lost t:231017 win:2400 if:1054 server | 2025/12/13 12:51:06 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic resend packet pnum:2 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion lost rec t:231017 win:2400 if:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:20 largest 19 fr:3 nranges:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:20 perr:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 260122 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 30000:261017 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: read:30000 pto:397 close:59104 server | 2025/12/13 12:51:06 [debug] 22#0: timer delta: 600 server | 2025/12/13 12:51:06 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic output init packet max:1200 min:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx init:4 ACK n:0 delay:0 20-16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx init:4 CRYPTO len:90 off:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:4 encoded nl:1 trunc:0x4 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic output hs packet max:1054 min:1054 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx hs:3 CRYPTO len:720 off:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx hs bytes:1008 need_ack:1 number:3 encoded nl:1 trunc:0x3 server | 2025/12/13 12:51:06 [debug] 22#0: *2 sendmsg: 1200 of 1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion send if:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion idle:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer: 6, old: 261017, new: 261017 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 231414 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer pto:997 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 997:232014 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: send:30000 pto:997 close:59104 server | 2025/12/13 12:51:06 [debug] 22#0: worker cycle server | 2025/12/13 12:51:06 [debug] 22#0: epoll timer: 997 server | 2025/12/13 12:51:06 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:06 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx long flags:cb version:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx init len:347 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx dcid len:8 1d5b6566cfd8d9b6 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic address validation token len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx clearflags:c0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx number:21 len:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 status tx:4851 rx:7512 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame rx init:21 CRYPTO len:325 off:1213 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic resend packet pnum:4 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion lost rec t:231018 win:2400 if:1054 server | 2025/12/13 12:51:06 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic resend packet pnum:3 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion lost rec t:231018 win:2400 if:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:21 largest 20 fr:4 nranges:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:21 perr:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx short flags:0 server | 2025/12/13 12:51:06 [info] 22#0: *2 quic fixed bit is not set while parsing quic packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:-5 parse failed server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer: 6, old: 261017, new: 261018 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: read:29999 pto:996 close:59103 server | 2025/12/13 12:51:06 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:06 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic output init packet max:1200 min:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx init:5 ACK n:0 delay:0 21-16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx init:5 CRYPTO len:90 off:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:5 encoded nl:1 trunc:0x5 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic output hs packet max:1054 min:1054 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx hs:4 CRYPTO len:720 off:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx hs bytes:1008 need_ack:1 number:4 encoded nl:1 trunc:0x4 server | 2025/12/13 12:51:06 [debug] 22#0: *2 sendmsg: 1200 of 1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion send if:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion idle:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer: 6, old: 261017, new: 261018 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 232014 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer pto:997 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 997:232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: send:29999 pto:997 close:59103 server | 2025/12/13 12:51:06 [debug] 22#0: worker cycle server | 2025/12/13 12:51:06 [debug] 22#0: epoll timer: 997 client | 1.805 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1 client | 1.805 DEBUG [Client 1d5b6566cfd8d9b6] pn=1 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1054 client | -> RX Crypto { offset: 0, len: 720 } client | -> RX Padding { len: 284 } client | 1.805 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 231, tv_nsec: 168064962 } client | 1.805 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.805 DEBUG CRYPTO for hs offset=0, len=36 client | 1.805 DEBUG [Client 1d5b6566cfd8d9b6] pn=2 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 90 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 5, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 1.805 DEBUG packet_sent this=0x55ac539e5d40, pn=2, ps=90 client | 1.805 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.805 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.805 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.805 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773228901s client | 1.805 DEBUG Setting timeout of 3.773228901s client | 1.805 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.805 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.805 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.805 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773189918s client | 1.805 DEBUG Setting timeout of 3.773189918s client | 1.806 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 2 client | 1.806 DEBUG [Client 1d5b6566cfd8d9b6] pn=2 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1054 client | -> RX Crypto { offset: 0, len: 720 } client | -> RX Padding { len: 284 } client | 1.806 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 231, tv_nsec: 168960813 } client | 1.806 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.806 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.806 DEBUG CRYPTO for hs offset=0, len=36 client | 1.806 DEBUG [Client 1d5b6566cfd8d9b6] pn=3 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 90 client | TX -> Ack { largest_acknowledged: 2, ack_delay: 4, first_ack_range: 2, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 1.806 DEBUG packet_sent this=0x55ac539e5d40, pn=3, ps=90 client | 1.806 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.806 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.806 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.806 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.806 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.806 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773232567s client | 1.806 DEBUG Setting timeout of 3.773232567s client | 1.806 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.806 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 1.806 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 1.806 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 1.806 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.77321251s client | 1.806 DEBUG Setting timeout of 3.77321251s server | 2025/12/13 12:51:06 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:06 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx long flags:cb version:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx init len:23 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic address validation token len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx clearflags:c0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx number:22 len:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet len:1252 via sock seq:0 path seq:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 status tx:6051 rx:8764 valid:0 st:0 mtu:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame rx init:22 ACK n:0 delay:167 1-0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic updated largest received ack:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer pto:388 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 388:232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:22 largest 21 fr:5 nranges:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:22 perr:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx long flags:e0 version:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx hs len:23 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx clearflags:e0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx number:0 len:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion ack idle t:231627 win:2400 if:1054 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:-1 closed nsock:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer pto:388 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 388:232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 in handshake tx:6051 rx:8764 valid:1 st:0 mtu:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame rx hs:0 ACK n:0 delay:167 0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:2 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic updated largest received ack:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer pto:388 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 388:232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:0 level:hs decr:1 pn:0 perr:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx short flags:0 server | 2025/12/13 12:51:06 [info] 22#0: *2 quic fixed bit is not set while parsing quic packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:-5 parse failed server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 261017 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 30000:261627 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: read:30000 pto:388 close:58494 server | 2025/12/13 12:51:06 [debug] 22#0: timer delta: 609 server | 2025/12/13 12:51:06 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: read:30000 pto:388 close:58494 server | 2025/12/13 12:51:06 [debug] 22#0: worker cycle server | 2025/12/13 12:51:06 [debug] 22#0: epoll timer: 388 server | 2025/12/13 12:51:06 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:06 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic recvmsg: fd:6 n:156 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx long flags:e0 version:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx hs len:56 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx clearflags:e0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx number:1 len:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet len:156 via sock seq:0 path seq:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 status tx:6051 rx:8920 valid:1 st:0 mtu:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame rx hs:1 CRYPTO len:36 off:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:3 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_add_handshake_data server | 2025/12/13 12:51:06 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_flush_flight() server | 2025/12/13 12:51:06 [debug] 22#0: *2 SSL_do_handshake: 1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 SSL: TLSv1.3, cipher: "TLS_AES_128_GCM_SHA256 Kx=GENERIC Au=GENERIC Enc=AESGCM(128) Mac=AEAD" server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 post event 0000564FB0A2AE88 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion ack idle t:231628 win:2400 if:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 232015 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer unset server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 schedule mtu:2400 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 100:231728 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic create sockets has:1 max:8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:1 listening at sid:000000000000000229bc4894817e03420fc45711 nsock:2 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stateless reset token e0b665549b728d80547cca8aa2d9d6ec server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A1FFF0:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:2 listening at sid:00000000000000020938770bb405972fc9b82f9c nsock:3 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stateless reset token 75d18197c9506d24e323d5109db1c0f9 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A20200:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:3 listening at sid:00000000000000028aaea39178c4c65652d890a7 nsock:4 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stateless reset token 99f28f0b901350c65997592f7dcd303e server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A20410:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:4 listening at sid:0000000000000002f3d32557c0df8ec3272c69d2 nsock:5 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stateless reset token 9abfeb0a91b14a94dde462d83320f0fa server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A1E430:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:5 listening at sid:000000000000000284188837fc11e69684d61443 nsock:6 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stateless reset token 9b0195897570972dbd2f6d6975b67460 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A1E640:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:6 listening at sid:0000000000000002e03dddc2efe6b65e7d3b2b13 nsock:7 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stateless reset token 8ba30497d7fa1da46ccd800baa5af279 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A1E850:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic socket seq:7 listening at sid:0000000000000002ecd98d3848dcfe30f274c97e nsock:8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stateless reset token d27458ddfb79fffb2cd6c6de4b81cdf9 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic init streams server | 2025/12/13 12:51:06 [debug] 22#0: *2 http3 init server | 2025/12/13 12:51:06 [debug] 22#0: *2 http3 init session server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A1EA60:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 add cleanup: 0000564FB0A201A8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 75000:306628 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 290121 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1 largest 0 fr:0 nranges:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:0 level:hs decr:1 pn:1 perr:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx short flags:45 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx clearflags:40 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet rx number:0 len:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame rx app:0 STREAM id:0x0 len:30 fin:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 is missing server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 create server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A78860:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A78A70:16384 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 reusable connection: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 add cleanup: 0000564FB0A78B30 server | 2025/12/13 12:51:06 [debug] 22#0: *3 post event 0000564FB0A465D0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 flow control msd:30/65536 md:30/8585216 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:0 perr:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer: 6, old: 261627, new: 261628 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: read:29999 server | 2025/12/13 12:51:06 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:06 [debug] 22#0: posted event 0000564FB0A2AE88 server | 2025/12/13 12:51:06 [debug] 22#0: *2 delete posted event 0000564FB0A2AE88 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic key update server | 2025/12/13 12:51:06 [debug] 22#0: posted event 0000564FB0A465D0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 delete posted event 0000564FB0A465D0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 quic init stream server | 2025/12/13 12:51:06 [debug] 22#0: *3 http3 init request stream server | 2025/12/13 12:51:06 [debug] 22#0: *3 add cleanup: 0000564FB0A78BA8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 306628 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http wait request handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 recv buf:1024 server | 2025/12/13 12:51:06 [debug] 22#0: *3 quic stream id:0x0 recv len:30 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 flow update 30 server | 2025/12/13 12:51:06 [debug] 22#0: *3 reusable connection: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 posix_memalign: 0000564FB0A7CA80:4096 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http process request line server | 2025/12/13 12:51:06 [debug] 22#0: *3 http request line: "GET /tan-exhilarated-actress" server | 2025/12/13 12:51:06 [debug] 22#0: *3 http uri: "/tan-exhilarated-actress" server | 2025/12/13 12:51:06 [debug] 22#0: *3 http args: "" server | 2025/12/13 12:51:06 [debug] 22#0: *3 http exten: "" server | 2025/12/13 12:51:06 [debug] 22#0: *3 rewrite phase: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 test location: "/" server | 2025/12/13 12:51:06 [debug] 22#0: *3 using configuration "/" server | 2025/12/13 12:51:06 [debug] 22#0: *3 http cl:-1 max:1048576 server | 2025/12/13 12:51:06 [debug] 22#0: *3 rewrite phase: 2 server | 2025/12/13 12:51:06 [debug] 22#0: *3 post rewrite phase: 3 server | 2025/12/13 12:51:06 [debug] 22#0: *3 generic phase: 4 server | 2025/12/13 12:51:06 [debug] 22#0: *3 generic phase: 5 server | 2025/12/13 12:51:06 [debug] 22#0: *3 access phase: 6 server | 2025/12/13 12:51:06 [debug] 22#0: *3 access phase: 7 server | 2025/12/13 12:51:06 [debug] 22#0: *3 post access phase: 8 server | 2025/12/13 12:51:06 [debug] 22#0: *3 generic phase: 9 server | 2025/12/13 12:51:06 [debug] 22#0: *3 generic phase: 10 server | 2025/12/13 12:51:06 [debug] 22#0: *3 content phase: 11 server | 2025/12/13 12:51:06 [debug] 22#0: *3 content phase: 12 server | 2025/12/13 12:51:06 [debug] 22#0: *3 content phase: 13 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http filename: "/www/tan-exhilarated-actress" server | 2025/12/13 12:51:06 [debug] 22#0: *3 add cleanup: 0000564FB0A7D7D0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http static fd: 3 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http set discard body server | 2025/12/13 12:51:06 [debug] 22#0: *3 http output filter "/tan-exhilarated-actress?" server | 2025/12/13 12:51:06 [debug] 22#0: *3 http copy filter: "/tan-exhilarated-actress?" server | 2025/12/13 12:51:06 [debug] 22#0: *3 posix_memalign: 0000564FB0A7DA90:4096 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *3 read: 3, 0000564FB0A7DAB0, 1024, 0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http postpone filter "/tan-exhilarated-actress?" 0000564FB0A7D9E8 server | 2025/12/13 12:51:06 [debug] 22#0: *3 write new buf t:1 f:0 0000564FB0A7DAB0, pos 0000564FB0A7DAB0, size: 1024 file: 0, size: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http write filter: l:1 f:0 s:1024 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http write filter limit 2097152 server | 2025/12/13 12:51:06 [debug] 22#0: *3 quic send_chain sent:1024 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 flush limit:1048576 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 flush len:1024 last:0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http write filter 0000000000000000 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http copy filter: 0 "/tan-exhilarated-actress?" server | 2025/12/13 12:51:06 [debug] 22#0: *3 http finalize request: 0, "/tan-exhilarated-actress?" a:1, c:1 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http request count:1 blk:0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 http close request server | 2025/12/13 12:51:06 [debug] 22#0: *3 http log handler server | 193.167.0.100 - - [13/Dec/2025:12:51:06 +0000] "GET /tan-exhilarated-actress" 200 1024 "-" "-" server | 2025/12/13 12:51:06 [debug] 22#0: *3 run cleanup: 0000564FB0A7D7D0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 file cleanup: fd:3 server | 2025/12/13 12:51:06 [debug] 22#0: *3 free: 0000564FB0A7CA80, unused: 40 server | 2025/12/13 12:51:06 [debug] 22#0: *3 free: 0000564FB0A7DA90, unused: 2912 server | 2025/12/13 12:51:06 [debug] 22#0: *3 close http connection: 6 server | 2025/12/13 12:51:06 [debug] 22#0: *3 reusable connection: 0 server | 2025/12/13 12:51:06 [debug] 22#0: *3 run cleanup: 0000564FB0A78BA8 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 75000:306628 server | 2025/12/13 12:51:06 [debug] 22#0: *3 run cleanup: 0000564FB0A78B30 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 cleanup server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 send shutdown server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 flush limit:1047552 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A7CA80:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 update posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic stream id:0x0 flush len:0 last:1 server | 2025/12/13 12:51:06 [debug] 22#0: *3 free: 0000564FB0A78A70, unused: 14944 server | 2025/12/13 12:51:06 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic output app packet max:1200 min:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 ACK n:0 delay:0 0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 CRYPTO len:572 off:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 HANDSHAKE DONE server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:5 retire:0 len:20 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:6 retire:0 len:20 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:7 retire:0 len:20 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic split frame now:1029 need:320 shrink:709 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:0 STREAM id:0x0 off:0 len:315 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx app bytes:1182 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 sendmsg: 1200 of 1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion send if:1200 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion idle:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic output app packet max:1200 min:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:315 len:709 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:1024 len:0 fin:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx app bytes:720 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 sendmsg: 738 of 738 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion send if:1938 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion idle:1 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer: 6, old: 261627, new: 261628 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer pto:1017 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 1017:232645 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic state: send:29999 pto:1017 server | 2025/12/13 12:51:06 [debug] 22#0: worker cycle server | 2025/12/13 12:51:06 [debug] 22#0: epoll timer: 100 server | 2025/12/13 12:51:06 [debug] 22#0: timer delta: 101 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 231728 server | 2025/12/13 12:51:06 [debug] 22#0: *2 posix_memalign: 0000564FB0A78A70:512 @16 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 send probe mtu:2400 pnum:2 tries:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic sendto app packet max:2400 min:2400 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:2 PING server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx app bytes:2382 need_ack:0 number:2 encoded nl:1 trunc:0x2 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 rejected mtu:2400 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 send probe mtu:1800 pnum:3 tries:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic sendto app packet max:1800 min:1800 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:3 PING server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx app bytes:1782 need_ack:0 number:3 encoded nl:1 trunc:0x3 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 rejected mtu:1800 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 send probe mtu:1500 pnum:4 tries:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic sendto app packet max:1500 min:1500 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:4 PING server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx app bytes:1482 need_ack:0 number:4 encoded nl:1 trunc:0x4 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 rejected mtu:1500 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic path seq:0 send probe mtu:1350 pnum:5 tries:0 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic sendto app packet max:1350 min:1350 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic frame tx app:5 PING server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic packet tx app bytes:1332 need_ack:0 number:5 encoded nl:1 trunc:0x5 server | 2025/12/13 12:51:06 [debug] 22#0: *2 sendmsg: 1350 of 1350 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic congestion send if:3288 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer del: 6: 232645 server | 2025/12/13 12:51:06 [debug] 22#0: *2 quic lost timer pto:1017 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 1017:232746 server | 2025/12/13 12:51:06 [debug] 22#0: *2 event timer add: 6: 1017:232746 server | 2025/12/13 12:51:06 [debug] 22#0: worker cycle server | 2025/12/13 12:51:06 [debug] 22#0: epoll timer: 1017 client | 2.406 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 3 client | 2.406 DEBUG [Client 1d5b6566cfd8d9b6] pn=3 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1054 client | -> RX Crypto { offset: 0, len: 720 } client | -> RX Padding { len: 284 } client | 2.406 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 231, tv_nsec: 769101535 } client | 2.406 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.406 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 2.406 DEBUG CRYPTO for hs offset=0, len=36 client | 2.406 DEBUG [Client 1d5b6566cfd8d9b6] pn=4 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 90 client | TX -> Ack { largest_acknowledged: 3, ack_delay: 12, first_ack_range: 3, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 2.406 DEBUG packet_sent this=0x55ac539e5d40, pn=4, ps=90 client | 2.406 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 2.406 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.406 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 2.406 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 2.406 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.406 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.77320209s client | 2.406 DEBUG Setting timeout of 3.77320209s client | 2.406 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.406 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 2.406 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 2.406 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.406 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773157938s client | 2.406 DEBUG Setting timeout of 3.773157938s client | 2.407 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 4 client | 2.407 DEBUG [Client 1d5b6566cfd8d9b6] pn=4 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1054 client | -> RX Crypto { offset: 0, len: 720 } client | -> RX Padding { len: 284 } client | 2.407 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 231, tv_nsec: 769980820 } client | 2.407 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 2.407 DEBUG CRYPTO for hs offset=0, len=36 client | 2.407 DEBUG [Client 1d5b6566cfd8d9b6] pn=5 type=Handshake pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 90 client | TX -> Ack { largest_acknowledged: 4, ack_delay: 5, first_ack_range: 4, ack_ranges: [], ecn_count: None } client | TX -> Crypto { offset: 0, len: 36 } client | 2.407 DEBUG packet_sent this=0x55ac539e5d40, pn=5, ps=90 client | 2.407 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 2.407 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773249038s client | 2.407 DEBUG Setting timeout of 3.773249038s client | 2.407 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG Building Handshake dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) scid Some(CID [0]: ) client | 2.407 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 2.407 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 2.407 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.773217459s client | 2.407 DEBUG Setting timeout of 3.773217459s server | 2025/12/13 12:51:07 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:07 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic recvmsg: fd:6 n:90 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx long flags:e9 version:1 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx hs len:61 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:07 [info] 22#0: *2 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/12/13 12:51:07 [debug] 22#0: timer delta: 189 server | 2025/12/13 12:51:07 [debug] 22#0: worker cycle server | 2025/12/13 12:51:07 [debug] 22#0: epoll timer: 828 server | 2025/12/13 12:51:07 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:07 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic recvmsg: fd:6 n:90 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx long flags:e4 version:1 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx hs len:61 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:07 [info] 22#0: *2 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/12/13 12:51:07 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:07 [debug] 22#0: worker cycle server | 2025/12/13 12:51:07 [debug] 22#0: epoll timer: 827 client | 3.017 DEBUG [Client 1d5b6566cfd8d9b6] pn=0 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 1200 client | -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } client | -> RX Crypto { offset: 0, len: 572 } client | -> RX HandshakeDone client | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 2, 41, 188, 72, 148, 129, 126, 3, 66, 15, 196, 87, 17], stateless_reset_token: Token([224, 182, 101, 84, 155, 114, 141, 128, 84, 124, 202, 138, 162, 217, 214, 236]) } client | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 2, 9, 56, 119, 11, 180, 5, 151, 47, 201, 184, 47, 156], stateless_reset_token: Token([117, 209, 129, 151, 201, 80, 109, 36, 227, 35, 213, 16, 157, 177, 192, 249]) } client | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 2, 138, 174, 163, 145, 120, 196, 198, 86, 82, 216, 144, 167], stateless_reset_token: Token([153, 242, 143, 11, 144, 19, 80, 198, 89, 151, 89, 47, 125, 205, 48, 62]) } client | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 2, 243, 211, 37, 87, 192, 223, 142, 195, 39, 44, 105, 210], stateless_reset_token: Token([154, 191, 235, 10, 145, 177, 74, 148, 221, 228, 98, 216, 51, 32, 240, 250]) } client | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 2, 132, 24, 136, 55, 252, 17, 230, 150, 132, 214, 20, 67], stateless_reset_token: Token([155, 1, 149, 137, 117, 112, 151, 45, 189, 47, 109, 105, 117, 182, 116, 96]) } client | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 2, 224, 61, 221, 194, 239, 230, 182, 94, 125, 59, 43, 19], stateless_reset_token: Token([139, 163, 4, 151, 215, 250, 29, 164, 108, 205, 128, 11, 170, 90, 242, 121]) } client | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 2, 236, 217, 141, 56, 72, 220, 254, 48, 242, 116, 201, 126], stateless_reset_token: Token([210, 116, 88, 221, 251, 121, 255, 251, 44, 214, 198, 222, 75, 129, 205, 249]) } client | -> RX Stream { stream_id: 0, offset: 0, len: 315, fin: false } client | 3.017 DEBUG [Client 1d5b6566cfd8d9b6] Rx ACK space=ap, ranges=[0..=0] client | 3.017 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0 client | 3.017 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 445/15095 ssthresh 18446744073709551615] slow start += 71 client | 3.017 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 445/15095 ssthresh 18446744073709551615] on_packets_acked this=0x55ac539e5d40, limited=0, bytes_in_flight=445, cwnd=15095, state=SlowStart, new_acked=71 client | 3.018 DEBUG [0x55ac539d2c60] Got resumption token [953]: 02000645d4d4e6cf..677825c3f6d510c4 client | 3.018 DEBUG [0x55ac539d2c60] Got resumption token [953]: 02000645d4d4e6cf..93756d58fa26f955 client | 3.018 DEBUG [Agent 0x55ac539d2c60] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) client | 3.018 DEBUG setting max_stream_data to 65536 client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] State change from Connected -> Confirmed client | 3.018 DEBUG PMTUD started, PLPMTU is now 1280 client | 3.018 DEBUG PMTUD started with probe size 1380 client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] Drop packet number space hs client | 3.018 DEBUG [recovery::Loss] Reset loss recovery state for Handshake client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] No preferred address to migrate to client | 3.018 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 232, tv_nsec: 400763667 } client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.018 DEBUG Sending PMTUD probe of size 1380, count 1 client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] pn=1 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 1352 client | TX -> Ping client | TX -> Padding { len: 1313 } client | 3.018 DEBUG packet_sent this=0x55ac539e5d40, pn=1, ps=1352 client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG ECN probing: sent 1 probes client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 19.454522ms client | 3.018 DEBUG Setting timeout of 19.454522ms client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] pn=1 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, NotEct) len 738 client | -> RX Stream { stream_id: 0, offset: 315, len: 709, fin: false } client | -> RX Stream { stream_id: 0, offset: 1024, len: 0, fin: true } client | 3.018 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 232, tv_nsec: 381341705 } client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] pn=2 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 43 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | 3.018 DEBUG ECN probing: sent 2 probes client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.236104306s client | 3.018 DEBUG Setting timeout of 3.236104306s client | 3.018 DEBUG stream 0 complete client | 3.018 DEBUG stream BiDi creatable client | 3.018 DEBUG stream UniDi creatable client | 3.018 WARN Unhandled event StateChange(Confirmed) client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] delay duration 3.236025589s client | 3.018 DEBUG Setting timeout of 3.236025589s client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 242, tv_nsec: 90617128 } } client | 3.018 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 242, tv_nsec: 90617128 } }) client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.018 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.018 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 232, tv_nsec: 381558510 } client | 3.018 DEBUG [Client 1d5b6566cfd8d9b6] pn=3 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 54 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 27, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 3.018 DEBUG ECN probing: sent 3 probes client | 3.018 DEBUG Setting timeout of 9.708987606s client | 3.118 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 3.118 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 3.118 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 232, tv_nsec: 481411416 } client | 3.118 DEBUG [Client 1d5b6566cfd8d9b6] pn=4 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 55 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 12508, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 3.118 DEBUG ECN probing: sent 4 probes client | 3.118 DEBUG Setting timeout of 9.609138667s client | 3.118 DEBUG Setting timeout of 9.609127076s server | 2025/12/13 12:51:07 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:07 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic recvmsg: fd:6 n:90 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx long flags:e3 version:1 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx hs len:61 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:07 [info] 22#0: *2 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/12/13 12:51:07 [debug] 22#0: timer delta: 600 server | 2025/12/13 12:51:07 [debug] 22#0: worker cycle server | 2025/12/13 12:51:07 [debug] 22#0: epoll timer: 227 server | 2025/12/13 12:51:07 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:07 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic recvmsg: fd:6 n:90 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx long flags:ea version:1 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx hs len:61 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/12/13 12:51:07 [info] 22#0: *2 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/12/13 12:51:07 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:07 [debug] 22#0: worker cycle server | 2025/12/13 12:51:07 [debug] 22#0: epoll timer: 226 server | 2025/12/13 12:51:07 [debug] 22#0: timer delta: 226 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer del: 6: 232746 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic pto timer server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic pto app pto_count:0 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic sendto app packet max:1200 min:0 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic frame tx app:6 PING server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet tx app bytes:3 need_ack:0 number:6 encoded nl:1 trunc:0x6 server | 2025/12/13 12:51:07 [debug] 22#0: *2 sendmsg: 21 of 21 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic congestion send if:3309 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic lost timer pto:1017 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer add: 6: 1017:233763 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic sendto app packet max:1200 min:0 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic frame tx app:7 PING server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet tx app bytes:3 need_ack:0 number:7 encoded nl:1 trunc:0x7 server | 2025/12/13 12:51:07 [debug] 22#0: *2 sendmsg: 21 of 21 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic congestion send if:3330 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer del: 6: 233763 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic lost timer pto:1017 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer add: 6: 1017:233763 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer del: 6: 233763 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic lost timer pto:2034 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer add: 6: 2034:234780 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic state: send:28881 pto:2034 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer del: 6: 232746 server | 2025/12/13 12:51:07 [debug] 22#0: *2 posix_memalign: 0000564FB0A78C80:512 @16 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic path seq:0 send probe mtu:1350 pnum:8 tries:1 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic sendto app packet max:1350 min:1350 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic frame tx app:8 PING server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic packet tx app bytes:1332 need_ack:0 number:8 encoded nl:1 trunc:0x8 server | 2025/12/13 12:51:07 [debug] 22#0: *2 sendmsg: 1350 of 1350 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic congestion send if:4680 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer del: 6: 234780 server | 2025/12/13 12:51:07 [debug] 22#0: *2 quic lost timer pto:2034 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer add: 6: 2034:234780 server | 2025/12/13 12:51:07 [debug] 22#0: *2 event timer add: 6: 2034:234780 server | 2025/12/13 12:51:07 [debug] 22#0: worker cycle server | 2025/12/13 12:51:07 [debug] 22#0: epoll timer: 2034 server | 2025/12/13 12:51:08 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:08 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic recvmsg: fd:6 n:1352 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx short flags:4c server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx clearflags:40 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx number:1 len:1 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet len:1352 via sock seq:0 path seq:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic path seq:0 status tx:10731 rx:10272 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic frame rx app:1 PING server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic frame rx app:1 PADDING server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1 largest 0 fr:0 nranges:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:1 perr:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer del: 6: 261627 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer add: 6: 30000:263132 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic state: read:30000 pto:1648 server | 2025/12/13 12:51:08 [debug] 22#0: timer delta: 386 server | 2025/12/13 12:51:08 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:08 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer add: 6: 25:233157 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic state: read:30000 push:25 pto:1648 server | 2025/12/13 12:51:08 [debug] 22#0: worker cycle server | 2025/12/13 12:51:08 [debug] 22#0: epoll timer: 25 server | 2025/12/13 12:51:08 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:08 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic recvmsg: fd:6 n:43 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx short flags:57 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx clearflags:40 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx number:2 len:1 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet len:43 via sock seq:0 path seq:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic path seq:0 status tx:10731 rx:10315 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic frame rx app:2 ACK n:0 delay:3 1-0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:3 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic congestion ack idle t:233132 win:2400 if:3480 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic ngx_quic_drop_ack_ranges pn:0 largest:1 fr:1 nranges:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic stream id:0x0 ack len:315 fin:0 unacked:709 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic congestion ack idle t:233132 win:2400 if:2742 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic stream id:0x0 ack len:709 fin:0 unacked:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic stream id:0x0 ack len:0 fin:1 unacked:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic stream id:0x0 close server | 2025/12/13 12:51:08 [debug] 22#0: *2 reusable connection: 1 server | 2025/12/13 12:51:08 [debug] 22#0: *2 post event 0000564FB0A2AD08 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic updated largest received ack:1 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic rtt sample latest:1504 min:1504 avg:1504 var:752 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer del: 6: 234780 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic lost timer pto:4146 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer add: 6: 4146:237278 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:2 largest 1 fr:0 nranges:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:2 perr:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer: 6, old: 263132, new: 263132 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic state: read:30000 push:25 pto:4146 server | 2025/12/13 12:51:08 [debug] 22#0: timer delta: 0 server | 2025/12/13 12:51:08 [debug] 22#0: posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:08 [debug] 22#0: *2 delete posted event 0000564FB0A2AD08 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic push handler server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic output app packet max:1200 min:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic frame tx app:9 ACK n:0 delay:0 2-1 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet tx app bytes:5 need_ack:0 number:9 encoded nl:1 trunc:0x9 server | 2025/12/13 12:51:08 [debug] 22#0: *2 sendmsg: 23 of 23 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic congestion send if:2742 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic congestion idle:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic state: read:30000 push:25 pto:4146 server | 2025/12/13 12:51:08 [debug] 22#0: worker cycle server | 2025/12/13 12:51:08 [debug] 22#0: epoll timer: 25 server | 2025/12/13 12:51:08 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:08 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic recvmsg: fd:6 n:54 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx short flags:7b server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx clearflags:60 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx number:3 len:1 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet len:54 via sock seq:0 path seq:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic path seq:0 status tx:10754 rx:10369 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic frame rx app:3 ACK n:0 delay:27 1-0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:3 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer del: 6: 237278 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic lost timer pto:4145 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer add: 6: 4145:237278 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic frame rx app:3 CONNECTION_CLOSE_APP err:0 kthxbye! server | 2025/12/13 12:51:08 [debug] 22#0: *2 post event 0000564FB0A2ADC8 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:3 largest 2 fr:1 nranges:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:3 perr:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer: 6, old: 263132, new: 263133 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic state: draining read:29999 push:24 pto:4145 server | 2025/12/13 12:51:08 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:08 [debug] 22#0: posted event 0000564FB0A2ADC8 server | 2025/12/13 12:51:08 [debug] 22#0: *2 delete posted event 0000564FB0A2ADC8 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic close handler server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic close initiated rc:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic close immediate term:0 drain:1 error:0 "" server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer add: 6: 13596:246729 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer del: 6: 233157 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer del: 6: 237278 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer del: 6: 234780 server | 2025/12/13 12:51:08 [debug] 22#0: worker cycle server | 2025/12/13 12:51:08 [debug] 22#0: epoll timer: 13596 server | 2025/12/13 12:51:08 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:08 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic recvmsg: fd:6 n:55 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx short flags:65 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx clearflags:60 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet rx number:4 len:1 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet len:55 via sock seq:0 path seq:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic path seq:0 status tx:10754 rx:10424 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:4 perr:0 server | 2025/12/13 12:51:08 [debug] 22#0: *2 event timer: 6, old: 263132, new: 263231 server | 2025/12/13 12:51:08 [debug] 22#0: *2 quic state: closing draining read:29901 close:13498 server | 2025/12/13 12:51:08 [debug] 22#0: timer delta: 98 server | 2025/12/13 12:51:08 [debug] 22#0: worker cycle server | 2025/12/13 12:51:08 [debug] 22#0: epoll timer: 13498 client | 4.134 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.134 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 4.134 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 233, tv_nsec: 497022562 } client | 4.134 DEBUG [Client 1d5b6566cfd8d9b6] pn=5 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 57 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 139460, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.134 DEBUG ECN probing: sent 5 probes client | 4.134 DEBUG Setting timeout of 8.593474172s client | 4.134 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.134 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 4.134 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 233, tv_nsec: 497158405 } client | 4.134 DEBUG [Client 1d5b6566cfd8d9b6] pn=6 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 57 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 139477, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.134 DEBUG ECN probing: sent 6 probes client | 4.134 DEBUG Setting timeout of 8.593379923s client | 4.134 DEBUG Setting timeout of 8.593367981s client | 4.135 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.135 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 4.135 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 233, tv_nsec: 498144677 } client | 4.135 DEBUG [Client 1d5b6566cfd8d9b6] pn=7 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 57 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 139600, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.135 DEBUG ECN probing: sent 7 probes client | 4.135 DEBUG Setting timeout of 8.592415655s client | 4.135 DEBUG Setting timeout of 8.592404174s client | 4.520 DEBUG [Client 1d5b6566cfd8d9b6] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false } client | 4.520 DEBUG Building Short dcid Some(CID [20]: 0000000000000002c754486edbbf172fff0707e8) client | 4.520 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 233, tv_nsec: 883614885 } client | 4.520 DEBUG [Client 1d5b6566cfd8d9b6] pn=8 type=Short pri-path:0000000000000002c754486edbbf172fff0707e8 0.0.0.0:59620->193.167.100.100:443 Tos(Cs0, Ect0) len 57 client | TX -> Ack { largest_acknowledged: 1, ack_delay: 187784, first_ack_range: 1, ack_ranges: [], ecn_count: None } client | TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" } client | 4.520 DEBUG ECN probing: sent 8 probes client | 4.520 DEBUG Setting timeout of 8.206912135s client | 4.520 DEBUG Setting timeout of 8.206898359s server | 2025/12/13 12:51:09 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:09 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic recvmsg: fd:6 n:57 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx short flags:5c server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx clearflags:40 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx number:5 len:1 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet len:57 via sock seq:0 path seq:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic path seq:0 status tx:10754 rx:10481 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:5 perr:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 event timer del: 6: 263132 server | 2025/12/13 12:51:09 [debug] 22#0: *2 event timer add: 6: 30000:264247 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic state: closing draining read:30000 close:12482 server | 2025/12/13 12:51:09 [debug] 22#0: timer delta: 1016 server | 2025/12/13 12:51:09 [debug] 22#0: worker cycle server | 2025/12/13 12:51:09 [debug] 22#0: epoll timer: 12482 server | 2025/12/13 12:51:09 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:09 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic recvmsg: fd:6 n:57 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx short flags:5a server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx clearflags:40 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx number:6 len:1 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet len:57 via sock seq:0 path seq:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic path seq:0 status tx:10754 rx:10538 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:6 perr:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 event timer: 6, old: 264247, new: 264247 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic state: closing draining read:30000 close:12482 server | 2025/12/13 12:51:09 [debug] 22#0: timer delta: 0 server | 2025/12/13 12:51:09 [debug] 22#0: worker cycle server | 2025/12/13 12:51:09 [debug] 22#0: epoll timer: 12482 server | 2025/12/13 12:51:09 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:09 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic recvmsg: fd:6 n:57 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx short flags:44 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx clearflags:40 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx number:7 len:1 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet len:57 via sock seq:0 path seq:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic path seq:0 status tx:10754 rx:10595 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:7 perr:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 event timer: 6, old: 264247, new: 264248 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic state: closing draining read:29999 close:12481 server | 2025/12/13 12:51:09 [debug] 22#0: timer delta: 1 server | 2025/12/13 12:51:09 [debug] 22#0: worker cycle server | 2025/12/13 12:51:09 [debug] 22#0: epoll timer: 12481 server | 2025/12/13 12:51:09 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F1A7ABF2108 server | 2025/12/13 12:51:09 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic recvmsg: fd:6 n:57 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic input handler server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx short flags:46 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000000002c754486edbbf172fff0707e8 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx clearflags:40 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet rx number:8 len:1 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet len:57 via sock seq:0 path seq:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic path seq:0 status tx:10754 rx:10652 valid:1 st:3 mtu:1200 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:8 perr:0 server | 2025/12/13 12:51:09 [debug] 22#0: *2 event timer del: 6: 264247 server | 2025/12/13 12:51:09 [debug] 22#0: *2 event timer add: 6: 30000:264633 server | 2025/12/13 12:51:09 [debug] 22#0: *2 quic state: closing draining read:30000 close:12096 server | 2025/12/13 12:51:09 [debug] 22#0: timer delta: 385 server | 2025/12/13 12:51:09 [debug] 22#0: worker cycle server | 2025/12/13 12:51:09 [debug] 22#0: epoll timer: 12096 client | 12.728 DEBUG [Client 1d5b6566cfd8d9b6] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 242, tv_nsec: 90617128 } } -> Closed(Application(0)) client | 12.728 INFO Closing timer expired client | 12.728 DEBUG Output::None client | 12.728 WARN Unhandled event StateChange(Closed(Application(0))) client | 12.728 DEBUG Timer fired while closed client | 12.728 DEBUG Output::None client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-12-13 12:51:27,716 Using the client's key log file. 2025-12-13 12:51:27,724 2025-12-13 12:51:27,724 Using the client's key log file. 2025-12-13 12:51:27,725 Using the client's key log file. 2025-12-13 12:51:27,732 2025-12-13 12:51:27,732 Using the client's key log file. 2025-12-13 12:51:27,942 Check of downloaded files succeeded. 2025-12-13 12:51:28,795 Check of downloaded files succeeded.