2025-04-30 10:06:30,237 Generated random file: vuvtbuvvet of size: 10485760 2025-04-30 10:06:30,237 Requests: https://server4:443/vuvtbuvvet 2025-04-30 10:06:30,295 2025-04-30 10:06:30,295 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_fhh2mfev/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_djc673iv/ DOWNLOADS=/tmp/download_xeo808e3/ SERVER_LOGS=/tmp/logs_server_4gqijh1z CLIENT_LOGS=/tmp/logs_client_eip2jj1v SCENARIO="rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s --rebind-addr" CLIENT=ghcr.io/facebook/proxygen/mvfst-interop:latest SERVER=haproxytech/haproxy-qns:latest REQUESTS="https://server4:443/vuvtbuvvet" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 10:06:48,140 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 | + set -o nounset client | + VERSION=1 client | + HQ_CLI=/proxygen/_build/proxygen/bin/hq client | + PORT=443 client | + LOGLEVEL=2 client | + /setup.sh 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 server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | starting lighttpd server client | + PROTOCOL=hq-interop client | + HTTPVERSION=0.9 client | + CONN_FLOW_CONTROL=107374182 client | + STREAM_FLOW_CONTROL=107374182 client | ++ echo https://server4:443/vuvtbuvvet client | ++ tr ' ' '\n' client | ++ awk -F / '{ print "/" $4 }' client | ++ paste -sd, client | + INVOCATIONS=/vuvtbuvvet client | + EARLYDATA=false client | + PSK_FILE= client | + '[' -n transfer ']' client | + case "${TESTCASE}" in client | + STREAM_FLOW_CONTROL=262144 client | + CONN_FLOW_CONTROL=2621440 client | + '[' client == client ']' client | + /wait-for-it.sh sim:57832 -s -t 10 client | wait-for-it.sh: waiting 10 seconds for sim:57832 server | haproxy version HAProxy version 0f9b3daf98a80004f90e9540b0385d419f0e4e16 2025/04/25 - https://haproxy.org/ server | Status: development branch - not safe for use in production. server | Known bugs: https://github.com/haproxy/haproxy/issues?q=is:issue+is:open server | Running on: Linux 6.11.0-1012-azure #12~24.04.1-Ubuntu SMP Mon Mar 10 19:00:39 UTC 2025 x86_64 server | starting haproxy... sim | server:443 is available after 1.048974747s sim | Using scenario: rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s --rebind-addr sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth1, 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 client | + echo 'Starting QUIC client...' client | Starting QUIC client... client | + '[' -n https://server4:443/vuvtbuvvet ']' client | + REQS=("${REQUESTS}") client | + REQ=https://server4:443/vuvtbuvvet client | ++ echo https://server4:443/vuvtbuvvet client | ++ cut -d/ -f3 client | ++ cut -d: -f1 client | + SERVER=server4 client | + for INVOCATION in ${INVOCATIONS} client | + echo 'requesting files '\''/vuvtbuvvet'\''' client | requesting files '/vuvtbuvvet' client | + /proxygen/_build/proxygen/bin/hq --mode=client --host=server4 --port=443 --protocol=hq-interop --httpversion=0.9 --use_version=true --quic-version=1 --path=/vuvtbuvvet --early_data=false --psk_file= --conn_flow_control=2621440 --stream_flow_control=262144 --outdir=/downloads --logdir=/logs --qlogger_path=/logs --v=2 client | + tee /logs/client.log client | I20250430 10:06:31.894088 42 HQClient.cpp:47] HQClient connecting to 193.167.100.100:443 client | I20250430 10:06:31.936298 42 CurlClient.cpp:187] Sending request for /vuvtbuvvet sim | 1s: rebinding: 193.167.0.100:39397 -> 193.167.0.224:59022 sim | 1.00074s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00177s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00279s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00382s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00484s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00587s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.0069s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00792s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00895s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.00955s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01058s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01161s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01243s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01346s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01448s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01551s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01653s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01671s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01774s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01876s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.01979s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02082s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02184s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02287s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02389s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02492s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02594s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02613s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.02626s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.0263s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.14313s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.14416s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.34448s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.34551s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.71009s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 1.71112s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 2.40412s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 2.40515s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 3.75361s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 3.75464s: unknown binding for destination 193.167.0.100:39397, dropping packet sim | 6s: rebinding: 193.167.0.224:59022 -> 193.167.0.71:39968 client | E20250430 10:06:37.787231 42 CurlClient.cpp:292] An error occurred: ingress timeout, streamID=1, timeout=5000ms client exited with code 0 Aborting on container exit... Container server Stopping Container client Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 10:06:48,254 Using the server's key log file. 2025-04-30 10:06:48,268 2025-04-30 10:06:48,270 Using the server's key log file. 2025-04-30 10:06:48,270 Using the server's key log file. 2025-04-30 10:06:48,280 2025-04-30 10:06:48,281 Using the server's key log file. 2025-04-30 10:06:48,893 File size of /tmp/download_xeo808e3/vuvtbuvvet doesn't match. Original: 10485760 bytes, downloaded: 836810 bytes. 2025-04-30 10:06:48,893 Using the server's key log file. 2025-04-30 10:06:49,505 File size of /tmp/download_xeo808e3/vuvtbuvvet doesn't match. Original: 10485760 bytes, downloaded: 836810 bytes. 2025-04-30 10:06:49,505 Using the server's key log file. 2025-04-30 10:06:52,437 Server saw these client addresses: {'193.167.0.100'} 2025-04-30 10:06:52,438 Server saw only a single client IP address in use; test broken?