2025-04-30 10:28:29,761 Generated random file: qmonxrgbtk of size: 5120 2025-04-30 10:28:29,761 Generated random file: njtkmaeatn of size: 10240 2025-04-30 10:28:29,761 Requests: https://server4:443/qmonxrgbtk https://server4:443/njtkmaeatn 2025-04-30 10:28:29,819 2025-04-30 10:28:29,819 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_rpxbr10x/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_oo996v17/ DOWNLOADS=/tmp/download_dh77v07v/ SERVER_LOGS=/tmp/logs_server_j75x0caq CLIENT_LOGS=/tmp/logs_client_yd8vxpk3 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=cloudflare/quiche-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/qmonxrgbtk https://server4:443/njtkmaeatn" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 10:28:42,438 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 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.TkRudwuLMR server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password 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 server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.TkRudwuLMR client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Warning: output encryption option -nodes ignored with -export client | supported server | + pk12util -d sql:/neqo/db -i /tmp/tmp.TkRudwuLMR -W '' client | wait-for-it: waiting 30 seconds for sim:57832 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 | 3d:b9:60:72:69:be:3f:09:ed:a4:f8:c6:3d:65:04:3a: server | d4:37:06:42 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 10:28:29 2025 server | Not After : Sat May 10 10:28:29 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:7b:99:9a:db:3f:69:c6:81:6d:b2:39:9c:41:b7:fc: server | 3a:cc:a9:5e:4a:c1:9c:03:83:e2:64:87:3f:03:1b:1a: server | ce:b0:b1:0f:80:4c:c0:dc:b1:34:b1:cc:c0:a2:00:81: server | 7e:49:b0:2b:18:b7:02:14:1a:45:b1:e8:2f:b4:11:0f: server | 8f 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 | a3:0c:cb:a8:33:d9:fd:35:55:47:06:fe:50:76:cd:cb: server | 31:27:fe:2f server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 1f:93:c5:07:42:e3:3d:a2:57:73:f5:d1:fa:0c:2b:1d: server | 8f:37:b2:eb server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:20:60:56:31:0c:20:05:47:f0:08:1a:18:bb: server | ec:3a:0a:fd:9a:2f:67:4a:46:0a:8a:74:d9:7c:7f:b6: server | 59:a7:0a:6a:02:21:00:8e:1d:64:96:31:1b:61:1d:ab: server | 98:20:22:82:23:7d:2a:f8:72:86:7b:08:1a:43:3c:30: server | 61:6c:b6:88:46:b1:d3 server | Fingerprint (SHA-256): server | 57:D1:7C:E7:56:51:49:82:45:3C:C7:EC:9E:4D:04:AA:FD:65:5F:B7:3B:AE:BA:42:BD:18:3A:81:83:F0:6E:71 server | Fingerprint (SHA1): server | 0F:7A:11:C4:5F:2B:39:A5:95:91:33:86:6A:4D:B9:BC:F5:10:DB:FA 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.805 DEBUG [Server] Unsupported version: 57414954 server | 0.805 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:60672 IpTos(Cs0, NotEct) len 15 sim | server:443 is available after 1.046139718s server | 0.806 DEBUG [Server] Unsupported version: 57414954 server | 0.806 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:60672 IpTos(Cs0, NotEct) len 15 server | 0.806 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.806 DEBUG [Server] Unsupported version: 57414954 server | 0.806 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:60672 IpTos(Cs0, NotEct) len 15 server | 0.806 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it: sim:57832 is available after 1 seconds client | ## Starting quiche client... client | ## Client params: client | ## Requests: https://server4:443/qmonxrgbtk https://server4:443/njtkmaeatn client | ## Test case: resumption server | 1.018 DEBUG [Server] Handle initial server | 1.018 INFO AddressValidation: no token; accepting server | 1.018 INFO [Server] Accept connection CID [16]: 5ac6ca5098233046a07fe85b7635fd9f server | 1.018 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.019 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.019 INFO [Server ...] Received valid Initial packet with scid CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f dcid CID [16]: 5ac6ca5098233046a07fe85b7635fd9f server | 1.019 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=5ac6ca5098233046a07fe85b7635fd9f server | 1.019 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.019 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.019 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] State change from Init -> WaitInitial server | 1.019 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 333 server | -> RX Crypto { offset: 0, len: 266 } server | 1.019 DEBUG Read Ok(266) bytes server | 1.020 DEBUG 0-RTT: no token, no 0-RTT server | 1.020 DEBUG Read secret available for Handshake: SymKey [32]: 67ec75c422d34826096014ef3ef43496c2c4e739e6a4db55c936e8b325897a71 server | 1.020 DEBUG Write secret available for Handshake: SymKey [32]: bff4cf7eb1790bbb614bc933e506d4ffc46d9b3b32d17dd869762a22efcb99f9 server | 1.020 DEBUG Writing transport parameters, msg=8 server | 1.020 DEBUG Read secret available for ApplicationData: SymKey [32]: 47ce38fee1a03a89963564eccba323444ee9790de9f4778e37165f732c0bd206 server | 1.020 DEBUG Write secret available for ApplicationData: SymKey [32]: 7c6c09cfd20fbeb3f30744555528f596b7ec7e06691545d678b670f051036e53 server | 1.020 DEBUG [Agent 0x55f07f2ab1d0] state -> InProgress server | 1.020 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.020 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.020 DEBUG [Crypto] Handshake keys installed server | 1.020 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.020 DEBUG [Crypto] Application write key installed server | 1.020 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 343, tv_nsec: 544656234 } server | 1.020 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:44718] Make permanent server | 1.020 DEBUG [unv-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718] set as primary path server | 1.020 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] State change from WaitInitial -> Handshaking server | 1.020 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1 server | 1.020 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.020 DEBUG Building Initial dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) scid Some(CID [10]: bd1ace0cd3e87ca1f2fc) server | 1.020 DEBUG CRYPTO for in offset=0, len=90 server | 1.021 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=0 type=Initial pri-unv-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 156 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 90 } server | 1.021 DEBUG Building Handshake dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) scid Some(CID [10]: bd1ace0cd3e87ca1f2fc) server | 1.021 DEBUG CRYPTO for hs offset=0, len=731 server | 1.021 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=0 type=Handshake pri-unv-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 947 server | TX -> Crypto { offset: 0, len: 731 } server | 1.021 DEBUG packet_sent this=0x55f07f2aab40, pn=0, ps=791 server | 1.021 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.021 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pad Initial from 947 to PLPMTU 1232 server | 1.021 DEBUG packet_sent this=0x55f07f2aab40, pn=0, ps=441 server | 1.021 DEBUG ECN probing: sent 1 probes server | 1.021 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.021 DEBUG Building Initial dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) scid Some(CID [10]: bd1ace0cd3e87ca1f2fc) server | 1.021 DEBUG Building Handshake dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) scid Some(CID [10]: bd1ace0cd3e87ca1f2fc) server | 1.021 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.021 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.021 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 297.120406ms server | 1.021 DEBUG Setting timeout of 297.120406ms server | 1.021 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.021 DEBUG Building Initial dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) scid Some(CID [10]: bd1ace0cd3e87ca1f2fc) server | 1.021 DEBUG Building Handshake dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) scid Some(CID [10]: bd1ace0cd3e87ca1f2fc) server | 1.021 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.021 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.021 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 297.084409ms server | 1.021 DEBUG Setting timeout of 297.084409ms server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=1 type=Initial pri-unv-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 63 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 69, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=in, ranges=[0..=0] server | 1.054 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 1.054 DEBUG on_packets_acked this=0x55f07f2aab40, limited=1, bytes_in_flight=791, cwnd=12320, state=SlowStart, new_acked=441 server | 1.054 DEBUG Acked crypto frame space=in offset=0 length=90 server | 1.054 DEBUG [pri-unv-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718] Path validated Instant { tv_sec: 343, tv_nsec: 580751222 } server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Drop packet number space in server | 1.054 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=2 type=Handshake pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 101 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 52, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=hs, ranges=[0..=0] server | 1.054 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.054 DEBUG on_packets_acked this=0x55f07f2aab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=791 server | 1.054 DEBUG Acked crypto frame space=hs offset=0 length=731 server | 1.054 DEBUG Read Ok(36) bytes server | 1.054 DEBUG [Agent 0x55f07f2ab1d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] TLS connection complete server | 1.054 DEBUG [pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718] Path validated Instant { tv_sec: 343, tv_nsec: 580751222 } server | 1.054 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.054 DEBUG [Crypto] application read keys installed server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] State change from Handshaking -> Connected server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] State change from Connected -> Confirmed server | 1.054 DEBUG PMTUD started with probe size 1380 server | 1.054 INFO [Server 5ac6ca5098233046a07fe85b7635fd9f] Connection established server | 1.054 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 343, tv_nsec: 580751222 } server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=3 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 1186 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [143, 202, 77, 115, 214, 116, 29, 226, 123, 132, 241, 122, 59, 122, 78, 166, 202, 186, 40, 127], stateless_reset_token: [58, 93, 102, 138, 7, 9, 184, 108, 16, 174, 240, 77, 3, 250, 150, 181] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [172, 12, 208, 190, 134, 142, 17, 91, 214, 133, 42, 222, 245, 170, 195, 169, 70, 103, 14, 154], stateless_reset_token: [108, 96, 0, 27, 247, 222, 127, 29, 155, 226, 33, 160, 177, 4, 24, 10] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [249, 199, 188, 23, 172, 104, 247, 231, 235, 77, 155, 136, 207, 51, 184, 103, 50, 11, 206, 33], stateless_reset_token: [220, 132, 13, 220, 80, 86, 142, 62, 176, 244, 78, 240, 209, 139, 98, 197] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [135, 206, 206, 195, 224, 98, 117, 139, 29, 144, 2, 104, 212, 215, 205, 135, 4, 134, 63, 1], stateless_reset_token: [230, 8, 32, 43, 37, 149, 46, 12, 182, 56, 253, 31, 89, 200, 22, 139] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [41, 241, 23, 224, 85, 54, 193, 242, 62, 213, 54, 52, 80, 156, 162, 114, 104, 143, 250, 68], stateless_reset_token: [226, 75, 5, 108, 193, 248, 138, 141, 226, 66, 60, 237, 36, 140, 128, 100] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [42, 241, 136, 212, 51, 16, 27, 211, 26, 52, 172, 130, 105, 107, 252, 71, 201, 22, 172, 219], stateless_reset_token: [115, 103, 57, 95, 248, 167, 144, 77, 205, 179, 104, 55, 9, 69, 82, 148] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [224, 87, 221, 0, 65, 113, 119, 229, 64, 70, 176, 133, 226, 198, 185, 18, 107, 39, 145, 13], stateless_reset_token: [54, 62, 58, 144, 147, 80, 48, 99, 197, 65, 37, 12, 72, 245, 182, 225] } server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 856 } server | 1.054 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 343, tv_nsec: 580751222 } server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.054 DEBUG Building Handshake dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) scid Some(CID [10]: bd1ace0cd3e87ca1f2fc) server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=1 type=Handshake pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 61 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Drop packet number space hs server | 1.054 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.054 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=0 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 1332 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [253, 21, 211, 227, 17, 217, 64, 32, 78, 142], stateless_reset_token: [66, 190, 85, 189, 16, 79, 200, 43, 197, 243, 141, 188, 128, 197, 44, 242] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [211, 122, 53, 19, 64, 34, 201, 190, 173, 15], stateless_reset_token: [224, 197, 132, 198, 193, 251, 158, 73, 209, 102, 81, 223, 155, 200, 189, 154] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [13, 79, 247, 174, 189, 197, 190, 255, 74, 219], stateless_reset_token: [81, 178, 157, 215, 43, 97, 77, 160, 116, 236, 50, 233, 145, 57, 142, 104] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [179, 163, 181, 152, 87, 148, 100, 85, 225, 252], stateless_reset_token: [99, 198, 148, 186, 99, 102, 9, 11, 226, 115, 193, 46, 28, 170, 31, 239] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [203, 127, 91, 195, 0, 197, 228, 222, 29, 14], stateless_reset_token: [41, 58, 160, 46, 8, 169, 56, 132, 44, 142, 183, 104, 97, 65, 99, 120] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [199, 159, 160, 159, 207, 186, 24, 140, 19, 22], stateless_reset_token: [37, 99, 16, 73, 238, 241, 24, 203, 64, 83, 234, 122, 132, 109, 97, 254] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [96, 218, 9, 164, 181, 164, 33, 105, 198, 31], stateless_reset_token: [178, 174, 49, 144, 181, 228, 42, 219, 32, 214, 65, 0, 230, 85, 128, 223] } server | TX -> Padding { len: 1017 } server | 1.054 DEBUG packet_sent this=0x55f07f2aab40, pn=0, ps=1271 server | 1.054 DEBUG ECN probing: sent 2 probes server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.054 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.054 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=1 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1293 } server | 1.054 DEBUG packet_sent this=0x55f07f2aab40, pn=1, ps=1332 server | 1.054 DEBUG ECN probing: sent 3 probes server | 1.054 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.055 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.055 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.055 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 1.32855ms server | 1.055 DEBUG Setting timeout of 1.32855ms server | 1.055 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] send session ticket 406b00105ac6ca5098233046a07fe85b7635fd9f0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0abd1ace0cd3e87ca1f2fc110c000000014a2a4a8a000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.055 DEBUG Path = 'qmonxrgbtk' server | 1.055 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.055 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.055 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.055 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 343.912µs server | 1.056 DEBUG Setting timeout of 343.912µs server | 1.057 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.057 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.057 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=2 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1192, fin: false } server | 1.057 DEBUG packet_sent this=0x55f07f2aab40, pn=2, ps=1232 server | 1.057 DEBUG ECN probing: sent 4 probes server | 1.057 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.057 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.057 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 987.663µs server | 1.057 DEBUG Setting timeout of 987.663µs server | 1.057 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.057 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.057 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 965.131µs server | 1.057 DEBUG Setting timeout of 965.131µs server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.059 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=3 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1192, len: >>1190, fin: false } server | 1.059 DEBUG packet_sent this=0x55f07f2aab40, pn=3, ps=1232 server | 1.059 DEBUG ECN probing: sent 5 probes server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.059 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=4 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2382, len: >>1190, fin: false } server | 1.059 DEBUG packet_sent this=0x55f07f2aab40, pn=4, ps=1232 server | 1.059 DEBUG ECN probing: sent 6 probes server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.059 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 1.738997ms server | 1.059 DEBUG Setting timeout of 1.738997ms server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.059 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.059 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 1.715763ms server | 1.059 DEBUG Setting timeout of 1.715763ms server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.062 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=5 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3572, len: >>1190, fin: false } server | 1.062 DEBUG packet_sent this=0x55f07f2aab40, pn=5, ps=1232 server | 1.062 DEBUG ECN probing: sent 7 probes server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.062 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.062 DEBUG CRYPTO for ap offset=0, len=321 server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=6 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 772 server | TX -> Stream { stream_id: 0, offset: 4762, len: 358, fin: true } server | TX -> Crypto { offset: 0, len: 321 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 146, 60, 178, 28, 240, 227, 102, 198, 15, 239, 32, 153, 55, 3, 166, 57, 216, 6, 96, 178, 161, 127, 127, 98, 163, 163, 240, 255, 87, 11, 242, 69, 13, 22, 52, 112] } server | 1.062 DEBUG packet_sent this=0x55f07f2aab40, pn=6, ps=772 server | 1.062 DEBUG ECN probing: sent 8 probes server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.062 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.062 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 1.34357ms server | 1.062 DEBUG Setting timeout of 1.34357ms server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.062 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.062 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.062 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 1.325206ms server | 1.062 DEBUG Setting timeout of 1.325206ms server | 1.065 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.065 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 112.040252ms server | 1.065 DEBUG Setting timeout of 112.040252ms server | 1.065 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.065 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 112.015175ms server | 1.065 DEBUG Setting timeout of 112.015175ms server | 1.086 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=4 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 12, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.086 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=ap, ranges=[0..=0] server | 1.086 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 server | 1.086 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 7032/13591 ssthresh 18446744073709551615] slow start += 1271 server | 1.086 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 7032/13591 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=7032, cwnd=13591, state=SlowStart, new_acked=1271 server | 1.086 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.086 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.086 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.086 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 81.206935ms server | 1.086 DEBUG Setting timeout of 81.206935ms server | 1.086 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.086 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.086 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.086 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 81.100767ms server | 1.086 DEBUG Setting timeout of 81.100767ms server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=5 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 4, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=ap, ranges=[0..=1] server | 1.087 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 1.087 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5700/14923 ssthresh 18446744073709551615] slow start += 1332 server | 1.087 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5700/14923 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=5700, cwnd=14923, state=SlowStart, new_acked=1332 server | 1.087 DEBUG PMTUD probe of size 1380 succeeded server | 1.087 DEBUG PMTUD started with probe size 1420 server | 1.087 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.087 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.087 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=7 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1333 } server | 1.087 DEBUG packet_sent this=0x55f07f2aab40, pn=7, ps=1372 server | 1.087 DEBUG ECN probing: sent 9 probes server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.087 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.087 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 96.498509ms server | 1.087 DEBUG Setting timeout of 96.498509ms server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.087 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.087 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.087 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 96.46702ms server | 1.087 DEBUG Setting timeout of 96.46702ms server | 1.088 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=6 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 4, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 1.088 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=ap, ranges=[0..=2] server | 1.088 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 1.088 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5840/16155 ssthresh 18446744073709551615] slow start += 1232 server | 1.088 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5840/16155 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=5840, cwnd=16155, state=SlowStart, new_acked=1232 server | 1.088 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.088 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.088 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.088 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 89.811165ms server | 1.088 DEBUG Setting timeout of 89.811165ms server | 1.088 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.088 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.088 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.088 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 89.732478ms server | 1.088 DEBUG Setting timeout of 89.732478ms server | 1.090 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=7 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 4, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 1.090 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=ap, ranges=[0..=3] server | 1.090 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 1.090 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4608/17387 ssthresh 18446744073709551615] slow start += 1232 server | 1.090 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4608/17387 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=4608, cwnd=17387, state=SlowStart, new_acked=1232 server | 1.090 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.090 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 82.718328ms server | 1.090 DEBUG Setting timeout of 82.718328ms server | 1.090 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.090 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 82.659449ms server | 1.090 DEBUG Setting timeout of 82.659449ms server | 1.091 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=8 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 4, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | 1.091 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=ap, ranges=[0..=4] server | 1.091 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 1.091 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3376/18619 ssthresh 18446744073709551615] slow start += 1232 server | 1.091 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3376/18619 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=3376, cwnd=18619, state=SlowStart, new_acked=1232 server | 1.091 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.091 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 76.777294ms server | 1.091 DEBUG Setting timeout of 76.777294ms server | 1.091 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.091 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 76.706412ms server | 1.091 DEBUG Setting timeout of 76.706412ms server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=9 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 4, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] Rx ACK space=ap, ranges=[0..=5] server | 1.094 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5 server | 1.094 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2144/19851 ssthresh 18446744073709551615] slow start += 1232 server | 1.094 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2144/19851 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=2144, cwnd=19851, state=SlowStart, new_acked=1232 server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.094 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 71.283038ms server | 1.094 DEBUG Setting timeout of 71.283038ms server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.094 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] delay duration 71.203179ms server | 1.094 DEBUG Setting timeout of 71.203179ms server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=10 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, NotEct) len 38 server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye" } server | 1.094 INFO [Server 5ac6ca5098233046a07fe85b7635fd9f] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 343, tv_nsec: 854575788 } } server | 1.094 DEBUG [pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718] Path validated Instant { tv_sec: 343, tv_nsec: 621139425 } server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG Building Short dcid Some(CID [20]: 10ac52e050e96d71e192fabc8f65d50bd62f132f) server | 1.094 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 343, tv_nsec: 621139425 } server | 1.094 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] pn=8 type=Short pri-path:10ac52e050e96d71e192fabc8f65d50bd62f132f [::]:443->[::ffff:193.167.0.100]:44718 IpTos(Cs0, Ect0) len 47 server | TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 1.094 DEBUG ECN probing: sent 10 probes server | 1.094 DEBUG ECN probing concluded with 10 probes sent server | 1.094 DEBUG Setting timeout of 233.280251ms server | 1.094 DEBUG Setting timeout of 233.263971ms server | 1.329 DEBUG [Server 5ac6ca5098233046a07fe85b7635fd9f] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 343, tv_nsec: 854575788 } } -> Closed(Transport(PeerApplicationError(0))) server | 1.329 INFO Closing timer expired server | 1.390 DEBUG [Server] Handle initial server | 1.390 INFO AddressValidation: no token; accepting server | 1.390 INFO [Server] Accept connection CID [16]: 55317feb6a9731495671b3c14fd0ef4f server | 1.390 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.392 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.392 INFO [Server ...] Received valid Initial packet with scid CID [20]: 4dc680cacbd12659fed998517afb346040214042 dcid CID [16]: 55317feb6a9731495671b3c14fd0ef4f server | 1.392 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=55317feb6a9731495671b3c14fd0ef4f server | 1.392 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.392 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.392 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] State change from Init -> WaitInitial server | 1.392 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 670 server | -> RX Crypto { offset: 0, len: 603 } server | 1.392 DEBUG Read Ok(603) bytes server | 1.392 INFO 0-RTT: transport parameters OK, passing to application checker server | 1.392 WARN AllowZeroRtt accepting 0-RTT server | 1.392 DEBUG Read secret available for Handshake: SymKey [32]: 8aa57793222e70ba19bc5adb0a723f0877c134d0fb1d78f83c12f0ec86bfcb32 server | 1.392 DEBUG Write secret available for Handshake: SymKey [32]: 298bfc559c446322e4a6aebb5a1f0cfe688a0a362d3cc8d25af1cd3300481ed2 server | 1.392 DEBUG Writing transport parameters, msg=8 server | 1.392 DEBUG Read secret available for ApplicationData: SymKey [32]: a1206c9d46b4d4fc886e55aa72b34a0ea5806d9f26a05b373ecc3fc943eb9530 server | 1.392 DEBUG Write secret available for ApplicationData: SymKey [32]: 5df588d8c53464e83806f1deb8251eb2e8d0f53bb90160e7d389839e04599cd9 server | 1.392 DEBUG [Agent 0x55f07f2d3c80] state -> InProgress server | 1.392 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.392 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.392 DEBUG [Crypto] Handshake keys installed server | 1.392 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.392 DEBUG [Crypto] Application write key installed server | 1.392 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 343, tv_nsec: 917333169 } server | 1.393 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:34664] Make permanent server | 1.393 DEBUG [unv-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664] set as primary path server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] State change from WaitInitial -> Handshaking server | 1.393 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1 server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.393 DEBUG Building Initial dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) scid Some(CID [10]: 2a00d9d27946d621b922) server | 1.393 DEBUG CRYPTO for in offset=0, len=96 server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=0 type=Initial pri-unv-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 162 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 96 } server | 1.393 DEBUG Building Handshake dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) scid Some(CID [10]: 2a00d9d27946d621b922) server | 1.393 DEBUG CRYPTO for hs offset=0, len=182 server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=0 type=Handshake pri-unv-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 404 server | TX -> Crypto { offset: 0, len: 182 } server | 1.393 DEBUG packet_sent this=0x55f07f2aab40, pn=0, ps=242 server | 1.393 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pad Initial from 404 to PLPMTU 1232 server | 1.393 DEBUG packet_sent this=0x55f07f2aab40, pn=0, ps=990 server | 1.393 DEBUG ECN probing: sent 1 probes server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.393 DEBUG Building Initial dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) scid Some(CID [10]: 2a00d9d27946d621b922) server | 1.393 DEBUG Building Handshake dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) scid Some(CID [10]: 2a00d9d27946d621b922) server | 1.393 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.393 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 297.761994ms server | 1.393 DEBUG Setting timeout of 297.761994ms server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.393 DEBUG Building Initial dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) scid Some(CID [10]: 2a00d9d27946d621b922) server | 1.393 DEBUG Building Handshake dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) scid Some(CID [10]: 2a00d9d27946d621b922) server | 1.393 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.393 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.393 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 297.728632ms server | 1.393 DEBUG Setting timeout of 297.728632ms server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=1 type=Initial pri-unv-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 62 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 43, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=in, ranges=[0..=0] server | 1.426 DEBUG [LossRecovery] ACK for Initial - largest_acked=0 server | 1.426 DEBUG on_packets_acked this=0x55f07f2aab40, limited=1, bytes_in_flight=242, cwnd=12320, state=SlowStart, new_acked=990 server | 1.426 DEBUG Acked crypto frame space=in offset=0 length=96 server | 1.426 DEBUG [pri-unv-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664] Path validated Instant { tv_sec: 343, tv_nsec: 952563638 } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Drop packet number space in server | 1.426 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=2 type=Handshake pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 101 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 30, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | -> RX Crypto { offset: 0, len: 36 } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=hs, ranges=[0..=0] server | 1.426 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.426 DEBUG on_packets_acked this=0x55f07f2aab40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=242 server | 1.426 DEBUG Acked crypto frame space=hs offset=0 length=182 server | 1.426 DEBUG Read Ok(36) bytes server | 1.426 DEBUG [Agent 0x55f07f2d3c80] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: true, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] TLS connection complete server | 1.426 DEBUG [pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664] Path validated Instant { tv_sec: 343, tv_nsec: 952563638 } server | 1.426 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.426 DEBUG [Crypto] application read keys installed server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] State change from Handshaking -> Connected server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] State change from Connected -> Confirmed server | 1.426 DEBUG PMTUD started with probe size 1380 server | 1.426 INFO [Server 55317feb6a9731495671b3c14fd0ef4f] Connection established server | 1.426 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 343, tv_nsec: 952563638 } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=3 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 1187 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [12, 103, 200, 189, 215, 53, 104, 205, 25, 222, 156, 98, 228, 183, 11, 196, 10, 228, 85, 126], stateless_reset_token: [188, 55, 183, 163, 104, 194, 102, 85, 135, 144, 20, 8, 158, 206, 158, 204] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [203, 74, 27, 96, 136, 242, 138, 37, 56, 21, 162, 139, 132, 213, 122, 249, 121, 27, 109, 98], stateless_reset_token: [38, 226, 212, 227, 127, 97, 144, 39, 35, 81, 82, 6, 217, 209, 212, 161] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [180, 190, 178, 165, 99, 41, 232, 172, 105, 154, 59, 22, 216, 31, 255, 164, 248, 240, 131, 143], stateless_reset_token: [74, 215, 183, 143, 246, 103, 192, 102, 96, 166, 3, 161, 209, 160, 251, 74] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [18, 98, 54, 172, 214, 213, 216, 10, 186, 148, 177, 209, 134, 236, 43, 207, 43, 121, 55, 125], stateless_reset_token: [101, 140, 53, 31, 147, 126, 136, 21, 136, 80, 148, 244, 15, 11, 65, 41] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [1, 144, 25, 98, 64, 106, 235, 169, 201, 194, 143, 219, 222, 25, 28, 72, 41, 52, 139, 252], stateless_reset_token: [110, 83, 117, 197, 24, 195, 124, 251, 38, 40, 225, 100, 55, 209, 56, 201] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [252, 74, 139, 228, 119, 177, 185, 14, 32, 84, 152, 184, 54, 14, 243, 192, 1, 32, 20, 101], stateless_reset_token: [210, 11, 118, 72, 240, 166, 142, 252, 78, 70, 125, 26, 236, 24, 255, 60] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [151, 224, 243, 125, 14, 2, 158, 235, 151, 72, 63, 75, 168, 60, 2, 142, 28, 119, 168, 58], stateless_reset_token: [74, 39, 150, 3, 55, 138, 0, 226, 95, 237, 199, 11, 205, 91, 43, 47] } server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 857 } server | 1.426 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 343, tv_nsec: 952563638 } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.426 DEBUG Building Handshake dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) scid Some(CID [10]: 2a00d9d27946d621b922) server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=1 type=Handshake pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 61 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Drop packet number space hs server | 1.426 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.426 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=0 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1332 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [54, 73, 66, 12, 128, 218, 33, 189, 198, 51], stateless_reset_token: [29, 125, 26, 118, 236, 112, 38, 183, 150, 188, 63, 82, 204, 125, 140, 150] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [131, 223, 174, 207, 53, 197, 220, 45, 77, 120], stateless_reset_token: [137, 239, 21, 95, 118, 40, 81, 218, 99, 121, 187, 123, 169, 225, 79, 29] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [4, 8, 233, 189, 137, 157, 19, 6, 164, 239], stateless_reset_token: [158, 100, 181, 3, 30, 120, 254, 205, 167, 135, 102, 168, 208, 31, 174, 227] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [79, 31, 104, 102, 189, 106, 244, 139, 58, 179], stateless_reset_token: [146, 6, 59, 252, 19, 176, 7, 252, 88, 235, 160, 27, 250, 160, 45, 222] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [40, 53, 44, 95, 136, 156, 70, 210, 111, 64], stateless_reset_token: [85, 95, 11, 31, 214, 147, 208, 233, 192, 127, 12, 16, 208, 4, 42, 241] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [222, 118, 166, 63, 111, 212, 178, 203, 223, 222], stateless_reset_token: [171, 96, 198, 49, 61, 17, 167, 103, 249, 244, 151, 62, 124, 22, 72, 25] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [200, 242, 251, 239, 180, 204, 205, 202, 128, 181], stateless_reset_token: [20, 191, 236, 10, 202, 207, 75, 164, 28, 137, 171, 104, 93, 120, 56, 173] } server | TX -> Padding { len: 1017 } server | 1.426 DEBUG packet_sent this=0x55f07f2aab40, pn=0, ps=1271 server | 1.426 DEBUG ECN probing: sent 2 probes server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.426 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.426 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=1 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1293 } server | 1.426 DEBUG packet_sent this=0x55f07f2aab40, pn=1, ps=1332 server | 1.426 DEBUG ECN probing: sent 3 probes server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.426 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.426 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.341168ms server | 1.426 DEBUG Setting timeout of 1.341168ms server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] send session ticket 406b001055317feb6a9731495671b3c14fd0ef4f0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a2a00d9d27946d621b922110c000000019a8a2a6a000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.426 DEBUG Path = 'njtkmaeatn' server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.426 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.426 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.426 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.195847ms server | 1.426 DEBUG Setting timeout of 1.195847ms server | 1.428 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.428 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.429 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=2 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1192, fin: false } server | 1.429 DEBUG packet_sent this=0x55f07f2aab40, pn=2, ps=1232 server | 1.429 DEBUG ECN probing: sent 4 probes server | 1.429 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.429 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.429 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=3 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1192, len: >>1190, fin: false } server | 1.429 DEBUG packet_sent this=0x55f07f2aab40, pn=3, ps=1232 server | 1.429 DEBUG ECN probing: sent 5 probes server | 1.429 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.429 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.429 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.429 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.672477ms server | 1.429 DEBUG Setting timeout of 1.672477ms server | 1.429 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.429 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.429 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.429 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.652199ms server | 1.429 DEBUG Setting timeout of 1.652199ms server | 1.431 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.431 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.431 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=4 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2382, len: >>1190, fin: false } server | 1.431 DEBUG packet_sent this=0x55f07f2aab40, pn=4, ps=1232 server | 1.431 DEBUG ECN probing: sent 6 probes server | 1.431 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.431 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.431 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.431 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.269566ms server | 1.431 DEBUG Setting timeout of 1.269566ms server | 1.431 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.431 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.431 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.431 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.246784ms server | 1.431 DEBUG Setting timeout of 1.246784ms server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.433 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=5 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3572, len: >>1190, fin: false } server | 1.433 DEBUG packet_sent this=0x55f07f2aab40, pn=5, ps=1232 server | 1.433 DEBUG ECN probing: sent 7 probes server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.433 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=6 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 4762, len: >>1190, fin: false } server | 1.433 DEBUG packet_sent this=0x55f07f2aab40, pn=6, ps=1232 server | 1.433 DEBUG ECN probing: sent 8 probes server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.433 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.433 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.644604ms server | 1.433 DEBUG Setting timeout of 1.644604ms server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.433 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.433 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.433 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 1.614448ms server | 1.433 DEBUG Setting timeout of 1.614448ms server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.436 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=7 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 5952, len: >>1190, fin: false } server | 1.436 DEBUG packet_sent this=0x55f07f2aab40, pn=7, ps=1232 server | 1.436 DEBUG ECN probing: sent 9 probes server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.436 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=8 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 7142, len: >>1190, fin: false } server | 1.436 DEBUG packet_sent this=0x55f07f2aab40, pn=8, ps=1232 server | 1.436 DEBUG ECN probing: sent 10 probes server | 1.436 DEBUG ECN probing concluded with 10 probes sent server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1093, pto: None, probe: EnumSet(), paced: false } server | 1.436 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=9 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 1093 server | TX -> Stream { stream_id: 0, offset: 8332, len: >>1051, fin: false } server | 1.436 DEBUG packet_sent this=0x55f07f2aab40, pn=9, ps=1093 server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.436 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.436 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 113.020415ms server | 1.436 DEBUG Setting timeout of 113.020415ms server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.436 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.436 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.436 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 112.999315ms server | 1.436 DEBUG Setting timeout of 112.999315ms server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=4 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 12, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=0] server | 1.458 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 server | 1.458 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11049/13591 ssthresh 18446744073709551615] slow start += 1271 server | 1.458 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11049/13591 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=11049, cwnd=13591, state=SlowStart, new_acked=1271 server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.458 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.458 DEBUG CRYPTO for ap offset=0, len=321 server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=10 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 1226 server | TX -> Stream { stream_id: 0, offset: 9383, len: 857, fin: true } server | TX -> Crypto { offset: 0, len: 321 } server | 1.458 DEBUG packet_sent this=0x55f07f2aab40, pn=10, ps=1226 server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.458 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=11 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 83 server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 2, 23, 58, 123, 13, 172, 148, 85, 94, 100, 96, 7, 96, 220, 11, 122, 228, 124, 200, 78, 8, 196, 159, 234, 77, 244, 117, 108, 191, 90, 27, 166, 154, 248, 172, 15] } server | 1.458 DEBUG packet_sent this=0x55f07f2aab40, pn=11, ps=83 server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.458 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.458 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 102.48047ms server | 1.458 DEBUG Setting timeout of 102.48047ms server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.458 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.458 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.458 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 102.447999ms server | 1.458 DEBUG Setting timeout of 102.447999ms server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=5 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=1] server | 1.459 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 1.459 WARN ECN validation failed, no ECN counts in ACK frame server | 1.459 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11026/14923 ssthresh 18446744073709551615] slow start += 1332 server | 1.459 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11026/14923 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=11026, cwnd=14923, state=SlowStart, new_acked=1332 server | 1.459 DEBUG PMTUD probe of size 1380 succeeded server | 1.459 DEBUG PMTUD started with probe size 1420 server | 1.459 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.459 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.459 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=12 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 1372 server | TX -> Ping server | TX -> Padding { len: 1333 } server | 1.459 DEBUG packet_sent this=0x55f07f2aab40, pn=12, ps=1372 server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.459 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.459 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 93.753201ms server | 1.459 DEBUG Setting timeout of 93.753201ms server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.459 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.459 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.459 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 93.723225ms server | 1.459 DEBUG Setting timeout of 93.723225ms server | 1.460 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=6 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 3, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 1.460 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=2] server | 1.460 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 1.460 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11166/16155 ssthresh 18446744073709551615] slow start += 1232 server | 1.460 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11166/16155 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=11166, cwnd=16155, state=SlowStart, new_acked=1232 server | 1.460 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.460 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.460 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.460 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 86.832892ms server | 1.460 DEBUG Setting timeout of 86.832892ms server | 1.460 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.460 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.460 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.460 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 86.754616ms server | 1.460 DEBUG Setting timeout of 86.754616ms server | 1.461 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=7 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 5, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 1.461 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=3] server | 1.461 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 1.461 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9934/17387 ssthresh 18446744073709551615] slow start += 1232 server | 1.461 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9934/17387 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=9934, cwnd=17387, state=SlowStart, new_acked=1232 server | 1.461 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.461 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.461 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.461 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 80.152846ms server | 1.461 DEBUG Setting timeout of 80.152846ms server | 1.461 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.461 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.461 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.461 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 80.086913ms server | 1.461 DEBUG Setting timeout of 80.086913ms server | 1.462 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=8 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 3, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | 1.462 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=4] server | 1.462 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 1.462 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8702/18619 ssthresh 18446744073709551615] slow start += 1232 server | 1.462 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8702/18619 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=8702, cwnd=18619, state=SlowStart, new_acked=1232 server | 1.462 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.462 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.462 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.462 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 75.277399ms server | 1.462 DEBUG Setting timeout of 75.277399ms server | 1.462 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.462 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.462 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.462 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 75.223959ms server | 1.462 DEBUG Setting timeout of 75.223959ms server | 1.464 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=9 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 4, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 1.464 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=5] server | 1.464 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5 server | 1.464 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 7470/19851 ssthresh 18446744073709551615] slow start += 1232 server | 1.465 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 7470/19851 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=7470, cwnd=19851, state=SlowStart, new_acked=1232 server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.465 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 69.844353ms server | 1.465 DEBUG Setting timeout of 69.844353ms server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.465 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 69.774052ms server | 1.465 DEBUG Setting timeout of 69.774052ms server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=10 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 3, first_ack_range: 6, ack_ranges: [], ecn_count: None } server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=6] server | 1.465 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6 server | 1.465 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6238/21083 ssthresh 18446744073709551615] slow start += 1232 server | 1.465 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6238/21083 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=6238, cwnd=21083, state=SlowStart, new_acked=1232 server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.465 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 65.465081ms server | 1.465 DEBUG Setting timeout of 65.465081ms server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.465 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.465 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 65.411661ms server | 1.465 DEBUG Setting timeout of 65.411661ms server | 1.468 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=11 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 3, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | 1.468 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=7] server | 1.468 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7 server | 1.468 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5006/22315 ssthresh 18446744073709551615] slow start += 1232 server | 1.468 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5006/22315 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=5006, cwnd=22315, state=SlowStart, new_acked=1232 server | 1.468 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.468 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.468 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.468 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 61.015906ms server | 1.468 DEBUG Setting timeout of 61.015906ms server | 1.468 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.468 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.468 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.468 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 60.962346ms server | 1.468 DEBUG Setting timeout of 60.962346ms server | 1.469 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=12 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 5, first_ack_range: 8, ack_ranges: [], ecn_count: None } server | 1.469 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=8] server | 1.469 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8 server | 1.469 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3774/23547 ssthresh 18446744073709551615] slow start += 1232 server | 1.469 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3774/23547 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=3774, cwnd=23547, state=SlowStart, new_acked=1232 server | 1.469 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.469 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.469 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.469 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 57.420461ms server | 1.469 DEBUG Setting timeout of 57.420461ms server | 1.469 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.469 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.469 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.469 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 57.364026ms server | 1.469 DEBUG Setting timeout of 57.364026ms server | 1.470 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=13 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 33 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 3, first_ack_range: 9, ack_ranges: [], ecn_count: None } server | 1.470 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] Rx ACK space=ap, ranges=[0..=9] server | 1.470 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9 server | 1.470 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2681/24640 ssthresh 18446744073709551615] slow start += 1093 server | 1.470 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2681/24640 ssthresh 18446744073709551615] on_packets_acked this=0x55f07f2aab40, limited=0, bytes_in_flight=2681, cwnd=24640, state=SlowStart, new_acked=1093 server | 1.470 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.470 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.470 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.470 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 54.588215ms server | 1.470 DEBUG Setting timeout of 54.588215ms server | 1.470 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.470 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.470 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.470 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] delay duration 54.542449ms server | 1.470 DEBUG Setting timeout of 54.542449ms server | 1.490 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=14 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 38 server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye" } server | 1.490 INFO [Server 55317feb6a9731495671b3c14fd0ef4f] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye server | 1.490 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 344, tv_nsec: 213248937 } } server | 1.490 DEBUG [pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664] Path validated Instant { tv_sec: 344, tv_nsec: 16516185 } server | 1.490 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.490 DEBUG Building Short dcid Some(CID [20]: 4dc680cacbd12659fed998517afb346040214042) server | 1.490 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 344, tv_nsec: 16516185 } server | 1.490 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] pn=13 type=Short pri-path:4dc680cacbd12659fed998517afb346040214042 [::]:443->[::ffff:193.167.0.100]:34664 IpTos(Cs0, NotEct) len 47 server | TX -> Ack { largest_acknowledged: 14, ack_delay: 0, first_ack_range: 11, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 1.490 DEBUG Setting timeout of 196.623388ms server | 1.490 DEBUG Setting timeout of 196.610804ms server | 1.687 DEBUG [Server 55317feb6a9731495671b3c14fd0ef4f] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 344, tv_nsec: 213248937 } } -> Closed(Transport(PeerApplicationError(0))) server | 1.687 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 10:28:42,534 Using the client's key log file. 2025-04-30 10:28:42,543 2025-04-30 10:28:42,544 Using the client's key log file. 2025-04-30 10:28:42,544 Using the client's key log file. 2025-04-30 10:28:42,552 2025-04-30 10:28:42,553 Using the client's key log file. 2025-04-30 10:28:42,553 Using the client's key log file. 2025-04-30 10:28:43,401 Check of downloaded files succeeded.