2025-04-30 12:29:19,253 Generated random file: vqxieeuigz of size: 1024
2025-04-30 12:29:19,253 Requests: https://server4:443/vqxieeuigz
2025-04-30 12:29:19,313 
2025-04-30 12:29:19,313 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_a8ldxagn/ TESTCASE_SERVER=v2 TESTCASE_CLIENT=v2 WWW=/tmp/www_d0o4skf9/ DOWNLOADS=/tmp/download_2_amt1o3/ SERVER_LOGS=/tmp/logs_server_9d66cj1u CLIENT_LOGS=/tmp/logs_client_v3vktp3e SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=litespeedtech/lsquic-qir:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/vqxieeuigz"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:29:31,284  Container sim  Created
 Container client  Recreate
 Container server  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-checksumming: off
client  | 	tx-checksum-ip-generic: off
client  | 	tx-checksum-sctp: off
client  | tcp-segmentation-offload: off
client  | 	tx-tcp-segmentation: off [requested on]
client  | 	tx-tcp-ecn-segmentation: off [requested on]
client  | 	tx-tcp-mangleid-segmentation: off [requested on]
client  | 	tx-tcp6-segmentation: off [requested on]
client  | tx-udp-segmentation: off [requested on]
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
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 v2 ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.RdY21vTd6T
server  | + mkdir -p /neqo/db
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
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.RdY21vTd6T
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.RdY21vTd6T -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  |             59:c9:69:df:01:b1:2d:dc:c3:92:db:93:18:f1:99:00:
server  |             e7:64:3b:b8
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:29:19 2025
server  |             Not After : Sat May 10 12:29:19 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:1f:59:e8:06:12:3a:5b:c4:af:6d:91:19:fd:e2:3f:
server  |                     ed:e8:76:51:42:9a:95:de:cd:bc:c6:86:a9:91:fc:50:
server  |                     05:1b:9a:4d:f5:4e:9c:8a:b4:cd:7a:fe:5e:68:bb:be:
server  |                     2f:09:ae:52:5d:93:b3:7f:3f:96:b6:fe:30:9a:24:6c:
server  |                     86
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  |                 55:6a:47:8c:9e:a3:27:3e:e4:81:e6:6d:f1:77:a9:4d:
server  |                 ba:0c:eb:b8
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 83:b7:e8:10:3f:94:c6:25:12:78:ef:a2:77:ee:c1:13:
server  |                 c8:86:26:4a
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:b7:90:68:b4:60:4c:14:3d:0c:97:c0:
server  |         e7:88:4a:94:31:bd:f3:08:55:7e:18:ae:ca:68:c5:3d:
server  |         64:18:c0:d4:20:02:21:00:84:44:fd:b8:56:bf:f1:5d:
server  |         b7:ad:e6:46:5c:f9:61:6c:b7:dc:e2:9d:66:a0:c6:7a:
server  |         8d:41:1d:b3:d2:17:cd:16
server  |     Fingerprint (SHA-256):
server  |         04:0A:E5:DB:6E:3C:F6:D7:CB:5A:B2:84:3E:FB:42:FD:C9:89:D0:5B:8D:35:88:A5:1F:A2:B1:8F:77:1E:43:5F
server  |     Fingerprint (SHA1):
server  |         FA:C9:9E:CD:6E:FB:B9:66:93:F4:F8:0B:FD:C4:D0:81:1D:D4:3A:A6
server  | 
server  |     Mozilla-CA-Policy: false (attribute missing)
server  |     Certificate Trust Flags:
server  |         SSL Flags:
server  |             User
server  |         Email Flags:
server  |             User
server  |         Object Signing Flags:
server  |             User
server  | 
server  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT")
server  | + '[' v2 = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test v2 --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443'
server  | ++ tee -i -a /logs/server.log
server  | 0.000 DEBUG Logging initialized
server  | 0.002 DEBUG Default socket send buffer size is 212992
server  | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.002 INFO Server waiting for connection on: [::]:443
server  | 0.801 DEBUG [Server] Unsupported version: 57414954
server  | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:55751 IpTos(Cs0, NotEct) len 23
sim     | server:443 is available after 1.05830101s
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
server  | 0.801 DEBUG [Server] Unsupported version: 57414954
server  | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:55751 IpTos(Cs0, NotEct) len 23
server  | 0.801 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.801 DEBUG [Server] Unsupported version: 57414954
server  | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:55751 IpTos(Cs0, NotEct) len 23
server  | 0.801 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
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  | TEST_PARAMS:
client  | REQUESTS: 'https://server4:443/vqxieeuigz'
client  | paths: -p /vqxieeuigz
client  | server: server4
client  | port: 443
client  | CLIENT_PARAMS:
server  | 0.992 DEBUG [Server] Handle initial
server  | 0.992 INFO AddressValidation: no token; accepting
server  | 0.992 INFO [Server] Accept connection CID [12]: 910c7296c713d37b792cbf80
server  | 0.992 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.994 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.994 INFO [Server ...] Received valid Initial packet with scid CID [8]: d8d1fa12161ed4a8 dcid CID [12]: 910c7296c713d37b792cbf80
server  | 0.994 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=910c7296c713d37b792cbf80
server  | 0.994 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.994 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.994 DEBUG [Server 910c7296c713d37b792cbf80] State change from Init -> WaitInitial
server  | 0.994 DEBUG [Server 910c7296c713d37b792cbf80] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect1) len 1200
server  |   -> RX Crypto { offset: 0, len: 262 }
server  |   -> RX Padding { len: 887 }
server  | 0.994 DEBUG Read Ok(262) bytes
server  | 0.994 INFO Compatible upgrade Version1 ==> Version2
server  | 0.994 DEBUG Overwrite initial version Version1 ==> Version2
server  | 0.994 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.994 DEBUG Read secret available for Handshake: SymKey [32]: 08212e0b233486ac529a7dc359f169565e6eccc01fecbf22dcaae4d1b810df41
server  | 0.994 DEBUG Write secret available for Handshake: SymKey [32]: 4991d746128d908b33de4f1377317644739c0dcb68b1501635ebb6f5a225803a
server  | 0.994 DEBUG Writing transport parameters, msg=8
server  | 0.995 DEBUG Read secret available for ApplicationData: SymKey [32]: 38b87017713b092b99c4b738d2b92640af2a52f8a67af4d1ddd00f0f2fa301d0
server  | 0.995 DEBUG Write secret available for ApplicationData: SymKey [32]: 9be6da6687e091ecb91c6ac738c99cc221dc9384c87148b90799bc4a79b39d8d
server  | 0.995 DEBUG [Agent 0x564f5a0e81e0] state -> InProgress
server  | 0.995 DEBUG [CryptoStates] Creating initial cipher state v=Version2, role=Server dcid=910c7296c713d37b792cbf80
server  | 0.995 DEBUG Making Write Initial CryptoDxState, v=Version2 cipher=4865
server  | 0.995 DEBUG Making Read Initial CryptoDxState, v=Version2 cipher=4865
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] Compatible upgrade Version1 ==> Version2
server  | 0.995 DEBUG Making Write Handshake CryptoDxState, v=Version2 cipher=4865
server  | 0.995 DEBUG Making Read Handshake CryptoDxState, v=Version2 cipher=4865
server  | 0.995 DEBUG [Crypto] Handshake keys installed
server  | 0.995 DEBUG Making Write ApplicationData CryptoDxState, v=Version2 cipher=4865
server  | 0.995 DEBUG [Crypto] Application write key installed
server  | 0.995 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 629, tv_nsec: 208816277 }
server  | 0.995 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:45845] Make permanent
server  | 0.995 DEBUG [unv-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845] set as primary path
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] State change from WaitInitial -> Handshaking
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.995 DEBUG Building Initial dcid Some(CID [8]: d8d1fa12161ed4a8) scid Some(CID [10]: c20163f87e56952fbf44)
server  | 0.995 DEBUG CRYPTO for in offset=0, len=90
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] pn=0 type=Initial pri-unv-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 147
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 0.995 DEBUG Building Handshake dcid Some(CID [8]: d8d1fa12161ed4a8) scid Some(CID [10]: c20163f87e56952fbf44)
server  | 0.995 DEBUG CRYPTO for hs offset=0, len=735
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] pn=0 type=Handshake pri-unv-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 930
server  |   TX -> Crypto { offset: 0, len: 735 }
server  | 0.995 DEBUG packet_sent this=0x564f5a0e7b20, pn=0, ps=783
server  | 0.995 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] pad Initial from 930 to PLPMTU 1232
server  | 0.995 DEBUG packet_sent this=0x564f5a0e7b20, pn=0, ps=449
server  | 0.995 DEBUG ECN probing: sent 1 probes
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.995 DEBUG Building Initial dcid Some(CID [8]: d8d1fa12161ed4a8) scid Some(CID [10]: c20163f87e56952fbf44)
server  | 0.995 DEBUG Building Handshake dcid Some(CID [8]: d8d1fa12161ed4a8) scid Some(CID [10]: c20163f87e56952fbf44)
server  | 0.995 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 0.995 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 297.010184ms
server  | 0.995 DEBUG Setting timeout of 297.010184ms
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.995 DEBUG Building Initial dcid Some(CID [8]: d8d1fa12161ed4a8) scid Some(CID [10]: c20163f87e56952fbf44)
server  | 0.995 DEBUG Building Handshake dcid Some(CID [8]: d8d1fa12161ed4a8) scid Some(CID [10]: c20163f87e56952fbf44)
server  | 0.995 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 0.995 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.995 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 296.979326ms
server  | 0.995 DEBUG Setting timeout of 296.979326ms
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] pn=1 type=Initial pri-unv-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect1) len 1035
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 203, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 983 }
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] Rx ACK space=in, ranges=[0..=0]
server  | 1.030 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 1.030 DEBUG on_packets_acked this=0x564f5a0e7b20, limited=1, bytes_in_flight=783, cwnd=12320, state=SlowStart, new_acked=449
server  | 1.030 DEBUG Acked crypto frame space=in offset=0 length=90
server  | 1.030 DEBUG [pri-unv-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845] Path validated Instant { tv_sec: 629, tv_nsec: 246092286 }
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] Drop packet number space in
server  | 1.030 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] pn=2 type=Handshake pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect1) len 89
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 94, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] Rx ACK space=hs, ranges=[0..=0]
server  | 1.030 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.030 DEBUG on_packets_acked this=0x564f5a0e7b20, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=783
server  | 1.030 DEBUG Acked crypto frame space=hs offset=0 length=735
server  | 1.030 DEBUG Read Ok(36) bytes
server  | 1.030 DEBUG [Agent 0x564f5a0e81e0] 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  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] TLS connection complete
server  | 1.030 DEBUG [pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845] Path validated Instant { tv_sec: 629, tv_nsec: 246092286 }
server  | 1.030 DEBUG Making Read ApplicationData CryptoDxState, v=Version2 cipher=4865
server  | 1.030 DEBUG [Crypto] application read keys installed
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] State change from Handshaking -> Connected
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] State change from Connected -> Confirmed
server  | 1.030 DEBUG PMTUD started with probe size 1380
server  | 1.030 INFO [Server 910c7296c713d37b792cbf80] Connection established
server  | 1.030 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 629, tv_nsec: 246092286 }
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] pn=3 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect1) len 76
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [117, 22, 21, 218, 235, 233, 123, 246], stateless_reset_token: [187, 130, 170, 159, 206, 194, 106, 112, 147, 76, 179, 193, 141, 82, 187, 152] }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.030 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 629, tv_nsec: 246092286 }
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.030 DEBUG Building Handshake dcid Some(CID [8]: d8d1fa12161ed4a8) scid Some(CID [10]: c20163f87e56952fbf44)
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] pn=1 type=Handshake pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 52
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] Drop packet number space hs
server  | 1.030 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.030 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] pn=0 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [159, 184, 220, 142, 125, 119, 199, 188, 23, 148], stateless_reset_token: [230, 10, 205, 53, 73, 216, 250, 184, 216, 157, 111, 250, 3, 30, 108, 72] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [74, 44, 158, 108, 35, 7, 98, 246, 31, 43], stateless_reset_token: [183, 102, 194, 242, 84, 238, 95, 108, 19, 166, 1, 181, 160, 67, 129, 219] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [56, 7, 249, 6, 110, 232, 34, 249, 207, 57], stateless_reset_token: [146, 255, 49, 58, 61, 125, 34, 134, 142, 161, 70, 233, 16, 239, 141, 211] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [207, 27, 97, 90, 83, 195, 159, 115, 113, 28], stateless_reset_token: [20, 231, 73, 47, 176, 150, 149, 107, 54, 195, 150, 61, 201, 108, 104, 19] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [220, 94, 19, 33, 50, 82, 35, 238, 75, 243], stateless_reset_token: [179, 175, 153, 84, 234, 187, 26, 55, 189, 73, 234, 15, 148, 178, 105, 157] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [231, 20, 78, 77, 170, 209, 151, 80, 100, 238], stateless_reset_token: [135, 215, 245, 39, 54, 54, 75, 13, 70, 50, 60, 118, 67, 26, 192, 144] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [248, 5, 176, 140, 12, 229, 184, 54, 23, 50], stateless_reset_token: [131, 234, 215, 52, 190, 72, 105, 186, 100, 37, 125, 112, 13, 122, 65, 134] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false }
server  |   TX -> Padding { len: 1028 }
server  | 1.030 DEBUG packet_sent this=0x564f5a0e7b20, pn=0, ps=1280
server  | 1.030 DEBUG ECN probing: sent 2 probes
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.030 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.030 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] pn=1 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 1.030 DEBUG packet_sent this=0x564f5a0e7b20, pn=1, ps=1332
server  | 1.030 DEBUG ECN probing: sent 3 probes
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.030 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.030 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 1.453663ms
server  | 1.030 DEBUG Setting timeout of 1.453663ms
server  | 1.031 DEBUG [Server 910c7296c713d37b792cbf80] send session ticket 406f000c910c7296c713d37b792cbf800104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ac20163f87e56952fbf4411146b3343cf9a8a4a6a6b3343cf00000001ff00001d6ab200c0000000ff02de1a0243e8200100686921
server  | 1.031 DEBUG Path = 'vqxieeuigz'
server  | 1.031 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.031 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 435.931µs
server  | 1.031 DEBUG Setting timeout of 435.931µs
server  | 1.033 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.033 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.033 DEBUG CRYPTO for ap offset=0, len=174
server  | 1.033 DEBUG [Server 910c7296c713d37b792cbf80] pn=2 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  |   TX -> Crypto { offset: 0, len: 174 }
server  | 1.033 DEBUG packet_sent this=0x564f5a0e7b20, pn=2, ps=1232
server  | 1.033 DEBUG ECN probing: sent 4 probes
server  | 1.033 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.033 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.033 DEBUG CRYPTO for ap offset=174, len=147
server  | 1.033 DEBUG [Server 910c7296c713d37b792cbf80] pn=3 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 223
server  |   TX -> Crypto { offset: 174, len: 147 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 27, 231, 178, 13, 127, 46, 50, 173, 202, 12, 195, 89, 100, 40, 191, 192, 233, 121, 174, 230, 206, 90, 244, 243, 198, 65, 7, 249, 249, 143, 100, 191, 45, 250, 215, 205] }
server  | 1.034 DEBUG packet_sent this=0x564f5a0e7b20, pn=3, ps=223
server  | 1.034 DEBUG ECN probing: sent 5 probes
server  | 1.034 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.034 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.034 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.034 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 118.081087ms
server  | 1.034 DEBUG Setting timeout of 118.081087ms
server  | 1.034 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.034 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.034 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.034 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 118.059406ms
server  | 1.034 DEBUG Setting timeout of 118.059406ms
client  | /logs/D8D1FA12161ED4A8.keys
client  | /logs/keys.log exists
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] pn=4 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 5, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] Rx ACK space=ap, ranges=[0..=1]
server  | 1.063 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.063 DEBUG on_packets_acked this=0x564f5a0e7b20, limited=1, bytes_in_flight=1455, cwnd=12320, state=SlowStart, new_acked=2612
server  | 1.063 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.063 DEBUG PMTUD started with probe size 1420
server  | 1.063 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.063 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] pn=4 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1345 }
server  | 1.063 DEBUG packet_sent this=0x564f5a0e7b20, pn=4, ps=1372
server  | 1.063 DEBUG ECN probing: sent 6 probes
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 92.894549ms
server  | 1.063 DEBUG Setting timeout of 92.894549ms
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 92.855265ms
server  | 1.063 DEBUG Setting timeout of 92.855265ms
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] pn=5 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 8, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] Rx ACK space=ap, ranges=[0..=2]
server  | 1.065 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 1.065 DEBUG on_packets_acked this=0x564f5a0e7b20, limited=1, bytes_in_flight=1595, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.065 DEBUG Acked crypto frame space=ap offset=0 length=174
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.065 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] pn=5 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 28
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.065 DEBUG packet_sent this=0x564f5a0e7b20, pn=5, ps=29
server  | 1.065 DEBUG ECN probing: sent 7 probes
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.065 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.065 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] delay duration 85.723276ms
server  | 1.065 DEBUG Setting timeout of 85.723276ms
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] pn=6 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect1) len 32
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  | 1.065 INFO [Server 910c7296c713d37b792cbf80] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 629, tv_nsec: 539297965 } }
server  | 1.065 DEBUG [pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845] Path validated Instant { tv_sec: 629, tv_nsec: 281724928 }
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.065 DEBUG Building Short dcid Some(CID [8]: d8d1fa12161ed4a8)
server  | 1.065 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 629, tv_nsec: 281724928 }
server  | 1.065 DEBUG [Server 910c7296c713d37b792cbf80] pn=6 type=Short pri-path:d8d1fa12161ed4a8 [::]:443->[::ffff:193.167.0.100]:45845 IpTos(Cs0, Ect0) len 38
server  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 4, Ect0: 0, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 1.065 DEBUG ECN probing: sent 8 probes
server  | 1.065 DEBUG Setting timeout of 257.43103ms
server  | 1.065 DEBUG Setting timeout of 257.418627ms

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:29:31,380 Using the client's key log file.
2025-04-30 12:29:31,389 
2025-04-30 12:29:31,390 Using the client's key log file.
2025-04-30 12:29:31,390 Using the client's key log file.
2025-04-30 12:29:31,398 
2025-04-30 12:29:31,399 Using the client's key log file.
2025-04-30 12:29:32,524 Check of downloaded files succeeded.