2025-12-13 12:16:38,822 Generated random file: zippy-subtropical-tractor of size: 10485760 2025-12-13 12:16:38,822 Requests: https://server4:443/zippy-subtropical-tractor 2025-12-13 12:16:38,882 2025-12-13 12:16:38,883 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_fuy0_quj/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_83nfe6a_/ DOWNLOADS=/tmp/download_qly08ce4/ SERVER_LOGS=/tmp/logs_server_gzsaqhef CLIENT_LOGS=/tmp/logs_client_89gebjcj SCENARIO="rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s --rebind-addr" CLIENT=us-central1-docker.pkg.dev/golang-interop-testing/quic/go-x-net:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/zippy-subtropical-tractor" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 12:17:26,214 Container sim Recreate Container sim Recreated Container server Recreate Container client Recreate Container server Recreated Container client 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 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 30 seconds for sim:57832 server | 2025/12/13 12:16:39 [notice] 21#0: using the "epoll" event method server | 2025/12/13 12:16:39 [notice] 21#0: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f) server | 2025/12/13 12:16:39 [notice] 21#0: built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) server | 2025/12/13 12:16:39 [notice] 21#0: OS: Linux 6.11.0-1018-azure server | 2025/12/13 12:16:39 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 65536:65536 server | 2025/12/13 12:16:39 [notice] 21#0: start worker processes server | 2025/12/13 12:16:39 [notice] 21#0: start worker process 22 sim | server:443 is available after 501.124892ms sim | Using scenario: rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s --rebind-addr 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 | 2025/12/13 12:16:40 listening on [::]:56879 server | 2025/12/13 12:16:40 [info] 22#0: *2 quic fixed bit is not set while parsing quic packet, client: 193.167.0.100, server: 0.0.0.0:443 client | 2025/12/13 12:16:40 connected to server4:443 sim | Packets: 1 Packets: 1 Packets: 4 Packets: 5 Packets: 9 Packets: 9 Packets: 53 Packets: 54 1s: rebinding: 193.167.0.100:56879 -> 193.167.0.224:59022 sim | 1.0002s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.0014s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.0026s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.0038s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.005s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01112s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01232s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01352s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01473s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01593s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01713s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01833s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.01953s: unknown binding for destination 193.167.0.100:56879, dropping packet server | 2025/12/13 12:16:40 [info] 22#0: *2 quic migrated to path seq:1 addr:193.167.0.224:59022 while handling frames, client: 193.167.0.224, server: 0.0.0.0:443 sim | 1.02073s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.02193s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.03655s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 1.03753s: unknown binding for destination 193.167.0.100:56879, dropping packet server | 2025/12/13 12:16:40 [info] 22#0: *2 quic path seq:1 addr:193.167.0.224:59022 successfully validated while handling frames, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 319 Packets: 324 Packets: 441 Packets: 438 Packets: 657 Packets: 664 Packets: 877 Packets: 880 Packets: 1093 Packets: 1095 2.06012s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 2.0611s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | Packets: 1308 Packets: 1310 Packets: 1536 Packets: 1532 Packets: 1764 Packets: 1762 Packets: 1995 Packets: 1994 Packets: 2228 Packets: 2227 Packets: 2455 Packets: 2456 Packets: 2665 Packets: 2666 Packets: 2879 Packets: 2879 Packets: 3099 Packets: 3098 Packets: 3328 Packets: 3327 Packets: 3562 Packets: 3560 4.05406s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | 4.05505s: unknown binding for destination 193.167.0.100:56879, dropping packet sim | Packets: 3793 Packets: 3792 Packets: 4014 Packets: 4010 Packets: 4223 Packets: 4220 Packets: 4433 Packets: 4430 Packets: 4641 Packets: 4639 Packets: 4851 Packets: 4850 Packets: 5061 Packets: 5061 Packets: 5273 Packets: 5274 Packets: 5504 Packets: 5506 Packets: 5737 Packets: 5738 6s: rebinding: 193.167.0.224:59022 -> 193.167.0.71:39968 sim | 6.00036s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.00156s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.00162s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.00282s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:45 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | 6.00402s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:45 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | 6.00522s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.00642s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.00761s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.00881s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.00887s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01007s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:45 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | 6.01127s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:45 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | 6.01247s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01367s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01486s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01606s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01611s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01732s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01852s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.01972s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02092s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02211s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02331s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02336s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02456s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02577s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02697s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02817s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.02935s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.03055s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.03061s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.03181s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.03301s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.03421s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.03542s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.0366s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:45 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 server | 2025/12/13 12:16:45 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | 6.098s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.09805s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:45 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 5970 Packets: 5971 6.26881s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.26885s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:46 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 6123 Packets: 6073 6.60853s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 6.60858s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:46 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 6126 Packets: 6074 Packets: 6129 Packets: 6075 7.28851s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 7.28856s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:48 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 6130 Packets: 6076 Packets: 6132 8.64912s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 8.64916s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:50 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 6133 Packets: 6077 Packets: 6135 11s: rebinding: 193.167.0.71:39968 -> 193.167.0.3:38910 sim | Packets: 6078 Packets: 6136 11.3701s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 11.3701s: unknown binding for destination 193.167.0.224:59022, dropping packet server | 2025/12/13 12:16:55 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 6138 Packets: 6139 Packets: 6079 Packets: 6080 Packets: 6140 Packets: 6141 Packets: 6081 16s: rebinding: 193.167.0.3:38910 -> 193.167.0.250:19495 sim | 16.8118s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 16.8118s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | Packets: 6143 21s: rebinding: 193.167.0.250:19495 -> 193.167.0.200:28302 server | 2025/12/13 12:17:04 [info] 22#0: *2 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.224, server: 0.0.0.0:443 sim | Packets: 6144 Packets: 6082 26s: rebinding: 193.167.0.200:28302 -> 193.167.0.217:46157 sim | 27.6939s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | 27.694s: unknown binding for destination 193.167.0.224:59022, dropping packet sim | Packets: 6145 Packets: 6147 Packets: 6083 Packets: 6084 31s: rebinding: 193.167.0.217:46157 -> 193.167.0.105:33243 sim | Packets: 6148 36s: rebinding: 193.167.0.105:33243 -> 193.167.0.60:32217 client | 2025/12/13 12:17:15 fetch https://server4:443/zippy-subtropical-tractor: stream reset by peer: stream error code 9223372036854775807 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:17:26,334 Using the client's key log file. 2025-12-13 12:17:26,357 2025-12-13 12:17:26,366 Using the client's key log file. 2025-12-13 12:17:26,366 Using the client's key log file. 2025-12-13 12:17:26,389 2025-12-13 12:17:26,397 Using the client's key log file. 2025-12-13 12:17:27,178 File size of /tmp/download_qly08ce4/zippy-subtropical-tractor doesn't match. Original: 10485760 bytes, downloaded: 6014823 bytes. 2025-12-13 12:17:27,178 Using the client's key log file. 2025-12-13 12:17:27,960 File size of /tmp/download_qly08ce4/zippy-subtropical-tractor doesn't match. Original: 10485760 bytes, downloaded: 6014823 bytes. 2025-12-13 12:17:27,960 Using the client's key log file. 2025-12-13 12:17:44,706 Server saw these client addresses: {'193.167.0.100', '193.167.0.224'} 2025-12-13 12:17:45,514 File size of /tmp/download_qly08ce4/zippy-subtropical-tractor doesn't match. Original: 10485760 bytes, downloaded: 6014823 bytes. 2025-12-13 12:17:45,514 Using the client's key log file. 2025-12-13 12:17:46,332 File size of /tmp/download_qly08ce4/zippy-subtropical-tractor doesn't match. Original: 10485760 bytes, downloaded: 6014823 bytes.