2025-04-30 12:26:33,426 Generated random file: jaahwrzncs of size: 1024
2025-04-30 12:26:33,426 Requests: https://server4:443/jaahwrzncs
2025-04-30 12:26:33,484 
2025-04-30 12:26:33,484 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_cyl9fj38/ TESTCASE_SERVER=ecn TESTCASE_CLIENT=ecn WWW=/tmp/www_eu20h1nq/ DOWNLOADS=/tmp/download_5q6ejao1/ SERVER_LOGS=/tmp/logs_server_k_jurpi5 CLIENT_LOGS=/tmp/logs_client_w3qzregs 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/jaahwrzncs"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:26:45,442  Container sim  Created
 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-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]
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
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 ecn ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.61FzIzTMpv
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.61FzIzTMpv
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.61FzIzTMpv -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  |             4b:61:ae:4b:ea:1a:68:e1:7f:e4:4e:3b:93:ff:93:05:
server  |             f3:a5:41:8b
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:26:33 2025
server  |             Not After : Sat May 10 12:26:33 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:2b:ec:0e:77:b0:bf:21:d3:89:32:e8:bb:9a:ef:f0:
server  |                     4f:c8:b7:d3:47:47:24:3c:d3:30:97:c8:c9:fe:21:4c:
server  |                     50:9f:4d:af:d2:f5:02:16:94:74:c0:18:20:ea:51:19:
server  |                     1c:8f:20:9f:09:ff:c4:69:5b:25:7a:48:97:26:1f:c9:
server  |                     78
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  |                 db:5d:5d:61:19:54:72:a7:e3:7d:8b:84:54:56:13:d0:
server  |                 93:31:7b:b0
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 8e:21:af:4d:3a:db:b7:f1:d5:72:1c:91:4c:3d:25:e7:
server  |                 77:c2:f0:b7
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:35:3a:e1:ec:b3:ee:cc:cb:63:20:79:11:
server  |         cd:47:a2:a6:a1:92:ab:25:85:11:25:6c:c7:cb:45:ea:
server  |         19:04:f4:08:02:20:57:45:76:eb:ad:d1:fb:e3:2e:72:
server  |         ca:d1:4b:36:b8:59:97:52:53:77:68:02:37:fe:fb:c9:
server  |         c8:06:13:4b:d6:c5
server  |     Fingerprint (SHA-256):
server  |         D4:3D:AE:3E:D8:4D:43:B4:3B:1E:9E:AC:D9:63:02:1F:46:4E:CD:DB:2C:C4:3C:CC:39:74:07:0E:17:6E:B0:30
server  |     Fingerprint (SHA1):
server  |         2A:EA:16:82:F0:A3:40:57:2E:EE:7F:2D:F6:88:D7:08:3D:71:16:BF
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  | + '[' ecn = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test ecn --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.768 DEBUG [Server] Unsupported version: 57414954
server  | 0.768 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36899 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.023318654s
server  | 0.768 DEBUG [Server] Unsupported version: 57414954
server  | 0.768 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36899 IpTos(Cs0, NotEct) len 15
server  | 0.768 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.768 DEBUG [Server] Unsupported version: 57414954
server  | 0.768 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36899 IpTos(Cs0, NotEct) len 15
server  | 0.768 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth0, 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/jaahwrzncs'
client  | paths: -p /jaahwrzncs
client  | server: server4
client  | port: 443
client  | CLIENT_PARAMS:
server  | 0.998 DEBUG [Server] Handle initial
server  | 0.998 INFO AddressValidation: no token; accepting
server  | 0.998 INFO [Server] Accept connection CID [13]: 48ea27757aa06aa8e652816e0c
server  | 0.998 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.000 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.000 INFO [Server ...] Received valid Initial packet with scid CID [8]: 33f64bed06b11a67 dcid CID [13]: 48ea27757aa06aa8e652816e0c
server  | 1.000 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=48ea27757aa06aa8e652816e0c
server  | 1.000 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.000 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.000 DEBUG [Server 48ea27757aa06aa8e652816e0c] State change from Init -> WaitInitial
server  | 1.000 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 1200
server  |   -> RX Crypto { offset: 0, len: 248 }
server  |   -> RX Padding { len: 900 }
server  | 1.000 DEBUG Read Ok(248) bytes
server  | 1.000 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.000 DEBUG Read secret available for Handshake: SymKey [32]: 68c13eef047e4359515d60a4fdab25100133061c5d4a246bacea1eb75e37830d
server  | 1.000 DEBUG Write secret available for Handshake: SymKey [32]: 2b8eed25d4949aac661523ff631863480c8f6d0a9620ec65ec30d78abc638145
server  | 1.001 DEBUG Writing transport parameters, msg=8
server  | 1.001 DEBUG Read secret available for ApplicationData: SymKey [32]: 5312c33e286729690db2035358ab366d50fcd6d7139509804b2423ce69901ea0
server  | 1.001 DEBUG Write secret available for ApplicationData: SymKey [32]: e8ce088d4d2e5742aa30eb733acecaf6fe30fdf9427091eb2ed94b777c721692
server  | 1.001 DEBUG [Agent 0x5558313af1d0] state -> InProgress
server  | 1.001 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.001 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.001 DEBUG [Crypto] Handshake keys installed
server  | 1.001 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.001 DEBUG [Crypto] Application write key installed
server  | 1.001 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 463, tv_nsec: 359639668 }
server  | 1.001 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:60838] Make permanent
server  | 1.001 DEBUG [unv-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838] set as primary path
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] State change from WaitInitial -> Handshaking
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.001 DEBUG Building Initial dcid Some(CID [8]: 33f64bed06b11a67) scid Some(CID [10]: bb6d4217c5e962205683)
server  | 1.001 DEBUG CRYPTO for in offset=0, len=90
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=0 type=Initial pri-unv-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 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: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 1.001 DEBUG Building Handshake dcid Some(CID [8]: 33f64bed06b11a67) scid Some(CID [10]: bb6d4217c5e962205683)
server  | 1.001 DEBUG CRYPTO for hs offset=0, len=727
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=0 type=Handshake pri-unv-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 922
server  |   TX -> Crypto { offset: 0, len: 727 }
server  | 1.001 DEBUG packet_sent this=0x5558313aeb40, pn=0, ps=775
server  | 1.001 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] pad Initial from 922 to PLPMTU 1232
server  | 1.001 DEBUG packet_sent this=0x5558313aeb40, pn=0, ps=457
server  | 1.001 DEBUG ECN probing: sent 1 probes
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.001 DEBUG Building Initial dcid Some(CID [8]: 33f64bed06b11a67) scid Some(CID [10]: bb6d4217c5e962205683)
server  | 1.001 DEBUG Building Handshake dcid Some(CID [8]: 33f64bed06b11a67) scid Some(CID [10]: bb6d4217c5e962205683)
server  | 1.001 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.001 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 297.121668ms
server  | 1.001 DEBUG Setting timeout of 297.121668ms
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.001 DEBUG Building Initial dcid Some(CID [8]: 33f64bed06b11a67) scid Some(CID [10]: bb6d4217c5e962205683)
server  | 1.001 DEBUG Building Handshake dcid Some(CID [8]: 33f64bed06b11a67) scid Some(CID [10]: bb6d4217c5e962205683)
server  | 1.001 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.001 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.001 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 297.084208ms
server  | 1.001 DEBUG Setting timeout of 297.084208ms
server  | 1.035 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=1 type=Initial pri-unv-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 1032
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 195, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX Padding { len: 977 }
server  | 1.035 DEBUG [Server 48ea27757aa06aa8e652816e0c] Rx ACK space=in, ranges=[0..=0]
server  | 1.035 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 1.035 DEBUG on_packets_acked this=0x5558313aeb40, limited=1, bytes_in_flight=775, cwnd=12320, state=SlowStart, new_acked=457
server  | 1.035 DEBUG Acked crypto frame space=in offset=0 length=90
server  | 1.035 DEBUG [pri-unv-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838] Path validated Instant { tv_sec: 463, tv_nsec: 396680526 }
server  | 1.035 DEBUG [Server 48ea27757aa06aa8e652816e0c] Drop packet number space in
server  | 1.035 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.035 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=2 type=Handshake pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 92
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 95, 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  | 1.035 DEBUG [Server 48ea27757aa06aa8e652816e0c] Rx ACK space=hs, ranges=[0..=0]
server  | 1.035 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.035 DEBUG on_packets_acked this=0x5558313aeb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=775
server  | 1.035 DEBUG Acked crypto frame space=hs offset=0 length=727
server  | 1.035 DEBUG Read Ok(36) bytes
server  | 1.036 DEBUG [Agent 0x5558313af1d0] 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.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] TLS connection complete
server  | 1.036 DEBUG [pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838] Path validated Instant { tv_sec: 463, tv_nsec: 396680526 }
server  | 1.036 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.036 DEBUG [Crypto] application read keys installed
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] State change from Handshaking -> Connected
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] State change from Connected -> Confirmed
server  | 1.036 DEBUG PMTUD started with probe size 1380
server  | 1.036 INFO [Server 48ea27757aa06aa8e652816e0c] Connection established
server  | 1.036 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 463, tv_nsec: 396680526 }
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=3 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 76
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [64, 66, 184, 105, 128, 7, 132, 220], stateless_reset_token: [34, 237, 7, 154, 200, 224, 22, 25, 80, 102, 194, 79, 207, 165, 131, 111] }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.036 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 463, tv_nsec: 396680526 }
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG Building Handshake dcid Some(CID [8]: 33f64bed06b11a67) scid Some(CID [10]: bb6d4217c5e962205683)
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=1 type=Handshake pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 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: 0, Ect0: 1, Ce: 0})) }
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] Drop packet number space hs
server  | 1.036 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.036 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=0 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 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: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [3, 120, 16, 105, 163, 32, 59, 250, 72, 16], stateless_reset_token: [8, 28, 156, 73, 45, 179, 135, 47, 225, 30, 186, 199, 92, 25, 218, 255] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [242, 180, 58, 159, 112, 122, 185, 174, 20, 214], stateless_reset_token: [246, 103, 5, 35, 208, 224, 202, 213, 216, 181, 197, 139, 226, 236, 238, 132] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [54, 82, 19, 121, 116, 10, 230, 59, 170, 254], stateless_reset_token: [39, 187, 241, 127, 133, 3, 248, 188, 219, 91, 130, 246, 199, 106, 236, 161] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [119, 153, 16, 84, 115, 239, 25, 134, 140, 181], stateless_reset_token: [150, 22, 184, 130, 216, 24, 110, 153, 62, 246, 38, 27, 19, 63, 85, 157] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [75, 1, 120, 172, 247, 190, 207, 222, 187, 43], stateless_reset_token: [240, 78, 212, 66, 52, 210, 125, 210, 148, 84, 124, 82, 203, 21, 50, 94] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [185, 82, 176, 242, 244, 50, 134, 100, 28, 66], stateless_reset_token: [79, 53, 208, 148, 69, 132, 168, 18, 164, 208, 249, 69, 189, 216, 85, 64] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [51, 19, 245, 27, 253, 206, 105, 8, 147, 129], stateless_reset_token: [166, 115, 78, 174, 227, 167, 24, 63, 127, 113, 75, 223, 120, 197, 203, 218] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false }
server  |   TX -> Padding { len: 1028 }
server  | 1.036 DEBUG packet_sent this=0x5558313aeb40, pn=0, ps=1280
server  | 1.036 DEBUG ECN probing: sent 2 probes
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.036 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=1 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 1.036 DEBUG packet_sent this=0x5558313aeb40, pn=1, ps=1332
server  | 1.036 DEBUG ECN probing: sent 3 probes
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.036 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.036 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 1.410677ms
server  | 1.036 DEBUG Setting timeout of 1.410677ms
server  | 1.037 DEBUG [Server 48ea27757aa06aa8e652816e0c] send session ticket 4068000d48ea27757aa06aa8e652816e0c0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0abb6d4217c5e962205683110c000000015aaaca8a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.037 DEBUG Path = 'jaahwrzncs'
server  | 1.037 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.037 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.037 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.037 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 413.563µs
server  | 1.037 DEBUG Setting timeout of 413.563µs
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.039 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.039 DEBUG CRYPTO for ap offset=0, len=174
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=2 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 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.039 DEBUG packet_sent this=0x5558313aeb40, pn=2, ps=1232
server  | 1.039 DEBUG ECN probing: sent 4 probes
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.039 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.039 DEBUG CRYPTO for ap offset=174, len=147
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=3 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 223
server  |   TX -> Crypto { offset: 174, len: 147 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 143, 14, 141, 238, 188, 101, 85, 206, 196, 184, 111, 179, 67, 29, 117, 120, 64, 203, 155, 209, 195, 56, 223, 170, 209, 69, 93, 160, 249, 135, 118, 115, 42, 80, 51, 19] }
server  | 1.039 DEBUG packet_sent this=0x5558313aeb40, pn=3, ps=223
server  | 1.039 DEBUG ECN probing: sent 5 probes
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.039 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.039 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 117.505111ms
server  | 1.039 DEBUG Setting timeout of 117.505111ms
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.039 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.039 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.039 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 117.478621ms
server  | 1.039 DEBUG Setting timeout of 117.478621ms
client  | /logs/33F64BED06B11A67.keys
client  | /logs/keys.log exists
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=4 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 37
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 5, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] Rx ACK space=ap, ranges=[0..=1]
server  | 1.069 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.069 DEBUG on_packets_acked this=0x5558313aeb40, limited=1, bytes_in_flight=1455, cwnd=12320, state=SlowStart, new_acked=2612
server  | 1.069 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.069 DEBUG PMTUD started with probe size 1420
server  | 1.069 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.069 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=4 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1345 }
server  | 1.069 DEBUG packet_sent this=0x5558313aeb40, pn=4, ps=1372
server  | 1.069 DEBUG ECN probing: sent 6 probes
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.069 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 92.43681ms
server  | 1.069 DEBUG Setting timeout of 92.43681ms
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.069 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 92.412394ms
server  | 1.069 DEBUG Setting timeout of 92.412394ms
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=5 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 37
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 8, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] Rx ACK space=ap, ranges=[0..=2]
server  | 1.071 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 1.071 DEBUG on_packets_acked this=0x5558313aeb40, limited=1, bytes_in_flight=1595, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.071 DEBUG Acked crypto frame space=ap offset=0 length=174
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.071 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=5 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 28
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.071 DEBUG packet_sent this=0x5558313aeb40, pn=5, ps=29
server  | 1.071 DEBUG ECN probing: sent 7 probes
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.071 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.071 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] delay duration 85.076605ms
server  | 1.071 DEBUG Setting timeout of 85.076605ms
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=6 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 IpTos(Cs0, Ect0) len 32
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  | 1.071 INFO [Server 48ea27757aa06aa8e652816e0c] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 463, tv_nsec: 687886446 } }
server  | 1.071 DEBUG [pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838] Path validated Instant { tv_sec: 463, tv_nsec: 432347925 }
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.071 DEBUG Building Short dcid Some(CID [8]: 33f64bed06b11a67)
server  | 1.071 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 463, tv_nsec: 432347925 }
server  | 1.071 DEBUG [Server 48ea27757aa06aa8e652816e0c] pn=6 type=Short pri-path:33f64bed06b11a67 [::]:443->[::ffff:193.167.0.100]:60838 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: 0, Ect0: 4, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 1.071 DEBUG ECN probing: sent 8 probes
server  | 1.071 DEBUG Setting timeout of 255.423455ms
server  | 1.071 DEBUG Setting timeout of 255.415721ms

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:26:45,534 Using the client's key log file.
2025-04-30 12:26:45,543 
2025-04-30 12:26:45,544 Using the client's key log file.
2025-04-30 12:26:45,544 Using the client's key log file.
2025-04-30 12:26:45,551 
2025-04-30 12:26:45,552 Using the client's key log file.
2025-04-30 12:26:45,832 Check of downloaded files succeeded.
2025-04-30 12:26:46,401 Using the client's key log file.
2025-04-30 12:26:46,681 Check of downloaded files succeeded.
2025-04-30 12:26:47,537 ECN.NONE 0
2025-04-30 12:26:47,537 ECN.ECT1 0
2025-04-30 12:26:47,537 ECN.ECT0 5
2025-04-30 12:26:47,537 ECN.CE 0
2025-04-30 12:26:47,830 ECN.NONE 0
2025-04-30 12:26:47,830 ECN.ECT1 0
2025-04-30 12:26:47,830 ECN.ECT0 8
2025-04-30 12:26:47,830 ECN.CE 0