2025-04-30 12:50:41,196 Generated random file: vbkiyrhjbj of size: 10240
2025-04-30 12:50:41,196 Requests: https://server4:443/vbkiyrhjbj
2025-04-30 12:50:41,259 
2025-04-30 12:50:41,259 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_5n987qt3/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_5krmu439/ DOWNLOADS=/tmp/download_81wewohy/ SERVER_LOGS=/tmp/logs_server_a08cba0_ CLIENT_LOGS=/tmp/logs_client_7rbtayit SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/vbkiyrhjbj"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:50:53,557  Container sim  Created
 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
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 retry ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.KOQfY2QJuI
server  | + mkdir -p /neqo/db
client  | Setting up routes...
server  | + certutil -N -d sql:/neqo/db --empty-password
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
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
client  | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
client  | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
client  | + '[' -n retry ']'
client  | + '[' -n /logs/qlog/ ']'
client  | + case "$ROLE" in
client  | + /wait-for-it.sh sim:57832 -s -t 30
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.KOQfY2QJuI
server  | Warning: output encryption option -nodes ignored with -export
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.KOQfY2QJuI -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  |             59:cd:64:f5:f4:87:9a:ce:bc:03:0e:f1:89:86:e6:22:
server  |             59:4e:84:65
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner Root Certificate Authority"
server  |         Validity:
server  |             Not Before: Wed Apr 30 12:50:41 2025
server  |             Not After : Sat May 10 12:50:41 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:4d:e8:82:f0:9e:eb:ba:7d:e7:5c:86:13:82:2e:f6:
server  |                     01:75:b8:1e:8b:56:5a:3a:6c:11:61:b8:0a:9a:02:71:
server  |                     91:af:01:70:5b:64:42:55:af:95:ce:2c:c5:a6:b3:61:
server  |                     25:d1:05:d1:e9:b4:4d:70:60:b3:0c:f7:b0:0b:bc:83:
server  |                     94
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  |                 84:58:dc:63:8d:54:bd:23:53:4f:3e:32:96:69:3b:69:
server  |                 3d:43:47:f5
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 84:6b:6b:cb:b8:50:4d:09:b4:bc:6a:a9:2e:7f:96:a6:
server  |                 e8:9e:c7:63
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:20:48:6b:e0:f0:99:27:fc:ad:b9:c9:2c:0e:
server  |         6a:3f:ae:4d:4e:c5:74:25:b6:1f:82:8a:69:a6:ce:e0:
server  |         45:44:a5:4b:02:21:00:95:a8:8b:18:03:58:1e:97:86:
server  |         ab:7f:7a:2d:31:91:ac:9d:c6:98:85:4a:30:91:bf:66:
server  |         e4:9d:09:4b:c8:02:db
server  |     Fingerprint (SHA-256):
server  |         E1:76:34:32:AB:4E:A9:2D:BB:97:65:90:41:47:08:8A:A2:3E:2D:9E:89:11:A5:F0:F2:EB:C1:F1:84:BB:01:CE
server  |     Fingerprint (SHA1):
server  |         6C:FF:87:4C:DE:0B:40:AA:2E:1B:23:60:BC:7B:20:45:CC:52:E9:CF
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  | + '[' retry = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test retry --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 212992
server  | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.002 INFO Server waiting for connection on: [::]:443
server  | 0.774 DEBUG [Server] Unsupported version: 57414954
server  | 0.774 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:58466 IpTos(Cs0, NotEct) len 15
server  | 0.775 DEBUG [Server] Unsupported version: 57414954
server  | 0.775 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:58466 IpTos(Cs0, NotEct) len 15
server  | 0.775 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.775 DEBUG [Server] Unsupported version: 57414954
server  | 0.775 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:58466 IpTos(Cs0, NotEct) len 15
server  | 0.775 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | server:443 is available after 1.032612388s
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads)
client  | + '[' https://server4:443/vbkiyrhjbj ']'
client  | + mapfile -d ' ' -t URLS
client  | + OPTIONS+=("${URLS[@]}")
client  | + RUST_LOG=debug
client  | + RUST_BACKTRACE=1
client  | + neqo-client --cc cubic --qns-test retry --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/vbkiyrhjbj
client  | '
client  | ++ tee -i -a /logs/client.log
client  | 0.000 DEBUG Logging initialized
client  | 0.000 DEBUG Default socket send buffer size is 212992
client  | 0.000 DEBUG Default socket receive buffer size is 1048576, not changing
client  | 0.000 INFO hq-interop Client connecting: 0.0.0.0:49360 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=1cdfe3d7bf312d1e
client  | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
client  | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
client  | 0.001 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500
client  | 0.001 DEBUG [unv-path 0.0.0.0:49360->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:1cdfe3d7bf312d1e 0.0.0.0:49360->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:1cdfe3d7bf312d1e 0.0.0.0:49360->193.167.100.100:443] Path validated Instant { tv_sec: 439, tv_nsec: 530164499 }
client  | 0.001 DEBUG [Client 1cdfe3d7bf312d1e] client_start
client  | 0.001 DEBUG Writing transport parameters, msg=1
client  | 0.001 DEBUG [Agent 0x55e10b5d4270] state -> InProgress
client  | 0.001 DEBUG [Client 1cdfe3d7bf312d1e] State change from Init -> WaitInitial
client  | 0.001 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.001 DEBUG Building Initial dcid Some(CID [8]: 1cdfe3d7bf312d1e) scid Some(CID [0]: )
client  | 0.001 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.001 DEBUG [Client 1cdfe3d7bf312d1e] pn=0 type=Initial pri-path:1cdfe3d7bf312d1e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.001 DEBUG packet_sent this=0x55e10b5e7970, pn=0, ps=1252
client  | 0.001 DEBUG ECN probing: sent 1 probes
client  | 0.002 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1cdfe3d7bf312d1e) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1213, len=326
client  | 0.002 DEBUG [Client 1cdfe3d7bf312d1e] pn=1 type=Initial pri-path:1cdfe3d7bf312d1e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 366
client  |   TX -> Crypto { offset: 1213, len: 326 }
client  | 0.002 DEBUG [Client 1cdfe3d7bf312d1e] pad Initial from 366 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55e10b5e7970, pn=1, ps=1252
client  | 0.002 DEBUG ECN probing: sent 2 probes
client  | 0.002 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1cdfe3d7bf312d1e) scid Some(CID [0]: )
client  | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 3.86036ms
client  | 0.002 DEBUG Setting timeout of 3.86036ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 1cdfe3d7bf312d1e) scid Some(CID [0]: )
client  | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 3.835724ms
client  | 0.002 DEBUG Setting timeout of 3.835724ms
client  | 0.007 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [8]: 1cdfe3d7bf312d1e) scid Some(CID [0]: )
client  | 0.007 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 294.818407ms
client  | 0.007 DEBUG Setting timeout of 294.818407ms
server  | 1.006 DEBUG [Server] Handle initial
server  | 1.006 INFO AddressValidation: no token; validating
server  | 1.006 INFO [Server] Send retry for CID [8]: 1cdfe3d7bf312d1e
server  | 1.006 DEBUG [Server] type=Retry path:1cdfe3d7bf312d1e [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 84
server  | 1.006 DEBUG [Server] Handle initial
server  | 1.006 INFO AddressValidation: no token; validating
server  | 1.006 INFO [Server] Send retry for CID [8]: 1cdfe3d7bf312d1e
server  | 1.006 DEBUG [Server] type=Retry path:1cdfe3d7bf312d1e [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 84
client  | 0.039 INFO [Client 1cdfe3d7bf312d1e] received Retry
client  | 0.039 INFO [Client 1cdfe3d7bf312d1e] Valid Retry received, token=5265747279010019b5c4e3f8aad9b1264287010c04f0a651bc93c0962430a5b0a3b8ce52936a9898c75c20e979418e82e683f2 scid=60e0acadf32892b097f6
client  | 0.039 INFO [pri-path:60e0acadf32892b097f6 0.0.0.0:49360->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=37.600552ms
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 })
client  | 0.039 INFO Lost crypto frame space=in offset=0 length=1213
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] Lost: EcnEct0
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 })
client  | 0.039 INFO Lost crypto frame space=in offset=1213 length=326
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] Lost: EcnEct0
client  | 0.039 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=60e0acadf32892b097f6
client  | 0.039 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
client  | 0.039 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
client  | 0.039 INFO [CryptoStates] Continue packet numbers for initial after retry (write is 0..0)
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [10]: 60e0acadf32892b097f6) scid Some(CID [0]: )
client  | 0.039 DEBUG CRYPTO for in offset=0, len=1160
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] pn=2 type=Initial pri-path:60e0acadf32892b097f6 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1160 }
client  | 0.039 DEBUG packet_sent this=0x55e10b5e7970, pn=2, ps=1252
client  | 0.039 DEBUG ECN probing: sent 3 probes
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [10]: 60e0acadf32892b097f6) scid Some(CID [0]: )
client  | 0.039 DEBUG CRYPTO for in offset=1160, len=379
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] pn=3 type=Initial pri-path:60e0acadf32892b097f6 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 472
client  |   TX -> Crypto { offset: 1160, len: 379 }
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] pad Initial from 472 to PLPMTU 1252
client  | 0.039 DEBUG packet_sent this=0x55e10b5e7970, pn=3, ps=1252
client  | 0.039 DEBUG ECN probing: sent 4 probes
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [10]: 60e0acadf32892b097f6) scid Some(CID [0]: )
client  | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 112.742696ms
client  | 0.039 DEBUG Setting timeout of 112.742696ms
client  | 0.039 INFO [Client 1cdfe3d7bf312d1e] received Retry
client  | 0.039 WARN [Client ...] Dropped received packet: Extra Retry; Total: 1
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [10]: 60e0acadf32892b097f6) scid Some(CID [0]: )
client  | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 112.71266ms
client  | 0.039 DEBUG Setting timeout of 112.71266ms
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG Building Initial dcid Some(CID [10]: 60e0acadf32892b097f6) scid Some(CID [0]: )
client  | 0.039 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.039 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 112.696791ms
client  | 0.039 DEBUG Setting timeout of 112.696791ms
server  | 1.038 DEBUG [Server] Handle initial
server  | 1.038 INFO AddressValidation: valid Retry token for 1cdfe3d7bf312d1e
server  | 1.038 INFO [Server] Accept connection CID [8]: 1cdfe3d7bf312d1e
server  | 1.038 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.040 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.040 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [10]: 60e0acadf32892b097f6
server  | 1.040 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=60e0acadf32892b097f6
server  | 1.040 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.040 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] State change from Init -> WaitInitial
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] pn=2 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1252
server  |   -> RX Crypto { offset: 0, len: 1160 }
server  | 1.040 DEBUG Read Ok(1160) bytes
server  | 1.040 DEBUG [Agent 0x561cd2a59310] state -> InProgress
server  | 1.040 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 449, tv_nsec: 585045789 }
server  | 1.040 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:49360] Make permanent
server  | 1.040 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:49360] set as primary path
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] State change from WaitInitial -> WaitVersion
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.040 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 45
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.040 DEBUG ECN probing: sent 1 probes
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.040 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.040 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] delay duration 298.118371ms
server  | 1.040 DEBUG Setting timeout of 298.118371ms
server  | 1.040 DEBUG [Server 60e0acadf32892b097f6] pn=3 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 472
server  |   -> RX Crypto { offset: 1160, len: 379 }
server  | 1.040 DEBUG Read Ok(379) bytes
server  | 1.040 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.041 DEBUG Read secret available for Handshake: SymKey [32]: 3ba69b47207178dcb70bc72ce7d29549e1236be5ae5e4844e8b9ad4ffa27dda8
server  | 1.041 DEBUG Write secret available for Handshake: SymKey [32]: c89086a2ef16f67a499ad428a18aa46d67822fde124e3ad6f18221ea9d344ff8
server  | 1.041 DEBUG Writing transport parameters, msg=8
server  | 1.041 DEBUG Read secret available for ApplicationData: SymKey [32]: 08e17316326cbe1d5054345f7b05afece83f2f05e37e9fb7637c9b942f3b0aa0
server  | 1.041 DEBUG Write secret available for ApplicationData: SymKey [32]: 0b8b0350fee6897a18d387da46623175520a207e558fa4ca22162b8c3632711e
server  | 1.041 DEBUG [Agent 0x561cd2a59310] state -> InProgress
server  | 1.041 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.041 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.041 DEBUG [Crypto] Handshake keys installed
server  | 1.041 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.041 DEBUG [Crypto] Application write key installed
server  | 1.041 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 449, tv_nsec: 586953256 }
server  | 1.041 DEBUG [Server 60e0acadf32892b097f6] State change from WaitVersion -> Handshaking
server  | 1.041 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1
server  | 1.041 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.041 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.041 DEBUG CRYPTO for in offset=0, len=1178
server  | 1.041 DEBUG [Server 60e0acadf32892b097f6] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1227
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 1.041 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.041 DEBUG [Server 60e0acadf32892b097f6] pad Initial from 1227 to PLPMTU 1232
server  | 1.041 DEBUG packet_sent this=0x561cd2a59b40, pn=1, ps=1232
server  | 1.041 DEBUG ECN probing: sent 2 probes
server  | 1.041 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.041 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.041 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.041 DEBUG CRYPTO for hs offset=0, len=746
server  | 1.041 DEBUG [Server 60e0acadf32892b097f6] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 786
server  |   TX -> Crypto { offset: 0, len: 746 }
server  | 1.041 DEBUG packet_sent this=0x561cd2a59b40, pn=0, ps=786
server  | 1.041 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.041 DEBUG ECN probing: sent 3 probes
server  | 1.042 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.042 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.042 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.042 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.042 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.042 DEBUG [Server 60e0acadf32892b097f6] delay duration 298.542442ms
server  | 1.042 DEBUG Setting timeout of 298.542442ms
server  | 1.042 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.042 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.042 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.042 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.042 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.042 DEBUG [Server 60e0acadf32892b097f6] delay duration 298.505894ms
server  | 1.042 DEBUG Setting timeout of 298.505894ms
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] pn=0 type=Initial pri-path:60e0acadf32892b097f6 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 45
client  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] Rx ACK space=in, ranges=[2..=2]
client  | 0.073 DEBUG [LossRecovery] ACK for Initial - largest_acked=2
client  | 0.073 DEBUG on_packets_acked this=0x55e10b5e7970, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.073 DEBUG Acked crypto frame space=in offset=0 length=1160
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] Changing to use Server CID=7194fb9fffac5c55376e
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] State change from WaitInitial -> WaitVersion
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.073 DEBUG Building Initial dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 63.738938ms
client  | 0.073 DEBUG Setting timeout of 63.738938ms
client  | 0.073 WARN Unhandled event StateChange(WaitVersion)
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.073 DEBUG Building Initial dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.073 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.073 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 63.701469ms
client  | 0.073 DEBUG Setting timeout of 63.701469ms
client  | 0.075 DEBUG [Client 1cdfe3d7bf312d1e] pn=1 type=Initial pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1227
client  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   -> RX Crypto { offset: 0, len: 1178 }
client  | 0.075 DEBUG [Client 1cdfe3d7bf312d1e] Rx ACK space=in, ranges=[2..=3]
client  | 0.075 DEBUG [LossRecovery] ACK for Initial - largest_acked=3
client  | 0.075 DEBUG on_packets_acked this=0x55e10b5e7970, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.075 DEBUG Acked crypto frame space=in offset=1160 length=379
client  | 0.075 DEBUG Read Ok(1178) bytes
client  | 0.075 DEBUG Write secret available for Handshake: SymKey [32]: 3ba69b47207178dcb70bc72ce7d29549e1236be5ae5e4844e8b9ad4ffa27dda8
client  | 0.075 DEBUG Read secret available for Handshake: SymKey [32]: c89086a2ef16f67a499ad428a18aa46d67822fde124e3ad6f18221ea9d344ff8
client  | 0.075 DEBUG [Agent 0x55e10b5d4270] state -> InProgress
client  | 0.075 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.075 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.075 DEBUG [Crypto] Handshake keys installed
client  | 0.075 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 604465495 }
client  | 0.075 DEBUG [Client 1cdfe3d7bf312d1e] State change from WaitVersion -> Handshaking
client  | 0.075 INFO [Client 1cdfe3d7bf312d1e] Garbage packet: Transport error: InvalidPacket
client  | 0.075 WARN [Client ...] Dropped received packet: Garbage packet; Total: 2
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Initial dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] pn=4 type=Initial pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 97
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 89, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.076 DEBUG Building Handshake dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] pad Initial from 97 to PLPMTU 1252
client  | 0.076 DEBUG ECN probing: sent 5 probes
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Initial dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.076 DEBUG Building Handshake dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 47.166816ms
client  | 0.076 DEBUG Setting timeout of 47.166816ms
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] pn=0 type=Handshake pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 786
client  |   -> RX Crypto { offset: 0, len: 746 }
client  | 0.076 DEBUG Read Ok(746) bytes
client  | 0.076 DEBUG [Agent 0x55e10b5d4270] state -> AuthenticationPending
client  | 0.076 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 605306083 }
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Initial dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.076 DEBUG Building Handshake dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] pn=0 type=Handshake pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 45
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 90, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] Drop packet number space in
client  | 0.076 DEBUG [LossRecovery] Reset loss recovery state for Initial
client  | 0.076 DEBUG ECN probing: sent 6 probes
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Handshake dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.076 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 83.53466ms
client  | 0.076 DEBUG Setting timeout of 83.53466ms
client  | 0.076 DEBUG StateChange(Handshaking)
client  | 0.076 WARN Cannot create stream ConnectionState
client  | 0.076 DEBUG [Client 1cdfe3d7bf312d1e] Authenticated Ok
client  | 0.077 DEBUG Write secret available for ApplicationData: SymKey [32]: 08e17316326cbe1d5054345f7b05afece83f2f05e37e9fb7637c9b942f3b0aa0
client  | 0.077 DEBUG Read secret available for ApplicationData: SymKey [32]: 0b8b0350fee6897a18d387da46623175520a207e558fa4ca22162b8c3632711e
client  | 0.077 DEBUG [Agent 0x55e10b5d4270] SSL_AuthCertificateComplete: Ok(())
client  | 0.077 DEBUG [Agent 0x55e10b5d4270] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.077 DEBUG [Client 1cdfe3d7bf312d1e] TLS connection complete
client  | 0.077 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.077 DEBUG [Crypto] Application write key installed
client  | 0.077 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.077 DEBUG [Crypto] application read keys installed
client  | 0.077 DEBUG [Client 1cdfe3d7bf312d1e] State change from Handshaking -> Connected
client  | 0.077 INFO [Client 1cdfe3d7bf312d1e] Connection established
client  | 0.077 DEBUG stream BiDi creatable
client  | 0.077 INFO Created stream 0 for https://server4/vbkiyrhjbj
client  | 0.077 INFO Saving https://server4/vbkiyrhjbj to "/downloads/vbkiyrhjbj"
client  | 0.077 DEBUG stream UniDi creatable
client  | 0.077 DEBUG StateChange(Connected)
client  | 0.077 DEBUG stream 0 writable
client  | 0.077 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Handshake dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.077 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.077 DEBUG [Client 1cdfe3d7bf312d1e] pn=1 type=Handshake pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 75
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.077 DEBUG packet_sent this=0x55e10b5e7970, pn=1, ps=75
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.077 DEBUG [Client 1cdfe3d7bf312d1e] pn=0 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 130
client  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 9232, ignore_order: false }
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 0.077 DEBUG packet_sent this=0x55e10b5e7970, pn=0, ps=55
client  | 0.077 DEBUG ECN probing: sent 7 probes
client  | 0.077 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Handshake dcid Some(CID [10]: 7194fb9fffac5c55376e) scid Some(CID [0]: )
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 83.508922ms
client  | 0.077 DEBUG Setting timeout of 83.508922ms
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] pn=4 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 97
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 89, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=in, ranges=[0..=1]
server  | 1.075 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
server  | 1.075 DEBUG on_packets_acked this=0x561cd2a59b40, limited=1, bytes_in_flight=786, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.075 DEBUG Acked crypto frame space=in offset=0 length=1178
server  | 1.075 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:49360] Path validated Instant { tv_sec: 449, tv_nsec: 621570897 }
server  | 1.075 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 2
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.075 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.075 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.075 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.075 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] delay duration 69.23528ms
server  | 1.075 DEBUG Setting timeout of 69.23528ms
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] Drop packet number space in
server  | 1.075 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] pn=0 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 45
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 90, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=hs, ranges=[0..=0]
server  | 1.075 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.075 DEBUG on_packets_acked this=0x561cd2a59b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=786
server  | 1.075 DEBUG Acked crypto frame space=hs offset=0 length=746
server  | 1.075 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:49360] Path validated Instant { tv_sec: 449, tv_nsec: 621730184 }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.075 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.075 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.075 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] delay duration 54.206416ms
server  | 1.075 DEBUG Setting timeout of 54.206416ms
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.075 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.075 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.075 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] delay duration 54.099296ms
server  | 1.075 DEBUG Setting timeout of 54.099296ms
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 75
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.075 DEBUG Read Ok(36) bytes
server  | 1.075 DEBUG [Agent 0x561cd2a59310] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] TLS connection complete
server  | 1.075 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:49360] Path validated Instant { tv_sec: 449, tv_nsec: 621926881 }
server  | 1.075 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.075 DEBUG [Crypto] application read keys installed
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] State change from Handshaking -> Connected
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] State change from Connected -> Confirmed
server  | 1.075 DEBUG PMTUD started with probe size 1380
server  | 1.075 INFO [Server 60e0acadf32892b097f6] Connection established
server  | 1.075 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 449, tv_nsec: 621926881 }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 55
server  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 9232, ignore_order: false }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.075 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 449, tv_nsec: 631158881 }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.075 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7194fb9fffac5c55376e)
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 44
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] Drop packet number space hs
server  | 1.075 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.075 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.075 DEBUG [Server 60e0acadf32892b097f6] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [98, 225, 232, 50, 227, 188, 91, 30, 131, 133], stateless_reset_token: [54, 200, 133, 60, 248, 254, 235, 249, 133, 195, 233, 126, 254, 162, 165, 249] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [145, 222, 248, 92, 44, 232, 30, 40, 103, 65], stateless_reset_token: [214, 44, 53, 131, 216, 14, 112, 36, 117, 211, 56, 21, 96, 255, 202, 77] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [89, 154, 204, 4, 21, 158, 83, 64, 85, 89], stateless_reset_token: [159, 60, 206, 237, 98, 27, 202, 39, 209, 32, 112, 191, 216, 139, 86, 69] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [223, 225, 117, 49, 38, 159, 19, 157, 105, 234], stateless_reset_token: [68, 102, 150, 81, 113, 186, 59, 241, 193, 178, 122, 103, 153, 248, 78, 128] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [125, 32, 205, 180, 40, 68, 82, 62, 28, 47], stateless_reset_token: [232, 115, 201, 139, 134, 20, 65, 17, 106, 73, 21, 120, 39, 145, 165, 171] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [223, 200, 37, 15, 120, 215, 131, 87, 50, 112], stateless_reset_token: [201, 118, 192, 233, 85, 154, 254, 239, 117, 6, 243, 29, 202, 153, 95, 91] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [109, 92, 15, 254, 153, 251, 92, 10, 168, 183], stateless_reset_token: [194, 154, 162, 121, 175, 162, 65, 63, 14, 204, 187, 78, 182, 206, 43, 21] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 8616, ignore_order: false }
server  |   TX -> Padding { len: 1052 }
server  | 1.076 DEBUG packet_sent this=0x561cd2a59b40, pn=0, ps=1288
server  | 1.076 DEBUG ECN probing: sent 4 probes
server  | 1.076 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.076 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.076 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.076 DEBUG [Server 60e0acadf32892b097f6] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.076 DEBUG packet_sent this=0x561cd2a59b40, pn=1, ps=1332
server  | 1.076 DEBUG ECN probing: sent 5 probes
server  | 1.076 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.076 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.076 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.076 DEBUG [Server 60e0acadf32892b097f6] delay duration 1.276988ms
server  | 1.076 DEBUG Setting timeout of 1.276988ms
server  | 1.077 DEBUG [Server 60e0acadf32892b097f6] send session ticket 406f00081cdfe3d7bf312d1e0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a7194fb9fffac5c55376e100a60e0acadf32892b097f6110c00000001da4a2a6a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.077 DEBUG Path = 'vbkiyrhjbj'
server  | 1.077 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.077 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.077 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.077 DEBUG [Server 60e0acadf32892b097f6] delay duration 221.64µs
server  | 1.077 DEBUG Setting timeout of 221.64µs
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1212, fin: false }
server  | 1.078 DEBUG packet_sent this=0x561cd2a59b40, pn=2, ps=1232
server  | 1.078 DEBUG ECN probing: sent 6 probes
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1212, len: >>1210, fin: false }
server  | 1.078 DEBUG packet_sent this=0x561cd2a59b40, pn=3, ps=1232
server  | 1.078 DEBUG ECN probing: sent 7 probes
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] delay duration 1.651929ms
server  | 1.078 DEBUG Setting timeout of 1.651929ms
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.078 DEBUG [Server 60e0acadf32892b097f6] delay duration 1.632202ms
server  | 1.078 DEBUG Setting timeout of 1.632202ms
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.081 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2422, len: >>1210, fin: false }
server  | 1.081 DEBUG packet_sent this=0x561cd2a59b40, pn=4, ps=1232
server  | 1.081 DEBUG ECN probing: sent 8 probes
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.081 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3632, len: >>1210, fin: false }
server  | 1.081 DEBUG packet_sent this=0x561cd2a59b40, pn=5, ps=1232
server  | 1.081 DEBUG ECN probing: sent 9 probes
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.081 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.081 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] delay duration 1.642043ms
server  | 1.081 DEBUG Setting timeout of 1.642043ms
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.081 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.081 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.081 DEBUG [Server 60e0acadf32892b097f6] delay duration 1.620033ms
server  | 1.081 DEBUG Setting timeout of 1.620033ms
server  | 1.084 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.084 DEBUG [Server 60e0acadf32892b097f6] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 1168, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Stream { stream_id: 0, offset: 4842, len: >>1201, fin: false }
server  | 1.084 DEBUG packet_sent this=0x561cd2a59b40, pn=6, ps=1232
server  | 1.084 DEBUG ECN probing: sent 10 probes
server  | 1.084 DEBUG ECN probing concluded with 10 probes sent
server  | 1.084 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.085 DEBUG [Server 60e0acadf32892b097f6] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 6043, len: >>1210, fin: false }
server  | 1.085 DEBUG packet_sent this=0x561cd2a59b40, pn=7, ps=1232
server  | 1.085 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.085 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.085 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.085 DEBUG [Server 60e0acadf32892b097f6] delay duration 1.616783ms
server  | 1.085 DEBUG Setting timeout of 1.616783ms
server  | 1.085 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.085 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.085 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.085 DEBUG [Server 60e0acadf32892b097f6] delay duration 1.592167ms
server  | 1.085 DEBUG Setting timeout of 1.592167ms
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] pn=8 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 7253, len: >>1210, fin: false }
server  | 1.087 DEBUG packet_sent this=0x561cd2a59b40, pn=8, ps=1232
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1076, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] pn=9 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 1076
server  |   TX -> Stream { stream_id: 0, offset: 8463, len: >>1054, fin: false }
server  | 1.087 DEBUG packet_sent this=0x561cd2a59b40, pn=9, ps=1076
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.087 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] delay duration 107.545412ms
server  | 1.087 DEBUG Setting timeout of 107.545412ms
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.087 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG [Server 60e0acadf32892b097f6] delay duration 107.52825ms
server  | 1.087 DEBUG Setting timeout of 107.52825ms
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] pn=1 type=Handshake pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 44
client  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] Rx ACK space=hs, ranges=[0..=1]
client  | 0.109 DEBUG [LossRecovery] ACK for Handshake - largest_acked=1
client  | 0.109 DEBUG on_packets_acked this=0x55e10b5e7970, limited=1, bytes_in_flight=55, cwnd=12520, state=SlowStart, new_acked=75
client  | 0.109 DEBUG Acked crypto frame space=hs offset=0 length=36
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] pn=0 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1288
client  |   -> RX HandshakeDone
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [98, 225, 232, 50, 227, 188, 91, 30, 131, 133], stateless_reset_token: [54, 200, 133, 60, 248, 254, 235, 249, 133, 195, 233, 126, 254, 162, 165, 249] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [145, 222, 248, 92, 44, 232, 30, 40, 103, 65], stateless_reset_token: [214, 44, 53, 131, 216, 14, 112, 36, 117, 211, 56, 21, 96, 255, 202, 77] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [89, 154, 204, 4, 21, 158, 83, 64, 85, 89], stateless_reset_token: [159, 60, 206, 237, 98, 27, 202, 39, 209, 32, 112, 191, 216, 139, 86, 69] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [223, 225, 117, 49, 38, 159, 19, 157, 105, 234], stateless_reset_token: [68, 102, 150, 81, 113, 186, 59, 241, 193, 178, 122, 103, 153, 248, 78, 128] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [125, 32, 205, 180, 40, 68, 82, 62, 28, 47], stateless_reset_token: [232, 115, 201, 139, 134, 20, 65, 17, 106, 73, 21, 120, 39, 145, 165, 171] }
client  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [223, 200, 37, 15, 120, 215, 131, 87, 50, 112], stateless_reset_token: [201, 118, 192, 233, 85, 154, 254, 239, 117, 6, 243, 29, 202, 153, 95, 91] }
client  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [109, 92, 15, 254, 153, 251, 92, 10, 168, 183], stateless_reset_token: [194, 154, 162, 121, 175, 162, 65, 63, 14, 204, 187, 78, 182, 206, 43, 21] }
client  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 8616, ignore_order: false }
client  |   -> RX Padding { len: 1052 }
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] State change from Connected -> Confirmed
client  | 0.109 DEBUG PMTUD started with probe size 1380
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] Drop packet number space hs
client  | 0.109 DEBUG [LossRecovery] Reset loss recovery state for Handshake
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] No preferred address to migrate to
client  | 0.109 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 647367020 }
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.109 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] pn=1 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1323 }
client  | 0.109 DEBUG packet_sent this=0x55e10b5e7970, pn=1, ps=1352
client  | 0.109 DEBUG ECN probing: sent 8 probes
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.243896ms
client  | 0.109 DEBUG Setting timeout of 8.243896ms
client  | 0.109 WARN Unhandled event StateChange(Confirmed)
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.109 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.204303ms
client  | 0.109 DEBUG Setting timeout of 8.204303ms
client  | 0.110 DEBUG [Client 1cdfe3d7bf312d1e] pn=1 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Ping
client  |   -> RX Padding { len: 1313 }
client  | 0.110 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 439, tv_nsec: 639797031 }
client  | 0.110 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 639797031 }
client  | 0.110 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.110 DEBUG [Client 1cdfe3d7bf312d1e] pn=2 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.110 DEBUG ECN probing: sent 9 probes
client  | 0.110 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.110 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 95.169872ms
client  | 0.110 DEBUG Setting timeout of 95.169872ms
client  | 0.110 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.110 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 95.143843ms
client  | 0.110 DEBUG Setting timeout of 95.143843ms
client  | 0.111 DEBUG [Client 1cdfe3d7bf312d1e] pn=2 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 0, len: >>1212, fin: false }
client  | 0.111 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 649569377 }
client  | 0.111 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.593137ms
client  | 0.111 DEBUG Setting timeout of 8.593137ms
client  | 0.111 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.573581ms
client  | 0.111 DEBUG Setting timeout of 8.573581ms
client  | 0.112 DEBUG [Client 1cdfe3d7bf312d1e] pn=3 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 1212, len: >>1210, fin: false }
client  | 0.112 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 641970684 }
client  | 0.112 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.112 DEBUG [Client 1cdfe3d7bf312d1e] pn=3 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 8, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
client  | 0.112 DEBUG ECN probing: sent 10 probes
client  | 0.112 DEBUG ECN probing concluded with 10 probes sent
client  | 0.112 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 92.947517ms
client  | 0.112 DEBUG Setting timeout of 92.947517ms
client  | 0.112 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 92.92253ms
client  | 0.112 DEBUG Setting timeout of 92.92253ms
client  | 0.115 DEBUG [Client 1cdfe3d7bf312d1e] pn=4 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 2422, len: >>1210, fin: false }
client  | 0.115 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 652839393 }
client  | 0.115 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.588208ms
client  | 0.115 DEBUG Setting timeout of 8.588208ms
client  | 0.115 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.565045ms
client  | 0.115 DEBUG Setting timeout of 8.565045ms
client  | 0.116 DEBUG [Client 1cdfe3d7bf312d1e] pn=5 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 3632, len: >>1210, fin: false }
client  | 0.116 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 645227425 }
client  | 0.116 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.116 DEBUG [Client 1cdfe3d7bf312d1e] pn=4 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, NotEct) len 36
client  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
client  | 0.116 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 89.734217ms
client  | 0.116 DEBUG Setting timeout of 89.734217ms
client  | 0.116 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 89.707888ms
client  | 0.116 DEBUG Setting timeout of 89.707888ms
client  | 0.118 DEBUG [Client 1cdfe3d7bf312d1e] pn=6 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 1168, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  |   -> RX Stream { stream_id: 0, offset: 4842, len: >>1201, fin: false }
client  | 0.118 DEBUG [Client 1cdfe3d7bf312d1e] Rx ACK space=ap, ranges=[0..=0]
client  | 0.118 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0
client  | 0.118 DEBUG on_packets_acked this=0x55e10b5e7970, limited=1, bytes_in_flight=1352, cwnd=12520, state=SlowStart, new_acked=55
client  | 0.118 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 656161048 }
client  | 0.118 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.118 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.118 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.118 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.570776ms
client  | 0.118 DEBUG Setting timeout of 8.570776ms
client  | 0.118 DEBUG stream 0 complete
client  | 0.118 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.118 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.118 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.118 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.549035ms
client  | 0.118 DEBUG Setting timeout of 8.549035ms
client  | 0.119 DEBUG [Client 1cdfe3d7bf312d1e] pn=7 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 6043, len: >>1210, fin: false }
client  | 0.119 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 648576882 }
client  | 0.119 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.119 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.119 DEBUG [Client 1cdfe3d7bf312d1e] pn=5 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, NotEct) len 36
client  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
client  | 0.119 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.119 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.119 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.119 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 70.905067ms
client  | 0.119 DEBUG Setting timeout of 70.905067ms
client  | 0.119 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.119 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.119 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.119 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 70.871875ms
client  | 0.119 DEBUG Setting timeout of 70.871875ms
client  | 0.120 DEBUG [Client 1cdfe3d7bf312d1e] pn=8 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, NotEct) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 7253, len: >>1210, fin: false }
client  | 0.120 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 658418307 }
client  | 0.120 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.120 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.120 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.120 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.594229ms
client  | 0.120 DEBUG Setting timeout of 8.594229ms
client  | 0.120 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.120 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.120 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.120 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 8.580023ms
client  | 0.120 DEBUG Setting timeout of 8.580023ms
client  | 0.121 DEBUG [Client 1cdfe3d7bf312d1e] pn=9 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, NotEct) len 1076
client  |   -> RX Stream { stream_id: 0, offset: 8463, len: >>1054, fin: false }
client  | 0.121 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 650687959 }
client  | 0.121 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.121 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.121 DEBUG [Client 1cdfe3d7bf312d1e] pn=6 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, NotEct) len 36
client  |   TX -> Ack { largest_acknowledged: 9, ack_delay: 2, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
client  | 0.121 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.121 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.121 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.121 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 68.829005ms
client  | 0.121 DEBUG Setting timeout of 68.829005ms
client  | 0.121 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.121 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.121 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.121 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 68.806403ms
client  | 0.121 DEBUG Setting timeout of 68.806403ms
server  | 1.108 DEBUG [Server 60e0acadf32892b097f6] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1352
server  |   -> RX Ping
server  |   -> RX Padding { len: 1323 }
server  | 1.109 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 449, tv_nsec: 655364952 }
server  | 1.109 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 449, tv_nsec: 655364952 }
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] pn=10 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 26
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.109 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] delay duration 85.738957ms
server  | 1.109 DEBUG Setting timeout of 85.738957ms
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=ap, ranges=[0..=1]
server  | 1.109 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.109 INFO ECN validation succeeded, path is capable
server  | 1.109 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9700/14940 ssthresh 18446744073709551615] slow start += 2620
server  | 1.109 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9700/14940 ssthresh 18446744073709551615] on_packets_acked this=0x561cd2a59b40, limited=0, bytes_in_flight=9700, cwnd=14940, state=SlowStart, new_acked=2620
server  | 1.109 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.109 DEBUG PMTUD started with probe size 1420
server  | 1.109 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.109 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.109 DEBUG CRYPTO for ap offset=0, len=321
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] pn=11 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 9517, len: 723, fin: true }
server  |   TX -> Crypto { offset: 0, len: 321 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 213, 151, 193, 141, 93, 135, 82, 149, 234, 26, 168, 82, 143, 156, 21, 16, 206, 180, 156, 185, 202, 156, 246, 50, 186, 122, 36, 77, 166, 196, 219, 44, 38, 186, 85, 96] }
server  |   TX -> Padding { len: 254 }
server  | 1.109 DEBUG packet_sent this=0x561cd2a59b40, pn=11, ps=1372
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.109 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] delay duration 84.034456ms
server  | 1.109 DEBUG Setting timeout of 84.034456ms
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.109 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.109 DEBUG [Server 60e0acadf32892b097f6] delay duration 84.013537ms
server  | 1.109 DEBUG Setting timeout of 84.013537ms
server  | 1.110 DEBUG [Server 60e0acadf32892b097f6] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 8, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  | 1.110 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=ap, ranges=[0..=3]
server  | 1.110 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 1.110 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8608/17404 ssthresh 18446744073709551615] slow start += 2464
server  | 1.110 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8608/17404 ssthresh 18446744073709551615] on_packets_acked this=0x561cd2a59b40, limited=0, bytes_in_flight=8608, cwnd=17404, state=SlowStart, new_acked=2464
server  | 1.110 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG [Server 60e0acadf32892b097f6] delay duration 73.714203ms
server  | 1.110 DEBUG Setting timeout of 73.714203ms
server  | 1.110 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.111 DEBUG [Server 60e0acadf32892b097f6] delay duration 73.652798ms
server  | 1.111 DEBUG Setting timeout of 73.652798ms
server  | 1.114 DEBUG [Server 60e0acadf32892b097f6] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
server  | 1.114 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=ap, ranges=[0..=5]
server  | 1.114 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5
server  | 1.114 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6144/19868 ssthresh 18446744073709551615] slow start += 2464
server  | 1.114 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6144/19868 ssthresh 18446744073709551615] on_packets_acked this=0x561cd2a59b40, limited=0, bytes_in_flight=6144, cwnd=19868, state=SlowStart, new_acked=2464
server  | 1.114 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.114 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.114 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.114 DEBUG [Server 60e0acadf32892b097f6] delay duration 63.777056ms
server  | 1.114 DEBUG Setting timeout of 63.777056ms
server  | 1.114 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.114 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.114 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.114 DEBUG [Server 60e0acadf32892b097f6] delay duration 63.719318ms
server  | 1.114 DEBUG Setting timeout of 63.719318ms
server  | 1.117 DEBUG [Server 60e0acadf32892b097f6] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 1.117 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=ap, ranges=[0..=7]
server  | 1.117 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
server  | 1.117 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3680/22332 ssthresh 18446744073709551615] slow start += 2464
server  | 1.117 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3680/22332 ssthresh 18446744073709551615] on_packets_acked this=0x561cd2a59b40, limited=0, bytes_in_flight=3680, cwnd=22332, state=SlowStart, new_acked=2464
server  | 1.117 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.117 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG [Server 60e0acadf32892b097f6] delay duration 55.045319ms
server  | 1.117 DEBUG Setting timeout of 55.045319ms
server  | 1.117 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.117 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG [Server 60e0acadf32892b097f6] delay duration 54.990977ms
server  | 1.117 DEBUG Setting timeout of 54.990977ms
server  | 1.119 DEBUG [Server 60e0acadf32892b097f6] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 2, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 1.119 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=ap, ranges=[0..=9]
server  | 1.119 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9
server  | 1.119 WARN ECN validation failed, no ECT(0) packets were newly acked
server  | 1.119 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] slow start += 2308
server  | 1.119 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] on_packets_acked this=0x561cd2a59b40, limited=0, bytes_in_flight=1372, cwnd=24640, state=SlowStart, new_acked=2308
server  | 1.119 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.119 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.119 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.119 DEBUG [Server 60e0acadf32892b097f6] delay duration 48.952141ms
server  | 1.119 DEBUG Setting timeout of 48.952141ms
server  | 1.119 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.119 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.119 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.119 DEBUG [Server 60e0acadf32892b097f6] delay duration 48.896657ms
server  | 1.119 DEBUG Setting timeout of 48.896657ms
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] pn=10 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, NotEct) len 26
client  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] Rx ACK space=ap, ranges=[0..=1]
client  | 0.141 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
client  | 0.141 INFO ECN validation succeeded, path is capable
client  | 0.141 DEBUG on_packets_acked this=0x55e10b5e7970, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=1352
client  | 0.141 DEBUG PMTUD probe of size 1380 succeeded
client  | 0.141 DEBUG PMTUD started with probe size 1420
client  | 0.141 DEBUG PLPMTU changed from 1252 to 1352, updating pacer
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.141 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.141 DEBUG Sending PMTUD probe of size 1420, count 1
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] pn=7 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1392
client  |   TX -> Ping
client  |   TX -> Padding { len: 1363 }
client  | 0.141 DEBUG packet_sent this=0x55e10b5e7970, pn=7, ps=1392
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.141 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.141 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 76.435447ms
client  | 0.141 DEBUG Setting timeout of 76.435447ms
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.141 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.141 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.141 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 76.398889ms
client  | 0.141 DEBUG Setting timeout of 76.398889ms
client  | 0.142 DEBUG [Client 1cdfe3d7bf312d1e] pn=11 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 1372
client  |   -> RX Ping
client  |   -> RX Stream { stream_id: 0, offset: 9517, len: 723, fin: true }
client  |   -> RX Crypto { offset: 0, len: 321 }
client  |   -> RX NewToken { token: [173, 154, 139, 141, 134, 1, 0, 213, 151, 193, 141, 93, 135, 82, 149, 234, 26, 168, 82, 143, 156, 21, 16, 206, 180, 156, 185, 202, 156, 246, 50, 186, 122, 36, 77, 166, 196, 219, 44, 38, 186, 85, 96] }
client  |   -> RX Padding { len: 254 }
client  | 0.142 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 439, tv_nsec: 671991409 }
client  | 0.142 DEBUG Read Ok(321) bytes
client  | 0.142 DEBUG [0x55e10b5d4270] Got resumption token [986]: 02000633fe5d75e3..2fd5584dca19d009
client  | 0.142 DEBUG [Agent 0x55e10b5d4270] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.142 DEBUG resumption token [1148]: 0000000124406f00..2fd5584dca19d009
client  | 0.143 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 439, tv_nsec: 671991409 }
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.143 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] pn=8 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 24, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.143 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.143 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 75.055293ms
client  | 0.143 DEBUG Setting timeout of 75.055293ms
client  | 0.143 DEBUG stream BiDi creatable
client  | 0.143 DEBUG stream UniDi creatable
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.143 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.143 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] delay duration 74.975324ms
client  | 0.143 DEBUG Setting timeout of 74.975324ms
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 439, tv_nsec: 901975496 } }
client  | 0.143 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 439, tv_nsec: 901975496 } })
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.143 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.143 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 439, tv_nsec: 672356069 }
client  | 0.143 DEBUG [Client 1cdfe3d7bf312d1e] pn=9 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 45, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.143 DEBUG Setting timeout of 229.539971ms
server  | 1.140 DEBUG [Server 60e0acadf32892b097f6] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 1392
server  |   -> RX Ping
server  |   -> RX Padding { len: 1363 }
server  | 1.140 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 449, tv_nsec: 687251281 }
server  | 1.140 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 449, tv_nsec: 687251281 }
server  | 1.140 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.140 DEBUG [Server 60e0acadf32892b097f6] pn=12 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 26
server  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  | 1.140 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.140 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG [Server 60e0acadf32892b097f6] delay duration 27.522122ms
server  | 1.141 DEBUG Setting timeout of 27.522122ms
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.141 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.141 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] delay duration 27.495102ms
server  | 1.141 DEBUG Setting timeout of 27.495102ms
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] pn=8 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 24, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=ap, ranges=[0..=11]
server  | 1.141 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=11
server  | 1.141 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] slow start += 1372
server  | 1.141 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] on_packets_acked this=0x561cd2a59b40, limited=0, bytes_in_flight=0, cwnd=26012, state=SlowStart, new_acked=1372
server  | 1.141 DEBUG PMTUD probe of size 1420 succeeded
server  | 1.141 DEBUG PMTUD started with probe size 1470
server  | 1.141 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 1.141 DEBUG Acked crypto frame space=ap offset=0 length=321
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.141 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.141 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] pn=13 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 1422
server  |   TX -> Ping
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  |   TX -> Padding { len: 1401 }
server  | 1.141 DEBUG packet_sent this=0x561cd2a59b40, pn=13, ps=1422
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.141 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.141 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] delay duration 56.244817ms
server  | 1.141 DEBUG Setting timeout of 56.244817ms
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] pn=9 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, Ect0) len 47
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 45, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] Rx ACK space=ap, ranges=[0..=11]
server  | 1.141 INFO [Server 60e0acadf32892b097f6] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye!
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 449, tv_nsec: 856772568 } }
server  | 1.141 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:49360] Path validated Instant { tv_sec: 449, tv_nsec: 687682284 }
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.141 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.141 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 449, tv_nsec: 687682284 }
server  | 1.141 DEBUG [Server 60e0acadf32892b097f6] pn=14 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:49360 IpTos(Cs0, NotEct) len 30
server  |   TX -> Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 1.141 DEBUG Setting timeout of 169.001588ms
server  | 1.141 DEBUG Setting timeout of 168.989966ms
client  | 0.173 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.173 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.173 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 439, tv_nsec: 702615560 }
client  | 0.173 DEBUG [Client 1cdfe3d7bf312d1e] pn=10 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 3828, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.173 DEBUG Setting timeout of 199.234863ms
client  | 0.173 DEBUG Setting timeout of 199.218282ms
client  | 0.174 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.174 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.174 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 439, tv_nsec: 704087415 }
client  | 0.174 DEBUG [Client 1cdfe3d7bf312d1e] pn=11 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 4012, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.174 DEBUG Setting timeout of 197.814714ms
client  | 0.174 DEBUG [Client 1cdfe3d7bf312d1e] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.174 DEBUG Building Short dcid Some(CID [10]: 7194fb9fffac5c55376e)
client  | 0.174 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 439, tv_nsec: 704173676 }
client  | 0.175 DEBUG [Client 1cdfe3d7bf312d1e] pn=12 type=Short pri-path:7194fb9fffac5c55376e 0.0.0.0:49360->193.167.100.100:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 4022, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.175 DEBUG Setting timeout of 197.744614ms
client  | 0.175 DEBUG Setting timeout of 197.735376ms
server  | 1.171 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 449, tv_nsec: 856772568 } }; Total: 3
server  | 1.171 DEBUG Setting timeout of 138.700377ms
server  | 1.171 DEBUG Setting timeout of 138.666474ms
server  | 1.172 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 449, tv_nsec: 856772568 } }; Total: 4
server  | 1.172 DEBUG Setting timeout of 137.416954ms
server  | 1.172 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 449, tv_nsec: 856772568 } }; Total: 5
server  | 1.172 DEBUG Setting timeout of 137.398148ms
server  | 1.172 DEBUG Setting timeout of 137.388661ms
server  | 1.311 DEBUG [Server 60e0acadf32892b097f6] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 449, tv_nsec: 856772568 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 1.311 INFO Closing timer expired
client  | 0.374 DEBUG [Client 1cdfe3d7bf312d1e] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 439, tv_nsec: 901975496 } } -> Closed(Application(0))
client  | 0.374 INFO Closing timer expired
client  | 0.374 DEBUG Output::None
client  | 0.374 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 0.374 DEBUG Timer fired while closed
client  | 0.374 DEBUG Output::None

client exited with code 0
Aborting on container exit...
 Container client  Stopping
 Container server  Stopping
 Container client  Stopped
 Container server  Stopped
 Container sim  Stopping
 Container sim  Stopped

2025-04-30 12:50:53,659 Using the client's key log file.
2025-04-30 12:50:53,670 
2025-04-30 12:50:53,671 Using the client's key log file.
2025-04-30 12:50:53,671 Using the client's key log file.
2025-04-30 12:50:53,681 
2025-04-30 12:50:53,682 Using the client's key log file.
2025-04-30 12:50:54,297 Check of downloaded files succeeded.
2025-04-30 12:50:54,907 Check of Retry succeeded. Token used: 5265747279010019b5c4e3f8aad9b1264287010c04f0a651bc93c0962430a5b0a3b8ce52936a9898c75c20e979418e82e683f2