2025-04-30 12:21:20,514 Generated random file: qcyurodvyj of size: 1024
2025-04-30 12:21:20,514 Requests: https://server4:443/qcyurodvyj
2025-04-30 12:21:20,573 
2025-04-30 12:21:20,574 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_uzkvnulb/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_1t0pf4hs/ DOWNLOADS=/tmp/download_tzclob_7/ SERVER_LOGS=/tmp/logs_server_4ega2zaq CLIENT_LOGS=/tmp/logs_client_o5sgydyt SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=privateoctopus/picoquic:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/qcyurodvyj"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:21:45,293  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container server  Recreated
 Container client  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up the simulation with setup.sh
client  | Setting up routes...
server  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
server  | 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]
client  | Endpoint's IPv4 address is 193.167.0.100
server  | Endpoint's IPv4 address is 193.167.100.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | Setup.sh completed
client  | Waiting for  the simulator to start
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
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | 2025/04/30 12:21:21 [debug] 20#0: bind() 0.0.0.0:443 #5 
server  | 2025/04/30 12:21:21 [debug] 20#0: bind() 0.0.0.0:443 #6 
server  | 2025/04/30 12:21:21 [debug] 20#0: bind() [::]:443 #7 
server  | 2025/04/30 12:21:21 [debug] 20#0: bind() [::]:443 #8 
server  | 2025/04/30 12:21:21 [debug] 20#0: add cleanup: 00005646E6B38750
server  | 2025/04/30 12:21:21 [notice] 20#0: using the "epoll" event method
server  | 2025/04/30 12:21:21 [debug] 20#0: counter: 00007F6D24873080, 1
server  | 2025/04/30 12:21:21 [notice] 20#0: nginx/1.27.0 (9270:d1b8568f3042)
server  | 2025/04/30 12:21:21 [notice] 20#0: built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) 
server  | 2025/04/30 12:21:21 [notice] 20#0: OS: Linux 6.11.0-1012-azure
server  | 2025/04/30 12:21:21 [notice] 20#0: getrlimit(RLIMIT_NOFILE): 1048576:1048576
server  | 2025/04/30 12:21:21 [debug] 20#0: write: 9, 00007FFCF85F3650, 3, 0
server  | 2025/04/30 12:21:21 [debug] 20#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf"
server  | 2025/04/30 12:21:21 [notice] 20#0: start worker processes
server  | 2025/04/30 12:21:21 [debug] 20#0: channel 3:9
server  | 2025/04/30 12:21:21 [notice] 20#0: start worker process 21
server  | 2025/04/30 12:21:21 [debug] 20#0: sigsuspend
server  | 2025/04/30 12:21:21 [debug] 21#0: add cleanup: 00005646E6B387D8
server  | 2025/04/30 12:21:21 [debug] 21#0: malloc: 00005646E6B3B7D0:16
server  | 2025/04/30 12:21:21 [debug] 21#0: add cleanup: 00005646E6B387F0
server  | 2025/04/30 12:21:21 [debug] 21#0: malloc: 00005646E6B39AD0:16
server  | 2025/04/30 12:21:21 [debug] 21#0: notify eventfd: 11
server  | 2025/04/30 12:21:21 [debug] 21#0: testing the EPOLLRDHUP flag: success
server  | 2025/04/30 12:21:21 [debug] 21#0: malloc: 00005646E6B21430:6144
server  | 2025/04/30 12:21:21 [debug] 21#0: malloc: 00007F6D241E5010:253952
server  | 2025/04/30 12:21:21 [debug] 21#0: malloc: 00005646E6B3D890:98304
server  | 2025/04/30 12:21:21 [debug] 21#0: malloc: 00005646E6B558A0:98304
server  | 2025/04/30 12:21:21 [debug] 21#0: epoll add event: fd:5 op:1 ev:00002001
server  | 2025/04/30 12:21:21 [debug] 21#0: epoll add event: fd:6 op:1 ev:00002001
server  | 2025/04/30 12:21:21 [debug] 21#0: epoll add event: fd:7 op:1 ev:00002001
server  | 2025/04/30 12:21:21 [debug] 21#0: epoll add event: fd:8 op:1 ev:00002001
server  | 2025/04/30 12:21:21 [debug] 21#0: epoll add event: fd:9 op:1 ev:00002001
server  | 2025/04/30 12:21:21 [debug] 21#0: setproctitle: "nginx: worker process"
server  | 2025/04/30 12:21:21 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:21 [debug] 21#0: epoll timer: -1
server  | 2025/04/30 12:21:22 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:22 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: posix_memalign: 00005646E6B39B30:512 @16
server  | 2025/04/30 12:21:22 [debug] 21#0: malloc: 00005646E6B3B7F0:1207
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic recvmsg: 193.167.100.2:44117 fd:6 n:1207
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic run
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic packet rx long flags:c0 version:57414954
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic packet rx dcid len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic packet rx scid len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic address validation token len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 sending version negotiation packet
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 sendmsg: 11 of 11
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 quic packet rejected rc:-4, cleanup connection
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 reusable connection: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 free: 00005646E6B3B7F0
server  | 2025/04/30 12:21:22 [debug] 21#0: *1 free: 00005646E6B39B30, unused: 24
server  | 2025/04/30 12:21:22 [debug] 21#0: timer delta: 814
server  | 2025/04/30 12:21:22 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:22 [debug] 21#0: epoll timer: -1
server  | 2025/04/30 12:21:22 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:22 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: posix_memalign: 00005646E6B39B30:512 @16
server  | 2025/04/30 12:21:22 [debug] 21#0: malloc: 00005646E6B3B7F0:1207
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic recvmsg: 193.167.100.2:44117 fd:6 n:1207
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic run
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic packet rx long flags:c0 version:57414954
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic packet rx dcid len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic packet rx scid len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic address validation token len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 sending version negotiation packet
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 sendmsg: 11 of 11
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 quic packet rejected rc:-4, cleanup connection
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 reusable connection: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 free: 00005646E6B3B7F0
server  | 2025/04/30 12:21:22 [debug] 21#0: *2 free: 00005646E6B39B30, unused: 24
server  | 2025/04/30 12:21:22 [debug] 21#0: timer delta: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:22 [debug] 21#0: epoll timer: -1
server  | 2025/04/30 12:21:22 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:22 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: posix_memalign: 00005646E6B39B30:512 @16
server  | 2025/04/30 12:21:22 [debug] 21#0: malloc: 00005646E6B3B7F0:1207
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic recvmsg: 193.167.100.2:44117 fd:6 n:1207
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic run
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic packet rx long flags:c0 version:57414954
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic packet rx dcid len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic packet rx scid len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic address validation token len:0 
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 sending version negotiation packet
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 sendmsg: 11 of 11
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 quic packet rejected rc:-4, cleanup connection
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 reusable connection: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 free: 00005646E6B3B7F0
server  | 2025/04/30 12:21:22 [debug] 21#0: *3 free: 00005646E6B39B30, unused: 24
server  | 2025/04/30 12:21:22 [debug] 21#0: timer delta: 0
server  | 2025/04/30 12:21:22 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:22 [debug] 21#0: epoll timer: -1
sim     | server:443 is available after 1.054303802s
sim     | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | Starting picoquic client for test: handshake
client  | Starting picoquic client ...
client  | Parsing server name from first request: https://server4:443/qcyurodvyj
client  | Server set to: server4
client  | Requests:  https://server4:443/qcyurodvyj
client  | parsing <https://server4:443/qcyurodvyj> as <qcyurodvyj>
client  | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443
client  | No token file present. Will create one as <demo_token_store.bin>.
client  | Testing scenario: <-:/qcyurodvyj;>
client  | Max stream id bidir remote before start = 0 (0)
client  | Starting client connection. Version = 1, I-CID: 1b5c32647e05134
client  | Max stream id bidir remote after start = -4 (0)
client  | Waiting for packets.
server  | 2025/04/30 12:21:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:23 [debug] 21#0: posix_memalign: 00005646E6B39B30:512 @16
server  | 2025/04/30 12:21:23 [debug] 21#0: malloc: 00005646E6B3B7F0:1252
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic recvmsg: 193.167.0.100:47585 fd:6 n:1252
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic run
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx long flags:cd version:1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx init len:1226
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx dcid len:8 01b5c32647e05134
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic address validation token len:0 
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 malloc: 00005646E6B22C40:2448
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 malloc: 00005646E6B235E0:1528
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 posix_memalign: 00005646E6B39D40:512 @16
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_set_initial_secret
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic socket seq:0 listening at sid:000000000000100279f5d71a0970c9e93617b830 nsock:1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic cid seq:0 received id:8:1ad56caa5173cc24:00000000000000000000000000000000
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 posix_memalign: 00005646E6B241E0:512 @16
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic path seq:0 created addr:193.167.0.100:47585
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic path seq:0 set active tx:0 rx:0 valid:0 st:0 mtu:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 posix_memalign: 00005646E6B243F0:512 @16
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic socket seq:-1 listening at sid:01b5c32647e05134 nsock:2
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 reusable connection: 1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic connection created
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx clearflags:c3
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx number:130508 len:4
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet len:1252 via sock seq:0 path seq:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic path seq:0 status tx:0 rx:1252 valid:0 st:0 mtu:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic stateless reset token c25570072d0ddccaf1786a13bac7d8b1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130508 PING
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130508 CRYPTO len:285 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 SSL server name: "server4"
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 SSL ALPN supported by client: hq-interop
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 SSL ALPN selected: hq-interop
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_add_handshake_data
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic SSL_get_peer_quic_transport_params(): params_len:85
server  | 2025/04/30 12:21:23 [info] 21#0: *4 quic reserved transport param id:0x59, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/04/30 12:21:23 [info] 21#0: *4 quic unknown transport param id:0xff04de1b, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/04/30 12:21:23 [info] 21#0: *4 quic unknown transport param id:0x7158, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/04/30 12:21:23 [info] 21#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 12:21:23 [debug] 21#0: *4 quic transport parameters parsed ok
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp disable active migration: 0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp idle_timeout:180000
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp max_udp_payload_size:1440
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp max_data:1048576
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp max_stream_data_bidi_local:2097152
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp max_stream_data_bidi_remote:65635
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp max_stream_data_uni:65535
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp initial_max_streams_bidi:512
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp initial_max_streams_uni:512
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp ack_delay_exponent:3
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp max_ack_delay:10
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp active_connection_id_limit:8
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic tp initial source_connection_id len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 malloc: 00005646E6B18170:4096
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 posix_memalign: 00005646E6B19180:512 @16
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 post event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_set_write_secret() level:2
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_add_handshake_data
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 malloc: 00005646E6B1A640:4096
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_set_write_secret() level:3
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_flush_flight()
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_set_read_secret() level:2
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 SSL_do_handshake: -1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 SSL_get_error: 2
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130508 PADDING
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:130508 largest -1 fr:0 nranges:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet done rc:0 level:init decr:1 pn:130508 perr:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer add: 6: 75000:430078
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer add: 6: 60000:415078
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic state: read:75000 close:60000
server  | 2025/04/30 12:21:23 [debug] 21#0: timer delta: 957
server  | 2025/04/30 12:21:23 [debug] 21#0: posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 delete posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic push handler
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 posix_memalign: 00005646E6B6DE00:512 @16
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic output init packet max:1200 min:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx init:0 ACK n:0 delay:0 130508
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx init:0 CRYPTO len:123 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet tx init bytes:135 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic output hs packet max:1010 min:1010
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx hs:0 CRYPTO len:719 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet tx hs bytes:956 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:190
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer: 6, old: 430078, new: 430078
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic lost timer pto:997
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer add: 6: 997:356075
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic state: send:75000 pto:997 close:60000
server  | 2025/04/30 12:21:23 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:23 [debug] 21#0: epoll timer: 997
server  | 2025/04/30 12:21:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic recvmsg: fd:6 n:1252
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx long flags:ca version:1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx init len:1226
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx dcid len:8 01b5c32647e05134
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic address validation token len:0 
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx clearflags:c3
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx number:130509 len:4
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet len:1252 via sock seq:-1 path seq:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic path seq:0 status tx:1200 rx:2504 valid:0 st:0 mtu:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130509 PING
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130509 CRYPTO len:285 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic resend packet pnum:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion lost recovery win:131054 ss:-1 if:1010
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 post event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic resend packet pnum:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion lost recovery win:131054 ss:-1 if:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130509 PADDING
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:130509 largest 130508 fr:0 nranges:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet done rc:0 level:init decr:1 pn:130509 perr:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer: 6, old: 430078, new: 430324
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic state: read:74754 pto:751 close:59754
server  | 2025/04/30 12:21:23 [debug] 21#0: timer delta: 246
server  | 2025/04/30 12:21:23 [debug] 21#0: posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 delete posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic push handler
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic output init packet max:1200 min:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx init:1 ACK n:0 delay:0 130509-130508
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx init:1 CRYPTO len:123 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet tx init bytes:135 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic output hs packet max:1010 min:1010
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx hs:1 CRYPTO len:719 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet tx hs bytes:956 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:190
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer: 6, old: 430078, new: 430324
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer del: 6: 356075
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic lost timer pto:997
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer add: 6: 997:356321
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic state: send:74754 pto:997 close:59754
server  | 2025/04/30 12:21:23 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:23 [debug] 21#0: epoll timer: 997
server  | 2025/04/30 12:21:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic recvmsg: fd:6 n:1252
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx long flags:cb version:1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx init len:1226
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx dcid len:8 01b5c32647e05134
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic address validation token len:0 
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx clearflags:c3
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet rx number:130510 len:4
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet len:1252 via sock seq:-1 path seq:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic path seq:0 status tx:2400 rx:3756 valid:0 st:0 mtu:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130510 PING
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130510 CRYPTO len:285 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic resend packet pnum:1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion lost win:131054 ss:131054 if:1010
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 post event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic resend packet pnum:1
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion lost recovery win:131054 ss:131054 if:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame rx init:130510 PADDING
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:130510 largest 130509 fr:1 nranges:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet done rc:0 level:init decr:1 pn:130510 perr:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer del: 6: 430078
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer add: 6: 75000:430824
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic state: read:75000 pto:497 close:59254
server  | 2025/04/30 12:21:23 [debug] 21#0: timer delta: 500
server  | 2025/04/30 12:21:23 [debug] 21#0: posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 delete posted event 00005646E6B23248
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic push handler
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic output init packet max:1200 min:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx init:2 ACK n:0 delay:0 130510-130508
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx init:2 CRYPTO len:123 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet tx init bytes:135 need_ack:1 number:2 encoded nl:1 trunc:0x2
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic output hs packet max:1010 min:1010
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic frame tx hs:2 CRYPTO len:719 off:0
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic packet tx hs bytes:956 need_ack:1 number:2 encoded nl:1 trunc:0x2
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:190
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer: 6, old: 430824, new: 430824
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer del: 6: 356321
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic lost timer pto:997
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 event timer add: 6: 997:356821
server  | 2025/04/30 12:21:23 [debug] 21#0: *4 quic state: send:75000 pto:997 close:59254
server  | 2025/04/30 12:21:23 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:23 [debug] 21#0: epoll timer: 997
client  | Client port (AF=2): 57785.
client  | Negotiated ALPN: hq-interop
client  | Almost ready!
client  | 
client  | Connection established. Version = 1, I-CID: 1b5c32647e05134, verified: 1
client  | Opening stream 0 to GET /qcyurodvyj
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:24 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic recvmsg: fd:6 n:1252
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx long flags:c7 version:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx init len:1226
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx dcid len:8 01b5c32647e05134
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic address validation token len:0 
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx clearflags:c3
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx number:130511 len:4
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet len:1252 via sock seq:-1 path seq:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic path seq:0 status tx:3600 rx:5008 valid:0 st:0 mtu:1200
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx init:130511 PING
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx init:130511 CRYPTO len:285 off:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic resend packet pnum:2
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion lost recovery win:131054 ss:131054 if:1010
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 post event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic resend packet pnum:2
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion lost recovery win:131054 ss:131054 if:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx init:130511 PADDING
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:130511 largest 130510 fr:2 nranges:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet done rc:0 level:init decr:1 pn:130511 perr:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 430824
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 75000:431325
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic state: read:75000 pto:496 close:58753
server  | 2025/04/30 12:21:24 [debug] 21#0: timer delta: 501
server  | 2025/04/30 12:21:24 [debug] 21#0: posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 delete posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic push handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic output init packet max:1200 min:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx init:3 ACK n:0 delay:0 130511-130508
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx init:3 CRYPTO len:123 off:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet tx init bytes:135 need_ack:1 number:3 encoded nl:1 trunc:0x3
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic output hs packet max:1010 min:1010
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx hs:3 CRYPTO len:719 off:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet tx hs bytes:956 need_ack:1 number:3 encoded nl:1 trunc:0x3
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 sendmsg: 1200 of 1200
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:190
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:1200
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer: 6, old: 431325, new: 431325
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 356821
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic lost timer pto:997
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 997:357322
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic state: send:75000 pto:997 close:58753
server  | 2025/04/30 12:21:24 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll timer: 997
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:24 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic recvmsg: fd:6 n:284
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx long flags:ef version:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx hs len:64
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx dcid len:20 000000000000100279f5d71a0970c9e93617b830
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx clearflags:e3
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx number:0 len:4
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet len:284 via sock seq:0 path seq:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic path seq:0 status tx:4800 rx:5292 valid:0 st:0 mtu:1200
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion avoidance win:131149 ss:131054 if:1010
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:-1 closed nsock:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 357322
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic lost timer pto:740
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 740:357322
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic path seq:0 in handshake tx:4800 rx:5292 valid:1 st:0 mtu:1200
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 post event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx hs:0 ACK n:0 delay:44 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_handle_ack_frame level:2
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic updated largest received ack:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 357322
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic lost timer pto:740
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 740:357322
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx hs:0 CRYPTO len:36 off:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_set_read_secret() level:3
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_add_handshake_data
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_flush_flight()
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 SSL_do_handshake: 1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 SSL: TLSv1.3, cipher: "TLS_AES_128_GCM_SHA256 Kx=GENERIC Au=GENERIC Enc=AESGCM(128) Mac=AEAD"
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 post event 00005646E6B233C8
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion avoidance win:131653 ss:131054 if:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 357322
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic lost timer unset
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic path seq:0 schedule mtu:1440
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 100:356682
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic create sockets has:1 max:8
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:1 listening at sid:0000000000001002952052188691d46cbd4e2b3b nsock:2
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stateless reset token 126836d0fa2d333b0445ef4fbc918392
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:2 listening at sid:000000000000100269f90678053ae510371821c0 nsock:3
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B17210:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stateless reset token a77bf1cf6c4f1473700980e901268648
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:3 listening at sid:00000000000010026c9e66edbec40a0e9b98cfbe nsock:4
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B17420:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stateless reset token ce17f6741173f693bcfe7119dd351f01
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:4 listening at sid:0000000000001002ff22852d9c03a3f29d8e552c nsock:5
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B17630:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stateless reset token daf248078901deeede9bb72ed92cd793
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:5 listening at sid:0000000000001002733aeb257a3c038e0040bf7b nsock:6
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B6E9C0:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stateless reset token 043cef0832456c8866c022ead4335b22
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:6 listening at sid:0000000000001002749d5502bbd5d57fbbf17061 nsock:7
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B6EBD0:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stateless reset token a52046d8b296de9ad2d8d94facd6840c
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic socket seq:7 listening at sid:0000000000001002f9c9bd1f442868c1cee83e0e nsock:8
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B6EDE0:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stateless reset token 1ec57ccb0f0578223089fc17dfea84de
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic init streams
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 http3 init
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 http3 init session
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B6EFF0:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 add cleanup: 00005646E6B173C8
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 75000:431582
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 415078
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet done rc:0 level:hs decr:1 pn:0 perr:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx short flags:5c
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx dcid len:20 000000000000100279f5d71a0970c9e93617b830
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx clearflags:40
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx number:0 len:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:8
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic cid seq:1 received id:8:36069dfde54715a3:bef16357fa3b6644166dfc163f6dd5b3
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx app:0 PADDING
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet done rc:0 level:app decr:1 pn:0 perr:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer: 6, old: 431325, new: 431582
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic state: read:74743
server  | 2025/04/30 12:21:24 [debug] 21#0: timer delta: 257
server  | 2025/04/30 12:21:24 [debug] 21#0: posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 delete posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic push handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic output app packet max:1200 min:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 ACK n:0 delay:0 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 CRYPTO len:572 off:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 HANDSHAKE DONE
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:5 retire:0 len:20
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:6 retire:0 len:20
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:7 retire:0 len:20
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet tx app bytes:862 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 sendmsg: 888 of 888
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:888
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer: 6, old: 431325, new: 431582
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic lost timer pto:1007
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 1007:357589
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic state: send:74743 pto:1007
server  | 2025/04/30 12:21:24 [debug] 21#0: posted event 00005646E6B233C8
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 delete posted event 00005646E6B233C8
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic key update
server  | 2025/04/30 12:21:24 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll timer: 100
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:24 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic recvmsg: fd:6 n:183
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx short flags:46
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx dcid len:20 000000000000100279f5d71a0970c9e93617b830
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx clearflags:40
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx number:1 len:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet len:183 via sock seq:0 path seq:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic path seq:0 status tx:5688 rx:5475 valid:1 st:2 mtu:1200
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx app:1 STREAM id:0x0 len:17 fin:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 is missing
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 create
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B6F200:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B6F410:16384 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 reusable connection: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 add cleanup: 00005646E6B6F4D0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 post event 00005646E6B3DAD0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 flow control msd:17/65536 md:17/8585216
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame rx app:1 PADDING
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:1 largest 0 fr:0 nranges:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 post event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet done rc:0 level:app decr:1 pn:1 perr:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer: 6, old: 431325, new: 431583
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic state: read:74742 pto:1006
server  | 2025/04/30 12:21:24 [debug] 21#0: timer delta: 1
server  | 2025/04/30 12:21:24 [debug] 21#0: posted event 00005646E6B3DAD0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 delete posted event 00005646E6B3DAD0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 quic init stream
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http3 init request stream
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 add cleanup: 00005646E6B6F540
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 431582
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http wait request handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 recv buf:1024
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 quic stream id:0x0 recv len:17
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 flow update 17
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 reusable connection: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 posix_memalign: 00005646E6B73420:4096 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http process request line
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http request line: "GET /qcyurodvyj"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http uri: "/qcyurodvyj"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http args: ""
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http exten: ""
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 rewrite phase: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 test location: "/"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 using configuration "/"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http cl:-1 max:1048576
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 rewrite phase: 2
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 post rewrite phase: 3
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 generic phase: 4
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 generic phase: 5
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 access phase: 6
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 access phase: 7
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 post access phase: 8
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 generic phase: 9
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 generic phase: 10
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 content phase: 11
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 content phase: 12
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 content phase: 13
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http filename: "/www/qcyurodvyj"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 add cleanup: 00005646E6B74158
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http static fd: 3
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http set discard body
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http output filter "/qcyurodvyj?"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http copy filter: "/qcyurodvyj?"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 posix_memalign: 00005646E6B74430:4096 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 read: 3, 00005646E6B74450, 1024, 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http postpone filter "/qcyurodvyj?" 00005646E6B74370
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 write new buf t:1 f:0 00005646E6B74450, pos 00005646E6B74450, size: 1024 file: 0, size: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http write filter: l:1 f:0 s:1024
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http write filter limit 2097152
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 malloc: 00005646E6B75440:4096
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 quic send_chain sent:1024
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 flush limit:1048576
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 flush len:1024 last:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http write filter 0000000000000000
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http copy filter: 0 "/qcyurodvyj?"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http finalize request: 0, "/qcyurodvyj?" a:1, c:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http request count:1 blk:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http close request
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 http log handler
server  | 193.167.0.100 - - [30/Apr/2025:12:21:24 +0000] "GET /qcyurodvyj" 200 1024 "-" "-"
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 run cleanup: 00005646E6B74158
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 file cleanup: fd:3
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 free: 00005646E6B73420, unused: 64
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 free: 00005646E6B74430, unused: 2925
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 close http connection: 6
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 reusable connection: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 run cleanup: 00005646E6B6F540
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 75000:431583
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 run cleanup: 00005646E6B6F4D0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 cleanup
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 send shutdown
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 flush limit:1047552
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 posix_memalign: 00005646E6B73420:512 @16
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic stream id:0x0 flush len:0 last:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *5 free: 00005646E6B6F410, unused: 14952
server  | 2025/04/30 12:21:24 [debug] 21#0: posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 delete posted event 00005646E6B23248
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic push handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic output app packet max:1200 min:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:1 ACK n:0 delay:0 1-0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:1 STREAM id:0x0 off:0 len:1024
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:1 STREAM id:0x0 off:1024 len:0 fin:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet tx app bytes:1039 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 sendmsg: 1065 of 1065
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:888
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:888
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:1953
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer: 6, old: 431325, new: 431583
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 357589
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic lost timer pto:1007
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 1007:357590
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic state: send:74742 pto:1007
server  | 2025/04/30 12:21:24 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll timer: 99
server  | 2025/04/30 12:21:24 [debug] 21#0: timer delta: 99
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 356682
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic path seq:0 send probe mtu:1440 pnum:2 tries:0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic sendto app packet max:1440 min:1440
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic frame tx app:2 PING
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet tx app bytes:1414 need_ack:0 number:2 encoded nl:1 trunc:0x2
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 sendmsg: 1440 of 1440
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic congestion send if:3393
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer del: 6: 357590
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic lost timer pto:1007
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 1007:357689
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 event timer add: 6: 1007:357689
server  | 2025/04/30 12:21:24 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll timer: 1007
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:24 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic recvmsg: fd:6 n:183
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx long flags:e8 version:1
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx hs len:146
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx dcid len:20 000000000000100279f5d71a0970c9e93617b830
server  | 2025/04/30 12:21:24 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:24 [info] 21#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 12:21:24 [debug] 21#0: *4 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0
server  | 2025/04/30 12:21:24 [debug] 21#0: timer delta: 144
server  | 2025/04/30 12:21:24 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:24 [debug] 21#0: epoll timer: 863
server  | 2025/04/30 12:21:25 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:25 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic recvmsg: fd:6 n:183
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx long flags:e9 version:1
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx hs len:146
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx dcid len:20 000000000000100279f5d71a0970c9e93617b830
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:25 [info] 21#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 12:21:25 [debug] 21#0: *4 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0
server  | 2025/04/30 12:21:25 [debug] 21#0: timer delta: 500
server  | 2025/04/30 12:21:25 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:25 [debug] 21#0: epoll timer: 363
client  | Stream 0 ended after 1024 bytes
client  | All done, Closing the connection.
server  | 2025/04/30 12:21:25 [debug] 21#0: timer delta: 364
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer del: 6: 357689
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic pto timer
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic pto app pto_count:0
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 posix_memalign: 00005646E6B73630:512 @16
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic sendto app packet max:1200 min:0
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic frame tx app:3 PING
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet tx app bytes:3 need_ack:0 number:3 encoded nl:1 trunc:0x3
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 sendmsg: 29 of 29
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic congestion send if:3422
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic lost timer pto:1007
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer add: 6: 1007:358697
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic sendto app packet max:1200 min:0
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic frame tx app:4 PING
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet tx app bytes:3 need_ack:0 number:4 encoded nl:1 trunc:0x4
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 sendmsg: 29 of 29
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic congestion send if:3451
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer del: 6: 358697
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic lost timer pto:1007
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer add: 6: 1007:358697
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer del: 6: 358697
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic lost timer pto:2014
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer add: 6: 2014:359704
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic state: send:73635 pto:2014
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer del: 6: 357689
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic path seq:0 send probe mtu:1440 pnum:5 tries:1
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic sendto app packet max:1440 min:1440
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic frame tx app:5 PING
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet tx app bytes:1414 need_ack:0 number:5 encoded nl:1 trunc:0x5
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 sendmsg: 1440 of 1440
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic congestion send if:4891
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer del: 6: 359704
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic lost timer pto:2014
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer add: 6: 2014:359704
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 event timer add: 6: 2014:359704
server  | 2025/04/30 12:21:25 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:25 [debug] 21#0: epoll timer: 2014
server  | 2025/04/30 12:21:25 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:25 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic recvmsg: fd:6 n:183
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx long flags:e1 version:1
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx hs len:146
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx dcid len:20 000000000000100279f5d71a0970c9e93617b830
server  | 2025/04/30 12:21:25 [debug] 21#0: *4 quic packet rx scid len:8 1ad56caa5173cc24
server  | 2025/04/30 12:21:25 [info] 21#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 12:21:25 [debug] 21#0: *4 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0
server  | 2025/04/30 12:21:25 [debug] 21#0: timer delta: 137
server  | 2025/04/30 12:21:25 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:25 [debug] 21#0: epoll timer: 1877
server  | 2025/04/30 12:21:26 [debug] 21#0: epoll: fd:6 ev:0001 d:00007F6D241E5108
server  | 2025/04/30 12:21:26 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic recvmsg: fd:6 n:46
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic input handler
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic packet rx short flags:70
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic packet rx dcid len:20 000000000000100279f5d71a0970c9e93617b830
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic packet rx clearflags:60
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic packet rx number:2 len:1
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic packet len:46 via sock seq:0 path seq:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic path seq:0 status tx:9691 rx:5521 valid:1 st:3 mtu:1200
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic frame rx app:2 ACK n:0 delay:16 1-0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic ngx_quic_handle_ack_frame level:3
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic congestion avoidance win:132094 ss:131054 if:4003
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic ngx_quic_drop_ack_ranges pn:0 largest:1 fr:1 nranges:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic congestion avoidance win:132622 ss:131054 if:2938
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic ngx_quic_drop_ack_ranges pn:1 largest:1 fr:0 nranges:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic stream id:0x0 ack len:1024 fin:0 unacked:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic stream id:0x0 ack len:0 fin:1 unacked:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic stream id:0x0 close
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 reusable connection: 1
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 post event 00005646E6B23248
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 update posted event 00005646E6B23248
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic updated largest received ack:1
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic rtt sample latest:1502 min:1502 avg:1502 var:751
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer del: 6: 359704
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic lost timer pto:4121
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer add: 6: 4121:362206
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic frame rx app:2 CONNECTION_CLOSE_APP err:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 post event 00005646E6B23308
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic ngx_quic_ack_packet pn:2 largest -1 fr:0 nranges:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic packet done rc:0 level:app decr:1 pn:2 perr:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer del: 6: 431325
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer add: 6: 75000:433085
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic state: draining read:75000 pto:4121
server  | 2025/04/30 12:21:26 [debug] 21#0: timer delta: 258
server  | 2025/04/30 12:21:26 [debug] 21#0: posted event 00005646E6B23248
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 delete posted event 00005646E6B23248
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic push handler
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic output app packet max:1200 min:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic frame tx app:6 MAX_STREAMS limit:129 bidi:1
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic packet tx app bytes:3 need_ack:1 number:6 encoded nl:1 trunc:0x6
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 sendmsg: 29 of 29
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic congestion send if:2938
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic congestion send if:2938
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic congestion send if:2967
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer: 6, old: 433085, new: 433085
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer del: 6: 362206
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic lost timer pto:4516
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer add: 6: 4516:362601
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic state: draining send:75000 pto:4516
server  | 2025/04/30 12:21:26 [debug] 21#0: posted event 00005646E6B23308
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 delete posted event 00005646E6B23308
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic close handler
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic close initiated rc:0
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 quic close immediate term:0 drain:1 error:1 ""
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer add: 6: 13548:371633
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer del: 6: 362601
server  | 2025/04/30 12:21:26 [debug] 21#0: *4 event timer del: 6: 359704
server  | 2025/04/30 12:21:26 [debug] 21#0: worker cycle
server  | 2025/04/30 12:21:26 [debug] 21#0: epoll timer: 13548
client  | Received a request to close the connection.
client  | Address Discovery mode: 0 / 0 (0:0)
client  | Quic Bit was NOT greased by the client.
client  | Quic Bit was NOT greased by the server.
client  | ECN was not received.
client  | ECN was not acknowledged.
client  | Received 1024 bytes in 12.726667 seconds, 0.000644 Mbps.
client  | Sent 17 bytes in 12.726667 seconds, 0.000011 Mbps.
client  | max_data_local: 1048576
client  | max_stream_data_local: 2097152
client  | max_data_remote: 8585216
client  | max_stream_data_remote: 0
client  | ack_delay_remote: 1000 ... 10000
client  | max_ack_gap_remote: 2
client  | ack_delay_local: 25000 ... 25000
client  | max_ack_gap_local: 2
client  | max_mtu_sent: 1252
client  | max_mtu_received: 1440
client  | System call duration max: 7
client  | System call duration smoothed: 0
client  | System call duration deviation: 0
client  | Received ticket from server4 (hq-interop):
client  | ticket time = 1746015685258, kx = 17, suite = 1301, 282 ticket, 32 secret.
client  | lifetime = 299, age_add = 3f662506, 1 nonce, 256 ticket, 12 extensions.
client  | ticket extensions: 42(ED: ffffffff),
client  | 4 extra bytes at the end of the extensions
client  | Client exit with code = 0

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 12:21:45,385 Using the client's key log file.
2025-04-30 12:21:45,393 
2025-04-30 12:21:45,394 Using the client's key log file.
2025-04-30 12:21:45,394 Using the client's key log file.
2025-04-30 12:21:45,401 
2025-04-30 12:21:45,402 Using the client's key log file.
2025-04-30 12:21:45,680 Check of downloaded files succeeded.
2025-04-30 12:21:46,824 Check of downloaded files succeeded.