2025-04-30 09:24:09,021 Generated random file: iyfvkjywev of size: 10485760 2025-04-30 09:24:09,021 Requests: https://server4:443/iyfvkjywev 2025-04-30 09:24:09,084 2025-04-30 09:24:09,084 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_b9a1_ueo/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_c0wd1ysk/ DOWNLOADS=/tmp/download_9s7eszhu/ SERVER_LOGS=/tmp/logs_server_4foo4g4l CLIENT_LOGS=/tmp/logs_client_dc3g85g0 SCENARIO="rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s --rebind-addr" CLIENT=stammw/quinn-interop:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/iyfvkjywev" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 09:25:01,959 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 client | Executing client server | Setting up routes... server | Actual changes: server | tx-checksumming: off server | tx-checksum-ip-generic: off server | tx-checksum-sctp: off server | tcp-segmentation-offload: off server | tx-tcp-segmentation: off [requested on] server | tx-tcp-ecn-segmentation: off [requested on] server | tx-tcp-mangleid-segmentation: off [requested on] server | tx-tcp6-segmentation: off [requested on] server | tx-udp-segmentation: off [requested on] server | Endpoint's IPv4 address is 193.167.100.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | >>> Starting nginx server... server | nginx version: nginx/1.27.0 (9270:d1b8568f3042) server | built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | built with OpenSSL 1.1.1 (compatible; BoringSSL) (running with BoringSSL) server | TLS SNI support enabled server | configure arguments: --prefix=/etc/nginx --build=9270:d1b8568f3042 --sbin-path=/usr/sbin/nginx --modules-path=/usr/lib/nginx/modules --conf-path=/etc/nginx/nginx.conf --error-log-path=/var/log/nginx/error.log --http-log-path=/var/log/nginx/access.log --pid-path=/run/nginx.pid --lock-path=/run/nginx.lock --http-client-body-temp-path=/var/cache/nginx/client_temp --http-proxy-temp-path=/var/cache/nginx/proxy_temp --http-fastcgi-temp-path=/var/cache/nginx/fastcgi_temp --http-uwsgi-temp-path=/var/cache/nginx/uwsgi_temp --http-scgi-temp-path=/var/cache/nginx/scgi_temp --user=nginx --group=nginx --with-compat --with-debug --with-http_ssl_module --with-http_v2_module --with-http_v3_module --with-cc=c++ --with-cc-opt='-I/boringssl/include -O0 -fno-common -fno-omit-frame-pointer -DNGX_QUIC_DRAFT_VERSION=29 -DNGX_HTTP_V3_HQ=1 -x c' --with-ld-opt='-L/boringssl/build/ssl -L/boringssl/build/crypto' server | >>> Parameters: server | >>> Test case: transfer server | 2025/04/30 09:24:09 [notice] 21#0: using the "epoll" event method server | 2025/04/30 09:24:09 [notice] 21#0: nginx/1.27.0 (9270:d1b8568f3042) server | 2025/04/30 09:24:09 [notice] 21#0: built by gcc 9.4.0 (Ubuntu 9.4.0-1ubuntu1~20.04.2) server | 2025/04/30 09:24:09 [notice] 21#0: OS: Linux 6.11.0-1012-azure server | 2025/04/30 09:24:09 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 1048576:1048576 server | 2025/04/30 09:24:09 [notice] 21#0: start worker processes server | 2025/04/30 09:24:09 [notice] 21#0: start worker process 22 sim | server:443 is available after 1.010816987s sim | Using scenario: rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s --rebind-addr sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | 0s: first rebind in 1s, frequency 5s client | wait-for-it.sh: sim:57832 is available after 1 seconds server | 2025/04/30 09:24:13 [info] 22#0: *4 quic reserved transport param id:0xb6, skipped while handling frames, client: 193.167.0.224, server: 0.0.0.0:443 server | 2025/04/30 09:24:13 [info] 22#0: *4 quic unknown transport param id:0x20, skipped while handling frames, client: 193.167.0.224, server: 0.0.0.0:443 server | 2025/04/30 09:24:13 [info] 22#0: *4 quic unknown transport param id:0x2ab2, skipped while handling frames, client: 193.167.0.224, server: 0.0.0.0:443 server | 2025/04/30 09:24:13 [info] 22#0: *4 quic unknown transport param id:0xff04de1b, skipped while handling frames, client: 193.167.0.224, server: 0.0.0.0:443 sim | 6s: rebinding: 193.167.0.224:39208 -> 193.167.0.229:18108 sim | 6.00046s: unknown binding for destination 193.167.0.224:39208, dropping packet server | 2025/04/30 09:24:16 [info] 22#0: *4 quic migrated to path seq:1 addr:193.167.0.229:18108 while handling frames, client: 193.167.0.229, server: 0.0.0.0:443 sim | 6.00122s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00242s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00319s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00439s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00516s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00636s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00713s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00833s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.00909s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01029s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01106s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01226s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01303s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01423s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01499s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01619s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01696s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01816s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.01916s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02036s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02112s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02233s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02309s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02429s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02506s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02626s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02703s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.02823s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.029s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.0302s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03096s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03217s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03293s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03413s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.0349s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.0361s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03687s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03807s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03884s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.03982s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 6.0408s: unknown binding for destination 193.167.0.224:39208, dropping packet server | 2025/04/30 09:24:16 [info] 22#0: *4 quic path seq:1 addr:193.167.0.229:18108 successfully validated while handling frames, client: 193.167.0.229, server: 0.0.0.0:443 sim | 7.0402s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 7.04119s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 9.04035s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 9.04134s: unknown binding for destination 193.167.0.224:39208, dropping packet sim | 11s: rebinding: 193.167.0.229:18108 -> 193.167.0.155:745 server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0011s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0023s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0032s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0044s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0051s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0063s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0071s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0083s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0091s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0103s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.011s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0122s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.013s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.0142s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0149s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0161s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0169s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0181s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0188s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0201s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0208s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.022s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0228s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.024s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0247s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0259s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0267s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0279s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0291s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0303s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0306s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0318s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0325s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0337s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0349s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0361s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0364s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0376s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0384s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.0402s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.1049s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.1049s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:21 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.2894s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.2894s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:22 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:22 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 11.657s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 11.657s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:22 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:22 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 12.393s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 12.3931s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 13.0168s: unknown binding for destination 193.167.0.229:18108, dropping packet server | 2025/04/30 09:24:24 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:24 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 14.4904s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 14.4904s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 16s: rebinding: 193.167.0.155:745 -> 193.167.0.151:64331 server | 2025/04/30 09:24:26 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:26 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 17.4358s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 17.4358s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 21s: rebinding: 193.167.0.151:64331 -> 193.167.0.76:51391 server | 2025/04/30 09:24:31 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:31 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 23.3257s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 23.3257s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 26s: rebinding: 193.167.0.76:51391 -> 193.167.0.110:55838 sim | 31s: rebinding: 193.167.0.110:55838 -> 193.167.0.179:26867 server | 2025/04/30 09:24:42 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 server | 2025/04/30 09:24:42 [info] 22#0: *4 quic no available client ids for new path while handling decrypted packet, client: 193.167.0.229, server: 0.0.0.0:443 sim | 35.1048s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 35.1048s: unknown binding for destination 193.167.0.229:18108, dropping packet sim | 36s: rebinding: 193.167.0.179:26867 -> 193.167.0.129:15378 server | 2025/04/30 09:24:51 [info] 22#0: *4 quic client timed out (110: Connection timed out) while handling quic input, client: 193.167.0.229, server: 0.0.0.0:443 server | 193.167.0.224 - - [30/Apr/2025:09:24:51 +0000] "GET /iyfvkjywev" 200 9160033 "-" "-" sim | 41s: rebinding: 193.167.0.129:15378 -> 193.167.0.125:26456 client | Error: request failed client | client | Caused by: client | 0: connection lost client | 1: timed out client | client | Stack backtrace: client | 0: anyhow::error:: for anyhow::Error>::from client | 1: client::hq_download::{{closure}} client | 2: client::hq_download_all::{{closure}}::{{closure}} client | 3: tokio::runtime::task::core::Core::poll client | 4: tokio::runtime::task::harness::Harness::poll client | 5: tokio::runtime::scheduler::multi_thread::worker::Context::run_task client | 6: tokio::runtime::scheduler::multi_thread::worker::Context::run client | 7: tokio::runtime::context::scoped::Scoped::set client | 8: tokio::runtime::context::runtime::enter_runtime client | 9: tokio::runtime::scheduler::multi_thread::worker::run client | 10: tokio::runtime::task::core::Core::poll client | 11: tokio::runtime::task::harness::Harness::poll client | 12: tokio::runtime::blocking::pool::Inner::run client | 13: std::sys_common::backtrace::__rust_begin_short_backtrace client | 14: core::ops::function::FnOnce::call_once{{vtable.shim}} client | 15: as core::ops::function::FnOnce>::call_once client | at /rustc/eb26296b556cef10fb713a38f3d16b9886080f26/library/alloc/src/boxed.rs:1985:9 client | 16: as core::ops::function::FnOnce>::call_once client | at /rustc/eb26296b556cef10fb713a38f3d16b9886080f26/library/alloc/src/boxed.rs:1985:9 client | 17: std::sys::unix::thread::Thread::new::thread_start client | at /rustc/eb26296b556cef10fb713a38f3d16b9886080f26/library/std/src/sys/unix/thread.rs:108:17 client | 18: client | 19: __clone client exited with code 1 Aborting on container exit... Container server Stopping Container client Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped