2025-12-13 11:47:14,747 Generated random file: spicy-frigid-plane of size: 5120
2025-12-13 11:47:14,748 Generated random file: frosty-turquoise-warlock of size: 10240
2025-12-13 11:47:14,748 Requests: https://server6:443/spicy-frigid-plane https://server6:443/frosty-turquoise-warlock
2025-12-13 11:47:14,806 
2025-12-13 11:47:14,807 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_oicz4_vv/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_lib2nso_/ DOWNLOADS=/tmp/download_ufpdudem/ SERVER_LOGS=/tmp/logs_server_i43yxcy3 CLIENT_LOGS=/tmp/logs_client_rsz0x0oq 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://server6:443/spicy-frigid-plane https://server6:443/frosty-turquoise-warlock"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-12-13 11:47:26,797  Container sim  Recreate
 Container sim  Recreated
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  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 transfer ']'
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.3tVOdRxelv
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://server6:443/spicy-frigid-plane https://server6:443/frosty-turquoise-warlock'
client  | ++ tr ' ' '\n'
client  | ++ paste -sd,
client  | ++ awk -F / '{ print "/" $4 }'
client  | + INVOCATIONS=/spicy-frigid-plane,/frosty-turquoise-warlock
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  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.3tVOdRxelv
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.3tVOdRxelv -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  |             07:61:06:23:1c:2c:0a:9e:27:e1:60:0b:34:d5:a1:a7:
server  |             89:27:3d:6c
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:47:14 2025
server  |             Not After : Tue Dec 23 11:47:14 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:b7:ad:1b:e9:36:49:91:87:5a:a7:a6:32:41:b6:51:
server  |                     30:78:5d:64:bf:73:ed:6a:e5:e6:15:86:8b:9d:31:f0:
server  |                     ab:e5:b5:5c:e1:4e:09:8f:c8:46:60:ff:22:58:92:b1:
server  |                     7c:d3:68:d8:f2:bc:4b:fb:56:db:cb:fb:35:12:f1:9d:
server  |                     3b
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  |                 30:79:ec:2e:64:ca:f1:8c:22:e9:18:04:eb:ed:81:51:
server  |                 7e:96:68:cd
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 35:62:c1:b5:dc:07:7e:21:80:74:c6:e5:8f:e0:0e:b8:
server  |                 c5:39:2d:41
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:20:79:67:7c:96:b9:5d:ec:8f:f4:1f:70:7c:
server  |         6c:89:84:8b:a3:53:04:de:b8:1b:34:cb:7c:0c:b0:51:
server  |         26:b1:34:f7:02:21:00:93:04:cf:f9:58:33:05:3f:60:
server  |         38:e5:63:f9:64:fe:ef:85:39:a3:33:0b:e6:ac:6e:54:
server  |         f8:f2:c6:1c:03:7b:8f
server  |     Fingerprint (SHA-256):
server  |         3A:55:5C:73:39:C4:E3:30:64:52:47:66:D4:95:87:86:8F:97:22:FD:E7:C9:E8:3D:24:2A:18:FB:39:1D:1C:97
server  |     Fingerprint (SHA1):
server  |         C9:4E:EC:C9:D2:89:F7:11:48:9D:B2:02:0A:15:D8:CD:78:5A:5F:D5
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  | + '[' transfer = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test transfer --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.002 DEBUG Default socket send buffer size is Ok(212992)
server  | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.002 INFO Server waiting for connection on: Ok([::]:443)
server  | 0.292 DEBUG [Server] Unsupported version: 57414954
server  | 0.292 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:37302 Tos(Cs0, NotEct) len 15
sim     | server:443 is available after 501.117734ms
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://server6:443/spicy-frigid-plane https://server6:443/frosty-turquoise-warlock' ']'
client  | + REQS=("${REQUESTS}")
client  | + REQ='https://server6:443/spicy-frigid-plane https://server6:443/frosty-turquoise-warlock'
client  | ++ echo 'https://server6:443/spicy-frigid-plane https://server6:443/frosty-turquoise-warlock'
client  | ++ cut -d/ -f3
client  | ++ cut -d: -f1
client  | + SERVER=server6
client  | + for INVOCATION in ${INVOCATIONS}
client  | + echo 'requesting files '\''/spicy-frigid-plane,/frosty-turquoise-warlock'\'''
client  | requesting files '/spicy-frigid-plane,/frosty-turquoise-warlock'
client  | + /proxygen/_build/proxygen/bin/hq --mode=client --host=server6 --port=443 --protocol=hq-interop --httpversion=0.9 --use_version=true --quic-version=1 --path=/spicy-frigid-plane,/frosty-turquoise-warlock --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  | I20251213 11:47:16.281183    43 HQClient.cpp:47] HQClient connecting to [fd00:cafe:cafe:100::100]:443
server  | 1.013 DEBUG [Server] Handle initial
server  | 1.013 INFO AddressValidation: no token; accepting
server  | 1.013 INFO [Server] Accept connection CID [8]: 86e1b03c9b0df787
server  | 1.013 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.015 DEBUG Outbound interface eth0 for destination fd00:cafe:cafe::100 has MTU 1500
server  | 1.015 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: 86e1b03c9b0df787
server  | 1.015 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=86e1b03c9b0df787
server  | 1.015 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=23
server  | 1.015 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.015 DEBUG [Server 86e1b03c9b0df787] State change from Init -> WaitInitial
server  | 1.015 DEBUG [Server 86e1b03c9b0df787] pn=5288977 type=Initial unv-path [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 1232
server  |   -> RX Crypto { offset: 0, len: 310 }
server  |   -> RX Padding { len: 881 }
server  | 1.015 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.015 DEBUG Read secret available for Handshake: SymKey [32]: e7375773e1b2e71665a524326792b7ed23f77936278f27d6fb1e946e867e5c48
server  | 1.015 DEBUG Write secret available for Handshake: SymKey [32]: eef27e8719d3a06040d4a55d1b9c44e0ce5c5bffba87d7360e204a0f08468d8e
server  | 1.015 DEBUG Writing transport parameters, msg=8
server  | 1.016 DEBUG Read secret available for ApplicationData: SymKey [32]: a0a5633df25eb4af50a35639e6e00f0ea50d98c70b0c75b42e86da46c3302144
server  | 1.016 DEBUG Write secret available for ApplicationData: SymKey [32]: 1eb97fc1fe9c8ecbd08851ada2c4aea733c979ef047f89d4b4fdfb4fd1c25bde
server  | 1.016 DEBUG [Agent 0x55967af8eee0] state -> InProgress
server  | 1.016 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.016 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.016 DEBUG [Crypto] Handshake keys installed
server  | 1.016 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.016 DEBUG [Crypto] Application write key installed
server  | 1.016 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 529, tv_nsec: 305670270 }
server  | 1.016 DEBUG [unv-path [::]:443->[fd00:cafe:cafe::100]:50198] Make permanent
server  | 1.016 DEBUG [unv-path: [::]:443->[fd00:cafe:cafe::100]:50198] set as primary path
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] State change from WaitInitial -> Handshaking
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.016 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 2f50ac5252c2a9c587c3)
server  | 1.016 DEBUG CRYPTO for in offset=0, len=90
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] pn=23 type=Initial pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 139
server  |   TX -> Ack { largest_acknowledged: 5288977, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 1.016 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2f50ac5252c2a9c587c3)
server  | 1.016 DEBUG CRYPTO for hs offset=0, len=719
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] pn=0 type=Handshake pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 898
server  |   TX -> Crypto { offset: 0, len: 719 }
server  | 1.016 DEBUG packet_sent this=0x55967afffc50, pn=0, ps=759
server  | 1.016 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] pad Initial from 898 to PLPMTU 1232
server  | 1.016 DEBUG packet_sent this=0x55967afffc50, pn=23, ps=473
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.016 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 2f50ac5252c2a9c587c3)
server  | 1.016 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2f50ac5252c2a9c587c3)
server  | 1.016 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.016 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] delay duration 297.087533ms
server  | 1.016 DEBUG Setting timeout of 297.087533ms
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.016 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 2f50ac5252c2a9c587c3)
server  | 1.016 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2f50ac5252c2a9c587c3)
server  | 1.016 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.016 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.016 DEBUG [Server 86e1b03c9b0df787] delay duration 297.056846ms
server  | 1.016 DEBUG Setting timeout of 297.056846ms
client  | I20251213 11:47:16.325726    43 CurlClient.cpp:187] Sending request for /spicy-frigid-plane
client  | I20251213 11:47:16.325826    43 CurlClient.cpp:187] Sending request for /frosty-turquoise-warlock
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] Drop packet number space in
server  | 1.049 DEBUG [recovery::Loss] Reset loss recovery state for Initial
server  | 1.049 INFO [pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:50198] discarding a packet without an RTT estimate; guessing RTT=35.580008ms
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] pn=5288977 type=Handshake pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 83
server  |   -> RX Crypto { offset: 0, len: 36 }
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 135, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.049 DEBUG [Agent 0x55967af8eee0] 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.049 DEBUG [Server 86e1b03c9b0df787] TLS connection complete
server  | 1.049 DEBUG [pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:50198] Path validated Instant { tv_sec: 529, tv_nsec: 341250278 }
server  | 1.049 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.049 DEBUG [Crypto] application read keys installed
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] State change from Handshaking -> Connected
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] State change from Connected -> Confirmed
server  | 1.049 DEBUG PMTUD started, PLPMTU is now 1280
server  | 1.049 DEBUG PMTUD started with probe size 1380
server  | 1.049 INFO [Server 86e1b03c9b0df787] Connection established
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] Rx ACK space=hs, ranges=[0..=0]
server  | 1.049 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=0
server  | 1.049 DEBUG on_packets_acked this=0x55967afffc50, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=759
server  | 1.049 DEBUG Acked crypto frame space=hs offset=0 length=719
server  | 1.049 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 529, tv_nsec: 341250278 }
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.049 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2f50ac5252c2a9c587c3)
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] pn=1 type=Handshake pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 44
server  |   TX -> Ack { largest_acknowledged: 5288977, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] Drop packet number space hs
server  | 1.049 DEBUG [recovery::Loss] Reset loss recovery state for Handshake
server  | 1.049 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] pn=0 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [93, 198, 165, 4, 173, 227, 64, 163, 39, 253], stateless_reset_token: Token([216, 199, 193, 84, 107, 234, 89, 90, 37, 182, 101, 93, 129, 170, 19, 180]) }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [157, 142, 84, 171, 115, 140, 244, 232, 129, 225], stateless_reset_token: Token([240, 220, 2, 70, 97, 90, 152, 145, 119, 173, 241, 199, 6, 115, 70, 99]) }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [173, 14, 54, 207, 99, 234, 212, 81, 2, 145], stateless_reset_token: Token([132, 241, 236, 7, 94, 21, 120, 119, 81, 115, 254, 217, 229, 238, 226, 75]) }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [104, 148, 236, 154, 107, 172, 200, 138, 176, 135], stateless_reset_token: Token([106, 84, 109, 104, 243, 213, 155, 34, 65, 38, 39, 18, 103, 239, 181, 144]) }
server  |   TX -> Padding { len: 1149 }
server  | 1.049 DEBUG packet_sent this=0x55967afffc50, pn=0, ps=1288
server  | 1.049 DEBUG ECN probing: sent 1 probes
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.049 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.049 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] pn=1 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.049 DEBUG packet_sent this=0x55967afffc50, pn=1, ps=1332
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] 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 ECN probing: sent 2 probes
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] 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 86e1b03c9b0df787] delay duration 1.542952ms
server  | 1.049 DEBUG Setting timeout of 1.542952ms
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] pn=5288977 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 112
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 25, fin: true }
server  |   -> RX Stream { stream_id: 4, offset: 0, len: 31, fin: true }
server  |   -> RX Padding { len: 20 }
server  | 1.049 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 529, tv_nsec: 341733405 }
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] 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 [Server 86e1b03c9b0df787] pn=2 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 26
server  |   TX -> Ack { largest_acknowledged: 5288977, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.049 DEBUG ECN probing: sent 3 probes
server  | 1.049 DEBUG [Server 86e1b03c9b0df787] 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 86e1b03c9b0df787] delay duration 1.485915ms
server  | 1.049 DEBUG Setting timeout of 1.485915ms
server  | 1.050 DEBUG [Server 86e1b03c9b0df787] send session ticket 4062000886e1b03c9b0df7870104800075300404802000000504801000000604801000000704801000000801100901100b01140e01080f0a2f50ac5252c2a9c587c3110c00000001baaadada000000016ab200c0000000ff02de1a0243e820048000ffff686921
server  | 1.050 DEBUG Path = 'spicy-frigid-plane'
server  | 1.050 DEBUG Path = 'frosty-turquoise-warlock'
server  | 1.050 DEBUG [Server 86e1b03c9b0df787] 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 86e1b03c9b0df787] delay duration 379.568µs
server  | 1.050 DEBUG Setting timeout of 379.568µs
server  | 1.051 DEBUG [Server 86e1b03c9b0df787] 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 [Server 86e1b03c9b0df787] pn=3 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1212, fin: false }
server  | 1.051 DEBUG packet_sent this=0x55967afffc50, pn=3, ps=1232
server  | 1.051 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.051 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.051 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.051 DEBUG ECN probing: sent 4 probes
server  | 1.052 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.052 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.052 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.052 DEBUG [Server 86e1b03c9b0df787] delay duration 1.00337ms
server  | 1.052 DEBUG Setting timeout of 1.00337ms
server  | 1.052 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.052 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.052 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.052 DEBUG [Server 86e1b03c9b0df787] delay duration 982.991µs
server  | 1.052 DEBUG Setting timeout of 982.991µs
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] pn=4 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1212, len: >>1210, fin: false }
server  | 1.054 DEBUG packet_sent this=0x55967afffc50, pn=4, ps=1232
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] pn=5 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2422, len: >>1210, fin: false }
server  | 1.054 DEBUG packet_sent this=0x55967afffc50, pn=5, ps=1232
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG ECN probing: sent 6 probes
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] delay duration 2.360495ms
server  | 1.054 DEBUG Setting timeout of 2.360495ms
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG [Server 86e1b03c9b0df787] delay duration 2.338834ms
server  | 1.054 DEBUG Setting timeout of 2.338834ms
server  | 1.057 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG [Server 86e1b03c9b0df787] pn=6 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3632, len: >>1210, fin: false }
server  | 1.057 DEBUG packet_sent this=0x55967afffc50, pn=6, ps=1232
server  | 1.057 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.057 DEBUG ECN probing: sent 7 probes
server  | 1.057 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.057 DEBUG [Server 86e1b03c9b0df787] delay duration 975.71µs
server  | 1.057 DEBUG Setting timeout of 975.71µs
server  | 1.057 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.057 DEBUG [Server 86e1b03c9b0df787] delay duration 953.609µs
server  | 1.057 DEBUG Setting timeout of 953.609µs
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.059 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] pn=7 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 4842, len: 278, fin: true }
server  |   TX -> Stream { stream_id: 4, offset: 0, len: >>928, fin: false }
server  | 1.059 DEBUG packet_sent this=0x55967afffc50, pn=7, ps=1232
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.059 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] pn=8 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 4, offset: 928, len: >>1210, fin: false }
server  | 1.059 DEBUG packet_sent this=0x55967afffc50, pn=8, ps=1232
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.059 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.059 DEBUG ECN probing: sent 9 probes
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.059 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] delay duration 2.312461ms
server  | 1.059 DEBUG Setting timeout of 2.312461ms
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.059 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.059 DEBUG [Server 86e1b03c9b0df787] delay duration 2.287564ms
server  | 1.059 DEBUG Setting timeout of 2.287564ms
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] pn=9 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 4, offset: 2138, len: >>1210, fin: false }
server  | 1.063 DEBUG packet_sent this=0x55967afffc50, pn=9, ps=1232
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1076, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] pn=10 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, Ect0) len 1076
server  |   TX -> Stream { stream_id: 4, offset: 3348, len: >>1054, fin: false }
server  | 1.063 DEBUG packet_sent this=0x55967afffc50, pn=10, ps=1076
server  | 1.063 DEBUG ECN probing: sent 11 probes
server  | 1.063 DEBUG ECN probing concluded with 11 probes sent
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] delay duration 113.842909ms
server  | 1.063 DEBUG Setting timeout of 113.842909ms
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server 86e1b03c9b0df787] delay duration 113.797764ms
server  | 1.063 DEBUG Setting timeout of 113.797764ms
client  | I20251213 11:47:16.367851    43 CurlClient.cpp:276] Got EOM for /spicy-frigid-plane. Txn Time= 42 ms
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] pn=5288978 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 48
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 2, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 15 }
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] Rx ACK space=ap, ranges=[0..=9]
server  | 1.095 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=9
server  | 1.095 INFO ECN validation failed, no ECN counts in ACK frame
server  | 1.095 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 1076/23564 ssthresh 18446744073709551615] slow start += 11244
server  | 1.095 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 1076/23564 ssthresh 18446744073709551615] on_packets_acked this=0x55967afffc50, limited=0, bytes_in_flight=1076, cwnd=23564, state=SlowStart, new_acked=11244
server  | 1.095 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.095 DEBUG PMTUD started with probe size 1420
server  | 1.095 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.095 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.095 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] pn=11 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 1372
server  |   TX -> Ping
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  |   TX -> Stream { stream_id: 4, offset: 4402, len: >>1347, fin: false }
server  | 1.095 DEBUG packet_sent this=0x55967afffc50, pn=11, ps=1372
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.095 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] pn=12 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 5749, len: >>1310, fin: false }
server  | 1.095 DEBUG packet_sent this=0x55967afffc50, pn=12, ps=1332
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.095 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] pn=13 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 7059, len: >>1310, fin: false }
server  | 1.095 DEBUG packet_sent this=0x55967afffc50, pn=13, ps=1332
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.095 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] pn=14 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 8369, len: >>1310, fin: false }
server  | 1.095 DEBUG packet_sent this=0x55967afffc50, pn=14, ps=1332
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.095 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.095 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.095 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.095 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] delay duration 1.832029ms
server  | 1.095 DEBUG Setting timeout of 1.832029ms
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.095 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.095 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.095 DEBUG [Server 86e1b03c9b0df787] delay duration 1.806752ms
server  | 1.095 DEBUG Setting timeout of 1.806752ms
server  | 1.098 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.098 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.098 DEBUG CRYPTO for ap offset=0, len=305
server  | 1.098 DEBUG [Server 86e1b03c9b0df787] pn=15 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 939
server  |   TX -> Stream { stream_id: 4, offset: 9679, len: 561, fin: true }
server  |   TX -> Crypto { offset: 0, len: 305 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 10, 76, 35, 176, 67, 3, 219, 204, 73, 35, 114, 195, 24, 79, 120, 132, 217, 104, 144, 137, 204, 233, 73, 212, 11, 46, 85, 241, 154, 49, 117, 164, 170, 56, 78, 169] }
server  | 1.098 DEBUG packet_sent this=0x55967afffc50, pn=15, ps=939
server  | 1.098 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.098 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.098 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.098 DEBUG [Server 86e1b03c9b0df787] delay duration 104.0438ms
server  | 1.098 DEBUG Setting timeout of 104.0438ms
server  | 1.098 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.098 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.098 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.098 DEBUG [Server 86e1b03c9b0df787] delay duration 104.022129ms
server  | 1.098 DEBUG Setting timeout of 104.022129ms
client  | I20251213 11:47:16.408082    43 CurlClient.cpp:276] Got EOM for /frosty-turquoise-warlock. Txn Time= 82 ms
server  | 1.120 DEBUG [Server 86e1b03c9b0df787] pn=5288979 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 48
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 3020, first_ack_range: 10, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 14 }
server  | 1.120 DEBUG [Server 86e1b03c9b0df787] Rx ACK space=ap, ranges=[0..=10]
server  | 1.120 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=10
server  | 1.120 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 6307/24640 ssthresh 18446744073709551615] slow start += 1076
server  | 1.120 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 6307/24640 ssthresh 18446744073709551615] on_packets_acked this=0x55967afffc50, limited=0, bytes_in_flight=6307, cwnd=24640, state=SlowStart, new_acked=1076
server  | 1.120 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.120 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.120 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.120 DEBUG [Server 86e1b03c9b0df787] delay duration 73.708758ms
server  | 1.120 DEBUG Setting timeout of 73.708758ms
server  | 1.120 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.120 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.120 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.120 DEBUG [Server 86e1b03c9b0df787] delay duration 73.591848ms
server  | 1.120 DEBUG Setting timeout of 73.591848ms
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] pn=5288980 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 48
server  |   -> RX Ack { largest_acknowledged: 15, ack_delay: 7, first_ack_range: 15, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 15 }
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] Rx ACK space=ap, ranges=[0..=15]
server  | 1.131 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=15
server  | 1.131 DEBUG on_packets_acked this=0x55967afffc50, limited=1, bytes_in_flight=0, cwnd=24640, state=SlowStart, new_acked=6307
server  | 1.131 DEBUG PMTUD probe of size 1420 succeeded
server  | 1.131 DEBUG PMTUD started with probe size 1470
server  | 1.131 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 1.131 DEBUG Acked crypto frame space=ap offset=0 length=305
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.131 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.131 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] pn=16 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 1422
server  |   TX -> Ping
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 18 }
server  |   TX -> Padding { len: 1401 }
server  | 1.131 DEBUG packet_sent this=0x55967afffc50, pn=16, ps=1422
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.131 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.131 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.131 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.131 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] delay duration 88.258605ms
server  | 1.131 DEBUG Setting timeout of 88.258605ms
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] pn=5288981 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 39
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "No Error" }
server  | 1.131 INFO [Server 86e1b03c9b0df787] ConnectionClose received. Error code: Application(0) frame type 0 reason No Error
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] State change from Confirmed -> Draining { error: Transport(PeerApplication(0)), timeout: Instant { tv_sec: 529, tv_nsec: 689183741 } }
server  | 1.131 DEBUG [pri-path: [::]:443->[fd00:cafe:cafe::100]:50198] Path validated Instant { tv_sec: 529, tv_nsec: 423596048 }
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] output_dgram_on_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.131 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.131 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 529, tv_nsec: 423596048 }
server  | 1.131 DEBUG [Server 86e1b03c9b0df787] pn=17 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:50198 Tos(Cs0, NotEct) len 30
server  |   TX -> Ack { largest_acknowledged: 5288981, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 1.131 DEBUG Setting timeout of 265.482005ms
server  | 1.131 DEBUG Setting timeout of 265.472757ms

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:47:26,880 Using the server's key log file.
2025-12-13 11:47:26,889 
2025-12-13 11:47:26,889 Using the server's key log file.
2025-12-13 11:47:26,889 Using the server's key log file.
2025-12-13 11:47:26,897 
2025-12-13 11:47:26,897 Using the server's key log file.
2025-12-13 11:47:27,307 Check of downloaded files succeeded.
2025-12-13 11:47:27,719 Check of downloaded files succeeded.