2025-04-30 08:42:31,613 Generated random file: kyoxytznpy of size: 1024 2025-04-30 08:42:31,613 Requests: https://server4:443/kyoxytznpy 2025-04-30 08:42:31,672 2025-04-30 08:42:31,673 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_0xrcfcen/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_gd080k4l/ DOWNLOADS=/tmp/download_50bbz6dv/ SERVER_LOGS=/tmp/logs_server_l8lkdfrl CLIENT_LOGS=/tmp/logs_client_h28i3o_2 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=privateoctopus/picoquic:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/kyoxytznpy" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:42:43,875 Container sim Recreate Container sim Recreated Container client Recreate Container server Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up the simulation with setup.sh server | Setting up routes... client | 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 | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off server | Endpoint's IPv4 address is 193.167.100.100 client | Endpoint's IPv4 address is 193.167.0.100 server | Endpoint's IPv6 address is fd00:cafe:cafe:100::100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 client | Setup.sh completed 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 client | Waiting for the simulator to start server | ++ mktemp server | + P12CERT=/tmp/tmp.uzc5YlniPa 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.uzc5YlniPa server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.uzc5YlniPa -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 | 5c:8c:d0:15:b2:96:d3:5d:60:54:94:0f:45:8f:59:97: server | c5:f2:e5:e7 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 08:42:31 2025 server | Not After : Sat May 10 08:42:31 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:22:13:24:c4:5b:ba:cd:df:33:e0:c2:e9:73:f8:42: server | d8:b0:6d:34:36:6a:61:02:dd:21:5a:eb:17:76:d2:f9: server | b9:5f:3f:c6:66:55:01:ed:75:c1:8f:8c:24:6c:d8:32: server | 54:81:30:38:f2:06:01:06:79:5f:2b:b3:41:c8:e1:6b: server | fb 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 | 3b:39:05:4a:55:b3:c1:0a:17:6f:d8:9c:f4:d1:31:4d: server | 39:94:86:d4 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 3f:ac:e1:79:20:d1:ca:6a:dc:8c:98:41:84:43:c5:c8: server | b8:2c:f7:60 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:44:02:20:31:59:ee:2c:64:4f:d6:be:57:91:3e:a2: server | 3d:9c:c3:46:03:e7:b5:87:4d:4b:1f:15:b0:57:62:d6: server | a5:c9:83:3a:02:20:44:61:5e:c6:e7:26:f2:1e:4a:42: server | 36:7e:b2:87:22:75:f3:b4:fc:16:24:5f:d0:86:fc:4d: server | 24:9f:d9:e0:29:fb server | Fingerprint (SHA-256): server | C4:7D:77:37:B6:80:A6:97:0A:0B:50:D1:6C:B8:03:8D:54:31:86:77:45:86:D8:82:7C:92:40:53:1D:6D:04:EE server | Fingerprint (SHA1): server | DC:2A:0B:4A:1A:13:B4:C6:94:51:2F:05:5B:A2:3C:6E:5C:B2:01:71 server | server | Mozilla-CA-Policy: false (attribute missing) server | Certificate Trust Flags: server | SSL Flags: server | User server | Email Flags: server | User server | Object Signing Flags: server | User server | server | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT") server | + '[' handshake = connectionmigration ']' server | + RUST_LOG=debug server | + RUST_BACKTRACE=1 server | + neqo-server --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443' server | ++ tee -i -a /logs/server.log server | 0.000 DEBUG Logging initialized server | 0.002 DEBUG Default socket send buffer size is 212992 server | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.002 INFO Server waiting for connection on: [::]:443 server | 0.730 DEBUG [Server] Unsupported version: 57414954 server | 0.730 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:44710 IpTos(Cs0, NotEct) len 15 sim | server:443 is available after 1.005484403s server | 0.730 DEBUG [Server] Unsupported version: 57414954 server | 0.730 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:44710 IpTos(Cs0, NotEct) len 15 server | 0.730 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.730 DEBUG [Server] Unsupported version: 57414954 server | 0.730 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:44710 IpTos(Cs0, NotEct) len 15 server | 0.730 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 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 | Starting picoquic client for test: handshake client | Starting picoquic client ... client | Parsing server name from first request: https://server4:443/kyoxytznpy client | Server set to: server4 client | Requests: https://server4:443/kyoxytznpy client | parsing as client | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443 client | No token file present. Will create one as . client | Testing scenario: <-:/kyoxytznpy;> client | Max stream id bidir remote before start = 0 (0) client | Starting client connection. Version = 1, I-CID: 72a223efc8f31670 client | Max stream id bidir remote after start = -4 (0) client | Waiting for packets. server | 0.996 DEBUG [Server] Handle initial server | 0.996 INFO AddressValidation: no token; accepting server | 0.996 INFO [Server] Accept connection CID [8]: 72a223efc8f31670 server | 0.996 DEBUG Overwrite initial version Version1 ==> Version1 server | 0.997 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 0.997 INFO [Server ...] Received valid Initial packet with scid CID [8]: bf1aac0b28ab96e7 dcid CID [8]: 72a223efc8f31670 server | 0.997 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=72a223efc8f31670 server | 0.997 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 0.997 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 0.998 DEBUG [Server 72a223efc8f31670] State change from Init -> WaitInitial server | 0.998 DEBUG [Server 72a223efc8f31670] pn=103526 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect1) len 1252 server | -> RX Ping server | -> RX Crypto { offset: 0, len: 285 } server | -> RX Padding { len: 916 } server | 0.998 DEBUG unmark 0-length range at 0 server | 0.998 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 228, tv_nsec: 274351848 } server | 0.998 DEBUG Read Ok(285) bytes server | 0.998 DEBUG 0-RTT: no token, no 0-RTT server | 0.998 DEBUG Read secret available for Handshake: SymKey [32]: b7f255ac9eb7d943306a6807dc719ad83d59efe8dbcedef19806f83b91f0d215 server | 0.998 DEBUG Write secret available for Handshake: SymKey [32]: a925524784d5f33fce9e4f906e0c28d89a7b2db8992ea6a98a2b3ee1e38c6608 server | 0.998 DEBUG Writing transport parameters, msg=8 server | 0.999 DEBUG Read secret available for ApplicationData: SymKey [32]: a1f8208a13df1c1cec0d758f1db3ebeaa5ebc4a501b7c44af09eb913db8c8230 server | 0.999 DEBUG Write secret available for ApplicationData: SymKey [32]: eb9b7dacdd833d13b37867ec8b2fb399fca1a8fb717da956c4b8d05cb99aa67b server | 0.999 DEBUG [Agent 0x55e332c9b1d0] state -> InProgress server | 0.999 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 0.999 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 0.999 DEBUG [Crypto] Handshake keys installed server | 0.999 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 0.999 DEBUG [Crypto] Application write key installed server | 0.999 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 228, tv_nsec: 274351848 } server | 0.999 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:37667] Make permanent server | 0.999 DEBUG [unv-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667] set as primary path server | 0.999 DEBUG [Server 72a223efc8f31670] State change from WaitInitial -> Handshaking server | 0.999 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.999 DEBUG Building Initial dcid Some(CID [8]: bf1aac0b28ab96e7) scid Some(CID [10]: 7e9a8223dcd03d25d6c2) server | 0.999 DEBUG CRYPTO for in offset=0, len=123 server | 0.999 DEBUG [Server 72a223efc8f31670] pn=0 type=Initial pri-unv-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 183 server | TX -> Ack { largest_acknowledged: 103526, 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: 123 } server | 0.999 DEBUG Building Handshake dcid Some(CID [8]: bf1aac0b28ab96e7) scid Some(CID [10]: 7e9a8223dcd03d25d6c2) server | 0.999 DEBUG CRYPTO for hs offset=0, len=720 server | 0.999 DEBUG [Server 72a223efc8f31670] pn=0 type=Handshake pri-unv-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 951 server | TX -> Crypto { offset: 0, len: 720 } server | 0.999 DEBUG packet_sent this=0x55e332c9ab40, pn=0, ps=768 server | 0.999 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 0.999 DEBUG [Server 72a223efc8f31670] pad Initial from 951 to PLPMTU 1232 server | 0.999 DEBUG packet_sent this=0x55e332c9ab40, pn=0, ps=464 server | 0.999 DEBUG ECN probing: sent 1 probes server | 0.999 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.999 DEBUG Building Initial dcid Some(CID [8]: bf1aac0b28ab96e7) scid Some(CID [10]: 7e9a8223dcd03d25d6c2) server | 0.999 DEBUG Building Handshake dcid Some(CID [8]: bf1aac0b28ab96e7) scid Some(CID [10]: 7e9a8223dcd03d25d6c2) server | 0.999 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 0.999 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.999 DEBUG [Server 72a223efc8f31670] delay duration 296.714339ms server | 0.999 DEBUG Setting timeout of 296.714339ms server | 0.999 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.999 DEBUG Building Initial dcid Some(CID [8]: bf1aac0b28ab96e7) scid Some(CID [10]: 7e9a8223dcd03d25d6c2) server | 0.999 DEBUG Building Handshake dcid Some(CID [8]: bf1aac0b28ab96e7) scid Some(CID [10]: 7e9a8223dcd03d25d6c2) server | 0.999 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 0.999 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.999 DEBUG [Server 72a223efc8f31670] delay duration 296.678422ms server | 0.999 DEBUG Setting timeout of 296.678422ms client | Client port (AF=2): 9107. client | Negotiated ALPN: hq-interop client | Almost ready! client | client | Connection established. Version = 1, I-CID: 72a223efc8f31670, verified: 1 client | Opening stream 0 to GET /kyoxytznpy server | 1.031 DEBUG [Server 72a223efc8f31670] Drop packet number space in server | 1.031 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.031 INFO [pri-unv-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667] discarding a packet without an RTT estimate; guessing RTT=35.434723ms server | 1.031 DEBUG [Server 72a223efc8f31670] pn=0 type=Handshake pri-unv-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect1) len 94 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 38, 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.031 DEBUG [Server 72a223efc8f31670] Rx ACK space=hs, ranges=[0..=0] server | 1.031 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.031 DEBUG on_packets_acked this=0x55e332c9ab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=768 server | 1.031 DEBUG Acked crypto frame space=hs offset=0 length=720 server | 1.031 DEBUG Read Ok(36) bytes server | 1.031 DEBUG [Agent 0x55e332c9b1d0] 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 | 1.031 DEBUG [Server 72a223efc8f31670] TLS connection complete server | 1.031 DEBUG [pri-unv-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667] Path validated Instant { tv_sec: 228, tv_nsec: 309786571 } server | 1.031 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.032 DEBUG [Crypto] application read keys installed server | 1.032 DEBUG [Server 72a223efc8f31670] State change from Handshaking -> Connected server | 1.032 DEBUG [Server 72a223efc8f31670] State change from Connected -> Confirmed server | 1.032 DEBUG PMTUD started with probe size 1380 server | 1.032 INFO [Server 72a223efc8f31670] Connection established server | 1.032 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 228, tv_nsec: 309786571 } server | 1.032 DEBUG [Server 72a223efc8f31670] pn=0 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect1) len 311 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [73, 44, 124, 176, 7, 94, 171, 43], stateless_reset_token: [218, 84, 81, 248, 162, 81, 127, 173, 135, 236, 137, 18, 207, 124, 74, 95] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [10, 43, 59, 22, 43, 139, 252, 8], stateless_reset_token: [229, 69, 146, 246, 40, 169, 185, 29, 0, 185, 6, 42, 67, 100, 89, 238] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [49, 198, 248, 51, 75, 241, 77, 201], stateless_reset_token: [48, 232, 160, 121, 41, 85, 203, 201, 198, 180, 95, 104, 99, 191, 207, 158] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [51, 91, 71, 81, 206, 205, 160, 52], stateless_reset_token: [83, 54, 177, 70, 14, 92, 130, 179, 50, 216, 95, 255, 46, 180, 130, 189] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [165, 254, 224, 165, 251, 131, 170, 152], stateless_reset_token: [26, 9, 1, 23, 83, 200, 21, 107, 49, 185, 160, 218, 175, 154, 246, 13] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [56, 241, 62, 65, 96, 10, 190, 210], stateless_reset_token: [25, 134, 211, 100, 95, 125, 37, 65, 4, 53, 7, 242, 109, 67, 219, 113] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [89, 100, 92, 164, 179, 21, 250, 162], stateless_reset_token: [151, 101, 234, 196, 35, 19, 190, 192, 198, 87, 71, 176, 15, 242, 211, 150] } server | -> RX Padding { len: 87 } server | 1.032 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 228, tv_nsec: 329786571 } server | 1.032 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.032 DEBUG Building Handshake dcid Some(CID [8]: bf1aac0b28ab96e7) scid Some(CID [10]: 7e9a8223dcd03d25d6c2) server | 1.032 DEBUG [Server 72a223efc8f31670] pn=1 type=Handshake pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 52 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 | 1.032 DEBUG [Server 72a223efc8f31670] Drop packet number space hs server | 1.032 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.032 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.032 DEBUG [Server 72a223efc8f31670] pn=0 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [62, 79, 159, 70, 215, 171, 100, 110, 221, 148], stateless_reset_token: [79, 88, 145, 227, 173, 10, 229, 53, 86, 246, 224, 18, 92, 13, 195, 85] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [219, 28, 163, 205, 190, 223, 25, 176, 64, 73], stateless_reset_token: [58, 27, 232, 255, 37, 217, 54, 226, 151, 50, 43, 195, 181, 255, 83, 228] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [132, 219, 70, 161, 74, 218, 122, 128, 220, 122], stateless_reset_token: [164, 5, 248, 21, 66, 72, 132, 96, 26, 135, 234, 246, 52, 125, 81, 53] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [220, 145, 102, 125, 250, 123, 148, 150, 119, 25], stateless_reset_token: [83, 90, 82, 70, 40, 71, 40, 167, 163, 120, 144, 91, 4, 159, 247, 105] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [20, 244, 187, 253, 243, 77, 252, 75, 189, 13], stateless_reset_token: [35, 221, 23, 143, 102, 157, 58, 17, 230, 248, 206, 104, 146, 203, 136, 179] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [14, 128, 216, 6, 206, 218, 146, 17, 224, 221], stateless_reset_token: [64, 33, 91, 55, 247, 235, 113, 133, 46, 205, 3, 144, 183, 195, 108, 55] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [69, 222, 64, 32, 56, 117, 81, 128, 190, 254], stateless_reset_token: [145, 214, 129, 82, 0, 53, 111, 89, 208, 232, 3, 34, 227, 20, 103, 120] } server | TX -> Padding { len: 1043 } server | 1.032 DEBUG packet_sent this=0x55e332c9ab40, pn=0, ps=1280 server | 1.032 DEBUG ECN probing: sent 2 probes server | 1.032 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.032 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.032 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.032 DEBUG [Server 72a223efc8f31670] pn=1 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 1.032 DEBUG packet_sent this=0x55e332c9ab40, pn=1, ps=1332 server | 1.032 DEBUG ECN probing: sent 3 probes server | 1.032 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG [Server 72a223efc8f31670] delay duration 1.242865ms server | 1.032 DEBUG Setting timeout of 1.242865ms server | 1.032 DEBUG [Server 72a223efc8f31670] pn=1 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect1) len 55 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 7 } server | 1.032 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 228, tv_nsec: 310562533 } server | 1.032 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.032 DEBUG [Server 72a223efc8f31670] pn=2 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 34 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) } server | 1.032 DEBUG ECN probing: sent 4 probes server | 1.032 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG [Server 72a223efc8f31670] delay duration 1.13531ms server | 1.032 DEBUG Setting timeout of 1.13531ms server | 1.033 DEBUG [Server 72a223efc8f31670] send session ticket 4063000872a223efc8f316700104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a7e9a8223dcd03d25d6c2110c000000013a1a3aea000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.033 DEBUG Path = 'kyoxytznpy' server | 1.033 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.033 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.033 DEBUG CRYPTO for ap offset=0, len=174 server | 1.033 DEBUG [Server 72a223efc8f31670] pn=3 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 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=0x55e332c9ab40, pn=3, ps=1232 server | 1.033 DEBUG ECN probing: sent 5 probes server | 1.033 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.033 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.033 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.033 DEBUG [Server 72a223efc8f31670] delay duration 1.631184ms server | 1.033 DEBUG Setting timeout of 1.631184ms server | 1.036 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.036 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.036 DEBUG CRYPTO for ap offset=174, len=131 server | 1.036 DEBUG [Server 72a223efc8f31670] pn=4 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 207 server | TX -> Crypto { offset: 174, len: 131 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 80, 65, 129, 205, 184, 104, 236, 235, 18, 22, 253, 17, 58, 237, 60, 188, 94, 26, 194, 129, 215, 35, 118, 153, 250, 114, 156, 221, 67, 148, 50, 128, 74, 209, 218, 160] } server | 1.036 DEBUG packet_sent this=0x55e332c9ab40, pn=4, ps=207 server | 1.036 DEBUG ECN probing: sent 6 probes server | 1.036 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.036 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.036 DEBUG [Server 72a223efc8f31670] delay duration 98.509419ms server | 1.036 DEBUG Setting timeout of 98.509419ms server | 1.036 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.036 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.036 DEBUG [Server 72a223efc8f31670] delay duration 98.486075ms server | 1.036 DEBUG Setting timeout of 98.486075ms client | Stream 0 ended after 1024 bytes client | All done, Closing the connection. server | 1.065 DEBUG [Server 72a223efc8f31670] pn=2 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 2, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | -> RX Padding { len: 19 } server | 1.065 DEBUG [Server 72a223efc8f31670] 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=0x55e332c9ab40, limited=1, bytes_in_flight=1439, cwnd=12320, state=SlowStart, new_acked=2612 server | 1.065 DEBUG PMTUD probe of size 1380 succeeded server | 1.065 DEBUG PMTUD started with probe size 1420 server | 1.065 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.065 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.065 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.065 DEBUG [Server 72a223efc8f31670] pn=5 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 1.065 DEBUG packet_sent this=0x55e332c9ab40, pn=5, ps=1372 server | 1.065 DEBUG ECN probing: sent 7 probes server | 1.065 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.065 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG [Server 72a223efc8f31670] delay duration 87.724883ms server | 1.065 DEBUG Setting timeout of 87.724883ms server | 1.065 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.065 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG [Server 72a223efc8f31670] delay duration 87.703012ms server | 1.065 DEBUG Setting timeout of 87.703012ms server | 1.066 DEBUG [Server 72a223efc8f31670] pn=3 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect1) len 39 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 13, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 1.066 DEBUG [Server 72a223efc8f31670] Rx ACK space=ap, ranges=[0..=3] server | 1.066 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 1.066 DEBUG on_packets_acked this=0x55e332c9ab40, limited=1, bytes_in_flight=1579, cwnd=12320, state=SlowStart, new_acked=1232 server | 1.066 DEBUG Acked crypto frame space=ap offset=0 length=174 server | 1.066 INFO [Server 72a223efc8f31670] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 1.066 DEBUG [Server 72a223efc8f31670] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 228, tv_nsec: 582987093 } } server | 1.066 DEBUG [pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667] Path validated Instant { tv_sec: 228, tv_nsec: 344171784 } server | 1.066 DEBUG [Server 72a223efc8f31670] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.066 DEBUG Building Short dcid Some(CID [8]: bf1aac0b28ab96e7) server | 1.066 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 228, tv_nsec: 344171784 } server | 1.066 DEBUG [Server 72a223efc8f31670] pn=6 type=Short pri-path:bf1aac0b28ab96e7 [::]:443->[::ffff:193.167.0.100]:37667 IpTos(Cs0, Ect0) len 38 server | TX -> Ack { largest_acknowledged: 3, 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: 29, reason_phrase: "" } server | 1.066 DEBUG ECN probing: sent 8 probes server | 1.066 DEBUG Setting timeout of 238.695054ms server | 1.066 DEBUG Setting timeout of 238.6873ms client | Received a request to close the connection. client | The connection is closed! client | Address Discovery mode: 0 / 0 (0:0) client | Quic Bit was greased by the client. client | Quic Bit was greased by the server. client | ECN was received (ect0: 6, ect1: 0, ce: 0). client | ECN was acknowledged (ect0: 0, ect1: 2, ce: 0). client | Received 1024 bytes in 0.108737 seconds, 0.075338 Mbps. client | Sent 17 bytes in 0.108737 seconds, 0.001251 Mbps. client | max_data_local: 1048576 client | max_stream_data_local: 2097152 client | max_data_remote: 4611686018427387903 client | max_stream_data_remote: 1048576 client | ack_delay_remote: 1000 ... 4069 client | max_ack_gap_remote: 2 client | ack_delay_local: 25000 ... 25000 client | max_ack_gap_local: 2 client | max_mtu_sent: 1252 client | max_mtu_received: 1372 client | System call duration max: 4 client | System call duration smoothed: 0 client | System call duration deviation: 0 client | Client exit with code = 0 client exited with code 0 Aborting on container exit... Container server Stopping Container client Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 08:42:43,968 Using the client's key log file. 2025-04-30 08:42:43,976 2025-04-30 08:42:43,977 Using the client's key log file. 2025-04-30 08:42:43,977 Using the client's key log file. 2025-04-30 08:42:43,984 2025-04-30 08:42:43,985 Using the client's key log file. 2025-04-30 08:42:43,985 Using selector: EpollSelector 2025-04-30 08:42:44,267 Check of downloaded files succeeded.