2025-04-30 12:31:39,030 Generated random file: nhiphkhsvw of size: 1024
2025-04-30 12:31:39,030 Requests: https://server4:443/nhiphkhsvw
2025-04-30 12:31:39,089 
2025-04-30 12:31:39,089 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_mhbpatlv/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_5f5qbojx/ DOWNLOADS=/tmp/download_jik383nb/ SERVER_LOGS=/tmp/logs_server_ld2ypz6n CLIENT_LOGS=/tmp/logs_client_4w9ns224 SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=peterdoornbosch/kwik_n_flupke-interop SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/nhiphkhsvw"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:31:56,519  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
server  | Setting up routes...
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  | 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
client  | Endpoint's IPv4 address is 193.167.0.100
server  | Endpoint's IPv4 address is 193.167.100.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::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.hMdiM5Bvsk
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.hMdiM5Bvsk
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.hMdiM5Bvsk -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  |             53:8e:73:f7:d5:af:64:41:5a:07:a6:d3:83:8e:cf:4d:
server  |             26:c8:30:74
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:31:39 2025
server  |             Not After : Sat May 10 12:31:39 2025
server  |         Subject: "O=interop runner leaf"
server  |         Subject Public Key Info:
server  |             Public Key Algorithm: X9.62 elliptic curve public key
server  |                 Args:
server  |                     06:08:2a:86:48:ce:3d:03:01:07
server  |             EC Public Key:
server  |                 PublicValue:
server  |                     04:21:1a:5b:5f:ea:16:2a:0a:9c:dc:81:63:2e:c8:35:
server  |                     e6:d9:61:2d:55:1b:72:c4:76:c3:7e:9e:0e:1b:0c:87:
server  |                     d6:67:44:e5:b5:55:c8:a5:d5:d8:67:58:09:29:6e:86:
server  |                     bc:4c:63:56:ee:d3:ee:9c:26:04:fc:e4:96:f9:ba:9d:
server  |                     7e
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  |                 c7:0e:e6:de:02:69:68:55:f8:3b:e5:c7:0b:10:70:61:
server  |                 e3:df:b0:89
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 59:a6:53:72:ff:45:64:56:45:2a:2d:7c:b2:03:05:9d:
server  |                 a0:eb:5e:37
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:20:0f:f6:18:e2:66:c5:87:30:a4:3c:88:f0:
server  |         e6:7d:cb:21:27:e1:2a:4e:5a:95:dd:e2:86:3d:15:af:
server  |         1b:1b:5f:27:02:21:00:f9:44:a2:d1:3c:74:76:f6:4a:
server  |         6e:8d:93:2e:6d:a3:a3:7b:fe:3a:ef:10:ba:dc:52:9f:
server  |         57:fb:83:cd:5c:1d:e3
server  |     Fingerprint (SHA-256):
server  |         1C:4A:33:76:82:45:E9:DB:28:CE:D5:69:C0:E8:93:E9:36:58:85:4B:97:0B:6A:C6:4A:FE:CD:98:75:ED:A4:BA
server  |     Fingerprint (SHA1):
server  |         B5:41:6B:92:7B:9D:87:B4:B9:5C:50:08:1F:72:B6:C7:E3:FE:E0:4A
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
client  | Certificate was added to keystore
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | 0.787 DEBUG [Server] Unsupported version: 57414954
server  | 0.787 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46828 IpTos(Cs0, NotEct) len 15
server  | 0.787 DEBUG [Server] Unsupported version: 57414954
server  | 0.787 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46828 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.043054992s
server  | 0.787 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.787 DEBUG [Server] Unsupported version: 57414954
server  | 0.787 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46828 IpTos(Cs0, NotEct) len 15
server  | 0.787 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | running kwik Kwik version: 0.10.2 with openjdk 21.0.5 2024-10-15 with https://server4:443/nhiphkhsvw
client  | 31:41.480 Creating connection with server4:443 with v1
client  | 31:41.559 Original destination connection id: efa05c3c6da2d8d6 (scid: e6da087d3f87ee32)
server  | 2.660 DEBUG [Server] Handle initial
server  | 2.660 INFO AddressValidation: no token; accepting
server  | 2.660 INFO [Server] Accept connection CID [8]: efa05c3c6da2d8d6
server  | 2.660 DEBUG Overwrite initial version Version1 ==> Version1
server  | 2.661 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 2.661 INFO [Server ...] Received valid Initial packet with scid CID [8]: e6da087d3f87ee32 dcid CID [8]: efa05c3c6da2d8d6
server  | 2.661 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=efa05c3c6da2d8d6
server  | 2.662 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 2.662 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 2.662 DEBUG [Server efa05c3c6da2d8d6] State change from Init -> WaitInitial
server  | 2.662 DEBUG [Server efa05c3c6da2d8d6] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, NotEct) len 1200
server  |   -> RX Crypto { offset: 0, len: 257 }
server  |   -> RX Padding { len: 896 }
server  | 2.662 DEBUG Read Ok(257) bytes
server  | 2.662 DEBUG 0-RTT: no token, no 0-RTT
server  | 2.662 DEBUG Read secret available for Handshake: SymKey [32]: 1024b323bca86e6c0215d83c475cacec5e4746aa123e72e663f8f1d74ed45c61
server  | 2.663 DEBUG Write secret available for Handshake: SymKey [32]: 688861afa22c7eb251b4704ca577f16bdc20b1a5556481905824f68cde8bdb82
server  | 2.663 DEBUG Writing transport parameters, msg=8
server  | 2.663 DEBUG Read secret available for ApplicationData: SymKey [32]: afc22ea239b0b47c50e2917e3f4cba09d9cea43d5a5e3325a4969d466bde7bb4
server  | 2.663 DEBUG Write secret available for ApplicationData: SymKey [32]: 53fa7fe907c92dfbde12ef22e35ea3356ff1cbf1cd67629817196f4f5e68db17
server  | 2.663 DEBUG [Agent 0x55f3e04cc1d0] state -> InProgress
server  | 2.663 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 2.663 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 2.663 DEBUG [Crypto] Handshake keys installed
server  | 2.663 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 2.663 DEBUG [Crypto] Application write key installed
server  | 2.663 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 460, tv_nsec: 885942508 }
server  | 2.663 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:47608] Make permanent
server  | 2.663 DEBUG [unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608] set as primary path
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] State change from WaitInitial -> Handshaking
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.663 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.663 DEBUG CRYPTO for in offset=0, len=123
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] pn=0 type=Initial pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 177
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 123 }
server  | 2.663 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.663 DEBUG CRYPTO for hs offset=0, len=719
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] pn=0 type=Handshake pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 944
server  |   TX -> Crypto { offset: 0, len: 719 }
server  | 2.663 DEBUG packet_sent this=0x55f3e04cbb40, pn=0, ps=767
server  | 2.663 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] pad Initial from 944 to PLPMTU 1232
server  | 2.663 DEBUG packet_sent this=0x55f3e04cbb40, pn=0, ps=465
server  | 2.663 DEBUG ECN probing: sent 1 probes
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.663 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.663 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.663 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 2.663 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] delay duration 296.289035ms
server  | 2.663 DEBUG Setting timeout of 296.289035ms
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.663 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.663 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.663 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 2.663 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.663 DEBUG [Server efa05c3c6da2d8d6] delay duration 296.252557ms
server  | 2.663 DEBUG Setting timeout of 296.252557ms
server  | 2.961 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 2.961 DEBUG [LossRecovery] PTO timer fired for Handshake
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
server  | 2.961 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 460, tv_nsec: 885942508 }
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 123 })
server  | 2.961 INFO Lost crypto frame space=in offset=0 length=123
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] Lost: EcnEct0
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 719 })
server  | 2.961 INFO Lost crypto frame space=hs offset=0 length=719
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 2.961 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.961 DEBUG CRYPTO for in offset=0, len=123
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] pn=1 type=Initial pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 180
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 37651, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 123 }
server  | 2.961 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.961 DEBUG CRYPTO for hs offset=0, len=719
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] pn=1 type=Handshake pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 947
server  |   TX -> Crypto { offset: 0, len: 719 }
server  | 2.961 DEBUG packet_sent this=0x55f3e04cbb40, pn=1, ps=767
server  | 2.961 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] pad Initial from 947 to PLPMTU 1232
server  | 2.961 DEBUG packet_sent this=0x55f3e04cbb40, pn=1, ps=465
server  | 2.961 DEBUG ECN probing: sent 2 probes
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 2.961 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.961 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.961 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 2.961 DEBUG TX blocked, profile=SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] delay duration 29.698566693s
server  | 2.961 DEBUG Setting timeout of 29.698566693s
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 2.961 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.961 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 2.961 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 2.961 DEBUG TX blocked, profile=SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 2.961 DEBUG [Server efa05c3c6da2d8d6] delay duration 29.698526908s
server  | 2.961 DEBUG Setting timeout of 29.698526908s
client  | 31:43.247 - unsupported transport parameter 0x2ab2, size 0 (grease-quic-bit)
client  | 31:43.247 - unsupported transport parameter 0xff02de1a, size 2 (delayed-ack)
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] pn=1 type=Initial pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, NotEct) len 1200
server  |   -> RX Crypto { offset: 0, len: 257 }
server  |   -> RX Padding { len: 896 }
server  | 3.668 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 461, tv_nsec: 894101685 }
server  | 3.668 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 3.668 DEBUG [LossRecovery] PTO timer fired for Handshake
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
server  | 3.668 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 461, tv_nsec: 187151949 }
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 123 })
server  | 3.668 INFO Lost crypto frame space=in offset=0 length=123
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] Lost: EcnEct0
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 719 })
server  | 3.668 INFO Lost crypto frame space=hs offset=0 length=719
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 3.668 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 3.668 DEBUG CRYPTO for in offset=0, len=123
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] pn=2 type=Initial pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 177
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 123 }
server  | 3.668 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 3.668 DEBUG CRYPTO for hs offset=0, len=719
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] pn=2 type=Handshake pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 944
server  |   TX -> Crypto { offset: 0, len: 719 }
server  | 3.668 DEBUG packet_sent this=0x55f3e04cbb40, pn=2, ps=767
server  | 3.668 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] pad Initial from 944 to PLPMTU 1232
server  | 3.668 DEBUG packet_sent this=0x55f3e04cbb40, pn=2, ps=465
server  | 3.668 DEBUG ECN probing: sent 3 probes
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.668 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 3.668 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 3.668 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 3.668 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] delay duration 1.199736066s
server  | 3.668 DEBUG Setting timeout of 1.199736066s
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.668 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 3.668 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 3.668 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 3.668 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.668 DEBUG [Server efa05c3c6da2d8d6] delay duration 1.199700089s
server  | 3.668 DEBUG Setting timeout of 1.199700089s
client  | 31:43.493 Discarding packet (1232 bytes) that cannot be decrypted (Missing keys for encryption level Initial (keys discarded))
server  | 4.207 DEBUG [Server efa05c3c6da2d8d6] pn=2 type=Initial pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, NotEct) len 1201
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 1151 }
server  | 4.207 DEBUG [Server efa05c3c6da2d8d6] Rx ACK space=in, ranges=[0..=0]
server  | 4.207 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 4.207 DEBUG on_packets_acked this=0x55f3e04cbb40, limited=1, bytes_in_flight=3231, cwnd=12320, state=SlowStart, new_acked=465
server  | 4.207 DEBUG Acked crypto frame space=in offset=0 length=123
server  | 4.207 DEBUG [pri-unv-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608] Path validated Instant { tv_sec: 462, tv_nsec: 433258250 }
server  | 4.207 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.207 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 4.207 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 4.207 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.207 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.207 DEBUG [Server efa05c3c6da2d8d6] delay duration 4.102790661s
server  | 4.207 DEBUG Setting timeout of 4.102790661s
server  | 4.207 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.207 DEBUG Building Initial dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 4.207 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 4.207 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.207 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.207 DEBUG [Server efa05c3c6da2d8d6] delay duration 4.102634029s
server  | 4.207 DEBUG Setting timeout of 4.102634029s
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] Drop packet number space in
server  | 4.245 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] pn=0 type=Handshake pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, NotEct) len 82
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 4.245 DEBUG Read Ok(36) bytes
server  | 4.245 DEBUG [Agent 0x55f3e04cc1d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] TLS connection complete
server  | 4.245 DEBUG [pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608] Path validated Instant { tv_sec: 462, tv_nsec: 470898431 }
server  | 4.245 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 4.245 DEBUG [Crypto] application read keys installed
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] State change from Handshaking -> Connected
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] State change from Connected -> Confirmed
server  | 4.245 DEBUG PMTUD started with probe size 1380
server  | 4.245 INFO [Server efa05c3c6da2d8d6] Connection established
server  | 4.245 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 462, tv_nsec: 470898431 }
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.245 DEBUG Building Handshake dcid Some(CID [8]: e6da087d3f87ee32) scid Some(CID [10]: 262ebeabc567bac416ca)
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] pn=3 type=Handshake pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 49
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] Drop packet number space hs
server  | 4.245 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 4.245 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] pn=0 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [107, 6, 96, 144, 136, 247, 96, 156, 232, 163], stateless_reset_token: [97, 158, 87, 145, 192, 234, 67, 90, 42, 221, 57, 120, 225, 10, 7, 244] }
server  |   TX -> Padding { len: 1226 }
server  | 4.245 DEBUG packet_sent this=0x55f3e04cbb40, pn=0, ps=1283
server  | 4.245 DEBUG ECN probing: sent 4 probes
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.245 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.245 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.245 DEBUG [Server efa05c3c6da2d8d6] delay duration 2.733038ms
server  | 4.245 DEBUG Setting timeout of 2.733038ms
server  | 4.246 DEBUG [Server efa05c3c6da2d8d6] send session ticket 40630008efa05c3c6da2d8d60104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a262ebeabc567bac416ca110c00000001fa8a3ada000000016ab200c0000000ff02de1a0243e8200100686921
server  | 4.246 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.246 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.246 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.246 DEBUG [Server efa05c3c6da2d8d6] delay duration 1.840566ms
server  | 4.246 DEBUG Setting timeout of 1.840566ms
server  | 4.247 WARN [Server ...] Dropped received packet: Decryption failure; Total: 1
server  | 4.247 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.247 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.247 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.247 DEBUG [Server efa05c3c6da2d8d6] delay duration 1.008757ms
server  | 4.247 DEBUG Setting timeout of 1.008757ms
server  | 4.247 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.247 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.247 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.247 DEBUG [Server efa05c3c6da2d8d6] delay duration 977.429µs
server  | 4.247 DEBUG Setting timeout of 977.429µs
server  | 4.249 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.249 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.249 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 4.249 DEBUG CRYPTO for ap offset=0, len=305
server  | 4.249 DEBUG [Server efa05c3c6da2d8d6] pn=1 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Crypto { offset: 0, len: 305 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 177, 3, 216, 238, 197, 144, 4, 0, 210, 215, 225, 62, 211, 248, 102, 148, 189, 50, 74, 244, 46, 82, 181, 124, 150, 233, 202, 185, 225, 255, 110, 168, 77, 19, 179, 7] }
server  |   TX -> Padding { len: 951 }
server  | 4.249 DEBUG packet_sent this=0x55f3e04cbb40, pn=1, ps=1332
server  | 4.249 DEBUG ECN probing: sent 5 probes
server  | 4.249 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.249 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.249 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.249 DEBUG [Server efa05c3c6da2d8d6] delay duration 77.267825ms
server  | 4.249 DEBUG Setting timeout of 77.267825ms
server  | 4.249 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.249 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.249 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.249 DEBUG [Server efa05c3c6da2d8d6] delay duration 77.242808ms
server  | 4.249 DEBUG Setting timeout of 77.242808ms
server  | 4.256 DEBUG [Server efa05c3c6da2d8d6] pn=0 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, NotEct) len 49
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 4.256 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 462, tv_nsec: 501919846 }
server  | 4.256 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.256 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.256 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.256 DEBUG [Server efa05c3c6da2d8d6] delay duration 20ms
server  | 4.256 DEBUG Setting timeout of 20ms
server  | 4.256 DEBUG Path = 'nhiphkhsvw'
server  | 4.256 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.256 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.256 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.256 DEBUG [Server efa05c3c6da2d8d6] delay duration 19.880647ms
server  | 4.256 DEBUG Setting timeout of 19.880647ms
server  | 4.277 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.277 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.277 DEBUG [Server efa05c3c6da2d8d6] pn=2 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 32
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 2655, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 4.277 DEBUG ECN probing: sent 6 probes
server  | 4.277 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.277 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.277 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.277 DEBUG [Server efa05c3c6da2d8d6] delay duration 51.341409ms
server  | 4.277 DEBUG Setting timeout of 51.341409ms
server  | 4.277 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.277 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.277 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.277 DEBUG [Server efa05c3c6da2d8d6] delay duration 51.313567ms
server  | 4.277 DEBUG Setting timeout of 51.313567ms
server  | 4.329 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.329 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.329 DEBUG [Server efa05c3c6da2d8d6] pn=3 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 1054
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  | 4.329 DEBUG packet_sent this=0x55f3e04cbb40, pn=3, ps=1054
server  | 4.329 DEBUG ECN probing: sent 7 probes
server  | 4.329 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.329 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.329 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.329 DEBUG [Server efa05c3c6da2d8d6] delay duration 65.226969ms
server  | 4.329 DEBUG Setting timeout of 65.226969ms
server  | 4.329 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.329 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.329 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.329 DEBUG [Server efa05c3c6da2d8d6] delay duration 65.203064ms
server  | 4.329 DEBUG Setting timeout of 65.203064ms
server  | 4.395 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.395 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.395 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.395 DEBUG [Server efa05c3c6da2d8d6] delay duration 4.600577496s
server  | 4.395 DEBUG Setting timeout of 4.600577496s
server  | 4.395 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.395 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 4.395 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.395 DEBUG [Server efa05c3c6da2d8d6] delay duration 4.600529987s
server  | 4.395 DEBUG Setting timeout of 4.600529987s
client  | 31:44.199 Discarding packet (1232 bytes) that cannot be decrypted (Missing keys for encryption level Initial (keys discarded))
client  | Get requested finished in 3.31 sec (0.00 MB/s) : tech.kwik.h09.client.HttpResponseImpl@2ddc8ecb
client  | 31:44.868 Closing ClientConnection[efa05c3c6da2d8d6/e6da087d3f87ee32(V1) with server4/193.167.100.100:443] (reason: ImmediateClose)
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] pn=1 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 250, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] Rx ACK space=ap, ranges=[0..=1]
server  | 5.753 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 5.753 DEBUG on_packets_acked this=0x55f3e04cbb40, limited=1, bytes_in_flight=1054, cwnd=12320, state=SlowStart, new_acked=2615
server  | 5.753 DEBUG PMTUD probe of size 1380 succeeded
server  | 5.753 DEBUG PMTUD started with probe size 1420
server  | 5.753 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 5.753 DEBUG Acked crypto frame space=ap offset=0 length=305
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.753 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 5.753 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] pn=4 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1345 }
server  | 5.753 DEBUG packet_sent this=0x55f3e04cbb40, pn=4, ps=1372
server  | 5.753 DEBUG ECN probing: sent 8 probes
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.753 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 5.753 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] delay duration 3.931025031s
server  | 5.753 DEBUG Setting timeout of 3.931025031s
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.753 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 5.753 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.753 DEBUG [Server efa05c3c6da2d8d6] delay duration 3.930970279s
server  | 5.753 DEBUG Setting timeout of 3.930970279s
server  | 5.841 DEBUG [Server efa05c3c6da2d8d6] pn=2 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, NotEct) len 38
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 1000, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 5.841 INFO [Server efa05c3c6da2d8d6] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 5.841 DEBUG [Server efa05c3c6da2d8d6] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 475, tv_nsec: 860871020 } }
server  | 5.841 DEBUG [Server efa05c3c6da2d8d6] Rx ACK space=ap, ranges=[0..=3]
server  | 5.841 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 5.841 DEBUG on_packets_acked this=0x55f3e04cbb40, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=1054
server  | 5.841 DEBUG [pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608] Path validated Instant { tv_sec: 464, tv_nsec: 67170403 }
server  | 5.841 DEBUG [Server efa05c3c6da2d8d6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 5.841 DEBUG Building Short dcid Some(CID [8]: e6da087d3f87ee32)
server  | 5.841 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 464, tv_nsec: 67170403 }
server  | 5.841 DEBUG [Server efa05c3c6da2d8d6] pn=5 type=Short pri-path:e6da087d3f87ee32 [::]:443->[::ffff:193.167.0.100]:47608 IpTos(Cs0, Ect0) len 35
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 5.841 DEBUG ECN probing: sent 9 probes
server  | 5.841 DEBUG Setting timeout of 11.793408172s
server  | 5.841 DEBUG Setting timeout of 11.793392773s
client  | Terminating Kwik

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:31:56,618 Using the server's key log file.
2025-04-30 12:31:56,627 
2025-04-30 12:31:56,628 Using the server's key log file.
2025-04-30 12:31:56,628 Using the server's key log file.
2025-04-30 12:31:56,636 
2025-04-30 12:31:56,636 Using the server's key log file.
2025-04-30 12:31:56,924 Check of downloaded files succeeded.
2025-04-30 12:31:58,140 Check of downloaded files succeeded.