2025-12-13 12:50:27,054 Generated random file: delighted-dark-engineer of size: 1024
2025-12-13 12:50:27,054 Requests: https://server4:443/delighted-dark-engineer
2025-12-13 12:50:27,110 
2025-12-13 12:50:27,111 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_9q5nloqy/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_zsvgkyrj/ DOWNLOADS=/tmp/download_x5i06xyg/ SERVER_LOGS=/tmp/logs_server_fd96_vta CLIENT_LOGS=/tmp/logs_client_lwi9lcuz SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/delighted-dark-engineer"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-12-13 12:50:39,616  Container sim  Recreate
 Container sim  Recreated
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
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
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
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
server  | >>> Starting nginx server...
client  | Endpoint's IPv4 address is 193.167.0.100
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  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | 2025/12/13 12:50:27 [debug] 21#0: bind() 0.0.0.0:443 #5 
server  | 2025/12/13 12:50:27 [debug] 21#0: bind() 0.0.0.0:443 #6 
server  | 2025/12/13 12:50:27 [debug] 21#0: bind() [::]:443 #7 
server  | 2025/12/13 12:50:27 [debug] 21#0: bind() [::]:443 #8 
server  | 2025/12/13 12:50:27 [debug] 21#0: add cleanup: 0000557ABE8811C0
server  | 2025/12/13 12:50:27 [notice] 21#0: using the "epoll" event method
server  | 2025/12/13 12:50:27 [debug] 21#0: counter: 00007FB273B42080, 1
server  | 2025/12/13 12:50:27 [notice] 21#0: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f)
server  | 2025/12/13 12:50:27 [notice] 21#0: built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) 
server  | 2025/12/13 12:50:27 [notice] 21#0: OS: Linux 6.11.0-1018-azure
server  | 2025/12/13 12:50:27 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 65536:65536
server  | 2025/12/13 12:50:27 [debug] 21#0: write: 9, 00007FFE7F84D8E0, 3, 0
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/ ']'
server  | 2025/12/13 12:50:27 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf"
server  | 2025/12/13 12:50:27 [notice] 21#0: start worker processes
server  | 2025/12/13 12:50:27 [debug] 21#0: channel 3:9
client  | + case "$ROLE" in
client  | + /wait-for-it.sh sim:57832 -s -t 30
server  | 2025/12/13 12:50:27 [notice] 21#0: start worker process 22
server  | 2025/12/13 12:50:27 [debug] 21#0: sigsuspend
server  | 2025/12/13 12:50:27 [debug] 22#0: add cleanup: 0000557ABE881248
server  | 2025/12/13 12:50:27 [debug] 22#0: malloc: 0000557ABE883A30:16
server  | 2025/12/13 12:50:27 [debug] 22#0: add cleanup: 0000557ABE881260
server  | 2025/12/13 12:50:27 [debug] 22#0: malloc: 0000557ABE882A20:16
server  | 2025/12/13 12:50:27 [debug] 22#0: notify eventfd: 11
server  | 2025/12/13 12:50:27 [debug] 22#0: testing the EPOLLRDHUP flag: success
server  | 2025/12/13 12:50:27 [debug] 22#0: malloc: 0000557ABE869EF0:6144
server  | 2025/12/13 12:50:27 [debug] 22#0: malloc: 00007FB273466010:253952
server  | 2025/12/13 12:50:27 [debug] 22#0: malloc: 0000557ABE887390:98304
server  | 2025/12/13 12:50:27 [debug] 22#0: malloc: 0000557ABE89F3A0:98304
server  | 2025/12/13 12:50:27 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001
server  | 2025/12/13 12:50:27 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001
server  | 2025/12/13 12:50:27 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001
server  | 2025/12/13 12:50:27 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001
server  | 2025/12/13 12:50:27 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001
server  | 2025/12/13 12:50:27 [debug] 22#0: setproctitle: "nginx: worker process"
server  | 2025/12/13 12:50:27 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:27 [debug] 22#0: epoll timer: -1
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: posix_memalign: 0000557ABE881AD0:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: malloc: 0000557ABE883A50:1207
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:41680 fd:6 n:1207
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic run
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic packet rx dcid len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic packet rx scid len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic address validation token len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 sending version negotiation packet
sim     | server:443 is available after 500.776107ms
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 sendmsg: 11 of 11
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 reusable connection: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 free: 0000557ABE883A50
server  | 2025/12/13 12:50:28 [debug] 22#0: *1 free: 0000557ABE881AD0, unused: 16
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 330
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: -1
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | Capturing on 'eth0'
sim     | Capturing on 'eth1'
sim     | File: /logs/trace_node_right.pcap
sim     | File: /logs/trace_node_left.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/delighted-dark-engineer ']'
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/delighted-dark-engineer
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:54449 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=c9757c83263bf760
client  | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=13
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:54449->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:c9757c83263bf760 0.0.0.0:54449->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:c9757c83263bf760 0.0.0.0:54449->193.167.100.100:443] Path validated Instant { tv_sec: 193, tv_nsec: 624559336 }
client  | 0.001 DEBUG [Client c9757c83263bf760] client_start
client  | 0.002 DEBUG Writing transport parameters, msg=1
client  | 0.002 DEBUG [Agent 0x55e6e45c4c60] state -> InProgress
client  | 0.002 DEBUG [Client c9757c83263bf760] State change from Init -> WaitInitial
client  | 0.002 DEBUG [Client c9757c83263bf760] 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]: c9757c83263bf760) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.002 DEBUG [Client c9757c83263bf760] pn=13 type=Initial pri-path:c9757c83263bf760 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.002 DEBUG packet_sent this=0x55e6e45d7d40, pn=13, ps=1252
client  | 0.002 DEBUG [Client c9757c83263bf760] 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]: c9757c83263bf760) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1213, len=325
client  | 0.002 DEBUG [Client c9757c83263bf760] pn=14 type=Initial pri-path:c9757c83263bf760 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 365
client  |   TX -> Crypto { offset: 1213, len: 325 }
client  | 0.002 DEBUG [Client c9757c83263bf760] pad Initial from 365 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55e6e45d7d40, pn=14, ps=1252
client  | 0.002 DEBUG [Client c9757c83263bf760] 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]: c9757c83263bf760) 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 c9757c83263bf760] 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]: c9757c83263bf760) 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 c9757c83263bf760] delay duration 3.37542ms
client  | 0.002 DEBUG Setting timeout of 3.37542ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client c9757c83263bf760] 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]: c9757c83263bf760) 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 c9757c83263bf760] delay duration 3.34296ms
client  | 0.002 DEBUG Setting timeout of 3.34296ms
client  | 0.007 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [8]: c9757c83263bf760) scid Some(CID [0]: )
client  | 0.007 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG [Client c9757c83263bf760] delay duration 293.800024ms
client  | 0.007 DEBUG Setting timeout of 293.800024ms
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: posix_memalign: 0000557ABE881AD0:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: malloc: 0000557ABE883A50:1252
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: 193.167.0.100:54449 fd:6 n:1252
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic run
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx long flags:c1 version:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx init len:1234
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:8 c9757c83263bf760
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx scid len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 malloc: 0000557ABE86B700:2512
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 malloc: 0000557ABE86C0E0:1528
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE883F40:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_set_initial_secret
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:0 listening at sid:00000000000000015bbc2428898f33a11e8a99dc nsock:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic cid seq:0 received id:0::00000000000000000000000000000000
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE86C9D0:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 created addr:193.167.0.100:54449
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE86CBE0:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:-1 listening at sid:c9757c83263bf760 nsock:2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 reusable connection: 1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic connection created
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:13 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:1252 via sock seq:0 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:0 rx:1252 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token 86c19b466b625fcd77d7e534a2e9c081
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx init:13 CRYPTO len:1213 off:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE85FDD0:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 malloc: 0000557ABE85FFE0:4096
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:13 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:13 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 75000:268647
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 60000:253647
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:75000 close:60000
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 714
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic output init packet max:1200 min:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx init:0 ACK n:0 delay:0 13
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 sendmsg: 51 of 51
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion send if:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:75000 close:60000
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 60000
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx long flags:cb version:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx init len:347
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:8 c9757c83263bf760
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx scid len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:14 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:1252 via sock seq:-1 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:51 rx:2504 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx init:14 CRYPTO len:325 off:1213
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL server name: "server4"
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL ALPN supported by client: hq-interop
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL ALPN supported by client: ��
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL ALPN selected: hq-interop
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic SSL_get_peer_quic_transport_params(): params_len:78
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic unknown transport param id:0x11, skipped
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic unknown transport param id:0x2ab2, skipped
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic reserved transport param id:0xff02de1a, skipped
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic unknown transport param id:0x20, skipped
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic transport parameters parsed ok
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp disable active migration: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp idle_timeout:30000
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp max_udp_payload_size:65527
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp max_data:2097152
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp max_stream_data_bidi_local:1048576
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp max_stream_data_bidi_remote:1048576
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp max_stream_data_uni:1048576
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp initial_max_streams_bidi:16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp initial_max_streams_uni:16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp ack_delay_exponent:3
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp max_ack_delay:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp active_connection_id_limit:8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic tp initial source_connection_id len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 malloc: 0000557ABE861D80:4096
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 malloc: 0000557ABE8B7410:4096
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE8B8420:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:3
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_flush_flight()
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:14 largest 13 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:14 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx short flags:0
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 quic packet done rc:-5 parse failed
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 268647
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 30000:223647
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:30000 close:60000
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic output init packet max:1200 min:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx init:1 ACK n:0 delay:0 14-13
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx init:1 CRYPTO len:90 off:0
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 quic output hs packet max:1054 min:1054
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx hs:0 CRYPTO len:718 off:0
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223647
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer pto:997
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 997:194644
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: send:30000 pto:997 close:60000
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 997
client  | 0.039 DEBUG [Client c9757c83263bf760] pn=0 type=Initial pri-path:c9757c83263bf760 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 51
client  |   -> RX Ack { largest_acknowledged: 13, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.039 DEBUG [Client c9757c83263bf760] Rx ACK space=in, ranges=[13..=13]
client  | 0.039 DEBUG [recovery::Loss] ACK for Initial - largest_acked=13
client  | 0.039 DEBUG on_packets_acked this=0x55e6e45d7d40, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.039 DEBUG Acked crypto frame space=in offset=0 length=1213
client  | 0.039 DEBUG [Client c9757c83263bf760] Changing to use Server CID=00000000000000015bbc2428898f33a11e8a99dc
client  | 0.039 DEBUG [Client c9757c83263bf760] State change from WaitInitial -> WaitVersion
client  | 0.039 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG [Client c9757c83263bf760] delay duration 76.056108ms
client  | 0.039 DEBUG Setting timeout of 76.056108ms
client  | 0.039 WARN Unhandled event StateChange(WaitVersion)
client  | 0.039 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG [Client c9757c83263bf760] delay duration 76.026433ms
client  | 0.039 DEBUG Setting timeout of 76.026433ms
client  | 0.041 DEBUG [Client c9757c83263bf760] pn=1 type=Initial pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 146
client  |   -> RX Ack { largest_acknowledged: 14, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   -> RX Crypto { offset: 0, len: 90 }
client  | 0.041 DEBUG [Client c9757c83263bf760] Rx ACK space=in, ranges=[13..=14]
client  | 0.041 DEBUG [recovery::Loss] ACK for Initial - largest_acked=14
client  | 0.041 DEBUG on_packets_acked this=0x55e6e45d7d40, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.041 DEBUG Acked crypto frame space=in offset=1213 length=325
client  | 0.042 DEBUG Write secret available for Handshake: SymKey [32]: 7d3370dbff679257685cfa4d47dd9236312bda1c512163c84d14ccc6997a21bb
client  | 0.042 DEBUG Read secret available for Handshake: SymKey [32]: 4542b32f4485158953580142a991fda0544ac0b1d88162f871f1a155b3f15df6
client  | 0.042 DEBUG [Agent 0x55e6e45c4c60] state -> InProgress
client  | 0.042 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.042 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.042 DEBUG [Crypto] Handshake keys installed
client  | 0.042 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 193, tv_nsec: 665426401 }
client  | 0.042 DEBUG [Client c9757c83263bf760] State change from WaitVersion -> Handshaking
client  | 0.042 DEBUG [Client c9757c83263bf760] pn=0 type=Handshake pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 1054
client  |   -> RX Crypto { offset: 0, len: 718 }
client  |   -> RX Padding { len: 286 }
client  | 0.043 DEBUG [Agent 0x55e6e45c4c60] state -> AuthenticationPending
client  | 0.043 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 193, tv_nsec: 665426401 }
client  | 0.043 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Initial dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client c9757c83263bf760] pn=15 type=Initial pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 174, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client c9757c83263bf760] pn=0 type=Handshake pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 105
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 174, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.043 DEBUG [Client c9757c83263bf760] Drop packet number space in
client  | 0.043 DEBUG [recovery::Loss] Reset loss recovery state for Initial
client  | 0.043 DEBUG [Client c9757c83263bf760] pad Initial from 105 to PLPMTU 1252
client  | 0.043 INFO [recovery::Loss] ignoring packet 15 from dropped space in
client  | 0.043 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client c9757c83263bf760] delay duration 97.620094ms
client  | 0.043 DEBUG Setting timeout of 97.620094ms
client  | 0.043 DEBUG StateChange(Handshaking)
client  | 0.043 WARN Cannot create stream ConnectionState
client  | 0.043 DEBUG [Client c9757c83263bf760] Authenticated Ok
client  | 0.043 DEBUG Write secret available for ApplicationData: SymKey [32]: 1cf4ba580bc375026b466057b0d9befbdb77569fd823334726f6c6c3fe532378
client  | 0.043 DEBUG Read secret available for ApplicationData: SymKey [32]: 924867ace2f8ce3da22744880116d9127fe16c2357ec5a740bf0a7e936f6b9dd
client  | 0.043 DEBUG [Agent 0x55e6e45c4c60] SSL_AuthCertificateComplete: Ok(())
client  | 0.043 DEBUG [Agent 0x55e6e45c4c60] 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  | 0.043 DEBUG [Client c9757c83263bf760] TLS connection complete
client  | 0.043 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.043 DEBUG [Crypto] Application write key installed
client  | 0.043 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.043 DEBUG [Crypto] application read keys installed
client  | 0.043 DEBUG [Client c9757c83263bf760] State change from Handshaking -> Connected
client  | 0.043 INFO [Client c9757c83263bf760] Connection established
client  | 0.043 DEBUG stream BiDi creatable
client  | 0.043 INFO Created stream 0 for https://server4/delighted-dark-engineer
client  | 0.043 INFO Saving https://server4/delighted-dark-engineer to "/downloads/delighted-dark-engineer"
client  | 0.043 DEBUG stream UniDi creatable
client  | 0.043 DEBUG StateChange(Connected)
client  | 0.043 DEBUG stream 0 writable
client  | 0.043 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.043 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.043 DEBUG [Client c9757c83263bf760] pn=1 type=Handshake pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 85
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.043 DEBUG packet_sent this=0x55e6e45d7d40, pn=1, ps=85
client  | 0.043 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.043 DEBUG [Client c9757c83263bf760] pn=0 type=Short pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, NotEct) len 156
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 30, fin: true }
client  | 0.043 DEBUG packet_sent this=0x55e6e45d7d40, pn=0, ps=71
client  | 0.043 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc) scid Some(CID [0]: )
client  | 0.043 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client c9757c83263bf760] delay duration 97.630191ms
client  | 0.043 DEBUG Setting timeout of 97.630191ms
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: fd:6 n:1252
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx long flags:cc version:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx init len:23
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx scid len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:15 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:1252 via sock seq:0 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:1251 rx:3756 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx init:15 ACK n:0 delay:174 1-0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion ack idle t:193683 win:12000 if:1054
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_drop_ack_ranges pn:14 largest:14 fr:1 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic updated largest received ack:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic rtt sample latest:36 min:36 avg:36 var:18
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 194644
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer pto:72
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 72:193755
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:15 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:15 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx long flags:ea version:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx hs len:23
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx scid len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:e0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:0 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:-1 closed nsock:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 193755
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer pto:72
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 72:193755
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 in handshake tx:1251 rx:3756 valid:1 st:0 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx hs:0 ACK n:0 delay:174 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion ack idle t:193683 win:12000 if:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic updated largest received ack:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic rtt sample latest:36 min:36 avg:36 var:14
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 193755
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer unset
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:hs decr:1 pn:0 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx short flags:0
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 quic packet done rc:-5 parse failed
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223683
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:29964 close:59964
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 36
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:29964 close:59964
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 29964
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: fd:6 n:156
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx long flags:e6 version:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx hs len:56
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx scid len:0 
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:e0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:1 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:156 via sock seq:0 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:1251 rx:3912 valid:1 st:0 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx hs:1 CRYPTO len:36 off:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:3
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_flush_flight()
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 SSL_do_handshake: 1
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BE88
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer unset
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 schedule mtu:2400
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 100:193783
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic create sockets has:1 max:8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:1 listening at sid:0000000000000001822cc3cc88dbf6e3b465ebb3 nsock:2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token 5a5005c344d4047f0f3a2d71bfaaa655
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE860FF0:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:2 listening at sid:00000000000000013f25fe4e952ae66f84c69781 nsock:3
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token b2b0e72f4bccd4d3932288a29efef6b6
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE861200:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:3 listening at sid:0000000000000001f527c4f2bb908eada1a50f9d nsock:4
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token abc5f645203d029dc5569cdaa0afb7ab
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE861410:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:4 listening at sid:0000000000000001a52b2e6356d07e8da743bc9d nsock:5
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token d635543c3cfd3a38399e2aa56e5cdf37
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE85F430:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:5 listening at sid:0000000000000001049df3252e59f474019c3086 nsock:6
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token 968932c00c77d75d7004dc43e0d36ad1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE85F640:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:6 listening at sid:00000000000000018cbdb190d49d1d1fd596021f nsock:7
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token e2e9d297291629e3f940fcd645a5b800
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE85F850:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic socket seq:7 listening at sid:0000000000000001e96ac66ba6d157bb7390b5bf nsock:8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stateless reset token d6a2f37c8b69ef1c71d6b1446e2d722b
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic init streams
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 http3 init
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 http3 init session
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE85FA60:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 add cleanup: 0000557ABE8611A8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 75000:268683
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 253647
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1 largest 0 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:hs decr:1 pn:1 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx short flags:6a
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:60
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:0 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx app:0 STREAM id:0x0 len:30 fin:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 is missing
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 create
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE8B9860:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE8B9A70:16384 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 reusable connection: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 add cleanup: 0000557ABE8B9B30
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 post event 0000557ABE8875D0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 flow control msd:30/65536 md:30/8585216
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:0 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223683
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:29964
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BE88
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BE88
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic key update
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE8875D0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 delete posted event 0000557ABE8875D0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 quic init stream
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http3 init request stream
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 add cleanup: 0000557ABE8B9BA8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 268683
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http wait request handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 recv buf:1024
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 quic stream id:0x0 recv len:30
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 flow update 30
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 reusable connection: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 posix_memalign: 0000557ABE8BDA80:4096 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http process request line
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http request line: "GET /delighted-dark-engineer"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http uri: "/delighted-dark-engineer"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http args: ""
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http exten: ""
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 rewrite phase: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 test location: "/"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 using configuration "/"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http cl:-1 max:1048576
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 rewrite phase: 2
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 post rewrite phase: 3
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 generic phase: 4
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 generic phase: 5
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 access phase: 6
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 access phase: 7
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 post access phase: 8
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 generic phase: 9
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 generic phase: 10
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 content phase: 11
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 content phase: 12
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 content phase: 13
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http filename: "/www/delighted-dark-engineer"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 add cleanup: 0000557ABE8BE7D0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http static fd: 3
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http set discard body
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http output filter "/delighted-dark-engineer?"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http copy filter: "/delighted-dark-engineer?"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 posix_memalign: 0000557ABE8BEA90:4096 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 read: 3, 0000557ABE8BEAB0, 1024, 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http postpone filter "/delighted-dark-engineer?" 0000557ABE8BE9E8
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 write new buf t:1 f:0 0000557ABE8BEAB0, pos 0000557ABE8BEAB0, size: 1024 file: 0, size: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http write filter: l:1 f:0 s:1024
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http write filter limit 2097152
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 quic send_chain sent:1024
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 flush limit:1048576
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 flush len:1024 last:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http write filter 0000000000000000
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http copy filter: 0 "/delighted-dark-engineer?"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http finalize request: 0, "/delighted-dark-engineer?" a:1, c:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http request count:1 blk:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http close request
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 http log handler
server  | 193.167.0.100 - - [13/Dec/2025:12:50:28 +0000] "GET /delighted-dark-engineer" 200 1024 "-" "-"
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 run cleanup: 0000557ABE8BE7D0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 file cleanup: fd:3
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 free: 0000557ABE8BDA80, unused: 40
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 free: 0000557ABE8BEA90, unused: 2912
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 close http connection: 6
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 reusable connection: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 run cleanup: 0000557ABE8B9BA8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 75000:268683
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 run cleanup: 0000557ABE8B9B30
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 cleanup
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 send shutdown
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 flush limit:1047552
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 posix_memalign: 0000557ABE8BDA80:512 @16
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 update posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 flush len:0 last:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *3 free: 0000557ABE8B9A70, unused: 14944
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 ACK n:0 delay:0 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 CRYPTO len:572 off:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 HANDSHAKE DONE
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:5 retire:0 len:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:6 retire:0 len:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:7 retire:0 len:20
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic split frame now:1029 need:320 shrink:709
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:0 STREAM id:0x0 off:0 len:315
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion idle:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:315 len:709
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:1024 len:0 fin:1
server  | 2025/12/13 12:50:28 [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:50:28 [debug] 22#0: *2 sendmsg: 738 of 738
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion send if:1938
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223683
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer pto:112
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 112:193795
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: send:29964 pto:112
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 100
client  | 0.077 DEBUG [Client c9757c83263bf760] pn=0 type=Short pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->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, 1, 130, 44, 195, 204, 136, 219, 246, 227, 180, 101, 235, 179], stateless_reset_token: Token([90, 80, 5, 195, 68, 212, 4, 127, 15, 58, 45, 113, 191, 170, 166, 85]) }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 1, 63, 37, 254, 78, 149, 42, 230, 111, 132, 198, 151, 129], stateless_reset_token: Token([178, 176, 231, 47, 75, 204, 212, 211, 147, 34, 136, 162, 158, 254, 246, 182]) }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 1, 245, 39, 196, 242, 187, 144, 142, 173, 161, 165, 15, 157], stateless_reset_token: Token([171, 197, 246, 69, 32, 61, 2, 157, 197, 86, 156, 218, 160, 175, 183, 171]) }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 1, 165, 43, 46, 99, 86, 208, 126, 141, 167, 67, 188, 157], stateless_reset_token: Token([214, 53, 84, 60, 60, 253, 58, 56, 57, 158, 42, 165, 110, 92, 223, 55]) }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 1, 4, 157, 243, 37, 46, 89, 244, 116, 1, 156, 48, 134], stateless_reset_token: Token([150, 137, 50, 192, 12, 119, 215, 93, 112, 4, 220, 67, 224, 211, 106, 209]) }
client  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 1, 140, 189, 177, 144, 212, 157, 29, 31, 213, 150, 2, 31], stateless_reset_token: Token([226, 233, 210, 151, 41, 22, 41, 227, 249, 64, 252, 214, 69, 165, 184, 0]) }
client  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [0, 0, 0, 0, 0, 0, 0, 1, 233, 106, 198, 107, 166, 209, 87, 187, 115, 144, 181, 191], stateless_reset_token: Token([214, 162, 243, 124, 139, 105, 239, 28, 113, 214, 177, 68, 110, 45, 114, 43]) }
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 315, fin: false }
client  | 0.077 DEBUG [Client c9757c83263bf760] Rx ACK space=ap, ranges=[0..=0]
client  | 0.077 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=0
client  | 0.077 DEBUG on_packets_acked this=0x55e6e45d7d40, limited=1, bytes_in_flight=85, cwnd=12520, state=SlowStart, new_acked=71
client  | 0.077 DEBUG [0x55e6e45c4c60] Got resumption token [952]: 02000645d4d2989a..5f0010979180baac
client  | 0.077 DEBUG [0x55e6e45c4c60] Got resumption token [952]: 02000645d4d2989a..7e9c07151e7807ac
client  | 0.077 DEBUG [Agent 0x55e6e45c4c60] 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  | 0.077 DEBUG setting max_stream_data to 65536
client  | 0.077 DEBUG [Client c9757c83263bf760] State change from Connected -> Confirmed
client  | 0.077 DEBUG PMTUD started, PLPMTU is now 1280
client  | 0.077 DEBUG PMTUD started with probe size 1380
client  | 0.077 DEBUG [Client c9757c83263bf760] Drop packet number space hs
client  | 0.077 DEBUG [recovery::Loss] Reset loss recovery state for Handshake
client  | 0.077 DEBUG [Client c9757c83263bf760] No preferred address to migrate to
client  | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 193, tv_nsec: 720958485 }
client  | 0.077 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.077 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.077 DEBUG [Client c9757c83263bf760] pn=1 type=Short pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, Ect0) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1313 }
client  | 0.077 DEBUG packet_sent this=0x55e6e45d7d40, pn=1, ps=1352
client  | 0.077 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG ECN probing: sent 1 probes
client  | 0.077 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client c9757c83263bf760] delay duration 19.591558ms
client  | 0.077 DEBUG Setting timeout of 19.591558ms
client  | 0.077 DEBUG stream 0 complete
client  | 0.077 DEBUG stream BiDi creatable
client  | 0.077 DEBUG stream UniDi creatable
client  | 0.077 WARN Unhandled event StateChange(Confirmed)
client  | 0.077 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client c9757c83263bf760] delay duration 19.551744ms
client  | 0.077 DEBUG Setting timeout of 19.551744ms
client  | 0.078 DEBUG [Client c9757c83263bf760] pn=1 type=Short pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->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  | 0.078 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 193, tv_nsec: 701600219 }
client  | 0.078 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.078 DEBUG [Client c9757c83263bf760] pn=2 type=Short pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->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  | 0.078 DEBUG ECN probing: sent 2 probes
client  | 0.078 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG [Client c9757c83263bf760] delay duration 111.582751ms
client  | 0.078 DEBUG Setting timeout of 111.582751ms
client  | 0.078 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG [Client c9757c83263bf760] delay duration 111.541815ms
client  | 0.078 DEBUG Setting timeout of 111.541815ms
client  | 0.078 DEBUG [Client c9757c83263bf760] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 194, tv_nsec: 37700824 } }
client  | 0.078 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 194, tv_nsec: 37700824 } })
client  | 0.078 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.078 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 193, tv_nsec: 701750559 }
client  | 0.078 DEBUG [Client c9757c83263bf760] pn=3 type=Short pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, Ect0) len 54
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 18, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.078 DEBUG ECN probing: sent 3 probes
client  | 0.078 DEBUG Setting timeout of 335.899721ms
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: fd:6 n:1352
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx short flags:5c
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:40
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:1 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:1352 via sock seq:0 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:3189 rx:5264 valid:1 st:2 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx app:1 PING
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx app:1 PADDING
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1 largest 0 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:1 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223717
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:29930 pto:78
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 34
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 25:193742
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:29930 push:25 pto:78
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 25
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: fd:6 n:43
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx short flags:69
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:60
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:2 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:43 via sock seq:0 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:3189 rx:5307 valid:1 st:2 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx app:2 ACK n:0 delay:3 1-0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:3
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion ack idle t:193717 win:12000 if:738
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_drop_ack_ranges pn:0 largest:1 fr:1 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 ack len:315 fin:0 unacked:709
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion ack idle t:193717 win:12000 if:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 ack len:709 fin:0 unacked:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 ack len:0 fin:1 unacked:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic stream id:0x0 close
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 reusable connection: 1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic updated largest received ack:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic rtt sample latest:34 min:34 avg:36 var:11
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 193795
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer unset
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:2 largest 1 fr:0 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:2 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223717
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: read:29930 push:25
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BD08
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:2 ACK n:0 delay:0 2-1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame tx app:2 MAX_STREAMS limit:129 bidi:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet tx app bytes:8 need_ack:1 number:2 encoded nl:1 trunc:0x2
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 sendmsg: 26 of 26
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion send if:26
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223717
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer pto:100
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 100:193817
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: send:29930 push:25 pto:100
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 25
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: fd:6 n:54
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx short flags:71
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:60
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:3 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:54 via sock seq:0 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:3215 rx:5361 valid:1 st:2 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx app:3 ACK n:0 delay:18 1-0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:3
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 193817
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic lost timer pto:99
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 99:193817
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic frame rx app:3 CONNECTION_CLOSE_APP err:0 kthxbye!
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 post event 0000557ABE86BDC8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:3 largest 2 fr:1 nranges:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:3 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223718
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: draining read:29929 push:24 pto:99
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 1
server  | 2025/12/13 12:50:28 [debug] 22#0: posted event 0000557ABE86BDC8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 delete posted event 0000557ABE86BDC8
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic close handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic close initiated rc:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic close immediate term:0 drain:1 error:0 ""
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer add: 6: 300:194018
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 193742
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 193817
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer del: 6: 193783
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 300
client  | 0.109 DEBUG [Client c9757c83263bf760] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG Building Short dcid Some(CID [20]: 00000000000000015bbc2428898f33a11e8a99dc)
client  | 0.109 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 193, tv_nsec: 733195572 }
client  | 0.109 DEBUG [Client c9757c83263bf760] pn=4 type=Short pri-path:00000000000000015bbc2428898f33a11e8a99dc 0.0.0.0:54449->193.167.100.100:443 Tos(Cs0, Ect0) len 55
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 3949, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.109 DEBUG ECN probing: sent 4 probes
client  | 0.109 DEBUG Setting timeout of 304.428148ms
client  | 0.109 DEBUG Setting timeout of 304.415084ms
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB273466108
server  | 2025/12/13 12:50:28 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic recvmsg: fd:6 n:55
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx short flags:69
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000015bbc2428898f33a11e8a99dc
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx clearflags:60
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet rx number:4 len:1
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet len:55 via sock seq:0 path seq:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic path seq:0 status tx:3215 rx:5416 valid:1 st:2 mtu:1200
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:4 perr:0
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 event timer: 6, old: 223647, new: 223748
server  | 2025/12/13 12:50:28 [debug] 22#0: *2 quic state: closing draining read:29899 close:270
server  | 2025/12/13 12:50:28 [debug] 22#0: timer delta: 30
server  | 2025/12/13 12:50:28 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:28 [debug] 22#0: epoll timer: 270
server  | 2025/12/13 12:50:29 [debug] 22#0: timer delta: 270
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 event timer del: 6: 194018
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic close handler
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic close resumed rc:0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:0 closed nsock:7
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:1 closed nsock:6
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:2 closed nsock:5
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:3 closed nsock:4
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:4 closed nsock:3
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:5 closed nsock:2
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:6 closed nsock:1
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic socket seq:7 closed nsock:0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic close completed
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 quic ngx_quic_send_alert() level:3 alert:0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 SSL_shutdown: 1
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 event timer del: 6: 223647
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 reusable connection: 0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 run cleanup: 0000557ABE8611A8
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 event timer del: -1: 268683
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE8B7410
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE861D80
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE85FFE0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE86C0E0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE86B700
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE883A50
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE881AD0, unused: 0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE883F40, unused: 0
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE86C9D0, unused: 40
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE86CBE0, unused: 18
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE85FDD0, unused: 16
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE8B8420, unused: 112
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE860FF0, unused: 48
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE861200, unused: 72
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE861410, unused: 56
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE85F430, unused: 72
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE85F640, unused: 72
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE85F850, unused: 72
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE85FA60, unused: 32
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE8B9860, unused: 80
server  | 2025/12/13 12:50:29 [debug] 22#0: *2 free: 0000557ABE8BDA80, unused: 48
server  | 2025/12/13 12:50:29 [debug] 22#0: worker cycle
server  | 2025/12/13 12:50:29 [debug] 22#0: epoll timer: -1
client  | 0.415 DEBUG [Client c9757c83263bf760] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 194, tv_nsec: 37700824 } } -> Closed(Application(0))
client  | 0.415 INFO Closing timer expired
client  | 0.415 DEBUG Output::None
client  | 0.415 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 0.415 DEBUG Timer fired while closed
client  | 0.415 DEBUG Output::None

client exited with code 0
Aborting on container exit...
 Container server  Stopping
 Container client  Stopping
 Container client  Stopped
 Container server  Stopped
 Container sim  Stopping
 Container sim  Stopped

2025-12-13 12:50:39,696 Using the client's key log file.
2025-12-13 12:50:39,705 
2025-12-13 12:50:39,705 Using the client's key log file.
2025-12-13 12:50:39,705 Using the client's key log file.
2025-12-13 12:50:39,712 
2025-12-13 12:50:39,713 Using the client's key log file.
2025-12-13 12:50:39,713 Using selector: EpollSelector
2025-12-13 12:50:39,931 Check of downloaded files succeeded.