2025-12-13 11:42:31,271 Generated random file: euphoric-large-actor of size: 1024 2025-12-13 11:42:31,271 Requests: https://server4:443/euphoric-large-actor 2025-12-13 11:42:31,329 2025-12-13 11:42:31,329 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_wt7hd4_5/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_byjxay3b/ DOWNLOADS=/tmp/download_rvpvn0i6/ SERVER_LOGS=/tmp/logs_server_4jxgclsf CLIENT_LOGS=/tmp/logs_client_iiq4js67 SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/facebook/proxygen/mvfst-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/euphoric-large-actor" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-12-13 11:42:46,297 Container sim Recreate Container sim Recreated Container client Recreate Container server 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 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.er9XytNeJ3 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 | ++ awk -F / '{ print "/" $4 }' client | ++ echo https://server4:443/euphoric-large-actor client | ++ tr ' ' '\n' client | ++ paste -sd, client | + INVOCATIONS=/euphoric-large-actor 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.er9XytNeJ3 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.er9XytNeJ3 -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 | 21:cd:2a:c1:40:da:86:a5:da:fb:6d:3d:8a:dd:db:5f: server | fe:04:6e:01 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:42:31 2025 server | Not After : Tue Dec 23 11:42:31 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:df:a3:a4:e6:b7:cc:dd:28:53:6f:16:e3:89:0f:b2: server | e6:14:19:c4:d3:2b:8b:84:20:e9:4c:08:25:2b:ba:db: server | 6e:43:5f:ef:6e:b8:ef:a7:3b:26:eb:90:6c:44:aa:da: server | 7b:c3:b9:9a:1a:2a:75:02:11:a1:d0:2a:e4:46:21:9e: server | 4b 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 | 0c:4f:dc:8b:5e:c7:43:31:f5:36:97:d6:de:02:1e:14: server | 32:ad:df:ed server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 53:86:72:34:56:14:d8:f7:7c:23:5b:c5:93:27:de:45: server | 42:ca:00:80 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:44:02:20:49:12:b8:df:3c:1c:b5:49:54:cf:2e:fb: server | 6d:b5:57:e1:46:bd:31:ec:6c:31:4e:57:8b:f1:eb:66: server | e1:7c:13:ed:02:20:03:d9:a9:19:f5:1c:5c:87:bb:07: server | d1:5f:ea:ae:da:1c:ce:d7:3b:d6:68:18:69:9a:dc:83: server | 0b:f2:8a:62:74:ff server | Fingerprint (SHA-256): server | 7D:6B:90:0F:0B:DB:5E:20:03:BC:FF:23:00:AF:2F:23:86:BD:1E:A6:9B:49:E8:74:22:35:1A:90:BA:30:35:BC server | Fingerprint (SHA1): server | 07:0F:8E:67:49:85:9F:2D:BF:C9:3D:E4:BD:63:A8:F2:79:82:5F:B6 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.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.267 DEBUG [Server] Unsupported version: 57414954 server | 0.267 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:42952 Tos(Cs0, NotEct) len 15 sim | server:443 is available after 500.996675ms sim | Using scenario: simple-p2p --delay=750ms --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/euphoric-large-actor ']' client | + REQS=("${REQUESTS}") client | + REQ=https://server4:443/euphoric-large-actor client | ++ echo https://server4:443/euphoric-large-actor client | ++ cut -d/ -f3 client | ++ cut -d: -f1 client | + SERVER=server4 client | + for INVOCATION in ${INVOCATIONS} client | + echo 'requesting files '\''/euphoric-large-actor'\''' client | requesting files '/euphoric-large-actor' 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=/euphoric-large-actor --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:42:32.863039 43 HQClient.cpp:47] HQClient connecting to 193.167.100.100:443 server | 1.741 DEBUG [Server] Handle initial server | 1.741 INFO AddressValidation: no token; accepting server | 1.741 INFO [Server] Accept connection CID [8]: ee0240829d9dee89 server | 1.741 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.743 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.743 INFO [Server ...] Received valid Initial packet with scid CID [0]: dcid CID [8]: ee0240829d9dee89 server | 1.743 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=ee0240829d9dee89 server | 1.743 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=933 server | 1.743 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.743 DEBUG [Server ee0240829d9dee89] State change from Init -> WaitInitial server | 1.743 DEBUG [Server ee0240829d9dee89] pn=9038103 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 1252 server | -> RX Crypto { offset: 0, len: 310 } server | -> RX Padding { len: 900 } server | 1.743 DEBUG 0-RTT: no token, no 0-RTT server | 1.744 DEBUG Read secret available for Handshake: SymKey [32]: a9bd21f9c0cc1f6d3306a002c1078f487d266f2591387c3b342254083cb3ff32 server | 1.744 DEBUG Write secret available for Handshake: SymKey [32]: 2e10ffe6dfccddfdca79d4fdc05a5588fb8ccb83b7514bb7a5df1ec1dc9bb4e2 server | 1.744 DEBUG Writing transport parameters, msg=8 server | 1.744 DEBUG Read secret available for ApplicationData: SymKey [32]: 8db2388ba492c754299dadbb5e683423beb39cc1ae83c3d5bf1bb53e82791534 server | 1.744 DEBUG Write secret available for ApplicationData: SymKey [32]: 7f773f11078f163ce61e7fa30d2d44c308e53025f56cbc6ae3ba9ef1909b9ed1 server | 1.744 DEBUG [Agent 0x55def7b3cee0] state -> InProgress server | 1.744 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.744 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.744 DEBUG [Crypto] Handshake keys installed server | 1.744 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.744 DEBUG [Crypto] Application write key installed server | 1.744 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 246, tv_nsec: 620483750 } server | 1.744 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:40408] Make permanent server | 1.744 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:40408] set as primary path server | 1.744 DEBUG [Server ee0240829d9dee89] State change from WaitInitial -> Handshaking server | 1.744 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.744 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.744 DEBUG CRYPTO for in offset=0, len=90 server | 1.744 DEBUG [Server ee0240829d9dee89] pn=933 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 140 server | TX -> Ack { largest_acknowledged: 9038103, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.744 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.744 DEBUG CRYPTO for hs offset=0, len=718 server | 1.744 DEBUG [Server ee0240829d9dee89] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 898 server | TX -> Crypto { offset: 0, len: 718 } server | 1.744 DEBUG packet_sent this=0x55def7badc50, pn=0, ps=758 server | 1.744 DEBUG Building Short dcid Some(CID [0]: ) server | 1.744 DEBUG [Server ee0240829d9dee89] pad Initial from 898 to PLPMTU 1232 server | 1.744 DEBUG packet_sent this=0x55def7badc50, pn=933, ps=474 server | 1.744 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.744 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.744 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.744 DEBUG Building Short dcid Some(CID [0]: ) server | 1.744 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.744 DEBUG [Server ee0240829d9dee89] delay duration 296.902852ms server | 1.744 DEBUG Setting timeout of 296.902852ms server | 1.744 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.744 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.744 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.744 DEBUG Building Short dcid Some(CID [0]: ) server | 1.744 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.744 DEBUG [Server ee0240829d9dee89] delay duration 296.873707ms server | 1.744 DEBUG Setting timeout of 296.873707ms server | 1.846 DEBUG [Server ee0240829d9dee89] pn=9038105 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 1252 server | -> RX Crypto { offset: 0, len: 310 } server | -> RX Padding { len: 900 } server | 1.846 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 246, tv_nsec: 724784198 } server | 1.846 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.846 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG CRYPTO for in offset=0, len=90 server | 1.846 DEBUG [Server ee0240829d9dee89] pn=934 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 142 server | TX -> Ack { largest_acknowledged: 9038105, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.846 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG CRYPTO for hs offset=0, len=718 server | 1.846 DEBUG [Server ee0240829d9dee89] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 900 server | TX -> Crypto { offset: 0, len: 718 } server | 1.846 DEBUG packet_sent this=0x55def7badc50, pn=1, ps=758 server | 1.846 DEBUG Building Short dcid Some(CID [0]: ) server | 1.846 DEBUG [Server ee0240829d9dee89] pad Initial from 900 to PLPMTU 1232 server | 1.846 DEBUG packet_sent this=0x55def7badc50, pn=934, ps=474 server | 1.846 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.846 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG Building Short dcid Some(CID [0]: ) server | 1.846 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.846 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.846 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG Building Short dcid Some(CID [0]: ) server | 1.846 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.846 DEBUG [Server ee0240829d9dee89] delay duration 299.849739ms server | 1.846 DEBUG Setting timeout of 299.849739ms server | 1.846 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.846 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 1.846 DEBUG Building Short dcid Some(CID [0]: ) server | 1.846 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.846 DEBUG [Server ee0240829d9dee89] delay duration 299.8186ms server | 1.846 DEBUG Setting timeout of 299.8186ms server | 2.056 DEBUG [Server ee0240829d9dee89] pn=9038107 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 1252 server | -> RX Crypto { offset: 0, len: 310 } server | -> RX Padding { len: 900 } server | 2.056 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 246, tv_nsec: 935247265 } server | 2.056 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.056 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG CRYPTO for in offset=0, len=90 server | 2.056 DEBUG [Server ee0240829d9dee89] pn=935 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 144 server | TX -> Ack { largest_acknowledged: 9038107, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 2.056 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG CRYPTO for hs offset=0, len=718 server | 2.056 DEBUG [Server ee0240829d9dee89] pn=2 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 902 server | TX -> Crypto { offset: 0, len: 718 } server | 2.056 DEBUG packet_sent this=0x55def7badc50, pn=2, ps=758 server | 2.056 DEBUG Building Short dcid Some(CID [0]: ) server | 2.056 DEBUG [Server ee0240829d9dee89] pad Initial from 902 to PLPMTU 1232 server | 2.056 DEBUG packet_sent this=0x55def7badc50, pn=935, ps=474 server | 2.056 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.056 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG Building Short dcid Some(CID [0]: ) server | 2.056 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.056 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.056 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG Building Short dcid Some(CID [0]: ) server | 2.056 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.056 DEBUG [Server ee0240829d9dee89] delay duration 299.789675ms server | 2.056 DEBUG Setting timeout of 299.789675ms server | 2.056 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.056 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.056 DEBUG Building Short dcid Some(CID [0]: ) server | 2.056 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.056 DEBUG [Server ee0240829d9dee89] delay duration 299.759799ms server | 2.056 DEBUG Setting timeout of 299.759799ms server | 2.357 DEBUG [recovery::Loss] PTO timer fired for Initial server | 2.357 DEBUG [recovery::Loss] PTO timer fired for Handshake server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 9038103, smallest: 9038103, ack_needed: true }] }) server | 2.357 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 246, tv_nsec: 620483750 } server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 2.357 INFO Lost crypto frame space=in offset=0 length=90 server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 9038105, smallest: 9038105, ack_needed: true }, PacketRange { largest: 9038103, smallest: 9038103, ack_needed: true }] }) server | 2.357 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 246, tv_nsec: 724784198 } server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 2.357 INFO Lost crypto frame space=in offset=0 length=90 server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 9038107, smallest: 9038107, ack_needed: true }, PacketRange { largest: 9038105, smallest: 9038105, ack_needed: true }, PacketRange { largest: 9038103, smallest: 9038103, ack_needed: true }] }) server | 2.357 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 246, tv_nsec: 935247265 } server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 2.357 INFO Lost crypto frame space=in offset=0 length=90 server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 718 }) server | 2.357 INFO Lost crypto frame space=hs offset=0 length=718 server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 718 }) server | 2.357 INFO Lost crypto frame space=hs offset=0 length=718 server | 2.357 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 718 }) server | 2.357 INFO Lost crypto frame space=hs offset=0 length=718 server | 2.357 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 2.357 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG CRYPTO for in offset=0, len=90 server | 2.357 DEBUG [Server ee0240829d9dee89] pn=936 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 147 server | TX -> Ack { largest_acknowledged: 9038107, ack_delay: 37585, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 2.357 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG CRYPTO for hs offset=0, len=718 server | 2.357 DEBUG [Server ee0240829d9dee89] pn=3 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 905 server | TX -> Crypto { offset: 0, len: 718 } server | 2.357 DEBUG packet_sent this=0x55def7badc50, pn=3, ps=758 server | 2.357 DEBUG Building Short dcid Some(CID [0]: ) server | 2.357 DEBUG [Server ee0240829d9dee89] pad Initial from 905 to PLPMTU 1232 server | 2.357 DEBUG packet_sent this=0x55def7badc50, pn=936, ps=474 server | 2.357 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.357 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG Building Short dcid Some(CID [0]: ) server | 2.357 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 2.357 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.357 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG Building Short dcid Some(CID [0]: ) server | 2.357 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.357 DEBUG [Server ee0240829d9dee89] delay duration 599.524468ms server | 2.357 DEBUG Setting timeout of 599.524468ms server | 2.357 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.357 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.357 DEBUG Building Short dcid Some(CID [0]: ) server | 2.357 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.357 DEBUG [Server ee0240829d9dee89] delay duration 599.497017ms server | 2.357 DEBUG Setting timeout of 599.497017ms server | 2.466 DEBUG [Server ee0240829d9dee89] pn=9038109 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 1252 server | -> RX Crypto { offset: 0, len: 310 } server | -> RX Padding { len: 900 } server | 2.466 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 247, tv_nsec: 344852346 } server | 2.466 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.466 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG CRYPTO for in offset=0, len=90 server | 2.466 DEBUG [Server ee0240829d9dee89] pn=937 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 146 server | TX -> Ack { largest_acknowledged: 9038109, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 2.466 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG CRYPTO for hs offset=0, len=718 server | 2.466 DEBUG [Server ee0240829d9dee89] pn=4 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 904 server | TX -> Crypto { offset: 0, len: 718 } server | 2.466 DEBUG packet_sent this=0x55def7badc50, pn=4, ps=758 server | 2.466 DEBUG Building Short dcid Some(CID [0]: ) server | 2.466 DEBUG [Server ee0240829d9dee89] pad Initial from 904 to PLPMTU 1232 server | 2.466 DEBUG packet_sent this=0x55def7badc50, pn=937, ps=474 server | 2.466 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.466 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG Building Short dcid Some(CID [0]: ) server | 2.466 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.466 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.466 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG Building Short dcid Some(CID [0]: ) server | 2.466 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.466 DEBUG [Server ee0240829d9dee89] delay duration 599.811477ms server | 2.466 DEBUG Setting timeout of 599.811477ms server | 2.466 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.466 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 2.466 DEBUG Building Short dcid Some(CID [0]: ) server | 2.466 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 2.466 DEBUG [Server ee0240829d9dee89] delay duration 599.781952ms server | 2.466 DEBUG Setting timeout of 599.781952ms client | I20251213 11:42:34.375924 43 CurlClient.cpp:187] Sending request for /euphoric-large-actor server | 3.067 DEBUG [recovery::Loss] PTO timer fired for Initial server | 3.067 DEBUG [recovery::Loss] PTO timer fired for Handshake server | 3.067 DEBUG [Server ee0240829d9dee89] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 9038107, smallest: 9038107, ack_needed: true }, PacketRange { largest: 9038105, smallest: 9038105, ack_needed: true }, PacketRange { largest: 9038103, smallest: 9038103, ack_needed: true }] }) server | 3.067 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 247, tv_nsec: 235928175 } server | 3.067 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 3.067 INFO Lost crypto frame space=in offset=0 length=90 server | 3.067 DEBUG [Server ee0240829d9dee89] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 9038109, smallest: 9038109, ack_needed: true }, PacketRange { largest: 9038107, smallest: 9038107, ack_needed: true }, PacketRange { largest: 9038105, smallest: 9038105, ack_needed: true }, PacketRange { largest: 9038103, smallest: 9038103, ack_needed: true }] }) server | 3.067 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 247, tv_nsec: 344852346 } server | 3.067 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 }) server | 3.067 INFO Lost crypto frame space=in offset=0 length=90 server | 3.067 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 718 }) server | 3.067 INFO Lost crypto frame space=hs offset=0 length=718 server | 3.067 DEBUG [Server ee0240829d9dee89] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 718 }) server | 3.067 INFO Lost crypto frame space=hs offset=0 length=718 server | 3.067 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false } server | 3.067 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG CRYPTO for in offset=0, len=90 server | 3.067 DEBUG [Server ee0240829d9dee89] pn=938 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 149 server | TX -> Ack { largest_acknowledged: 9038109, ack_delay: 75114, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 3.067 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG CRYPTO for hs offset=0, len=718 server | 3.067 DEBUG [Server ee0240829d9dee89] pn=5 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 907 server | TX -> Crypto { offset: 0, len: 718 } server | 3.067 DEBUG packet_sent this=0x55def7badc50, pn=5, ps=758 server | 3.067 DEBUG Building Short dcid Some(CID [0]: ) server | 3.067 DEBUG [Server ee0240829d9dee89] pad Initial from 907 to PLPMTU 1232 server | 3.067 DEBUG packet_sent this=0x55def7badc50, pn=938, ps=474 server | 3.067 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 3.067 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG Building Short dcid Some(CID [0]: ) server | 3.067 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(), paced: false } server | 3.067 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.067 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG Building Short dcid Some(CID [0]: ) server | 3.067 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.067 DEBUG [Server ee0240829d9dee89] delay duration 1.199720697s server | 3.067 DEBUG Setting timeout of 1.199720697s server | 3.067 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.067 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.067 DEBUG Building Short dcid Some(CID [0]: ) server | 3.067 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.067 DEBUG [Server ee0240829d9dee89] delay duration 1.199695049s server | 3.067 DEBUG Setting timeout of 1.199695049s server | 3.247 DEBUG [Server ee0240829d9dee89] Drop packet number space in server | 3.247 DEBUG [recovery::Loss] Reset loss recovery state for Initial server | 3.247 INFO [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408] discarding a packet without an RTT estimate; guessing RTT=1.505939482s server | 3.247 DEBUG [Server ee0240829d9dee89] pn=9038103 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 84 server | -> RX Crypto { offset: 0, len: 36 } server | -> RX Ack { largest_acknowledged: 0, ack_delay: 140, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.247 DEBUG [Agent 0x55def7b3cee0] 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 | 3.247 DEBUG [Server ee0240829d9dee89] TLS connection complete server | 3.247 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:40408] Path validated Instant { tv_sec: 248, tv_nsec: 126423232 } server | 3.247 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 3.247 DEBUG [Crypto] application read keys installed server | 3.248 DEBUG [Server ee0240829d9dee89] State change from Handshaking -> Connected server | 3.248 DEBUG [Server ee0240829d9dee89] State change from Connected -> Confirmed server | 3.248 DEBUG PMTUD started, PLPMTU is now 1280 server | 3.248 DEBUG PMTUD started with probe size 1380 server | 3.248 INFO [Server ee0240829d9dee89] Connection established server | 3.248 DEBUG [Server ee0240829d9dee89] Rx ACK space=hs, ranges=[0..=0] server | 3.248 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=0 server | 3.248 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 3790/13078 ssthresh 18446744073709551615] slow start += 758 server | 3.248 DEBUG [Cubic [w_max: 0, k: 0, t_epoch: None] CongCtrl 3790/13078 ssthresh 18446744073709551615] on_packets_acked this=0x55def7badc50, limited=0, bytes_in_flight=3790, cwnd=13078, state=SlowStart, new_acked=758 server | 3.248 DEBUG Acked crypto frame space=hs offset=0 length=718 server | 3.248 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 248, tv_nsec: 126423232 } server | 3.248 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.248 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: f996c6a2f5bfd16d57fb) server | 3.248 DEBUG [Server ee0240829d9dee89] pn=6 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, Ect0) len 44 server | TX -> Ack { largest_acknowledged: 9038103, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.248 DEBUG [Server ee0240829d9dee89] Drop packet number space hs server | 3.248 DEBUG [recovery::Loss] Reset loss recovery state for Handshake server | 3.248 DEBUG Building Short dcid Some(CID [0]: ) server | 3.248 DEBUG [Server ee0240829d9dee89] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [32, 52, 210, 210, 186, 202, 200, 95, 166, 73], stateless_reset_token: Token([59, 43, 137, 131, 149, 167, 108, 190, 39, 191, 107, 93, 119, 134, 147, 221]) } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [156, 75, 247, 152, 11, 191, 137, 177, 35, 30], stateless_reset_token: Token([87, 117, 34, 7, 149, 196, 188, 121, 221, 192, 155, 34, 154, 176, 160, 202]) } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [155, 137, 231, 166, 148, 248, 101, 57, 56, 204], stateless_reset_token: Token([83, 150, 154, 138, 147, 214, 16, 243, 1, 162, 35, 236, 222, 102, 13, 13]) } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [81, 200, 86, 37, 237, 18, 71, 112, 47, 106], stateless_reset_token: Token([245, 0, 169, 221, 4, 206, 98, 166, 221, 193, 19, 127, 139, 100, 189, 8]) } server | TX -> Padding { len: 1149 } server | 3.248 DEBUG packet_sent this=0x55def7badc50, pn=0, ps=1288 server | 3.248 DEBUG ECN probing: sent 1 probes server | 3.248 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.248 DEBUG Building Short dcid Some(CID [0]: ) server | 3.248 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.248 DEBUG [Server ee0240829d9dee89] delay duration 2.434195ms server | 3.248 DEBUG Setting timeout of 2.434195ms server | 3.248 DEBUG [Server ee0240829d9dee89] pn=9038103 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, NotEct) len 68 server | -> RX Stream { stream_id: 0, offset: 0, len: 27, fin: true } server | -> RX Padding { len: 7 } server | 3.248 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 248, tv_nsec: 127240955 } server | 3.248 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.248 DEBUG Building Short dcid Some(CID [0]: ) server | 3.248 DEBUG [Server ee0240829d9dee89] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, Ect0) len 26 server | TX -> Ack { largest_acknowledged: 9038103, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 3.248 DEBUG ECN probing: sent 2 probes server | 3.248 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.248 DEBUG Building Short dcid Some(CID [0]: ) server | 3.248 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.248 DEBUG [Server ee0240829d9dee89] delay duration 3.828447ms server | 3.248 DEBUG Setting timeout of 3.828447ms server | 3.249 DEBUG [Server ee0240829d9dee89] send session ticket 40620008ee0240829d9dee890104800075300404802000000504801000000604801000000704801000000801100901100b01140e01080f0af996c6a2f5bfd16d57fb110c000000010a0aeaba000000016ab200c0000000ff02de1a0243e820048000ffff686921 server | 3.249 DEBUG Path = 'euphoric-large-actor' server | 3.249 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG Building Short dcid Some(CID [0]: ) server | 3.249 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.249 DEBUG [Server ee0240829d9dee89] delay duration 2.827181ms server | 3.249 DEBUG Setting timeout of 2.827181ms server | 3.253 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.253 DEBUG Building Short dcid Some(CID [0]: ) server | 3.253 DEBUG Sending PMTUD probe of size 1380, count 1 server | 3.253 DEBUG CRYPTO for ap offset=0, len=281 server | 3.253 DEBUG [Server ee0240829d9dee89] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true } server | TX -> Crypto { offset: 0, len: 281 } server | 3.253 DEBUG packet_sent this=0x55def7badc50, pn=2, ps=1332 server | 3.253 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG Building Short dcid Some(CID [0]: ) server | 3.253 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG ECN probing: sent 3 probes server | 3.253 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG Building Short dcid Some(CID [0]: ) server | 3.253 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG [Server ee0240829d9dee89] delay duration 75.387991ms server | 3.253 DEBUG Setting timeout of 75.387991ms server | 3.253 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG Building Short dcid Some(CID [0]: ) server | 3.253 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.253 DEBUG [Server ee0240829d9dee89] delay duration 75.373273ms server | 3.253 DEBUG Setting timeout of 75.373273ms server | 3.329 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.329 DEBUG Building Short dcid Some(CID [0]: ) server | 3.329 DEBUG CRYPTO for ap offset=281, len=24 server | 3.329 DEBUG [Server ee0240829d9dee89] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:40408 Tos(Cs0, Ect0) len 91 server | TX -> Crypto { offset: 281, len: 24 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 157, 68, 10, 181, 220, 41, 84, 37, 11, 53, 225, 66, 29, 48, 103, 36, 208, 127, 174, 78, 144, 116, 146, 53, 21, 37, 78, 132, 6, 186, 84, 123, 180, 180, 65, 149] } server | 3.330 DEBUG packet_sent this=0x55def7badc50, pn=3, ps=91 server | 3.330 DEBUG ECN probing: sent 4 probes server | 3.330 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG Building Short dcid Some(CID [0]: ) server | 3.330 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG [Server ee0240829d9dee89] delay duration 4.326878ms server | 3.330 DEBUG Setting timeout of 4.326878ms server | 3.330 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG Building Short dcid Some(CID [0]: ) server | 3.330 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 3.330 DEBUG [Server ee0240829d9dee89] delay duration 4.305468ms server | 3.330 DEBUG Setting timeout of 4.305468ms server | 3.335 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.335 DEBUG Building Short dcid Some(CID [0]: ) server | 3.335 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.335 DEBUG [Server ee0240829d9dee89] delay duration 3.78464011s server | 3.335 DEBUG Setting timeout of 3.78464011s server | 3.335 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.335 DEBUG Building Short dcid Some(CID [0]: ) server | 3.335 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.335 DEBUG [Server ee0240829d9dee89] delay duration 3.784615453s server | 3.335 DEBUG Setting timeout of 3.784615453s server | 3.347 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 1 server | 3.347 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.347 DEBUG Building Short dcid Some(CID [0]: ) server | 3.347 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.347 DEBUG [Server ee0240829d9dee89] delay duration 3.772092403s server | 3.347 DEBUG Setting timeout of 3.772092403s server | 3.347 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.347 DEBUG Building Short dcid Some(CID [0]: ) server | 3.347 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.347 DEBUG [Server ee0240829d9dee89] delay duration 3.772055735s server | 3.347 DEBUG Setting timeout of 3.772055735s server | 3.558 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 2 server | 3.558 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.558 DEBUG Building Short dcid Some(CID [0]: ) server | 3.558 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.558 DEBUG [Server ee0240829d9dee89] delay duration 3.561409263s server | 3.558 DEBUG Setting timeout of 3.561409263s server | 3.558 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.558 DEBUG Building Short dcid Some(CID [0]: ) server | 3.558 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.558 DEBUG [Server ee0240829d9dee89] delay duration 3.561345844s server | 3.558 DEBUG Setting timeout of 3.561345844s server | 3.859 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 3 server | 3.859 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.859 DEBUG Building Short dcid Some(CID [0]: ) server | 3.859 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.859 DEBUG [Server ee0240829d9dee89] delay duration 3.260444342s server | 3.859 DEBUG Setting timeout of 3.260444342s server | 3.859 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.859 DEBUG Building Short dcid Some(CID [0]: ) server | 3.859 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.859 DEBUG [Server ee0240829d9dee89] delay duration 3.260373019s server | 3.859 DEBUG Setting timeout of 3.260373019s server | 3.967 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 4 server | 3.967 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.967 DEBUG Building Short dcid Some(CID [0]: ) server | 3.967 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.967 DEBUG [Server ee0240829d9dee89] delay duration 3.151908129s server | 3.967 DEBUG Setting timeout of 3.151908129s server | 3.967 DEBUG [Server ee0240829d9dee89] output_dgram_on_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.967 DEBUG Building Short dcid Some(CID [0]: ) server | 3.967 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.967 DEBUG [Server ee0240829d9dee89] delay duration 3.151833029s server | 3.967 DEBUG Setting timeout of 3.151833029s client | I20251213 11:42:35.883957 43 CurlClient.cpp:276] Got EOM for /euphoric-large-actor. Txn Time= 1508 ms client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-12-13 11:42:46,382 Using the server's key log file. 2025-12-13 11:42:46,390 2025-12-13 11:42:46,391 Using the server's key log file. 2025-12-13 11:42:46,391 Using the server's key log file. 2025-12-13 11:42:46,398 2025-12-13 11:42:46,399 Using the server's key log file. 2025-12-13 11:42:46,618 Check of downloaded files succeeded. 2025-12-13 11:42:47,555 Check of downloaded files succeeded.