2025-04-30 09:57:45,722 Generated random file: qsbifdtgbz of size: 1024
2025-04-30 09:57:45,722 Requests: https://server4:443/qsbifdtgbz
2025-04-30 09:57:45,781 
2025-04-30 09:57:45,781 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_o8a94gnt/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_mo545qoy/ DOWNLOADS=/tmp/download_8cud9oeo/ SERVER_LOGS=/tmp/logs_server_umiv33ko CLIENT_LOGS=/tmp/logs_client_w_gxsnfm SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/ngtcp2/ngtcp2-interop:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/qsbifdtgbz"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 09:58:00,856  Container sim  Recreate
 Container sim  Recreated
 Container server  Recreate
 Container client  Recreate
 Container server  Recreated
 Container client  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
server  | Setting up routes...
server  | Actual changes:
server  | tx-checksumming: off
server  | 	tx-checksum-ip-generic: off
server  | 	tx-checksum-sctp: off
server  | tcp-segmentation-offload: off
server  | 	tx-tcp-segmentation: off [requested on]
server  | 	tx-tcp-ecn-segmentation: off [requested on]
server  | 	tx-tcp-mangleid-segmentation: off [requested on]
server  | 	tx-tcp6-segmentation: off [requested on]
server  | tx-udp-segmentation: off [requested on]
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
server  | >>> Starting nginx server...
server  | nginx version: nginx/1.27.0 (9270:d1b8568f3042)
server  | built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) 
server  | built with OpenSSL 1.1.1 (compatible; BoringSSL) (running with BoringSSL)
server  | TLS SNI support enabled
server  | configure arguments: --prefix=/etc/nginx --build=9270:d1b8568f3042 --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 -DNGX_QUIC_DRAFT_VERSION=29 -DNGX_HTTP_V3_HQ=1 -x c' --with-ld-opt='-L/boringssl/build/ssl -L/boringssl/build/crypto'
server  | >>> Parameters: 
server  | >>> Test case: handshake
server  | 2025/04/30 09:57:46 [debug] 21#0: bind() 0.0.0.0:443 #5 
server  | 2025/04/30 09:57:46 [debug] 21#0: bind() 0.0.0.0:443 #6 
server  | 2025/04/30 09:57:46 [debug] 21#0: bind() [::]:443 #7 
server  | 2025/04/30 09:57:46 [debug] 21#0: bind() [::]:443 #8 
server  | 2025/04/30 09:57:46 [debug] 21#0: add cleanup: 000055EAE471E750
server  | 2025/04/30 09:57:46 [notice] 21#0: using the "epoll" event method
server  | 2025/04/30 09:57:46 [debug] 21#0: counter: 00007F5F289B2080, 1
server  | 2025/04/30 09:57:46 [notice] 21#0: nginx/1.27.0 (9270:d1b8568f3042)
server  | 2025/04/30 09:57:46 [notice] 21#0: built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) 
server  | 2025/04/30 09:57:46 [notice] 21#0: OS: Linux 6.11.0-1012-azure
server  | 2025/04/30 09:57:46 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 1048576:1048576
server  | 2025/04/30 09:57:46 [debug] 21#0: write: 9, 00007FFC60EC5CE0, 3, 0
server  | 2025/04/30 09:57:46 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf"
server  | 2025/04/30 09:57:46 [notice] 21#0: start worker processes
server  | 2025/04/30 09:57:46 [debug] 21#0: channel 3:9
server  | 2025/04/30 09:57:46 [notice] 21#0: start worker process 22
server  | 2025/04/30 09:57:46 [debug] 21#0: sigsuspend
server  | 2025/04/30 09:57:46 [debug] 22#0: add cleanup: 000055EAE471E7D8
server  | 2025/04/30 09:57:46 [debug] 22#0: malloc: 000055EAE47217D0:16
server  | 2025/04/30 09:57:46 [debug] 22#0: add cleanup: 000055EAE471E7F0
server  | 2025/04/30 09:57:46 [debug] 22#0: malloc: 000055EAE471FAD0:16
server  | 2025/04/30 09:57:46 [debug] 22#0: notify eventfd: 11
server  | 2025/04/30 09:57:46 [debug] 22#0: testing the EPOLLRDHUP flag: success
server  | 2025/04/30 09:57:46 [debug] 22#0: malloc: 000055EAE4707430:6144
server  | 2025/04/30 09:57:46 [debug] 22#0: malloc: 00007F5F28324010:253952
server  | 2025/04/30 09:57:46 [debug] 22#0: malloc: 000055EAE4723890:98304
server  | 2025/04/30 09:57:46 [debug] 22#0: malloc: 000055EAE473B8A0:98304
server  | 2025/04/30 09:57:46 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001
server  | 2025/04/30 09:57:46 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001
server  | 2025/04/30 09:57:46 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001
server  | 2025/04/30 09:57:46 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001
server  | 2025/04/30 09:57:46 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001
server  | 2025/04/30 09:57:46 [debug] 22#0: setproctitle: "nginx: worker process"
server  | 2025/04/30 09:57:46 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:46 [debug] 22#0: epoll timer: -1
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | 2025/04/30 09:57:47 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:47 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: posix_memalign: 000055EAE471FB30:512 @16
server  | 2025/04/30 09:57:47 [debug] 22#0: malloc: 000055EAE47217F0:1207
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:59820 fd:6 n:1207
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic run
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic packet rx dcid len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic packet rx scid len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic address validation token len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 sending version negotiation packet
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 sendmsg: 11 of 11
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 reusable connection: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 free: 000055EAE47217F0
server  | 2025/04/30 09:57:47 [debug] 22#0: *1 free: 000055EAE471FB30, unused: 24
server  | 2025/04/30 09:57:47 [debug] 22#0: timer delta: 851
server  | 2025/04/30 09:57:47 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:47 [debug] 22#0: epoll timer: -1
server  | 2025/04/30 09:57:47 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:47 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: posix_memalign: 000055EAE471FB30:512 @16
sim     | server:443 is available after 1.049908935s
server  | 2025/04/30 09:57:47 [debug] 22#0: malloc: 000055EAE47217F0:1207
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic recvmsg: 193.167.100.2:59820 fd:6 n:1207
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic run
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic packet rx long flags:c0 version:57414954
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic packet rx dcid len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic packet rx scid len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 sending version negotiation packet
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 sendmsg: 11 of 11
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 quic packet rejected rc:-4, cleanup connection
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 reusable connection: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 free: 000055EAE47217F0
server  | 2025/04/30 09:57:47 [debug] 22#0: *2 free: 000055EAE471FB30, unused: 24
server  | 2025/04/30 09:57:47 [debug] 22#0: timer delta: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:47 [debug] 22#0: epoll timer: -1
server  | 2025/04/30 09:57:47 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:47 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: posix_memalign: 000055EAE471FB30:512 @16
server  | 2025/04/30 09:57:47 [debug] 22#0: malloc: 000055EAE47217F0:1207
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic recvmsg: 193.167.100.2:59820 fd:6 n:1207
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic run
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic packet rx long flags:c0 version:57414954
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic packet rx dcid len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic packet rx scid len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic address validation token len:0 
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 sending version negotiation packet
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 sendmsg: 11 of 11
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 quic packet rejected rc:-4, cleanup connection
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 reusable connection: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 free: 000055EAE47217F0
server  | 2025/04/30 09:57:47 [debug] 22#0: *3 free: 000055EAE471FB30, unused: 24
server  | 2025/04/30 09:57:47 [debug] 22#0: timer delta: 0
server  | 2025/04/30 09:57:47 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:47 [debug] 22#0: epoll timer: -1
sim     | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
server  | 2025/04/30 09:57:48 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:48 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:48 [debug] 22#0: posix_memalign: 000055EAE471FB30:512 @16
server  | 2025/04/30 09:57:48 [debug] 22#0: malloc: 000055EAE47217F0:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic recvmsg: 193.167.0.100:40370 fd:6 n:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic run
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx long flags:c6 version:1
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx init len:1153
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx dcid len:18 688cf33b916fe167394a665b7320982725ac
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx scid len:17 8e0bd84ffe688212950329270ae22d894b
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic address validation token len:0 
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 malloc: 000055EAE4708C40:2448
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 malloc: 000055EAE47095E0:1528
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 posix_memalign: 000055EAE471FD40:512 @16
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_set_initial_secret
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic socket seq:0 listening at sid:0000000000000002483dfa4a9e0b0bd36320541a nsock:1
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic cid seq:0 received id:17:8e0bd84ffe688212950329270ae22d894b:00000000000000000000000000000000
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 posix_memalign: 000055EAE470A1E0:512 @16
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic path seq:0 created addr:193.167.0.100:40370
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic path seq:0 set active tx:0 rx:0 valid:0 st:0 mtu:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 posix_memalign: 000055EAE470A3F0:512 @16
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic socket seq:-1 listening at sid:688cf33b916fe167394a665b7320982725ac nsock:2
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 reusable connection: 1
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic connection created
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx clearflags:c3
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx number:2012645137 len:4
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet len:1200 via sock seq:0 path seq:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic path seq:0 status tx:0 rx:1200 valid:0 st:0 mtu:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic stateless reset token 3e521caff85d90e71b7a4213b03648d0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame rx init:2012645137 CRYPTO len:263 off:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 SSL server name: "server4"
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 SSL ALPN supported by client: hq-interop
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 SSL ALPN selected: hq-interop
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_add_handshake_data
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic SSL_get_peer_quic_transport_params(): params_len:59
server  | 2025/04/30 09:57:48 [info] 22#0: *4 quic unknown transport param id:0x2ab2, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/04/30 09:57:48 [info] 22#0: *4 quic unknown transport param id:0x11, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic transport parameters parsed ok
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp disable active migration: 0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp idle_timeout:30000
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp max_udp_payload_size:65527
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp max_data:25165824
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp max_stream_data_bidi_local:16777216
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp max_stream_data_bidi_remote:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp max_stream_data_uni:16777216
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp initial_max_streams_bidi:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp initial_max_streams_uni:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp ack_delay_exponent:3
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp max_ack_delay:25
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp active_connection_id_limit:7
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic tp initial source_connection_id len:17 8e0bd84ffe688212950329270ae22d894b
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 posix_memalign: 000055EAE46FDE00:512 @16
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 malloc: 000055EAE46FE010:4096
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 post event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_set_write_secret() level:2
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_add_handshake_data
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 malloc: 000055EAE4700370:4096
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 posix_memalign: 000055EAE4701380:512 @16
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_set_write_secret() level:3
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_flush_flight()
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_set_read_secret() level:2
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 SSL_do_handshake: -1
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 SSL_get_error: 2
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame rx init:2012645137 PADDING
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:2012645137 largest -1 fr:0 nranges:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:2012645137 perr:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer add: 6: 30000:301160
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer add: 6: 60000:331160
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic state: read:30000 close:60000
server  | 2025/04/30 09:57:48 [debug] 22#0: timer delta: 1005
server  | 2025/04/30 09:57:48 [debug] 22#0: posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 delete posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic push handler
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic output init packet max:1200 min:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame tx init:0 ACK n:0 delay:0 2012645137
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame tx init:0 CRYPTO len:90 off:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet tx init bytes:106 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic output hs packet max:1030 min:1030
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame tx hs:0 CRYPTO len:727 off:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet tx hs bytes:967 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion send if:170
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer: 6, old: 301160, new: 301160
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic lost timer pto:997
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer add: 6: 997:272157
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic state: send:30000 pto:997 close:60000
server  | 2025/04/30 09:57:48 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:48 [debug] 22#0: epoll timer: 997
server  | 2025/04/30 09:57:48 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:48 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic recvmsg: fd:6 n:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic input handler
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx long flags:cb version:1
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx init len:1153
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx dcid len:18 688cf33b916fe167394a665b7320982725ac
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx scid len:17 8e0bd84ffe688212950329270ae22d894b
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic address validation token len:0 
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx clearflags:c3
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet rx number:2012645138 len:4
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet len:1200 via sock seq:-1 path seq:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic path seq:0 status tx:1200 rx:2400 valid:0 st:0 mtu:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame rx init:2012645138 CRYPTO len:263 off:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic resend packet pnum:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:-1 if:1030
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 post event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic resend packet pnum:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:-1 if:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame rx init:2012645138 PADDING
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:2012645138 largest 2012645137 fr:0 nranges:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:2012645138 perr:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer: 6, old: 301160, new: 301456
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic state: read:29704 pto:701 close:59704
server  | 2025/04/30 09:57:48 [debug] 22#0: timer delta: 296
server  | 2025/04/30 09:57:48 [debug] 22#0: posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 delete posted event 000055EAE4709248
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic push handler
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic output init packet max:1200 min:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame tx init:1 ACK n:0 delay:0 2012645138-2012645137
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame tx init:1 CRYPTO len:90 off:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet tx init bytes:106 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic output hs packet max:1030 min:1030
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic frame tx hs:1 CRYPTO len:727 off:0
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic packet tx hs bytes:967 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion send if:170
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer: 6, old: 301160, new: 301456
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer del: 6: 272157
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic lost timer pto:997
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 event timer add: 6: 997:272453
server  | 2025/04/30 09:57:48 [debug] 22#0: *4 quic state: send:29704 pto:997 close:59704
server  | 2025/04/30 09:57:48 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:48 [debug] 22#0: epoll timer: 997
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic recvmsg: fd:6 n:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic input handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx long flags:ce version:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx init len:1153
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx dcid len:18 688cf33b916fe167394a665b7320982725ac
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx scid len:17 8e0bd84ffe688212950329270ae22d894b
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic address validation token len:0 
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx clearflags:c3
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx number:2012645139 len:4
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet len:1200 via sock seq:-1 path seq:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 status tx:2400 rx:3600 valid:0 st:0 mtu:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx init:2012645139 CRYPTO len:263 off:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic resend packet pnum:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion lost win:131054 ss:131054 if:1030
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 post event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic resend packet pnum:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:131054 if:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx init:2012645139 PADDING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:2012645139 largest 2012645138 fr:1 nranges:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:2012645139 perr:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 301160
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 30000:302057
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic state: read:30000 pto:396 close:59103
server  | 2025/04/30 09:57:49 [debug] 22#0: timer delta: 601
server  | 2025/04/30 09:57:49 [debug] 22#0: posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 delete posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic push handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic output init packet max:1200 min:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx init:2 ACK n:0 delay:0 2012645139-2012645137
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx init:2 CRYPTO len:90 off:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx init bytes:106 need_ack:1 number:2 encoded nl:1 trunc:0x2
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic output hs packet max:1030 min:1030
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx hs:2 CRYPTO len:727 off:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx hs bytes:967 need_ack:1 number:2 encoded nl:1 trunc:0x2
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:170
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer: 6, old: 302057, new: 302057
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 272453
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic lost timer pto:997
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 997:273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic state: send:30000 pto:997 close:59103
server  | 2025/04/30 09:57:49 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll timer: 997
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic recvmsg: fd:6 n:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic input handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx long flags:cb version:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx init len:22
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000000002483dfa4a9e0b0bd36320541a
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx scid len:17 8e0bd84ffe688212950329270ae22d894b
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic address validation token len:0 
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx clearflags:c0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx number:2012645140 len:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet len:1200 via sock seq:0 path seq:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 status tx:3600 rx:4800 valid:0 st:0 mtu:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx init:2012645140 ACK n:0 delay:0 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_handle_ack_frame level:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic updated largest received ack:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic lost timer pto:387
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 387:273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:2012645140 largest 2012645139 fr:2 nranges:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:2012645140 perr:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx long flags:e8 version:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx hs len:64
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000000002483dfa4a9e0b0bd36320541a
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx scid len:17 8e0bd84ffe688212950329270ae22d894b
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx clearflags:e3
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx number:2012645137 len:4
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion ack recovery win:131054 ss:131054 if:1030
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic socket seq:-1 closed nsock:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic lost timer pto:387
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 387:273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 in handshake tx:3600 rx:4800 valid:1 st:0 mtu:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 post event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx hs:2012645137 ACK n:0 delay:0 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_handle_ack_frame level:2
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic updated largest received ack:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic lost timer pto:387
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 387:273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx hs:2012645137 CRYPTO len:36 off:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_set_read_secret() level:3
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_add_handshake_data
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_flush_flight()
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 SSL_do_handshake: 1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 SSL: TLSv1.3, cipher: "TLS_AES_128_GCM_SHA256 Kx=GENERIC Au=GENERIC Enc=AESGCM(128) Mac=AEAD"
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 post event 000055EAE47093C8
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion ack recovery win:131054 ss:131054 if:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 273054
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic lost timer unset
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 schedule mtu:2400
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 100:272767
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic create sockets has:1 max:7
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic socket seq:1 listening at sid:000000000000000263c8339429a8a522640b29e1 nsock:2
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stateless reset token d5d31f91874a8cace6c68757bd0a4dd0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE46FD250:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic socket seq:2 listening at sid:000000000000000252d21d58f198fbf9a4f35160 nsock:3
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stateless reset token 88e93682a0daa90ff326a91384869645
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE46FD460:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic socket seq:3 listening at sid:00000000000000026c80d34dbf19cc94f1a2dcd8 nsock:4
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stateless reset token 9550b2a3bbcf8c6eb6c0cd9d60a3be84
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE46FD670:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic socket seq:4 listening at sid:000000000000000243feab18e16ead5d17e887b5 nsock:5
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stateless reset token 02c44867539674d124e0526c0589d694
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE4754550:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic socket seq:5 listening at sid:000000000000000242c31927de4fb07f4b999bdc nsock:6
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stateless reset token 926ffbf795046e3789c21b35de8ff144
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE4754760:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic socket seq:6 listening at sid:000000000000000284766542471b234727192a14 nsock:7
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stateless reset token e7384102bc09dc9b30d8f0185e097518
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic init streams
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 http3 init
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 http3 init session
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE4754970:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 add cleanup: 000055EAE4701550
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 75000:347667
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 331160
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:2012645137 largest -1 fr:0 nranges:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet done rc:0 level:hs decr:1 pn:2012645137 perr:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx short flags:4f
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000000002483dfa4a9e0b0bd36320541a
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx clearflags:43
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx number:2012645137 len:4
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx app:2012645137 NEW_CONNECTION_ID seq:1 retire:0 len:17
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic cid seq:1 received id:17:b0ff3f9b5abcb53d1537c3b194a1c82c3a:d1b9f36537b4e568f34653114c039371
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx app:2012645137 STREAM id:0x0 len:17 fin:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 is missing
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 create
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE4754B80:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE4754D90:16384 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 reusable connection: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 add cleanup: 000055EAE4754E50
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 post event 000055EAE4723AD0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 delete posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 post event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 flow control msd:17/65536 md:17/8585216
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx app:2012645137 PADDING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:2012645137 largest -1 fr:0 nranges:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet done rc:0 level:app decr:1 pn:2012645137 perr:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 302057
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 30000:302667
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic state: read:30000
server  | 2025/04/30 09:57:49 [debug] 22#0: timer delta: 610
server  | 2025/04/30 09:57:49 [debug] 22#0: posted event 000055EAE47093C8
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 delete posted event 000055EAE47093C8
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic key update
server  | 2025/04/30 09:57:49 [debug] 22#0: posted event 000055EAE4723AD0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 delete posted event 000055EAE4723AD0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 quic init stream
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http3 init request stream
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 add cleanup: 000055EAE4754EC0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 347667
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http wait request handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 recv buf:1024
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 quic stream id:0x0 recv len:17
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 flow update 17
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 reusable connection: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 posix_memalign: 000055EAE4758DA0:4096 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http process request line
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http request line: "GET /qsbifdtgbz"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http uri: "/qsbifdtgbz"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http args: ""
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http exten: ""
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 rewrite phase: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 test location: "/"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 using configuration "/"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http cl:-1 max:1048576
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 rewrite phase: 2
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 post rewrite phase: 3
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 generic phase: 4
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 generic phase: 5
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 access phase: 6
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 access phase: 7
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 post access phase: 8
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 generic phase: 9
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 generic phase: 10
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 content phase: 11
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 content phase: 12
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 content phase: 13
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http filename: "/www/qsbifdtgbz"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 add cleanup: 000055EAE4759AD8
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http static fd: 3
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http set discard body
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http output filter "/qsbifdtgbz?"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http copy filter: "/qsbifdtgbz?"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 posix_memalign: 000055EAE4759DB0:4096 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 read: 3, 000055EAE4759DD0, 1024, 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http postpone filter "/qsbifdtgbz?" 000055EAE4759CF0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 write new buf t:1 f:0 000055EAE4759DD0, pos 000055EAE4759DD0, size: 1024 file: 0, size: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http write filter: l:1 f:0 s:1024
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http write filter limit 2097152
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 malloc: 000055EAE475ADC0:4096
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 quic send_chain sent:1024
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 flush limit:16777216
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 flush len:1024 last:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http write filter 0000000000000000
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http copy filter: 0 "/qsbifdtgbz?"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http finalize request: 0, "/qsbifdtgbz?" a:1, c:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http request count:1 blk:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http close request
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 http log handler
server  | 193.167.0.100 - - [30/Apr/2025:09:57:49 +0000] "GET /qsbifdtgbz" 200 1024 "-" "-"
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 run cleanup: 000055EAE4759AD8
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 file cleanup: fd:3
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 free: 000055EAE4758DA0, unused: 64
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 free: 000055EAE4759DB0, unused: 2925
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 close http connection: 6
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 reusable connection: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 run cleanup: 000055EAE4754EC0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 75000:347667
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 run cleanup: 000055EAE4754E50
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 cleanup
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 send shutdown
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 flush limit:16776192
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE4758DA0:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 update posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic stream id:0x0 flush len:0 last:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *5 free: 000055EAE4754D90, unused: 14952
server  | 2025/04/30 09:57:49 [debug] 22#0: posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 delete posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic push handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic output app packet max:1200 min:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 ACK n:0 delay:0 2012645137
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 CRYPTO len:572 off:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 HANDSHAKE DONE
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:5 retire:0 len:20
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:6 retire:0 len:20
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic split frame now:1029 need:336 shrink:693
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:0 STREAM id:0x0 off:0 len:331
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx app bytes:1165 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic output app packet max:1200 min:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:1 STREAM id:0x0 off:331 len:693
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:1 STREAM id:0x0 off:1024 len:0 fin:1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx app bytes:704 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 sendmsg: 739 of 739
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1939
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer: 6, old: 302667, new: 302667
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic lost timer pto:1022
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 1022:273689
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic state: send:30000 pto:1022
server  | 2025/04/30 09:57:49 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll timer: 100
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic recvmsg: fd:6 n:1406
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic input handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx short flags:41
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000000002483dfa4a9e0b0bd36320541a
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx clearflags:43
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet rx number:2012645138 len:4
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet len:1406 via sock seq:0 path seq:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 status tx:5539 rx:6206 valid:1 st:2 mtu:1200
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx app:2012645138 PING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame rx app:2012645138 PADDING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:2012645138 largest 2012645137 fr:0 nranges:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 post event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet done rc:0 level:app decr:1 pn:2012645138 perr:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer: 6, old: 302667, new: 302668
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic state: read:29999 pto:1021
server  | 2025/04/30 09:57:49 [debug] 22#0: timer delta: 1
server  | 2025/04/30 09:57:49 [debug] 22#0: posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 delete posted event 000055EAE4709248
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic push handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 25:272693
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic state: read:29999 push:25 pto:1021
server  | 2025/04/30 09:57:49 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll timer: 25
server  | 2025/04/30 09:57:49 [debug] 22#0: timer delta: 25
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 272693
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic push handler
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 posix_memalign: 000055EAE4758FB0:512 @16
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic output app packet max:1200 min:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:2 ACK n:0 delay:3125 2012645138-2012645137
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx app bytes:13 need_ack:0 number:2 encoded nl:1 trunc:0x2
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 sendmsg: 48 of 48
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1939
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1939
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:1939
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic state: read:29974 pto:996
server  | 2025/04/30 09:57:49 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll timer: 74
server  | 2025/04/30 09:57:49 [debug] 22#0: timer delta: 75
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 272767
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 send probe mtu:2400 pnum:3 tries:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic sendto app packet max:2400 min:2400
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:3 PING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx app bytes:2365 need_ack:0 number:3 encoded nl:1 trunc:0x3
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 rejected mtu:2400
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 send probe mtu:1800 pnum:4 tries:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic sendto app packet max:1800 min:1800
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:4 PING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx app bytes:1765 need_ack:0 number:4 encoded nl:1 trunc:0x4
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 rejected mtu:1800
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 send probe mtu:1500 pnum:5 tries:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic sendto app packet max:1500 min:1500
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:5 PING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx app bytes:1465 need_ack:0 number:5 encoded nl:1 trunc:0x5
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 rejected mtu:1500
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic path seq:0 send probe mtu:1350 pnum:6 tries:0
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic sendto app packet max:1350 min:1350
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic frame tx app:6 PING
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic packet tx app bytes:1315 need_ack:0 number:6 encoded nl:1 trunc:0x6
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 sendmsg: 1350 of 1350
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic congestion send if:3289
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer: 6, old: 302667, new: 302768
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer del: 6: 273689
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 quic lost timer pto:1022
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 1022:273790
server  | 2025/04/30 09:57:49 [debug] 22#0: *4 event timer add: 6: 1022:273790
server  | 2025/04/30 09:57:49 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:49 [debug] 22#0: epoll timer: 1022
server  | 2025/04/30 09:57:50 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5F28324108
server  | 2025/04/30 09:57:50 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 09:57:50 [debug] 22#0: *4 quic recvmsg: fd:6 n:73
server  | 2025/04/30 09:57:50 [debug] 22#0: *4 quic input handler
server  | 2025/04/30 09:57:50 [debug] 22#0: *4 quic packet rx long flags:ee version:1
server  | 2025/04/30 09:57:50 [debug] 22#0: *4 quic packet rx hs len:25
server  | 2025/04/30 09:57:50 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000000002483dfa4a9e0b0bd36320541a
server  | 2025/04/30 09:57:50 [debug] 22#0: *4 quic packet rx scid len:17 8e0bd84ffe688212950329270ae22d894b
server  | 2025/04/30 09:57:50 [info] 22#0: *4 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/04/30 09:57:50 [debug] 22#0: *4 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0
server  | 2025/04/30 09:57:50 [debug] 22#0: timer delta: 190
server  | 2025/04/30 09:57:50 [debug] 22#0: worker cycle
server  | 2025/04/30 09:57:50 [debug] 22#0: epoll timer: 832

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-04-30 09:58:00,956 Using the client's key log file.
2025-04-30 09:58:00,964 
2025-04-30 09:58:00,965 Using the client's key log file.
2025-04-30 09:58:00,965 Using the client's key log file.
2025-04-30 09:58:00,973 
2025-04-30 09:58:00,973 Using the client's key log file.
2025-04-30 09:58:01,265 Check of downloaded files succeeded.
2025-04-30 09:58:02,416 Check of downloaded files succeeded.