2025-04-30 12:48:59,093 Generated random file: exozqjswzo of size: 1024
2025-04-30 12:48:59,094 Requests: https://server4:443/exozqjswzo
2025-04-30 12:48:59,153 
2025-04-30 12:48:59,153 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_90pokx2m/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_9v5utacx/ DOWNLOADS=/tmp/download_1_81j4tw/ SERVER_LOGS=/tmp/logs_server_gq78p6lk CLIENT_LOGS=/tmp/logs_client_xahrtewa 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/exozqjswzo"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:49:11,603  Container sim  Recreate
 Container sim  Recreated
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
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.xk2lsaSmcD
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.xk2lsaSmcD
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.xk2lsaSmcD -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  |             60:ac:d2:40:08:6d:84:95:9a:54:e5:89:34:83:65:77:
server  |             e6:7c:08:ad
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:48:59 2025
server  |             Not After : Sat May 10 12:48:59 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:61:af:c1:54:57:bc:db:37:20:6f:0b:60:87:94:be:
server  |                     82:48:5d:22:27:0f:ae:f6:8d:43:ee:2a:a2:0a:a7:5c:
server  |                     2f:30:3a:11:e4:c8:c8:0d:a1:86:a3:8d:ce:4b:5b:69:
server  |                     bc:d8:dc:b9:d8:67:53:c7:1e:97:ee:da:8a:16:c2:73:
server  |                     98
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  |                 7e:e1:5f:68:7b:11:b1:b2:7b:a6:d8:58:3c:30:5a:ff:
server  |                 c2:21:bf:09
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 79:fe:24:13:36:5f:29:01:a7:0e:72:a9:ff:0c:56:22:
server  |                 98:b0:74:ce
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:63:e5:18:52:5a:92:d8:d9:e1:04:11:f8:
server  |         27:a3:39:5a:01:ff:ff:f8:ae:dc:1d:3e:8a:4c:df:fe:
server  |         54:03:14:84:02:20:38:31:23:2c:04:45:9b:b7:5c:08:
server  |         b7:12:1f:23:e6:bc:fb:54:24:c5:52:6d:54:c9:44:c6:
server  |         f0:5e:b0:73:9d:8c
server  |     Fingerprint (SHA-256):
server  |         32:35:68:C6:53:FD:F0:FA:9C:3C:A6:30:56:1F:75:52:6C:D9:6A:04:11:D9:A3:F1:E9:6D:AF:15:2A:B9:65:38
server  |     Fingerprint (SHA1):
server  |         EC:42:01:1E:D5:F6:98:00:6A:3B:33:0E:54:05:CD:02:6B:0B:B9:95
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.769 DEBUG [Server] Unsupported version: 57414954
server  | 0.769 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:51639 IpTos(Cs0, NotEct) len 15
server  | 0.769 DEBUG [Server] Unsupported version: 57414954
server  | 0.769 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:51639 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.036321072s
server  | 0.770 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.770 DEBUG [Server] Unsupported version: 57414954
server  | 0.770 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:51639 IpTos(Cs0, NotEct) len 15
server  | 0.770 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: 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/exozqjswzo ']'
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/exozqjswzo
client  | '
client  | ++ tee -i -a /logs/client.log
client  | 0.000 DEBUG Logging initialized
client  | 0.001 DEBUG Default socket send buffer size is 212992
client  | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing
client  | 0.001 INFO hq-interop Client connecting: 0.0.0.0:33235 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=5292b520c35a3b0a
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:33235->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:5292b520c35a3b0a 0.0.0.0:33235->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:5292b520c35a3b0a 0.0.0.0:33235->193.167.100.100:443] Path validated Instant { tv_sec: 337, tv_nsec: 549957190 }
client  | 0.001 DEBUG [Client 5292b520c35a3b0a] client_start
client  | 0.001 DEBUG Writing transport parameters, msg=1
client  | 0.002 DEBUG [Agent 0x55f0b752b270] state -> InProgress
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] State change from Init -> WaitInitial
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 5292b520c35a3b0a) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] pn=0 type=Initial pri-path:5292b520c35a3b0a 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.002 DEBUG packet_sent this=0x55f0b753e970, pn=0, ps=1252
client  | 0.002 DEBUG ECN probing: sent 1 probes
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 5292b520c35a3b0a) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1213, len=326
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] pn=1 type=Initial pri-path:5292b520c35a3b0a 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 366
client  |   TX -> Crypto { offset: 1213, len: 326 }
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] pad Initial from 366 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55f0b753e970, pn=1, ps=1252
client  | 0.002 DEBUG ECN probing: sent 2 probes
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 5292b520c35a3b0a) 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 5292b520c35a3b0a] delay duration 3.834291ms
client  | 0.002 DEBUG Setting timeout of 3.834291ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: 5292b520c35a3b0a) 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 5292b520c35a3b0a] delay duration 3.802411ms
client  | 0.002 DEBUG Setting timeout of 3.802411ms
client  | 0.007 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [8]: 5292b520c35a3b0a) 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 5292b520c35a3b0a] delay duration 294.812667ms
client  | 0.007 DEBUG Setting timeout of 294.812667ms
server  | 0.972 DEBUG [Server] Handle initial
server  | 0.972 INFO AddressValidation: no token; accepting
server  | 0.972 INFO [Server] Accept connection CID [8]: 5292b520c35a3b0a
server  | 0.972 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.974 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.974 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: 5292b520c35a3b0a
server  | 0.974 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=5292b520c35a3b0a
server  | 0.974 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.974 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] State change from Init -> WaitInitial
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 1252
server  |   -> RX Crypto { offset: 0, len: 1213 }
server  | 0.974 DEBUG Read Ok(1213) bytes
server  | 0.974 DEBUG [Agent 0x55db95c821d0] state -> InProgress
server  | 0.974 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 347, tv_nsec: 572459213 }
server  | 0.974 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:33235] Make permanent
server  | 0.974 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:33235] set as primary path
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] State change from WaitInitial -> WaitVersion
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.974 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:33235 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.974 DEBUG ECN probing: sent 1 probes
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.974 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.974 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] delay duration 298.06658ms
server  | 0.974 DEBUG Setting timeout of 298.06658ms
server  | 0.974 DEBUG [Server 5292b520c35a3b0a] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 366
server  |   -> RX Crypto { offset: 1213, len: 326 }
server  | 0.974 DEBUG Read Ok(326) bytes
server  | 0.974 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.975 DEBUG Read secret available for Handshake: SymKey [32]: b27b6fb27933d6d33b592161e062610834a26e3229e8edc4a366a755eaa43a2a
server  | 0.975 DEBUG Write secret available for Handshake: SymKey [32]: 0d378b0d0bdaabf410b46d4a21b137a4ab9d77698332c30406fa9a12dea91db0
server  | 0.975 DEBUG Writing transport parameters, msg=8
server  | 0.975 DEBUG Read secret available for ApplicationData: SymKey [32]: 1765363f7643985a0bb282da8acb5faa6225320bedeb03a5c51c84a33ec9e0ff
server  | 0.975 DEBUG Write secret available for ApplicationData: SymKey [32]: 21959b16e240a98eaa788a192fc14ca4e8b07c051f799eb6679b3e72e2f58e47
server  | 0.975 DEBUG [Agent 0x55db95c821d0] state -> InProgress
server  | 0.975 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.975 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.976 DEBUG [Crypto] Handshake keys installed
server  | 0.976 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 0.976 DEBUG [Crypto] Application write key installed
server  | 0.976 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 347, tv_nsec: 574422809 }
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] State change from WaitVersion -> Handshaking
server  | 0.976 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG CRYPTO for in offset=0, len=1178
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] pn=1 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:33235 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.976 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] pad Initial from 1227 to PLPMTU 1232
server  | 0.976 DEBUG packet_sent this=0x55db95c81b40, pn=1, ps=1232
server  | 0.976 DEBUG ECN probing: sent 2 probes
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG CRYPTO for hs offset=0, len=731
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 771
server  |   TX -> Crypto { offset: 0, len: 731 }
server  | 0.976 DEBUG packet_sent this=0x55db95c81b40, pn=0, ps=771
server  | 0.976 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.976 DEBUG ECN probing: sent 3 probes
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.976 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] delay duration 298.623713ms
server  | 0.976 DEBUG Setting timeout of 298.623713ms
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 0.976 DEBUG Building Short dcid Some(CID [0]: )
server  | 0.976 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.976 DEBUG [Server 5292b520c35a3b0a] delay duration 298.596853ms
server  | 0.976 DEBUG Setting timeout of 298.596853ms
client  | 0.040 DEBUG [Client 5292b520c35a3b0a] pn=0 type=Initial pri-path:5292b520c35a3b0a 0.0.0.0:33235->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 5292b520c35a3b0a] 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=0x55f0b753e970, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.040 DEBUG Acked crypto frame space=in offset=0 length=1213
client  | 0.040 DEBUG [Client 5292b520c35a3b0a] Changing to use Server CID=ac08d8d19d64e4a0fabb
client  | 0.040 DEBUG [Client 5292b520c35a3b0a] State change from WaitInitial -> WaitVersion
client  | 0.040 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.040 DEBUG Building Initial dcid Some(CID [10]: ac08d8d19d64e4a0fabb) 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 5292b520c35a3b0a] delay duration 78.704254ms
client  | 0.040 DEBUG Setting timeout of 78.704254ms
client  | 0.040 WARN Unhandled event StateChange(WaitVersion)
client  | 0.040 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.040 DEBUG Building Initial dcid Some(CID [10]: ac08d8d19d64e4a0fabb) 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 5292b520c35a3b0a] delay duration 78.668247ms
client  | 0.040 DEBUG Setting timeout of 78.668247ms
client  | 0.042 DEBUG [Client 5292b520c35a3b0a] pn=1 type=Initial pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->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 5292b520c35a3b0a] 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=0x55f0b753e970, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=1252
client  | 0.042 DEBUG Acked crypto frame space=in offset=1213 length=326
client  | 0.042 DEBUG Read Ok(1178) bytes
client  | 0.043 DEBUG Write secret available for Handshake: SymKey [32]: b27b6fb27933d6d33b592161e062610834a26e3229e8edc4a366a755eaa43a2a
client  | 0.043 DEBUG Read secret available for Handshake: SymKey [32]: 0d378b0d0bdaabf410b46d4a21b137a4ab9d77698332c30406fa9a12dea91db0
client  | 0.043 DEBUG [Agent 0x55f0b752b270] state -> InProgress
client  | 0.043 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.043 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.043 DEBUG [Crypto] Handshake keys installed
client  | 0.043 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 337, tv_nsec: 591832362 }
client  | 0.043 DEBUG [Client 5292b520c35a3b0a] State change from WaitVersion -> Handshaking
client  | 0.043 INFO [Client 5292b520c35a3b0a] Garbage packet: Transport error: InvalidPacket
client  | 0.043 WARN [Client ...] Dropped received packet: Garbage packet; Total: 1
client  | 0.043 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Initial dcid Some(CID [10]: ac08d8d19d64e4a0fabb) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client 5292b520c35a3b0a] pn=2 type=Initial pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 46
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 94, 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]: ac08d8d19d64e4a0fabb) scid Some(CID [0]: )
client  | 0.043 DEBUG [Client 5292b520c35a3b0a] pad Initial from 46 to PLPMTU 1252
client  | 0.043 DEBUG ECN probing: sent 3 probes
client  | 0.043 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.043 DEBUG Building Initial dcid Some(CID [10]: ac08d8d19d64e4a0fabb) scid Some(CID [0]: )
client  | 0.043 DEBUG Building Handshake dcid Some(CID [10]: ac08d8d19d64e4a0fabb) 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 5292b520c35a3b0a] delay duration 58.063354ms
client  | 0.043 DEBUG Setting timeout of 58.063354ms
client  | 0.043 DEBUG [Client 5292b520c35a3b0a] pn=0 type=Handshake pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 771
client  |   -> RX Crypto { offset: 0, len: 731 }
client  | 0.043 DEBUG Read Ok(731) bytes
client  | 0.044 DEBUG [Agent 0x55f0b752b270] state -> AuthenticationPending
client  | 0.044 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 337, tv_nsec: 592707035 }
client  | 0.044 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG Building Initial dcid Some(CID [10]: ac08d8d19d64e4a0fabb) scid Some(CID [0]: )
client  | 0.044 DEBUG Building Handshake dcid Some(CID [10]: ac08d8d19d64e4a0fabb) scid Some(CID [0]: )
client  | 0.044 DEBUG [Client 5292b520c35a3b0a] pn=0 type=Handshake pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 45
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 83, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 0.044 DEBUG [Client 5292b520c35a3b0a] 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 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG Building Handshake dcid Some(CID [10]: ac08d8d19d64e4a0fabb) 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 5292b520c35a3b0a] delay duration 99.602528ms
client  | 0.044 DEBUG Setting timeout of 99.602528ms
client  | 0.044 DEBUG StateChange(Handshaking)
client  | 0.044 WARN Cannot create stream ConnectionState
client  | 0.044 DEBUG [Client 5292b520c35a3b0a] Authenticated Ok
client  | 0.044 DEBUG Write secret available for ApplicationData: SymKey [32]: 1765363f7643985a0bb282da8acb5faa6225320bedeb03a5c51c84a33ec9e0ff
client  | 0.044 DEBUG Read secret available for ApplicationData: SymKey [32]: 21959b16e240a98eaa788a192fc14ca4e8b07c051f799eb6679b3e72e2f58e47
client  | 0.044 DEBUG [Agent 0x55f0b752b270] SSL_AuthCertificateComplete: Ok(())
client  | 0.044 DEBUG [Agent 0x55f0b752b270] 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 5292b520c35a3b0a] TLS connection complete
client  | 0.044 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.044 DEBUG [Crypto] Application write key installed
client  | 0.044 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.044 DEBUG [Crypto] application read keys installed
client  | 0.044 DEBUG [Client 5292b520c35a3b0a] State change from Handshaking -> Connected
client  | 0.044 INFO [Client 5292b520c35a3b0a] Connection established
client  | 0.044 DEBUG stream BiDi creatable
client  | 0.044 INFO Created stream 0 for https://server4/exozqjswzo
client  | 0.044 INFO Saving https://server4/exozqjswzo to "/downloads/exozqjswzo"
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 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.044 DEBUG Building Handshake dcid Some(CID [10]: ac08d8d19d64e4a0fabb) scid Some(CID [0]: )
client  | 0.044 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.044 DEBUG [Client 5292b520c35a3b0a] pn=1 type=Handshake pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 75
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.045 DEBUG packet_sent this=0x55f0b753e970, pn=1, ps=75
client  | 0.045 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.045 DEBUG [Client 5292b520c35a3b0a] pn=0 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 130
client  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 9835, ignore_order: false }
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 0.045 DEBUG packet_sent this=0x55f0b753e970, pn=0, ps=55
client  | 0.045 DEBUG ECN probing: sent 5 probes
client  | 0.045 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.045 DEBUG Building Handshake dcid Some(CID [10]: ac08d8d19d64e4a0fabb) scid Some(CID [0]: )
client  | 0.045 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.045 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.045 DEBUG [Client 5292b520c35a3b0a] delay duration 99.574165ms
client  | 0.045 DEBUG Setting timeout of 99.574165ms
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] pn=2 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 46
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 94, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] Rx ACK space=in, ranges=[0..=1]
server  | 1.009 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
server  | 1.009 DEBUG on_packets_acked this=0x55db95c81b40, limited=1, bytes_in_flight=771, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.009 DEBUG Acked crypto frame space=in offset=0 length=1178
server  | 1.009 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:33235] Path validated Instant { tv_sec: 347, tv_nsec: 608945022 }
server  | 1.009 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 2
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.009 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 1.009 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 1.009 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.009 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] delay duration 69.044424ms
server  | 1.009 DEBUG Setting timeout of 69.044424ms
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] Drop packet number space in
server  | 1.009 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] pn=0 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 45
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 83, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] Rx ACK space=hs, ranges=[0..=0]
server  | 1.009 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.009 DEBUG on_packets_acked this=0x55db95c81b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=771
server  | 1.009 DEBUG Acked crypto frame space=hs offset=0 length=731
server  | 1.009 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:33235] Path validated Instant { tv_sec: 347, tv_nsec: 609114348 }
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.009 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 1.009 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.009 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] delay duration 53.878898ms
server  | 1.009 DEBUG Setting timeout of 53.878898ms
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 75
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.009 DEBUG Read Ok(36) bytes
server  | 1.009 DEBUG [Agent 0x55db95c821d0] 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.009 DEBUG [Server 5292b520c35a3b0a] TLS connection complete
server  | 1.009 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:33235] Path validated Instant { tv_sec: 347, tv_nsec: 609221548 }
server  | 1.009 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.009 DEBUG [Crypto] application read keys installed
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] State change from Handshaking -> Connected
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] State change from Connected -> Confirmed
server  | 1.009 DEBUG PMTUD started with probe size 1380
server  | 1.009 INFO [Server 5292b520c35a3b0a] Connection established
server  | 1.009 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 347, tv_nsec: 609221548 }
server  | 1.009 DEBUG [Server 5292b520c35a3b0a] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 55
server  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 9835, ignore_order: false }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.010 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 347, tv_nsec: 619056548 }
server  | 1.010 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.010 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: ac08d8d19d64e4a0fabb)
server  | 1.010 DEBUG [Server 5292b520c35a3b0a] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:33235 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.010 DEBUG [Server 5292b520c35a3b0a] Drop packet number space hs
server  | 1.010 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.010 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.010 DEBUG [Server 5292b520c35a3b0a] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [134, 206, 42, 105, 227, 79, 217, 83, 77, 184], stateless_reset_token: [114, 123, 102, 231, 0, 6, 242, 144, 86, 106, 157, 4, 89, 181, 95, 198] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [78, 203, 12, 162, 42, 194, 47, 102, 195, 111], stateless_reset_token: [10, 100, 166, 20, 125, 78, 114, 48, 189, 205, 25, 120, 160, 25, 83, 184] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [108, 152, 186, 54, 101, 241, 254, 125, 11, 160], stateless_reset_token: [27, 174, 230, 242, 19, 177, 68, 224, 142, 9, 186, 31, 28, 5, 192, 152] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [124, 125, 42, 70, 61, 161, 167, 3, 158, 138], stateless_reset_token: [74, 86, 17, 96, 83, 228, 142, 188, 193, 137, 51, 33, 123, 40, 112, 226] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [147, 50, 200, 31, 84, 140, 64, 175, 239, 211], stateless_reset_token: [47, 104, 215, 217, 12, 114, 246, 37, 198, 248, 140, 195, 189, 187, 154, 255] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [81, 241, 76, 16, 163, 30, 145, 181, 33, 249], stateless_reset_token: [94, 216, 10, 226, 27, 203, 78, 228, 217, 244, 167, 159, 5, 29, 3, 224] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [182, 196, 243, 151, 104, 6, 103, 199, 206, 134], stateless_reset_token: [214, 150, 90, 33, 222, 237, 40, 95, 128, 153, 116, 110, 131, 176, 138, 150] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 8595, ignore_order: false }
server  |   TX -> Padding { len: 1052 }
server  | 1.010 DEBUG packet_sent this=0x55db95c81b40, pn=0, ps=1288
server  | 1.010 DEBUG ECN probing: sent 4 probes
server  | 1.010 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.010 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.010 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.010 DEBUG [Server 5292b520c35a3b0a] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.010 DEBUG packet_sent this=0x55db95c81b40, pn=1, ps=1332
server  | 1.010 DEBUG ECN probing: sent 5 probes
server  | 1.010 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.010 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.010 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.010 DEBUG [Server 5292b520c35a3b0a] delay duration 1.315756ms
server  | 1.010 DEBUG Setting timeout of 1.315756ms
server  | 1.011 DEBUG [Server 5292b520c35a3b0a] send session ticket 406300085292b520c35a3b0a0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0aac08d8d19d64e4a0fabb110c00000001ea8a0aea000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.011 DEBUG Path = 'exozqjswzo'
server  | 1.011 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.011 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.011 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.011 DEBUG [Server 5292b520c35a3b0a] delay duration 232.423µs
server  | 1.011 DEBUG Setting timeout of 232.423µs
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.012 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.012 DEBUG CRYPTO for ap offset=0, len=182
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 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.012 DEBUG packet_sent this=0x55db95c81b40, pn=2, ps=1232
server  | 1.012 DEBUG ECN probing: sent 6 probes
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.012 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.012 DEBUG CRYPTO for ap offset=182, len=123
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 191
server  |   TX -> Crypto { offset: 182, len: 123 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 185, 68, 158, 15, 242, 113, 162, 220, 47, 7, 3, 111, 114, 122, 99, 83, 91, 23, 67, 25, 155, 106, 18, 133, 64, 236, 111, 76, 237, 57, 128, 167, 232, 185, 57, 80] }
server  | 1.012 DEBUG packet_sent this=0x55db95c81b40, pn=3, ps=191
server  | 1.012 DEBUG ECN probing: sent 7 probes
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.012 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.012 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] delay duration 946.846µs
server  | 1.012 DEBUG Setting timeout of 946.846µs
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.012 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.012 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.012 DEBUG [Server 5292b520c35a3b0a] delay duration 924.595µs
server  | 1.012 DEBUG Setting timeout of 924.595µs
server  | 1.014 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.014 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.014 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.014 DEBUG [Server 5292b520c35a3b0a] delay duration 4.752961ms
server  | 1.014 DEBUG Setting timeout of 4.752961ms
server  | 1.014 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.014 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.014 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.014 DEBUG [Server 5292b520c35a3b0a] delay duration 4.733905ms
server  | 1.014 DEBUG Setting timeout of 4.733905ms
server  | 1.020 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.020 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.020 DEBUG [Server 5292b520c35a3b0a] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 27
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 1397, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.020 DEBUG ECN probing: sent 8 probes
server  | 1.020 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.020 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.020 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.020 DEBUG [Server 5292b520c35a3b0a] delay duration 98.920813ms
server  | 1.020 DEBUG Setting timeout of 98.920813ms
server  | 1.020 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.020 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.020 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.021 DEBUG [Server 5292b520c35a3b0a] delay duration 98.900826ms
server  | 1.021 DEBUG Setting timeout of 98.900826ms
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] pn=1 type=Handshake pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->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.077 DEBUG [Client 5292b520c35a3b0a] Rx ACK space=hs, ranges=[0..=1]
client  | 0.077 DEBUG [LossRecovery] ACK for Handshake - largest_acked=1
client  | 0.077 DEBUG on_packets_acked this=0x55f0b753e970, limited=1, bytes_in_flight=55, cwnd=12520, state=SlowStart, new_acked=75
client  | 0.077 DEBUG Acked crypto frame space=hs offset=0 length=36
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] pn=0 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 1288
client  |   -> RX HandshakeDone
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [134, 206, 42, 105, 227, 79, 217, 83, 77, 184], stateless_reset_token: [114, 123, 102, 231, 0, 6, 242, 144, 86, 106, 157, 4, 89, 181, 95, 198] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [78, 203, 12, 162, 42, 194, 47, 102, 195, 111], stateless_reset_token: [10, 100, 166, 20, 125, 78, 114, 48, 189, 205, 25, 120, 160, 25, 83, 184] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [108, 152, 186, 54, 101, 241, 254, 125, 11, 160], stateless_reset_token: [27, 174, 230, 242, 19, 177, 68, 224, 142, 9, 186, 31, 28, 5, 192, 152] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [124, 125, 42, 70, 61, 161, 167, 3, 158, 138], stateless_reset_token: [74, 86, 17, 96, 83, 228, 142, 188, 193, 137, 51, 33, 123, 40, 112, 226] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [147, 50, 200, 31, 84, 140, 64, 175, 239, 211], stateless_reset_token: [47, 104, 215, 217, 12, 114, 246, 37, 198, 248, 140, 195, 189, 187, 154, 255] }
client  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [81, 241, 76, 16, 163, 30, 145, 181, 33, 249], stateless_reset_token: [94, 216, 10, 226, 27, 203, 78, 228, 217, 244, 167, 159, 5, 29, 3, 224] }
client  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [182, 196, 243, 151, 104, 6, 103, 199, 206, 134], stateless_reset_token: [214, 150, 90, 33, 222, 237, 40, 95, 128, 153, 116, 110, 131, 176, 138, 150] }
client  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 8595, ignore_order: false }
client  |   -> RX Padding { len: 1052 }
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] State change from Connected -> Confirmed
client  | 0.077 DEBUG PMTUD started with probe size 1380
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] Drop packet number space hs
client  | 0.077 DEBUG [LossRecovery] Reset loss recovery state for Handshake
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] No preferred address to migrate to
client  | 0.077 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 337, tv_nsec: 634623589 }
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.077 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] pn=1 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->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=0x55f0b753e970, pn=1, ps=1352
client  | 0.077 DEBUG ECN probing: sent 6 probes
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] delay duration 8.314116ms
client  | 0.077 DEBUG Setting timeout of 8.314116ms
client  | 0.077 WARN Unhandled event StateChange(Confirmed)
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.077 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.077 DEBUG [Client 5292b520c35a3b0a] delay duration 8.277638ms
client  | 0.077 DEBUG Setting timeout of 8.277638ms
client  | 0.078 DEBUG [Client 5292b520c35a3b0a] pn=1 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Ping
client  |   -> RX Padding { len: 1313 }
client  | 0.078 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 337, tv_nsec: 627073800 }
client  | 0.078 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 337, tv_nsec: 627073800 }
client  | 0.078 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.078 DEBUG [Client 5292b520c35a3b0a] pn=2 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 4, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.078 DEBUG ECN probing: sent 7 probes
client  | 0.078 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG [Client 5292b520c35a3b0a] delay duration 109.935731ms
client  | 0.078 DEBUG Setting timeout of 109.935731ms
client  | 0.078 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.078 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.078 DEBUG [Client 5292b520c35a3b0a] delay duration 109.906406ms
client  | 0.078 DEBUG Setting timeout of 109.906406ms
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] pn=2 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->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.079 DEBUG Read Ok(182) bytes
client  | 0.079 DEBUG [Agent 0x55f0b752b270] 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.079 DEBUG setting max_stream_data to 1048576
client  | 0.079 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 337, tv_nsec: 636832633 }
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] delay duration 8.543313ms
client  | 0.079 DEBUG Setting timeout of 8.543313ms
client  | 0.079 DEBUG stream BiDi creatable
client  | 0.079 DEBUG stream UniDi creatable
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] delay duration 8.475006ms
client  | 0.079 DEBUG Setting timeout of 8.475006ms
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 337, tv_nsec: 961048765 } }
client  | 0.079 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 337, tv_nsec: 961048765 } })
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.079 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 337, tv_nsec: 628387373 }
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] pn=3 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 18, 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 332.580912ms
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.079 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 337, tv_nsec: 628485686 }
client  | 0.079 DEBUG [Client 5292b520c35a3b0a] pn=4 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 31, 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 9 probes
client  | 0.079 DEBUG Setting timeout of 332.430551ms
client  | 0.079 DEBUG Setting timeout of 332.422406ms
client  | 0.086 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.086 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.086 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 337, tv_nsec: 635663044 }
client  | 0.086 DEBUG [Client 5292b520c35a3b0a] pn=5 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 928, 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 325.286515ms
client  | 0.086 DEBUG Setting timeout of 325.273331ms
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 1352
server  |   -> RX Ping
server  |   -> RX Padding { len: 1323 }
server  | 1.043 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 347, tv_nsec: 642654942 }
server  | 1.043 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 347, tv_nsec: 642654942 }
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 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.043 DEBUG ECN probing: sent 9 probes
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.043 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] delay duration 76.583587ms
server  | 1.043 DEBUG Setting timeout of 76.583587ms
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 4, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] Rx ACK space=ap, ranges=[0..=1]
server  | 1.043 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.043 DEBUG on_packets_acked this=0x55db95c81b40, limited=1, bytes_in_flight=1423, cwnd=12320, state=SlowStart, new_acked=2620
server  | 1.043 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.043 DEBUG PMTUD started with probe size 1420
server  | 1.043 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.043 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1353 }
server  | 1.043 DEBUG packet_sent this=0x55db95c81b40, pn=6, ps=1372
server  | 1.043 DEBUG ECN probing: sent 10 probes
server  | 1.043 DEBUG ECN probing concluded with 10 probes sent
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.043 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] delay duration 83.601032ms
server  | 1.043 DEBUG Setting timeout of 83.601032ms
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.043 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.043 DEBUG [Server 5292b520c35a3b0a] delay duration 83.578971ms
server  | 1.043 DEBUG Setting timeout of 83.578971ms
server  | 1.044 DEBUG [Server 5292b520c35a3b0a] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 IpTos(Cs0, Ect0) len 47
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 18, 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.044 DEBUG [Server 5292b520c35a3b0a] Rx ACK space=ap, ranges=[0..=2]
server  | 1.044 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 1.044 INFO ECN validation succeeded, path is capable
server  | 1.044 DEBUG on_packets_acked this=0x55db95c81b40, limited=1, bytes_in_flight=1563, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.044 DEBUG Acked crypto frame space=ap offset=0 length=182
server  | 1.044 INFO [Server 5292b520c35a3b0a] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye!
server  | 1.044 DEBUG [Server 5292b520c35a3b0a] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 347, tv_nsec: 871102557 } }
server  | 1.044 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:33235] Path validated Instant { tv_sec: 347, tv_nsec: 643656318 }
server  | 1.044 DEBUG [Server 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.044 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.044 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 347, tv_nsec: 643656318 }
server  | 1.044 DEBUG [Server 5292b520c35a3b0a] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:33235 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.044 DEBUG Setting timeout of 227.325583ms
server  | 1.044 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 347, tv_nsec: 871102557 } }; Total: 3
server  | 1.044 DEBUG Setting timeout of 227.310836ms
server  | 1.044 DEBUG Setting timeout of 227.298503ms
server  | 1.051 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 347, tv_nsec: 871102557 } }; Total: 4
server  | 1.051 DEBUG Setting timeout of 220.13256ms
server  | 1.051 DEBUG Setting timeout of 220.112533ms
client  | 0.109 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.109 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.109 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 337, tv_nsec: 658106481 }
client  | 0.109 DEBUG [Client 5292b520c35a3b0a] pn=6 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 3733, 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.109 DEBUG Setting timeout of 302.805389ms
client  | 0.109 DEBUG Setting timeout of 302.788597ms
client  | 0.110 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.110 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 337, tv_nsec: 659343470 }
client  | 0.110 DEBUG [Client 5292b520c35a3b0a] pn=7 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 3888, 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 301.625536ms
client  | 0.110 DEBUG [Client 5292b520c35a3b0a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG Building Short dcid Some(CID [10]: ac08d8d19d64e4a0fabb)
client  | 0.110 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 337, tv_nsec: 659438668 }
client  | 0.110 DEBUG [Client 5292b520c35a3b0a] pn=8 type=Short pri-path:ac08d8d19d64e4a0fabb 0.0.0.0:33235->193.167.100.100:443 IpTos(Cs0, NotEct) len 48
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 3900, 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 301.552499ms
client  | 0.110 DEBUG Setting timeout of 301.547089ms
server  | 1.074 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 347, tv_nsec: 871102557 } }; Total: 5
server  | 1.074 DEBUG Setting timeout of 197.503811ms
server  | 1.074 DEBUG Setting timeout of 197.468324ms
server  | 1.075 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 347, tv_nsec: 871102557 } }; Total: 6
server  | 1.075 DEBUG Setting timeout of 196.460633ms
server  | 1.075 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 347, tv_nsec: 871102557 } }; Total: 7
server  | 1.075 DEBUG Setting timeout of 196.440846ms
server  | 1.075 DEBUG Setting timeout of 196.433252ms
server  | 1.272 DEBUG [Server 5292b520c35a3b0a] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 347, tv_nsec: 871102557 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 1.272 INFO Closing timer expired
client  | 0.413 DEBUG [Client 5292b520c35a3b0a] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 337, tv_nsec: 961048765 } } -> Closed(Application(0))
client  | 0.413 INFO Closing timer expired
client  | 0.413 DEBUG Output::None
client  | 0.413 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 0.413 DEBUG Timer fired while closed
client  | 0.413 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:49:11,706 Using the client's key log file.
2025-04-30 12:49:11,715 
2025-04-30 12:49:11,716 Using the client's key log file.
2025-04-30 12:49:11,716 Using the client's key log file.
2025-04-30 12:49:11,724 
2025-04-30 12:49:11,724 Using the client's key log file.
2025-04-30 12:49:11,724 Using selector: EpollSelector
2025-04-30 12:49:12,029 Check of downloaded files succeeded.