2025-04-30 12:49:35,488 Generated random file: voklwkgyxp of size: 1024
2025-04-30 12:49:35,488 Requests: https://server4:443/voklwkgyxp
2025-04-30 12:49:35,548 
2025-04-30 12:49:35,549 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_78yhudg3/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_zf5x8ahr/ DOWNLOADS=/tmp/download__g53nccl/ SERVER_LOGS=/tmp/logs_server_6zt6lhgt CLIENT_LOGS=/tmp/logs_client_wvnb8uh0 SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/voklwkgyxp"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:50:00,344  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container server  Recreated
 Container client  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
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 handshake ']'
client  | + '[' -n /logs/qlog/ ']'
client  | + case "$ROLE" in
client  | + /wait-for-it.sh sim:57832 -s -t 30
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
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 handshake ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.UX2KzRl4s0
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.UX2KzRl4s0
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.UX2KzRl4s0 -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  |             2a:ea:41:2e:e3:fc:7d:08:07:7c:ae:9c:26:fc:78:d8:
server  |             6d:d2:3b:0b
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:49:35 2025
server  |             Not After : Sat May 10 12:49:35 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:e2:4a:d1:a3:88:7d:74:79:9e:13:45:81:f3:80:34:
server  |                     94:b5:8b:a8:9c:f1:eb:d1:31:dd:7e:4d:ae:a8:32:7d:
server  |                     a3:c4:12:df:72:29:b9:45:a9:9f:df:12:ea:6b:7d:7d:
server  |                     b7:30:1f:6c:3a:a3:e2:25:c1:27:a6:8a:f9:df:1b:7d:
server  |                     d3
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  |                 65:d0:fd:d6:16:79:fc:3a:0c:7d:64:81:0d:59:4d:3b:
server  |                 6b:a4:1f:6e
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 1f:2b:da:71:19:d7:76:4d:13:4b:4c:b2:69:92:8c:49:
server  |                 d5:29:b2:00
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:20:70:7b:99:6e:39:60:10:15:3f:69:6d:3e:
server  |         3c:ab:fe:f5:aa:b6:31:f4:77:c9:f4:75:47:c0:82:2c:
server  |         85:c5:ac:f5:02:21:00:88:2a:ca:d6:62:0c:bf:17:3d:
server  |         62:ec:22:c2:37:77:0c:a3:80:7a:5c:4a:23:c3:81:b0:
server  |         33:45:6b:63:54:47:53
server  |     Fingerprint (SHA-256):
server  |         CA:22:1C:20:4E:53:C4:F9:3E:39:A5:B6:8A:17:A1:D2:DD:50:8F:AA:12:70:1A:0A:80:CC:79:27:60:DC:76:9D
server  |     Fingerprint (SHA1):
server  |         D2:CB:89:D8:BE:BF:B8:73:9E:8B:10:18:E8:72:61:DF:66:06:89:04
server  | 
server  |     Mozilla-CA-Policy: false (attribute missing)
server  |     Certificate Trust Flags:
server  |         SSL Flags:
server  |             User
server  |         Email Flags:
server  |             User
server  |         Object Signing Flags:
server  |             User
server  | 
server  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT")
server  | + '[' handshake = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443'
server  | ++ tee -i -a /logs/server.log
server  | 0.000 DEBUG Logging initialized
server  | 0.002 DEBUG Default socket send buffer size is 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.793 DEBUG [Server] Unsupported version: 57414954
server  | 0.793 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:33047 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.05051198s
server  | 0.793 DEBUG [Server] Unsupported version: 57414954
server  | 0.793 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:33047 IpTos(Cs0, NotEct) len 15
server  | 0.793 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.793 DEBUG [Server] Unsupported version: 57414954
server  | 0.793 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:33047 IpTos(Cs0, NotEct) len 15
server  | 0.793 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: tcpdump: listening on eth1listening on eth0, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | , 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/voklwkgyxp ']'
client  | + mapfile -d ' ' -t URLS
client  | + OPTIONS+=("${URLS[@]}")
client  | + RUST_LOG=debug
client  | + RUST_BACKTRACE=1
client  | + neqo-client --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/voklwkgyxp
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:47882 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=6214971d9e562ea9
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:47882->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443] Path validated Instant { tv_sec: 373, tv_nsec: 879073462 }
client  | 0.001 DEBUG [Client 6214971d9e562ea9] client_start
client  | 0.001 DEBUG Writing transport parameters, msg=1
client  | 0.001 DEBUG [Agent 0x55888a36d270] state -> InProgress
client  | 0.001 DEBUG [Client 6214971d9e562ea9] State change from Init -> WaitInitial
client  | 0.001 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.001 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.001 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.001 DEBUG [Client 6214971d9e562ea9] pn=0 type=Initial pri-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.001 DEBUG packet_sent this=0x55888a380970, pn=0, ps=1252
client  | 0.001 DEBUG ECN probing: sent 1 probes
client  | 0.002 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1213, len=326
client  | 0.002 DEBUG [Client 6214971d9e562ea9] pn=1 type=Initial pri-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 366
client  |   TX -> Crypto { offset: 1213, len: 326 }
client  | 0.002 DEBUG [Client 6214971d9e562ea9] pad Initial from 366 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55888a380970, pn=1, ps=1252
client  | 0.002 DEBUG ECN probing: sent 2 probes
client  | 0.002 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) 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 6214971d9e562ea9] delay duration 3.876457ms
client  | 0.002 DEBUG Setting timeout of 3.876457ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) 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 6214971d9e562ea9] delay duration 3.853074ms
client  | 0.002 DEBUG Setting timeout of 3.853074ms
client  | 0.007 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) 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 6214971d9e562ea9] delay duration 294.842542ms
client  | 0.007 DEBUG Setting timeout of 294.842542ms
client  | 0.303 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 0.303 DEBUG [Client 6214971d9e562ea9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 })
client  | 0.303 INFO Lost crypto frame space=in offset=0 length=1213
client  | 0.303 DEBUG [Client 6214971d9e562ea9] Lost: EcnEct0
client  | 0.303 DEBUG [Client 6214971d9e562ea9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 })
client  | 0.303 INFO Lost crypto frame space=in offset=1213 length=326
client  | 0.303 DEBUG [Client 6214971d9e562ea9] Lost: EcnEct0
client  | 0.303 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.303 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.303 DEBUG [Client 6214971d9e562ea9] pn=2 type=Initial pri-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.303 DEBUG packet_sent this=0x55888a380970, pn=2, ps=1252
client  | 0.303 DEBUG ECN probing: sent 3 probes
client  | 0.303 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.303 DEBUG CRYPTO for in offset=1213, len=326
client  | 0.303 DEBUG [Client 6214971d9e562ea9] pn=3 type=Initial pri-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 366
client  |   TX -> Crypto { offset: 1213, len: 326 }
client  | 0.303 DEBUG [Client 6214971d9e562ea9] pad Initial from 366 to PLPMTU 1252
client  | 0.303 DEBUG packet_sent this=0x55888a380970, pn=3, ps=1252
client  | 0.303 DEBUG ECN probing: sent 4 probes
client  | 0.304 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.304 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.304 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.304 DEBUG [Client 6214971d9e562ea9] delay duration 599.898992ms
client  | 0.304 DEBUG Setting timeout of 599.898992ms
server  | 1.708 DEBUG [Server] Handle initial
server  | 1.708 INFO AddressValidation: no token; accepting
server  | 1.708 INFO [Server] Accept connection CID [8]: 6214971d9e562ea9
server  | 1.708 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.710 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.710 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: 6214971d9e562ea9
server  | 1.710 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=6214971d9e562ea9
server  | 1.710 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.710 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.710 DEBUG [Server 6214971d9e562ea9] State change from Init -> WaitInitial
server  | 1.710 DEBUG [Server 6214971d9e562ea9] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1252
server  |   -> RX Crypto { offset: 0, len: 1213 }
server  | 1.710 DEBUG Read Ok(1213) bytes
server  | 1.710 DEBUG [Agent 0x55f981a701d0] state -> InProgress
server  | 1.710 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 636652050 }
server  | 1.710 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:47882] Make permanent
server  | 1.710 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:47882] set as primary path
server  | 1.710 DEBUG [Server 6214971d9e562ea9] State change from WaitInitial -> WaitVersion
server  | 1.710 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.711 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.711 DEBUG [Server 6214971d9e562ea9] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 45
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.711 DEBUG ECN probing: sent 1 probes
server  | 1.711 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.711 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.711 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.711 DEBUG [Server 6214971d9e562ea9] delay duration 297.664807ms
server  | 1.711 DEBUG Setting timeout of 297.664807ms
server  | 1.711 DEBUG [Server 6214971d9e562ea9] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 366
server  |   -> RX Crypto { offset: 1213, len: 326 }
server  | 1.711 DEBUG Read Ok(326) bytes
server  | 1.711 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.711 DEBUG Read secret available for Handshake: SymKey [32]: 4118588614e189e0010c440e4a68453f2b703f92136d0aaf1c908fc80111ec07
server  | 1.711 DEBUG Write secret available for Handshake: SymKey [32]: 249d6da9694bca710f5c9cdd742562ddbaf972a9c30419a7a3746b0ef4dca39b
server  | 1.711 DEBUG Writing transport parameters, msg=8
server  | 1.712 DEBUG Read secret available for ApplicationData: SymKey [32]: 597ac37d20d2d0f1695ce13ed437fc320b30610d5ea2ce66838961b9ab0e5f31
server  | 1.712 DEBUG Write secret available for ApplicationData: SymKey [32]: 0c5f0c87b2871a01062dfa4b70eb4814654bdcf3a07662c722cb46a789810bdf
server  | 1.712 DEBUG [Agent 0x55f981a701d0] state -> InProgress
server  | 1.712 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.712 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.712 DEBUG [Crypto] Handshake keys installed
server  | 1.712 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.712 DEBUG [Crypto] Application write key installed
server  | 1.712 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 639040933 }
server  | 1.712 DEBUG [Server 6214971d9e562ea9] State change from WaitVersion -> Handshaking
server  | 1.712 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1
server  | 1.712 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.712 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG CRYPTO for in offset=0, len=1178
server  | 1.712 DEBUG [Server 6214971d9e562ea9] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1227
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  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 1.712 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG [Server 6214971d9e562ea9] pad Initial from 1227 to PLPMTU 1232
server  | 1.712 DEBUG packet_sent this=0x55f981a6fb40, pn=1, ps=1232
server  | 1.712 DEBUG ECN probing: sent 2 probes
server  | 1.712 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.712 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG CRYPTO for hs offset=0, len=732
server  | 1.712 DEBUG [Server 6214971d9e562ea9] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 772
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 1.712 DEBUG packet_sent this=0x55f981a6fb40, pn=0, ps=772
server  | 1.712 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.712 DEBUG ECN probing: sent 3 probes
server  | 1.712 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.712 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.712 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.712 DEBUG [Server 6214971d9e562ea9] delay duration 298.676033ms
server  | 1.712 DEBUG Setting timeout of 298.676033ms
server  | 1.712 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.712 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 1.712 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.712 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.712 DEBUG [Server 6214971d9e562ea9] delay duration 298.648451ms
server  | 1.712 DEBUG Setting timeout of 298.648451ms
client  | 0.905 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 0.905 DEBUG [Client 6214971d9e562ea9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 })
client  | 0.905 INFO Lost crypto frame space=in offset=0 length=1213
client  | 0.905 DEBUG [Client 6214971d9e562ea9] Lost: EcnEct0
client  | 0.905 DEBUG ECN validation failed, all 3 initial marked packets were lost
client  | 0.905 DEBUG [Client 6214971d9e562ea9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 326 })
client  | 0.905 INFO Lost crypto frame space=in offset=1213 length=326
client  | 0.905 DEBUG [Client 6214971d9e562ea9] Lost: EcnEct0
client  | 0.905 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 0.905 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.905 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.905 DEBUG [Client 6214971d9e562ea9] pn=4 type=Initial pri-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.905 DEBUG packet_sent this=0x55888a380970, pn=4, ps=1252
client  | 0.906 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.906 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.906 DEBUG CRYPTO for in offset=1213, len=326
client  | 0.906 DEBUG [Client 6214971d9e562ea9] pn=5 type=Initial pri-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 366
client  |   TX -> Crypto { offset: 1213, len: 326 }
client  | 0.906 DEBUG [Client 6214971d9e562ea9] pad Initial from 366 to PLPMTU 1252
client  | 0.906 DEBUG packet_sent this=0x55888a380970, pn=5, ps=1252
client  | 0.906 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.906 DEBUG Building Initial dcid Some(CID [8]: 6214971d9e562ea9) scid Some(CID [0]: )
client  | 0.906 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.906 DEBUG [Client 6214971d9e562ea9] delay duration 1.199916394s
client  | 0.906 DEBUG Setting timeout of 1.199916394s
server  | 2.005 DEBUG [Server 6214971d9e562ea9] pn=2 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1252
server  |   -> RX Crypto { offset: 0, len: 1213 }
server  | 2.005 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 933413162 }
server  | 2.005 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG CRYPTO for in offset=0, len=1178
server  | 2.005 DEBUG [Server 6214971d9e562ea9] pn=2 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1227
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 2.005 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG [Server 6214971d9e562ea9] pad Initial from 1227 to PLPMTU 1232
server  | 2.005 DEBUG packet_sent this=0x55f981a6fb40, pn=2, ps=1232
server  | 2.005 DEBUG ECN probing: sent 4 probes
server  | 2.005 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG CRYPTO for hs offset=0, len=732
server  | 2.005 DEBUG [Server 6214971d9e562ea9] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 772
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 2.005 DEBUG packet_sent this=0x55f981a6fb40, pn=1, ps=772
server  | 2.005 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.005 DEBUG ECN probing: sent 5 probes
server  | 2.005 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.005 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG [Server 6214971d9e562ea9] delay duration 299.711512ms
server  | 2.005 DEBUG Setting timeout of 299.711512ms
server  | 2.005 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.005 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.005 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG [Server 6214971d9e562ea9] delay duration 299.676707ms
server  | 2.005 DEBUG Setting timeout of 299.676707ms
server  | 2.006 DEBUG [Server 6214971d9e562ea9] pn=3 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 366
server  |   -> RX Crypto { offset: 1213, len: 326 }
server  | 2.006 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 934238971 }
server  | 2.006 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 2
server  | 2.006 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.006 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.006 DEBUG CRYPTO for in offset=0, len=1178
server  | 2.006 DEBUG [Server 6214971d9e562ea9] pn=3 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1227
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 2.006 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.006 DEBUG [Server 6214971d9e562ea9] pad Initial from 1227 to PLPMTU 1232
server  | 2.006 DEBUG packet_sent this=0x55f981a6fb40, pn=3, ps=1232
server  | 2.006 DEBUG ECN probing: sent 6 probes
server  | 2.006 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.006 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.006 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.006 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.006 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.006 DEBUG [Server 6214971d9e562ea9] delay duration 2.192926ms
server  | 2.006 DEBUG Setting timeout of 2.192926ms
server  | 2.006 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.006 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.006 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.006 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.006 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.006 DEBUG [Server 6214971d9e562ea9] delay duration 2.160235ms
server  | 2.006 DEBUG Setting timeout of 2.160235ms
server  | 2.009 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.009 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.009 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.009 DEBUG CRYPTO for hs offset=0, len=732
server  | 2.009 DEBUG [Server 6214971d9e562ea9] pn=2 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 772
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 2.009 DEBUG packet_sent this=0x55f981a6fb40, pn=2, ps=772
server  | 2.009 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.009 DEBUG ECN probing: sent 7 probes
server  | 2.009 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.009 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.009 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.009 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.009 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.009 DEBUG [Server 6214971d9e562ea9] delay duration 2.138734ms
server  | 2.009 DEBUG Setting timeout of 2.138734ms
server  | 2.009 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.009 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.009 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.009 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.009 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.009 DEBUG [Server 6214971d9e562ea9] delay duration 2.113537ms
server  | 2.009 DEBUG Setting timeout of 2.113537ms
server  | 2.012 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.012 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.012 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.012 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.012 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.012 DEBUG [Server 6214971d9e562ea9] delay duration 293.575626ms
server  | 2.012 DEBUG Setting timeout of 293.575626ms
server  | 2.012 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.012 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.012 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.012 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.012 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.012 DEBUG [Server 6214971d9e562ea9] delay duration 293.546672ms
server  | 2.012 DEBUG Setting timeout of 293.546672ms
server  | 2.307 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 2.307 DEBUG [Server 6214971d9e562ea9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
server  | 2.307 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 384, tv_nsec: 636652050 }
server  | 2.307 DEBUG [Server 6214971d9e562ea9] Lost: EcnEct0
server  | 2.307 DEBUG [Server 6214971d9e562ea9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1, smallest: 0, ack_needed: true }] })
server  | 2.307 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 384, tv_nsec: 639040933 }
server  | 2.307 DEBUG [Server 6214971d9e562ea9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1178 })
server  | 2.307 INFO Lost crypto frame space=in offset=0 length=1178
server  | 2.307 DEBUG [Server 6214971d9e562ea9] Lost: EcnEct0
server  | 2.307 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 2.307 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.307 DEBUG CRYPTO for in offset=0, len=1178
server  | 2.307 DEBUG [Server 6214971d9e562ea9] pn=4 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1230
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 37614, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 2.307 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.307 DEBUG [Server 6214971d9e562ea9] pad Initial from 1230 to PLPMTU 1232
server  | 2.307 DEBUG packet_sent this=0x55f981a6fb40, pn=4, ps=1232
server  | 2.307 DEBUG ECN probing: sent 8 probes
server  | 2.307 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.307 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.307 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.307 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.307 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.307 DEBUG [Server 6214971d9e562ea9] delay duration 302.096733ms
server  | 2.307 DEBUG Setting timeout of 302.096733ms
server  | 2.307 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.307 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.307 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.307 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.307 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.307 DEBUG [Server 6214971d9e562ea9] delay duration 302.056859ms
server  | 2.307 DEBUG Setting timeout of 302.056859ms
client  | 1.511 DEBUG [Client 6214971d9e562ea9] pn=0 type=Initial pri-path:6214971d9e562ea9 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 45
client  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 1.511 DEBUG [Client 6214971d9e562ea9] Rx ACK space=in, ranges=[0..=0]
client  | 1.511 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
client  | 1.511 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] slow start += 1252
client  | 1.511 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6260/13772 ssthresh 18446744073709551615] on_packets_acked this=0x55888a380970, limited=0, bytes_in_flight=6260, cwnd=13772, state=SlowStart, new_acked=1252
client  | 1.511 DEBUG Acked crypto frame space=in offset=0 length=1213
client  | 1.511 DEBUG [Client 6214971d9e562ea9] Changing to use Server CID=0c3d3e9afc9d6102d6f3
client  | 1.511 DEBUG [Client 6214971d9e562ea9] State change from WaitInitial -> WaitVersion
client  | 1.511 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.511 DEBUG Building Initial dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.511 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.511 DEBUG [Client 6214971d9e562ea9] delay duration 3.924402301s
client  | 1.511 DEBUG Setting timeout of 3.924402301s
client  | 1.511 WARN Unhandled event StateChange(WaitVersion)
client  | 1.511 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.511 DEBUG Building Initial dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.511 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.511 DEBUG [Client 6214971d9e562ea9] delay duration 3.924353249s
client  | 1.511 DEBUG Setting timeout of 3.924353249s
client  | 1.513 DEBUG [Client 6214971d9e562ea9] pn=1 type=Initial pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 1227
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  |   -> RX Crypto { offset: 0, len: 1178 }
client  | 1.513 DEBUG [Client 6214971d9e562ea9] Rx ACK space=in, ranges=[0..=1]
client  | 1.513 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
client  | 1.513 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5008/15024 ssthresh 18446744073709551615] slow start += 1252
client  | 1.513 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5008/15024 ssthresh 18446744073709551615] on_packets_acked this=0x55888a380970, limited=0, bytes_in_flight=5008, cwnd=15024, state=SlowStart, new_acked=1252
client  | 1.513 DEBUG Acked crypto frame space=in offset=1213 length=326
client  | 1.513 DEBUG Read Ok(1178) bytes
client  | 1.513 DEBUG Write secret available for Handshake: SymKey [32]: 4118588614e189e0010c440e4a68453f2b703f92136d0aaf1c908fc80111ec07
client  | 1.513 DEBUG Read secret available for Handshake: SymKey [32]: 249d6da9694bca710f5c9cdd742562ddbaf972a9c30419a7a3746b0ef4dca39b
client  | 1.513 DEBUG [Agent 0x55888a36d270] state -> InProgress
client  | 1.513 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
client  | 1.513 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
client  | 1.513 DEBUG [Crypto] Handshake keys installed
client  | 1.513 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 375, tv_nsec: 391392374 }
client  | 1.513 DEBUG [Client 6214971d9e562ea9] State change from WaitVersion -> Handshaking
client  | 1.513 INFO [Client 6214971d9e562ea9] Garbage packet: Transport error: InvalidPacket
client  | 1.513 WARN [Client ...] Dropped received packet: Garbage packet; Total: 1
client  | 1.513 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.513 DEBUG Building Initial dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.513 DEBUG [Client 6214971d9e562ea9] pn=6 type=Initial pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 46
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 79, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 1.513 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.513 DEBUG [Client 6214971d9e562ea9] pad Initial from 46 to PLPMTU 1252
client  | 1.514 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.514 DEBUG Building Initial dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.514 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.514 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.514 DEBUG [Client 6214971d9e562ea9] delay duration 3.168411659s
client  | 1.514 DEBUG Setting timeout of 3.168411659s
client  | 1.514 DEBUG [Client 6214971d9e562ea9] pn=0 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 772
client  |   -> RX Crypto { offset: 0, len: 732 }
client  | 1.514 DEBUG Read Ok(732) bytes
client  | 1.514 DEBUG [Agent 0x55888a36d270] state -> AuthenticationPending
client  | 1.514 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 375, tv_nsec: 392140969 }
client  | 1.514 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.514 DEBUG Building Initial dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.514 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.514 DEBUG [Client 6214971d9e562ea9] pn=0 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 87, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 1.514 DEBUG [Client 6214971d9e562ea9] Drop packet number space in
client  | 1.514 DEBUG [LossRecovery] Reset loss recovery state for Initial
client  | 1.514 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.514 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.514 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.514 DEBUG [Client 6214971d9e562ea9] delay duration 3.776316787s
client  | 1.514 DEBUG Setting timeout of 3.776316787s
client  | 1.514 DEBUG StateChange(Handshaking)
client  | 1.514 WARN Cannot create stream ConnectionState
client  | 1.514 DEBUG [Client 6214971d9e562ea9] Authenticated Ok
client  | 1.514 DEBUG Write secret available for ApplicationData: SymKey [32]: 597ac37d20d2d0f1695ce13ed437fc320b30610d5ea2ce66838961b9ab0e5f31
client  | 1.514 DEBUG Read secret available for ApplicationData: SymKey [32]: 0c5f0c87b2871a01062dfa4b70eb4814654bdcf3a07662c722cb46a789810bdf
client  | 1.515 DEBUG [Agent 0x55888a36d270] SSL_AuthCertificateComplete: Ok(())
client  | 1.515 DEBUG [Agent 0x55888a36d270] 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  | 1.515 DEBUG [Client 6214971d9e562ea9] TLS connection complete
client  | 1.515 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 1.515 DEBUG [Crypto] Application write key installed
client  | 1.515 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 1.515 DEBUG [Crypto] application read keys installed
client  | 1.515 DEBUG [Client 6214971d9e562ea9] State change from Handshaking -> Connected
client  | 1.515 INFO [Client 6214971d9e562ea9] Connection established
client  | 1.515 DEBUG stream BiDi creatable
client  | 1.515 INFO Created stream 0 for https://server4/voklwkgyxp
client  | 1.515 INFO Saving https://server4/voklwkgyxp to "/downloads/voklwkgyxp"
client  | 1.515 DEBUG stream UniDi creatable
client  | 1.515 DEBUG StateChange(Connected)
client  | 1.515 DEBUG stream 0 writable
client  | 1.515 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.515 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.515 DEBUG CRYPTO for hs offset=0, len=36
client  | 1.515 DEBUG [Client 6214971d9e562ea9] pn=1 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 75
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 1.515 DEBUG packet_sent this=0x55888a380970, pn=1, ps=75
client  | 1.515 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.515 DEBUG [Client 6214971d9e562ea9] pn=0 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 132
client  |   TX -> AckFrequency { seqno: 0, tolerance: 3, delay: 50000, ignore_order: false }
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 1.515 DEBUG packet_sent this=0x55888a380970, pn=0, ps=57
client  | 1.515 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.515 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.515 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.515 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.515 DEBUG [Client 6214971d9e562ea9] delay duration 3.776297712s
client  | 1.515 DEBUG Setting timeout of 3.776297712s
server  | 2.607 DEBUG [Server 6214971d9e562ea9] pn=4 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 1252
server  |   -> RX Crypto { offset: 0, len: 1213 }
server  | 2.607 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 385, tv_nsec: 535490922 }
server  | 2.607 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.607 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG CRYPTO for in offset=0, len=1178
server  | 2.607 DEBUG [Server 6214971d9e562ea9] pn=5 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1227
server  |   TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 2.607 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG [Server 6214971d9e562ea9] pad Initial from 1227 to PLPMTU 1232
server  | 2.607 DEBUG packet_sent this=0x55f981a6fb40, pn=5, ps=1232
server  | 2.607 DEBUG ECN probing: sent 9 probes
server  | 2.607 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.607 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG CRYPTO for hs offset=0, len=732
server  | 2.607 DEBUG [Server 6214971d9e562ea9] pn=3 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 772
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 2.607 DEBUG packet_sent this=0x55f981a6fb40, pn=3, ps=772
server  | 2.607 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.607 DEBUG ECN probing: sent 10 probes
server  | 2.607 DEBUG ECN probing concluded with 10 probes sent
server  | 2.607 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.607 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.607 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.607 DEBUG [Server 6214971d9e562ea9] delay duration 599.690733ms
server  | 2.607 DEBUG Setting timeout of 599.690733ms
server  | 2.607 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.607 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.607 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.607 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.607 DEBUG [Server 6214971d9e562ea9] delay duration 599.657892ms
server  | 2.607 DEBUG Setting timeout of 599.657892ms
server  | 2.608 DEBUG [Server 6214971d9e562ea9] pn=5 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 366
server  |   -> RX Crypto { offset: 1213, len: 326 }
server  | 2.608 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 385, tv_nsec: 536328824 }
server  | 2.608 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 3
server  | 2.608 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.608 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.608 DEBUG CRYPTO for in offset=0, len=1178
server  | 2.608 DEBUG [Server 6214971d9e562ea9] pn=6 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 1227
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 2.608 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.608 DEBUG [Server 6214971d9e562ea9] pad Initial from 1227 to PLPMTU 1232
server  | 2.608 DEBUG packet_sent this=0x55f981a6fb40, pn=6, ps=1232
server  | 2.608 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.608 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.608 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.608 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.608 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.608 DEBUG [Server 6214971d9e562ea9] delay duration 2.182944ms
server  | 2.608 DEBUG Setting timeout of 2.182944ms
server  | 2.608 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.608 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.608 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.608 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.608 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.608 DEBUG [Server 6214971d9e562ea9] delay duration 2.15379ms
server  | 2.608 DEBUG Setting timeout of 2.15379ms
server  | 2.611 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.611 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.611 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.611 DEBUG CRYPTO for hs offset=0, len=732
server  | 2.611 DEBUG [Server 6214971d9e562ea9] pn=4 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 772
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 2.611 DEBUG packet_sent this=0x55f981a6fb40, pn=4, ps=772
server  | 2.611 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.611 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1068, pto: None, probe: EnumSet(), paced: false }
server  | 2.611 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.611 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.611 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.611 DEBUG TX blocked, profile=SendProfile { limit: 1068, pto: None, probe: EnumSet(), paced: false }
server  | 2.611 DEBUG [Server 6214971d9e562ea9] delay duration 596.701522ms
server  | 2.611 DEBUG Setting timeout of 596.701522ms
server  | 2.611 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1068, pto: None, probe: EnumSet(), paced: false }
server  | 2.611 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.611 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 2.611 DEBUG Building Short dcid Some(CID [0]: )
server  | 2.611 DEBUG TX blocked, profile=SendProfile { limit: 1068, pto: None, probe: EnumSet(), paced: false }
server  | 2.611 DEBUG [Server 6214971d9e562ea9] delay duration 596.674282ms
server  | 2.611 DEBUG Setting timeout of 596.674282ms
client  | 1.806 WARN [Client ...] Dropped received packet: Decryption failure; Total: 2
client  | 1.806 INFO [Client 6214971d9e562ea9] Garbage packet: Transport error: InvalidPacket
client  | 1.806 WARN [Client ...] Dropped received packet: Garbage packet; Total: 3
client  | 1.806 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.807 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.807 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG [Client 6214971d9e562ea9] delay duration 3.4847499s
client  | 1.807 DEBUG Setting timeout of 3.4847499s
client  | 1.807 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.807 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.807 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG [Client 6214971d9e562ea9] delay duration 3.484702652s
client  | 1.807 DEBUG Setting timeout of 3.484702652s
client  | 1.807 DEBUG [Client 6214971d9e562ea9] pn=1 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 772
client  |   -> RX Crypto { offset: 0, len: 732 }
client  | 1.807 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 375, tv_nsec: 685445014 }
client  | 1.807 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.807 DEBUG CRYPTO for hs offset=0, len=36
client  | 1.807 DEBUG [Client 6214971d9e562ea9] pn=2 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 83
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 7, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 1.807 DEBUG packet_sent this=0x55888a380970, pn=2, ps=83
client  | 1.807 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.807 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.807 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.807 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG [Client 6214971d9e562ea9] delay duration 3.776298823s
client  | 1.807 DEBUG Setting timeout of 3.776298823s
client  | 1.807 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.807 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.807 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.807 DEBUG [Client 6214971d9e562ea9] delay duration 3.776261333s
client  | 1.807 DEBUG Setting timeout of 3.776261333s
client  | 1.808 WARN [Client ...] Dropped received packet: Decryption failure; Total: 4
client  | 1.808 INFO [Client 6214971d9e562ea9] Garbage packet: Transport error: InvalidPacket
client  | 1.808 WARN [Client ...] Dropped received packet: Garbage packet; Total: 5
client  | 1.808 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.808 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.808 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.808 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.808 DEBUG [Client 6214971d9e562ea9] delay duration 3.775468913s
client  | 1.808 DEBUG Setting timeout of 3.775468913s
client  | 1.808 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.808 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.808 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.808 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.808 DEBUG [Client 6214971d9e562ea9] delay duration 3.775451311s
client  | 1.808 DEBUG Setting timeout of 3.775451311s
client  | 1.810 DEBUG [Client 6214971d9e562ea9] pn=2 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 772
client  |   -> RX Crypto { offset: 0, len: 732 }
client  | 1.810 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 375, tv_nsec: 688338620 }
client  | 1.810 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.810 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.810 DEBUG CRYPTO for hs offset=0, len=36
client  | 1.810 DEBUG [Client 6214971d9e562ea9] pn=3 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 83
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 2, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 1.810 DEBUG packet_sent this=0x55888a380970, pn=3, ps=83
client  | 1.810 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.810 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.810 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.810 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.810 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.810 DEBUG [Client 6214971d9e562ea9] delay duration 3.776333327s
client  | 1.810 DEBUG Setting timeout of 3.776333327s
client  | 1.810 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.810 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 1.810 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 1.810 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 1.810 DEBUG [Client 6214971d9e562ea9] delay duration 3.776304454s
client  | 1.810 DEBUG Setting timeout of 3.776304454s
client  | 2.108 WARN [Client ...] Dropped received packet: Decryption failure; Total: 6
client  | 2.108 INFO [Client 6214971d9e562ea9] Garbage packet: Transport error: InvalidPacket
client  | 2.108 WARN [Client ...] Dropped received packet: Garbage packet; Total: 7
client  | 2.108 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.108 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.108 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.108 DEBUG [Client 6214971d9e562ea9] delay duration 3.478058882s
client  | 2.108 DEBUG Setting timeout of 3.478058882s
client  | 2.108 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.108 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.108 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.108 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.108 DEBUG [Client 6214971d9e562ea9] delay duration 3.478019409s
client  | 2.108 DEBUG Setting timeout of 3.478019409s
server  | 3.209 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 3.209 DEBUG [Server 6214971d9e562ea9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 3, smallest: 0, ack_needed: true }] })
server  | 3.209 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 384, tv_nsec: 934238971 }
server  | 3.209 DEBUG [Server 6214971d9e562ea9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1178 })
server  | 3.209 INFO Lost crypto frame space=in offset=0 length=1178
server  | 3.209 DEBUG [Server 6214971d9e562ea9] Lost: EcnEct0
server  | 3.209 DEBUG [Server 6214971d9e562ea9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 3, smallest: 0, ack_needed: true }] })
server  | 3.209 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 385, tv_nsec: 235155384 }
server  | 3.209 DEBUG [Server 6214971d9e562ea9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1178 })
server  | 3.209 INFO Lost crypto frame space=in offset=0 length=1178
server  | 3.209 DEBUG [Server 6214971d9e562ea9] Lost: EcnEct0
server  | 3.209 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 3.209 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.209 DEBUG CRYPTO for in offset=0, len=1178
server  | 3.209 DEBUG [Server 6214971d9e562ea9] pn=7 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 1230
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 75142, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 3.209 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.209 DEBUG [Server 6214971d9e562ea9] pad Initial from 1230 to PLPMTU 1232
server  | 3.209 DEBUG packet_sent this=0x55f981a6fb40, pn=7, ps=1232
server  | 3.209 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.209 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.209 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.209 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.209 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.209 DEBUG [Server 6214971d9e562ea9] delay duration 601.871003ms
server  | 3.209 DEBUG Setting timeout of 601.871003ms
server  | 3.209 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.209 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.209 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.209 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.209 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 3.209 DEBUG [Server 6214971d9e562ea9] delay duration 601.828263ms
server  | 3.209 DEBUG Setting timeout of 601.828263ms
server  | 3.215 DEBUG [Server 6214971d9e562ea9] pn=6 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 46
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 79, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 3.215 DEBUG [Server 6214971d9e562ea9] Rx ACK space=in, ranges=[0..=1]
server  | 3.215 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
server  | 3.215 INFO ECN validation succeeded, path is capable
server  | 3.215 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11252/13552 ssthresh 18446744073709551615] slow start += 1232
server  | 3.215 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11252/13552 ssthresh 18446744073709551615] on_packets_acked this=0x55f981a6fb40, limited=0, bytes_in_flight=11252, cwnd=13552, state=SlowStart, new_acked=1232
server  | 3.215 DEBUG Acked crypto frame space=in offset=0 length=1178
server  | 3.215 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:47882] Path validated Instant { tv_sec: 386, tv_nsec: 143326259 }
server  | 3.215 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 4
server  | 3.215 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.215 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.215 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.215 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.215 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.215 DEBUG [Server 6214971d9e562ea9] delay duration 3.907192282s
server  | 3.215 DEBUG Setting timeout of 3.907192282s
server  | 3.215 DEBUG [Server 6214971d9e562ea9] Drop packet number space in
server  | 3.215 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 3.215 DEBUG [Server 6214971d9e562ea9] pn=0 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 45
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 87, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 3.215 DEBUG [Server 6214971d9e562ea9] Rx ACK space=hs, ranges=[0..=0]
server  | 3.215 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 3.215 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3088/14324 ssthresh 18446744073709551615] slow start += 772
server  | 3.215 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3088/14324 ssthresh 18446744073709551615] on_packets_acked this=0x55f981a6fb40, limited=0, bytes_in_flight=3088, cwnd=14324, state=SlowStart, new_acked=772
server  | 3.215 DEBUG Acked crypto frame space=hs offset=0 length=732
server  | 3.215 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:47882] Path validated Instant { tv_sec: 386, tv_nsec: 143455611 }
server  | 3.215 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.215 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.215 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.215 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.215 DEBUG [Server 6214971d9e562ea9] delay duration 3.155641268s
server  | 3.215 DEBUG Setting timeout of 3.155641268s
server  | 3.215 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.215 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.215 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.215 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.215 DEBUG [Server 6214971d9e562ea9] delay duration 3.155523048s
server  | 3.215 DEBUG Setting timeout of 3.155523048s
server  | 3.215 DEBUG [Server 6214971d9e562ea9] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 75
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 3.216 DEBUG Read Ok(36) bytes
server  | 3.216 DEBUG [Agent 0x55f981a701d0] 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  | 3.216 DEBUG [Server 6214971d9e562ea9] TLS connection complete
server  | 3.216 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:47882] Path validated Instant { tv_sec: 386, tv_nsec: 143806955 }
server  | 3.216 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 3.216 DEBUG [Crypto] application read keys installed
server  | 3.216 DEBUG [Server 6214971d9e562ea9] State change from Handshaking -> Connected
server  | 3.216 DEBUG [Server 6214971d9e562ea9] State change from Connected -> Confirmed
server  | 3.216 DEBUG PMTUD started with probe size 1380
server  | 3.216 INFO [Server 6214971d9e562ea9] Connection established
server  | 3.216 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 143806955 }
server  | 3.216 DEBUG [Server 6214971d9e562ea9] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 57
server  |   -> RX AckFrequency { seqno: 0, tolerance: 3, delay: 50000, ignore_order: false }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 3.216 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 386, tv_nsec: 193806955 }
server  | 3.216 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.216 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
server  | 3.216 DEBUG [Server 6214971d9e562ea9] pn=5 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 41
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 3.216 DEBUG [Server 6214971d9e562ea9] Drop packet number space hs
server  | 3.216 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 3.216 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.216 DEBUG [Server 6214971d9e562ea9] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [242, 217, 76, 96, 113, 151, 117, 187, 139, 158], stateless_reset_token: [92, 148, 75, 225, 93, 166, 143, 98, 154, 118, 108, 40, 99, 134, 242, 199] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [211, 62, 128, 190, 106, 138, 190, 242, 102, 79], stateless_reset_token: [53, 241, 158, 185, 57, 165, 239, 245, 199, 164, 1, 31, 62, 28, 232, 90] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [8, 34, 214, 211, 165, 13, 17, 227, 209, 219], stateless_reset_token: [28, 191, 179, 247, 98, 50, 240, 8, 143, 164, 30, 185, 66, 115, 56, 104] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [187, 216, 102, 244, 235, 91, 45, 43, 14, 59], stateless_reset_token: [185, 125, 113, 9, 34, 65, 77, 44, 186, 27, 135, 192, 89, 81, 15, 130] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [23, 163, 177, 201, 95, 156, 153, 36, 242, 179], stateless_reset_token: [14, 134, 104, 134, 72, 36, 194, 233, 236, 85, 158, 171, 74, 195, 186, 54] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [25, 228, 58, 85, 108, 100, 144, 71, 18, 70], stateless_reset_token: [192, 123, 47, 211, 137, 61, 76, 254, 43, 92, 240, 178, 246, 57, 86, 152] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [201, 222, 123, 59, 171, 70, 143, 38, 136, 216], stateless_reset_token: [126, 152, 3, 148, 60, 21, 58, 75, 155, 155, 76, 45, 213, 235, 0, 60] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 50000, ignore_order: false }
server  |   TX -> Padding { len: 1053 }
server  | 3.216 DEBUG packet_sent this=0x55f981a6fb40, pn=0, ps=1291
server  | 3.216 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.216 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.216 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.216 DEBUG [Server 6214971d9e562ea9] delay duration 2.457874ms
server  | 3.216 DEBUG Setting timeout of 2.457874ms
server  | 3.217 DEBUG [Server 6214971d9e562ea9] send session ticket 406300086214971d9e562ea90104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a0c3d3e9afc9d6102d6f3110c000000014a0aea1a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 3.217 DEBUG Path = 'voklwkgyxp'
server  | 3.217 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.217 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.217 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.217 DEBUG [Server 6214971d9e562ea9] delay duration 1.443504ms
server  | 3.217 DEBUG Setting timeout of 1.443504ms
server  | 3.220 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.220 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.220 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 3.220 DEBUG CRYPTO for ap offset=0, len=281
server  | 3.220 DEBUG [Server 6214971d9e562ea9] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  |   TX -> Crypto { offset: 0, len: 281 }
server  | 3.220 DEBUG packet_sent this=0x55f981a6fb40, pn=1, ps=1332
server  | 3.220 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.220 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.220 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.220 DEBUG [Server 6214971d9e562ea9] delay duration 45.140392ms
server  | 3.220 DEBUG Setting timeout of 45.140392ms
server  | 3.220 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.220 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.220 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.220 DEBUG [Server 6214971d9e562ea9] delay duration 45.115165ms
server  | 3.220 DEBUG Setting timeout of 45.115165ms
server  | 3.267 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.267 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.267 DEBUG [Server 6214971d9e562ea9] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 24
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 6392, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 3.267 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.267 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.267 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.267 DEBUG [Server 6214971d9e562ea9] delay duration 19.387288ms
server  | 3.267 DEBUG Setting timeout of 19.387288ms
server  | 3.267 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.267 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.267 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.267 DEBUG [Server 6214971d9e562ea9] delay duration 19.358735ms
server  | 3.267 DEBUG Setting timeout of 19.358735ms
server  | 3.287 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.287 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.287 DEBUG CRYPTO for ap offset=281, len=24
server  | 3.287 DEBUG [Server 6214971d9e562ea9] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 91
server  |   TX -> Crypto { offset: 281, len: 24 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 242, 254, 235, 238, 89, 147, 68, 16, 192, 159, 122, 6, 66, 164, 198, 18, 215, 246, 193, 237, 206, 56, 232, 193, 74, 4, 87, 16, 233, 64, 119, 187, 30, 138, 210, 29] }
server  | 3.287 DEBUG packet_sent this=0x55f981a6fb40, pn=3, ps=91
server  | 3.287 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.287 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.287 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.287 DEBUG [Server 6214971d9e562ea9] delay duration 3.864669ms
server  | 3.287 DEBUG Setting timeout of 3.864669ms
server  | 3.287 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.287 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.287 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.287 DEBUG [Server 6214971d9e562ea9] delay duration 3.837158ms
server  | 3.287 DEBUG Setting timeout of 3.837158ms
server  | 3.292 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.292 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.292 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.292 DEBUG [Server 6214971d9e562ea9] delay duration 3.804243387s
server  | 3.292 DEBUG Setting timeout of 3.804243387s
server  | 3.292 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.292 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.292 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.292 DEBUG [Server 6214971d9e562ea9] delay duration 3.804216387s
server  | 3.292 DEBUG Setting timeout of 3.804216387s
client  | 2.408 WARN [Client ...] Dropped received packet: Decryption failure; Total: 8
client  | 2.409 INFO [Client 6214971d9e562ea9] Garbage packet: Transport error: InvalidPacket
client  | 2.409 WARN [Client ...] Dropped received packet: Garbage packet; Total: 9
client  | 2.409 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.409 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.409 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG [Client 6214971d9e562ea9] delay duration 3.177674902s
client  | 2.409 DEBUG Setting timeout of 3.177674902s
client  | 2.409 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.409 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.409 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG [Client 6214971d9e562ea9] delay duration 3.177627433s
client  | 2.409 DEBUG Setting timeout of 3.177627433s
client  | 2.409 DEBUG [Client 6214971d9e562ea9] pn=3 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 772
client  |   -> RX Crypto { offset: 0, len: 732 }
client  | 2.409 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 376, tv_nsec: 287559087 }
client  | 2.409 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.409 DEBUG CRYPTO for hs offset=0, len=36
client  | 2.409 DEBUG [Client 6214971d9e562ea9] pn=4 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 83
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 13, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 2.409 DEBUG packet_sent this=0x55888a380970, pn=4, ps=83
client  | 2.409 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.409 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.409 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.409 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG [Client 6214971d9e562ea9] delay duration 3.776291991s
client  | 2.409 DEBUG Setting timeout of 3.776291991s
client  | 2.409 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.409 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.409 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.409 DEBUG [Client 6214971d9e562ea9] delay duration 3.776263828s
client  | 2.409 DEBUG Setting timeout of 3.776263828s
client  | 2.410 WARN [Client ...] Dropped received packet: Decryption failure; Total: 10
client  | 2.410 INFO [Client 6214971d9e562ea9] Garbage packet: Transport error: InvalidPacket
client  | 2.410 WARN [Client ...] Dropped received packet: Garbage packet; Total: 11
client  | 2.410 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.410 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.410 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.410 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.410 DEBUG [Client 6214971d9e562ea9] delay duration 3.775555376s
client  | 2.410 DEBUG Setting timeout of 3.775555376s
client  | 2.410 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.410 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.410 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.410 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.410 DEBUG [Client 6214971d9e562ea9] delay duration 3.775523907s
client  | 2.410 DEBUG Setting timeout of 3.775523907s
client  | 2.412 DEBUG [Client 6214971d9e562ea9] pn=4 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 772
client  |   -> RX Crypto { offset: 0, len: 732 }
client  | 2.412 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 376, tv_nsec: 290394394 }
client  | 2.412 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.412 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.412 DEBUG CRYPTO for hs offset=0, len=36
client  | 2.412 DEBUG [Client 6214971d9e562ea9] pn=5 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 83
client  |   TX -> Ack { largest_acknowledged: 4, ack_delay: 4, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 2.412 DEBUG packet_sent this=0x55888a380970, pn=5, ps=83
client  | 2.412 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.412 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.412 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.412 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.412 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.412 DEBUG [Client 6214971d9e562ea9] delay duration 3.776311666s
client  | 2.412 DEBUG Setting timeout of 3.776311666s
client  | 2.412 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.412 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 2.412 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 2.412 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.412 DEBUG [Client 6214971d9e562ea9] delay duration 3.776282262s
client  | 2.412 DEBUG Setting timeout of 3.776282262s
server  | 3.508 WARN [Server ...] Dropped received packet: Decryption failure; Total: 5
server  | 3.508 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.508 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.508 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.508 DEBUG [Server 6214971d9e562ea9] delay duration 3.588718012s
server  | 3.508 DEBUG Setting timeout of 3.588718012s
server  | 3.508 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.508 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.508 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.508 DEBUG [Server 6214971d9e562ea9] delay duration 3.588648041s
server  | 3.508 DEBUG Setting timeout of 3.588648041s
server  | 3.510 WARN [Server ...] Dropped received packet: Decryption failure; Total: 6
server  | 3.510 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.510 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.510 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.510 DEBUG [Server 6214971d9e562ea9] delay duration 3.586138356s
server  | 3.510 DEBUG Setting timeout of 3.586138356s
server  | 3.510 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.510 DEBUG Building Short dcid Some(CID [0]: )
server  | 3.510 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.510 DEBUG [Server 6214971d9e562ea9] delay duration 3.586104343s
server  | 3.510 DEBUG Setting timeout of 3.586104343s
client  | 3.010 WARN [Client ...] Dropped received packet: Decryption failure; Total: 12
client  | 3.010 INFO [Client 6214971d9e562ea9] Garbage packet: Transport error: InvalidPacket
client  | 3.010 WARN [Client ...] Dropped received packet: Garbage packet; Total: 13
client  | 3.011 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.011 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 3.011 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.011 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.011 DEBUG [Client 6214971d9e562ea9] delay duration 3.177763571s
client  | 3.011 DEBUG Setting timeout of 3.177763571s
client  | 3.011 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.011 DEBUG Building Handshake dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3) scid Some(CID [0]: )
client  | 3.011 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.011 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.011 DEBUG [Client 6214971d9e562ea9] delay duration 3.177727433s
client  | 3.011 DEBUG Setting timeout of 3.177727433s
client  | 3.017 DEBUG [Client 6214971d9e562ea9] pn=5 type=Handshake pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 41
client  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 3.017 DEBUG [Client 6214971d9e562ea9] Rx ACK space=hs, ranges=[0..=1]
client  | 3.017 DEBUG [LossRecovery] ACK for Handshake - largest_acked=1
client  | 3.017 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 389/15099 ssthresh 18446744073709551615] slow start += 75
client  | 3.017 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 389/15099 ssthresh 18446744073709551615] on_packets_acked this=0x55888a380970, limited=0, bytes_in_flight=389, cwnd=15099, state=SlowStart, new_acked=75
client  | 3.017 DEBUG Acked crypto frame space=hs offset=0 length=36
client  | 3.017 DEBUG [Client 6214971d9e562ea9] pn=0 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 1291
client  |   -> RX HandshakeDone
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [242, 217, 76, 96, 113, 151, 117, 187, 139, 158], stateless_reset_token: [92, 148, 75, 225, 93, 166, 143, 98, 154, 118, 108, 40, 99, 134, 242, 199] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [211, 62, 128, 190, 106, 138, 190, 242, 102, 79], stateless_reset_token: [53, 241, 158, 185, 57, 165, 239, 245, 199, 164, 1, 31, 62, 28, 232, 90] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [8, 34, 214, 211, 165, 13, 17, 227, 209, 219], stateless_reset_token: [28, 191, 179, 247, 98, 50, 240, 8, 143, 164, 30, 185, 66, 115, 56, 104] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [187, 216, 102, 244, 235, 91, 45, 43, 14, 59], stateless_reset_token: [185, 125, 113, 9, 34, 65, 77, 44, 186, 27, 135, 192, 89, 81, 15, 130] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [23, 163, 177, 201, 95, 156, 153, 36, 242, 179], stateless_reset_token: [14, 134, 104, 134, 72, 36, 194, 233, 236, 85, 158, 171, 74, 195, 186, 54] }
client  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [25, 228, 58, 85, 108, 100, 144, 71, 18, 70], stateless_reset_token: [192, 123, 47, 211, 137, 61, 76, 254, 43, 92, 240, 178, 246, 57, 86, 152] }
client  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [201, 222, 123, 59, 171, 70, 143, 38, 136, 216], stateless_reset_token: [126, 152, 3, 148, 60, 21, 58, 75, 155, 155, 76, 45, 213, 235, 0, 60] }
client  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 50000, ignore_order: false }
client  |   -> RX Padding { len: 1053 }
client  | 3.017 DEBUG [Client 6214971d9e562ea9] State change from Connected -> Confirmed
client  | 3.017 DEBUG PMTUD started with probe size 1380
client  | 3.017 DEBUG [Client 6214971d9e562ea9] Drop packet number space hs
client  | 3.017 DEBUG [LossRecovery] Reset loss recovery state for Handshake
client  | 3.017 DEBUG [Client 6214971d9e562ea9] No preferred address to migrate to
client  | 3.017 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 376, tv_nsec: 945707076 }
client  | 3.017 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.017 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.017 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 3.017 DEBUG [Client 6214971d9e562ea9] pn=1 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1323 }
client  | 3.017 DEBUG packet_sent this=0x55888a380970, pn=1, ps=1352
client  | 3.017 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.017 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.018 DEBUG [Client 6214971d9e562ea9] delay duration 49.6519ms
client  | 3.018 DEBUG Setting timeout of 49.6519ms
client  | 3.018 WARN Unhandled event StateChange(Confirmed)
client  | 3.018 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.018 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.018 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.018 DEBUG [Client 6214971d9e562ea9] delay duration 49.616715ms
client  | 3.018 DEBUG Setting timeout of 49.616715ms
client  | 3.021 DEBUG [Client 6214971d9e562ea9] pn=1 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Ping
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  |   -> RX Crypto { offset: 0, len: 281 }
client  | 3.021 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 376, tv_nsec: 899896271 }
client  | 3.021 DEBUG Read Ok(281) bytes
client  | 3.021 DEBUG [Agent 0x55888a36d270] 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  | 3.021 DEBUG setting max_stream_data to 1048576
client  | 3.021 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 376, tv_nsec: 899896271 }
client  | 3.021 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.021 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.021 DEBUG [Client 6214971d9e562ea9] pn=2 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 36
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 8, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 3.021 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.021 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.021 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.021 DEBUG [Client 6214971d9e562ea9] delay duration 3.262579287s
client  | 3.021 DEBUG Setting timeout of 3.262579287s
client  | 3.022 DEBUG stream BiDi creatable
client  | 3.022 DEBUG stream UniDi creatable
client  | 3.022 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.022 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.022 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.022 DEBUG [Client 6214971d9e562ea9] delay duration 3.262475362s
client  | 3.022 DEBUG Setting timeout of 3.262475362s
client  | 3.022 DEBUG [Client 6214971d9e562ea9] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 386, tv_nsec: 700322417 } }
client  | 3.022 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 386, tv_nsec: 700322417 } })
client  | 3.022 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.022 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.022 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 376, tv_nsec: 900202971 }
client  | 3.022 DEBUG [Client 6214971d9e562ea9] pn=3 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 47
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 38, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 3.022 DEBUG Setting timeout of 9.800022202s
client  | 3.067 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.067 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.067 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 376, tv_nsec: 945544994 }
client  | 3.067 DEBUG [Client 6214971d9e562ea9] pn=4 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 5706, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 3.067 DEBUG Setting timeout of 9.754634016s
client  | 3.067 DEBUG Setting timeout of 9.754619408s
client  | 3.087 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.087 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 3.087 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 376, tv_nsec: 965872844 }
client  | 3.087 DEBUG [Client 6214971d9e562ea9] pn=5 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 8247, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 3.087 DEBUG Setting timeout of 9.734291738s
client  | 3.087 DEBUG Setting timeout of 9.734275759s
server  | 4.110 WARN [Server ...] Dropped received packet: Decryption failure; Total: 7
server  | 4.110 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.110 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.110 DEBUG [Server 6214971d9e562ea9] delay duration 2.986529901s
server  | 4.110 DEBUG Setting timeout of 2.986529901s
server  | 4.110 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.110 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.110 DEBUG [Server 6214971d9e562ea9] delay duration 2.986451355s
server  | 4.110 DEBUG Setting timeout of 2.986451355s
server  | 4.112 WARN [Server ...] Dropped received packet: Decryption failure; Total: 8
server  | 4.112 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.112 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.112 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.112 DEBUG [Server 6214971d9e562ea9] delay duration 2.984061324s
server  | 4.112 DEBUG Setting timeout of 2.984061324s
server  | 4.112 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.112 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.112 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.112 DEBUG [Server 6214971d9e562ea9] delay duration 2.984020818s
server  | 4.112 DEBUG Setting timeout of 2.984020818s
server  | 4.719 DEBUG [Server 6214971d9e562ea9] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 1352
server  |   -> RX Ping
server  |   -> RX Padding { len: 1323 }
server  | 4.719 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 387, tv_nsec: 647448591 }
server  | 4.719 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 387, tv_nsec: 647448591 }
server  | 4.719 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.719 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.719 DEBUG [Server 6214971d9e562ea9] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 4.719 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.719 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.719 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.719 DEBUG [Server 6214971d9e562ea9] delay duration 2.377168918s
server  | 4.719 DEBUG Setting timeout of 2.377168918s
server  | 4.719 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.719 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.719 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.719 DEBUG [Server 6214971d9e562ea9] delay duration 2.377133462s
server  | 4.719 DEBUG Setting timeout of 2.377133462s
server  | 4.722 DEBUG [Server 6214971d9e562ea9] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 8, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 4.722 DEBUG [Server 6214971d9e562ea9] Rx ACK space=ap, ranges=[0..=1]
server  | 4.722 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 4.722 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 91/16947 ssthresh 18446744073709551615] slow start += 2623
server  | 4.722 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 91/16947 ssthresh 18446744073709551615] on_packets_acked this=0x55f981a6fb40, limited=0, bytes_in_flight=91, cwnd=16947, state=SlowStart, new_acked=2623
server  | 4.722 DEBUG PMTUD probe of size 1380 succeeded
server  | 4.722 DEBUG PMTUD started with probe size 1420
server  | 4.722 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 4.722 DEBUG Acked crypto frame space=ap offset=0 length=281
server  | 4.722 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.722 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.722 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 4.722 DEBUG [Server 6214971d9e562ea9] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  |   TX -> Padding { len: 1351 }
server  | 4.722 DEBUG packet_sent this=0x55f981a6fb40, pn=5, ps=1372
server  | 4.722 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.722 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.722 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.722 DEBUG [Server 6214971d9e562ea9] delay duration 7.690616ms
server  | 4.722 DEBUG Setting timeout of 7.690616ms
server  | 4.722 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.722 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.722 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.722 DEBUG [Server 6214971d9e562ea9] delay duration 7.66596ms
server  | 4.722 DEBUG Setting timeout of 7.66596ms
server  | 4.722 DEBUG [Server 6214971d9e562ea9] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, NotEct) len 47
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 38, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
server  | 4.722 DEBUG [Server 6214971d9e562ea9] Rx ACK space=ap, ranges=[0..=1]
server  | 4.722 INFO [Server 6214971d9e562ea9] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye!
server  | 4.722 DEBUG [Server 6214971d9e562ea9] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 397, tv_nsec: 392173545 } }
server  | 4.722 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:47882] Path validated Instant { tv_sec: 387, tv_nsec: 650503465 }
server  | 4.722 DEBUG [Server 6214971d9e562ea9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.722 DEBUG Building Short dcid Some(CID [0]: )
server  | 4.722 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 387, tv_nsec: 650503465 }
server  | 4.722 DEBUG [Server 6214971d9e562ea9] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:47882 IpTos(Cs0, Ect0) len 27
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 4.722 DEBUG Setting timeout of 9.741551077s
server  | 4.722 DEBUG Setting timeout of 9.741537031s
server  | 4.768 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 397, tv_nsec: 392173545 } }; Total: 9
server  | 4.768 DEBUG Setting timeout of 9.696118861s
server  | 4.768 DEBUG Setting timeout of 9.696077845s
server  | 4.788 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 397, tv_nsec: 392173545 } }; Total: 10
server  | 4.788 DEBUG Setting timeout of 9.675788882s
server  | 4.788 DEBUG Setting timeout of 9.675756742s
client  | 4.519 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.520 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 4.520 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 378, tv_nsec: 398059684 }
client  | 4.520 DEBUG [Client 6214971d9e562ea9] pn=6 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 50
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 187270, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 4.520 DEBUG Setting timeout of 8.302106432s
client  | 4.520 DEBUG Setting timeout of 8.302090983s
client  | 4.523 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.523 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 4.523 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 378, tv_nsec: 401691442 }
client  | 4.523 DEBUG [Client 6214971d9e562ea9] pn=7 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 50
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 187724, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 4.523 DEBUG Setting timeout of 8.298514498s
client  | 4.523 DEBUG [Client 6214971d9e562ea9] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.523 DEBUG Building Short dcid Some(CID [10]: 0c3d3e9afc9d6102d6f3)
client  | 4.523 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 378, tv_nsec: 401828047 }
client  | 4.523 DEBUG [Client 6214971d9e562ea9] pn=8 type=Short pri-path:0c3d3e9afc9d6102d6f3 0.0.0.0:47882->193.167.100.100:443 IpTos(Cs0, NotEct) len 50
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 187741, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 4.523 DEBUG Setting timeout of 8.298424289s
client  | 4.523 DEBUG Setting timeout of 8.298411826s
server  | 6.220 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 397, tv_nsec: 392173545 } }; Total: 11
server  | 6.220 DEBUG Setting timeout of 8.243555513s
server  | 6.220 DEBUG Setting timeout of 8.243513655s
server  | 6.224 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 397, tv_nsec: 392173545 } }; Total: 12
server  | 6.224 DEBUG Setting timeout of 8.240101666s
server  | 6.224 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 397, tv_nsec: 392173545 } }; Total: 13
server  | 6.224 DEBUG Setting timeout of 8.24007135s
server  | 6.224 DEBUG Setting timeout of 8.240063154s
client  | 12.823 DEBUG [Client 6214971d9e562ea9] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 386, tv_nsec: 700322417 } } -> Closed(Application(0))
client  | 12.823 INFO Closing timer expired
client  | 12.823 DEBUG Output::None
client  | 12.823 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 12.823 DEBUG Timer fired while closed
client  | 12.823 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:00,444 Using the client's key log file.
2025-04-30 12:50:00,454 
2025-04-30 12:50:00,455 Using the client's key log file.
2025-04-30 12:50:00,455 Using the client's key log file.
2025-04-30 12:50:00,464 
2025-04-30 12:50:00,465 Using the client's key log file.
2025-04-30 12:50:00,782 Check of downloaded files succeeded.
2025-04-30 12:50:02,085 Check of downloaded files succeeded.