2025-12-13 11:41:55,896 Generated random file: bored-salty-uxie of size: 1024
2025-12-13 11:41:55,896 Requests: https://server4:443/bored-salty-uxie
2025-12-13 11:41:55,953 
2025-12-13 11:41:55,953 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_2dtazi5j/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_9yamoa_n/ DOWNLOADS=/tmp/download_kae5sbn5/ SERVER_LOGS=/tmp/logs_server_iq9ypst3 CLIENT_LOGS=/tmp/logs_client_92l7bmni SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/facebook/proxygen/mvfst-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/bored-salty-uxie"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-12-13 11:42:08,107  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
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  | + set -o nounset
client  | + VERSION=1
client  | + HQ_CLI=/proxygen/_build/proxygen/bin/hq
client  | + PORT=443
client  | + LOGLEVEL=2
client  | + /setup.sh
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  | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
server  | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
server  | + '[' -n handshake ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
client  | Endpoint's IPv4 address is 193.167.0.100
server  | + P12CERT=/tmp/tmp.85DZShNl1x
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
client  | + PROTOCOL=hq-interop
client  | + HTTPVERSION=0.9
client  | + CONN_FLOW_CONTROL=107374182
client  | + STREAM_FLOW_CONTROL=107374182
client  | ++ echo https://server4:443/bored-salty-uxie
client  | ++ tr ' ' '\n'
client  | ++ awk -F / '{ print "/" $4 }'
client  | ++ paste -sd,
client  | + INVOCATIONS=/bored-salty-uxie
client  | + EARLYDATA=false
client  | + PSK_FILE=
client  | + '[' -n handshake ']'
client  | + case "${TESTCASE}" in
client  | + '[' client == client ']'
client  | + /wait-for-it.sh sim:57832 -s -t 10
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.85DZShNl1x
server  | Warning: output encryption option -nodes ignored with -export
client  | wait-for-it.sh: waiting 10 seconds for sim:57832
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.85DZShNl1x -W ''
server  | pk12util: PKCS12 IMPORT SUCCESSFUL
server  | + certutil -L -d sql:/neqo/db -n cert
server  | Certificate:
server  |     Data:
server  |         Version: 3 (0x2)
server  |         Serial Number:
server  |             73:2e:17:56:3f:06:15:10:aa:5a:ed:0e:ae:92:43:6f:
server  |             a6:b0:6f:d1
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner Root Certificate Authority"
server  |         Validity:
server  |             Not Before: Sat Dec 13 11:41:55 2025
server  |             Not After : Tue Dec 23 11:41:55 2025
server  |         Subject: "O=interop runner leaf"
server  |         Subject Public Key Info:
server  |             Public Key Algorithm: X9.62 elliptic curve public key
server  |                 Args:
server  |                     06:08:2a:86:48:ce:3d:03:01:07
server  |             EC Public Key:
server  |                 PublicValue:
server  |                     04:e3:c6:50:e1:d0:70:69:8e:7c:96:11:7f:8e:f7:3a:
server  |                     d7:62:22:42:90:a3:5b:8e:b1:ce:8b:a8:f6:a2:f5:5b:
server  |                     4e:26:43:67:57:c4:62:ab:59:0c:f6:1b:d8:2a:24:53:
server  |                     27:25:6d:c7:c3:34:6b:f1:57:ad:9b:51:b7:d0:78:bb:
server  |                     7f
server  |                 Curve: ANSI X9.62 elliptic curve prime256v1 (aka secp256r1, NIST P-256)
server  |         Signed Extensions:
server  |             Name: Certificate Subject Alt Name
server  |             DNS name: "server"
server  |             DNS name: "server4"
server  |             DNS name: "server6"
server  |             DNS name: "server46"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 d1:d5:83:30:04:b4:f3:9c:c8:54:57:27:cd:33:b3:42:
server  |                 90:e5:42:95
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 61:bd:c9:cd:a4:35:b1:9a:c0:97:d9:a1:f4:58:38:d3:
server  |                 cf:b6:4a:41
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:9c:e0:aa:80:d7:e9:13:a7:10:29:69:
server  |         1f:71:09:5b:4b:be:b9:58:7e:81:0a:61:97:a2:b9:93:
server  |         b1:45:56:b2:6c:02:21:00:b7:f7:f6:ed:44:b8:bd:c9:
server  |         e2:f6:ee:8a:96:56:4e:2c:c0:e2:32:27:52:3f:d3:c7:
server  |         80:d7:d1:5c:74:38:77:e0
server  |     Fingerprint (SHA-256):
server  |         CE:ED:8A:ED:11:90:D6:ED:FC:F9:22:51:DD:21:6E:D2:0E:49:3F:A3:55:D2:70:63:FA:29:49:7A:BD:8C:86:EE
server  |     Fingerprint (SHA1):
server  |         D8:EA:EE:26:2B:6C:10:6D:FC:5B:BD:29:40:29:09:2B:E4:A7:C5:F8
server  | 
server  |     Mozilla-CA-Policy: false (attribute missing)
server  |     Certificate Trust Flags:
server  |         SSL Flags:
server  |             User
server  |         Email Flags:
server  |             User
server  |         Object Signing Flags:
server  |             User
server  | 
server  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT")
server  | + '[' handshake = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443'
server  | ++ tee -i -a /logs/server.log
server  | 0.000 DEBUG Logging initialized
server  | 0.003 DEBUG Default socket send buffer size is Ok(212992)
server  | 0.003 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.003 INFO Server waiting for connection on: Ok([::]:443)
server  | 0.285 DEBUG [Server] Unsupported version: 57414954
server  | 0.285 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:52527 Tos(Cs0, NotEct) len 15
sim     | server:443 is available after 501.467625ms
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | Capturing on 'eth1'
sim     | Capturing on 'eth0'
sim     | File: /logs/trace_node_right.pcap
sim     | File: /logs/trace_node_left.pcap
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/bored-salty-uxie ']'
client  | + REQS=("${REQUESTS}")
client  | + REQ=https://server4:443/bored-salty-uxie
client  | ++ echo https://server4:443/bored-salty-uxie
client  | ++ cut -d/ -f3
client  | ++ cut -d: -f1
client  | + SERVER=server4
client  | + for INVOCATION in ${INVOCATIONS}
client  | + echo 'requesting files '\''/bored-salty-uxie'\'''
client  | requesting files '/bored-salty-uxie'
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=/bored-salty-uxie --early_data=false --psk_file= --conn_flow_control=107374182 --stream_flow_control=107374182 --outdir=/downloads --logdir=/logs --qlogger_path=/logs --v=2
client  | + tee /logs/client.log
client  | I20251213 11:41:57.643504    43 HQClient.cpp:47] HQClient connecting to 193.167.100.100:443
server  | 1.012 DEBUG [Server] Handle initial
server  | 1.012 INFO AddressValidation: no token; accepting
server  | 1.012 INFO [Server] Accept connection CID [8]: 984f41073e52ff69
server  | 1.012 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.013 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.013 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: 984f41073e52ff69
server  | 1.013 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=984f41073e52ff69
server  | 1.013 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=11
server  | 1.013 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.013 DEBUG [Server 984f41073e52ff69] State change from Init -> WaitInitial
server  | 1.013 DEBUG [Server 984f41073e52ff69] pn=9263260 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, NotEct) len 1252
server  |   -> RX Crypto { offset: 0, len: 310 }
server  |   -> RX Padding { len: 900 }
server  | 1.013 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.014 DEBUG Read secret available for Handshake: SymKey [32]: 4bf983a4db9f7eecb85609e37def58a407831a4a9fa23af975560e654830fcb4
server  | 1.014 DEBUG Write secret available for Handshake: SymKey [32]: 73dd51666cbb53a58cf91ca09b429f9435ec684fd1e84deb129e14a504bd17c2
server  | 1.014 DEBUG Writing transport parameters, msg=8
server  | 1.014 DEBUG Read secret available for ApplicationData: SymKey [32]: a0e07a3a57c443750f89c492912b4a1c4dba05ee0ac768ddff5523fd27b8dda8
server  | 1.014 DEBUG Write secret available for ApplicationData: SymKey [32]: f17f9c6abe07a42cfda2a50afc99f835c190022760aaa7dbcd181acb6cd0eebf
server  | 1.014 DEBUG [Agent 0x55e99c54fee0] state -> InProgress
server  | 1.014 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.014 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.014 DEBUG [Crypto] Handshake keys installed
server  | 1.014 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.014 DEBUG [Crypto] Application write key installed
server  | 1.014 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 210, tv_nsec: 665983824 }
server  | 1.014 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:37173] Make permanent
server  | 1.014 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:37173] set as primary path
server  | 1.014 DEBUG [Server 984f41073e52ff69] State change from WaitInitial -> Handshaking
server  | 1.014 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.014 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 3dc49ab27ddf77315944)
server  | 1.014 DEBUG CRYPTO for in offset=0, len=90
server  | 1.014 DEBUG [Server 984f41073e52ff69] pn=11 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, NotEct) len 139
server  |   TX -> Ack { largest_acknowledged: 9263260, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 1.014 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 3dc49ab27ddf77315944)
server  | 1.014 DEBUG CRYPTO for hs offset=0, len=722
server  | 1.014 DEBUG [Server 984f41073e52ff69] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, NotEct) len 901
server  |   TX -> Crypto { offset: 0, len: 722 }
server  | 1.014 DEBUG packet_sent this=0x55e99c5c0c50, pn=0, ps=762
server  | 1.014 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.014 DEBUG [Server 984f41073e52ff69] pad Initial from 901 to PLPMTU 1232
server  | 1.014 DEBUG packet_sent this=0x55e99c5c0c50, pn=11, ps=470
server  | 1.015 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.015 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 3dc49ab27ddf77315944)
server  | 1.015 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 3dc49ab27ddf77315944)
server  | 1.015 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.015 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.015 DEBUG [Server 984f41073e52ff69] delay duration 297.111592ms
server  | 1.015 DEBUG Setting timeout of 297.111592ms
server  | 1.015 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.015 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 3dc49ab27ddf77315944)
server  | 1.015 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 3dc49ab27ddf77315944)
server  | 1.015 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.015 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.015 DEBUG [Server 984f41073e52ff69] delay duration 297.080003ms
server  | 1.015 DEBUG Setting timeout of 297.080003ms
client  | I20251213 11:41:57.686096    43 CurlClient.cpp:187] Sending request for /bored-salty-uxie
server  | 1.047 DEBUG [Server 984f41073e52ff69] Drop packet number space in
server  | 1.047 DEBUG [recovery::Loss] Reset loss recovery state for Initial
server  | 1.047 INFO [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37173] discarding a packet without an RTT estimate; guessing RTT=35.48799ms
server  | 1.047 DEBUG [Server 984f41073e52ff69] pn=9263260 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, NotEct) len 84
server  |   -> RX Crypto { offset: 0, len: 36 }
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 129, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.047 DEBUG [Agent 0x55e99c54fee0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.047 DEBUG [Server 984f41073e52ff69] TLS connection complete
server  | 1.047 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:37173] Path validated Instant { tv_sec: 210, tv_nsec: 701471814 }
server  | 1.047 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.047 DEBUG [Crypto] application read keys installed
server  | 1.047 DEBUG [Server 984f41073e52ff69] State change from Handshaking -> Connected
server  | 1.047 DEBUG [Server 984f41073e52ff69] State change from Connected -> Confirmed
server  | 1.047 DEBUG PMTUD started, PLPMTU is now 1280
server  | 1.047 DEBUG PMTUD started with probe size 1380
server  | 1.047 INFO [Server 984f41073e52ff69] Connection established
server  | 1.047 DEBUG [Server 984f41073e52ff69] Rx ACK space=hs, ranges=[0..=0]
server  | 1.047 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=0
server  | 1.047 DEBUG on_packets_acked this=0x55e99c5c0c50, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=762
server  | 1.047 DEBUG Acked crypto frame space=hs offset=0 length=722
server  | 1.047 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 210, tv_nsec: 701471814 }
server  | 1.047 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.047 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 3dc49ab27ddf77315944)
server  | 1.047 DEBUG [Server 984f41073e52ff69] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 44
server  |   TX -> Ack { largest_acknowledged: 9263260, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.048 DEBUG [Server 984f41073e52ff69] Drop packet number space hs
server  | 1.048 DEBUG [recovery::Loss] Reset loss recovery state for Handshake
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG [Server 984f41073e52ff69] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [59, 182, 155, 129, 163, 101, 124, 119, 200, 123], stateless_reset_token: Token([146, 138, 241, 11, 173, 233, 164, 164, 251, 187, 28, 239, 176, 125, 79, 206]) }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [138, 244, 214, 15, 215, 70, 78, 240, 242, 21], stateless_reset_token: Token([150, 250, 85, 202, 195, 118, 236, 75, 155, 37, 209, 238, 1, 109, 184, 17]) }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [31, 17, 107, 67, 125, 106, 138, 121, 17, 29], stateless_reset_token: Token([15, 90, 225, 115, 60, 5, 119, 229, 78, 248, 67, 69, 101, 65, 117, 104]) }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [33, 228, 108, 48, 95, 61, 155, 208, 128, 242], stateless_reset_token: Token([87, 110, 101, 169, 107, 5, 107, 109, 25, 41, 82, 248, 29, 82, 147, 222]) }
server  |   TX -> Padding { len: 1149 }
server  | 1.048 DEBUG packet_sent this=0x55e99c5c0c50, pn=0, ps=1288
server  | 1.048 DEBUG ECN probing: sent 1 probes
server  | 1.048 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.048 DEBUG [Server 984f41073e52ff69] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.048 DEBUG packet_sent this=0x55e99c5c0c50, pn=1, ps=1332
server  | 1.048 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG ECN probing: sent 2 probes
server  | 1.048 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG [Server 984f41073e52ff69] delay duration 1.371239ms
server  | 1.048 DEBUG Setting timeout of 1.371239ms
server  | 1.048 DEBUG [Server 984f41073e52ff69] pn=9263260 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, NotEct) len 68
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 23, fin: true }
server  |   -> RX Padding { len: 11 }
server  | 1.048 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 210, tv_nsec: 702130660 }
server  | 1.048 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG [Server 984f41073e52ff69] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 26
server  |   TX -> Ack { largest_acknowledged: 9263260, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.048 DEBUG ECN probing: sent 3 probes
server  | 1.048 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG [Server 984f41073e52ff69] delay duration 1.284038ms
server  | 1.048 DEBUG Setting timeout of 1.284038ms
server  | 1.049 DEBUG [Server 984f41073e52ff69] send session ticket 40620008984f41073e52ff690104800075300404802000000504801000000604801000000704801000000801100901100b01140e01080f0a3dc49ab27ddf77315944110c000000017a2a8a8a000000016ab200c0000000ff02de1a0243e820048000ffff686921
server  | 1.049 DEBUG Path = 'bored-salty-uxie'
server  | 1.049 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.049 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.049 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.049 DEBUG [Server 984f41073e52ff69] delay duration 60.193µs
server  | 1.049 DEBUG Setting timeout of 60.193µs
server  | 1.050 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.050 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.050 DEBUG CRYPTO for ap offset=0, len=182
server  | 1.050 DEBUG [Server 984f41073e52ff69] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  |   TX -> Crypto { offset: 0, len: 182 }
server  | 1.050 DEBUG packet_sent this=0x55e99c5c0c50, pn=3, ps=1232
server  | 1.050 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.050 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.050 DEBUG CRYPTO for ap offset=182, len=123
server  | 1.050 DEBUG [Server 984f41073e52ff69] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 191
server  |   TX -> Crypto { offset: 182, len: 123 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 112, 90, 152, 144, 74, 22, 187, 76, 40, 108, 169, 214, 40, 189, 113, 197, 81, 201, 227, 103, 175, 97, 50, 175, 200, 245, 171, 87, 135, 111, 148, 21, 71, 178, 228, 211] }
server  | 1.050 DEBUG packet_sent this=0x55e99c5c0c50, pn=4, ps=191
server  | 1.050 DEBUG ECN probing: sent 5 probes
server  | 1.050 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.050 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.050 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.050 DEBUG [Server 984f41073e52ff69] delay duration 921.355µs
server  | 1.050 DEBUG Setting timeout of 921.355µs
server  | 1.050 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.050 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.050 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.050 DEBUG [Server 984f41073e52ff69] delay duration 897.851µs
server  | 1.050 DEBUG Setting timeout of 897.851µs
server  | 1.051 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.051 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.051 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.051 DEBUG [Server 984f41073e52ff69] delay duration 112.506596ms
server  | 1.051 DEBUG Setting timeout of 112.506596ms
server  | 1.051 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.051 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.051 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.051 DEBUG [Server 984f41073e52ff69] delay duration 112.48174ms
server  | 1.051 DEBUG Setting timeout of 112.48174ms
client  | I20251213 11:41:57.720865    43 CurlClient.cpp:276] Got EOM for /bored-salty-uxie. Txn Time= 34 ms
server  | 1.082 DEBUG [Server 984f41073e52ff69] pn=9263261 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 1, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 3 }
server  | 1.082 DEBUG [Server 984f41073e52ff69] Rx ACK space=ap, ranges=[0..=3]
server  | 1.082 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=3
server  | 1.082 DEBUG on_packets_acked this=0x55e99c5c0c50, limited=1, bytes_in_flight=191, cwnd=12320, state=SlowStart, new_acked=3852
server  | 1.082 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.082 DEBUG PMTUD started with probe size 1420
server  | 1.082 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.082 DEBUG Acked crypto frame space=ap offset=0 length=182
server  | 1.082 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.082 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.082 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.082 DEBUG [Server 984f41073e52ff69] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  |   TX -> Padding { len: 1351 }
server  | 1.082 DEBUG packet_sent this=0x55e99c5c0c50, pn=5, ps=1372
server  | 1.082 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.082 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.082 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.082 DEBUG ECN probing: sent 6 probes
server  | 1.082 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.082 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.082 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.082 DEBUG [Server 984f41073e52ff69] delay duration 103.646933ms
server  | 1.082 DEBUG Setting timeout of 103.646933ms
server  | 1.082 DEBUG [Server 984f41073e52ff69] pn=9263262 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, NotEct) len 39
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "No Error" }
server  | 1.082 INFO [Server 984f41073e52ff69] ConnectionClose received. Error code: Application(0) frame type 0 reason No Error
server  | 1.082 DEBUG [Server 984f41073e52ff69] State change from Confirmed -> Draining { error: Transport(PeerApplication(0)), timeout: Instant { tv_sec: 211, tv_nsec: 47859283 } }
server  | 1.082 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:37173] Path validated Instant { tv_sec: 210, tv_nsec: 736323848 }
server  | 1.082 DEBUG [Server 984f41073e52ff69] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.082 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.082 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 210, tv_nsec: 736323848 }
server  | 1.082 DEBUG [Server 984f41073e52ff69] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:37173 Tos(Cs0, Ect0) len 30
server  |   TX -> Ack { largest_acknowledged: 9263262, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 1.082 DEBUG ECN probing: sent 7 probes
server  | 1.082 DEBUG Setting timeout of 311.462358ms
server  | 1.082 DEBUG Setting timeout of 311.455786ms

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-12-13 11:42:08,187 Using the server's key log file.
2025-12-13 11:42:08,195 
2025-12-13 11:42:08,196 Using the server's key log file.
2025-12-13 11:42:08,196 Using the server's key log file.
2025-12-13 11:42:08,203 
2025-12-13 11:42:08,204 Using the server's key log file.
2025-12-13 11:42:08,204 Using selector: EpollSelector
2025-12-13 11:42:08,414 Check of downloaded files succeeded.