2025-12-13 12:25:45,082 Generated random file: merry-magenta-wizard of size: 1024
2025-12-13 12:25:45,082 Requests: https://server4:443/merry-magenta-wizard
2025-12-13 12:25:45,138 
2025-12-13 12:25:45,139 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_11yw7x_y/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_e5zfhktq/ DOWNLOADS=/tmp/download_li7zvjty/ SERVER_LOGS=/tmp/logs_server_h6v4tfgw CLIENT_LOGS=/tmp/logs_client_ddcutglk SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=litespeedtech/lsquic-qir:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/merry-magenta-wizard"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-12-13 12:26:00,099  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
server  | Setting up routes...
client  | Setting up routes...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | >>> Starting nginx server...
server  | nginx version: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f)
server  | built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) 
server  | built with OpenSSL 1.1.1 (compatible; BoringSSL) (running with BoringSSL)
server  | TLS SNI support enabled
server  | configure arguments: --prefix=/etc/nginx --build=6ed1188411882086e3518eda779ab782d8ab4d3f --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 -x c' --with-ld-opt=-L/boringssl/build
server  | >>> Parameters: 
server  | >>> Test case: handshake
server  | 2025/12/13 12:25:45 [debug] 21#0: bind() 0.0.0.0:443 #5 
server  | 2025/12/13 12:25:45 [debug] 21#0: bind() 0.0.0.0:443 #6 
server  | 2025/12/13 12:25:45 [debug] 21#0: bind() [::]:443 #7 
server  | 2025/12/13 12:25:45 [debug] 21#0: bind() [::]:443 #8 
server  | 2025/12/13 12:25:45 [debug] 21#0: add cleanup: 000055B56EA1E1C0
server  | 2025/12/13 12:25:45 [notice] 21#0: using the "epoll" event method
server  | 2025/12/13 12:25:45 [debug] 21#0: counter: 00007F462F945080, 1
server  | 2025/12/13 12:25:45 [notice] 21#0: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f)
server  | 2025/12/13 12:25:45 [notice] 21#0: built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) 
server  | 2025/12/13 12:25:45 [notice] 21#0: OS: Linux 6.11.0-1018-azure
server  | 2025/12/13 12:25:45 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 65536:65536
server  | 2025/12/13 12:25:45 [debug] 21#0: write: 9, 00007FFF08803B80, 3, 0
server  | 2025/12/13 12:25:45 [debug] 21#0: setproctitle: "nginx: master process /usr/sbin/nginx -c /etc/nginx/nginx.conf"
server  | 2025/12/13 12:25:45 [notice] 21#0: start worker processes
server  | 2025/12/13 12:25:45 [debug] 21#0: channel 3:9
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | 2025/12/13 12:25:45 [notice] 21#0: start worker process 22
server  | 2025/12/13 12:25:45 [debug] 21#0: sigsuspend
server  | 2025/12/13 12:25:45 [debug] 22#0: add cleanup: 000055B56EA1E248
server  | 2025/12/13 12:25:45 [debug] 22#0: malloc: 000055B56EA20A30:16
server  | 2025/12/13 12:25:45 [debug] 22#0: add cleanup: 000055B56EA1E260
server  | 2025/12/13 12:25:45 [debug] 22#0: malloc: 000055B56EA1FA20:16
server  | 2025/12/13 12:25:45 [debug] 22#0: notify eventfd: 11
server  | 2025/12/13 12:25:45 [debug] 22#0: testing the EPOLLRDHUP flag: success
server  | 2025/12/13 12:25:45 [debug] 22#0: malloc: 000055B56EA06EF0:6144
server  | 2025/12/13 12:25:45 [debug] 22#0: malloc: 00007F462F269010:253952
server  | 2025/12/13 12:25:45 [debug] 22#0: malloc: 000055B56EA24390:98304
server  | 2025/12/13 12:25:45 [debug] 22#0: malloc: 000055B56EA3C3A0:98304
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll add event: fd:5 op:1 ev:00002001
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll add event: fd:6 op:1 ev:00002001
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll add event: fd:7 op:1 ev:00002001
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll add event: fd:8 op:1 ev:00002001
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll add event: fd:9 op:1 ev:00002001
server  | 2025/12/13 12:25:45 [debug] 22#0: setproctitle: "nginx: worker process"
server  | 2025/12/13 12:25:45 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll timer: -1
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:45 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:45 [debug] 22#0: posix_memalign: 000055B56EA1EAD0:512 @16
server  | 2025/12/13 12:25:45 [debug] 22#0: malloc: 000055B56EA20A50:1207
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic recvmsg: 193.167.100.2:45498 fd:6 n:1207
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic run
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic packet rx long flags:c0 version:57414954
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic packet rx dcid len:0 
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic packet rx scid len:0 
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic address validation token len:0 
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 sending version negotiation packet
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 sendmsg: 11 of 11
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic packet done rc:-4 level:init decr:0 pn:0 perr:0
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 quic packet rejected rc:-4, cleanup connection
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 reusable connection: 0
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 free: 000055B56EA20A50
server  | 2025/12/13 12:25:45 [debug] 22#0: *1 free: 000055B56EA1EAD0, unused: 16
sim     | server:443 is available after 501.422619ms
server  | 2025/12/13 12:25:45 [debug] 22#0: timer delta: 327
server  | 2025/12/13 12:25:45 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:45 [debug] 22#0: epoll timer: -1
sim     | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25
sim     | Capturing on 'eth0'
sim     | Capturing on 'eth1'
sim     | File: /logs/trace_node_left.pcap
sim     | File: /logs/trace_node_right.pcap
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | TEST_PARAMS:
client  | REQUESTS: 'https://server4:443/merry-magenta-wizard'
client  | paths: -p /merry-magenta-wizard
client  | server: server4
client  | port: 443
client  | CLIENT_PARAMS:
server  | 2025/12/13 12:25:47 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:47 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:47 [debug] 22#0: posix_memalign: 000055B56EA1EAD0:512 @16
server  | 2025/12/13 12:25:47 [debug] 22#0: malloc: 000055B56EA20A50:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic recvmsg: 193.167.0.100:56261 fd:6 n:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic run
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx long flags:c6 version:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx init len:1167
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx dcid len:15 d8dbf121934b73483837e297200ec3
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 malloc: 000055B56EA08700:2512
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 malloc: 000055B56EA090E0:1528
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 posix_memalign: 000055B56EA20F10:512 @16
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_set_initial_secret
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic socket seq:0 listening at sid:0000000000001002f767228805526d2b473a9fa3 nsock:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic cid seq:0 received id:8:0e838b1b64b1d496:00000000000000000000000000000000
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 posix_memalign: 000055B56EA099D0:512 @16
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic path seq:0 created addr:193.167.0.100:56261
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic path seq:0 set active tx:0 rx:0 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 posix_memalign: 000055B56EA09BE0:512 @16
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic socket seq:-1 listening at sid:d8dbf121934b73483837e297200ec3 nsock:2
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 reusable connection: 1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic connection created
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx number:0 len:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet len:1200 via sock seq:0 path seq:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic path seq:0 status tx:0 rx:1200 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic stateless reset token 8b816c47049ad8e584476375ce984be1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame rx init:0 CRYPTO len:248 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 posix_memalign: 000055B56E9FCD60:512 @16
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 malloc: 000055B56E9FCF70:4096
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 SSL server name: "server4"
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 SSL ALPN supported by client: hq-interop
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 SSL ALPN selected: hq-interop
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic SSL_get_peer_quic_transport_params(): params_len:69
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic unknown transport param id:0xde1a, skipped
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic reserved transport param id:0xff02de1a, skipped
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic unknown transport param id:0x7158, skipped
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic transport parameters parsed ok
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp disable active migration: 0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp idle_timeout:30000
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp max_udp_payload_size:65527
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp max_data:15728640
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp max_stream_data_bidi_local:6291456
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp max_stream_data_bidi_remote:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp max_stream_data_uni:32768
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp initial_max_streams_bidi:100
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp initial_max_streams_uni:100
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp ack_delay_exponent:3
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp max_ack_delay:25
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp active_connection_id_limit:8
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic tp initial source_connection_id len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 malloc: 000055B56E9FE840:4096
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 post event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:2
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 malloc: 000055B56EA543B0:4096
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 posix_memalign: 000055B56EA00800:512 @16
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_set_write_secret() level:3
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_flush_flight()
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:2
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 SSL_do_handshake: -1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 SSL_get_error: 2
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame rx init:0 PADDING
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:0 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:0 perr:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer add: 6: 30000:346886
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer add: 6: 60000:376886
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic state: read:30000 close:60000
server  | 2025/12/13 12:25:47 [debug] 22#0: timer delta: 1444
server  | 2025/12/13 12:25:47 [debug] 22#0: posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 delete posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic output init packet max:1200 min:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx init:0 ACK n:0 delay:0 0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx init:0 CRYPTO len:90 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic output hs packet max:1046 min:1046
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx hs:0 CRYPTO len:725 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet tx hs bytes:992 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer: 6, old: 346886, new: 346886
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic lost timer pto:997
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer add: 6: 997:317883
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic state: send:30000 pto:997 close:60000
server  | 2025/12/13 12:25:47 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:47 [debug] 22#0: epoll timer: 997
server  | 2025/12/13 12:25:47 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:47 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic recvmsg: fd:6 n:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx long flags:c6 version:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx init len:1167
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx dcid len:15 d8dbf121934b73483837e297200ec3
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx number:1 len:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet len:1200 via sock seq:-1 path seq:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic path seq:0 status tx:1200 rx:2400 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame rx init:1 CRYPTO len:248 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic resend packet pnum:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic cubic time:2715 n:4
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion lost t:317032 win:2400 if:1046
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 post event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic resend packet pnum:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion lost rec t:317032 win:2400 if:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame rx init:1 PADDING
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:1 largest 0 fr:0 nranges:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:1 perr:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer: 6, old: 346886, new: 347032
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic state: read:29854 pto:851 close:59854
server  | 2025/12/13 12:25:47 [debug] 22#0: timer delta: 146
server  | 2025/12/13 12:25:47 [debug] 22#0: posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 delete posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic output init packet max:1200 min:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx init:1 ACK n:0 delay:0 1-0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx init:1 CRYPTO len:90 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic output hs packet max:1046 min:1046
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx hs:1 CRYPTO len:725 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet tx hs bytes:992 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer: 6, old: 346886, new: 347032
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer del: 6: 317883
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic lost timer pto:997
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer add: 6: 997:318029
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic state: send:29854 pto:997 close:59854
server  | 2025/12/13 12:25:47 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:47 [debug] 22#0: epoll timer: 997
server  | 2025/12/13 12:25:47 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:47 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic recvmsg: fd:6 n:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx long flags:c4 version:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx init len:1167
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx dcid len:15 d8dbf121934b73483837e297200ec3
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet rx number:2 len:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet len:1200 via sock seq:-1 path seq:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic path seq:0 status tx:2400 rx:3600 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame rx init:2 CRYPTO len:248 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic resend packet pnum:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion lost rec t:317332 win:2400 if:1046
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 post event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic resend packet pnum:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion lost rec t:317332 win:2400 if:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame rx init:2 PADDING
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:2 largest 1 fr:1 nranges:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:2 perr:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer del: 6: 346886
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer add: 6: 30000:347332
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic state: read:30000 pto:697 close:59554
server  | 2025/12/13 12:25:47 [debug] 22#0: timer delta: 300
server  | 2025/12/13 12:25:47 [debug] 22#0: posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 delete posted event 000055B56EA08D08
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic output init packet max:1200 min:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx init:2 ACK n:0 delay:0 2-0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx init:2 CRYPTO len:90 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:2 encoded nl:1 trunc:0x2
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic output hs packet max:1046 min:1046
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic frame tx hs:2 CRYPTO len:725 off:0
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic packet tx hs bytes:992 need_ack:1 number:2 encoded nl:1 trunc:0x2
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer: 6, old: 347332, new: 347332
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer del: 6: 318029
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic lost timer pto:997
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 event timer add: 6: 997:318329
server  | 2025/12/13 12:25:47 [debug] 22#0: *2 quic state: send:30000 pto:997 close:59554
server  | 2025/12/13 12:25:47 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:47 [debug] 22#0: epoll timer: 997
server  | 2025/12/13 12:25:48 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:48 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic recvmsg: fd:6 n:1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx long flags:c6 version:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx init len:1167
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx dcid len:15 d8dbf121934b73483837e297200ec3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx clearflags:c0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx number:3 len:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet len:1200 via sock seq:-1 path seq:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic path seq:0 status tx:3600 rx:4800 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx init:3 CRYPTO len:248 off:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic resend packet pnum:2
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion lost t:317933 win:2400 if:1046
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 post event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic resend packet pnum:2
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion lost rec t:317933 win:2400 if:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx init:3 PADDING
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:3 largest 2 fr:2 nranges:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:3 perr:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 347332
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 30000:347933
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic state: read:30000 pto:396 close:58953
server  | 2025/12/13 12:25:48 [debug] 22#0: timer delta: 601
server  | 2025/12/13 12:25:48 [debug] 22#0: posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 delete posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic output init packet max:1200 min:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx init:3 ACK n:0 delay:0 3-0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx init:3 CRYPTO len:90 off:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet tx init bytes:99 need_ack:1 number:3 encoded nl:1 trunc:0x3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic output hs packet max:1046 min:1046
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx hs:3 CRYPTO len:725 off:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet tx hs bytes:992 need_ack:1 number:3 encoded nl:1 trunc:0x3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer: 6, old: 347933, new: 347933
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 318329
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic lost timer pto:997
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 997:318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic state: send:30000 pto:997 close:58953
server  | 2025/12/13 12:25:48 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:48 [debug] 22#0: epoll timer: 997
server  | 2025/12/13 12:25:48 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:48 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic recvmsg: fd:6 n:1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx long flags:c2 version:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx init len:967
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000001002f767228805526d2b473a9fa3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic address validation token len:0 
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx clearflags:c1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx number:4 len:2
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet len:1200 via sock seq:0 path seq:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic path seq:0 status tx:4800 rx:6000 valid:0 st:0 mtu:1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx init:4 ACK n:0 delay:195 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic updated largest received ack:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic lost timer pto:538
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 538:318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx init:4 PADDING
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:4 largest 3 fr:3 nranges:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet done rc:0 level:init decr:1 pn:4 perr:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx long flags:e9 version:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx hs len:62
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000001002f767228805526d2b473a9fa3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx clearflags:e0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx number:5 len:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion ack rec t:318392 win:2400 if:1046
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:-1 closed nsock:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic lost timer pto:538
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 538:318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic path seq:0 in handshake tx:4800 rx:6000 valid:1 st:0 mtu:1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 post event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx hs:5 ACK n:0 delay:86 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_handle_ack_frame level:2
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic updated largest received ack:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic lost timer pto:538
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 538:318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx hs:5 CRYPTO len:36 off:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_set_read_secret() level:3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_add_handshake_data
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_flush_flight()
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 SSL_do_handshake: 1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 SSL: TLSv1.3, cipher: "TLS_AES_128_GCM_SHA256 Kx=GENERIC Au=GENERIC Enc=AESGCM(128) Mac=AEAD"
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 post event 000055B56EA08E88
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion ack rec t:318392 win:2400 if:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 318930
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic lost timer unset
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic path seq:0 schedule mtu:2400
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 100:318492
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic create sockets has:1 max:8
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:1 listening at sid:000000000000100210a7ff8e2a89d2c43c2dd7d4 nsock:2
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stateless reset token 29d1e0d82f5a76d93447d7f2d5548555
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56E9FC430:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:2 listening at sid:0000000000001002f7b230dd61b6c992e27f05d9 nsock:3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stateless reset token be22a559c855ab725396aa78629d5753
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56E9FC640:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:3 listening at sid:000000000000100225d240895e1b2f190811998c nsock:4
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stateless reset token 7ede9bc229c18dec39797fe4e692b004
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56E9FC850:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:4 listening at sid:000000000000100261af7076c28fb724a6e2ad26 nsock:5
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stateless reset token 5881b569ec29048aec1a2b30100c8aed
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56E9FCA60:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:5 listening at sid:0000000000001002696ea137c984ab38d4df72f8 nsock:6
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stateless reset token 318a902134cf5c739d93d17e265613e3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56EA56350:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:6 listening at sid:000000000000100288c0132a1bd32f7548a8ae61 nsock:7
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stateless reset token 6031f1da40eb24acfc3567d004552f01
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56EA56560:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic socket seq:7 listening at sid:0000000000001002ccb92cba9517dd3871435c32 nsock:8
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stateless reset token fa651f63e38b6a3178f11af18dcdb652
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic init streams
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 http3 init
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 http3 init session
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56EA56770:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 add cleanup: 000055B56E9FC5E8
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 75000:393392
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 376886
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:5 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet done rc:0 level:hs decr:1 pn:5 perr:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx short flags:5f
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000001002f767228805526d2b473a9fa3
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx clearflags:40
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet rx number:6 len:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx app:6 NEW_CONNECTION_ID seq:1 retire:0 len:8
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic cid seq:1 received id:8:551b1286ab1db0e1:0a0fcac41f62fb17bd69e380cc81892a
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame rx app:6 STREAM id:0x0 len:27 fin:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 is missing
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 create
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56EA56980:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56EA56B90:16384 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 reusable connection: 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 add cleanup: 000055B56EA56C50
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 post event 000055B56EA245D0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 delete posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 post event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 flow control msd:27/65536 md:27/8585216
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic ngx_quic_ack_packet pn:6 largest -1 fr:0 nranges:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet done rc:0 level:app decr:1 pn:6 perr:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 347933
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 30000:348392
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic state: read:30000
server  | 2025/12/13 12:25:48 [debug] 22#0: timer delta: 459
server  | 2025/12/13 12:25:48 [debug] 22#0: posted event 000055B56EA08E88
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 delete posted event 000055B56EA08E88
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic key update
server  | 2025/12/13 12:25:48 [debug] 22#0: posted event 000055B56EA245D0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 delete posted event 000055B56EA245D0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 quic init stream
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http3 init request stream
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 add cleanup: 000055B56EA56CC8
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer del: 6: 393392
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http wait request handler
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 recv buf:1024
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 quic stream id:0x0 recv len:27
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 flow update 27
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 reusable connection: 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 posix_memalign: 000055B56EA5ABA0:4096 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http process request line
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http request line: "GET /merry-magenta-wizard"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http uri: "/merry-magenta-wizard"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http args: ""
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http exten: ""
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 rewrite phase: 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 test location: "/"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 using configuration "/"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http cl:-1 max:1048576
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 rewrite phase: 2
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 post rewrite phase: 3
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 generic phase: 4
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 generic phase: 5
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 access phase: 6
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 access phase: 7
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 post access phase: 8
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 generic phase: 9
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 generic phase: 10
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 content phase: 11
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 content phase: 12
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 content phase: 13
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http filename: "/www/merry-magenta-wizard"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 add cleanup: 000055B56EA5B8F0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http static fd: 3
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http set discard body
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http output filter "/merry-magenta-wizard?"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http copy filter: "/merry-magenta-wizard?"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 posix_memalign: 000055B56EA5BBB0:4096 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 read: 3, 000055B56EA5BBD0, 1024, 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http postpone filter "/merry-magenta-wizard?" 000055B56EA5BB08
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 write new buf t:1 f:0 000055B56EA5BBD0, pos 000055B56EA5BBD0, size: 1024 file: 0, size: 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http write filter: l:1 f:0 s:1024
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http write filter limit 2097152
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 quic send_chain sent:1024
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 flush limit:6291456
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 flush len:1024 last:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http write filter 0000000000000000
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http copy filter: 0 "/merry-magenta-wizard?"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http finalize request: 0, "/merry-magenta-wizard?" a:1, c:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http request count:1 blk:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http close request
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 http log handler
server  | 193.167.0.100 - - [13/Dec/2025:12:25:48 +0000] "GET /merry-magenta-wizard" 200 1024 "-" "-"
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 run cleanup: 000055B56EA5B8F0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 file cleanup: fd:3
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 free: 000055B56EA5ABA0, unused: 40
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 free: 000055B56EA5BBB0, unused: 2915
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 close http connection: 6
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 reusable connection: 0
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 run cleanup: 000055B56EA56CC8
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 75000:393392
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 run cleanup: 000055B56EA56C50
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 cleanup
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 send shutdown
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 flush limit:6290432
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 posix_memalign: 000055B56EA5ABA0:512 @16
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 update posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic stream id:0x0 flush len:0 last:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *3 free: 000055B56EA56B90, unused: 14944
server  | 2025/12/13 12:25:48 [debug] 22#0: posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 delete posted event 000055B56EA08D08
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic push handler
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 ACK n:0 delay:0 6
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 CRYPTO len:572 off:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 HANDSHAKE DONE
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:1 retire:0 len:20
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:2 retire:0 len:20
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:3 retire:0 len:20
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:4 retire:0 len:20
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:5 retire:0 len:20
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:6 retire:0 len:20
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 NEW_CONNECTION_ID seq:7 retire:0 len:20
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic split frame now:1029 need:312 shrink:717
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:0 STREAM id:0x0 off:0 len:307
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet tx app bytes:1174 need_ack:1 number:0 encoded nl:1 trunc:0x0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 sendmsg: 1200 of 1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion send if:1200
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion idle:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic output app packet max:1200 min:0
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:307 len:717
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic frame tx app:1 STREAM id:0x0 off:1024 len:0 fin:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic packet tx app bytes:728 need_ack:1 number:1 encoded nl:1 trunc:0x1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 sendmsg: 754 of 754
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion send if:1954
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic congestion idle:1
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer: 6, old: 348392, new: 348392
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic lost timer pto:1022
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 event timer add: 6: 1022:319414
server  | 2025/12/13 12:25:48 [debug] 22#0: *2 quic state: send:30000 pto:1022
server  | 2025/12/13 12:25:48 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:48 [debug] 22#0: epoll timer: 100
server  | 2025/12/13 12:25:49 [debug] 22#0: timer delta: 102
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 event timer del: 6: 318492
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 posix_memalign: 000055B56EA56B90:512 @16
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic path seq:0 send probe mtu:2400 pnum:2 tries:0
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic sendto app packet max:2400 min:2400
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic frame tx app:2 PING
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet tx app bytes:2374 need_ack:0 number:2 encoded nl:1 trunc:0x2
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic path seq:0 rejected mtu:2400
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic path seq:0 send probe mtu:1800 pnum:3 tries:0
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic sendto app packet max:1800 min:1800
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic frame tx app:3 PING
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet tx app bytes:1774 need_ack:0 number:3 encoded nl:1 trunc:0x3
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic path seq:0 rejected mtu:1800
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic path seq:0 send probe mtu:1500 pnum:4 tries:0
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic sendto app packet max:1500 min:1500
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic frame tx app:4 PING
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet tx app bytes:1474 need_ack:0 number:4 encoded nl:1 trunc:0x4
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic path seq:0 rejected mtu:1500
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic path seq:0 send probe mtu:1350 pnum:5 tries:0
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic sendto app packet max:1350 min:1350
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic frame tx app:5 PING
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet tx app bytes:1324 need_ack:0 number:5 encoded nl:1 trunc:0x5
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 sendmsg: 1350 of 1350
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic congestion send if:3304
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 event timer del: 6: 319414
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic lost timer pto:1022
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 event timer add: 6: 1022:319516
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 event timer add: 6: 1022:319516
server  | 2025/12/13 12:25:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:49 [debug] 22#0: epoll timer: 1022
server  | 2025/12/13 12:25:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic recvmsg: fd:6 n:59
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx long flags:ed version:1
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx hs len:22
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000001002f767228805526d2b473a9fa3
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:49 [info] 22#0: *2 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0
server  | 2025/12/13 12:25:49 [debug] 22#0: timer delta: 40
server  | 2025/12/13 12:25:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:49 [debug] 22#0: epoll timer: 982
server  | 2025/12/13 12:25:49 [debug] 22#0: epoll: fd:6 ev:0001 d:00007F462F269108
server  | 2025/12/13 12:25:49 [debug] 22#0: quic recvmsg on 0.0.0.0:443, ready: 0
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic recvmsg: fd:6 n:59
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic input handler
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx long flags:e4 version:1
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx hs len:22
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx dcid len:20 0000000000001002f767228805526d2b473a9fa3
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet rx scid len:8 0e838b1b64b1d496
server  | 2025/12/13 12:25:49 [info] 22#0: *2 quic no hs keys, ignoring packet while decrypting packet, client: 193.167.0.100, server: 0.0.0.0:443
server  | 2025/12/13 12:25:49 [debug] 22#0: *2 quic packet done rc:-5 level:hs decr:0 pn:0 perr:0
server  | 2025/12/13 12:25:49 [debug] 22#0: timer delta: 300
server  | 2025/12/13 12:25:49 [debug] 22#0: worker cycle
server  | 2025/12/13 12:25:49 [debug] 22#0: epoll timer: 682
client  | /logs/0E838B1B64B1D496.keys
client  | /logs/keys.log exists

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-12-13 12:26:00,180 Using the client's key log file.
2025-12-13 12:26:00,188 
2025-12-13 12:26:00,188 Using the client's key log file.
2025-12-13 12:26:00,189 Using the client's key log file.
2025-12-13 12:26:00,196 
2025-12-13 12:26:00,196 Using the client's key log file.
2025-12-13 12:26:00,404 Check of downloaded files succeeded.
2025-12-13 12:26:01,253 Check of downloaded files succeeded.