2025-12-13 12:53:58,398 Generated random file: toasty-abundant-president of size: 5120
2025-12-13 12:53:58,398 Requests: https://server4:443/toasty-abundant-president
2025-12-13 12:53:58,774 
2025-12-13 12:53:58,774 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_5xlhqdi9/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_n_x1pusq/ DOWNLOADS=/tmp/download_kuwi7foq/ SERVER_LOGS=/tmp/logs_server_pwiilvo1 CLIENT_LOGS=/tmp/logs_client_f8jq71fs SCENARIO="droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/nginx/nginx-quic-qns:latest REQUESTS="https://server4:443/toasty-abundant-president"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-12-13 12:54:44,442  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 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
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  | >>> Starting nginx server...
client  | Endpoint's IPv4 address is 193.167.0.100
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  | Endpoint's IPv6 address is fd00:cafe:cafe::100
client  | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
client  | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
client  | + '[' -n transfer ']'
client  | + '[' -n /logs/qlog/ ']'
client  | + case "$ROLE" in
client  | + /wait-for-it.sh sim:57832 -s -t 30
server  | 2025/12/13 12:53:59 [notice] 21#0: using the "epoll" event method
server  | 2025/12/13 12:53:59 [notice] 21#0: nginx/1.29.4 (6ed1188411882086e3518eda779ab782d8ab4d3f)
server  | 2025/12/13 12:53:59 [notice] 21#0: built by gcc 13.3.0 (Ubuntu 13.3.0-6ubuntu2~24.04) 
server  | 2025/12/13 12:53:59 [notice] 21#0: OS: Linux 6.11.0-1018-azure
server  | 2025/12/13 12:53:59 [notice] 21#0: getrlimit(RLIMIT_NOFILE): 65536:65536
server  | 2025/12/13 12:53:59 [notice] 21#0: start worker processes
server  | 2025/12/13 12:53:59 [notice] 21#0: start worker process 22
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
sim     | server:443 is available after 500.862823ms
sim     | Using scenario: droplist --delay=15ms --bandwidth=10Mbps --queue=25 --drops_to_server=2,3,4,5,6,7
sim     | Capturing on 'eth0'
sim     | Capturing on 'eth1'
sim     | File: /logs/trace_node_left.pcap
sim     | File: /logs/trace_node_right.pcap
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads)
client  | + '[' https://server4:443/toasty-abundant-president ']'
client  | + mapfile -d ' ' -t URLS
client  | + OPTIONS+=("${URLS[@]}")
client  | + RUST_LOG=debug
client  | + RUST_BACKTRACE=1
client  | + neqo-client --cc cubic --qns-test transfer --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/toasty-abundant-president
client  | '
client  | ++ tee -i -a /logs/client.log
client  | 0.000 DEBUG Logging initialized
client  | 0.001 DEBUG Default socket send buffer size is Ok(212992)
client  | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing
client  | 0.001 INFO hq-interop Client connecting: 0.0.0.0:47869 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=95c5f40582a3a96c
client  | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=6
client  | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.001 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500
client  | 0.001 DEBUG [unv-path 0.0.0.0:47869->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:95c5f40582a3a96c 0.0.0.0:47869->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:95c5f40582a3a96c 0.0.0.0:47869->193.167.100.100:443] Path validated Instant { tv_sec: 405, tv_nsec: 88842299 }
client  | 0.001 DEBUG [Client 95c5f40582a3a96c] client_start
client  | 0.002 DEBUG Writing transport parameters, msg=1
client  | 0.002 DEBUG [Agent 0x55f99a807ca0] state -> InProgress
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] State change from Init -> WaitInitial
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 95c5f40582a3a96c) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] pn=6 type=Initial pri-path:95c5f40582a3a96c 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.002 DEBUG packet_sent this=0x55f99a81acf0, pn=6, ps=1252
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 95c5f40582a3a96c) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1213, len=325
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] pn=7 type=Initial pri-path:95c5f40582a3a96c 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 365
client  |   TX -> Crypto { offset: 1213, len: 325 }
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] pad Initial from 365 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55f99a81acf0, pn=7, ps=1252
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 95c5f40582a3a96c) scid Some(CID [0]: )
client  | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 95c5f40582a3a96c) scid Some(CID [0]: )
client  | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] delay duration 3.251405ms
client  | 0.002 DEBUG Setting timeout of 3.251405ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 95c5f40582a3a96c) scid Some(CID [0]: )
client  | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG [Client 95c5f40582a3a96c] delay duration 3.218333ms
client  | 0.002 DEBUG Setting timeout of 3.218333ms
client  | 0.006 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.006 DEBUG Building Initial dcid Some(CID [8]: 95c5f40582a3a96c) scid Some(CID [0]: )
client  | 0.006 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.006 DEBUG [Client 95c5f40582a3a96c] delay duration 294.682935ms
client  | 0.006 DEBUG Setting timeout of 294.682935ms
sim     | 
Packets: 3 
Packets: 4 Dropping packet 2 (1252 bytes) from 193.167.0.100
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] pn=0 type=Initial pri-path:95c5f40582a3a96c 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 51
client  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] Rx ACK space=in, ranges=[6..=6]
client  | 0.039 DEBUG [recovery::Loss] ACK for Initial - largest_acked=6
client  | 0.039 DEBUG on_packets_acked this=0x55f99a81acf0, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.039 DEBUG Acked crypto frame space=in offset=0 length=1213
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] Changing to use Server CID=0000000000001003709947e94f013c204d24ac92
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] State change from WaitInitial -> WaitVersion
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] delay duration 75.657952ms
client  | 0.039 DEBUG Setting timeout of 75.657952ms
client  | 0.039 WARN Unhandled event StateChange(WaitVersion)
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG [Client 95c5f40582a3a96c] delay duration 75.627445ms
client  | 0.039 DEBUG Setting timeout of 75.627445ms
client  | 0.116 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 0.116 DEBUG [Client 95c5f40582a3a96c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 })
client  | 0.116 INFO Lost crypto frame space=in offset=1213 length=325
client  | 0.116 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 0.116 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.116 DEBUG CRYPTO for in offset=1213, len=325
client  | 0.116 DEBUG [Client 95c5f40582a3a96c] pn=8 type=Initial pri-path:0000000000001003709947e94f013c204d24ac92 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 377
client  |   TX -> Crypto { offset: 1213, len: 325 }
client  | 0.116 DEBUG [Client 95c5f40582a3a96c] pad Initial from 377 to PLPMTU 1252
client  | 0.116 DEBUG packet_sent this=0x55f99a81acf0, pn=8, ps=1252
client  | 0.116 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false }
client  | 0.116 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG [Client 95c5f40582a3a96c] delay duration 227.089903ms
client  | 0.116 DEBUG Setting timeout of 227.089903ms
sim     | 
Packets: 8 
Packets: 6 Dropping packet 3 (1252 bytes) from 193.167.0.100
client  | 0.345 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 0.345 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 0.345 DEBUG [Client 95c5f40582a3a96c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 })
client  | 0.345 INFO Lost crypto frame space=in offset=1213 length=325
client  | 0.345 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
client  | 0.345 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.345 DEBUG CRYPTO for in offset=1213, len=325
client  | 0.345 DEBUG [Client 95c5f40582a3a96c] pn=9 type=Initial pri-path:0000000000001003709947e94f013c204d24ac92 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 377
client  |   TX -> Crypto { offset: 1213, len: 325 }
client  | 0.345 DEBUG [Client 95c5f40582a3a96c] pad Initial from 377 to PLPMTU 1252
client  | 0.345 DEBUG packet_sent this=0x55f99a81acf0, pn=9, ps=1252
client  | 0.345 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 0.345 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.345 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 0.345 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.345 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.345 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.345 DEBUG [Client 95c5f40582a3a96c] delay duration 225.782591ms
client  | 0.345 DEBUG Setting timeout of 225.782591ms
sim     | 
Packets: 19 
Packets: 33 Dropping packet 4 (1252 bytes) from 193.167.0.100
client  | 0.572 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 0.572 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
client  | 0.572 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.572 DEBUG [Client 95c5f40582a3a96c] pn=10 type=Initial pri-path:0000000000001003709947e94f013c204d24ac92 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 48
client  |   TX -> Ping
client  | 0.572 DEBUG [Client 95c5f40582a3a96c] pad Initial from 50 to PLPMTU 1252
client  | 0.572 DEBUG packet_sent this=0x55f99a81acf0, pn=10, ps=1252
client  | 0.572 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 0.572 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.572 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 0.572 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.572 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 0.572 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.572 DEBUG [Client 95c5f40582a3a96c] delay duration 452.724719ms
client  | 0.572 DEBUG Setting timeout of 452.724719ms
sim     | 
Packets: 20 
Packets: 36 Dropping packet 5 (1252 bytes) from 193.167.0.100
client  | 1.026 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 1.026 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
client  | 1.026 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 1.026 DEBUG [Client 95c5f40582a3a96c] pn=11 type=Initial pri-path:0000000000001003709947e94f013c204d24ac92 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 48
client  |   TX -> Ping
client  | 1.026 DEBUG [Client 95c5f40582a3a96c] pad Initial from 50 to PLPMTU 1252
client  | 1.026 DEBUG packet_sent this=0x55f99a81acf0, pn=11, ps=1252
client  | 1.026 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 1.026 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 1.026 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.026 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.026 DEBUG [Client 95c5f40582a3a96c] delay duration 907.796371ms
client  | 1.026 DEBUG Setting timeout of 907.796371ms
sim     | 
Packets: 21 
Packets: 41 Dropping packet 6 (1252 bytes) from 193.167.0.100
client  | 1.935 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 1.935 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
client  | 1.935 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 1.935 DEBUG [Client 95c5f40582a3a96c] pn=12 type=Initial pri-path:0000000000001003709947e94f013c204d24ac92 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 48
client  |   TX -> Ping
client  | 1.935 DEBUG [Client 95c5f40582a3a96c] pad Initial from 50 to PLPMTU 1252
client  | 1.935 DEBUG packet_sent this=0x55f99a81acf0, pn=12, ps=1252
client  | 1.935 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 1.935 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 1.935 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 1.935 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.935 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 1.935 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.935 DEBUG [Client 95c5f40582a3a96c] delay duration 1.816185863s
client  | 1.935 DEBUG Setting timeout of 1.816185863s
sim     | 
Packets: 24 
Packets: 44 
Packets: 27 
Packets: 49 Dropping packet 7 (1252 bytes) from 193.167.0.100
client  | 3.753 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 3.753 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.753 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.753 DEBUG [Client 95c5f40582a3a96c] pn=13 type=Initial pri-path:0000000000001003709947e94f013c204d24ac92 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 48
client  |   TX -> Ping
client  | 3.753 DEBUG [Client 95c5f40582a3a96c] pad Initial from 50 to PLPMTU 1252
client  | 3.753 DEBUG packet_sent this=0x55f99a81acf0, pn=13, ps=1252
client  | 3.753 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 3.753 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.753 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Handshake), paced: false }
client  | 3.753 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.753 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.753 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.753 DEBUG [Client 95c5f40582a3a96c] delay duration 3.633232815s
client  | 3.754 DEBUG Setting timeout of 3.633232815s
server  | 2025/12/13 12:54:04 [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  | 3.785 DEBUG [Client 95c5f40582a3a96c] pn=1 type=Initial pri-path:0000000000001003709947e94f013c204d24ac92 0.0.0.0:47869->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   -> RX Ack { largest_acknowledged: 13, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 5, range: 0 }], ecn_count: None }
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] Rx ACK space=in, ranges=[13..=13, 6..=6]
client  | 3.785 DEBUG [recovery::Loss] ACK for Initial - largest_acked=13
client  | 3.785 DEBUG packet_lost this=0x55f99a81acf0, pn=7, ps=1252
client  | 3.785 DEBUG packet_lost this=0x55f99a81acf0, pn=8, ps=1252
client  | 3.785 DEBUG packet_lost this=0x55f99a81acf0, pn=9, ps=1252
client  | 3.785 DEBUG packet_lost this=0x55f99a81acf0, pn=10, ps=1252
client  | 3.785 DEBUG packet_lost this=0x55f99a81acf0, pn=11, ps=1252
client  | 3.785 DEBUG packet_lost this=0x55f99a81acf0, pn=12, ps=1252
client  | 3.785 INFO [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 1252/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764
client  | 3.785 DEBUG [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 1252/8764 ssthresh 8764] state -> RecoveryStart
client  | 3.785 INFO [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 1252/8764 ssthresh 8764] persistent congestion
client  | 3.785 DEBUG [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 1252/2504 ssthresh 8764] state -> PersistentCongestion
client  | 3.785 DEBUG on_packets_lost this=0x55f99a81acf0, bytes_in_flight=1252, cwnd=2504, state=PersistentCongestion
client  | 3.785 DEBUG [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 0/2504 ssthresh 8764] slow start += 0
client  | 3.785 DEBUG [Cubic [w_max: 12520, k: 0, t_epoch: None] CongCtrl 0/2504 ssthresh 8764] on_packets_acked this=0x55f99a81acf0, limited=0, bytes_in_flight=0, cwnd=2504, state=PersistentCongestion, new_acked=0
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 })
client  | 3.785 INFO Lost crypto frame space=in offset=1213 length=325
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 })
client  | 3.785 INFO Lost crypto frame space=in offset=1213 length=325
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 325 })
client  | 3.785 INFO Lost crypto frame space=in offset=1213 length=325
client  | 3.785 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.785 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.785 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] delay duration 0ns
client  | 3.785 DEBUG Setting timeout of 0ns
client  | 3.785 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.785 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.785 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.785 DEBUG [Client 95c5f40582a3a96c] delay duration 0ns
client  | 3.785 DEBUG Setting timeout of 0ns
client  | 3.786 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 3.786 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.786 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.786 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.786 DEBUG [Client 95c5f40582a3a96c] delay duration 0ns
client  | 3.786 DEBUG Setting timeout of 0ns
client  | 3.787 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 3.787 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.787 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.787 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.787 DEBUG [Client 95c5f40582a3a96c] delay duration 0ns
client  | 3.787 DEBUG Setting timeout of 0ns
client  | 3.788 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 3.788 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.788 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.788 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.788 DEBUG [Client 95c5f40582a3a96c] delay duration 0ns
client  | 3.788 DEBUG Setting timeout of 0ns
client  | 3.790 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 3.790 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.790 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 3.790 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 3.790 DEBUG [Client 95c5f40582a3a96c] delay duration 2.733778648s
client  | 3.790 DEBUG Setting timeout of 2.733778648s
client  | 6.525 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 6.525 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 6.525 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 6.525 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 6.525 DEBUG [Client 95c5f40582a3a96c] delay duration 6.404840276s
client  | 6.525 DEBUG Setting timeout of 6.404840276s
client  | 12.932 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 12.932 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 12.932 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 12.932 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 12.932 DEBUG [Client 95c5f40582a3a96c] delay duration 12.812108271s
client  | 12.932 DEBUG Setting timeout of 12.812108271s
client  | 25.745 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 25.745 DEBUG [Client 95c5f40582a3a96c] output_dgram_on_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 25.745 DEBUG Building Initial dcid Some(CID [20]: 0000000000001003709947e94f013c204d24ac92) scid Some(CID [0]: )
client  | 25.745 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Initial | Handshake), paced: false }
client  | 25.745 DEBUG [Client 95c5f40582a3a96c] delay duration 8.039818242s
client  | 25.745 DEBUG Setting timeout of 8.039818242s
client  | 33.786 INFO [Client 95c5f40582a3a96c] idle timeout expired
client  | 33.786 DEBUG [Client 95c5f40582a3a96c] State change from WaitVersion -> Closed(Transport(IdleTimeout))
client  | 33.786 DEBUG Output::None
client  | 33.786 WARN Unhandled event StateChange(Closed(Transport(IdleTimeout)))
client  | 33.786 DEBUG Timer fired while closed
client  | 33.786 DEBUG Output::None
client  | Error: Transport(IdleTimeout)

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