2025-12-13 13:39:09,642 Generated random file: abundant-euphoric-ring of size: 10485760 2025-12-13 13:39:09,642 Requests: https://server4:443/abundant-euphoric-ring 2025-12-13 13:39:09,703 2025-12-13 13:39:09,703 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_aplb5r_u/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_lf_ylx_7/ DOWNLOADS=/tmp/download_sngi0gck/ SERVER_LOGS=/tmp/logs_server_rly_y161 CLIENT_LOGS=/tmp/logs_client_s58zat1u SCENARIO="rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s" CLIENT=martenseemann/quic-go-interop:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/abundant-euphoric-ring" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 13:39:52,057 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container client Recreated Container server Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Setting up routes... server | Actual changes: server | tx-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 server | Endpoint's IPv4 address is 193.167.100.100 client | Using commit: add7529015e46cda13441129d3081020f1baddc1 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::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: transfer client | wait-for-it.sh: waiting 10 seconds for sim:57832 server | 2025/12/13 13:39:10 [notice] 21#0: using the "epoll" event method server | 2025/12/13 13:39:10 [notice] 21#0: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f) server | 2025/12/13 13:39:10 [notice] 21#0: built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) server | 2025/12/13 13:39:10 [notice] 21#0: OS: Linux 6.11.0-1018-azure server | 2025/12/13 13:39:10 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 65536:65536 server | 2025/12/13 13:39:10 [notice] 21#0: start worker processes server | 2025/12/13 13:39:10 [notice] 21#0: start worker process 22 sim | server:443 is available after 501.242684ms sim | Using scenario: rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s sim | Capturing on 'eth1' sim | Capturing on 'eth0' sim | File: /logs/trace_node_left.pcap sim | File: /logs/trace_node_right.pcap sim | 0s: first rebind in 1s, frequency 5s client | wait-for-it.sh: sim:57832 is available after 1 seconds client | Starting QUIC client... client | Client params: client | Test case: transfer sim | Packets: 1 Packets: 1 Packets: 3 Packets: 6 Packets: 10 Packets: 9 Packets: 58 Packets: 55 1s: rebinding: 193.167.0.100:56213 -> 193.167.0.100:57607 server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.00075s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.00194s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.00271s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.0039s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.00488s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.00607s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.00685s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.00803s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.00881s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.00999s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.01077s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.01195s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.01273s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.01391s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.01469s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.01587s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.01665s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.01784s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.01861s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.0198s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02058s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02177s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02255s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02373s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02451s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.0257s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02648s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02766s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02845s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.02963s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03042s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.0316s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03238s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03357s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03435s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03554s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03608s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03726s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.03804s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | 1.10678s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.10682s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | Packets: 323 Packets: 332 1.29228s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.29232s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:11 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | Packets: 523 Packets: 498 Packets: 531 Packets: 506 1.66521s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 1.66525s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:12 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:12 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | Packets: 538 Packets: 510 Packets: 542 Packets: 512 2.41025s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 2.41029s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:13 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:13 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | Packets: 513 Packets: 544 Packets: 546 Packets: 515 3.89913s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 3.89917s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:15 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:15 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | Packets: 548 Packets: 550 Packets: 518 Packets: 551 Packets: 519 6s: rebinding: 193.167.0.100:57607 -> 193.167.0.100:59022 sim | Packets: 552 6.8775s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 6.87754s: unknown binding for destination 193.167.0.100:56213, dropping packet server | 2025/12/13 13:39:19 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:19 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | Packets: 554 Packets: 556 Packets: 521 11s: rebinding: 193.167.0.100:59022 -> 193.167.0.100:18108 sim | 12.8339s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 12.8339s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | Packets: 558 Packets: 522 Packets: 559 Packets: 523 Packets: 560 16s: rebinding: 193.167.0.100:18108 -> 193.167.0.100:39968 server | 2025/12/13 13:39:26 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 server | 2025/12/13 13:39:26 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.100, server: 0.0.0.0:443 sim | Packets: 562 Packets: 525 21s: rebinding: 193.167.0.100:39968 -> 193.167.0.100:745 sim | 24.7389s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | 24.739s: unknown binding for destination 193.167.0.100:56213, dropping packet sim | Packets: 563 Packets: 564 26s: rebinding: 193.167.0.100:745 -> 193.167.0.100:38910 server | 2025/12/13 13:39:41 [info] 22#0: *2 quic client timed out (110: Connection timed out) while handling quic input, client: 193.167.0.100, server: 0.0.0.0:443 server | 193.167.0.100 - - [13/Dec/2025:13:39:41 +0000] "GET /abundant-euphoric-ring" 200 395669 "-" "-" sim | Packets: 526 Packets: 565 31s: rebinding: 193.167.0.100:38910 -> 193.167.0.100:64331 client | Downloading files failed: timeout: no recent network activity client exited with code 1 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped