2025-12-13 12:18:47,183 Generated random file: dry-envious-doctor of size: 1024
2025-12-13 12:18:47,183 Requests: https://server4:443/dry-envious-doctor
2025-12-13 12:18:47,249 
2025-12-13 12:18:47,249 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_poyjok95/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_grcbzqos/ DOWNLOADS=/tmp/download_mz4u84wq/ SERVER_LOGS=/tmp/logs_server_rdutd4kr CLIENT_LOGS=/tmp/logs_client_j31c_ghd SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/ngtcp2/ngtcp2-interop:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/dry-envious-doctor"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-12-13 12:18:59,554  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
client  | Endpoint's IPv4 address is 193.167.0.100
server  | >>> Starting nginx server...
client  | Endpoint's IPv6 address is fd00:cafe:cafe::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
server  | 2025/12/13 12:18:47 [debug] 21#0: bind() 0.0.0.0:443 #5 
server  | 2025/12/13 12:18:47 [debug] 21#0: bind() 0.0.0.0:443 #6 
server  | 2025/12/13 12:18:47 [debug] 21#0: bind() [::]:443 #7 
server  | 2025/12/13 12:18:47 [debug] 21#0: bind() [::]:443 #8 
server  | 2025/12/13 12:18:47 [debug] 21#0: add cleanup: 00005566384721C0
server  | 2025/12/13 12:18:47 [notice] 21#0: using the "epoll" event method
server  | 2025/12/13 12:18:47 [debug] 21#0: counter: 00007FA50F8C7080, 1
server  | 2025/12/13 12:18:47 [notice] 21#0: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f)
server  | 2025/12/13 12:18:47 [notice] 21#0: built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) 
server  | 2025/12/13 12:18:47 [notice] 21#0: OS: Linux 6.11.0-1018-azure
server  | 2025/12/13 12:18:47 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 65536:65536
server  | 2025/12/13 12:18:47 [debug] 21#0: write: 9, 00007FFF4FBEE820, 3, 0
server  | 2025/12/13 12:18:47 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf"
server  | 2025/12/13 12:18:47 [notice] 21#0: start worker processes
server  | 2025/12/13 12:18:47 [debug] 21#0: channel 3:9
server  | 2025/12/13 12:18:47 [notice] 21#0: start worker process 22
server  | 2025/12/13 12:18:47 [debug] 21#0: sigsuspend
server  | 2025/12/13 12:18:47 [debug] 22#0: add cleanup: 0000556638472248
server  | 2025/12/13 12:18:47 [debug] 22#0: malloc: 0000556638474A30:16
server  | 2025/12/13 12:18:47 [debug] 22#0: add cleanup: 0000556638472260
server  | 2025/12/13 12:18:47 [debug] 22#0: malloc: 0000556638473A20:16
server  | 2025/12/13 12:18:47 [debug] 22#0: notify eventfd: 11
server  | 2025/12/13 12:18:47 [debug] 22#0: testing the EPOLLRDHUP flag: success
server  | 2025/12/13 12:18:47 [debug] 22#0: malloc: 000055663845AEF0:6144
server  | 2025/12/13 12:18:47 [debug] 22#0: malloc: 00007FA50F1EB010:253952
server  | 2025/12/13 12:18:47 [debug] 22#0: malloc: 0000556638478390:98304
server  | 2025/12/13 12:18:47 [debug] 22#0: malloc: 00005566384903A0:98304
server  | 2025/12/13 12:18:47 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001
server  | 2025/12/13 12:18:47 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001
server  | 2025/12/13 12:18:47 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001
server  | 2025/12/13 12:18:47 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001
server  | 2025/12/13 12:18:47 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001
server  | 2025/12/13 12:18:47 [debug] 22#0: setproctitle: "nginx: worker process"
server  | 2025/12/13 12:18:47 [debug] 22#0: worker cycle
server  | 2025/12/13 12:18:47 [debug] 22#0: epoll timer: -1
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | 2025/12/13 12:18:48 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FA50F1EB108
server  | 2025/12/13 12:18:48 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:18:48 [debug] 22#0: posix_memalign: 0000556638472AD0:512 @16
server  | 2025/12/13 12:18:48 [debug] 22#0: malloc: 0000556638474A50:1207
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:35623 fd:6 n:1207
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic run
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic packet rx dcid len:0 
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic packet rx scid len:0 
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic address validation token len:0 
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 sending version negotiation packet
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 sendmsg: 11 of 11
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 reusable connection: 0
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 free: 0000556638474A50
server  | 2025/12/13 12:18:48 [debug] 22#0: *1 free: 0000556638472AD0, unused: 16
server  | 2025/12/13 12:18:48 [debug] 22#0: timer delta: 325
server  | 2025/12/13 12:18:48 [debug] 22#0: worker cycle
server  | 2025/12/13 12:18:48 [debug] 22#0: epoll timer: -1
sim     | server:443 is available after 502.134889ms
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | Capturing on 'eth1'
sim     | Capturing on 'eth0'
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
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FA50F1EB108
server  | 2025/12/13 12:18:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: posix_memalign: 0000556638472AD0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: malloc: 0000556638474A50:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic recvmsg: 193.167.0.100:37433 fd:6 n:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic run
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx long flags:c5 version:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx init len:1153
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx dcid len:18 ce3a7929dd0c6ce9912974fee6acc3ac9df5
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx scid len:17 829670be09a2e780ac1f8ff5f5d95bfa9e
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 malloc: 000055663845C700:2512
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 malloc: 000055663845D0E0:1528
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638474F10:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_set_initial_secret
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:0 listening at sid:00000000000000011f5e776643df450cbaa18d8b nsock:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic cid seq:0 received id:17:829670be09a2e780ac1f8ff5f5d95bfa9e:00000000000000000000000000000000
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 000055663845D9D0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic path seq:0 created addr:193.167.0.100:37433
server  | 2025/12/13 12:18:49 [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:18:49 [debug] 22#0: *2 posix_memalign: 000055663845DBE0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:-1 listening at sid:ce3a7929dd0c6ce9912974fee6acc3ac9df5 nsock:2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 reusable connection: 1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic connection created
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx clearflags:c3
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx number:1259541624 len:4
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet len:1200 via sock seq:0 path seq:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic path seq:0 status tx:0 rx:1200 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stateless reset token ae6c57486592ad910d42f23a0ca8951b
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:5 off:249
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638450D60:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 malloc: 0000556638450F70:4096
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:28 off:28
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:5 off:224
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638451F80:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:28 off:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:5 off:244
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 PADDING
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:5 off:229
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:11 off:254
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:56 off:168
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:56 off:56
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:56 off:112
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 CRYPTO len:10 off:234
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL server name: "server4"
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL ALPN supported by client: hq-interop
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL ALPN selected: hq-interop
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic SSL_get_peer_quic_transport_params(): params_len:59
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic unknown transport param id:0x2ab2, skipped
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic unknown transport param id:0x11, skipped
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic transport parameters parsed ok
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp disable active migration: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp idle_timeout:30000
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp max_udp_payload_size:65527
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp max_data:25165824
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp max_stream_data_bidi_local:16777216
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp max_stream_data_bidi_remote:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp max_stream_data_uni:16777216
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp initial_max_streams_bidi:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp initial_max_streams_uni:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp ack_delay_exponent:3
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp max_ack_delay:25
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp active_connection_id_limit:7
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic tp initial source_connection_id len:17 829670be09a2e780ac1f8ff5f5d95bfa9e
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 malloc: 0000556638452B40:4096
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638453B50:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 post event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 malloc: 00005566384A83B0:4096
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:3
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_flush_flight()
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 PING
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541624 PADDING
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1259541624 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:1259541624 perr:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 30000:195102
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 60000:225102
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: read:30000 close:60000
server  | 2025/12/13 12:18:49 [debug] 22#0: timer delta: 782
server  | 2025/12/13 12:18:49 [debug] 22#0: posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 delete posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic output init packet max:1200 min:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx init:0 ACK n:0 delay:0 1259541624
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx init:0 CRYPTO len:90 off:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet tx init bytes:106 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic output hs packet max:1030 min:1030
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx hs:0 CRYPTO len:726 off:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet tx hs bytes:967 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer: 6, old: 195102, new: 195102
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic lost timer pto:997
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 997:166099
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: send:30000 pto:997 close:60000
server  | 2025/12/13 12:18:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll timer: 997
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FA50F1EB108
server  | 2025/12/13 12:18:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic recvmsg: fd:6 n:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx long flags:c4 version:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx init len:22
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000011f5e776643df450cbaa18d8b
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx scid len:17 829670be09a2e780ac1f8ff5f5d95bfa9e
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx number:1259541625 len:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet len:1200 via sock seq:0 path seq:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic path seq:0 status tx:1200 rx:2400 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx init:1259541625 ACK n:0 delay:0 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion ack idle t:165138 win:12000 if:1030
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_drop_ack_ranges pn:1259541624 largest:1259541624 fr:0 nranges:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 post event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic updated largest received ack:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic rtt sample latest:36 min:36 avg:36 var:18
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 166099
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic lost timer pto:72
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 72:165210
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1259541625 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:1259541625 perr:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx long flags:e8 version:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx hs len:64
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000011f5e776643df450cbaa18d8b
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx scid len:17 829670be09a2e780ac1f8ff5f5d95bfa9e
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx clearflags:e3
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx number:1259541624 len:4
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:-1 closed nsock:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 165210
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic lost timer pto:72
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 72:165210
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic path seq:0 in handshake tx:1200 rx:2400 valid:1 st:0 mtu:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx hs:1259541624 ACK n:0 delay:0 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion ack idle t:165138 win:12000 if:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic updated largest received ack:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic rtt sample latest:36 min:36 avg:36 var:14
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 165210
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic lost timer unset
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx hs:1259541624 CRYPTO len:36 off:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:3
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_flush_flight()
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 SSL_do_handshake: 1
server  | 2025/12/13 12:18:49 [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:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 post event 000055663845CE88
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic lost timer unset
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic path seq:0 schedule mtu:2400
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 100:165238
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic create sockets has:1 max:7
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:1 listening at sid:00000000000000014c7e3d6ef00d9df29f4babba nsock:2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stateless reset token edc35c5be92bbdf702da273222820c3e
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638450430:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:2 listening at sid:0000000000000001cbfd471583b1f8c149cb4885 nsock:3
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stateless reset token 1178af77f68e09f1308c89d14974aa02
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638450640:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:3 listening at sid:0000000000000001afd849b0c0889734639a40d6 nsock:4
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stateless reset token d9836e38cd9e25a55949167fede9ce59
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638450850:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:4 listening at sid:0000000000000001d000b68e605bc7c4a3bac0b5 nsock:5
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stateless reset token fd7082ced58d0f0cad676cf50c38242a
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 0000556638450A60:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:5 listening at sid:0000000000000001600e267fb7c75807c7b020de nsock:6
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stateless reset token 307bce3c227c07ef5dc9a5283adaec08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 00005566384AA5A0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic socket seq:6 listening at sid:00000000000000012647638e3fd770df6201ba7c nsock:7
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stateless reset token 41a4978a88a173af8ddb84afde22f17a
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic init streams
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 http3 init
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 http3 init session
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 00005566384AA7B0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 add cleanup: 0000556638453D20
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 75000:240138
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 225102
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1259541624 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet done rc:0 level:hs decr:1 pn:1259541624 perr:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx short flags:58
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000011f5e776643df450cbaa18d8b
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx clearflags:43
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx number:1259541624 len:4
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx app:1259541624 NEW_CONNECTION_ID seq:1 retire:0 len:17
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic cid seq:1 received id:17:27735db814a4959d1bc3de0fc404f59150:ac9b572e5e55101042ac8b69eb11c864
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx app:1259541624 STREAM id:0x0 len:25 fin:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 is missing
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 create
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 00005566384AA9C0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 00005566384AABD0:16384 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 reusable connection: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 add cleanup: 00005566384AAC90
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 post event 00005566384785D0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 delete posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 post event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 flow control msd:25/65536 md:25/8585216
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx app:1259541624 PADDING
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1259541624 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:1259541624 perr:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer: 6, old: 195102, new: 195138
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: read:29964
server  | 2025/12/13 12:18:49 [debug] 22#0: timer delta: 36
server  | 2025/12/13 12:18:49 [debug] 22#0: posted event 000055663845CE88
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 delete posted event 000055663845CE88
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic key update
server  | 2025/12/13 12:18:49 [debug] 22#0: posted event 00005566384785D0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 delete posted event 00005566384785D0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 quic init stream
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http3 init request stream
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 add cleanup: 00005566384AAD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 240138
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http wait request handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 recv buf:1024
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 quic stream id:0x0 recv len:25
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 flow update 25
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 reusable connection: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 posix_memalign: 00005566384AEBE0:4096 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http process request line
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http request line: "GET /dry-envious-doctor"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http uri: "/dry-envious-doctor"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http args: ""
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http exten: ""
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 rewrite phase: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 test location: "/"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 using configuration "/"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http cl:-1 max:1048576
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 rewrite phase: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 post rewrite phase: 3
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 generic phase: 4
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 generic phase: 5
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 access phase: 6
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 access phase: 7
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 post access phase: 8
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 generic phase: 9
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 generic phase: 10
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 content phase: 11
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 content phase: 12
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 content phase: 13
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http filename: "/www/dry-envious-doctor"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 add cleanup: 00005566384AF928
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http static fd: 3
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http set discard body
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http output filter "/dry-envious-doctor?"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http copy filter: "/dry-envious-doctor?"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 posix_memalign: 00005566384AFBF0:4096 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 read: 3, 00005566384AFC10, 1024, 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http postpone filter "/dry-envious-doctor?" 00005566384AFB40
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 write new buf t:1 f:0 00005566384AFC10, pos 00005566384AFC10, size: 1024 file: 0, size: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http write filter: l:1 f:0 s:1024
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http write filter limit 2097152
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 quic send_chain sent:1024
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 flush limit:16777216
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 flush len:1024 last:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http write filter 0000000000000000
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http copy filter: 0 "/dry-envious-doctor?"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http finalize request: 0, "/dry-envious-doctor?" a:1, c:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http request count:1 blk:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http close request
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 http log handler
server  | 193.167.0.100 - - [13/Dec/2025:12:18:49 +0000] "GET /dry-envious-doctor" 200 1024 "-" "-"
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 run cleanup: 00005566384AF928
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 file cleanup: fd:3
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 free: 00005566384AEBE0, unused: 48
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 free: 00005566384AFBF0, unused: 2917
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 close http connection: 6
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 reusable connection: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 run cleanup: 00005566384AAD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 75000:240138
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 run cleanup: 00005566384AAC90
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 cleanup
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 send shutdown
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 flush limit:16776192
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 00005566384AEBE0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 update posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 flush len:0 last:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *3 free: 00005566384AABD0, unused: 14944
server  | 2025/12/13 12:18:49 [debug] 22#0: posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 delete posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 ACK n:0 delay:0 1259541624
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 CRYPTO len:572 off:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 HANDSHAKE DONE
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:5 retire:0 len:20
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:6 retire:0 len:20
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic split frame now:1029 need:336 shrink:693
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:0 STREAM id:0x0 off:0 len:331
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet tx app bytes:1165 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion idle:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:331 len:693
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:1024 len:0 fin:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet tx app bytes:704 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 sendmsg: 739 of 739
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion send if:1939
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer: 6, old: 195102, new: 195138
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic lost timer pto:117
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 117:165255
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: send:29964 pto:117
server  | 2025/12/13 12:18:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll timer: 100
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FA50F1EB108
server  | 2025/12/13 12:18:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic recvmsg: fd:6 n:1406
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx short flags:5a
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000011f5e776643df450cbaa18d8b
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx clearflags:43
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx number:1259541625 len:4
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet len:1406 via sock seq:0 path seq:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic path seq:0 status tx:3139 rx:3806 valid:1 st:2 mtu:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx app:1259541625 PING
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx app:1259541625 PADDING
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1259541625 largest 1259541624 fr:0 nranges:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 post event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:1259541625 perr:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer: 6, old: 195102, new: 195140
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: read:29962 pto:115
server  | 2025/12/13 12:18:49 [debug] 22#0: timer delta: 2
server  | 2025/12/13 12:18:49 [debug] 22#0: posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 delete posted event 000055663845CD08
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 25:165165
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: read:29962 push:25 pto:115
server  | 2025/12/13 12:18:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll timer: 25
server  | 2025/12/13 12:18:49 [debug] 22#0: timer delta: 25
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 165165
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 posix_memalign: 00005566384AABD0:512 @16
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame tx app:2 ACK n:0 delay:3125 1259541625-1259541624
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet tx app bytes:13 need_ack:0 number:2 encoded nl:1 trunc:0x2
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 sendmsg: 48 of 48
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion send if:1939
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: read:29937 pto:90
server  | 2025/12/13 12:18:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll timer: 73
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FA50F1EB108
server  | 2025/12/13 12:18:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic recvmsg: fd:6 n:42
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx short flags:41
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx dcid len:20 00000000000000011f5e776643df450cbaa18d8b
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx clearflags:40
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet rx number:1259541626 len:1
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet len:42 via sock seq:0 path seq:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic path seq:0 status tx:3187 rx:3848 valid:1 st:2 mtu:1200
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic frame rx app:1259541626 CONNECTION_CLOSE err:0 ft:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 post event 000055663845CDC8
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1259541626 largest 1259541625 fr:1 nranges:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:1259541626 perr:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer: 6, old: 195102, new: 195172
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic state: draining read:29930 pto:83
server  | 2025/12/13 12:18:49 [debug] 22#0: timer delta: 7
server  | 2025/12/13 12:18:49 [debug] 22#0: posted event 000055663845CDC8
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 delete posted event 000055663845CDC8
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic close handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic close initiated rc:0
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic close immediate term:0 drain:1 error:0 ""
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer add: 6: 351:165523
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic stream id:0x0 close
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 post event 000055663845CDC8
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 165255
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 event timer del: 6: 165238
server  | 2025/12/13 12:18:49 [debug] 22#0: posted event 000055663845CDC8
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 delete posted event 000055663845CDC8
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic close handler
server  | 2025/12/13 12:18:49 [debug] 22#0: *2 quic close resumed rc:0
server  | 2025/12/13 12:18:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:18:49 [debug] 22#0: epoll timer: 351

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:18:59,646 Using the client's key log file.
2025-12-13 12:18:59,655 
2025-12-13 12:18:59,656 Using the client's key log file.
2025-12-13 12:18:59,656 Using the client's key log file.
2025-12-13 12:18:59,664 
2025-12-13 12:18:59,665 Using the client's key log file.
2025-12-13 12:18:59,665 Using selector: EpollSelector
2025-12-13 12:18:59,918 Check of downloaded files succeeded.