2025-04-30 11:28:51,713 Generated random file: agmtjrmbzu of size: 10240 2025-04-30 11:28:51,713 Requests: https://server4:443/agmtjrmbzu 2025-04-30 11:28:51,770 2025-04-30 11:28:51,771 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_htxx49qa/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_z7ee7_nn/ DOWNLOADS=/tmp/download_ztd5o_k2/ SERVER_LOGS=/tmp/logs_server_fx9525o9 CLIENT_LOGS=/tmp/logs_client_x2pcvp1l SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=peterdoornbosch/kwik_n_flupke-interop SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/agmtjrmbzu" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 11:29:05,734 Container sim Created Container client Recreate Container server Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Setting up routes... server | Actual changes: server | tx-checksumming: off server | tx-checksum-ip-generic: off server | tx-checksum-sctp: off server | tcp-segmentation-offload: off server | tx-tcp-segmentation: off [requested on] server | tx-tcp-ecn-segmentation: off [requested on] server | tx-tcp-mangleid-segmentation: off [requested on] server | tx-tcp6-segmentation: off [requested on] server | tx-udp-segmentation: off [requested on] server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 server | >>> Starting nginx server... server | nginx version: nginx/1.27.0 (9270:d1b8568f3042) server | built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | built with OpenSSL 1.1.1 (compatible; BoringSSL) (running with BoringSSL) server | TLS SNI support enabled server | configure arguments: --prefix=/etc/nginx --build=9270:d1b8568f3042 --sbin-path=/usr/sbin/nginx --modules-path=/usr/lib/nginx/modules --conf-path=/etc/nginx/nginx.conf --error-log-path=/var/log/nginx/error.log --http-log-path=/var/log/nginx/access.log --pid-path=/run/nginx.pid --lock-path=/run/nginx.lock --http-client-body-temp-path=/var/cache/nginx/client_temp --http-proxy-temp-path=/var/cache/nginx/proxy_temp --http-fastcgi-temp-path=/var/cache/nginx/fastcgi_temp --http-uwsgi-temp-path=/var/cache/nginx/uwsgi_temp --http-scgi-temp-path=/var/cache/nginx/scgi_temp --user=nginx --group=nginx --with-compat --with-debug --with-http_ssl_module --with-http_v2_module --with-http_v3_module --with-cc=c++ --with-cc-opt='-I/boringssl/include -O0 -fno-common -fno-omit-frame-pointer -DNGX_QUIC_DRAFT_VERSION=29 -DNGX_HTTP_V3_HQ=1 -x c' --with-ld-opt='-L/boringssl/build/ssl -L/boringssl/build/crypto' server | >>> Parameters: server | >>> Test case: retry server | 2025/04/30 11:28:52 [debug] 21#0: bind() 0.0.0.0:443 #5 server | 2025/04/30 11:28:52 [debug] 21#0: bind() 0.0.0.0:443 #6 server | 2025/04/30 11:28:52 [debug] 21#0: bind() [::]:443 #7 server | 2025/04/30 11:28:52 [debug] 21#0: bind() [::]:443 #8 server | 2025/04/30 11:28:52 [debug] 21#0: add cleanup: 00005654261E3780 server | 2025/04/30 11:28:52 [notice] 21#0: using the "epoll" event method server | 2025/04/30 11:28:52 [debug] 21#0: counter: 00007F2D75B6D080, 1 server | 2025/04/30 11:28:52 [notice] 21#0: nginx/1.27.0 (9270:d1b8568f3042) server | 2025/04/30 11:28:52 [notice] 21#0: built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | 2025/04/30 11:28:52 [notice] 21#0: OS: Linux 6.11.0-1012-azure server | 2025/04/30 11:28:52 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 1048576:1048576 server | 2025/04/30 11:28:52 [debug] 21#0: write: 9, 00007FFDD331FD70, 3, 0 server | 2025/04/30 11:28:52 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf.retry" server | 2025/04/30 11:28:52 [notice] 21#0: start worker processes server | 2025/04/30 11:28:52 [debug] 21#0: channel 3:9 server | 2025/04/30 11:28:52 [notice] 21#0: start worker process 22 server | 2025/04/30 11:28:52 [debug] 21#0: sigsuspend server | 2025/04/30 11:28:52 [debug] 22#0: add cleanup: 00005654261E3808 server | 2025/04/30 11:28:52 [debug] 22#0: malloc: 00005654261E67D0:16 server | 2025/04/30 11:28:52 [debug] 22#0: add cleanup: 00005654261E3820 server | 2025/04/30 11:28:52 [debug] 22#0: malloc: 00005654261E4AD0:16 server | 2025/04/30 11:28:52 [debug] 22#0: notify eventfd: 11 server | 2025/04/30 11:28:52 [debug] 22#0: testing the EPOLLRDHUP flag: success server | 2025/04/30 11:28:52 [debug] 22#0: malloc: 00005654261CC430:6144 server | 2025/04/30 11:28:52 [debug] 22#0: malloc: 00007F2D754DF010:253952 server | 2025/04/30 11:28:52 [debug] 22#0: malloc: 00005654261E8890:98304 server | 2025/04/30 11:28:52 [debug] 22#0: malloc: 00005654262008A0:98304 server | 2025/04/30 11:28:52 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001 server | 2025/04/30 11:28:52 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001 server | 2025/04/30 11:28:52 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001 server | 2025/04/30 11:28:52 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001 server | 2025/04/30 11:28:52 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001 server | 2025/04/30 11:28:52 [debug] 22#0: setproctitle: "nginx: worker process" server | 2025/04/30 11:28:52 [debug] 22#0: worker cycle server | 2025/04/30 11:28:52 [debug] 22#0: epoll timer: -1 client | Certificate was added to keystore client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | 2025/04/30 11:28:53 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:53 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:53 [debug] 22#0: posix_memalign: 00005654261E4B30:512 @16 server | 2025/04/30 11:28:53 [debug] 22#0: malloc: 00005654261E67F0:1207 server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:41083 fd:6 n:1207 server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic run server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic packet rx dcid len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic packet rx scid len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic address validation token len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *1 sending version negotiation packet server | 2025/04/30 11:28:53 [debug] 22#0: *1 sendmsg: 11 of 11 server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 11:28:53 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 11:28:53 [debug] 22#0: *1 reusable connection: 0 server | 2025/04/30 11:28:53 [debug] 22#0: *1 free: 00005654261E67F0 server | 2025/04/30 11:28:53 [debug] 22#0: *1 free: 00005654261E4B30, unused: 24 server | 2025/04/30 11:28:53 [debug] 22#0: timer delta: 794 server | 2025/04/30 11:28:53 [debug] 22#0: worker cycle server | 2025/04/30 11:28:53 [debug] 22#0: epoll timer: -1 server | 2025/04/30 11:28:53 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:53 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:53 [debug] 22#0: posix_memalign: 00005654261E4B30:512 @16 server | 2025/04/30 11:28:53 [debug] 22#0: malloc: 00005654261E67F0:1207 server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic recvmsg: 193.167.100.2:41083 fd:6 n:1207 server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic run server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic packet rx dcid len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic address validation token len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *2 sending version negotiation packet server | 2025/04/30 11:28:53 [debug] 22#0: *2 sendmsg: 11 of 11 server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 11:28:53 [debug] 22#0: *2 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 11:28:53 [debug] 22#0: *2 reusable connection: 0 server | 2025/04/30 11:28:53 [debug] 22#0: *2 free: 00005654261E67F0 server | 2025/04/30 11:28:53 [debug] 22#0: *2 free: 00005654261E4B30, unused: 24 server | 2025/04/30 11:28:53 [debug] 22#0: timer delta: 0 server | 2025/04/30 11:28:53 [debug] 22#0: worker cycle server | 2025/04/30 11:28:53 [debug] 22#0: epoll timer: -1 server | 2025/04/30 11:28:53 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:53 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:53 [debug] 22#0: posix_memalign: 00005654261E4B30:512 @16 server | 2025/04/30 11:28:53 [debug] 22#0: malloc: 00005654261E67F0:1207 server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic recvmsg: 193.167.100.2:41083 fd:6 n:1207 server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic run server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic packet rx dcid len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic packet rx scid len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic address validation token len:0 server | 2025/04/30 11:28:53 [debug] 22#0: *3 sending version negotiation packet server | 2025/04/30 11:28:53 [debug] 22#0: *3 sendmsg: 11 of 11 server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 11:28:53 [debug] 22#0: *3 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 11:28:53 [debug] 22#0: *3 reusable connection: 0 server | 2025/04/30 11:28:53 [debug] 22#0: *3 free: 00005654261E67F0 server | 2025/04/30 11:28:53 [debug] 22#0: *3 free: 00005654261E4B30, unused: 24 server | 2025/04/30 11:28:53 [debug] 22#0: timer delta: 0 server | 2025/04/30 11:28:53 [debug] 22#0: worker cycle server | 2025/04/30 11:28:53 [debug] 22#0: epoll timer: -1 sim | server:443 is available after 1.014821113s 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 | 28:53.928 Creating connection with server4:443 with v1 client | 28:54.005 Original destination connection id: bb4c678a918390c5 (scid: b7ab4676c9209522) client | >- CryptoStream[I|ClientHello] server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: posix_memalign: 00005654261E4B30:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: malloc: 00005654261E67F0:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic recvmsg: 193.167.0.100:52595 fd:6 n:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic run server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic packet rx long flags:c6 version:1 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic packet rx init len:1174 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic packet rx dcid len:8 bb4c678a918390c5 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic packet rx scid len:8 b7ab4676c9209522 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic address validation token len:0 server | 2025/04/30 11:28:54 [debug] 22#0: *4 sendmsg: 117 of 117 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic retry packet sent to b7ab4676c9209522 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *4 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 11:28:54 [debug] 22#0: *4 reusable connection: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *4 free: 00005654261E67F0 server | 2025/04/30 11:28:54 [debug] 22#0: *4 free: 00005654261E4B30, unused: 24 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 1052 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: -1 client | 28:54.161 -> Packet I|0|L|1200|2 Token=[] CryptoFrame[0,257] Padding(896) client | 28:54.198 <- (1) Packet I|-|R|117| Retry Token (66): 3223716e17deca015e816dbe70417e65d390e90bacb0cb53b35d18d397284a1918f4f9ba1f0236db92b380ed51a7b84a89d5d8da39740fc6e31fbea76d8bf574805b client | 28:54.224 -> Packet I|1|L|1201|2 Token=3223716e17deca015e816dbe70417e65d390e90bacb0cb53b35d18d397284a1918f4f9ba1f0236db92b380ed51a7b84a89d5d8da39740fc6e31fbea76d8bf574805b CryptoFrame[0,257] Padding(818) server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: posix_memalign: 00005654261E4B30:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: malloc: 00005654261E67F0:1201 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: 193.167.0.100:52595 fd:6 n:1201 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic run server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx long flags:c6 version:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx init len:1096 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 549f23e801cc41d1135db5987cacba5479fbab13 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx scid len:8 b7ab4676c9209522 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic address validation token len:66 3223716e17deca015e816dbe70417e65d390e90bacb0cb53b35d18d397284a1918f4f9ba1f0236db92b380ed51a7b84a89d5d8da39740fc6e31fbea76d8bf574805b server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 00005654261CDEA0:2448 server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 00005654261CE840:1528 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654261CEE40:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_set_initial_secret server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic socket seq:0 listening at sid:0000000000001003e8e598e4d144f43dc2d6240c nsock:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic cid seq:0 received id:8:b7ab4676c9209522:00000000000000000000000000000000 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654261C19A0:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 created addr:193.167.0.100:52595 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 set active tx:0 rx:0 valid:1 st:0 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654261C1BB0:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic socket seq:-1 listening at sid:549f23e801cc41d1135db5987cacba5479fbab13 nsock:2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 reusable connection: 1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic connection created server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:c0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:1 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:1201 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:0 rx:1201 valid:1 st:0 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stateless reset token 8367c625e278f261b33d680e522e84c9 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654261C2CE0:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx init:1 CRYPTO len:257 off:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL server name: "server4" server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL ALPN supported by client: hq-interop server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL ALPN selected: hq-interop server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic SSL_get_peer_quic_transport_params(): params_len:59 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic transport parameters parsed ok server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp disable active migration: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp idle_timeout:60000 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp max_udp_payload_size:1500 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp max_data:2500000 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp max_stream_data_bidi_local:250000 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp max_stream_data_bidi_remote:250000 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp max_stream_data_uni:250000 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp initial_max_streams_bidi:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp initial_max_streams_uni:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp ack_delay_exponent:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp max_ack_delay:25 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp active_connection_id_limit:2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic tp initial source_connection_id len:8 b7ab4676c9209522 server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 00005654261C37F0:4096 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_set_write_secret() level:2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 00005654261C5900:4096 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654262188B0:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_set_write_secret() level:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_flush_flight() server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_set_read_secret() level:2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL_do_handshake: -1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL_get_error: 2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx init:1 PADDING server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:1 largest -1 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:init decr:1 pn:1 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 60000:367840 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 60000:367840 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:60000 close:60000 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 58 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output init packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx init:0 ACK n:0 delay:0 1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx init:0 CRYPTO len:123 off:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx init bytes:132 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output hs packet max:1013 min:1013 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx hs:0 CRYPTO len:740 off:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx hs bytes:959 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:187 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367840 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:997 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 997:308837 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: send:60000 pto:997 close:60000 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 997 client | 28:54.258 <- (2) Packet I|0|L|187|2 Token=[] AckFrame[1|?0] CryptoFrame[0,123] client | -< CryptoStream[I|ServerHello] client | 28:54.286 -> Packet I|2|L|1202|2 Token=3223716e17deca015e816dbe70417e65d390e90bacb0cb53b35d18d397284a1918f4f9ba1f0236db92b380ed51a7b84a89d5d8da39740fc6e31fbea76d8bf574805b AckFrame[0|?0] Padding(1075) client | 28:54.258 <- (2) Packet H|0|L|1013|2 CryptoFrame[0,740] Padding(215) server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:1202 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx long flags:c1 version:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx init len:1097 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx scid len:8 b7ab4676c9209522 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic address validation token len:66 3223716e17deca015e816dbe70417e65d390e90bacb0cb53b35d18d397284a1918f4f9ba1f0236db92b380ed51a7b84a89d5d8da39740fc6e31fbea76d8bf574805b server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:c0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:2 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:1202 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:1200 rx:2403 valid:1 st:0 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx init:2 ACK n:0 delay:0 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion ack recovery win:131054 ss:-1 if:1013 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_drop_ack_ranges pn:1 largest:1 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic updated largest received ack:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic rtt sample latest:62 min:62 avg:62 var:31 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308837 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:124 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 124:308026 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx init:2 PADDING server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:2 largest -1 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:init decr:1 pn:2 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367902 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59938 pto:124 close:59938 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 62 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59938 pto:124 close:59938 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 124 client | >- CryptoStream[H|Finished] client | 28:54.319 -> Packet H|0|L|92|1 CryptoFrame[0,36] client | -< CryptoStream[H|EncryptedExtensions,Certificate,CertificateVerify,Finished] client | 28:54.323 -> Packet H|1|L|58|1 AckFrame[0|?0] server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:92 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx long flags:e6 version:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx hs len:56 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx scid len:8 b7ab4676c9209522 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:e0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:0 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:92 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:1200 rx:2495 valid:1 st:0 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic socket seq:-1 closed nsock:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308026 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:93 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 93:308026 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx hs:0 CRYPTO len:36 off:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_set_read_secret() level:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_add_handshake_data server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_flush_flight() server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL_do_handshake: 1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL: TLSv1.3, cipher: "TLS_AES_128_GCM_SHA256 Kx=GENERIC Au=GENERIC Enc=AESGCM(128) Mac=AEAD" server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 0000565426219A30:4096 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654261C2670:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE628 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion ack recovery win:131054 ss:-1 if:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308026 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer unset server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 schedule mtu:1500 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 100:308033 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic create sockets has:1 max:2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic socket seq:1 listening at sid:00000000000010039f9d471b445f8e9e22ccbe46 nsock:2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stateless reset token 1ccd9a6476d2bef5dee4e0f077f8fcb3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic init streams server | 2025/04/30 11:28:54 [debug] 22#0: *5 http3 init server | 2025/04/30 11:28:54 [debug] 22#0: *5 http3 init session server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654261C2880:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 add cleanup: 00005654261C1B88 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 75000:382933 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 367840 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:hs decr:1 pn:0 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367933 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59907 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 31 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:0 CRYPTO len:572 off:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:0 HANDSHAKE DONE server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:0 NEW_TOKEN server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:677 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 703 of 703 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:703 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367933 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:211 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 211:308144 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: send:59907 pto:211 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE628 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE628 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic key update server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 100 server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:58 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx long flags:ed version:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx hs len:22 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx scid len:8 b7ab4676c9209522 server | 2025/04/30 11:28:54 [info] 22#0: *5 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 4 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 96 client | 28:54.329 -> Packet A|0|S0|0000000000001003e8e598e4d144f43dc2d6240c|59|1 StreamFrame[0(CIB),0,17,fin] server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:59 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx short flags:4f server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:40 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:0 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:59 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:1903 rx:2554 valid:1 st:2 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx app:0 STREAM id:0x0 off:0 len:17 fin:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 is missing server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 create server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 000056542621AA40:16384 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 reusable connection: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 add cleanup: 000056542621AB00 server | 2025/04/30 11:28:54 [debug] 22#0: *6 post event 00005654261E8AD0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 flow control msd:17/65536 md:17/8585216 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:0 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367943 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59897 pto:201 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 6 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261E8AD0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 delete posted event 00005654261E8AD0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 quic init stream server | 2025/04/30 11:28:54 [debug] 22#0: *6 http3 init request stream server | 2025/04/30 11:28:54 [debug] 22#0: *6 add cleanup: 000056542621AB70 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 382933 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http wait request handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 recv buf:1024 server | 2025/04/30 11:28:54 [debug] 22#0: *6 quic stream id:0x0 recv len:17 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 flow update 17 server | 2025/04/30 11:28:54 [debug] 22#0: *6 reusable connection: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 posix_memalign: 000056542621EA50:4096 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http process request line server | 2025/04/30 11:28:54 [debug] 22#0: *6 http request line: "GET /agmtjrmbzu" server | 2025/04/30 11:28:54 [debug] 22#0: *6 http uri: "/agmtjrmbzu" server | 2025/04/30 11:28:54 [debug] 22#0: *6 http args: "" server | 2025/04/30 11:28:54 [debug] 22#0: *6 http exten: "" server | 2025/04/30 11:28:54 [debug] 22#0: *6 rewrite phase: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 test location: "/" server | 2025/04/30 11:28:54 [debug] 22#0: *6 using configuration "/" server | 2025/04/30 11:28:54 [debug] 22#0: *6 http cl:-1 max:1048576 server | 2025/04/30 11:28:54 [debug] 22#0: *6 rewrite phase: 2 server | 2025/04/30 11:28:54 [debug] 22#0: *6 post rewrite phase: 3 server | 2025/04/30 11:28:54 [debug] 22#0: *6 generic phase: 4 server | 2025/04/30 11:28:54 [debug] 22#0: *6 generic phase: 5 server | 2025/04/30 11:28:54 [debug] 22#0: *6 access phase: 6 server | 2025/04/30 11:28:54 [debug] 22#0: *6 access phase: 7 server | 2025/04/30 11:28:54 [debug] 22#0: *6 post access phase: 8 server | 2025/04/30 11:28:54 [debug] 22#0: *6 generic phase: 9 server | 2025/04/30 11:28:54 [debug] 22#0: *6 generic phase: 10 server | 2025/04/30 11:28:54 [debug] 22#0: *6 content phase: 11 server | 2025/04/30 11:28:54 [debug] 22#0: *6 content phase: 12 server | 2025/04/30 11:28:54 [debug] 22#0: *6 content phase: 13 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http filename: "/www/agmtjrmbzu" server | 2025/04/30 11:28:54 [debug] 22#0: *6 add cleanup: 000056542621F788 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http static fd: 3 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http set discard body server | 2025/04/30 11:28:54 [debug] 22#0: *6 http output filter "/agmtjrmbzu?" server | 2025/04/30 11:28:54 [debug] 22#0: *6 http copy filter: "/agmtjrmbzu?" server | 2025/04/30 11:28:54 [debug] 22#0: *6 malloc: 000056542621FA60:10240 server | 2025/04/30 11:28:54 [debug] 22#0: *6 read: 3, 000056542621FA60, 10240, 0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http postpone filter "/agmtjrmbzu?" 000056542621F9A0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 write new buf t:1 f:0 000056542621FA60, pos 000056542621FA60, size: 10240 file: 0, size: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http write filter: l:1 f:0 s:10240 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http write filter limit 2097152 server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 0000565426222270:4096 server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 0000565426223280:4096 server | 2025/04/30 11:28:54 [debug] 22#0: *5 malloc: 0000565426224290:4096 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 00005654261C2A90:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *6 quic send_chain sent:10240 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 flush limit:250000 server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 flush len:10240 last:0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http write filter 0000000000000000 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http copy filter: 0 "/agmtjrmbzu?" server | 2025/04/30 11:28:54 [debug] 22#0: *6 http finalize request: 0, "/agmtjrmbzu?" a:1, c:1 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http request count:1 blk:0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 http close request server | 2025/04/30 11:28:54 [debug] 22#0: *6 http log handler server | 2025/04/30 11:28:54 [debug] 22#0: *6 posix_memalign: 00005654262252A0:4096 @16 server | 193.167.0.100 - - [30/Apr/2025:11:28:54 +0000] "GET /agmtjrmbzu" 200 10240 "-" "-" server | 2025/04/30 11:28:54 [debug] 22#0: *6 run cleanup: 000056542621F788 server | 2025/04/30 11:28:54 [debug] 22#0: *6 file cleanup: fd:3 server | 2025/04/30 11:28:54 [debug] 22#0: *6 free: 000056542621FA60 server | 2025/04/30 11:28:54 [debug] 22#0: *6 free: 000056542621EA50, unused: 48 server | 2025/04/30 11:28:54 [debug] 22#0: *6 free: 00005654262252A0, unused: 3949 server | 2025/04/30 11:28:54 [debug] 22#0: *6 close http connection: 6 server | 2025/04/30 11:28:54 [debug] 22#0: *6 reusable connection: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *6 run cleanup: 000056542621AB70 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 75000:382943 server | 2025/04/30 11:28:54 [debug] 22#0: *6 run cleanup: 000056542621AB00 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 cleanup server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 send shutdown server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 flush limit:239760 server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 flush len:0 last:1 server | 2025/04/30 11:28:54 [debug] 22#0: *6 free: 000056542621AA40, unused: 14952 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 000056542621AA40:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:1 ACK n:0 delay:0 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:10245 need:1169 shrink:9076 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:1 STREAM id:0x0 off:0 len:1164 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:703 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:703 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:1903 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:9082 need:1174 shrink:7908 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 000056542621AC50:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:2 STREAM id:0x0 off:1164 len:1168 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:2 encoded nl:1 trunc:0x2 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:1903 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:1903 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:3103 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:7914 need:1174 shrink:6740 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:3 STREAM id:0x0 off:2332 len:1168 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:3 encoded nl:1 trunc:0x3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:3103 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:3103 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:4303 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:6746 need:1174 shrink:5572 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 000056542621AE60:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:4 STREAM id:0x0 off:3500 len:1168 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:4 encoded nl:1 trunc:0x4 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:4303 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:4303 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:5503 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:5578 need:1174 shrink:4404 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:5 STREAM id:0x0 off:4668 len:1168 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:5 encoded nl:1 trunc:0x5 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:5503 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:5503 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:6703 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:4410 need:1174 shrink:3236 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 000056542621B070:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:6 STREAM id:0x0 off:5836 len:1168 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:6 encoded nl:1 trunc:0x6 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:6703 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:6703 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:7903 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:3242 need:1174 shrink:2068 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:7 STREAM id:0x0 off:7004 len:1168 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:7 encoded nl:1 trunc:0x7 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:7903 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:7903 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:9103 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic split frame now:2074 need:1174 shrink:900 server | 2025/04/30 11:28:54 [debug] 22#0: *5 posix_memalign: 000056542621B280:512 @16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:8 STREAM id:0x0 off:8172 len:1168 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:1174 need_ack:1 number:8 encoded nl:1 trunc:0x8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 1200 of 1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:9103 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:9103 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:10303 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:9 STREAM id:0x0 off:9340 len:900 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:9 STREAM id:0x0 off:10240 len:0 fin:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:911 need_ack:1 number:9 encoded nl:1 trunc:0x9 server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 937 of 937 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:10303 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:10303 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:11240 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367943 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308144 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:211 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 211:308154 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: send:59897 pto:211 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 90 client | 28:54.353 <- (3) Packet A|0|S0|b7ab4676c9209522|703|4 CryptoFrame[0,572] HandshakeDoneFrame[] NewTokenFrame[f3070c636b5cc07f03f302fa9cb2afeaa5c611b2d5a82b7cdf0915fffc1444e8fbe8bbf9374f3e414f0a0a0e9dc06d7c2dd3d61f44c98e45a103] NewConnectionIdFrame[1,<0|00000000000010039f9d471b445f8e9e22ccbe46|1ccd9a6476d2bef5dee4e0f077f8fcb3] client | -< CryptoStream[A|NewSessionTicket,NewSessionTicket] client | 28:54.362 <- (4) Packet A|1|S0|b7ab4676c9209522|1200|2 AckFrame[0|?0] StreamFrame[0(CIB),0,1164] client | 28:54.362 <- (5) Packet A|2|S0|b7ab4676c9209522|1200|1 StreamFrame[0(CIB),1164,1168] client | 28:54.364 <- (6) Packet A|3|S0|b7ab4676c9209522|1200|1 StreamFrame[0(CIB),2332,1168] client | 28:54.365 -> Packet A|1|S0|0000000000001003e8e598e4d144f43dc2d6240c|44|1 AckFrame[1-0|?3] client | 28:54.364 <- (7) Packet A|4|S0|b7ab4676c9209522|1200|1 StreamFrame[0(CIB),3500,1168] client | 28:54.369 -> Packet A|2|S0|0000000000001003e8e598e4d144f43dc2d6240c|44|1 AckFrame[4-0|?2] client | 28:54.366 <- (8) Packet A|5|S0|b7ab4676c9209522|1200|1 StreamFrame[0(CIB),4668,1168] client | 28:54.366 <- (9) Packet A|6|S0|b7ab4676c9209522|1200|1 StreamFrame[0(CIB),5836,1168] client | 28:54.367 <- (10) Packet A|7|S0|b7ab4676c9209522|1200|1 StreamFrame[0(CIB),7004,1168] client | 28:54.368 <- (11) Packet A|8|S0|b7ab4676c9209522|1200|1 StreamFrame[0(CIB),8172,1168] client | 28:54.369 <- (12) Packet A|9|S0|b7ab4676c9209522|937|2 StreamFrame[0(CIB),9340,900] StreamFrame[0(CIB),10240,0,fin] client | 28:54.372 -> Packet A|3|S0|0000000000001003e8e598e4d144f43dc2d6240c|44|1 AckFrame[6-0|?1] client | 28:54.375 -> Packet A|4|S0|0000000000001003e8e598e4d144f43dc2d6240c|44|1 AckFrame[9-0|?2] server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:44 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx short flags:54 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:40 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:1 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:44 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:12440 rx:2598 valid:1 st:2 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx app:1 ACK n:0 delay:375 1-0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:131757 ss:-1 if:10537 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:132957 ss:-1 if:9337 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_drop_ack_ranges pn:0 largest:0 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1164 fin:0 unacked:9076 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic updated largest received ack:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic rtt sample latest:38 min:38 avg:59 var:30 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308154 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:166 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 166:308147 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:1 largest -1 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:1 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367981 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59859 pto:166 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 38 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59859 pto:166 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 52 client | Get requested finished in 0.37 sec (0.03 MB/s) : tech.kwik.h09.client.HttpResponseImpl@2ddc8ecb server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:44 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx short flags:50 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:40 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:2 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:44 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:12440 rx:2642 valid:1 st:2 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx app:2 ACK n:0 delay:250 4-0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:134157 ss:-1 if:8137 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:7908 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:135357 ss:-1 if:6937 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:6740 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:136557 ss:-1 if:5737 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:5572 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic updated largest received ack:4 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic rtt sample latest:41 min:38 avg:56 var:28 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308147 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:152 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 152:308136 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:2 largest 1 fr:0 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:2 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367984 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59856 pto:152 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 3 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59856 pto:152 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 49 server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:44 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx short flags:53 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:40 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:3 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:44 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:12440 rx:2686 valid:1 st:2 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx app:3 ACK n:0 delay:125 6-0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:137757 ss:-1 if:4537 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:4404 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:138957 ss:-1 if:3337 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:3236 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic updated largest received ack:6 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic rtt sample latest:44 min:38 avg:54 var:24 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308136 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:131 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 131:308118 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:3 largest 2 fr:1 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:3 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367987 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59853 pto:131 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 3 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59853 pto:131 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 46 server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:44 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx short flags:53 client | 28:54.391 Closing ClientConnection[bb4c678a918390c5/b7ab4676c9209522(V1) with server4/193.167.100.100:443] (reason: ImmediateClose) server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:40 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:4 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:44 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:12440 rx:2730 valid:1 st:2 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx app:4 ACK n:0 delay:250 9-0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:140157 ss:-1 if:2137 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:2068 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:141357 ss:-1 if:937 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:1168 fin:0 unacked:900 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion slow start win:142294 ss:-1 if:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:900 fin:0 unacked:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 ack len:0 fin:1 unacked:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic stream id:0x0 close server | 2025/04/30 11:28:54 [debug] 22#0: *5 reusable connection: 1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 update posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic updated largest received ack:9 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic rtt sample latest:47 min:38 avg:53 var:20 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308118 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer unset server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:4 largest 3 fr:2 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:4 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367990 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: read:59850 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 3 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE4A8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic push handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic output app packet max:1200 min:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame tx app:10 MAX_STREAMS limit:129 bidi:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet tx app bytes:3 need_ack:1 number:10 encoded nl:1 trunc:0xa server | 2025/04/30 11:28:54 [debug] 22#0: *5 sendmsg: 29 of 29 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic congestion send if:29 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 367990 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:158 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 158:308148 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: send:59850 pto:158 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 43 client | 28:54.393 -> Packet A|5|S0|0000000000001003e8e598e4d144f43dc2d6240c|47|2 ConnectionCloseFrame[0|0|] AckFrame[9-0|?0] server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:47 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx short flags:47 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:40 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:5 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:47 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:12469 rx:2777 valid:1 st:2 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx app:5 CONNECTION_CLOSE err:0 ft:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic frame rx app:5 ACK n:0 delay:0 9-0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_handle_ack_frame level:3 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308148 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic lost timer pto:141 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 141:308148 server | 2025/04/30 11:28:54 [debug] 22#0: *5 post event 00005654261CE568 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_ack_packet pn:5 largest 4 fr:3 nranges:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:5 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 368007 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: draining read:59833 pto:141 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 17 server | 2025/04/30 11:28:54 [debug] 22#0: posted event 00005654261CE568 server | 2025/04/30 11:28:54 [debug] 22#0: *5 delete posted event 00005654261CE568 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic close handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic close initiated rc:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic close immediate term:0 drain:1 error:1 "" server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer add: 6: 474:308481 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308148 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308033 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 474 client | 28:54.408 <- (13) Packet A|10|S0|b7ab4676c9209522|29|1 MaxStreamsFrame[B,129] client | 28:54.412 -> Packet A|6|S0|0000000000001003e8e598e4d144f43dc2d6240c|47|2 ConnectionCloseFrame[0|0|] AckFrame[9-0|?0] server | 2025/04/30 11:28:54 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F2D754DF108 server | 2025/04/30 11:28:54 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic recvmsg: fd:6 n:47 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic input handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx short flags:59 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx dcid len:20 0000000000001003e8e598e4d144f43dc2d6240c server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx clearflags:40 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet rx number:6 len:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet len:47 via sock seq:0 path seq:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic path seq:0 status tx:12469 rx:2824 valid:1 st:2 mtu:1200 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic packet done rc:0 level:app decr:1 pn:6 perr:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer: 6, old: 367840, new: 368027 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic state: error:0 "connection is closing, packet discarded" closing draining read:59813 close:454 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 20 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: 454 server | 2025/04/30 11:28:54 [debug] 22#0: timer delta: 455 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 308481 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic close handler server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic close resumed rc:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic socket seq:0 closed nsock:1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic socket seq:1 closed nsock:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic close completed server | 2025/04/30 11:28:54 [debug] 22#0: *5 quic ngx_quic_send_alert() level:app alert:0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 SSL_shutdown: 1 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: 6: 367840 server | 2025/04/30 11:28:54 [debug] 22#0: *5 reusable connection: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 run cleanup: 00005654261C1B88 server | 2025/04/30 11:28:54 [debug] 22#0: *5 event timer del: -1: 382943 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 0000565426224290 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 0000565426223280 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 0000565426222270 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 0000565426219A30 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C5900 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C37F0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261CE840 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261CDEA0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261E67F0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261E4B30, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261CEE40, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C19A0, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C1BB0, unused: 8 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C2CE0, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654262188B0, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C2670, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C2880, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 00005654261C2A90, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 000056542621AA40, unused: 0 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 000056542621AC50, unused: 16 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 000056542621AE60, unused: 32 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 000056542621B070, unused: 112 server | 2025/04/30 11:28:54 [debug] 22#0: *5 free: 000056542621B280, unused: 336 server | 2025/04/30 11:28:54 [debug] 22#0: worker cycle server | 2025/04/30 11:28:54 [debug] 22#0: epoll timer: -1 client | Terminating Kwik 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 11:29:05,828 No key log file found. 2025-04-30 11:29:05,828 No key log file found. 2025-04-30 11:29:05,828 No key log file found. 2025-04-30 11:29:05,828 No key log file found. 2025-04-30 11:29:06,380 Check of downloaded files succeeded. 2025-04-30 11:29:06,930 Check of Retry succeeded. Token used: 3223716e17deca015e816dbe70417e65d390e90bacb0cb53b35d18d397284a1918f4f9ba1f0236db92b380ed51a7b84a89d5d8da39740fc6e31fbea76d8bf574805b