2025-04-30 12:55:39,317 Generated random file: jrgxsvdnbc of size: 1024
2025-04-30 12:55:39,318 Requests: https://server4:443/jrgxsvdnbc
2025-04-30 12:55:39,381 
2025-04-30 12:55:39,381 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_965cxlxl/ TESTCASE_SERVER=v2 TESTCASE_CLIENT=v2 WWW=/tmp/www_ui94sfxw/ DOWNLOADS=/tmp/download_bw0be07z/ SERVER_LOGS=/tmp/logs_server_s5ogh7ut CLIENT_LOGS=/tmp/logs_client_wqharrcp SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/jrgxsvdnbc"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:55:51,685  Container sim  Created
 Container server  Recreate
 Container client  Recreate
 Container server  Recreated
 Container client  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up routes...
server  | 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]
server  | Actual changes:
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
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
client  | Endpoint's IPv4 address is 193.167.0.100
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::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 v2 ']'
server  | + '[' -n /logs/qlog/ ']'
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 v2 ']'
client  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
client  | + case "$ROLE" in
server  | ++ mktemp
client  | + /wait-for-it.sh sim:57832 -s -t 30
server  | + P12CERT=/tmp/tmp.AUbksMAfdB
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.AUbksMAfdB
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.AUbksMAfdB -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  |             40:70:37:91:a0:1a:9c:50:51:63:86:08:93:1d:1e:e1:
server  |             38:9d:d6:d3
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:55:39 2025
server  |             Not After : Sat May 10 12:55:39 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:ce:fb:56:76:f7:a8:bd:dc:42:eb:f7:79:b3:05:24:
server  |                     fa:2c:8f:e5:92:ea:09:89:03:cd:e7:7a:68:b7:b1:fa:
server  |                     fb:af:03:17:25:9d:de:e1:7d:df:3e:3d:30:aa:50:57:
server  |                     dc:c2:36:aa:b9:cb:98:78:dc:98:c9:ae:f4:b8:4c:14:
server  |                     fc
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  |                 f9:6d:f6:16:8e:19:07:21:1f:eb:22:0e:e8:f1:a2:78:
server  |                 36:c7:55:a3
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 a4:ed:7c:09:e3:cc:ea:97:b2:4b:1b:9f:5e:7d:4c:0e:
server  |                 ac:89:ef:11
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:b4:3f:4a:4d:6a:5e:3e:b8:4f:e6:42:
server  |         a5:8c:22:89:d6:83:ae:52:fd:7d:f4:8b:2f:37:8d:09:
server  |         d7:2c:9c:06:ed:02:21:00:d7:18:36:a2:cf:b8:63:04:
server  |         5f:97:bd:7b:f3:c2:78:57:5b:55:4e:43:9c:65:54:72:
server  |         76:07:0c:cc:89:12:f5:ed
server  |     Fingerprint (SHA-256):
server  |         90:2C:B5:0E:00:EC:E0:36:33:44:43:9A:60:1F:EA:24:EF:6F:F6:3E:E6:94:90:4C:6E:C9:A1:44:AB:3E:BE:07
server  |     Fingerprint (SHA1):
server  |         0A:6C:AD:4F:CA:06:53:61:4B:F2:21:CE:CD:51:98:D7:45:DF:93:AE
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  | + '[' v2 = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test v2 --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.753 DEBUG [Server] Unsupported version: 57414954
server  | 0.753 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:53038 IpTos(Cs0, NotEct) len 23
server  | 0.753 DEBUG [Server] Unsupported version: 57414954
server  | 0.753 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:53038 IpTos(Cs0, NotEct) len 23
sim     | server:443 is available after 1.039986918s
server  | 0.753 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.753 DEBUG [Server] Unsupported version: 57414954
server  | 0.753 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:53038 IpTos(Cs0, NotEct) len 23
server  | 0.753 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads)
client  | + '[' https://server4:443/jrgxsvdnbc ']'
client  | + mapfile -d ' ' -t URLS
client  | + OPTIONS+=("${URLS[@]}")
client  | + RUST_LOG=debug
client  | + RUST_BACKTRACE=1
client  | + neqo-client --cc cubic --qns-test v2 --qlog-dir /logs/qlog/ --output-dir /downloads 'https://server4:443/jrgxsvdnbc
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.001 INFO hq-interop Client connecting: 0.0.0.0:45067 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version2, role=Client dcid=8b91192a8c0d5d19
client  | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version2 cipher=4865
client  | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version2 cipher=4865
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=8b91192a8c0d5d19
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:45067->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:8b91192a8c0d5d19 0.0.0.0:45067->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:8b91192a8c0d5d19 0.0.0.0:45067->193.167.100.100:443] Path validated Instant { tv_sec: 737, tv_nsec: 645125572 }
client  | 0.001 DEBUG [Client 8b91192a8c0d5d19] client_start
client  | 0.002 DEBUG Writing transport parameters, msg=1
client  | 0.002 DEBUG [Agent 0x55cb01727270] state -> InProgress
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] State change from Init -> WaitInitial
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 8b91192a8c0d5d19) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=892, len=651
client  | 0.002 DEBUG CRYPTO for in offset=0, len=120
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] pn=0 type=Initial pri-path:8b91192a8c0d5d19 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 815
client  |   TX -> Crypto { offset: 892, len: 651 }
client  |   TX -> Crypto { offset: 0, len: 120 }
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] pad Initial from 815 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55cb01743290, pn=0, ps=1252
client  | 0.002 DEBUG ECN probing: sent 1 probes
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 8b91192a8c0d5d19) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=120, len=772
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] pn=1 type=Initial pri-path:8b91192a8c0d5d19 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 812
client  |   TX -> Crypto { offset: 120, len: 772 }
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] pad Initial from 812 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55cb01743290, pn=1, ps=1252
client  | 0.002 DEBUG ECN probing: sent 2 probes
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 8b91192a8c0d5d19) 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 8b91192a8c0d5d19] delay duration 3.711701ms
client  | 0.002 DEBUG Setting timeout of 3.711701ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 8b91192a8c0d5d19) 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 8b91192a8c0d5d19] delay duration 3.680482ms
client  | 0.002 DEBUG Setting timeout of 3.680482ms
client  | 0.007 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [8]: 8b91192a8c0d5d19) 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 8b91192a8c0d5d19] delay duration 294.808702ms
client  | 0.007 DEBUG Setting timeout of 294.808702ms
server  | 0.987 DEBUG [Server] Handle initial
server  | 0.987 INFO AddressValidation: no token; accepting
server  | 0.987 INFO [Server] Accept connection CID [8]: 8b91192a8c0d5d19
server  | 0.987 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.989 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.989 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: 8b91192a8c0d5d19
server  | 0.989 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=8b91192a8c0d5d19
server  | 0.989 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.989 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] State change from Init -> WaitInitial
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 815
server  |   -> RX Crypto { offset: 892, len: 651 }
server  |   -> RX Crypto { offset: 0, len: 120 }
server  | 0.989 DEBUG unmark 0-length range at 0
server  | 0.989 DEBUG unmark 0-length range at 0
server  | 0.989 DEBUG Read Ok(120) bytes
server  | 0.989 DEBUG [Agent 0x55ad6fc3f1e0] state -> InProgress
server  | 0.989 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 747, tv_nsec: 667561230 }
server  | 0.989 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:45067] Make permanent
server  | 0.989 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:45067] set as primary path
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] State change from WaitInitial -> WaitVersion
server  | 0.989 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.989 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:45067 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  | 0.989 DEBUG ECN probing: sent 1 probes
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.989 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.989 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] delay duration 298.083898ms
server  | 0.989 DEBUG Setting timeout of 298.083898ms
server  | 0.989 DEBUG [Server 8b91192a8c0d5d19] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 812
server  |   -> RX Crypto { offset: 120, len: 772 }
server  | 0.989 DEBUG Read Ok(1423) bytes
server  | 0.989 INFO Compatible upgrade Version1 ==> Version2
server  | 0.989 DEBUG Overwrite initial version Version1 ==> Version2
server  | 0.989 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.990 DEBUG Read secret available for Handshake: SymKey [32]: ad59a71ccade7d61995f99a346141e7c70c8a5c37bf87f9936cce0bc815a0bd9
server  | 0.990 DEBUG Write secret available for Handshake: SymKey [32]: 6f38cf809a2dc9a7cf1ff508046f84f529b189cfdc823492d3af34939208eaaa
server  | 0.990 DEBUG Writing transport parameters, msg=8
server  | 0.990 DEBUG Read secret available for ApplicationData: SymKey [32]: f1e687533e3a871db9555a2345d7f9f29826da3fb421172e02ce39b5669715ba
server  | 0.990 DEBUG Write secret available for ApplicationData: SymKey [32]: ad9a927f445e061ae0fa00fa42136793fceb2fb99a7745142dc62c0e0162602a
server  | 0.990 DEBUG [Agent 0x55ad6fc3f1e0] state -> InProgress
server  | 0.990 DEBUG [CryptoStates] Creating initial cipher state v=Version2, role=Server dcid=8b91192a8c0d5d19
server  | 0.990 DEBUG Making Write Initial CryptoDxState, v=Version2 cipher=4865
server  | 0.990 DEBUG Making Read Initial CryptoDxState, v=Version2 cipher=4865
server  | 0.990 DEBUG [Server 8b91192a8c0d5d19] Compatible upgrade Version1 ==> Version2
server  | 0.990 DEBUG Making Write Handshake CryptoDxState, v=Version2 cipher=4865
server  | 0.990 DEBUG Making Read Handshake CryptoDxState, v=Version2 cipher=4865
server  | 0.990 DEBUG [Crypto] Handshake keys installed
server  | 0.990 DEBUG Making Write ApplicationData CryptoDxState, v=Version2 cipher=4865
server  | 0.990 DEBUG [Crypto] Application write key installed
server  | 0.990 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 747, tv_nsec: 669511205 }
server  | 0.990 DEBUG [Server 8b91192a8c0d5d19] State change from WaitVersion -> Handshaking
server  | 0.991 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 2
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.991 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG CRYPTO for in offset=0, len=1178
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:45067 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  | 0.991 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] pad Initial from 1227 to PLPMTU 1232
server  | 0.991 DEBUG packet_sent this=0x55ad6fc3eb20, pn=1, ps=1232
server  | 0.991 DEBUG ECN probing: sent 2 probes
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.991 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG CRYPTO for hs offset=0, len=742
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 782
server  |   TX -> Crypto { offset: 0, len: 742 }
server  | 0.991 DEBUG packet_sent this=0x55ad6fc3eb20, pn=0, ps=782
server  | 0.991 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.991 DEBUG ECN probing: sent 3 probes
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.991 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.991 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] delay duration 298.547605ms
server  | 0.991 DEBUG Setting timeout of 298.547605ms
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.991 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 0.991 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.991 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.991 DEBUG [Server 8b91192a8c0d5d19] delay duration 298.515966ms
server  | 0.991 DEBUG Setting timeout of 298.515966ms
client  | 0.040 DEBUG [Client 8b91192a8c0d5d19] pn=0 type=Initial pri-path:8b91192a8c0d5d19 0.0.0.0:45067->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  | 0.040 DEBUG [Client 8b91192a8c0d5d19] Rx ACK space=in, ranges=[0..=0]
client  | 0.040 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
client  | 0.040 DEBUG on_packets_acked this=0x55cb01743290, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.040 DEBUG Acked crypto frame space=in offset=892 length=651
client  | 0.040 DEBUG Acked crypto frame space=in offset=0 length=120
client  | 0.040 DEBUG [Client 8b91192a8c0d5d19] Changing to use Server CID=b91f6cfeb6d115c68f2c
client  | 0.040 DEBUG [Client 8b91192a8c0d5d19] State change from WaitInitial -> WaitVersion
client  | 0.040 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.040 DEBUG Building Initial dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.040 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.040 DEBUG [Client 8b91192a8c0d5d19] delay duration 78.253573ms
client  | 0.040 DEBUG Setting timeout of 78.253573ms
client  | 0.040 WARN Unhandled event StateChange(WaitVersion)
client  | 0.040 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.040 DEBUG Building Initial dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.040 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.040 DEBUG [Client 8b91192a8c0d5d19] delay duration 78.226012ms
client  | 0.040 DEBUG Setting timeout of 78.226012ms
client  | 0.042 DEBUG [Client 8b91192a8c0d5d19] pn=1 type=Initial pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->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  | 0.042 DEBUG [Client 8b91192a8c0d5d19] Rx ACK space=in, ranges=[0..=1]
client  | 0.042 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
client  | 0.042 DEBUG on_packets_acked this=0x55cb01743290, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.042 DEBUG Acked crypto frame space=in offset=120 length=772
client  | 0.042 DEBUG Read Ok(1178) bytes
client  | 0.043 DEBUG Write secret available for Handshake: SymKey [32]: ad59a71ccade7d61995f99a346141e7c70c8a5c37bf87f9936cce0bc815a0bd9
client  | 0.043 DEBUG Read secret available for Handshake: SymKey [32]: 6f38cf809a2dc9a7cf1ff508046f84f529b189cfdc823492d3af34939208eaaa
client  | 0.043 DEBUG [Agent 0x55cb01727270] state -> InProgress
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] Compatible upgrade Version1 ==> Version2
client  | 0.043 DEBUG Making Write Handshake CryptoDxState, v=Version2 cipher=4865
client  | 0.043 DEBUG Making Read Handshake CryptoDxState, v=Version2 cipher=4865
client  | 0.043 DEBUG [Crypto] Handshake keys installed
client  | 0.043 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 737, tv_nsec: 686991530 }
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] State change from WaitVersion -> Handshaking
client  | 0.043 INFO [Client 8b91192a8c0d5d19] Garbage packet: Transport error: InvalidPacket
client  | 0.043 WARN [Client ...] Dropped received packet: Garbage packet; Total: 1
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Initial dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] pn=2 type=Initial pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 46
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 77, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.043 DEBUG Building Handshake dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] pad Initial from 46 to PLPMTU 1252
client  | 0.043 DEBUG ECN probing: sent 3 probes
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Initial dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.043 DEBUG Building Handshake dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.043 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] delay duration 57.877011ms
client  | 0.043 DEBUG Setting timeout of 57.877011ms
client  | 0.043 DEBUG [Client 8b91192a8c0d5d19] pn=0 type=Handshake pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 782
client  |   -> RX Crypto { offset: 0, len: 742 }
client  | 0.043 DEBUG Read Ok(742) bytes
client  | 0.044 DEBUG [Agent 0x55cb01727270] state -> AuthenticationPending
client  | 0.044 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 737, tv_nsec: 687707975 }
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG Building Initial dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.044 DEBUG Building Handshake dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] pn=0 type=Handshake pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 45
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 82, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] Drop packet number space in
client  | 0.044 DEBUG [LossRecovery] Reset loss recovery state for Initial
client  | 0.044 DEBUG ECN probing: sent 4 probes
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG Building Handshake dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.044 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] delay duration 99.094259ms
client  | 0.044 DEBUG Setting timeout of 99.094259ms
client  | 0.044 DEBUG StateChange(Handshaking)
client  | 0.044 WARN Cannot create stream ConnectionState
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] Authenticated Ok
client  | 0.044 DEBUG Write secret available for ApplicationData: SymKey [32]: f1e687533e3a871db9555a2345d7f9f29826da3fb421172e02ce39b5669715ba
client  | 0.044 DEBUG Read secret available for ApplicationData: SymKey [32]: ad9a927f445e061ae0fa00fa42136793fceb2fb99a7745142dc62c0e0162602a
client  | 0.044 DEBUG [Agent 0x55cb01727270] SSL_AuthCertificateComplete: Ok(())
client  | 0.044 DEBUG [Agent 0x55cb01727270] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] TLS connection complete
client  | 0.044 DEBUG Making Write ApplicationData CryptoDxState, v=Version2 cipher=4865
client  | 0.044 DEBUG [Crypto] Application write key installed
client  | 0.044 DEBUG Making Read ApplicationData CryptoDxState, v=Version2 cipher=4865
client  | 0.044 DEBUG [Crypto] application read keys installed
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] State change from Handshaking -> Connected
client  | 0.044 INFO [Client 8b91192a8c0d5d19] Connection established
client  | 0.044 DEBUG stream BiDi creatable
client  | 0.044 INFO Created stream 0 for https://server4/jrgxsvdnbc
client  | 0.044 INFO Saving https://server4/jrgxsvdnbc to "/downloads/jrgxsvdnbc"
client  | 0.044 DEBUG stream UniDi creatable
client  | 0.044 DEBUG StateChange(Connected)
client  | 0.044 DEBUG stream 0 writable
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG Building Handshake dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.044 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] pn=1 type=Handshake pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 75
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.044 DEBUG packet_sent this=0x55cb01743290, pn=1, ps=75
client  | 0.044 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] pn=0 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 130
client  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 9779, ignore_order: false }
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 0.044 DEBUG packet_sent this=0x55cb01743290, pn=0, ps=55
client  | 0.044 DEBUG ECN probing: sent 5 probes
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG Building Handshake dcid Some(CID [10]: b91f6cfeb6d115c68f2c) scid Some(CID [0]: )
client  | 0.044 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.044 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG [Client 8b91192a8c0d5d19] delay duration 99.115054ms
client  | 0.044 DEBUG Setting timeout of 99.115054ms
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] pn=2 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 46
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 77, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] Rx ACK space=in, ranges=[0..=1]
server  | 1.024 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
server  | 1.024 DEBUG on_packets_acked this=0x55ad6fc3eb20, limited=1, bytes_in_flight=782, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.024 DEBUG Acked crypto frame space=in offset=0 length=1178
server  | 1.024 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:45067] Path validated Instant { tv_sec: 747, tv_nsec: 703992423 }
server  | 1.024 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 3
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.024 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 1.024 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 1.024 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.024 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] delay duration 68.962436ms
server  | 1.024 DEBUG Setting timeout of 68.962436ms
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] Drop packet number space in
server  | 1.024 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] pn=0 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 45
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 82, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] Rx ACK space=hs, ranges=[0..=0]
server  | 1.024 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.024 DEBUG on_packets_acked this=0x55ad6fc3eb20, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=782
server  | 1.024 DEBUG Acked crypto frame space=hs offset=0 length=742
server  | 1.024 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:45067] Path validated Instant { tv_sec: 747, tv_nsec: 704136041 }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.024 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 1.024 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.024 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] delay duration 54.009929ms
server  | 1.024 DEBUG Setting timeout of 54.009929ms
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 75
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.024 DEBUG Read Ok(36) bytes
server  | 1.024 DEBUG [Agent 0x55ad6fc3f1e0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] TLS connection complete
server  | 1.024 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:45067] Path validated Instant { tv_sec: 747, tv_nsec: 704261134 }
server  | 1.024 DEBUG Making Read ApplicationData CryptoDxState, v=Version2 cipher=4865
server  | 1.024 DEBUG [Crypto] application read keys installed
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] State change from Handshaking -> Connected
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] State change from Connected -> Confirmed
server  | 1.024 DEBUG PMTUD started with probe size 1380
server  | 1.024 INFO [Server 8b91192a8c0d5d19] Connection established
server  | 1.024 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 747, tv_nsec: 704261134 }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 55
server  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 9779, ignore_order: false }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.024 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 747, tv_nsec: 714040134 }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.024 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: b91f6cfeb6d115c68f2c)
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 44
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] Drop packet number space hs
server  | 1.024 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.024 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [243, 26, 197, 105, 190, 58, 228, 213, 209, 215], stateless_reset_token: [65, 249, 106, 94, 212, 129, 106, 58, 188, 161, 152, 47, 218, 68, 30, 183] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [109, 61, 127, 243, 242, 242, 16, 95, 179, 69], stateless_reset_token: [210, 78, 101, 246, 138, 129, 182, 101, 5, 3, 178, 216, 127, 213, 191, 194] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [224, 94, 39, 90, 54, 200, 34, 211, 6, 117], stateless_reset_token: [83, 186, 15, 36, 40, 221, 55, 229, 24, 134, 216, 53, 31, 215, 49, 245] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [238, 97, 82, 113, 251, 52, 188, 28, 42, 224], stateless_reset_token: [38, 163, 175, 176, 166, 145, 20, 92, 106, 21, 205, 169, 188, 0, 128, 210] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [193, 155, 177, 123, 172, 34, 48, 38, 54, 213], stateless_reset_token: [252, 139, 166, 145, 65, 20, 169, 222, 81, 203, 61, 154, 96, 126, 175, 57] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [59, 18, 137, 57, 194, 254, 209, 47, 67, 19], stateless_reset_token: [187, 54, 247, 236, 130, 20, 189, 67, 218, 145, 182, 176, 8, 221, 17, 218] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [244, 153, 240, 42, 249, 250, 84, 87, 111, 182], stateless_reset_token: [204, 227, 162, 130, 123, 194, 80, 222, 41, 84, 153, 134, 111, 92, 31, 250] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 8582, ignore_order: false }
server  |   TX -> Padding { len: 1052 }
server  | 1.024 DEBUG packet_sent this=0x55ad6fc3eb20, pn=0, ps=1288
server  | 1.024 DEBUG ECN probing: sent 4 probes
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.024 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.024 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.024 DEBUG [Server 8b91192a8c0d5d19] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.024 DEBUG packet_sent this=0x55ad6fc3eb20, pn=1, ps=1332
server  | 1.024 DEBUG ECN probing: sent 5 probes
server  | 1.025 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.025 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.025 DEBUG [Server 8b91192a8c0d5d19] delay duration 1.423826ms
server  | 1.025 DEBUG Setting timeout of 1.423826ms
server  | 1.025 DEBUG [Server 8b91192a8c0d5d19] send session ticket 406b00088b91192a8c0d5d190104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ab91f6cfeb6d115c68f2c11146b3343cfaa8afaca6b3343cf00000001ff00001d6ab200c0000000ff02de1a0243e8200100686921
server  | 1.025 DEBUG Path = 'jrgxsvdnbc'
server  | 1.025 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.025 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.025 DEBUG [Server 8b91192a8c0d5d19] delay duration 463.435µs
server  | 1.025 DEBUG Setting timeout of 463.435µs
server  | 1.027 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.027 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.027 DEBUG CRYPTO for ap offset=0, len=182
server  | 1.027 DEBUG [Server 8b91192a8c0d5d19] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  |   TX -> Crypto { offset: 0, len: 182 }
server  | 1.027 DEBUG packet_sent this=0x55ad6fc3eb20, pn=2, ps=1232
server  | 1.027 DEBUG ECN probing: sent 6 probes
server  | 1.027 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG [Server 8b91192a8c0d5d19] delay duration 1.010469ms
server  | 1.027 DEBUG Setting timeout of 1.010469ms
server  | 1.027 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG [Server 8b91192a8c0d5d19] delay duration 997.204µs
server  | 1.027 DEBUG Setting timeout of 997.204µs
server  | 1.029 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.029 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.029 DEBUG CRYPTO for ap offset=182, len=139
server  | 1.029 DEBUG [Server 8b91192a8c0d5d19] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 207
server  |   TX -> Crypto { offset: 182, len: 139 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 191, 214, 0, 148, 109, 138, 113, 45, 102, 228, 148, 109, 83, 66, 132, 10, 188, 113, 170, 230, 78, 6, 217, 90, 130, 143, 250, 222, 20, 63, 8, 180, 144, 248, 34, 248] }
server  | 1.029 DEBUG packet_sent this=0x55ad6fc3eb20, pn=3, ps=207
server  | 1.029 DEBUG ECN probing: sent 7 probes
server  | 1.029 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.029 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.029 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.029 DEBUG [Server 8b91192a8c0d5d19] delay duration 4.948472ms
server  | 1.029 DEBUG Setting timeout of 4.948472ms
server  | 1.029 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.029 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.029 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.029 DEBUG [Server 8b91192a8c0d5d19] delay duration 4.926892ms
server  | 1.029 DEBUG Setting timeout of 4.926892ms
server  | 1.035 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.035 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.035 DEBUG [Server 8b91192a8c0d5d19] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 27
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 1367, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.035 DEBUG ECN probing: sent 8 probes
server  | 1.035 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.035 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.035 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.035 DEBUG [Server 8b91192a8c0d5d19] delay duration 100.989235ms
server  | 1.035 DEBUG Setting timeout of 100.989235ms
server  | 1.035 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.035 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.035 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.035 DEBUG [Server 8b91192a8c0d5d19] delay duration 100.962846ms
server  | 1.035 DEBUG Setting timeout of 100.962846ms
client  | 0.076 DEBUG [Client 8b91192a8c0d5d19] pn=1 type=Handshake pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 44
client  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.076 DEBUG [Client 8b91192a8c0d5d19] Rx ACK space=hs, ranges=[0..=1]
client  | 0.076 DEBUG [LossRecovery] ACK for Handshake - largest_acked=1
client  | 0.076 DEBUG on_packets_acked this=0x55cb01743290, limited=1, bytes_in_flight=55, cwnd=12520, state=SlowStart, new_acked=75
client  | 0.076 DEBUG Acked crypto frame space=hs offset=0 length=36
client  | 0.076 DEBUG [Client 8b91192a8c0d5d19] pn=0 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 1288
client  |   -> RX HandshakeDone
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [243, 26, 197, 105, 190, 58, 228, 213, 209, 215], stateless_reset_token: [65, 249, 106, 94, 212, 129, 106, 58, 188, 161, 152, 47, 218, 68, 30, 183] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [109, 61, 127, 243, 242, 242, 16, 95, 179, 69], stateless_reset_token: [210, 78, 101, 246, 138, 129, 182, 101, 5, 3, 178, 216, 127, 213, 191, 194] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [224, 94, 39, 90, 54, 200, 34, 211, 6, 117], stateless_reset_token: [83, 186, 15, 36, 40, 221, 55, 229, 24, 134, 216, 53, 31, 215, 49, 245] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [238, 97, 82, 113, 251, 52, 188, 28, 42, 224], stateless_reset_token: [38, 163, 175, 176, 166, 145, 20, 92, 106, 21, 205, 169, 188, 0, 128, 210] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [193, 155, 177, 123, 172, 34, 48, 38, 54, 213], stateless_reset_token: [252, 139, 166, 145, 65, 20, 169, 222, 81, 203, 61, 154, 96, 126, 175, 57] }
client  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [59, 18, 137, 57, 194, 254, 209, 47, 67, 19], stateless_reset_token: [187, 54, 247, 236, 130, 20, 189, 67, 218, 145, 182, 176, 8, 221, 17, 218] }
client  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [244, 153, 240, 42, 249, 250, 84, 87, 111, 182], stateless_reset_token: [204, 227, 162, 130, 123, 194, 80, 222, 41, 84, 153, 134, 111, 92, 31, 250] }
client  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 8582, ignore_order: false }
client  |   -> RX Padding { len: 1052 }
client  | 0.076 DEBUG [Client 8b91192a8c0d5d19] State change from Connected -> Confirmed
client  | 0.076 DEBUG PMTUD started with probe size 1380
client  | 0.076 DEBUG [Client 8b91192a8c0d5d19] Drop packet number space hs
client  | 0.076 DEBUG [LossRecovery] Reset loss recovery state for Handshake
client  | 0.076 DEBUG [Client 8b91192a8c0d5d19] No preferred address to migrate to
client  | 0.076 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 737, tv_nsec: 729488958 }
client  | 0.076 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.076 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.076 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] pn=1 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1323 }
client  | 0.077 DEBUG packet_sent this=0x55cb01743290, pn=1, ps=1352
client  | 0.077 DEBUG ECN probing: sent 6 probes
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] delay duration 8.276951ms
client  | 0.077 DEBUG Setting timeout of 8.276951ms
client  | 0.077 WARN Unhandled event StateChange(Confirmed)
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] delay duration 8.241625ms
client  | 0.077 DEBUG Setting timeout of 8.241625ms
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] pn=1 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Ping
client  |   -> RX Padding { len: 1313 }
client  | 0.077 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 737, tv_nsec: 721987227 }
client  | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 737, tv_nsec: 721987227 }
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] pn=2 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 6, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.077 DEBUG ECN probing: sent 7 probes
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 8b91192a8c0d5d19] delay duration 109.391359ms
client  | 0.078 DEBUG Setting timeout of 109.391359ms
client  | 0.078 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG [Client 8b91192a8c0d5d19] delay duration 109.362595ms
client  | 0.078 DEBUG Setting timeout of 109.362595ms
client  | 0.078 DEBUG [Client 8b91192a8c0d5d19] pn=2 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  |   -> RX Crypto { offset: 0, len: 182 }
client  | 0.078 DEBUG Read Ok(182) bytes
client  | 0.078 DEBUG [Agent 0x55cb01727270] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.078 DEBUG setting max_stream_data to 1048576
client  | 0.078 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 737, tv_nsec: 731637852 }
client  | 0.078 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG [Client 8b91192a8c0d5d19] delay duration 8.542647ms
client  | 0.078 DEBUG Setting timeout of 8.542647ms
client  | 0.079 DEBUG stream BiDi creatable
client  | 0.079 DEBUG stream UniDi creatable
client  | 0.079 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG [Client 8b91192a8c0d5d19] delay duration 8.485971ms
client  | 0.079 DEBUG Setting timeout of 8.485971ms
client  | 0.079 DEBUG [Client 8b91192a8c0d5d19] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 738, tv_nsec: 54332306 } }
client  | 0.079 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 738, tv_nsec: 54332306 } })
client  | 0.079 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.079 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 737, tv_nsec: 723177358 }
client  | 0.079 DEBUG [Client 8b91192a8c0d5d19] pn=3 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 15, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.079 DEBUG ECN probing: sent 8 probes
client  | 0.079 DEBUG Setting timeout of 331.082252ms
client  | 0.080 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.080 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.080 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 737, tv_nsec: 724454409 }
client  | 0.080 DEBUG [Client 8b91192a8c0d5d19] pn=4 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 174, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.080 DEBUG ECN probing: sent 9 probes
client  | 0.080 DEBUG Setting timeout of 329.77773ms
client  | 0.080 DEBUG Setting timeout of 329.767632ms
client  | 0.086 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.086 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.086 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 737, tv_nsec: 730455987 }
client  | 0.086 DEBUG [Client 8b91192a8c0d5d19] pn=5 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 925, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.086 DEBUG ECN probing: sent 10 probes
client  | 0.086 DEBUG ECN probing concluded with 10 probes sent
client  | 0.086 DEBUG Setting timeout of 323.808232ms
client  | 0.086 DEBUG Setting timeout of 323.798203ms
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 1352
server  |   -> RX Ping
server  |   -> RX Padding { len: 1323 }
server  | 1.057 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 747, tv_nsec: 737432976 }
server  | 1.057 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 747, tv_nsec: 737432976 }
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 26
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.057 DEBUG ECN probing: sent 9 probes
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] delay duration 78.722735ms
server  | 1.057 DEBUG Setting timeout of 78.722735ms
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 6, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] Rx ACK space=ap, ranges=[0..=1]
server  | 1.057 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.057 DEBUG on_packets_acked this=0x55ad6fc3eb20, limited=1, bytes_in_flight=1439, cwnd=12320, state=SlowStart, new_acked=2620
server  | 1.057 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.057 DEBUG PMTUD started with probe size 1420
server  | 1.057 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1353 }
server  | 1.057 DEBUG packet_sent this=0x55ad6fc3eb20, pn=6, ps=1372
server  | 1.057 DEBUG ECN probing: sent 10 probes
server  | 1.057 DEBUG ECN probing concluded with 10 probes sent
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] delay duration 83.729467ms
server  | 1.057 DEBUG Setting timeout of 83.729467ms
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.057 DEBUG [Server 8b91192a8c0d5d19] delay duration 83.704781ms
server  | 1.057 DEBUG Setting timeout of 83.704781ms
server  | 1.058 DEBUG [Server 8b91192a8c0d5d19] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 47
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 15, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
server  | 1.058 DEBUG [Server 8b91192a8c0d5d19] Rx ACK space=ap, ranges=[0..=2]
server  | 1.058 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 1.058 INFO ECN validation succeeded, path is capable
server  | 1.058 DEBUG on_packets_acked this=0x55ad6fc3eb20, limited=1, bytes_in_flight=1579, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.058 DEBUG Acked crypto frame space=ap offset=0 length=182
server  | 1.058 INFO [Server 8b91192a8c0d5d19] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye!
server  | 1.058 DEBUG [Server 8b91192a8c0d5d19] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 747, tv_nsec: 965851803 } }
server  | 1.058 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:45067] Path validated Instant { tv_sec: 747, tv_nsec: 738458175 }
server  | 1.058 DEBUG [Server 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.058 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.058 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 747, tv_nsec: 738458175 }
server  | 1.058 DEBUG [Server 8b91192a8c0d5d19] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:45067 IpTos(Cs0, Ect0) len 30
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 -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 1.058 DEBUG Setting timeout of 227.263245ms
server  | 1.058 DEBUG Setting timeout of 227.250481ms
server  | 1.059 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 747, tv_nsec: 965851803 } }; Total: 4
server  | 1.060 DEBUG Setting timeout of 226.090018ms
server  | 1.060 DEBUG Setting timeout of 226.063869ms
server  | 1.065 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 747, tv_nsec: 965851803 } }; Total: 5
server  | 1.065 DEBUG Setting timeout of 220.125789ms
server  | 1.065 DEBUG Setting timeout of 220.111332ms
client  | 0.108 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.108 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.108 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 737, tv_nsec: 752731253 }
client  | 0.108 DEBUG [Client 8b91192a8c0d5d19] pn=6 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 3709, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.108 DEBUG Setting timeout of 301.52461ms
client  | 0.108 DEBUG Setting timeout of 301.511516ms
client  | 0.109 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.109 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 737, tv_nsec: 754055792 }
client  | 0.109 DEBUG [Client 8b91192a8c0d5d19] pn=7 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 3874, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.110 DEBUG Setting timeout of 300.208607ms
client  | 0.110 DEBUG [Client 8b91192a8c0d5d19] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG Building Short dcid Some(CID [10]: b91f6cfeb6d115c68f2c)
client  | 0.110 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 737, tv_nsec: 754140290 }
client  | 0.110 DEBUG [Client 8b91192a8c0d5d19] pn=8 type=Short pri-path:b91f6cfeb6d115c68f2c 0.0.0.0:45067->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 3885, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.110 DEBUG Setting timeout of 300.133517ms
client  | 0.110 DEBUG Setting timeout of 300.122196ms
server  | 1.088 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 747, tv_nsec: 965851803 } }; Total: 6
server  | 1.088 DEBUG Setting timeout of 197.781432ms
server  | 1.088 DEBUG Setting timeout of 197.754942ms
server  | 1.089 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 747, tv_nsec: 965851803 } }; Total: 7
server  | 1.089 DEBUG Setting timeout of 196.532674ms
server  | 1.089 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 747, tv_nsec: 965851803 } }; Total: 8
server  | 1.089 DEBUG Setting timeout of 196.512156ms
server  | 1.089 DEBUG Setting timeout of 196.50362ms
server  | 1.287 DEBUG [Server 8b91192a8c0d5d19] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 747, tv_nsec: 965851803 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 1.287 INFO Closing timer expired
client  | 0.411 DEBUG [Client 8b91192a8c0d5d19] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 738, tv_nsec: 54332306 } } -> Closed(Application(0))
client  | 0.411 INFO Closing timer expired
client  | 0.411 DEBUG Output::None
client  | 0.411 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 0.411 DEBUG Timer fired while closed
client  | 0.411 DEBUG Output::None

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

2025-04-30 12:55:51,792 Using the client's key log file.
2025-04-30 12:55:51,805 
2025-04-30 12:55:51,805 Using the client's key log file.
2025-04-30 12:55:51,805 Using the client's key log file.
2025-04-30 12:55:51,816 
2025-04-30 12:55:51,817 Using the client's key log file.
2025-04-30 12:55:53,004 Check of downloaded files succeeded.