2025-04-30 08:51:18,299 Generated random file: ftwonfzwvw of size: 10240 2025-04-30 08:51:18,299 Requests: https://server4:443/ftwonfzwvw 2025-04-30 08:51:18,357 2025-04-30 08:51:18,358 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_8_om2zxc/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_mv9afteo/ DOWNLOADS=/tmp/download_x1s1w0l7/ SERVER_LOGS=/tmp/logs_server_73hm2y0v CLIENT_LOGS=/tmp/logs_client_v09r399p SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/microsoft/msquic/qns:main SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/ftwonfzwvw" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:51:35,727 Container sim Created 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... client | Setting up routes... server | Actual changes: server | tx-checksumming: off server | tx-checksum-ip-generic: off server | tx-checksum-sctp: off client | Actual changes: client | tx-checksumming: off client | tx-checksum-ip-generic: off client | tx-checksum-sctp: off client | tcp-segmentation-offload: 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 | tx-tcp-segmentation: off [requested on] client | tx-tcp-ecn-segmentation: off [requested on] client | tx-tcp-mangleid-segmentation: off [requested on] client | tx-tcp6-segmentation: off [requested on] client | tx-udp-segmentation: off [requested on] server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Endpoint's IPv6 address is fd00:cafe:cafe::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: retry server | 2025/04/30 08:51:18 [debug] 21#0: bind() 0.0.0.0:443 #5 server | 2025/04/30 08:51:18 [debug] 21#0: bind() 0.0.0.0:443 #6 server | 2025/04/30 08:51:18 [debug] 21#0: bind() [::]:443 #7 server | 2025/04/30 08:51:18 [debug] 21#0: bind() [::]:443 #8 server | 2025/04/30 08:51:18 [debug] 21#0: add cleanup: 000056093DF3E780 server | 2025/04/30 08:51:18 [notice] 21#0: using the "epoll" event method server | 2025/04/30 08:51:18 [debug] 21#0: counter: 00007FB99B60A080, 1 server | 2025/04/30 08:51:18 [notice] 21#0: nginx/1.27.0 (9270:d1b8568f3042) server | 2025/04/30 08:51:18 [notice] 21#0: built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | 2025/04/30 08:51:18 [notice] 21#0: OS: Linux 6.11.0-1012-azure server | 2025/04/30 08:51:18 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 1048576:1048576 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | 2025/04/30 08:51:18 [debug] 21#0: write: 9, 00007FFFF77ABDE0, 3, 0 server | 2025/04/30 08:51:18 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf.retry" server | 2025/04/30 08:51:18 [notice] 21#0: start worker processes server | 2025/04/30 08:51:18 [debug] 21#0: channel 3:9 server | 2025/04/30 08:51:18 [notice] 21#0: start worker process 22 server | 2025/04/30 08:51:18 [debug] 21#0: sigsuspend server | 2025/04/30 08:51:18 [debug] 22#0: add cleanup: 000056093DF3E808 server | 2025/04/30 08:51:18 [debug] 22#0: malloc: 000056093DF417D0:16 server | 2025/04/30 08:51:18 [debug] 22#0: add cleanup: 000056093DF3E820 server | 2025/04/30 08:51:18 [debug] 22#0: malloc: 000056093DF3FAD0:16 server | 2025/04/30 08:51:18 [debug] 22#0: notify eventfd: 11 server | 2025/04/30 08:51:18 [debug] 22#0: testing the EPOLLRDHUP flag: success server | 2025/04/30 08:51:18 [debug] 22#0: malloc: 000056093DF27430:6144 server | 2025/04/30 08:51:18 [debug] 22#0: malloc: 00007FB99AF7C010:253952 server | 2025/04/30 08:51:18 [debug] 22#0: malloc: 000056093DF43890:98304 server | 2025/04/30 08:51:18 [debug] 22#0: malloc: 000056093DF5B8A0:98304 server | 2025/04/30 08:51:18 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001 server | 2025/04/30 08:51:18 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001 server | 2025/04/30 08:51:18 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001 server | 2025/04/30 08:51:18 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001 server | 2025/04/30 08:51:18 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001 server | 2025/04/30 08:51:18 [debug] 22#0: setproctitle: "nginx: worker process" server | 2025/04/30 08:51:18 [debug] 22#0: worker cycle server | 2025/04/30 08:51:18 [debug] 22#0: epoll timer: -1 server | 2025/04/30 08:51:19 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:19 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:19 [debug] 22#0: posix_memalign: 000056093DF3FB30:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: malloc: 000056093DF417F0:1207 server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:46359 fd:6 n:1207 server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic run server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic packet rx dcid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic packet rx scid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic address validation token len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *1 sending version negotiation packet server | 2025/04/30 08:51:19 [debug] 22#0: *1 sendmsg: 11 of 11 server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 08:51:19 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 08:51:19 [debug] 22#0: *1 reusable connection: 0 server | 2025/04/30 08:51:19 [debug] 22#0: *1 free: 000056093DF417F0 server | 2025/04/30 08:51:19 [debug] 22#0: *1 free: 000056093DF3FB30, unused: 24 server | 2025/04/30 08:51:19 [debug] 22#0: timer delta: 788 server | 2025/04/30 08:51:19 [debug] 22#0: worker cycle sim | server:443 is available after 1.005727704s server | 2025/04/30 08:51:19 [debug] 22#0: epoll timer: -1 server | 2025/04/30 08:51:19 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:19 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:19 [debug] 22#0: posix_memalign: 000056093DF3FB30:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: malloc: 000056093DF417F0:1207 server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic recvmsg: 193.167.100.2:46359 fd:6 n:1207 server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic run server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic packet rx dcid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic address validation token len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *2 sending version negotiation packet server | 2025/04/30 08:51:19 [debug] 22#0: *2 sendmsg: 11 of 11 server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 08:51:19 [debug] 22#0: *2 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 08:51:19 [debug] 22#0: *2 reusable connection: 0 server | 2025/04/30 08:51:19 [debug] 22#0: *2 free: 000056093DF417F0 server | 2025/04/30 08:51:19 [debug] 22#0: *2 free: 000056093DF3FB30, unused: 24 server | 2025/04/30 08:51:19 [debug] 22#0: timer delta: 0 server | 2025/04/30 08:51:19 [debug] 22#0: worker cycle server | 2025/04/30 08:51:19 [debug] 22#0: epoll timer: -1 server | 2025/04/30 08:51:19 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:19 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:19 [debug] 22#0: posix_memalign: 000056093DF3FB30:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: malloc: 000056093DF417F0:1207 server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic recvmsg: 193.167.100.2:46359 fd:6 n:1207 server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic run server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic packet rx dcid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic packet rx scid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic address validation token len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *3 sending version negotiation packet server | 2025/04/30 08:51:19 [debug] 22#0: *3 sendmsg: 11 of 11 server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 08:51:19 [debug] 22#0: *3 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 08:51:19 [debug] 22#0: *3 reusable connection: 0 server | 2025/04/30 08:51:19 [debug] 22#0: *3 free: 000056093DF417F0 server | 2025/04/30 08:51:19 [debug] 22#0: *3 free: 000056093DF3FB30, unused: 24 server | 2025/04/30 08:51:19 [debug] 22#0: timer delta: 0 server | 2025/04/30 08:51:19 [debug] 22#0: worker cycle server | 2025/04/30 08:51:19 [debug] 22#0: epoll timer: -1 sim | Using scenario: simple-p2p --delay=15ms --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 client | Connecting to server4 client | Client params (before files):-test:D -sslkeylogfile:/logs/keys.log client | Sending request: GET /ftwonfzwvw server | 2025/04/30 08:51:19 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:19 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:19 [debug] 22#0: posix_memalign: 000056093DF3FB30:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: malloc: 000056093DF417F0:1220 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic recvmsg: 193.167.0.100:36565 fd:6 n:1220 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic run server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic packet rx long flags:c3 version:1 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic packet rx init len:1202 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic packet rx dcid len:8 6530375afc4d6774 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic address validation token len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *4 sendmsg: 109 of 109 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic retry packet sent to server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 08:51:19 [debug] 22#0: *4 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 08:51:19 [debug] 22#0: *4 reusable connection: 0 server | 2025/04/30 08:51:19 [debug] 22#0: *4 free: 000056093DF417F0 server | 2025/04/30 08:51:19 [debug] 22#0: *4 free: 000056093DF3FB30, unused: 24 server | 2025/04/30 08:51:19 [debug] 22#0: timer delta: 259 server | 2025/04/30 08:51:19 [debug] 22#0: worker cycle server | 2025/04/30 08:51:19 [debug] 22#0: epoll timer: -1 server | 2025/04/30 08:51:19 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:19 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:19 [debug] 22#0: posix_memalign: 000056093DF3FB30:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: malloc: 000056093DF417F0:1220 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic recvmsg: 193.167.0.100:36565 fd:6 n:1220 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic run server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet rx long flags:c7 version:1 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet rx init len:1123 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet rx dcid len:20 494e54ea1979b40aaf35f32163f60bc5135069d9 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet rx scid len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic address validation token len:66 56d6c0413ca7e103711f8ced4ea98ff2644c874432595afbab656a5f91c71057ccd91bbbcabfed141477154767a68842175fa193292edf06d6819552dbf21b0518bf server | 2025/04/30 08:51:19 [debug] 22#0: *5 malloc: 000056093DF28EA0:2448 server | 2025/04/30 08:51:19 [debug] 22#0: *5 malloc: 000056093DF29840:1528 server | 2025/04/30 08:51:19 [debug] 22#0: *5 posix_memalign: 000056093DF29E40:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_set_initial_secret server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic socket seq:0 listening at sid:0000000000002002aa8a2eec8632ca8edf85e582 nsock:1 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic cid seq:0 received id:0::00000000000000000000000000000000 server | 2025/04/30 08:51:19 [debug] 22#0: *5 posix_memalign: 000056093DF1C9A0:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic path seq:0 created addr:193.167.0.100:36565 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic path seq:0 set active tx:0 rx:0 valid:1 st:0 mtu:1200 server | 2025/04/30 08:51:19 [debug] 22#0: *5 posix_memalign: 000056093DF1CBB0:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic socket seq:-1 listening at sid:494e54ea1979b40aaf35f32163f60bc5135069d9 nsock:2 server | 2025/04/30 08:51:19 [debug] 22#0: *5 reusable connection: 1 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic connection created server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet rx clearflags:c3 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet rx number:1 len:4 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet len:1220 via sock seq:0 path seq:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic path seq:0 status tx:0 rx:1220 valid:1 st:0 mtu:1200 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic stateless reset token 4f53be07e72a304042f094274b237f5d server | 2025/04/30 08:51:19 [debug] 22#0: *5 posix_memalign: 000056093DF1DCE0:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic frame rx init:1 CRYPTO len:288 off:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 SSL server name: "server4" server | 2025/04/30 08:51:19 [debug] 22#0: *5 SSL ALPN supported by client: hq-interop server | 2025/04/30 08:51:19 [debug] 22#0: *5 SSL ALPN supported by client: hq-29 server | 2025/04/30 08:51:19 [debug] 22#0: *5 SSL ALPN selected: hq-interop server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic SSL_get_peer_quic_transport_params(): params_len:57 server | 2025/04/30 08:51:19 [info] 22#0: *5 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 08:51:19 [debug] 22#0: *5 quic transport parameters parsed ok server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp disable active migration: 0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp idle_timeout:10000 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp max_udp_payload_size:1472 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp max_data:16777216 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp max_stream_data_bidi_local:65536 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp max_stream_data_bidi_remote:65536 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp max_stream_data_uni:65536 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp initial_max_streams_bidi:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp initial_max_streams_uni:3 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp ack_delay_exponent:8 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp max_ack_delay:26 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp active_connection_id_limit:4 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic tp initial source_connection_id len:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 malloc: 000056093DF1EA30:4096 server | 2025/04/30 08:51:19 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_set_write_secret() level:2 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 08:51:19 [debug] 22#0: *5 malloc: 000056093DF738B0:4096 server | 2025/04/30 08:51:19 [debug] 22#0: *5 posix_memalign: 000056093DF20BD0:512 @16 server | 2025/04/30 08:51:19 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_set_write_secret() level:3 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_flush_flight() server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_set_read_secret() level:2 server | 2025/04/30 08:51:19 [debug] 22#0: *5 SSL_do_handshake: -1 server | 2025/04/30 08:51:19 [debug] 22#0: *5 SSL_get_error: 2 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic frame rx init:1 PADDING server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:1 largest -1 fr:0 nranges:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet done rc:0 level:init decr:1 pn:1 perr:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 event timer add: 6: 10000:388231 server | 2025/04/30 08:51:19 [debug] 22#0: *5 event timer add: 6: 60000:438231 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic state: read:10000 close:60000 server | 2025/04/30 08:51:19 [debug] 22#0: timer delta: 32 server | 2025/04/30 08:51:19 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:19 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic output init packet max:1200 min:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic frame tx init:0 ACK n:0 delay:0 1 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic frame tx init:0 CRYPTO len:90 off:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet tx init bytes:99 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic output hs packet max:1054 min:1054 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic frame tx hs:0 CRYPTO len:758 off:0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic packet tx hs bytes:1008 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 08:51:19 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic congestion send if:146 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic congestion send if:1200 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic congestion send if:1200 server | 2025/04/30 08:51:19 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388231 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic lost timer pto:997 server | 2025/04/30 08:51:19 [debug] 22#0: *5 event timer add: 6: 997:379228 server | 2025/04/30 08:51:19 [debug] 22#0: *5 quic state: send:10000 pto:997 close:60000 server | 2025/04/30 08:51:19 [debug] 22#0: worker cycle server | 2025/04/30 08:51:19 [debug] 22#0: epoll timer: 997 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:109 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx long flags:e3 version:1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx hs len:80 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx scid len:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:e3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:2 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:109 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:1200 rx:1329 valid:1 st:0 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion ack recovery win:131054 ss:-1 if:1054 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:-1 closed nsock:1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 379228 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:963 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 963:379228 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx hs:2 ACK n:0 delay:2 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:2 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion ack recovery win:131054 ss:-1 if:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic updated largest received ack:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic rtt sample latest:34 min:34 avg:34 var:17 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 379228 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer unset server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx hs:2 CRYPTO len:52 off:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_set_read_secret() level:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_flush_flight() server | 2025/04/30 08:51:20 [debug] 22#0: *5 SSL_do_handshake: 1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 SSL: TLSv1.3, cipher: "TLS_AES_256_GCM_SHA384 Kx=GENERIC Au=GENERIC Enc=AESGCM(256) Mac=AEAD" server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF29628 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer unset server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 schedule mtu:1472 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 100:378365 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic create sockets has:1 max:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:1 listening at sid:00000000000020023a5984d5eab754405a4f8629 nsock:2 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stateless reset token 5d731c94a92199bb897f876601768eaf server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF1D670:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:2 listening at sid:00000000000020029b6b508f6dc096c63b9bbc3c nsock:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stateless reset token 122b8c93c7f4124e8199363baf59f82b server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF1D880:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:3 listening at sid:0000000000002002650387bb80a925309a742f39 nsock:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stateless reset token 0a604a9fd7efe40013705c96afdc3a57 server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic init streams server | 2025/04/30 08:51:20 [debug] 22#0: *5 http3 init server | 2025/04/30 08:51:20 [debug] 22#0: *5 http3 init session server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF1DA90:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 add cleanup: 000056093DF1CB68 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 75000:453265 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 438231 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:2 largest -1 fr:0 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:hs decr:1 pn:2 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388265 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9966 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 34 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:0 CRYPTO len:604 off:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:0 HANDSHAKE DONE server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:0 NEW_TOKEN server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:789 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 807 of 807 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:807 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388265 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:128 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 128:378393 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: send:9966 pto:128 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF29628 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF29628 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic key update server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 100 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:1252 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:40 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:43 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:3 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:1252 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:2007 rx:2581 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:3 PING server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:3 STREAM id:0x0 len:17 fin:1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 is missing server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 create server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF74FF0:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF75200:16384 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 reusable connection: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 add cleanup: 000056093DF752C0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 post event 000056093DF43AD0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 flow control msd:17/65536 md:17/8585216 server | 2025/04/30 08:51:20 [debug] 22#0: *5 malloc: 000056093DF79210:4096 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:3 PADDING server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:3 largest -1 fr:0 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:3 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388266 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9965 pto:127 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 1 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF43AD0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 delete posted event 000056093DF43AD0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 quic init stream server | 2025/04/30 08:51:20 [debug] 22#0: *6 http3 init request stream server | 2025/04/30 08:51:20 [debug] 22#0: *6 add cleanup: 000056093DF75330 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 453265 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http wait request handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 recv buf:1024 server | 2025/04/30 08:51:20 [debug] 22#0: *6 quic stream id:0x0 recv len:17 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 flow update 17 server | 2025/04/30 08:51:20 [debug] 22#0: *6 reusable connection: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 posix_memalign: 000056093DF7A220:4096 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http process request line server | 2025/04/30 08:51:20 [debug] 22#0: *6 http request line: "GET /ftwonfzwvw" server | 2025/04/30 08:51:20 [debug] 22#0: *6 http uri: "/ftwonfzwvw" server | 2025/04/30 08:51:20 [debug] 22#0: *6 http args: "" server | 2025/04/30 08:51:20 [debug] 22#0: *6 http exten: "" server | 2025/04/30 08:51:20 [debug] 22#0: *6 rewrite phase: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 test location: "/" server | 2025/04/30 08:51:20 [debug] 22#0: *6 using configuration "/" server | 2025/04/30 08:51:20 [debug] 22#0: *6 http cl:-1 max:1048576 server | 2025/04/30 08:51:20 [debug] 22#0: *6 rewrite phase: 2 server | 2025/04/30 08:51:20 [debug] 22#0: *6 post rewrite phase: 3 server | 2025/04/30 08:51:20 [debug] 22#0: *6 generic phase: 4 server | 2025/04/30 08:51:20 [debug] 22#0: *6 generic phase: 5 server | 2025/04/30 08:51:20 [debug] 22#0: *6 access phase: 6 server | 2025/04/30 08:51:20 [debug] 22#0: *6 access phase: 7 server | 2025/04/30 08:51:20 [debug] 22#0: *6 post access phase: 8 server | 2025/04/30 08:51:20 [debug] 22#0: *6 generic phase: 9 server | 2025/04/30 08:51:20 [debug] 22#0: *6 generic phase: 10 server | 2025/04/30 08:51:20 [debug] 22#0: *6 content phase: 11 server | 2025/04/30 08:51:20 [debug] 22#0: *6 content phase: 12 server | 2025/04/30 08:51:20 [debug] 22#0: *6 content phase: 13 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http filename: "/www/ftwonfzwvw" server | 2025/04/30 08:51:20 [debug] 22#0: *6 add cleanup: 000056093DF7AF58 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http static fd: 3 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http set discard body server | 2025/04/30 08:51:20 [debug] 22#0: *6 http output filter "/ftwonfzwvw?" server | 2025/04/30 08:51:20 [debug] 22#0: *6 http copy filter: "/ftwonfzwvw?" server | 2025/04/30 08:51:20 [debug] 22#0: *6 malloc: 000056093DF7B230:10240 server | 2025/04/30 08:51:20 [debug] 22#0: *6 read: 3, 000056093DF7B230, 10240, 0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http postpone filter "/ftwonfzwvw?" 000056093DF7B170 server | 2025/04/30 08:51:20 [debug] 22#0: *6 write new buf t:1 f:0 000056093DF7B230, pos 000056093DF7B230, size: 10240 file: 0, size: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http write filter: l:1 f:0 s:10240 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http write filter limit 2097152 server | 2025/04/30 08:51:20 [debug] 22#0: *5 malloc: 000056093DF7DA40:4096 server | 2025/04/30 08:51:20 [debug] 22#0: *5 malloc: 000056093DF7EA50:4096 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF7FA60:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 malloc: 000056093DF7FC70:4096 server | 2025/04/30 08:51:20 [debug] 22#0: *6 quic send_chain sent:10240 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 flush limit:65536 server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 flush len:10240 last:0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http write filter 0000000000000000 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http copy filter: 0 "/ftwonfzwvw?" server | 2025/04/30 08:51:20 [debug] 22#0: *6 http finalize request: 0, "/ftwonfzwvw?" a:1, c:1 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http request count:1 blk:0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 http close request server | 2025/04/30 08:51:20 [debug] 22#0: *6 http log handler server | 2025/04/30 08:51:20 [debug] 22#0: *6 posix_memalign: 000056093DF80C80:4096 @16 server | 193.167.0.100 - - [30/Apr/2025:08:51:20 +0000] "GET /ftwonfzwvw" 200 10240 "-" "-" server | 2025/04/30 08:51:20 [debug] 22#0: *6 run cleanup: 000056093DF7AF58 server | 2025/04/30 08:51:20 [debug] 22#0: *6 file cleanup: fd:3 server | 2025/04/30 08:51:20 [debug] 22#0: *6 free: 000056093DF7B230 server | 2025/04/30 08:51:20 [debug] 22#0: *6 free: 000056093DF7A220, unused: 48 server | 2025/04/30 08:51:20 [debug] 22#0: *6 free: 000056093DF80C80, unused: 3949 server | 2025/04/30 08:51:20 [debug] 22#0: *6 close http connection: 6 server | 2025/04/30 08:51:20 [debug] 22#0: *6 reusable connection: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *6 run cleanup: 000056093DF75330 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 75000:453266 server | 2025/04/30 08:51:20 [debug] 22#0: *6 run cleanup: 000056093DF752C0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 cleanup server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 send shutdown server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 flush limit:55296 server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 flush len:0 last:1 server | 2025/04/30 08:51:20 [debug] 22#0: *6 free: 000056093DF75200, unused: 14952 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF7A220:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:1 ACK n:0 delay:0 3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:10245 need:1177 shrink:9068 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:1 STREAM id:0x0 off:0 len:1172 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:807 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:807 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:2007 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:9074 need:1182 shrink:7892 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF7A430:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:2 STREAM id:0x0 off:1172 len:1176 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:2 encoded nl:1 trunc:0x2 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:2007 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:2007 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:3207 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:7898 need:1182 shrink:6716 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:3 STREAM id:0x0 off:2348 len:1176 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:3 encoded nl:1 trunc:0x3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:3207 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:3207 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:4407 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:6722 need:1182 shrink:5540 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF7A640:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:4 STREAM id:0x0 off:3524 len:1176 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:4 encoded nl:1 trunc:0x4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:4407 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:4407 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:5607 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:5546 need:1182 shrink:4364 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:5 STREAM id:0x0 off:4700 len:1176 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:5 encoded nl:1 trunc:0x5 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:5607 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:5607 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:6807 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:4370 need:1182 shrink:3188 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF7A850:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:6 STREAM id:0x0 off:5876 len:1176 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:6 encoded nl:1 trunc:0x6 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:6807 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:6807 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:8007 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:3194 need:1182 shrink:2012 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:7 STREAM id:0x0 off:7052 len:1176 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:7 encoded nl:1 trunc:0x7 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:8007 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:8007 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:9207 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic split frame now:2018 need:1182 shrink:836 server | 2025/04/30 08:51:20 [debug] 22#0: *5 posix_memalign: 000056093DF7AA60:512 @16 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:8 STREAM id:0x0 off:8228 len:1176 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:1182 need_ack:1 number:8 encoded nl:1 trunc:0x8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:9207 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:9207 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:10407 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:9 STREAM id:0x0 off:9404 len:836 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:9 STREAM id:0x0 off:10240 len:0 fin:1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:847 need_ack:1 number:9 encoded nl:1 trunc:0x9 server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 865 of 865 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:10407 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:10407 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:11272 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388266 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378393 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:128 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 128:378394 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: send:9965 pto:128 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 99 client | ftwonfzwvw: Wrote 1172 bytes.(0 ms/0 ms/0 ms) client | ftwonfzwvw: Wrote 1176 bytes.(1 ms/1 ms/1 ms) client | ftwonfzwvw: Wrote 1176 bytes.(2 ms/1 ms/0 ms) client | ftwonfzwvw: Wrote 1176 bytes.(3 ms/1 ms/0 ms) client | ftwonfzwvw: Wrote 1176 bytes.(4 ms/1 ms/0 ms) client | ftwonfzwvw: Wrote 1176 bytes.(5 ms/1 ms/0 ms) client | ftwonfzwvw: Wrote 1176 bytes.(6 ms/1 ms/0 ms) client | ftwonfzwvw: Wrote 1176 bytes.(7 ms/1 ms/0 ms) client | ftwonfzwvw: Wrote 836 bytes.(7 ms/0 ms/-1 ms) client | ftwonfzwvw: Completed download! (7 ms) server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:46 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:71 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:63 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:4 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:46 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:12472 rx:2627 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:4 ACK n:0 delay:0 1-0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:131861 ss:-1 if:10465 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:133061 ss:-1 if:9265 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_drop_ack_ranges pn:3 largest:3 fr:0 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1172 fin:0 unacked:9068 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic updated largest received ack:1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic rtt sample latest:32 min:32 avg:34 var:13 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378394 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:80 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 80:378378 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:4 largest -1 fr:0 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:4 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388298 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9933 pto:80 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 32 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9933 pto:80 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 67 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:1332 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:7a server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:63 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:5 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:1332 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:12472 rx:3959 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:5 PING server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:5 PADDING server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:5 largest 4 fr:0 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:5 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388299 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9932 pto:79 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 1 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 25:378324 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9932 push:25 pto:79 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 25 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:57 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:69 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:63 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:6 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:57 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:12472 rx:4016 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:6 ACK n:0 delay:0 3-0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:134261 ss:-1 if:8065 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1176 fin:0 unacked:7892 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:135461 ss:-1 if:6865 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1176 fin:0 unacked:6716 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic updated largest received ack:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic rtt sample latest:34 min:32 avg:34 var:10 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378378 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:66 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 66:378366 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:6 MAX_DATA max_data:16780740 on recv server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:6 MAX_STREAM_DATA id:0x0 limit:69060 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:6 largest 5 fr:1 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:6 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388300 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9931 push:24 pto:66 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 1 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:10 ACK n:0 delay:0 6-4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:5 need_ack:0 number:10 encoded nl:1 trunc:0xa server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 23 of 23 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:6865 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:6865 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:6865 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9931 push:24 pto:66 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 24 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:57 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:65 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:63 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:7 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:57 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:12495 rx:4073 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:7 ACK n:0 delay:0 5-0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:136661 ss:-1 if:5665 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1176 fin:0 unacked:5540 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:137861 ss:-1 if:4465 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1176 fin:0 unacked:4364 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic updated largest received ack:5 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic rtt sample latest:36 min:32 avg:34 var:8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378366 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:56 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 56:378358 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:7 MAX_DATA max_data:16783092 on recv server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:7 MAX_STREAM_DATA id:0x0 limit:71412 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:7 largest 6 fr:2 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:7 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388302 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9929 push:22 pto:56 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 2 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9929 push:22 pto:56 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 22 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:57 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:74 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:63 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:8 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:57 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:12495 rx:4130 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:8 ACK n:0 delay:0 7-0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:139061 ss:-1 if:3265 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1176 fin:0 unacked:3188 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:140261 ss:-1 if:2065 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1176 fin:0 unacked:2012 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic updated largest received ack:7 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic rtt sample latest:38 min:32 avg:34 var:7 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378358 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:50 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 50:378354 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:8 MAX_DATA max_data:16785444 on recv server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:8 MAX_STREAM_DATA id:0x0 limit:73764 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:8 largest 7 fr:3 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:8 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388304 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9927 push:20 pto:50 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 2 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:11 ACK n:0 delay:0 8-4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:5 need_ack:0 number:11 encoded nl:1 trunc:0xb server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 23 of 23 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:2065 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:2065 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:2065 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9927 push:20 pto:50 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 20 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:51 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:71 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:63 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:9 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:51 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:12518 rx:4181 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:9 ACK n:0 delay:0 9-0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:141461 ss:-1 if:865 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:1176 fin:0 unacked:836 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion slow start win:142326 ss:-1 if:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:836 fin:0 unacked:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 ack len:0 fin:1 unacked:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic stream id:0x0 close server | 2025/04/30 08:51:20 [debug] 22#0: *5 reusable connection: 1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic updated largest received ack:9 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic rtt sample latest:40 min:32 avg:35 var:7 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378354 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer unset server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:9 MAX_DATA max_data:16787456 on recv server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:9 largest 8 fr:4 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 update posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:9 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388306 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: read:9925 push:18 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 2 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF294A8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic push handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:12 ACK n:0 delay:0 9-4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame tx app:12 MAX_STREAMS limit:129 bidi:1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet tx app bytes:8 need_ack:1 number:12 encoded nl:1 trunc:0xc server | 2025/04/30 08:51:20 [debug] 22#0: *5 sendmsg: 26 of 26 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic congestion send if:26 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388306 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic lost timer pto:89 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 89:378395 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: send:9925 push:18 pto:89 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 18 server | 2025/04/30 08:51:20 [debug] 22#0: epoll: fd:6 ev:0001 d:00007FB99AF7C108 server | 2025/04/30 08:51:20 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic recvmsg: fd:6 n:44 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic input handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx short flags:74 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000002002aa8a2eec8632ca8edf85e582 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx clearflags:63 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet rx number:10 len:4 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet len:44 via sock seq:0 path seq:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic path seq:0 status tx:12544 rx:4225 valid:1 st:2 mtu:1200 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic frame rx app:10 CONNECTION_CLOSE_APP err:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 post event 000056093DF29568 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:10 largest 9 fr:5 nranges:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:10 perr:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer: 6, old: 388231, new: 388306 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic state: draining read:9925 push:18 pto:89 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 0 server | 2025/04/30 08:51:20 [debug] 22#0: posted event 000056093DF29568 server | 2025/04/30 08:51:20 [debug] 22#0: *5 delete posted event 000056093DF29568 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic close handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic close initiated rc:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic close immediate term:0 drain:1 error:1 "" server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer add: 6: 267:378573 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378324 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378395 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378365 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: 267 server | 2025/04/30 08:51:20 [debug] 22#0: timer delta: 268 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 378573 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic close handler server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic close resumed rc:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:0 closed nsock:3 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:1 closed nsock:2 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:2 closed nsock:1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic socket seq:3 closed nsock:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic close completed server | 2025/04/30 08:51:20 [debug] 22#0: *5 quic ngx_quic_send_alert() level:app alert:0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 SSL_shutdown: 1 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: 6: 388231 server | 2025/04/30 08:51:20 [debug] 22#0: *5 reusable connection: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 run cleanup: 000056093DF1CB68 server | 2025/04/30 08:51:20 [debug] 22#0: *5 event timer del: -1: 453266 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7FC70 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7EA50 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7DA40 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF79210 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF738B0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF1EA30 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF29840 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF28EA0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF417F0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF3FB30, unused: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF29E40, unused: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF1C9A0, unused: 32 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF1CBB0, unused: 8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF1DCE0, unused: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF20BD0, unused: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF1D670, unused: 8 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF1D880, unused: 40 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF1DA90, unused: 0 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF74FF0, unused: 64 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7FA60, unused: 32 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7A220, unused: 32 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7A430, unused: 32 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7A640, unused: 32 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7A850, unused: 32 server | 2025/04/30 08:51:20 [debug] 22#0: *5 free: 000056093DF7AA60, unused: 336 server | 2025/04/30 08:51:20 [debug] 22#0: worker cycle server | 2025/04/30 08:51:20 [debug] 22#0: epoll timer: -1 client | client | TARGET VHDCRZSQUMBGA2E VERSION ALPN client | ============================================ client | server4 --D------------ 0x00000001 hq-interop client | client | Total execution time: 0.450s client | client | Client complete. client | Script complete. 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 08:51:35,822 Using the client's key log file. 2025-04-30 08:51:35,830 2025-04-30 08:51:35,831 Using the client's key log file. 2025-04-30 08:51:35,831 Using the client's key log file. 2025-04-30 08:51:35,839 2025-04-30 08:51:35,839 Using the client's key log file. 2025-04-30 08:51:36,395 Check of downloaded files succeeded. 2025-04-30 08:51:36,960 Check of Retry succeeded. Token used: 56d6c0413ca7e103711f8ced4ea98ff2644c874432595afbab656a5f91c71057ccd91bbbcabfed141477154767a68842175fa193292edf06d6819552dbf21b0518bf