2025-04-30 12:22:21,413 Generated random file: sstdjfbcok of size: 10240 2025-04-30 12:22:21,413 Requests: https://server4:443/sstdjfbcok 2025-04-30 12:22:21,472 2025-04-30 12:22:21,472 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_qs5n2x6b/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_2jb1jzyd/ DOWNLOADS=/tmp/download_gt5yrewu/ SERVER_LOGS=/tmp/logs_server_zvupz470 CLIENT_LOGS=/tmp/logs_client_uwlo73cc SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=privateoctopus/picoquic:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/sstdjfbcok" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 12:22:33,760 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... server | Actual changes: server | tx-checksumming: off server | tx-checksum-ip-generic: off server | tx-checksum-sctp: off server | tcp-segmentation-offload: off server | tx-tcp-segmentation: off [requested on] server | tx-tcp-ecn-segmentation: off [requested on] server | tx-tcp-mangleid-segmentation: off [requested on] server | tx-tcp6-segmentation: off [requested on] server | tx-udp-segmentation: off [requested on] server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 server | >>> Starting nginx server... client | Setting up the simulation with setup.sh 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 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 server | 2025/04/30 12:22:21 [debug] 20#0: bind() 0.0.0.0:443 #5 server | 2025/04/30 12:22:21 [debug] 20#0: bind() 0.0.0.0:443 #6 server | 2025/04/30 12:22:21 [debug] 20#0: bind() [::]:443 #7 server | 2025/04/30 12:22:21 [debug] 20#0: bind() [::]:443 #8 server | 2025/04/30 12:22:21 [debug] 20#0: add cleanup: 00005609BFF84780 server | 2025/04/30 12:22:21 [notice] 20#0: using the "epoll" event method server | 2025/04/30 12:22:21 [debug] 20#0: counter: 00007FBED5B7A080, 1 server | 2025/04/30 12:22:21 [notice] 20#0: nginx/1.27.0 (9270:d1b8568f3042) server | 2025/04/30 12:22:21 [notice] 20#0: built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | 2025/04/30 12:22:21 [notice] 20#0: OS: Linux 6.11.0-1012-azure server | 2025/04/30 12:22:21 [notice] 20#0: getrlimit(RLIMIT_NOFILE): 1048576:1048576 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | 2025/04/30 12:22:21 [debug] 20#0: write: 9, 00007FFDE028CF40, 3, 0 server | 2025/04/30 12:22:21 [debug] 20#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf.retry" server | 2025/04/30 12:22:21 [notice] 20#0: start worker processes server | 2025/04/30 12:22:21 [debug] 20#0: channel 3:9 server | 2025/04/30 12:22:21 [notice] 20#0: start worker process 21 server | 2025/04/30 12:22:21 [debug] 20#0: sigsuspend server | 2025/04/30 12:22:21 [debug] 21#0: add cleanup: 00005609BFF84808 server | 2025/04/30 12:22:21 [debug] 21#0: malloc: 00005609BFF877D0:16 server | 2025/04/30 12:22:21 [debug] 21#0: add cleanup: 00005609BFF84820 server | 2025/04/30 12:22:21 [debug] 21#0: malloc: 00005609BFF85AD0:16 server | 2025/04/30 12:22:21 [debug] 21#0: notify eventfd: 11 server | 2025/04/30 12:22:21 [debug] 21#0: testing the EPOLLRDHUP flag: success server | 2025/04/30 12:22:21 [debug] 21#0: malloc: 00005609BFF6D430:6144 server | 2025/04/30 12:22:21 [debug] 21#0: malloc: 00007FBED54EC010:253952 server | 2025/04/30 12:22:21 [debug] 21#0: malloc: 00005609BFF89890:98304 server | 2025/04/30 12:22:21 [debug] 21#0: malloc: 00005609BFFA18A0:98304 server | 2025/04/30 12:22:21 [debug] 21#0: epoll add event: fd:5 op:1 ev:00002001 server | 2025/04/30 12:22:21 [debug] 21#0: epoll add event: fd:6 op:1 ev:00002001 server | 2025/04/30 12:22:21 [debug] 21#0: epoll add event: fd:7 op:1 ev:00002001 server | 2025/04/30 12:22:21 [debug] 21#0: epoll add event: fd:8 op:1 ev:00002001 server | 2025/04/30 12:22:21 [debug] 21#0: epoll add event: fd:9 op:1 ev:00002001 server | 2025/04/30 12:22:21 [debug] 21#0: setproctitle: "nginx: worker process" server | 2025/04/30 12:22:21 [debug] 21#0: worker cycle server | 2025/04/30 12:22:21 [debug] 21#0: epoll timer: -1 client | Setup.sh completed client | Waiting for the simulator to start client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | 2025/04/30 12:22:22 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:22 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:22 [debug] 21#0: posix_memalign: 00005609BFF85B30:512 @16 server | 2025/04/30 12:22:22 [debug] 21#0: malloc: 00005609BFF877F0:1207 server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic recvmsg: 193.167.100.2:45671 fd:6 n:1207 server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic run server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic packet rx dcid len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic packet rx scid len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic address validation token len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *1 sending version negotiation packet server | 2025/04/30 12:22:22 [debug] 21#0: *1 sendmsg: 11 of 11 server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 12:22:22 [debug] 21#0: *1 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 12:22:22 [debug] 21#0: *1 reusable connection: 0 server | 2025/04/30 12:22:22 [debug] 21#0: *1 free: 00005609BFF877F0 server | 2025/04/30 12:22:22 [debug] 21#0: *1 free: 00005609BFF85B30, unused: 24 server | 2025/04/30 12:22:22 [debug] 21#0: timer delta: 850 server | 2025/04/30 12:22:22 [debug] 21#0: worker cycle server | 2025/04/30 12:22:22 [debug] 21#0: epoll timer: -1 server | 2025/04/30 12:22:22 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:22 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:22 [debug] 21#0: posix_memalign: 00005609BFF85B30:512 @16 server | 2025/04/30 12:22:22 [debug] 21#0: malloc: 00005609BFF877F0:1207 server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic recvmsg: 193.167.100.2:45671 fd:6 n:1207 server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic run server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic packet rx dcid len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic packet rx scid len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic address validation token len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *2 sending version negotiation packet server | 2025/04/30 12:22:22 [debug] 21#0: *2 sendmsg: 11 of 11 server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 12:22:22 [debug] 21#0: *2 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 12:22:22 [debug] 21#0: *2 reusable connection: 0 server | 2025/04/30 12:22:22 [debug] 21#0: *2 free: 00005609BFF877F0 server | 2025/04/30 12:22:22 [debug] 21#0: *2 free: 00005609BFF85B30, unused: 24 sim | server:443 is available after 1.057316622s server | 2025/04/30 12:22:22 [debug] 21#0: timer delta: 0 server | 2025/04/30 12:22:22 [debug] 21#0: worker cycle server | 2025/04/30 12:22:22 [debug] 21#0: epoll timer: -1 server | 2025/04/30 12:22:22 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:22 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:22 [debug] 21#0: posix_memalign: 00005609BFF85B30:512 @16 server | 2025/04/30 12:22:22 [debug] 21#0: malloc: 00005609BFF877F0:1207 server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic recvmsg: 193.167.100.2:45671 fd:6 n:1207 server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic run server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic packet rx dcid len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic packet rx scid len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic address validation token len:0 server | 2025/04/30 12:22:22 [debug] 21#0: *3 sending version negotiation packet server | 2025/04/30 12:22:22 [debug] 21#0: *3 sendmsg: 11 of 11 server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 12:22:22 [debug] 21#0: *3 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 12:22:22 [debug] 21#0: *3 reusable connection: 0 server | 2025/04/30 12:22:22 [debug] 21#0: *3 free: 00005609BFF877F0 server | 2025/04/30 12:22:22 [debug] 21#0: *3 free: 00005609BFF85B30, unused: 24 server | 2025/04/30 12:22:22 [debug] 21#0: timer delta: 0 server | 2025/04/30 12:22:22 [debug] 21#0: worker cycle server | 2025/04/30 12:22:22 [debug] 21#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 | Starting picoquic client for test: retry client | Starting picoquic client ... client | Parsing server name from first request: https://server4:443/sstdjfbcok client | Server set to: server4 client | Requests: https://server4:443/sstdjfbcok client | parsing as client | rm: cannot remove '*.bin': No such file or directory client | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443 client | No token file present. Will create one as . client | Testing scenario: <-:/sstdjfbcok;> client | Max stream id bidir remote before start = 0 (0) client | Starting client connection. Version = 1, I-CID: e1cf32417c180a84 client | Max stream id bidir remote after start = -4 (0) client | Waiting for packets. server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: posix_memalign: 00005609BFF85B30:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: malloc: 00005609BFF877F0:1252 server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic recvmsg: 193.167.0.100:47835 fd:6 n:1252 server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic run server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic packet rx long flags:c4 version:1 server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic packet rx init len:1226 server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic packet rx dcid len:8 e1cf32417c180a84 server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic packet rx scid len:8 cc15c54a1302557a server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic address validation token len:0 server | 2025/04/30 12:22:23 [debug] 21#0: *4 sendmsg: 117 of 117 server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic retry packet sent to cc15c54a1302557a server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *4 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 12:22:23 [debug] 21#0: *4 reusable connection: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *4 free: 00005609BFF877F0 server | 2025/04/30 12:22:23 [debug] 21#0: *4 free: 00005609BFF85B30, unused: 24 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 200 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: -1 server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: posix_memalign: 00005609BFF85B30:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: malloc: 00005609BFF877F0:1252 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: 193.167.0.100:47835 fd:6 n:1252 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic run server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx long flags:c0 version:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx init len:1147 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 2c5c314021c987a0721c547656525fde523a57c0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx scid len:8 cc15c54a1302557a server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic address validation token len:66 27906b25378762b859b0abbef65d7b1f632bb7ee8da9de4ccf7b029be3d7b7dcfc154930f09195d32f675e615151c4fc49e73a90f8494bc5bdcebccf92febbcbd8ee server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFF6EEA0:2448 server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFF6F840:1528 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF6FE40:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_set_initial_secret server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:0 listening at sid:0000000000001003232249c7476003111e3d219c nsock:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic cid seq:0 received id:8:cc15c54a1302557a:00000000000000000000000000000000 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF629A0:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 created addr:193.167.0.100:47835 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 set active tx:0 rx:0 valid:1 st:0 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF62BB0:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:-1 listening at sid:2c5c314021c987a0721c547656525fde523a57c0 nsock:2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 reusable connection: 1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic connection created server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:c3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:123380 len:4 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:1252 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:0 rx:1252 valid:1 st:0 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token a44fb94f1ce0e988d12387fb5e2b6dad server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF63CE0:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx init:123380 CRYPTO len:285 off:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL server name: "server4" server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL ALPN supported by client: hq-interop server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL ALPN selected: hq-interop server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic SSL_get_peer_quic_transport_params(): params_len:85 server | 2025/04/30 12:22:23 [info] 21#0: *5 quic reserved transport param id:0x58e, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 12:22:23 [info] 21#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 12:22:23 [info] 21#0: *5 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:22:23 [info] 21#0: *5 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:22:23 [debug] 21#0: *5 quic transport parameters parsed ok server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp disable active migration: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp idle_timeout:180000 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp max_udp_payload_size:1440 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp max_data:1048576 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp max_stream_data_bidi_local:2097152 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp max_stream_data_bidi_remote:65635 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp max_stream_data_uni:65535 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp initial_max_streams_bidi:512 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp initial_max_streams_uni:512 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp ack_delay_exponent:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp max_ack_delay:10 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp active_connection_id_limit:8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic tp initial source_connection_id len:8 cc15c54a1302557a server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFF64810:4096 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_set_write_secret() level:2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFFB98B0:4096 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF66B10:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_set_write_secret() level:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_flush_flight() server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_set_read_secret() level:2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL_do_handshake: -1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL_get_error: 2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx init:123380 PADDING server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:123380 largest -1 fr:0 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:init decr:1 pn:123380 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 75000:490153 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 60000:475153 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:75000 close:60000 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 32 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output init packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx init:0 ACK n:0 delay:0 123380 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx init:0 CRYPTO len:123 off:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx init bytes:135 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output hs packet max:1010 min:1010 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx hs:0 CRYPTO len:740 off:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx hs bytes:956 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:190 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490153 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:997 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 997:416150 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: send:75000 pto:997 close:60000 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 997 client | Client port (AF=2): 56250. client | Negotiated ALPN: hq-interop client | Almost ready! client | client | Connection established. Version = 1, I-CID: 2c5c314021c987a0, verified: 1 client | Opening stream 0 to GET /sstdjfbcok server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: fd:6 n:284 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic input handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx long flags:ec version:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx hs len:64 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx scid len:8 cc15c54a1302557a server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:e3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:0 len:4 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:284 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:1200 rx:1536 valid:1 st:0 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion ack recovery win:131054 ss:-1 if:1010 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:-1 closed nsock:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 416150 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:964 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 964:416150 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx hs:0 ACK n:0 delay:32 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_handle_ack_frame level:2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion ack recovery win:131054 ss:-1 if:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic updated largest received ack:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic rtt sample latest:33 min:33 avg:33 var:16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 416150 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer unset server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx hs:0 CRYPTO len:36 off:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_set_read_secret() level:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_flush_flight() server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL_do_handshake: 1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL: TLSv1.3, cipher: "TLS_AES_128_GCM_SHA256 Kx=GENERIC Au=GENERIC Enc=AESGCM(128) Mac=AEAD" server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F628 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer unset server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 schedule mtu:1440 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 100:415286 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic create sockets has:1 max:8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:1 listening at sid:0000000000001003835b15377d0ab719172c2a9d nsock:2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token b5cc98d75b57a9521ead79378e0f0746 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF63670:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:2 listening at sid:0000000000001003697e677289ddff09f5ec069f nsock:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token 0243b50a714ccc02ea180f5bcc100c35 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF63880:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:3 listening at sid:0000000000001003582e683aba954174c5ecb3a6 nsock:4 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token bc859f349f0e791f3fcf6333b3c9c40e server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFF63A90:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:4 listening at sid:00000000000010033fcc49cdca47717e3c711f83 nsock:5 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token 4c9b227423277543e5add30ffccc2213 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFBADF0:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:5 listening at sid:00000000000010037c94f56c3672506e9bd922cb nsock:6 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token d3311e0ec1cf73369c26189de874571c server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFBB000:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:6 listening at sid:00000000000010033391f5bbf16ed4310553fb1e nsock:7 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token 294db8d127cffb1bf2a2564e149bc059 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFBB210:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:7 listening at sid:00000000000010034a40772dabdd29e8cbaf0bc9 nsock:8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stateless reset token 46fc961b4767aef2826e423897d395f1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic init streams server | 2025/04/30 12:22:23 [debug] 21#0: *5 http3 init server | 2025/04/30 12:22:23 [debug] 21#0: *5 http3 init session server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFBB420:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 add cleanup: 00005609BFF63828 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 75000:490186 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 475153 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:hs decr:1 pn:0 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx short flags:55 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:40 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:0 len:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic cid seq:1 received id:8:0375fa943ca6f073:411a85b1c8b26e8fc501b4c6155c09c8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:0 PADDING server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:app decr:1 pn:0 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490186 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74967 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 33 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFBB630:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 ACK n:0 delay:0 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 CRYPTO len:572 off:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 HANDSHAKE DONE server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_TOKEN server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:5 retire:0 len:20 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:6 retire:0 len:20 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:7 retire:0 len:20 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:922 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 948 of 948 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:948 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490186 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:107 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 107:415293 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: send:74967 pto:107 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F628 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F628 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic key update server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 100 server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: fd:6 n:183 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic input handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx short flags:57 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:40 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:1 len:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:183 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:2148 rx:1719 valid:1 st:2 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:1 STREAM id:0x0 len:17 fin:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 is missing server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 create server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFBB840:16384 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 reusable connection: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 add cleanup: 00005609BFFBB900 server | 2025/04/30 12:22:23 [debug] 21#0: *6 post event 00005609BFF89AD0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 flow control msd:17/65536 md:17/8585216 server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFFBF850:4096 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:1 PADDING server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:1 largest 0 fr:0 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:app decr:1 pn:1 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490187 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74966 pto:106 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 1 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF89AD0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 delete posted event 00005609BFF89AD0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 quic init stream server | 2025/04/30 12:22:23 [debug] 21#0: *6 http3 init request stream server | 2025/04/30 12:22:23 [debug] 21#0: *6 add cleanup: 00005609BFFBB970 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 490186 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http wait request handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 recv buf:1024 server | 2025/04/30 12:22:23 [debug] 21#0: *6 quic stream id:0x0 recv len:17 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 flow update 17 server | 2025/04/30 12:22:23 [debug] 21#0: *6 reusable connection: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 posix_memalign: 00005609BFFC0860:4096 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http process request line server | 2025/04/30 12:22:23 [debug] 21#0: *6 http request line: "GET /sstdjfbcok" server | 2025/04/30 12:22:23 [debug] 21#0: *6 http uri: "/sstdjfbcok" server | 2025/04/30 12:22:23 [debug] 21#0: *6 http args: "" server | 2025/04/30 12:22:23 [debug] 21#0: *6 http exten: "" server | 2025/04/30 12:22:23 [debug] 21#0: *6 rewrite phase: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 test location: "/" server | 2025/04/30 12:22:23 [debug] 21#0: *6 using configuration "/" server | 2025/04/30 12:22:23 [debug] 21#0: *6 http cl:-1 max:1048576 server | 2025/04/30 12:22:23 [debug] 21#0: *6 rewrite phase: 2 server | 2025/04/30 12:22:23 [debug] 21#0: *6 post rewrite phase: 3 server | 2025/04/30 12:22:23 [debug] 21#0: *6 generic phase: 4 server | 2025/04/30 12:22:23 [debug] 21#0: *6 generic phase: 5 server | 2025/04/30 12:22:23 [debug] 21#0: *6 access phase: 6 server | 2025/04/30 12:22:23 [debug] 21#0: *6 access phase: 7 server | 2025/04/30 12:22:23 [debug] 21#0: *6 post access phase: 8 server | 2025/04/30 12:22:23 [debug] 21#0: *6 generic phase: 9 server | 2025/04/30 12:22:23 [debug] 21#0: *6 generic phase: 10 server | 2025/04/30 12:22:23 [debug] 21#0: *6 content phase: 11 server | 2025/04/30 12:22:23 [debug] 21#0: *6 content phase: 12 server | 2025/04/30 12:22:23 [debug] 21#0: *6 content phase: 13 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http filename: "/www/sstdjfbcok" server | 2025/04/30 12:22:23 [debug] 21#0: *6 add cleanup: 00005609BFFC1598 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http static fd: 3 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http set discard body server | 2025/04/30 12:22:23 [debug] 21#0: *6 http output filter "/sstdjfbcok?" server | 2025/04/30 12:22:23 [debug] 21#0: *6 http copy filter: "/sstdjfbcok?" server | 2025/04/30 12:22:23 [debug] 21#0: *6 malloc: 00005609BFFC1870:10240 server | 2025/04/30 12:22:23 [debug] 21#0: *6 read: 3, 00005609BFFC1870, 10240, 0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http postpone filter "/sstdjfbcok?" 00005609BFFC17B0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 write new buf t:1 f:0 00005609BFFC1870, pos 00005609BFFC1870, size: 10240 file: 0, size: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http write filter: l:1 f:0 s:10240 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http write filter limit 2097152 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFC4080:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFFC4290:4096 server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFFC52A0:4096 server | 2025/04/30 12:22:23 [debug] 21#0: *5 malloc: 00005609BFFC62B0:4096 server | 2025/04/30 12:22:23 [debug] 21#0: *6 quic send_chain sent:10240 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 flush limit:1048576 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 flush len:10240 last:0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http write filter 0000000000000000 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http copy filter: 0 "/sstdjfbcok?" server | 2025/04/30 12:22:23 [debug] 21#0: *6 http finalize request: 0, "/sstdjfbcok?" a:1, c:1 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http request count:1 blk:0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 http close request server | 2025/04/30 12:22:23 [debug] 21#0: *6 http log handler server | 2025/04/30 12:22:23 [debug] 21#0: *6 posix_memalign: 00005609BFFC72C0:4096 @16 server | 193.167.0.100 - - [30/Apr/2025:12:22:23 +0000] "GET /sstdjfbcok" 200 10240 "-" "-" server | 2025/04/30 12:22:23 [debug] 21#0: *6 run cleanup: 00005609BFFC1598 server | 2025/04/30 12:22:23 [debug] 21#0: *6 file cleanup: fd:3 server | 2025/04/30 12:22:23 [debug] 21#0: *6 free: 00005609BFFC1870 server | 2025/04/30 12:22:23 [debug] 21#0: *6 free: 00005609BFFC0860, unused: 48 server | 2025/04/30 12:22:23 [debug] 21#0: *6 free: 00005609BFFC72C0, unused: 3949 server | 2025/04/30 12:22:23 [debug] 21#0: *6 close http connection: 6 server | 2025/04/30 12:22:23 [debug] 21#0: *6 reusable connection: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *6 run cleanup: 00005609BFFBB970 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 75000:490187 server | 2025/04/30 12:22:23 [debug] 21#0: *6 run cleanup: 00005609BFFBB900 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 cleanup server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 send shutdown server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 flush limit:1038336 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFC0860:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 flush len:0 last:1 server | 2025/04/30 12:22:23 [debug] 21#0: *6 free: 00005609BFFBB840, unused: 14952 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:1 ACK n:0 delay:0 1-0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:10245 need:1169 shrink:9076 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFC0A70:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:1 STREAM id:0x0 off:0 len:1164 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:948 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:948 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:2148 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:9082 need:1174 shrink:7908 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:2 STREAM id:0x0 off:1164 len:1168 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:2 encoded nl:1 trunc:0x2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:2148 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:2148 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:3348 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:7914 need:1174 shrink:6740 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFC0C80:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:3 STREAM id:0x0 off:2332 len:1168 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:3 encoded nl:1 trunc:0x3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:3348 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:3348 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:4548 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:6746 need:1174 shrink:5572 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:4 STREAM id:0x0 off:3500 len:1168 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:4 encoded nl:1 trunc:0x4 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:4548 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:4548 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:5748 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:5578 need:1174 shrink:4404 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFC0E90:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:5 STREAM id:0x0 off:4668 len:1168 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:5 encoded nl:1 trunc:0x5 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:5748 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:5748 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:6948 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:4410 need:1174 shrink:3236 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:6 STREAM id:0x0 off:5836 len:1168 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:6 encoded nl:1 trunc:0x6 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:6948 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:6948 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:8148 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:3242 need:1174 shrink:2068 server | 2025/04/30 12:22:23 [debug] 21#0: *5 posix_memalign: 00005609BFFC10A0:512 @16 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:7 STREAM id:0x0 off:7004 len:1168 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:7 encoded nl:1 trunc:0x7 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:8148 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:8148 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:9348 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic split frame now:2074 need:1174 shrink:900 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:8 STREAM id:0x0 off:8172 len:1168 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:1174 need_ack:1 number:8 encoded nl:1 trunc:0x8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:9348 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:9348 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:10548 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:9 STREAM id:0x0 off:9340 len:900 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:9 STREAM id:0x0 off:10240 len:0 fin:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:911 need_ack:1 number:9 encoded nl:1 trunc:0x9 server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 937 of 937 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:10548 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:10548 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:11485 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490187 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415293 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:107 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 107:415294 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: send:74966 pto:107 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 99 client | Stream 0 ended after 10240 bytes client | All done, Closing the connection. server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: fd:6 n:55 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic input handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx short flags:74 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:60 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:2 len:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:55 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:12685 rx:1774 valid:1 st:2 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:2 ACK n:0 delay:3 2-0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:132002 ss:-1 if:10537 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_drop_ack_ranges pn:0 largest:1 fr:1 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:133202 ss:-1 if:9337 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_drop_ack_ranges pn:1 largest:1 fr:0 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1164 fin:0 unacked:9076 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:134402 ss:-1 if:8137 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:7908 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic updated largest received ack:2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic rtt sample latest:34 min:33 avg:33 var:12 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415294 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:57 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 57:415278 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:2 PADDING server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:2 largest -1 fr:0 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:app decr:1 pn:2 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490221 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74932 pto:57 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 34 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74932 pto:57 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 57 server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: fd:6 n:55 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic input handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx short flags:75 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:60 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:3 len:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:55 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:12685 rx:1829 valid:1 st:2 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:3 ACK n:0 delay:3 4-0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:135602 ss:-1 if:6937 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:6740 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:136802 ss:-1 if:5737 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:5572 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic updated largest received ack:4 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic rtt sample latest:35 min:33 avg:33 var:9 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415278 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:44 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 44:415266 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:3 PADDING server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:3 largest 2 fr:0 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:app decr:1 pn:3 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490222 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74931 pto:44 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 1 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74931 pto:44 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 44 server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: fd:6 n:55 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic input handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx short flags:77 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:60 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:4 len:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:55 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:12685 rx:1884 valid:1 st:2 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:4 ACK n:0 delay:2 6-0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:138002 ss:-1 if:4537 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:4404 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:139202 ss:-1 if:3337 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:3236 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic updated largest received ack:6 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic rtt sample latest:37 min:33 avg:33 var:8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415266 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:38 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 38:415262 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:4 PADDING server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:4 largest 3 fr:1 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:app decr:1 pn:4 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490224 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74929 pto:38 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 2 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74929 pto:38 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 38 server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: fd:6 n:55 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic input handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx short flags:6d server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:60 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:5 len:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:55 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:12685 rx:1939 valid:1 st:2 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:5 ACK n:0 delay:3 8-0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:140402 ss:-1 if:2137 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:2068 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:141602 ss:-1 if:937 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:900 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic updated largest received ack:8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic rtt sample latest:39 min:33 avg:33 var:7 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415262 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:32 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 32:415258 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:5 PADDING server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:5 largest 4 fr:2 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:app decr:1 pn:5 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490226 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74927 pto:32 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 2 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: read:74927 pto:32 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 32 server | 2025/04/30 12:22:23 [debug] 21#0: epoll: fd:6 ev:0001 d:00007FBED54EC108 server | 2025/04/30 12:22:23 [debug] 21#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic recvmsg: fd:6 n:46 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic input handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx short flags:6f server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx dcid len:20 0000000000001003232249c7476003111e3d219c server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx clearflags:60 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet rx number:6 len:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet len:46 via sock seq:0 path seq:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic path seq:0 status tx:12685 rx:1985 valid:1 st:2 mtu:1200 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:6 ACK n:0 delay:5 9-0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion slow start win:142539 ss:-1 if:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:900 fin:0 unacked:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 ack len:0 fin:1 unacked:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic stream id:0x0 close server | 2025/04/30 12:22:23 [debug] 21#0: *5 reusable connection: 1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 update posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic updated largest received ack:9 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic rtt sample latest:40 min:33 avg:34 var:7 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415258 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer unset server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame rx app:6 CONNECTION_CLOSE_APP err:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 post event 00005609BFF6F568 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_ack_packet pn:6 largest 5 fr:3 nranges:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet done rc:0 level:app decr:1 pn:6 perr:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490227 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: draining read:74926 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 1 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F4A8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic push handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic frame tx app:10 MAX_STREAMS limit:129 bidi:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic packet tx app bytes:3 need_ack:1 number:10 encoded nl:1 trunc:0xa server | 2025/04/30 12:22:23 [debug] 21#0: *5 sendmsg: 29 of 29 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic congestion send if:29 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer: 6, old: 490153, new: 490227 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic lost timer pto:72 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 72:415299 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic state: draining send:74926 pto:72 server | 2025/04/30 12:22:23 [debug] 21#0: posted event 00005609BFF6F568 server | 2025/04/30 12:22:23 [debug] 21#0: *5 delete posted event 00005609BFF6F568 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic close handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic close initiated rc:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic close immediate term:0 drain:1 error:1 "" server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer add: 6: 216:415443 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415299 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415286 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: 216 server | 2025/04/30 12:22:23 [debug] 21#0: timer delta: 217 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 415443 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic close handler server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic close resumed rc:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:0 closed nsock:7 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:1 closed nsock:6 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:2 closed nsock:5 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:3 closed nsock:4 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:4 closed nsock:3 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:5 closed nsock:2 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:6 closed nsock:1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic socket seq:7 closed nsock:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic close completed server | 2025/04/30 12:22:23 [debug] 21#0: *5 quic ngx_quic_send_alert() level:app alert:0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 SSL_shutdown: 1 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: 6: 490153 server | 2025/04/30 12:22:23 [debug] 21#0: *5 reusable connection: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 run cleanup: 00005609BFF63828 server | 2025/04/30 12:22:23 [debug] 21#0: *5 event timer del: -1: 490187 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC62B0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC52A0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC4290 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFBF850 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFB98B0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF64810 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF6F840 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF6EEA0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF877F0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF85B30, unused: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF6FE40, unused: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF629A0, unused: 56 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF62BB0, unused: 40 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF63CE0, unused: 48 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF66B10, unused: 48 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF63670, unused: 48 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF63880, unused: 40 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFF63A90, unused: 8 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFBADF0, unused: 24 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFBB000, unused: 40 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFBB210, unused: 40 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFBB420, unused: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFBB630, unused: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC4080, unused: 0 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC0860, unused: 32 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC0A70, unused: 32 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC0C80, unused: 32 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC0E90, unused: 32 server | 2025/04/30 12:22:23 [debug] 21#0: *5 free: 00005609BFFC10A0, unused: 192 server | 2025/04/30 12:22:23 [debug] 21#0: worker cycle server | 2025/04/30 12:22:23 [debug] 21#0: epoll timer: -1 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 10240 bytes in 0.404629 seconds, 0.202457 Mbps. client | Sent 17 bytes in 0.404629 seconds, 0.000336 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 ... 4181 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: 1200 client | System call duration max: 3 client | System call duration smoothed: 0 client | System call duration deviation: 0 client | Received ticket from server4 (hq-interop): client | ticket time = 1746015743127, kx = 17, suite = 1301, 282 ticket, 32 secret. client | lifetime = 300, age_add = 667296aa, 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 client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 12:22:33,856 Using the client's key log file. 2025-04-30 12:22:33,865 2025-04-30 12:22:33,865 Using the client's key log file. 2025-04-30 12:22:33,865 Using the client's key log file. 2025-04-30 12:22:33,874 2025-04-30 12:22:33,874 Using the client's key log file. 2025-04-30 12:22:34,432 Check of downloaded files succeeded. 2025-04-30 12:22:34,992 At least one QUIC packet could not be decrypted 2025-04-30 12:22:34,993 Packet (Length: 326) Layer ETH : Destination: 02:42:c1:a7:00:02 .... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) Source: 02:42:c1:a7:00:64 .... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) Type: IPv4 (0x0800) Stream index: 2 Layer IP : 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes (5) Differentiated Services Field: 0x01 (DSCP: CS0, ECN: ECT(1)) 0000 00.. = Differentiated Services Codepoint: Default (0) .... ..01 = Explicit Congestion Notification: ECN-Capable Transport codepoint '01' (1) Total Length: 312 Identification: 0x0000 (0) 010. .... = Flags: 0x2, Don't fragment 0... .... = Reserved bit: Not set .1.. .... = Don't fragment: Set ..0. .... = More fragments: Not set ...0 0000 0000 0000 = Fragment Offset: 0 Time to Live: 64 Protocol: UDP (17) Header Checksum: 0x519d [validation disabled] Header checksum status: Unverified Source Address: 193.167.0.100 Destination Address: 193.167.100.100 Stream index: 1 Layer UDP : Source Port: 47835 Destination Port: 443 Length: 292 Checksum: 0x1f38 [unverified] Checksum Status: Unverified Stream index: 0 Stream Packet Number: 5 Timestamps Time since first frame: 70.889000 milliseconds Time since previous frame: 404.000 microseconds UDP payload (284 bytes) Layer QUIC : QUIC Connection information Connection Number: 0 Packet Length: 101 1... .... = Header Form: Long Header (1) .1.. .... = Fixed Bit: True ..10 .... = Packet Type: Handshake (2) Version: 1 (0x00000001) Destination Connection ID Length: 20 Destination Connection ID: 0000000000001003232249c7476003111e3d219c Source Connection ID Length: 8 Source Connection ID: cc15c54a1302557a Length: 64 Expert Info (Warning/Decryption): Failed to create decryption context: Secrets are not available Failed to create decryption context: Secrets are not available Severity level: Warning Group: Decryption Remaining Payload: 6ffac317e143645924434d84d6c69f7d3ca078e0ac899f4fb68c0ccc6e1eb9784c2e456891af84d2f30491478180d01ecac5364ca02b6caddd0708300f566ed7 Layer QUIC : Packet Length: 183 QUIC Short Header DCID=0000000000001003232249c7476003111e3d219c 0... .... = Header Form: Short Header (0) .1.. .... = Fixed Bit: True ..0. .... = Spin Bit: False Destination Connection ID: 0000000000001003232249c7476003111e3d219c Expert Info (Warning/Decryption): Failed to create decryption context: Secrets are not available Failed to create decryption context: Secrets are not available Severity level: Warning Group: Decryption Remaining Payload […]: e2228dc389f9e73ae313b8ca413b22dd58070b0809f79910abfa09efde8ef7d2e4166702be415895e48591299cc80b7eaf6d6bc17a42ed1cc9e6131ca55e96f2aad9be59a24615f4b4ef40410709daf41e092bec58edf651cd2cb79040ee6e0e769614ddbb91ee4eda9aac 2025-04-30 12:22:34,994 Check of Retry succeeded. Token used: 27906b25378762b859b0abbef65d7b1f632bb7ee8da9de4ccf7b029be3d7b7dcfc154930f09195d32f675e615151c4fc49e73a90f8494bc5bdcebccf92febbcbd8ee