2025-04-30 12:03:42,565 Generated random file: qzarsanhtm of size: 5120
2025-04-30 12:03:42,565 Generated random file: aqxhncspjp of size: 10240
2025-04-30 12:03:42,565 Requests: https://server4:443/qzarsanhtm https://server4:443/aqxhncspjp
2025-04-30 12:03:42,623 
2025-04-30 12:03:42,623 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_v21m9ca5/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_dl2g8dca/ DOWNLOADS=/tmp/download_8tc_i0hk/ SERVER_LOGS=/tmp/logs_server_s8n9rgas CLIENT_LOGS=/tmp/logs_client_pf7195uk SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=privateoctopus/picoquic:latest SERVER=ghcr.io/facebook/proxygen/mvfst-interop:latest REQUESTS="https://server4:443/qzarsanhtm https://server4:443/aqxhncspjp"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:03:54,727  Container sim  Created
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up the simulation with setup.sh
server  | + set -o nounset
server  | + VERSION=1
server  | + HQ_CLI=/proxygen/_build/proxygen/bin/hq
server  | + PORT=443
server  | + LOGLEVEL=2
server  | + /setup.sh
client  | Setting up routes...
server  | Setting up routes...
client  | Actual changes:
server  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
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  | 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
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
client  | Setup.sh completed
client  | Waiting for  the simulator to start
server  | + PROTOCOL=hq-interop
server  | + HTTPVERSION=0.9
server  | + CONN_FLOW_CONTROL=107374182
server  | + STREAM_FLOW_CONTROL=107374182
server  | ./run_endpoint.sh: line 28: REQUESTS: unbound variable
server  | ++ tr ' ' '\n'
server  | ++ paste -sd,
server  | ++ awk -F / '{ print "/" $4 }'
server  | + INVOCATIONS=
server  | + EARLYDATA=false
server  | + PSK_FILE=
server  | + '[' -n resumption ']'
server  | + case "${TESTCASE}" in
server  | + INVOCATIONS=
server  | + PSK_FILE=/psk
server  | + '[' server == client ']'
server  | + '[' server == server ']'
server  | + echo 'Running QUIC server on [::]:443'
server  | Running QUIC server on [::]:443
server  | + /proxygen/_build/proxygen/bin/hq --mode=server --cert=/certs/cert.pem --key=/certs/priv.key --conn_flow_control=107374182 --stream_flow_control=107374182 --port=443 --httpversion=0.9 --h2port=443 --static_root=/www --logdir=/logs --qlogger_path=/logs --host=:: --congestion=bbr --pacing=true --v=2
server  | + tee /logs/server.log
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | W20250430 12:03:43.184818    26 QuicServerWorker.cpp:1234] HostId is already set to 0
server  | W20250430 12:03:43.184892    26 QuicServerWorker.cpp:1234] HostId is already set to 0
server  | W20250430 12:03:43.184898    26 QuicServerWorker.cpp:1234] HostId is already set to 0
server  | W20250430 12:03:43.184904    26 QuicServerWorker.cpp:1234] HostId is already set to 0
server  | I20250430 12:03:43.185269    26 HQServer.cpp:283] HQ server started at: [::]:443
sim     | server:443 is available after 1.04444082s
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
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
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | Starting picoquic client for test: resumption
client  | Starting picoquic client ...
client  | Parsing server name from first request: https://server4:443/qzarsanhtm
client  | Server set to: server4
client  | Requests:  https://server4:443/qzarsanhtm https://server4:443/aqxhncspjp
client  | parsing <https://server4:443/qzarsanhtm> as <qzarsanhtm>
client  | parsing <https://server4:443/aqxhncspjp> as <aqxhncspjp>
client  | File1: -:/qzarsanhtm
client  | File2: -:/aqxhncspjp;
client  | rm: cannot remove '*.bin': No such file or directory
client  | /picoquic/picoquicdemo  -8 -L -l /logs/test_log.txt -q /logs/qlog -o /downloads -0 -d 180000 -a hq-interop -v 00000001 server4 443 -:/qzarsanhtm
client  | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443
client  | No token file present. Will create one as <demo_token_store.bin>.
client  | Testing scenario: <-:/qzarsanhtm>
client  | Max stream id bidir remote before start = 0 (0)
client  | Starting client connection. Version = 1, I-CID: ae1df63f71cff28c
client  | Max stream id bidir remote after start = -4 (0)
client  | Waiting for packets.
client  | Client port (AF=2): 65432.
client  | Negotiated ALPN: hq-interop
client  | Almost ready!
client  | 
client  | Connection established. Version = 1, I-CID: ae1df63f71cff28c, verified: 1
client  | Opening stream 0 to GET /qzarsanhtm
client  | Stream 0 ended after 5120 bytes
client  | All done, Closing the connection.
server  | I20250430 12:03:44.316092    34 H1QDownstreamSession.h:84] onConnectionEnd
client  | Received a request to close the connection.
client  | The connection is closed!
client  | Address Discovery mode: 0 / 0 (0:0)
client  | Quic Bit was NOT greased by the client.
client  | Quic Bit was NOT greased by the server.
client  | ECN was not received.
client  | ECN was not acknowledged.
client  | Received 5120 bytes in 0.140073 seconds, 0.292419 Mbps.
client  | Sent 17 bytes in 0.140073 seconds, 0.000971 Mbps.
client  | max_data_local: 1048576
client  | max_stream_data_local: 2097152
client  | max_data_remote: 107374182
client  | max_stream_data_remote: 107374182
client  | ack_delay_remote: 1000 ... 3856
client  | max_ack_gap_remote: 2
client  | ack_delay_local: 25000 ... 25000
client  | max_ack_gap_local: 2
client  | max_mtu_sent: 1252
client  | max_mtu_received: 1232
client  | System call duration max: 20
client  | System call duration smoothed: 1
client  | System call duration deviation: 2
client  | Received ticket from server4 (hq-interop):
client  | ticket time = 1746014624296, kx = 1d, suite = 1301, 246 ticket, 32 secret.
client  | lifetime = 3600, age_add = aa65ef66, 0 nonce, 225 ticket, 8 extensions.
client  | ticket extensions: 42(ED: ffffffff),
client  | Client exit with code = 0
client  | /picoquic/picoquicdemo  -8 -L -l /logs/test_log.txt -q /logs/qlog -o /downloads -0 -d 180000 -a hq-interop -v 00000001 server4 443 -:/aqxhncspjp;
client  | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443
client  | Testing scenario: <-:/aqxhncspjp;>
client  | Max stream id bidir remote before start = 0 (0)
client  | Starting client connection. Version = 1, I-CID: e234f5c314f5924a
client  | Max stream id bidir remote after start = 396 (100)
client  | Max stream id bidir remote after 0rtt = 396 (100)
client  | Opening stream 0 to GET /aqxhncspjp
client  | Waiting for packets.
client  | Client port (AF=2): 63153.
client  | The session was properly resumed!
client  | Zero RTT data is accepted!
client  | Negotiated ALPN: hq-interop
client  | Almost ready!
client  | 
client  | Connection established. Version = 1, I-CID: e234f5c314f5924a, verified: 1
client  | Stream 0 ended after 10240 bytes
client  | All done, Closing the connection.
server  | I20250430 12:03:44.394757    35 H1QDownstreamSession.h:84] onConnectionEnd
client  | Received a request to close the connection.
client  | The connection is closed!
client  | Out of 1 zero RTT packets, 1 were acked by the server.
client  | Address Discovery mode: 0 / 0 (0:0)
client  | Quic Bit was NOT greased by the client.
client  | Quic Bit was NOT greased by the server.
client  | ECN was not received.
client  | ECN was not acknowledged.
client  | Received 10240 bytes in 0.074660 seconds, 1.097241 Mbps.
client  | Sent 17 bytes in 0.074660 seconds, 0.001822 Mbps.
client  | max_data_local: 1048576
client  | max_stream_data_local: 2097152
client  | max_data_remote: 107374182
client  | max_stream_data_remote: 107374182
client  | ack_delay_remote: 1000 ... 1000
client  | max_ack_gap_remote: 2
client  | ack_delay_local: 25000 ... 25000
client  | max_ack_gap_local: 2
client  | max_mtu_sent: 1252
client  | max_mtu_received: 1232
client  | System call duration max: 7
client  | System call duration smoothed: 0
client  | System call duration deviation: 0
client  | Received ticket from server4 (hq-interop):
client  | ticket time = 1746014624296, kx = 1d, suite = 1301, 246 ticket, 32 secret.
client  | lifetime = 3600, age_add = aa65ef66, 0 nonce, 225 ticket, 8 extensions.
client  | ticket extensions: 42(ED: ffffffff),
client  | Client exit with code = 0

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 12:03:54,815 Using the client's key log file.
2025-04-30 12:03:54,823 
2025-04-30 12:03:54,823 Using the client's key log file.
2025-04-30 12:03:54,824 Using the client's key log file.
2025-04-30 12:03:54,831 
2025-04-30 12:03:54,831 Using the client's key log file.
2025-04-30 12:03:54,831 Using the client's key log file.
2025-04-30 12:03:55,676 Check of downloaded files succeeded.