2025-04-30 10:22:46,321 Generated random file: dkxfamzuaj of size: 1024
2025-04-30 10:22:46,321 Requests: https://server4:443/dkxfamzuaj
2025-04-30 10:22:46,382 
2025-04-30 10:22:46,383 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_8a0mrz1b/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_3rkk11h8/ DOWNLOADS=/tmp/download_kp6tp3l5/ SERVER_LOGS=/tmp/logs_server_3wvikaev CLIENT_LOGS=/tmp/logs_client_hyjy77iw SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/ngtcp2/ngtcp2-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/dkxfamzuaj"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 10:23:01,535  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
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
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
server  | + P12CERT=/tmp/tmp.kPVbEwT0Pw
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.kPVbEwT0Pw
server  | Warning: output encryption option -nodes ignored with -export
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.kPVbEwT0Pw -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  |             79:c9:5d:9b:7e:7c:31:51:1a:76:e8:ee:d9:78:51:a5:
server  |             d0:c8:8e:a8
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 10:22:46 2025
server  |             Not After : Sat May 10 10:22:46 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:53:5b:9b:5b:3f:6c:f8:e6:af:45:c5:93:8b:b6:2d:
server  |                     ba:9a:3d:9e:ad:5f:50:3f:c2:04:98:f3:6c:ae:de:43:
server  |                     ed:6f:4a:0b:1e:f0:88:d9:ea:26:ee:4d:19:53:9c:10:
server  |                     d8:09:ca:c5:d5:42:87:33:30:7e:c7:f1:46:0e:c2:90:
server  |                     e6
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  |                 b1:ce:68:57:12:d8:36:96:ed:e5:71:45:de:85:e6:ba:
server  |                 28:99:12:d4
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 47:cb:0f:0f:5d:5d:39:53:63:97:71:d0:dd:01:45:d3:
server  |                 82:22:66:3c
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:3b:2f:4c:2c:03:3a:76:8f:e4:2a:12:cd:
server  |         a4:9c:3a:1b:bd:4b:38:fb:f9:83:01:ff:3a:d4:f8:64:
server  |         1b:8a:bd:95:02:20:5f:49:f0:dd:1b:2c:5e:b2:22:71:
server  |         c8:d4:1a:ec:51:83:14:f6:c0:f2:06:3f:c9:1c:49:f0:
server  |         bf:93:bc:52:d0:35
server  |     Fingerprint (SHA-256):
server  |         B8:09:E4:0A:73:90:C5:F1:B6:45:94:BB:E7:83:88:50:F9:D9:48:7F:E8:5A:F4:17:2D:A5:57:52:4F:4A:55:E4
server  |     Fingerprint (SHA1):
server  |         A4:9F:35:F5:90:5A:B5:B9:9C:78:10:10:AD:EA:03:CB:CB:E8:03:A0
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.791 DEBUG [Server] Unsupported version: 57414954
server  | 0.791 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48739 IpTos(Cs0, NotEct) len 15
server  | 0.791 DEBUG [Server] Unsupported version: 57414954
server  | 0.791 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48739 IpTos(Cs0, NotEct) len 15
server  | 0.791 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.792 DEBUG [Server] Unsupported version: 57414954
server  | 0.792 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48739 IpTos(Cs0, NotEct) len 15
server  | 0.792 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | server:443 is available after 1.034458295s
sim     | Using scenario: simple-p2p --delay=750ms --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
server  | 1.800 DEBUG [Server] Handle initial
server  | 1.800 INFO AddressValidation: no token; accepting
server  | 1.801 INFO [Server] Accept connection CID [18]: f90265f80e41f6e6afdc3aa28b1df0bbd707
server  | 1.801 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.802 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.802 INFO [Server ...] Received valid Initial packet with scid CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19 dcid CID [18]: f90265f80e41f6e6afdc3aa28b1df0bbd707
server  | 1.802 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=f90265f80e41f6e6afdc3aa28b1df0bbd707
server  | 1.802 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.802 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.802 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] State change from Init -> WaitInitial
server  | 1.802 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1433105552 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 1200
server  |   -> RX Crypto { offset: 0, len: 263 }
server  |   -> RX Padding { len: 866 }
server  | 1.802 DEBUG Read Ok(263) bytes
server  | 1.802 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.803 DEBUG Read secret available for Handshake: SymKey [32]: 9268b30c1088da70b8dd47a9a2ff021993d32e6dc51cc96a863ec1d5e65cf885
server  | 1.803 DEBUG Write secret available for Handshake: SymKey [32]: 0600c10a0d59cb5a779eeef8e280cda6e7fccd39c5c1b0a21e9ad918dc45ac38
server  | 1.803 DEBUG Writing transport parameters, msg=8
server  | 1.803 DEBUG Read secret available for ApplicationData: SymKey [32]: 4291f122ffcfc4508deec2d0f5e8c1a5b7fdb468b7915b5e03d4c52499294bf9
server  | 1.803 DEBUG Write secret available for ApplicationData: SymKey [32]: 3f4509a204af4044fd31b3bd9935ae879c8d2f770298961ec2695fbe3e783775
server  | 1.803 DEBUG [Agent 0x5583f715c1d0] state -> InProgress
server  | 1.803 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.803 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.803 DEBUG [Crypto] Handshake keys installed
server  | 1.803 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.803 DEBUG [Crypto] Application write key installed
server  | 1.803 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 192, tv_nsec: 668554295 }
server  | 1.803 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:47015] Make permanent
server  | 1.803 DEBUG [unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015] set as primary path
server  | 1.803 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] State change from WaitInitial -> Handshaking
server  | 1.803 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.803 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 1.803 DEBUG CRYPTO for in offset=0, len=90
server  | 1.803 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=0 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 163
server  |   TX -> Ack { largest_acknowledged: 1433105552, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 1.803 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 1.803 DEBUG CRYPTO for hs offset=0, len=734
server  | 1.803 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=0 type=Handshake pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 954
server  |   TX -> Crypto { offset: 0, len: 734 }
server  | 1.803 DEBUG packet_sent this=0x5583f715bb40, pn=0, ps=791
server  | 1.804 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 1.804 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pad Initial from 954 to PLPMTU 1232
server  | 1.804 DEBUG packet_sent this=0x5583f715bb40, pn=0, ps=441
server  | 1.804 DEBUG ECN probing: sent 1 probes
server  | 1.804 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.804 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 1.804 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 1.804 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 1.804 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.804 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 296.901008ms
server  | 1.804 DEBUG Setting timeout of 296.901008ms
server  | 1.804 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.804 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 1.804 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 1.804 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 1.804 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.804 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 296.868497ms
server  | 1.804 DEBUG Setting timeout of 296.868497ms
server  | 2.095 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1433105553 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 1200
server  |   -> RX Crypto { offset: 0, len: 263 }
server  |   -> RX Padding { len: 866 }
server  | 2.095 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 192, tv_nsec: 963455423 }
server  | 2.095 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.095 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.095 DEBUG CRYPTO for in offset=0, len=90
server  | 2.095 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 163
server  |   TX -> Ack { largest_acknowledged: 1433105553, 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: 90 }
server  | 2.096 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.096 DEBUG CRYPTO for hs offset=0, len=734
server  | 2.096 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1 type=Handshake pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 954
server  |   TX -> Crypto { offset: 0, len: 734 }
server  | 2.096 DEBUG packet_sent this=0x5583f715bb40, pn=1, ps=791
server  | 2.096 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.096 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pad Initial from 954 to PLPMTU 1232
server  | 2.096 DEBUG packet_sent this=0x5583f715bb40, pn=1, ps=441
server  | 2.096 DEBUG ECN probing: sent 2 probes
server  | 2.096 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.096 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.096 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.096 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.096 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.096 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 299.759088ms
server  | 2.096 DEBUG Setting timeout of 299.759088ms
server  | 2.096 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.096 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.096 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.096 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.096 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.096 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 299.716167ms
server  | 2.096 DEBUG Setting timeout of 299.716167ms
server  | 2.397 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 2.397 DEBUG [LossRecovery] PTO timer fired for Handshake
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1433105552, smallest: 1433105552, ack_needed: true }] })
server  | 2.397 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 192, tv_nsec: 668554295 }
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 2.397 INFO Lost crypto frame space=in offset=0 length=90
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: EcnEct0
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1433105553, smallest: 1433105552, ack_needed: true }] })
server  | 2.397 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 192, tv_nsec: 963455423 }
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 2.397 INFO Lost crypto frame space=in offset=0 length=90
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: EcnEct0
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 734 })
server  | 2.397 INFO Lost crypto frame space=hs offset=0 length=734
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 734 })
server  | 2.397 INFO Lost crypto frame space=hs offset=0 length=734
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 2.397 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.397 DEBUG CRYPTO for in offset=0, len=90
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=2 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 166
server  |   TX -> Ack { largest_acknowledged: 1433105553, ack_delay: 37720, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 2.397 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.397 DEBUG CRYPTO for hs offset=0, len=734
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=2 type=Handshake pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 957
server  |   TX -> Crypto { offset: 0, len: 734 }
server  | 2.397 DEBUG packet_sent this=0x5583f715bb40, pn=2, ps=791
server  | 2.397 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pad Initial from 957 to PLPMTU 1232
server  | 2.397 DEBUG packet_sent this=0x5583f715bb40, pn=2, ps=441
server  | 2.397 DEBUG ECN probing: sent 3 probes
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.397 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.397 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.397 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.397 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 599.719614ms
server  | 2.397 DEBUG Setting timeout of 599.719614ms
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.397 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.397 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.397 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.397 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.397 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 599.68007ms
server  | 2.397 DEBUG Setting timeout of 599.68007ms
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1433105554 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 1200
server  |   -> RX Crypto { offset: 0, len: 263 }
server  |   -> RX Padding { len: 866 }
server  | 2.696 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 193, tv_nsec: 564246459 }
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.696 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.696 DEBUG CRYPTO for in offset=0, len=90
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=3 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 163
server  |   TX -> Ack { largest_acknowledged: 1433105554, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 2.696 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.696 DEBUG CRYPTO for hs offset=0, len=734
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=3 type=Handshake pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 954
server  |   TX -> Crypto { offset: 0, len: 734 }
server  | 2.696 DEBUG packet_sent this=0x5583f715bb40, pn=3, ps=791
server  | 2.696 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pad Initial from 954 to PLPMTU 1232
server  | 2.696 DEBUG packet_sent this=0x5583f715bb40, pn=3, ps=441
server  | 2.696 DEBUG ECN probing: sent 4 probes
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.696 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.696 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.696 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.696 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 599.761603ms
server  | 2.696 DEBUG Setting timeout of 599.761603ms
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.696 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.696 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 2.696 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 2.696 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.696 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 599.717119ms
server  | 2.696 DEBUG Setting timeout of 599.717119ms
server  | 3.297 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 3.297 DEBUG [LossRecovery] PTO timer fired for Handshake
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1433105553, smallest: 1433105552, ack_needed: true }] })
server  | 3.297 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 193, tv_nsec: 265220321 }
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 3.297 INFO Lost crypto frame space=in offset=0 length=90
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: EcnEct0
server  | 3.297 DEBUG ECN validation failed, all 3 initial marked packets were lost
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1433105554, smallest: 1433105552, ack_needed: true }] })
server  | 3.297 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 193, tv_nsec: 564246459 }
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 3.297 INFO Lost crypto frame space=in offset=0 length=90
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: EcnEct0
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 734 })
server  | 3.297 INFO Lost crypto frame space=hs offset=0 length=734
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 734 })
server  | 3.297 INFO Lost crypto frame space=hs offset=0 length=734
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 3.297 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 3.297 DEBUG CRYPTO for in offset=0, len=90
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=4 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 166
server  |   TX -> Ack { largest_acknowledged: 1433105554, ack_delay: 75137, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 3.297 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 3.297 DEBUG CRYPTO for hs offset=0, len=734
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=4 type=Handshake pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 957
server  |   TX -> Crypto { offset: 0, len: 734 }
server  | 3.297 DEBUG packet_sent this=0x5583f715bb40, pn=4, ps=791
server  | 3.297 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.297 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pad Initial from 957 to PLPMTU 1232
server  | 3.297 DEBUG packet_sent this=0x5583f715bb40, pn=4, ps=441
server  | 3.298 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.298 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 3.298 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 3.298 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.298 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.298 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 1.199672225s
server  | 3.298 DEBUG Setting timeout of 1.199672225s
server  | 3.298 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.298 DEBUG Building Initial dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 3.298 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 3.298 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.298 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.298 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 1.199627221s
server  | 3.298 DEBUG Setting timeout of 1.199627221s
server  | 3.308 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1433105555 type=Initial pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 64
server  |   -> 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})) }
server  | 3.308 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Rx ACK space=in, ranges=[0..=0]
server  | 3.308 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 3.308 DEBUG on_packets_acked this=0x5583f715bb40, limited=1, bytes_in_flight=5719, cwnd=12320, state=SlowStart, new_acked=441
server  | 3.308 DEBUG Acked crypto frame space=in offset=0 length=90
server  | 3.308 DEBUG [pri-unv-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015] Path validated Instant { tv_sec: 194, tv_nsec: 176330183 }
server  | 3.308 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Drop packet number space in
server  | 3.308 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 3.308 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1433105552 type=Handshake pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 105
server  |   -> 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})) }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 3.308 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Rx ACK space=hs, ranges=[0..=0]
server  | 3.308 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 3.308 DEBUG on_packets_acked this=0x5583f715bb40, limited=1, bytes_in_flight=3164, cwnd=12320, state=SlowStart, new_acked=791
server  | 3.308 DEBUG Acked crypto frame space=hs offset=0 length=734
server  | 3.308 DEBUG Read Ok(36) bytes
server  | 3.309 DEBUG [Agent 0x5583f715c1d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] TLS connection complete
server  | 3.309 DEBUG [pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015] Path validated Instant { tv_sec: 194, tv_nsec: 176330183 }
server  | 3.309 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 3.309 DEBUG [Crypto] application read keys installed
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] State change from Handshaking -> Connected
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] State change from Connected -> Confirmed
server  | 3.309 DEBUG PMTUD started with probe size 1380
server  | 3.309 INFO [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Connection established
server  | 3.309 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 194, tv_nsec: 176330183 }
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1433105552 type=Short pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 1031
server  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [193, 7, 102, 231, 203, 89, 76, 253, 221, 235, 114, 223, 175, 153, 134, 77, 26], stateless_reset_token: [193, 239, 42, 125, 216, 90, 27, 179, 243, 126, 166, 230, 14, 4, 78, 171] }
server  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [244, 7, 137, 224, 72, 207, 78, 97, 241, 63, 45, 27, 67, 8, 22, 254, 201], stateless_reset_token: [199, 133, 47, 224, 241, 224, 70, 103, 219, 132, 104, 0, 203, 32, 249, 174] }
server  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [124, 177, 230, 125, 13, 245, 96, 172, 160, 46, 254, 120, 180, 245, 146, 199, 72], stateless_reset_token: [140, 100, 109, 54, 55, 244, 44, 238, 193, 51, 182, 226, 218, 58, 30, 158] }
server  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [29, 143, 27, 127, 117, 100, 35, 35, 87, 41, 90, 155, 196, 23, 101, 35, 5], stateless_reset_token: [13, 35, 91, 10, 50, 104, 68, 41, 80, 107, 94, 84, 232, 232, 20, 193] }
server  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [91, 126, 246, 152, 15, 55, 86, 57, 174, 121, 30, 99, 202, 249, 131, 108, 87], stateless_reset_token: [250, 56, 142, 82, 124, 217, 64, 47, 249, 22, 0, 199, 128, 73, 235, 51] }
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [0, 248, 25, 165, 224, 228, 187, 254, 175, 74, 159, 94, 250, 29, 246, 205, 241], stateless_reset_token: [114, 181, 49, 51, 15, 185, 239, 124, 83, 182, 5, 66, 187, 1, 170, 176] }
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [17, 93, 110, 6, 67, 193, 109, 98, 119, 193, 123, 16, 246, 253, 221, 175, 31], stateless_reset_token: [250, 20, 238, 207, 173, 94, 166, 63, 216, 244, 85, 144, 105, 224, 72, 90] }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  |   -> RX Padding { len: 721 }
server  | 3.309 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 194, tv_nsec: 176330183 }
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.309 DEBUG Building Handshake dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19) scid Some(CID [10]: 3e8c3fa300443c162e6d)
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=5 type=Handshake pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 68
server  |   TX -> Ack { largest_acknowledged: 1433105552, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] Drop packet number space hs
server  | 3.309 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 3.309 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=0 type=Short pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 1332
server  |   TX -> Ack { largest_acknowledged: 1433105552, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [74, 137, 62, 101, 23, 243, 141, 15, 192, 71], stateless_reset_token: [217, 174, 59, 183, 197, 143, 124, 66, 174, 56, 19, 105, 122, 212, 240, 195] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [50, 11, 155, 118, 7, 242, 255, 212, 127, 2], stateless_reset_token: [228, 72, 137, 158, 179, 232, 89, 182, 59, 218, 152, 78, 124, 10, 105, 15] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [185, 32, 185, 45, 14, 107, 177, 140, 227, 218], stateless_reset_token: [36, 76, 153, 40, 93, 17, 75, 203, 73, 90, 138, 119, 34, 225, 77, 161] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [118, 44, 64, 222, 197, 117, 220, 224, 30, 125], stateless_reset_token: [224, 231, 230, 204, 157, 68, 233, 206, 197, 190, 148, 8, 255, 125, 20, 126] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [147, 38, 237, 17, 73, 11, 134, 241, 119, 207], stateless_reset_token: [97, 61, 150, 3, 204, 123, 215, 33, 53, 200, 185, 209, 247, 29, 180, 51] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [40, 56, 44, 155, 65, 27, 102, 85, 10, 191], stateless_reset_token: [165, 143, 121, 195, 94, 125, 217, 126, 153, 151, 240, 162, 107, 67, 96, 93] }
server  |   TX -> Padding { len: 1033 }
server  | 3.309 DEBUG packet_sent this=0x5583f715bb40, pn=0, ps=1264
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.309 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.309 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.309 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 21.337959ms
server  | 3.309 DEBUG Setting timeout of 21.337959ms
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] send session ticket 406d0012f90265f80e41f6e6afdc3aa28b1df0bbd7070104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a3e8c3fa300443c162e6d110c000000014a8aaa7a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 3.310 DEBUG Path = 'dkxfamzuaj'
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.310 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.310 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 20.299942ms
server  | 3.310 DEBUG Setting timeout of 20.299942ms
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1433105553 type=Short pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, Ect0) len 1406
server  |   -> RX Ping
server  |   -> RX Padding { len: 1374 }
server  | 3.310 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 194, tv_nsec: 178218457 }
server  | 3.310 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 194, tv_nsec: 178218457 }
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.310 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=1 type=Short pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 50
server  |   TX -> Ack { largest_acknowledged: 1433105553, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.310 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.310 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 23.270604ms
server  | 3.310 DEBUG Setting timeout of 23.270604ms
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.310 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.310 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.310 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 23.244706ms
server  | 3.310 DEBUG Setting timeout of 23.244706ms
server  | 3.334 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.334 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.334 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 3.334 DEBUG CRYPTO for ap offset=0, len=264
server  | 3.334 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=2 type=Short pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 1332
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  |   TX -> Crypto { offset: 0, len: 264 }
server  | 3.335 DEBUG packet_sent this=0x5583f715bb40, pn=2, ps=1332
server  | 3.335 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.335 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.335 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.335 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 75.17877ms
server  | 3.335 DEBUG Setting timeout of 75.17877ms
server  | 3.335 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.335 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.335 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.335 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 75.15184ms
server  | 3.335 DEBUG Setting timeout of 75.15184ms
server  | 3.411 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.411 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.411 DEBUG CRYPTO for ap offset=264, len=57
server  | 3.411 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] pn=3 type=Short pri-path:05e97d5324ca7e6397233f2df9f82d0e19 [::]:443->[::ffff:193.167.0.100]:47015 IpTos(Cs0, NotEct) len 141
server  |   TX -> Crypto { offset: 264, len: 57 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 85, 150, 167, 11, 16, 97, 221, 44, 237, 246, 229, 143, 140, 110, 190, 239, 224, 73, 8, 42, 222, 36, 111, 51, 110, 186, 20, 124, 6, 189, 164, 128, 89, 36, 50, 128] }
server  | 3.411 DEBUG packet_sent this=0x5583f715bb40, pn=3, ps=141
server  | 3.411 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.411 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.411 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.411 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 7.335983ms
server  | 3.411 DEBUG Setting timeout of 7.335983ms
server  | 3.411 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.411 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.411 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.411 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 7.307339ms
server  | 3.411 DEBUG Setting timeout of 7.307339ms
server  | 3.420 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.420 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.420 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.420 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 3.785120772s
server  | 3.420 DEBUG Setting timeout of 3.785120772s
server  | 3.420 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.420 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.420 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.420 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 3.785083091s
server  | 3.420 DEBUG Setting timeout of 3.785083091s
server  | 3.598 WARN [Server ...] Dropped received packet: Decryption failure; Total: 1
server  | 3.598 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.598 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.598 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.598 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 3.607664373s
server  | 3.598 DEBUG Setting timeout of 3.607664373s
server  | 3.598 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.598 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.598 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.598 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 3.607597738s
server  | 3.598 DEBUG Setting timeout of 3.607597738s
server  | 3.900 WARN [Server ...] Dropped received packet: Decryption failure; Total: 2
server  | 3.900 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.900 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.900 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.900 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 3.305900192s
server  | 3.900 DEBUG Setting timeout of 3.305900192s
server  | 3.900 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.900 DEBUG Building Short dcid Some(CID [17]: 05e97d5324ca7e6397233f2df9f82d0e19)
server  | 3.900 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.900 DEBUG [Server f90265f80e41f6e6afdc3aa28b1df0bbd707] delay duration 3.305834859s
server  | 3.900 DEBUG Setting timeout of 3.305834859s

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 10:23:01,635 Using the client's key log file.
2025-04-30 10:23:01,644 
2025-04-30 10:23:01,645 Using the client's key log file.
2025-04-30 10:23:01,645 Using the client's key log file.
2025-04-30 10:23:01,654 
2025-04-30 10:23:01,655 Using the client's key log file.
2025-04-30 10:23:01,958 Check of downloaded files succeeded.
2025-04-30 10:23:03,199 Check of downloaded files succeeded.