2025-04-30 09:13:11,853 Generated random file: kxgjoaliyv of size: 5120 2025-04-30 09:13:11,853 Generated random file: xprpyxbged of size: 10240 2025-04-30 09:13:11,853 Requests: https://server4:443/kxgjoaliyv https://server4:443/xprpyxbged 2025-04-30 09:13:11,913 2025-04-30 09:13:11,914 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_rwgx5djb/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_xh0eh0i3/ DOWNLOADS=/tmp/download_kxuidc29/ SERVER_LOGS=/tmp/logs_server_pmgr8fgk CLIENT_LOGS=/tmp/logs_client_o628ylq2 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=stammw/quinn-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/kxgjoaliyv https://server4:443/xprpyxbged" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 09:13:27,340 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-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 client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 client | Executing client client | wait-for-it.sh: waiting 30 seconds for sim:57832 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 resumption ']' server | + '[' -n /logs/qlog/ ']' server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.qwhRbqsrp0 server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.qwhRbqsrp0 server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.qwhRbqsrp0 -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 | 2e:4f:25:47:48:33:32:45:b2:03:0b:c8:1c:04:ab:b3: server | b0:a1:2d:c5 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 09:13:11 2025 server | Not After : Sat May 10 09:13:11 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:88:cf:19:c7:f3:41:0f:d1:a2:22:a1:94:44:dc:9a: server | 92:05:80:85:7c:24:65:da:c9:8a:93:19:6c:67:c8:2e: server | ef:2c:b8:52:a2:69:56:f1:c5:7a:ea:57:22:ea:ba:bc: server | cb:76:74:11:2d:82:38:ed:72:28:8c:3b:21:63:4e:92: server | 36 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 | 50:a8:5a:50:64:ce:89:1f:64:63:0e:65:00:1f:8b:b4: server | 7b:39:fb:bf server | server | Name: Certificate Authority Key Identifier server | Key ID: server | d3:88:60:be:8f:0a:0b:8e:81:49:f6:3e:c0:8b:69:0c: server | bd:bc:55:42 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:20:13:ea:d5:59:00:98:34:11:92:4e:77:bb: server | b8:f6:8d:ed:d5:f5:cd:35:7e:bf:b6:e1:31:f2:e3:da: server | e0:ce:25:05:02:21:00:e7:0c:ea:b3:8e:9e:bb:57:3c: server | 7e:31:df:75:eb:7e:7d:13:61:50:0c:21:ca:5e:82:ee: server | 1b:02:7e:ef:5b:53:5e server | Fingerprint (SHA-256): server | 32:52:5D:14:D2:0E:71:7B:3A:DF:27:06:DF:25:54:8B:B8:73:F8:FB:DF:EB:BC:0B:6F:44:5B:B9:56:E7:E6:D4 server | Fingerprint (SHA1): server | 45:24:61:B7:47:2A:BB:A3:14:75:AC:32:4C:D8:69:21:B8:2A:3E:72 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 | + '[' resumption = connectionmigration ']' server | + RUST_LOG=debug server | + RUST_BACKTRACE=1 server | + neqo-server --cc cubic --qns-test resumption --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.731 DEBUG [Server] Unsupported version: 57414954 server | 0.731 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46400 IpTos(Cs0, NotEct) len 15 server | 0.731 DEBUG [Server] Unsupported version: 57414954 server | 0.731 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46400 IpTos(Cs0, NotEct) len 15 sim | server:443 is available after 1.005507016s server | 0.731 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.731 DEBUG [Server] Unsupported version: 57414954 server | 0.731 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46400 IpTos(Cs0, NotEct) len 15 server | 0.731 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 server | 3.976 DEBUG [Server] Handle initial server | 3.976 INFO AddressValidation: no token; accepting server | 3.976 INFO [Server] Accept connection CID [20]: 56bcabd5daf0c87dc8061d8423b3e442d1302c6b server | 3.976 DEBUG Overwrite initial version Version1 ==> Version1 server | 3.977 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 3.977 INFO [Server ...] Received valid Initial packet with scid CID [8]: c023dca6ab4bd403 dcid CID [20]: 56bcabd5daf0c87dc8061d8423b3e442d1302c6b server | 3.977 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=56bcabd5daf0c87dc8061d8423b3e442d1302c6b server | 3.977 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 3.977 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 3.977 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] State change from Init -> WaitInitial server | 3.977 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 286 } server | -> RX Padding { len: 855 } server | 3.977 DEBUG Read Ok(286) bytes server | 3.977 DEBUG 0-RTT: no token, no 0-RTT server | 3.978 DEBUG Read secret available for Handshake: SymKey [32]: 7d5435b0c530a6bb9257a8d6af9b4cf8fa3f69bc36ae2095e1ac2a5bb5ebd840 server | 3.978 DEBUG Write secret available for Handshake: SymKey [32]: 22099149844f6c80a9e1f268bcbb56c5916fbf6c9c50185c0c7c02495469079f server | 3.978 DEBUG Writing transport parameters, msg=8 server | 3.978 DEBUG Read secret available for ApplicationData: SymKey [32]: 36a5ab4d3ab517cdcf1b9b201beec423b8ca10842d300f0a134110e2daafd97c server | 3.978 DEBUG Write secret available for ApplicationData: SymKey [32]: 9e0b49c54bdb0f8c8665ec0c511b6a8c5e7d0968809f84dc46ed38780dc46c02 server | 3.978 DEBUG [Agent 0x55a89bc281d0] state -> InProgress server | 3.978 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 3.978 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 3.978 DEBUG [Crypto] Handshake keys installed server | 3.978 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 3.978 DEBUG [Crypto] Application write key installed server | 3.978 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 722118479 } server | 3.978 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:47642] Make permanent server | 3.978 DEBUG [unv-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642] set as primary path server | 3.978 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] State change from WaitInitial -> Handshaking server | 3.978 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.978 DEBUG Building Initial dcid Some(CID [8]: c023dca6ab4bd403) scid Some(CID [10]: 549fc903d841a2caa2a5) server | 3.978 DEBUG CRYPTO for in offset=0, len=90 server | 3.978 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=0 type=Initial pri-unv-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 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 | 3.978 DEBUG Building Handshake dcid Some(CID [8]: c023dca6ab4bd403) scid Some(CID [10]: 549fc903d841a2caa2a5) server | 3.978 DEBUG CRYPTO for hs offset=0, len=736 server | 3.978 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=0 type=Handshake pri-unv-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 931 server | TX -> Crypto { offset: 0, len: 736 } server | 3.978 DEBUG packet_sent this=0x55a89bc27b40, pn=0, ps=784 server | 3.978 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 3.978 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pad Initial from 931 to PLPMTU 1232 server | 3.978 DEBUG packet_sent this=0x55a89bc27b40, pn=0, ps=448 server | 3.979 DEBUG ECN probing: sent 1 probes server | 3.979 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.979 DEBUG Building Initial dcid Some(CID [8]: c023dca6ab4bd403) scid Some(CID [10]: 549fc903d841a2caa2a5) server | 3.979 DEBUG Building Handshake dcid Some(CID [8]: c023dca6ab4bd403) scid Some(CID [10]: 549fc903d841a2caa2a5) server | 3.979 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 3.979 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.979 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 297.097653ms server | 3.979 DEBUG Setting timeout of 297.097653ms server | 3.979 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.979 DEBUG Building Initial dcid Some(CID [8]: c023dca6ab4bd403) scid Some(CID [10]: 549fc903d841a2caa2a5) server | 3.979 DEBUG Building Handshake dcid Some(CID [8]: c023dca6ab4bd403) scid Some(CID [10]: 549fc903d841a2caa2a5) server | 3.979 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 3.979 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 3.979 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 297.060504ms server | 3.979 DEBUG Setting timeout of 297.060504ms server | 4.011 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=1 type=Initial pri-unv-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 53 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 | 4.011 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Rx ACK space=in, ranges=[0..=0] server | 4.011 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 4.011 DEBUG on_packets_acked this=0x55a89bc27b40, limited=1, bytes_in_flight=784, cwnd=12320, state=SlowStart, new_acked=448 server | 4.011 DEBUG Acked crypto frame space=in offset=0 length=90 server | 4.011 DEBUG [pri-unv-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642] Path validated Instant { tv_sec: 323, tv_nsec: 757735204 } server | 4.011 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Drop packet number space in server | 4.011 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 4.011 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=0 type=Handshake pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1147 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 | -> RX Padding { len: 1056 } server | 4.011 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Rx ACK space=hs, ranges=[0..=0] server | 4.011 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 4.011 DEBUG on_packets_acked this=0x55a89bc27b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=784 server | 4.011 DEBUG Acked crypto frame space=hs offset=0 length=736 server | 4.011 DEBUG Read Ok(36) bytes server | 4.012 DEBUG [Agent 0x55a89bc281d0] 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 | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] TLS connection complete server | 4.012 DEBUG [pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642] Path validated Instant { tv_sec: 323, tv_nsec: 757735204 } server | 4.012 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 4.012 DEBUG [Crypto] application read keys installed server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] State change from Handshaking -> Connected server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] State change from Connected -> Confirmed server | 4.012 DEBUG PMTUD started with probe size 1380 server | 4.012 INFO [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Connection established server | 4.012 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 757735204 } server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.012 DEBUG Building Handshake dcid Some(CID [8]: c023dca6ab4bd403) scid Some(CID [10]: 549fc903d841a2caa2a5) server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=1 type=Handshake pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 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: 0, Ect0: 1, Ce: 0})) } server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Drop packet number space hs server | 4.012 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 4.012 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=0 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [177, 76, 195, 229, 178, 77, 176, 115, 165, 187], stateless_reset_token: [162, 44, 148, 158, 197, 149, 7, 234, 20, 38, 88, 27, 248, 94, 28, 76] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [91, 175, 68, 153, 69, 187, 188, 210, 24, 155], stateless_reset_token: [56, 19, 179, 67, 64, 223, 84, 168, 153, 219, 179, 194, 230, 72, 202, 54] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [150, 92, 162, 81, 118, 178, 180, 198, 248, 100], stateless_reset_token: [193, 80, 40, 118, 140, 44, 123, 224, 244, 122, 161, 17, 204, 35, 175, 172] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [237, 31, 87, 44, 187, 244, 99, 131, 91, 157], stateless_reset_token: [164, 35, 40, 171, 57, 211, 92, 235, 246, 88, 177, 35, 244, 69, 75, 82] } server | TX -> Padding { len: 1133 } server | 4.012 DEBUG packet_sent this=0x55a89bc27b40, pn=0, ps=1280 server | 4.012 DEBUG ECN probing: sent 2 probes server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.012 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.012 DEBUG Sending PMTUD probe of size 1380, count 1 server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=1 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 4.012 DEBUG packet_sent this=0x55a89bc27b40, pn=1, ps=1332 server | 4.012 DEBUG ECN probing: sent 3 probes server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.012 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.012 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.298057ms server | 4.012 DEBUG Setting timeout of 1.298057ms server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=0 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 224 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [170, 52, 65, 122, 255, 220, 79, 124], stateless_reset_token: [51, 149, 240, 197, 129, 40, 91, 83, 173, 110, 140, 177, 199, 109, 74, 89] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [44, 198, 120, 58, 241, 203, 4, 144], stateless_reset_token: [249, 242, 49, 165, 147, 248, 147, 90, 222, 13, 51, 33, 223, 162, 82, 3] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [175, 97, 120, 243, 133, 155, 85, 7], stateless_reset_token: [168, 240, 225, 58, 206, 170, 252, 81, 120, 71, 89, 177, 234, 154, 238, 77] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [222, 132, 84, 234, 47, 24, 237, 135], stateless_reset_token: [174, 49, 35, 97, 11, 200, 108, 128, 149, 163, 212, 36, 59, 196, 181, 235] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [151, 134, 208, 142, 127, 42, 129, 83], stateless_reset_token: [188, 188, 58, 174, 146, 115, 39, 136, 113, 106, 86, 167, 136, 231, 210, 4] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [63, 100, 222, 168, 12, 166, 1, 14], stateless_reset_token: [31, 132, 55, 163, 34, 9, 40, 88, 102, 174, 82, 18, 14, 60, 89, 8] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [137, 97, 89, 94, 76, 39, 186, 89], stateless_reset_token: [154, 46, 98, 233, 162, 78, 20, 176, 191, 131, 25, 151, 222, 169, 247, 193] } server | 4.012 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 778463593 } server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.012 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.012 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.266808ms server | 4.012 DEBUG Setting timeout of 1.266808ms server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=1 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 48 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 4.012 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 758568658 } server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.012 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=2 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 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: 0, Ect0: 2, Ce: 0})) } server | 4.012 DEBUG ECN probing: sent 4 probes server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.012 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.012 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.012 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.092225ms server | 4.012 DEBUG Setting timeout of 1.092225ms server | 4.013 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] send session ticket 406f001456bcabd5daf0c87dc8061d8423b3e442d1302c6b0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a549fc903d841a2caa2a5110c000000012a7afa4a000000016ab200c0000000ff02de1a0243e8200100686921 server | 4.013 DEBUG Path = 'kxgjoaliyv' server | 4.013 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.013 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.013 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.013 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 70.751µs server | 4.013 DEBUG Setting timeout of 70.751µs server | 4.014 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.014 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.014 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=3 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 4.014 DEBUG packet_sent this=0x55a89bc27b40, pn=3, ps=1232 server | 4.014 DEBUG ECN probing: sent 5 probes server | 4.014 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.014 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.014 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=4 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 4.014 DEBUG packet_sent this=0x55a89bc27b40, pn=4, ps=1232 server | 4.014 DEBUG ECN probing: sent 6 probes server | 4.014 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.014 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.014 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.015 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.718821ms server | 4.015 DEBUG Setting timeout of 1.718821ms server | 4.015 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.015 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.015 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.015 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.699254ms server | 4.015 DEBUG Setting timeout of 1.699254ms server | 4.017 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.017 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.017 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=5 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 4.017 DEBUG packet_sent this=0x55a89bc27b40, pn=5, ps=1232 server | 4.017 DEBUG ECN probing: sent 7 probes server | 4.017 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.017 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.017 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.017 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.310883ms server | 4.017 DEBUG Setting timeout of 1.310883ms server | 4.017 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.017 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.017 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.017 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.28237ms server | 4.017 DEBUG Setting timeout of 1.28237ms server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.019 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=6 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false } server | 4.019 DEBUG packet_sent this=0x55a89bc27b40, pn=6, ps=1232 server | 4.019 DEBUG ECN probing: sent 8 probes server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.019 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.019 DEBUG CRYPTO for ap offset=0, len=321 server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=7 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 712 server | TX -> Stream { stream_id: 0, offset: 4810, len: 310, fin: true } server | TX -> Crypto { offset: 0, len: 321 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 176, 54, 140, 42, 55, 253, 113, 99, 16, 134, 51, 157, 127, 221, 2, 34, 92, 150, 185, 84, 161, 108, 108, 36, 170, 60, 15, 220, 47, 169, 90, 7, 173, 245, 147, 44] } server | 4.019 DEBUG packet_sent this=0x55a89bc27b40, pn=7, ps=712 server | 4.019 DEBUG ECN probing: sent 9 probes server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.019 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.019 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.67556ms server | 4.019 DEBUG Setting timeout of 1.67556ms server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.019 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.019 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.019 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 1.650843ms server | 4.019 DEBUG Setting timeout of 1.650843ms server | 4.022 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.022 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.022 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.022 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 110.825035ms server | 4.022 DEBUG Setting timeout of 110.825035ms server | 4.022 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.022 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.022 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.022 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 110.794017ms server | 4.022 DEBUG Setting timeout of 110.794017ms server | 4.044 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=2 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 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 | 4.044 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Rx ACK space=ap, ranges=[0..=1] server | 4.044 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 4.044 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5640/14932 ssthresh 18446744073709551615] slow start += 2612 server | 4.044 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5640/14932 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bc27b40, limited=0, bytes_in_flight=5640, cwnd=14932, state=SlowStart, new_acked=2612 server | 4.044 DEBUG PMTUD probe of size 1380 succeeded server | 4.044 DEBUG PMTUD started with probe size 1420 server | 4.045 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 4.045 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.045 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.045 DEBUG Sending PMTUD probe of size 1420, count 1 server | 4.045 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=8 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 4.045 DEBUG packet_sent this=0x55a89bc27b40, pn=8, ps=1372 server | 4.045 DEBUG ECN probing: sent 10 probes server | 4.045 DEBUG ECN probing concluded with 10 probes sent server | 4.045 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.045 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.045 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.045 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 103.161394ms server | 4.045 DEBUG Setting timeout of 103.161394ms server | 4.045 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.045 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.045 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.045 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 103.125868ms server | 4.045 DEBUG Setting timeout of 103.125868ms server | 4.047 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=3 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 1, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | 4.047 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Rx ACK space=ap, ranges=[0..=4] server | 4.047 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 4.047 INFO ECN validation succeeded, path is capable server | 4.047 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4548/17396 ssthresh 18446744073709551615] slow start += 2464 server | 4.047 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4548/17396 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bc27b40, limited=0, bytes_in_flight=4548, cwnd=17396, state=SlowStart, new_acked=2464 server | 4.047 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.047 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.047 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.047 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 92.758466ms server | 4.047 DEBUG Setting timeout of 92.758466ms server | 4.047 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.047 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.047 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.047 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 92.692032ms server | 4.047 DEBUG Setting timeout of 92.692032ms server | 4.050 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=4 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 2, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } server | 4.050 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Rx ACK space=ap, ranges=[0..=6] server | 4.050 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6 server | 4.050 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2084/19860 ssthresh 18446744073709551615] slow start += 2464 server | 4.050 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2084/19860 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bc27b40, limited=0, bytes_in_flight=2084, cwnd=19860, state=SlowStart, new_acked=2464 server | 4.050 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.050 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.050 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.050 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 83.485997ms server | 4.050 DEBUG Setting timeout of 83.485997ms server | 4.050 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.050 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.050 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.050 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] delay duration 83.413903ms server | 4.050 DEBUG Setting timeout of 83.413903ms server | 4.051 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=6 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 14, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 4.051 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] Rx ACK space=ap, ranges=[0..=7] server | 4.051 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7 server | 4.051 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20572 ssthresh 18446744073709551615] slow start += 712 server | 4.051 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20572 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bc27b40, limited=0, bytes_in_flight=1372, cwnd=20572, state=SlowStart, new_acked=712 server | 4.051 DEBUG Acked crypto frame space=ap offset=0 length=321 server | 4.051 INFO [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 4.051 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 324, tv_nsec: 49438330 } } server | 4.051 DEBUG [pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642] Path validated Instant { tv_sec: 323, tv_nsec: 797402588 } server | 4.051 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.051 DEBUG Building Short dcid Some(CID [8]: c023dca6ab4bd403) server | 4.051 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 323, tv_nsec: 797402588 } server | 4.051 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] pn=9 type=Short pri-path:c023dca6ab4bd403 [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 40 server | TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 4 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 4.051 DEBUG Setting timeout of 251.914366ms server | 4.051 DEBUG Setting timeout of 251.904267ms server | 4.052 DEBUG [Server] Handle initial server | 4.052 INFO AddressValidation: no token; accepting server | 4.052 INFO [Server] Accept connection CID [20]: 8a251f9113a1daab11ee6231ba6abf989b640e08 server | 4.052 DEBUG Overwrite initial version Version1 ==> Version1 server | 4.053 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 4.053 INFO [Server ...] Received valid Initial packet with scid CID [8]: 39dcc9c38a2b43dc dcid CID [20]: 8a251f9113a1daab11ee6231ba6abf989b640e08 server | 4.053 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=8a251f9113a1daab11ee6231ba6abf989b640e08 server | 4.053 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 4.053 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 4.053 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] State change from Init -> WaitInitial server | 4.053 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1200 server | -> RX Crypto { offset: 0, len: 623 } server | -> RX Padding { len: 518 } server | 4.053 DEBUG Read Ok(623) bytes server | 4.053 INFO 0-RTT: transport parameters OK, passing to application checker server | 4.053 WARN AllowZeroRtt accepting 0-RTT server | 4.054 DEBUG Read secret available for ZeroRtt: SymKey [32]: 1912a042c797525106478e9436174a3d788eb709bbffb2416c86cda66e5f9701 server | 4.054 DEBUG Read secret available for Handshake: SymKey [32]: e9404c9963102e50619e780092acca289bff9850ccdef0e44cf5c0b51bc7fcfb server | 4.054 DEBUG Write secret available for Handshake: SymKey [32]: 71712c80403c624444fd00678d0b996c04af6e36cdf195156d86e9116708e4ee server | 4.054 DEBUG Writing transport parameters, msg=8 server | 4.054 DEBUG Read secret available for ApplicationData: SymKey [32]: e8ee5ec547977ba2c056023716b6d4886824654dd853198c10a49fffa54d6f21 server | 4.054 DEBUG Write secret available for ApplicationData: SymKey [32]: 2156d87bcb1626f3f05bf868f68f0b562b6b94453199bfb22dafb7f8dda2a4f3 server | 4.054 DEBUG [Agent 0x55a89bc53ab0] state -> InProgress server | 4.054 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 4.054 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 4.054 DEBUG [Crypto] Handshake keys installed server | 4.054 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 4.054 DEBUG [Crypto] Application write key installed server | 4.054 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 798557350 } server | 4.054 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:47642] Make permanent server | 4.054 DEBUG [unv-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642] set as primary path server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] State change from WaitInitial -> Handshaking server | 4.054 DEBUG Making Read ZeroRtt CryptoDxState, v=Version1 cipher=4865 server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Accepted 0-RTT server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.054 DEBUG Building Initial dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.054 DEBUG CRYPTO for in offset=0, len=96 server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=0 type=Initial pri-unv-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 153 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: 96 } server | 4.054 DEBUG Building Handshake dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.054 DEBUG CRYPTO for hs offset=0, len=190 server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=0 type=Handshake pri-unv-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 391 server | TX -> Crypto { offset: 0, len: 190 } server | 4.054 DEBUG packet_sent this=0x55a89bb65590, pn=0, ps=238 server | 4.054 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pad Initial from 391 to PLPMTU 1232 server | 4.054 DEBUG packet_sent this=0x55a89bb65590, pn=0, ps=994 server | 4.054 DEBUG ECN probing: sent 1 probes server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.054 DEBUG Building Initial dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.054 DEBUG Building Handshake dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.054 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.054 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 297.78335ms server | 4.054 DEBUG Setting timeout of 248.66433ms server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.054 DEBUG Building Initial dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.054 DEBUG Building Handshake dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.054 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.054 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.054 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 297.726366ms server | 4.054 DEBUG Setting timeout of 248.607346ms server | 4.076 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 324, tv_nsec: 49438330 } }; Total: 1 server | 4.076 DEBUG Setting timeout of 226.848578ms server | 4.076 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.076 DEBUG Building Initial dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.076 DEBUG Building Handshake dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.076 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.076 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.076 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 275.95276ms server | 4.076 DEBUG Setting timeout of 226.83374ms server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=1 type=Initial pri-unv-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 53 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 29, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=in, ranges=[0..=0] server | 4.087 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 4.087 DEBUG on_packets_acked this=0x55a89bb65590, limited=1, bytes_in_flight=238, cwnd=12320, state=SlowStart, new_acked=994 server | 4.087 DEBUG Acked crypto frame space=in offset=0 length=96 server | 4.087 DEBUG [pri-unv-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642] Path validated Instant { tv_sec: 323, tv_nsec: 833243855 } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Drop packet number space in server | 4.087 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=0 type=Handshake pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1147 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 29, 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 | -> RX Padding { len: 1056 } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=hs, ranges=[0..=0] server | 4.087 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 4.087 DEBUG on_packets_acked this=0x55a89bb65590, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=238 server | 4.087 DEBUG Acked crypto frame space=hs offset=0 length=190 server | 4.087 DEBUG Read Ok(36) bytes server | 4.087 DEBUG [Agent 0x55a89bc53ab0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: true, early_data: true, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] TLS connection complete server | 4.087 DEBUG [pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642] Path validated Instant { tv_sec: 323, tv_nsec: 833243855 } server | 4.087 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 4.087 DEBUG [Crypto] application read keys installed server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] State change from Handshaking -> Connected server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] State change from Connected -> Confirmed server | 4.087 DEBUG PMTUD started with probe size 1380 server | 4.087 INFO [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Connection established server | 4.087 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 833243855 } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.087 DEBUG Building Handshake dcid Some(CID [8]: 39dcc9c38a2b43dc) scid Some(CID [10]: 896bca34d7eedc66b9c8) server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=1 type=Handshake pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 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: 0, Ect0: 1, Ce: 0})) } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Drop packet number space hs server | 4.087 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 4.087 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=0 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [171, 137, 222, 155, 53, 15, 159, 176, 199, 221], stateless_reset_token: [77, 169, 192, 181, 163, 56, 223, 206, 172, 185, 54, 88, 91, 90, 66, 123] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [248, 73, 197, 83, 164, 233, 15, 139, 146, 241], stateless_reset_token: [250, 177, 151, 89, 181, 46, 83, 197, 137, 142, 59, 59, 110, 37, 210, 123] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [117, 72, 37, 69, 85, 81, 51, 192, 31, 86], stateless_reset_token: [195, 93, 211, 227, 28, 55, 17, 191, 163, 236, 111, 159, 146, 241, 55, 215] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [189, 113, 132, 173, 109, 193, 113, 226, 218, 255], stateless_reset_token: [10, 27, 253, 247, 223, 52, 211, 63, 124, 112, 122, 205, 10, 228, 4, 97] } server | TX -> Padding { len: 1133 } server | 4.087 DEBUG packet_sent this=0x55a89bb65590, pn=0, ps=1280 server | 4.087 DEBUG ECN probing: sent 2 probes server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.087 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.087 DEBUG Sending PMTUD probe of size 1380, count 1 server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=1 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 4.087 DEBUG packet_sent this=0x55a89bb65590, pn=1, ps=1332 server | 4.087 DEBUG ECN probing: sent 3 probes server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.087 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.087 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.392833ms server | 4.087 DEBUG Setting timeout of 1.392833ms server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=0 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 224 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [162, 192, 41, 233, 59, 86, 221, 186], stateless_reset_token: [203, 112, 95, 28, 49, 202, 65, 209, 133, 135, 66, 115, 137, 253, 77, 235] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [235, 10, 16, 93, 10, 127, 96, 132], stateless_reset_token: [99, 118, 233, 243, 71, 159, 153, 168, 81, 32, 123, 209, 112, 204, 196, 58] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [223, 29, 107, 224, 148, 181, 84, 154], stateless_reset_token: [231, 121, 39, 200, 219, 59, 177, 238, 81, 7, 120, 79, 40, 203, 148, 252] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [194, 253, 107, 105, 31, 225, 233, 169], stateless_reset_token: [16, 44, 0, 164, 242, 139, 60, 239, 95, 169, 91, 237, 36, 23, 185, 229] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [199, 12, 55, 95, 210, 36, 99, 118], stateless_reset_token: [130, 193, 151, 215, 48, 95, 15, 100, 88, 206, 163, 153, 142, 130, 229, 112] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [161, 73, 239, 95, 255, 69, 91, 123], stateless_reset_token: [47, 218, 43, 22, 57, 64, 139, 155, 169, 80, 160, 4, 42, 52, 249, 71] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [50, 131, 50, 253, 119, 62, 61, 190], stateless_reset_token: [142, 174, 183, 209, 226, 216, 84, 75, 13, 54, 102, 7, 214, 166, 31, 185] } server | 4.087 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 853823425 } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.087 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.087 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.363138ms server | 4.087 DEBUG Setting timeout of 1.363138ms server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=1 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 68 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Stream { stream_id: 4, offset: 0, len: 17, fin: false } server | 4.087 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 833903905 } server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.087 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.087 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=2 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 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: 0, Ect0: 2, Ce: 0})) } server | 4.087 DEBUG ECN probing: sent 4 probes server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.088 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.088 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.261553ms server | 4.088 DEBUG Setting timeout of 1.261553ms server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=2 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 32 server | -> RX Stream { stream_id: 4, offset: 17, len: 0, fin: true } server | 4.088 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 854018779 } server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.088 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.088 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.21684ms server | 4.088 DEBUG Setting timeout of 1.21684ms server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] send session ticket 406f00148a251f9113a1daab11ee6231ba6abf989b640e080104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a896bca34d7eedc66b9c8110c00000001da5aca3a000000016ab200c0000000ff02de1a0243e8200100686921 server | 4.088 DEBUG Path = 'kxgjoaliyv' server | 4.088 DEBUG Path = 'xprpyxbged' server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.088 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.088 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.088 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 990.979µs server | 4.088 DEBUG Setting timeout of 990.979µs server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.090 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=3 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 4.090 DEBUG packet_sent this=0x55a89bb65590, pn=3, ps=1232 server | 4.090 DEBUG ECN probing: sent 5 probes server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.090 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=4 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 4.090 DEBUG packet_sent this=0x55a89bb65590, pn=4, ps=1232 server | 4.090 DEBUG ECN probing: sent 6 probes server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.090 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.090 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.669735ms server | 4.090 DEBUG Setting timeout of 1.669735ms server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.090 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.090 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.090 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.650659ms server | 4.090 DEBUG Setting timeout of 1.650659ms server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.093 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=5 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 4.093 DEBUG packet_sent this=0x55a89bb65590, pn=5, ps=1232 server | 4.093 DEBUG ECN probing: sent 7 probes server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.093 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=6 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false } server | 4.093 DEBUG packet_sent this=0x55a89bb65590, pn=6, ps=1232 server | 4.093 DEBUG ECN probing: sent 8 probes server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.093 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.093 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.64066ms server | 4.093 DEBUG Setting timeout of 1.64066ms server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.093 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.093 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.093 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.618008ms server | 4.093 DEBUG Setting timeout of 1.618008ms server | 4.096 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.096 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.096 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=7 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 4810, len: 310, fin: true } server | TX -> Stream { stream_id: 4, offset: 0, len: >>888, fin: false } server | 4.096 DEBUG packet_sent this=0x55a89bb65590, pn=7, ps=1232 server | 4.096 DEBUG ECN probing: sent 9 probes server | 4.096 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.096 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.096 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=8 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 4, offset: 888, len: >>1202, fin: false } server | 4.096 DEBUG packet_sent this=0x55a89bb65590, pn=8, ps=1232 server | 4.096 DEBUG ECN probing: sent 10 probes server | 4.096 DEBUG ECN probing concluded with 10 probes sent server | 4.097 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.097 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.097 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.097 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.668362ms server | 4.097 DEBUG Setting timeout of 1.668362ms server | 4.097 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.097 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.097 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 4.097 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 1.650359ms server | 4.097 DEBUG Setting timeout of 1.650359ms server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 4.099 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=9 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, NotEct) len 1232 server | TX -> Stream { stream_id: 4, offset: 2090, len: >>1202, fin: false } server | 4.099 DEBUG packet_sent this=0x55a89bb65590, pn=9, ps=1232 server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1084, pto: None, probe: EnumSet(), paced: false } server | 4.099 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=10 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, NotEct) len 1084 server | TX -> Stream { stream_id: 4, offset: 3292, len: >>1054, fin: false } server | 4.099 DEBUG packet_sent this=0x55a89bb65590, pn=10, ps=1084 server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.099 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.099 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 8.809913ms server | 4.099 DEBUG Setting timeout of 8.809913ms server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.099 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.099 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.099 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 8.789545ms server | 4.099 DEBUG Setting timeout of 8.789545ms server | 4.109 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.109 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.109 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=11 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, NotEct) len 35 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 2661, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | 4.109 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.109 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.109 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.109 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 64.580509ms server | 4.109 DEBUG Setting timeout of 64.580509ms server | 4.109 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.109 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.109 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.109 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 64.557947ms server | 4.109 DEBUG Setting timeout of 64.557947ms server | 4.120 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=3 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | 4.120 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=2] server | 4.120 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 4.120 INFO ECN validation succeeded, path is capable server | 4.120 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] slow start += 2612 server | 4.120 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=9708, cwnd=14932, state=SlowStart, new_acked=2612 server | 4.120 DEBUG PMTUD probe of size 1380 succeeded server | 4.120 DEBUG PMTUD started with probe size 1420 server | 4.120 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 4.120 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.120 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.120 DEBUG Sending PMTUD probe of size 1420, count 1 server | 4.120 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=12 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Stream { stream_id: 4, offset: 4346, len: >>1341, fin: false } server | 4.120 DEBUG packet_sent this=0x55a89bb65590, pn=12, ps=1372 server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.121 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=13 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1332 server | TX -> Stream { stream_id: 4, offset: 5687, len: >>1302, fin: false } server | 4.121 DEBUG packet_sent this=0x55a89bb65590, pn=13, ps=1332 server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.121 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=14 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1332 server | TX -> Stream { stream_id: 4, offset: 6989, len: >>1302, fin: false } server | 4.121 DEBUG packet_sent this=0x55a89bb65590, pn=14, ps=1332 server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1188, pto: None, probe: EnumSet(), paced: false } server | 4.121 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=15 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1188 server | TX -> Stream { stream_id: 4, offset: 8291, len: >>1158, fin: false } server | 4.121 DEBUG packet_sent this=0x55a89bb65590, pn=15, ps=1188 server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.121 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.121 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 52.703188ms server | 4.121 DEBUG Setting timeout of 52.703188ms server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.121 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.121 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 4.121 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 52.669796ms server | 4.121 DEBUG Setting timeout of 52.669796ms server | 4.122 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=4 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 1, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | 4.122 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=4] server | 4.122 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 4.122 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 12468/17396 ssthresh 18446744073709551615] slow start += 2464 server | 4.122 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 12468/17396 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=12468, cwnd=17396, state=SlowStart, new_acked=2464 server | 4.122 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.122 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.122 DEBUG CRYPTO for ap offset=0, len=321 server | 4.122 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=16 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 1193 server | TX -> Stream { stream_id: 4, offset: 9449, len: 791, fin: true } server | TX -> Crypto { offset: 0, len: 321 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 218, 65, 73, 197, 151, 139, 215, 155, 185, 201, 116, 107, 97, 255, 67, 165, 98, 139, 240, 252, 11, 61, 6, 220, 47, 62, 179, 60, 19, 83, 196, 242, 58, 107, 184, 213] } server | 4.122 DEBUG packet_sent this=0x55a89bb65590, pn=16, ps=1193 server | 4.123 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.123 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.123 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.123 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 50.988976ms server | 4.123 DEBUG Setting timeout of 50.988976ms server | 4.123 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.123 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.123 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.123 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 50.961805ms server | 4.123 DEBUG Setting timeout of 50.961805ms server | 4.126 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=5 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 1, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } server | 4.126 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=6] server | 4.126 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6 server | 4.126 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11197/19860 ssthresh 18446744073709551615] slow start += 2464 server | 4.126 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11197/19860 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=11197, cwnd=19860, state=SlowStart, new_acked=2464 server | 4.126 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.126 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.126 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.126 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 48.006894ms server | 4.126 DEBUG Setting timeout of 48.006894ms server | 4.126 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.126 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.126 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.126 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 47.927435ms server | 4.126 DEBUG Setting timeout of 47.927435ms server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=6 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 1, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=8] server | 4.129 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8 server | 4.129 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8733/22324 ssthresh 18446744073709551615] slow start += 2464 server | 4.129 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8733/22324 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=8733, cwnd=22324, state=SlowStart, new_acked=2464 server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.129 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=17 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 28 server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | 4.129 DEBUG packet_sent this=0x55a89bb65590, pn=17, ps=29 server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.129 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.129 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 44.598773ms server | 4.129 DEBUG Setting timeout of 44.598773ms server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.129 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.129 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.129 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 44.568817ms server | 4.129 DEBUG Setting timeout of 44.568817ms server | 4.131 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=7 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 2, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | 4.131 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=10] server | 4.131 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=10 server | 4.131 WARN ECN validation failed, no ECT(0) packets were newly acked server | 4.131 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6446/24640 ssthresh 18446744073709551615] slow start += 2316 server | 4.131 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6446/24640 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=6446, cwnd=24640, state=SlowStart, new_acked=2316 server | 4.131 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.131 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.131 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.131 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 42.49339ms server | 4.131 DEBUG Setting timeout of 42.49339ms server | 4.131 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.131 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.131 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 4.131 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 42.441483ms server | 4.131 DEBUG Setting timeout of 42.441483ms server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=8 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 13, ack_delay: 1, first_ack_range: 13, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) } server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=13] server | 4.153 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=13 server | 4.153 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3742/27344 ssthresh 18446744073709551615] slow start += 2704 server | 4.153 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3742/27344 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=3742, cwnd=27344, state=SlowStart, new_acked=2704 server | 4.153 DEBUG PMTUD probe of size 1420 succeeded server | 4.153 DEBUG PMTUD started with probe size 1470 server | 4.153 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.153 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.153 DEBUG Sending PMTUD probe of size 1470, count 1 server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=18 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, NotEct) len 1422 server | TX -> Ping server | TX -> Padding { len: 1395 } server | 4.153 DEBUG packet_sent this=0x55a89bb65590, pn=18, ps=1422 server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.153 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.153 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 20.185707ms server | 4.153 DEBUG Setting timeout of 20.185707ms server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.153 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.153 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.153 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 20.125435ms server | 4.153 DEBUG Setting timeout of 20.125435ms server | 4.155 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=9 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 36 server | -> RX Ack { largest_acknowledged: 15, ack_delay: 1, first_ack_range: 15, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 13, Ce: 0})) } server | 4.155 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=15] server | 4.155 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=15 server | 4.155 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2644/29864 ssthresh 18446744073709551615] slow start += 2520 server | 4.155 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2644/29864 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=2644, cwnd=29864, state=SlowStart, new_acked=2520 server | 4.155 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.155 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.155 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.155 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 18.333245ms server | 4.155 DEBUG Setting timeout of 18.333245ms server | 4.155 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.155 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.155 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.155 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] delay duration 18.272652ms server | 4.155 DEBUG Setting timeout of 18.272652ms server | 4.156 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=10 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, Ect0) len 39 server | -> RX Ack { largest_acknowledged: 16, ack_delay: 16, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 14, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 4.156 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] Rx ACK space=ap, ranges=[0..=16] server | 4.156 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=16 server | 4.156 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1451/31057 ssthresh 18446744073709551615] slow start += 1193 server | 4.156 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1451/31057 ssthresh 18446744073709551615] on_packets_acked this=0x55a89bb65590, limited=0, bytes_in_flight=1451, cwnd=31057, state=SlowStart, new_acked=1193 server | 4.156 DEBUG Acked crypto frame space=ap offset=0 length=321 server | 4.156 INFO [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 4.156 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 324, tv_nsec: 105091366 } } server | 4.156 DEBUG [pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642] Path validated Instant { tv_sec: 323, tv_nsec: 902727962 } server | 4.156 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 4.156 DEBUG Building Short dcid Some(CID [8]: 39dcc9c38a2b43dc) server | 4.156 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 323, tv_nsec: 902727962 } server | 4.156 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] pn=19 type=Short pri-path:39dcc9c38a2b43dc [::]:443->[::ffff:193.167.0.100]:47642 IpTos(Cs0, NotEct) len 38 server | TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 4.156 DEBUG Setting timeout of 146.565949ms server | 4.156 DEBUG Setting timeout of 146.552844ms server | 4.159 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 324, tv_nsec: 105091366 } }; Total: 1 server | 4.159 DEBUG Setting timeout of 199.310295ms server | 4.159 DEBUG Setting timeout of 143.639215ms server | 4.185 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 324, tv_nsec: 105091366 } }; Total: 2 server | 4.185 DEBUG Setting timeout of 173.700856ms server | 4.185 DEBUG Setting timeout of 118.024397ms server | 4.304 DEBUG [Server 56bcabd5daf0c87dc8061d8423b3e442d1302c6b] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 324, tv_nsec: 49438330 } } -> Closed(Transport(PeerApplicationError(0))) server | 4.304 INFO Closing timer expired server | 4.304 DEBUG Setting timeout of 54.405473ms server | 4.304 DEBUG Setting timeout of 54.19466ms server | 4.360 DEBUG [Server 8a251f9113a1daab11ee6231ba6abf989b640e08] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 324, tv_nsec: 105091366 } } -> Closed(Transport(PeerApplicationError(0))) server | 4.360 INFO Closing timer expired 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 09:13:27,433 Using the client's key log file. 2025-04-30 09:13:27,442 2025-04-30 09:13:27,442 Using the client's key log file. 2025-04-30 09:13:27,443 Using the client's key log file. 2025-04-30 09:13:27,450 2025-04-30 09:13:27,451 Using the client's key log file. 2025-04-30 09:13:27,451 Using the client's key log file. 2025-04-30 09:13:28,318 Check of downloaded files succeeded.