2025-04-30 11:27:14,419 Generated random file: wbipntbarf of size: 10485760 2025-04-30 11:27:14,419 Requests: https://server4:443/wbipntbarf 2025-04-30 11:27:14,476 2025-04-30 11:27:14,477 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_fhr34byg/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_d2zgrbz6/ DOWNLOADS=/tmp/download_6_dhs4cm/ SERVER_LOGS=/tmp/logs_server_n779fl0n CLIENT_LOGS=/tmp/logs_client_lt72q6uv SCENARIO="rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s" CLIENT=ghcr.io/facebook/proxygen/mvfst-interop:latest SERVER=privateoctopus/picoquic:latest REQUESTS="https://server4:443/wbipntbarf" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 11:27:35,753 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 the simulation with setup.sh server | Setting up routes... 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 | 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 | 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 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 | Setup.sh completed server | Starting picoquic server for test: transfer client | + PROTOCOL=hq-interop client | + HTTPVERSION=0.9 client | + CONN_FLOW_CONTROL=107374182 client | + STREAM_FLOW_CONTROL=107374182 client | ++ echo https://server4:443/wbipntbarf client | ++ tr ' ' '\n' client | ++ awk -F / '{ print "/" $4 }' client | ++ paste -sd, server | wbipntbarf server | Starting picoquic server ... server | TEST_PARAMS: -8 -w ./www -L -l /logs/server_log.txt -q /logs/qlog -k /certs/priv.key -c /certs/cert.pem -p 443 -V -0 -d 180000 client | + INVOCATIONS=/wbipntbarf 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 server | Starting Picoquic server (v1.1.26.1) on port 443, server name = ::, just_once = 0, do_retry = 0 server | No token file present. Will create one as . server | Accept enable multipath: 0. server | Waiting for packets. client | wait-for-it.sh: waiting 10 seconds for sim:57832 sim | server:443 is available after 1.061752956s sim | Using scenario: rebind --delay=15ms --bandwidth=10Mbps --queue=25 --first-rebind=1s --rebind-freq=5s 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/wbipntbarf ']' client | + REQS=("${REQUESTS}") client | + REQ=https://server4:443/wbipntbarf client | ++ echo https://server4:443/wbipntbarf client | ++ cut -d/ -f3 client | ++ cut -d: -f1 client | + SERVER=server4 client | + for INVOCATION in ${INVOCATIONS} client | + echo 'requesting files '\''/wbipntbarf'\''' client | requesting files '/wbipntbarf' 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=/wbipntbarf --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 11:27:16.057922 43 HQClient.cpp:47] HQClient connecting to 193.167.100.100:443 client | I20250430 11:27:16.098696 43 CurlClient.cpp:187] Sending request for /wbipntbarf sim | 1s: rebinding: 193.167.0.100:40695 -> 193.167.0.100:57607 sim | 1.00071s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00174s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00276s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00379s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00482s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00584s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00687s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00789s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00892s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.00994s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.01097s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.012s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.01302s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.01405s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.01507s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.0161s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.01712s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.01815s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.01917s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.0202s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.02123s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.02225s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.02328s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.0243s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.02533s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.02635s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.02738s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.0284s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.02943s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.03046s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 1.03059s: unknown binding for destination 193.167.0.100:40695, dropping packet sim | 6s: rebinding: 193.167.0.100:57607 -> 193.167.0.100:59022 sim | 6.00027s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00132s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00236s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00343s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00447s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00551s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00656s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00759s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00865s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.00973s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.01075s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.0118s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.01285s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.0139s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.01496s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.016s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.01706s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.01811s: unknown binding for destination 193.167.0.100:57607, dropping packet sim | 6.01825s: unknown binding for destination 193.167.0.100:57607, dropping packet client | I20250430 11:27:25.325089 43 CurlClient.cpp:276] Got EOM for /wbipntbarf. Txn Time= 9226 ms 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-04-30 11:27:35,911 Using the server's key log file. 2025-04-30 11:27:35,940 2025-04-30 11:27:35,954 Using the server's key log file. 2025-04-30 11:27:35,954 Using the server's key log file. 2025-04-30 11:27:35,983 2025-04-30 11:27:35,997 Using the server's key log file. 2025-04-30 11:27:37,069 Check of downloaded files succeeded. 2025-04-30 11:27:37,069 Using the server's key log file. 2025-04-30 11:27:38,117 Check of downloaded files succeeded. 2025-04-30 11:28:09,818 Server saw these paths used: {(('193.167.100.100', 443), ('193.167.0.100', 59022)), (('193.167.100.100', 443), ('193.167.0.100', 40695)), (('193.167.100.100', 443), ('193.167.0.100', 57607))}