2025-04-30 12:47:56,688 Generated random file: agqfhgciue of size: 1024 2025-04-30 12:47:56,688 Requests: https://server4:443/agqfhgciue 2025-04-30 12:47:56,746 2025-04-30 12:47:56,746 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_bbdifoi0/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_fgxr2d35/ DOWNLOADS=/tmp/download_x0d791py/ SERVER_LOGS=/tmp/logs_server_79jv1kh6 CLIENT_LOGS=/tmp/logs_client_qkgxp9mw SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/facebook/proxygen/mvfst-interop:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/agqfhgciue" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 12:48:11,715 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | + set -o nounset client | + VERSION=1 client | + HQ_CLI=/proxygen/_build/proxygen/bin/hq client | + PORT=443 client | + LOGLEVEL=2 client | + /setup.sh client | Setting up routes... 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] client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 client | + PROTOCOL=hq-interop client | + HTTPVERSION=0.9 client | + CONN_FLOW_CONTROL=107374182 client | + STREAM_FLOW_CONTROL=107374182 server | >>> Starting nginx server... client | ++ tr ' ' '\n' client | ++ echo https://server4:443/agqfhgciue client | ++ awk -F / '{ print "/" $4 }' client | ++ paste -sd, server | nginx version: nginx/1.27.0 (9270:d1b8568f3042) server | built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | built with OpenSSL 1.1.1 (compatible; BoringSSL) (running with BoringSSL) server | TLS SNI support enabled server | configure arguments: --prefix=/etc/nginx --build=9270:d1b8568f3042 --sbin-path=/usr/sbin/nginx --modules-path=/usr/lib/nginx/modules --conf-path=/etc/nginx/nginx.conf --error-log-path=/var/log/nginx/error.log --http-log-path=/var/log/nginx/access.log --pid-path=/run/nginx.pid --lock-path=/run/nginx.lock --http-client-body-temp-path=/var/cache/nginx/client_temp --http-proxy-temp-path=/var/cache/nginx/proxy_temp --http-fastcgi-temp-path=/var/cache/nginx/fastcgi_temp --http-uwsgi-temp-path=/var/cache/nginx/uwsgi_temp --http-scgi-temp-path=/var/cache/nginx/scgi_temp --user=nginx --group=nginx --with-compat --with-debug --with-http_ssl_module --with-http_v2_module --with-http_v3_module --with-cc=c++ --with-cc-opt='-I/boringssl/include -O0 -fno-common -fno-omit-frame-pointer -DNGX_QUIC_DRAFT_VERSION=29 -DNGX_HTTP_V3_HQ=1 -x c' --with-ld-opt='-L/boringssl/build/ssl -L/boringssl/build/crypto' server | >>> Parameters: server | >>> Test case: handshake client | + INVOCATIONS=/agqfhgciue client | + EARLYDATA=false client | + PSK_FILE= client | + '[' -n handshake ']' client | + case "${TESTCASE}" in client | + '[' client == client ']' client | + /wait-for-it.sh sim:57832 -s -t 10 server | 2025/04/30 12:47:57 [debug] 21#0: bind() 0.0.0.0:443 #5 server | 2025/04/30 12:47:57 [debug] 21#0: bind() 0.0.0.0:443 #6 server | 2025/04/30 12:47:57 [debug] 21#0: bind() [::]:443 #7 server | 2025/04/30 12:47:57 [debug] 21#0: bind() [::]:443 #8 server | 2025/04/30 12:47:57 [debug] 21#0: add cleanup: 000055DD3D035750 server | 2025/04/30 12:47:57 [notice] 21#0: using the "epoll" event method server | 2025/04/30 12:47:57 [debug] 21#0: counter: 00007F5E3B45A080, 1 server | 2025/04/30 12:47:57 [notice] 21#0: nginx/1.27.0 (9270:d1b8568f3042) server | 2025/04/30 12:47:57 [notice] 21#0: built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | 2025/04/30 12:47:57 [notice] 21#0: OS: Linux 6.11.0-1012-azure server | 2025/04/30 12:47:57 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 1048576:1048576 server | 2025/04/30 12:47:57 [debug] 21#0: write: 9, 00007FFEE3A2F200, 3, 0 server | 2025/04/30 12:47:57 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf" server | 2025/04/30 12:47:57 [notice] 21#0: start worker processes server | 2025/04/30 12:47:57 [debug] 21#0: channel 3:9 server | 2025/04/30 12:47:57 [notice] 21#0: start worker process 22 server | 2025/04/30 12:47:57 [debug] 21#0: sigsuspend server | 2025/04/30 12:47:57 [debug] 22#0: add cleanup: 000055DD3D0357D8 server | 2025/04/30 12:47:57 [debug] 22#0: malloc: 000055DD3D0387D0:16 server | 2025/04/30 12:47:57 [debug] 22#0: add cleanup: 000055DD3D0357F0 server | 2025/04/30 12:47:57 [debug] 22#0: malloc: 000055DD3D036AD0:16 server | 2025/04/30 12:47:57 [debug] 22#0: notify eventfd: 11 server | 2025/04/30 12:47:57 [debug] 22#0: testing the EPOLLRDHUP flag: success server | 2025/04/30 12:47:57 [debug] 22#0: malloc: 000055DD3D01E430:6144 server | 2025/04/30 12:47:57 [debug] 22#0: malloc: 00007F5E3ADCC010:253952 server | 2025/04/30 12:47:57 [debug] 22#0: malloc: 000055DD3D03A890:98304 server | 2025/04/30 12:47:57 [debug] 22#0: malloc: 000055DD3D0528A0:98304 server | 2025/04/30 12:47:57 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001 server | 2025/04/30 12:47:57 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001 server | 2025/04/30 12:47:57 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001 server | 2025/04/30 12:47:57 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001 server | 2025/04/30 12:47:57 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001 server | 2025/04/30 12:47:57 [debug] 22#0: setproctitle: "nginx: worker process" server | 2025/04/30 12:47:57 [debug] 22#0: worker cycle server | 2025/04/30 12:47:57 [debug] 22#0: epoll timer: -1 client | wait-for-it.sh: waiting 10 seconds for sim:57832 server | 2025/04/30 12:47:58 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:47:58 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:47:58 [debug] 22#0: posix_memalign: 000055DD3D036B30:512 @16 server | 2025/04/30 12:47:58 [debug] 22#0: malloc: 000055DD3D0387F0:1207 server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:40287 fd:6 n:1207 server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic run server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic packet rx dcid len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic packet rx scid len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic address validation token len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *1 sending version negotiation packet server | 2025/04/30 12:47:58 [debug] 22#0: *1 sendmsg: 11 of 11 server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 12:47:58 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 12:47:58 [debug] 22#0: *1 reusable connection: 0 server | 2025/04/30 12:47:58 [debug] 22#0: *1 free: 000055DD3D0387F0 server | 2025/04/30 12:47:58 [debug] 22#0: *1 free: 000055DD3D036B30, unused: 24 server | 2025/04/30 12:47:58 [debug] 22#0: timer delta: 793 server | 2025/04/30 12:47:58 [debug] 22#0: worker cycle server | 2025/04/30 12:47:58 [debug] 22#0: epoll timer: -1 server | 2025/04/30 12:47:58 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:47:58 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:47:58 [debug] 22#0: posix_memalign: 000055DD3D036B30:512 @16 server | 2025/04/30 12:47:58 [debug] 22#0: malloc: 000055DD3D0387F0:1207 server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic recvmsg: 193.167.100.2:40287 fd:6 n:1207 server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic run server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic packet rx dcid len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic packet rx scid len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic address validation token len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *2 sending version negotiation packet server | 2025/04/30 12:47:58 [debug] 22#0: *2 sendmsg: 11 of 11 server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 sim | server:443 is available after 1.022702975s server | 2025/04/30 12:47:58 [debug] 22#0: *2 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 12:47:58 [debug] 22#0: *2 reusable connection: 0 server | 2025/04/30 12:47:58 [debug] 22#0: *2 free: 000055DD3D0387F0 server | 2025/04/30 12:47:58 [debug] 22#0: *2 free: 000055DD3D036B30, unused: 24 server | 2025/04/30 12:47:58 [debug] 22#0: timer delta: 0 server | 2025/04/30 12:47:58 [debug] 22#0: worker cycle server | 2025/04/30 12:47:58 [debug] 22#0: epoll timer: -1 server | 2025/04/30 12:47:58 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:47:58 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:47:58 [debug] 22#0: posix_memalign: 000055DD3D036B30:512 @16 server | 2025/04/30 12:47:58 [debug] 22#0: malloc: 000055DD3D0387F0:1207 server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic recvmsg: 193.167.100.2:40287 fd:6 n:1207 server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic run server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic packet rx long flags:c0 version:57414954 server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic packet rx dcid len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic packet rx scid len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic address validation token len:0 server | 2025/04/30 12:47:58 [debug] 22#0: *3 sending version negotiation packet server | 2025/04/30 12:47:58 [debug] 22#0: *3 sendmsg: 11 of 11 server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic packet done rc:-4 level:init decr:0 pn:0 perr:0 server | 2025/04/30 12:47:58 [debug] 22#0: *3 quic packet rejected rc:-4, cleanup connection server | 2025/04/30 12:47:58 [debug] 22#0: *3 reusable connection: 0 server | 2025/04/30 12:47:58 [debug] 22#0: *3 free: 000055DD3D0387F0 server | 2025/04/30 12:47:58 [debug] 22#0: *3 free: 000055DD3D036B30, unused: 24 server | 2025/04/30 12:47:58 [debug] 22#0: timer delta: 0 server | 2025/04/30 12:47:58 [debug] 22#0: worker cycle server | 2025/04/30 12:47:58 [debug] 22#0: epoll timer: -1 sim | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25 sim | tcpdump: tcpdump: listening on eth0listening on eth1, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | , snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | + echo 'Starting QUIC client...' client | Starting QUIC client... client | + '[' -n https://server4:443/agqfhgciue ']' client | + REQS=("${REQUESTS}") client | + REQ=https://server4:443/agqfhgciue client | ++ echo https://server4:443/agqfhgciue client | ++ cut -d/ -f3 client | ++ cut -d: -f1 client | + SERVER=server4 client | + for INVOCATION in ${INVOCATIONS} client | + echo 'requesting files '\''/agqfhgciue'\''' client | requesting files '/agqfhgciue' client | + /proxygen/_build/proxygen/bin/hq --mode=client --host=server4 --port=443 --protocol=hq-interop --httpversion=0.9 --use_version=true --quic-version=1 --path=/agqfhgciue --early_data=false --psk_file= --conn_flow_control=107374182 --stream_flow_control=107374182 --outdir=/downloads --logdir=/logs --qlogger_path=/logs --v=2 client | + tee /logs/client.log client | I20250430 12:47:58.380870 42 HQClient.cpp:47] HQClient connecting to 193.167.100.100:443 server | 2025/04/30 12:47:59 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:47:59 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:47:59 [debug] 22#0: posix_memalign: 000055DD3D036B30:512 @16 server | 2025/04/30 12:47:59 [debug] 22#0: malloc: 000055DD3D0387F0:1252 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic recvmsg: 193.167.0.100:47048 fd:6 n:1252 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic run server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx long flags:c3 version:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx init len:1234 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx dcid len:8 3e7e5ccc5fec7ab6 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic address validation token len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 malloc: 000055DD3D01FC40:2448 server | 2025/04/30 12:47:59 [debug] 22#0: *4 malloc: 000055DD3D0205E0:1528 server | 2025/04/30 12:47:59 [debug] 22#0: *4 posix_memalign: 000055DD3D036D40:512 @16 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_set_initial_secret server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic socket seq:0 listening at sid:0000000000001001c5c2bfbc7992fd3a17803496 nsock:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic cid seq:0 received id:0::00000000000000000000000000000000 server | 2025/04/30 12:47:59 [debug] 22#0: *4 posix_memalign: 000055DD3D0211E0:512 @16 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic path seq:0 created addr:193.167.0.100:47048 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic path seq:0 set active tx:0 rx:0 valid:0 st:0 mtu:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 posix_memalign: 000055DD3D0213F0:512 @16 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic socket seq:-1 listening at sid:3e7e5ccc5fec7ab6 nsock:2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 reusable connection: 1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic connection created server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx clearflags:c3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx number:13194333 len:4 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet len:1252 via sock seq:0 path seq:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic path seq:0 status tx:0 rx:1252 valid:0 st:0 mtu:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic stateless reset token bcaf1c3fb3d7793ec2188b05282acce3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194333 CRYPTO len:310 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 SSL server name: "server4" server | 2025/04/30 12:47:59 [debug] 22#0: *4 SSL ALPN supported by client: hq-interop server | 2025/04/30 12:47:59 [debug] 22#0: *4 SSL ALPN selected: hq-interop server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_add_handshake_data server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic SSL_get_peer_quic_transport_params(): params_len:68 server | 2025/04/30 12:47:59 [info] 22#0: *4 quic unknown transport param id:0x20, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 12:47:59 [info] 22#0: *4 quic unknown transport param id:0xff0a001, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 12:47:59 [info] 22#0: *4 quic unknown transport param id:0x5178, skipped while handling frames, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic transport parameters parsed ok server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp disable active migration: 0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp idle_timeout:60000 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp max_udp_payload_size:1500 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp max_data:107374182 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp max_stream_data_bidi_local:107374182 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp max_stream_data_bidi_remote:107374182 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp max_stream_data_uni:107374182 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp initial_max_streams_bidi:100 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp initial_max_streams_uni:100 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp ack_delay_exponent:3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp max_ack_delay:25 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp active_connection_id_limit:5 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic tp initial source_connection_id len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 malloc: 000055DD3D014FE0:4096 server | 2025/04/30 12:47:59 [debug] 22#0: *4 posix_memalign: 000055DD3D015FF0:512 @16 server | 2025/04/30 12:47:59 [debug] 22#0: *4 post event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_set_write_secret() level:2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_add_handshake_data server | 2025/04/30 12:47:59 [debug] 22#0: *4 malloc: 000055DD3D017540:4096 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_set_write_secret() level:3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_flush_flight() server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_set_read_secret() level:2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 SSL_do_handshake: -1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 SSL_get_error: 2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194333 PADDING server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:13194333 largest -1 fr:0 nranges:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:13194333 perr:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 60000:469485 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 60000:469485 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: read:60000 close:60000 server | 2025/04/30 12:47:59 [debug] 22#0: timer delta: 986 server | 2025/04/30 12:47:59 [debug] 22#0: posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 delete posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic push handler server | 2025/04/30 12:47:59 [debug] 22#0: *4 posix_memalign: 000055DD3D06ACE0:512 @16 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output init packet max:1200 min:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:0 ACK n:0 delay:0 13194333 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:0 CRYPTO len:90 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx init bytes:102 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output hs packet max:1051 min:1051 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx hs:0 CRYPTO len:719 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx hs bytes:1005 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 sendmsg: 1200 of 1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:149 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer: 6, old: 469485, new: 469485 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic lost timer pto:997 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 997:410482 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: send:60000 pto:997 close:60000 server | 2025/04/30 12:47:59 [debug] 22#0: worker cycle server | 2025/04/30 12:47:59 [debug] 22#0: epoll timer: 997 server | 2025/04/30 12:47:59 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:47:59 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic recvmsg: fd:6 n:1252 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx long flags:c5 version:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx init len:1234 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx dcid len:8 3e7e5ccc5fec7ab6 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic address validation token len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx clearflags:c3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx number:13194335 len:4 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic path seq:0 status tx:1200 rx:2504 valid:0 st:0 mtu:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194335 CRYPTO len:310 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic resend packet pnum:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:-1 if:1051 server | 2025/04/30 12:47:59 [debug] 22#0: *4 post event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic resend packet pnum:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:-1 if:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194335 PADDING server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:13194335 largest 13194333 fr:0 nranges:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:13194335 perr:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer: 6, old: 469485, new: 469590 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: read:59895 pto:892 close:59895 server | 2025/04/30 12:47:59 [debug] 22#0: timer delta: 105 server | 2025/04/30 12:47:59 [debug] 22#0: posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 delete posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic push handler server | 2025/04/30 12:47:59 [debug] 22#0: *4 malloc: 000055DD3D06AEF0:4096 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output init packet max:1200 min:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:1 ACK n:1 delay:0 13194335 13194333 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:1 CRYPTO len:90 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx init bytes:104 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output hs packet max:1049 min:1049 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx hs:1 CRYPTO len:719 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx hs bytes:1003 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 sendmsg: 1200 of 1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:151 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer: 6, old: 469485, new: 469590 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer del: 6: 410482 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic lost timer pto:997 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 997:410587 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: send:59895 pto:997 close:59895 server | 2025/04/30 12:47:59 [debug] 22#0: worker cycle server | 2025/04/30 12:47:59 [debug] 22#0: epoll timer: 997 server | 2025/04/30 12:47:59 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:47:59 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic recvmsg: fd:6 n:1252 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx long flags:cb version:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx init len:1234 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx dcid len:8 3e7e5ccc5fec7ab6 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic address validation token len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx clearflags:c3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx number:13194337 len:4 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic path seq:0 status tx:2400 rx:3756 valid:0 st:0 mtu:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194337 CRYPTO len:310 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic resend packet pnum:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion lost win:131054 ss:131054 if:1049 server | 2025/04/30 12:47:59 [debug] 22#0: *4 post event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic resend packet pnum:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:131054 if:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194337 PADDING server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:13194337 largest 13194335 fr:0 nranges:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:13194337 perr:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer del: 6: 469485 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 60000:469800 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: read:60000 pto:787 close:59685 server | 2025/04/30 12:47:59 [debug] 22#0: timer delta: 210 server | 2025/04/30 12:47:59 [debug] 22#0: posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 delete posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic push handler server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output init packet max:1200 min:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:2 ACK n:2 delay:0 13194337 13194335 13194333 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:2 CRYPTO len:90 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx init bytes:106 need_ack:1 number:2 encoded nl:1 trunc:0x2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output hs packet max:1047 min:1047 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx hs:2 CRYPTO len:719 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx hs bytes:1001 need_ack:1 number:2 encoded nl:1 trunc:0x2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 sendmsg: 1200 of 1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:153 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer: 6, old: 469800, new: 469800 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer del: 6: 410587 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic lost timer pto:997 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 997:410797 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: send:60000 pto:997 close:59685 server | 2025/04/30 12:47:59 [debug] 22#0: worker cycle server | 2025/04/30 12:47:59 [debug] 22#0: epoll timer: 997 server | 2025/04/30 12:47:59 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:47:59 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic recvmsg: fd:6 n:1252 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx long flags:c3 version:1 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx init len:1234 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx dcid len:8 3e7e5ccc5fec7ab6 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic address validation token len:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx clearflags:c3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet rx number:13194339 len:4 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet len:1252 via sock seq:-1 path seq:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic path seq:0 status tx:3600 rx:5008 valid:0 st:0 mtu:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194339 CRYPTO len:310 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic resend packet pnum:2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:131054 if:1047 server | 2025/04/30 12:47:59 [debug] 22#0: *4 post event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic resend packet pnum:2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion lost recovery win:131054 ss:131054 if:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame rx init:13194339 PADDING server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:13194339 largest 13194337 fr:0 nranges:2 server | 2025/04/30 12:47:59 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet done rc:0 level:init decr:1 pn:13194339 perr:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer del: 6: 469800 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 60000:470213 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: read:60000 pto:584 close:59272 server | 2025/04/30 12:47:59 [debug] 22#0: timer delta: 413 server | 2025/04/30 12:47:59 [debug] 22#0: posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 delete posted event 000055DD3D020248 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic push handler server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output init packet max:1200 min:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:3 ACK n:3 delay:0 13194339 13194337 13194335 13194333 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx init:3 CRYPTO len:90 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx init bytes:108 need_ack:1 number:3 encoded nl:1 trunc:0x3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic output hs packet max:1045 min:1045 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic frame tx hs:3 CRYPTO len:719 off:0 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic packet tx hs bytes:999 need_ack:1 number:3 encoded nl:1 trunc:0x3 server | 2025/04/30 12:47:59 [debug] 22#0: *4 sendmsg: 1200 of 1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:155 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic congestion send if:1200 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer: 6, old: 470213, new: 470213 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer del: 6: 410797 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic lost timer pto:997 server | 2025/04/30 12:47:59 [debug] 22#0: *4 event timer add: 6: 997:411210 server | 2025/04/30 12:47:59 [debug] 22#0: *4 quic state: send:60000 pto:997 close:59272 server | 2025/04/30 12:47:59 [debug] 22#0: worker cycle server | 2025/04/30 12:47:59 [debug] 22#0: epoll timer: 997 client | I20250430 12:47:59.892575 42 CurlClient.cpp:187] Sending request for /agqfhgciue server | 2025/04/30 12:48:00 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:48:00 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic recvmsg: fd:6 n:94 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx long flags:e5 version:1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx hs len:65 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000001001c5c2bfbc7992fd3a17803496 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx clearflags:e3 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx number:13194333 len:4 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet len:94 via sock seq:0 path seq:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic path seq:0 status tx:4800 rx:5102 valid:0 st:0 mtu:1200 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion avoidance win:131131 ss:131054 if:1045 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic socket seq:-1 closed nsock:1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 411210 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic lost timer pto:220 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 220:411210 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic path seq:0 in handshake tx:4800 rx:5102 valid:1 st:0 mtu:1200 server | 2025/04/30 12:48:00 [debug] 22#0: *4 post event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame rx hs:13194333 CRYPTO len:36 off:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic ngx_quic_set_read_secret() level:3 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic ngx_quic_add_handshake_data server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic ngx_quic_flush_flight() server | 2025/04/30 12:48:00 [debug] 22#0: *4 SSL_do_handshake: 1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 SSL: TLSv1.3, cipher: "TLS_AES_128_GCM_SHA256 Kx=GENERIC Au=GENERIC Enc=AESGCM(128) Mac=AEAD" server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 post event 000055DD3D0203C8 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion avoidance win:131653 ss:131054 if:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 411210 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic lost timer unset server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic path seq:0 schedule mtu:1500 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 100:411090 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic create sockets has:1 max:5 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic socket seq:1 listening at sid:0000000000001001c28ec0d3caeef849a0789e4e nsock:2 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stateless reset token 9d221981a4720a118658c0eabd3f0caf server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 posix_memalign: 000055DD3D014210:512 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic socket seq:2 listening at sid:0000000000001001938b8a4fed9f752af2e92bfd nsock:3 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stateless reset token 45dbd79bfd4404f75c7d15437f691872 server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 posix_memalign: 000055DD3D014420:512 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic socket seq:3 listening at sid:0000000000001001e2f6e502912b8343dd6d3207 nsock:4 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stateless reset token 679ca23802d8d8d646ae057acaeb3dbb server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 posix_memalign: 000055DD3D014630:512 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic socket seq:4 listening at sid:0000000000001001a96f709461d2fa3da453ebb2 nsock:5 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stateless reset token bfe62a99a1af060f2e526cc2b33e41de server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic init streams server | 2025/04/30 12:48:00 [debug] 22#0: *4 http3 init server | 2025/04/30 12:48:00 [debug] 22#0: *4 http3 init session server | 2025/04/30 12:48:00 [debug] 22#0: *4 posix_memalign: 000055DD3D06C770:512 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *4 add cleanup: 000055DD3D0161D0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 75000:485990 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 469485 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame rx hs:13194333 ACK n:0 delay:151 0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic ngx_quic_handle_ack_frame level:2 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic updated largest received ack:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic lost timer unset server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:13194333 largest -1 fr:0 nranges:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet done rc:0 level:hs decr:1 pn:13194333 perr:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 470213 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 60000:470990 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic state: read:60000 server | 2025/04/30 12:48:00 [debug] 22#0: timer delta: 777 server | 2025/04/30 12:48:00 [debug] 22#0: posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 delete posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic push handler server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic output app packet max:1200 min:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:0 CRYPTO len:572 off:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:0 HANDSHAKE DONE server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet tx app bytes:737 need_ack:1 number:0 encoded nl:1 trunc:0x0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 sendmsg: 755 of 755 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion send if:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion send if:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion send if:755 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer: 6, old: 470990, new: 470990 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic lost timer pto:1022 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 1022:412012 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic state: send:60000 pto:1022 server | 2025/04/30 12:48:00 [debug] 22#0: posted event 000055DD3D0203C8 server | 2025/04/30 12:48:00 [debug] 22#0: *4 delete posted event 000055DD3D0203C8 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic key update server | 2025/04/30 12:48:00 [debug] 22#0: worker cycle server | 2025/04/30 12:48:00 [debug] 22#0: epoll timer: 100 server | 2025/04/30 12:48:00 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:48:00 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic recvmsg: fd:6 n:68 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx short flags:57 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000001001c5c2bfbc7992fd3a17803496 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx clearflags:43 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx number:13194333 len:4 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet len:68 via sock seq:0 path seq:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic path seq:0 status tx:5555 rx:5170 valid:1 st:2 mtu:1200 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame rx app:13194333 STREAM id:0x0 len:17 fin:1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 is missing server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 create server | 2025/04/30 12:48:00 [debug] 22#0: *4 posix_memalign: 000055DD3D06C980:512 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *4 posix_memalign: 000055DD3D06CB90:16384 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *4 reusable connection: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 add cleanup: 000055DD3D06CC50 server | 2025/04/30 12:48:00 [debug] 22#0: *5 post event 000055DD3D03AAD0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 flow control msd:17/65536 md:17/8585216 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame rx app:13194333 PADDING server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic ngx_quic_ack_packet pn:13194333 largest -1 fr:0 nranges:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 post event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet done rc:0 level:app decr:1 pn:13194333 perr:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer: 6, old: 470990, new: 470991 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic state: read:59999 pto:1021 server | 2025/04/30 12:48:00 [debug] 22#0: timer delta: 1 server | 2025/04/30 12:48:00 [debug] 22#0: posted event 000055DD3D03AAD0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 delete posted event 000055DD3D03AAD0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 quic init stream server | 2025/04/30 12:48:00 [debug] 22#0: *5 http3 init request stream server | 2025/04/30 12:48:00 [debug] 22#0: *5 add cleanup: 000055DD3D06CCC0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 485990 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http wait request handler server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 recv buf:1024 server | 2025/04/30 12:48:00 [debug] 22#0: *5 quic stream id:0x0 recv len:17 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 flow update 17 server | 2025/04/30 12:48:00 [debug] 22#0: *5 reusable connection: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 posix_memalign: 000055DD3D070BA0:4096 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http process request line server | 2025/04/30 12:48:00 [debug] 22#0: *5 http request line: "GET /agqfhgciue" server | 2025/04/30 12:48:00 [debug] 22#0: *5 http uri: "/agqfhgciue" server | 2025/04/30 12:48:00 [debug] 22#0: *5 http args: "" server | 2025/04/30 12:48:00 [debug] 22#0: *5 http exten: "" server | 2025/04/30 12:48:00 [debug] 22#0: *5 rewrite phase: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 test location: "/" server | 2025/04/30 12:48:00 [debug] 22#0: *5 using configuration "/" server | 2025/04/30 12:48:00 [debug] 22#0: *5 http cl:-1 max:1048576 server | 2025/04/30 12:48:00 [debug] 22#0: *5 rewrite phase: 2 server | 2025/04/30 12:48:00 [debug] 22#0: *5 post rewrite phase: 3 server | 2025/04/30 12:48:00 [debug] 22#0: *5 generic phase: 4 server | 2025/04/30 12:48:00 [debug] 22#0: *5 generic phase: 5 server | 2025/04/30 12:48:00 [debug] 22#0: *5 access phase: 6 server | 2025/04/30 12:48:00 [debug] 22#0: *5 access phase: 7 server | 2025/04/30 12:48:00 [debug] 22#0: *5 post access phase: 8 server | 2025/04/30 12:48:00 [debug] 22#0: *5 generic phase: 9 server | 2025/04/30 12:48:00 [debug] 22#0: *5 generic phase: 10 server | 2025/04/30 12:48:00 [debug] 22#0: *5 content phase: 11 server | 2025/04/30 12:48:00 [debug] 22#0: *5 content phase: 12 server | 2025/04/30 12:48:00 [debug] 22#0: *5 content phase: 13 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http filename: "/www/agqfhgciue" server | 2025/04/30 12:48:00 [debug] 22#0: *5 add cleanup: 000055DD3D0718D8 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http static fd: 3 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http set discard body server | 2025/04/30 12:48:00 [debug] 22#0: *5 http output filter "/agqfhgciue?" server | 2025/04/30 12:48:00 [debug] 22#0: *5 http copy filter: "/agqfhgciue?" server | 2025/04/30 12:48:00 [debug] 22#0: *5 posix_memalign: 000055DD3D071BB0:4096 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *5 read: 3, 000055DD3D071BD0, 1024, 0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http postpone filter "/agqfhgciue?" 000055DD3D071AF0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 write new buf t:1 f:0 000055DD3D071BD0, pos 000055DD3D071BD0, size: 1024 file: 0, size: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http write filter: l:1 f:0 s:1024 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http write filter limit 2097152 server | 2025/04/30 12:48:00 [debug] 22#0: *5 quic send_chain sent:1024 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 flush limit:107374182 server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 flush len:1024 last:0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http write filter 0000000000000000 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http copy filter: 0 "/agqfhgciue?" server | 2025/04/30 12:48:00 [debug] 22#0: *5 http finalize request: 0, "/agqfhgciue?" a:1, c:1 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http request count:1 blk:0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 http close request server | 2025/04/30 12:48:00 [debug] 22#0: *5 http log handler server | 193.167.0.100 - - [30/Apr/2025:12:48:00 +0000] "GET /agqfhgciue" 200 1024 "-" "-" server | 2025/04/30 12:48:00 [debug] 22#0: *5 run cleanup: 000055DD3D0718D8 server | 2025/04/30 12:48:00 [debug] 22#0: *5 file cleanup: fd:3 server | 2025/04/30 12:48:00 [debug] 22#0: *5 free: 000055DD3D070BA0, unused: 64 server | 2025/04/30 12:48:00 [debug] 22#0: *5 free: 000055DD3D071BB0, unused: 2925 server | 2025/04/30 12:48:00 [debug] 22#0: *5 close http connection: 6 server | 2025/04/30 12:48:00 [debug] 22#0: *5 reusable connection: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *5 run cleanup: 000055DD3D06CCC0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 75000:485991 server | 2025/04/30 12:48:00 [debug] 22#0: *5 run cleanup: 000055DD3D06CC50 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 cleanup server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 send shutdown server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 flush limit:107373158 server | 2025/04/30 12:48:00 [debug] 22#0: *4 update posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic stream id:0x0 flush len:0 last:1 server | 2025/04/30 12:48:00 [debug] 22#0: *5 free: 000055DD3D06CB90, unused: 14952 server | 2025/04/30 12:48:00 [debug] 22#0: posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 delete posted event 000055DD3D020248 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic push handler server | 2025/04/30 12:48:00 [debug] 22#0: *4 posix_memalign: 000055DD3D06CB90:512 @16 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic output app packet max:1200 min:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:1 ACK n:0 delay:0 13194333 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:1 STREAM id:0x0 off:0 len:1024 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:1 STREAM id:0x0 off:1024 len:0 fin:1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet tx app bytes:1042 need_ack:1 number:1 encoded nl:1 trunc:0x1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 sendmsg: 1060 of 1060 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion send if:755 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion send if:755 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion send if:1815 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer: 6, old: 470990, new: 470991 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 412012 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic lost timer pto:1022 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 1022:412013 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic state: send:59999 pto:1022 server | 2025/04/30 12:48:00 [debug] 22#0: worker cycle server | 2025/04/30 12:48:00 [debug] 22#0: epoll timer: 99 server | 2025/04/30 12:48:00 [debug] 22#0: timer delta: 99 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 411090 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic path seq:0 send probe mtu:1500 pnum:2 tries:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic sendto app packet max:1500 min:1500 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:2 PING server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet tx app bytes:1482 need_ack:0 number:2 encoded nl:1 trunc:0x2 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic path seq:0 rejected mtu:1500 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic path seq:0 send probe mtu:1350 pnum:3 tries:0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic sendto app packet max:1350 min:1350 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic frame tx app:3 PING server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet tx app bytes:1332 need_ack:0 number:3 encoded nl:1 trunc:0x3 server | 2025/04/30 12:48:00 [debug] 22#0: *4 sendmsg: 1350 of 1350 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic congestion send if:3165 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer del: 6: 412013 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic lost timer pto:1022 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 1022:412112 server | 2025/04/30 12:48:00 [debug] 22#0: *4 event timer add: 6: 1022:412112 server | 2025/04/30 12:48:00 [debug] 22#0: worker cycle server | 2025/04/30 12:48:00 [debug] 22#0: epoll timer: 1022 server | 2025/04/30 12:48:00 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:48:00 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic recvmsg: fd:6 n:53 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx long flags:ef version:1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx hs len:25 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000001001c5c2bfbc7992fd3a17803496 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:48:00 [info] 22#0: *4 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/04/30 12:48:00 [debug] 22#0: timer delta: 2 server | 2025/04/30 12:48:00 [debug] 22#0: worker cycle server | 2025/04/30 12:48:00 [debug] 22#0: epoll timer: 1020 server | 2025/04/30 12:48:00 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:48:00 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic recvmsg: fd:6 n:53 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx long flags:e6 version:1 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx hs len:25 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000001001c5c2bfbc7992fd3a17803496 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:48:00 [info] 22#0: *4 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 12:48:00 [debug] 22#0: *4 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/04/30 12:48:00 [debug] 22#0: timer delta: 210 server | 2025/04/30 12:48:00 [debug] 22#0: worker cycle server | 2025/04/30 12:48:00 [debug] 22#0: epoll timer: 810 server | 2025/04/30 12:48:01 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F5E3ADCC108 server | 2025/04/30 12:48:01 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0 server | 2025/04/30 12:48:01 [debug] 22#0: *4 quic recvmsg: fd:6 n:53 server | 2025/04/30 12:48:01 [debug] 22#0: *4 quic input handler server | 2025/04/30 12:48:01 [debug] 22#0: *4 quic packet rx long flags:eb version:1 server | 2025/04/30 12:48:01 [debug] 22#0: *4 quic packet rx hs len:25 server | 2025/04/30 12:48:01 [debug] 22#0: *4 quic packet rx dcid len:20 0000000000001001c5c2bfbc7992fd3a17803496 server | 2025/04/30 12:48:01 [debug] 22#0: *4 quic packet rx scid len:0 server | 2025/04/30 12:48:01 [info] 22#0: *4 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/04/30 12:48:01 [debug] 22#0: *4 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0 server | 2025/04/30 12:48:01 [debug] 22#0: timer delta: 413 server | 2025/04/30 12:48:01 [debug] 22#0: worker cycle server | 2025/04/30 12:48:01 [debug] 22#0: epoll timer: 397 client | I20250430 12:48:01.396080 42 CurlClient.cpp:276] Got EOM for /agqfhgciue. Txn Time= 1503 ms 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:48:11,810 No key log file found. 2025-04-30 12:48:11,810 No key log file found. 2025-04-30 12:48:11,810 No key log file found. 2025-04-30 12:48:11,810 No key log file found. 2025-04-30 12:48:12,091 Check of downloaded files succeeded. 2025-04-30 12:48:13,232 Check of downloaded files succeeded.