2025-04-30 08:44:20,015 Generated random file: hpkivgzoei of size: 5120 2025-04-30 08:44:20,016 Generated random file: pyexhbwjco of size: 10240 2025-04-30 08:44:20,016 Requests: https://server4:443/hpkivgzoei https://server4:443/pyexhbwjco 2025-04-30 08:44:20,074 2025-04-30 08:44:20,075 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_hlbdcpwv/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_wspg_052/ DOWNLOADS=/tmp/download_i7ccitd0/ SERVER_LOGS=/tmp/logs_server_440e3j2z CLIENT_LOGS=/tmp/logs_client_2ykabnt3 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=privateoctopus/picoquic:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/hpkivgzoei https://server4:443/pyexhbwjco" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-04-30 08:44:32,202 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.oI9BoF40gu server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password client | Setting up the simulation with setup.sh 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.oI9BoF40gu client | Endpoint's IPv6 address is fd00:cafe:cafe::100 server | Warning: output encryption option -nodes ignored with -export client | Setup.sh completed client | Waiting for the simulator to start server | + pk12util -d sql:/neqo/db -i /tmp/tmp.oI9BoF40gu -W '' client | wait-for-it.sh: 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 | 53:79:8f:eb:57:10:f5:08:88:66:c2:84:9e:53:54:7a: server | b8:91:04:80 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner Root Certificate Authority" server | Validity: server | Not Before: Wed Apr 30 08:44:20 2025 server | Not After : Sat May 10 08:44:20 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:ae:f1:b1:5d:55:d7:f0:32:58:39:49:27:58:61:1f: server | 3d:32:7f:67:49:52:52:56:0b:b3:23:59:07:f1:ba:9a: server | 0f:dd:9c:66:6b:c8:71:b8:2b:d8:f6:a3:cd:0b:26:99: server | 65:d5:45:79:08:46:32:0f:12:79:d7:86:4c:9a:de:45: server | 13 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 | b5:02:38:32:e0:90:08:36:47:57:36:8f:a4:a4:71:fa: server | e0:e0:35:14 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | a9:7e:38:35:56:89:16:c9:be:57:18:6c:eb:20:b4:bc: server | 76:d4:d8:c4 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:45:02:21:00:a0:9f:42:63:a5:bc:5b:18:0b:94:2b: server | 78:24:92:1b:06:98:f3:ee:31:ff:11:a0:25:ef:4e:64: server | c9:bb:6c:ab:01:02:20:51:2a:f1:b8:88:bd:3d:7c:a2: server | 3f:a5:5a:8a:2d:b9:c2:dc:6c:af:91:a7:fc:49:34:d1: server | f8:c4:95:2c:d5:9e:85 server | Fingerprint (SHA-256): server | 2E:2F:5E:1B:CF:B7:84:F3:B5:60:09:EC:2B:50:20:0E:92:C2:4C:90:D5:A8:45:73:B7:81:0B:49:E6:57:01:65 server | Fingerprint (SHA1): server | D4:3A:D3:31:89:2B:3A:5E:F1:8A:85:DF:43:AA:B7:6B:11:0F:DC:14 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.832 DEBUG [Server] Unsupported version: 57414954 server | 0.832 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36949 IpTos(Cs0, NotEct) len 15 server | 0.832 DEBUG [Server] Unsupported version: 57414954 server | 0.832 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36949 IpTos(Cs0, NotEct) len 15 server | 0.832 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) server | 0.832 DEBUG [Server] Unsupported version: 57414954 server | 0.832 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:36949 IpTos(Cs0, NotEct) len 15 server | 0.832 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" }) sim | server:443 is available after 1.063253058s sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | Starting picoquic client for test: resumption client | Starting picoquic client ... client | Parsing server name from first request: https://server4:443/hpkivgzoei client | Server set to: server4 client | Requests: https://server4:443/hpkivgzoei https://server4:443/pyexhbwjco client | parsing as client | parsing as client | File1: -:/hpkivgzoei client | File2: -:/pyexhbwjco; client | rm: cannot remove '*.bin': No such file or directory client | /picoquic/picoquicdemo -8 -L -l /logs/test_log.txt -q /logs/qlog -o /downloads -0 -d 180000 -a hq-interop -v 00000001 server4 443 -:/hpkivgzoei client | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443 client | No token file present. Will create one as . client | Testing scenario: <-:/hpkivgzoei> client | Max stream id bidir remote before start = 0 (0) client | Starting client connection. Version = 1, I-CID: 3d23a0cd414cbb7 client | Max stream id bidir remote after start = -4 (0) client | Waiting for packets. server | 1.022 DEBUG [Server] Handle initial server | 1.022 INFO AddressValidation: no token; accepting server | 1.022 INFO [Server] Accept connection CID [8]: 03d23a0cd414cbb7 server | 1.022 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.024 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.024 INFO [Server ...] Received valid Initial packet with scid CID [8]: db7848a7774e638a dcid CID [8]: 03d23a0cd414cbb7 server | 1.024 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=03d23a0cd414cbb7 server | 1.024 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.024 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.024 DEBUG [Server 03d23a0cd414cbb7] State change from Init -> WaitInitial server | 1.024 DEBUG [Server 03d23a0cd414cbb7] pn=116460 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 1252 server | -> RX Ping server | -> RX Crypto { offset: 0, len: 285 } server | -> RX Padding { len: 916 } server | 1.024 DEBUG unmark 0-length range at 0 server | 1.024 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 336, tv_nsec: 486740796 } server | 1.024 DEBUG Read Ok(285) bytes server | 1.024 DEBUG 0-RTT: no token, no 0-RTT server | 1.025 DEBUG Read secret available for Handshake: SymKey [32]: 1ada75edeb51abcda0cd283744229343e8c1611d0555327182c8b757729d658a server | 1.025 DEBUG Write secret available for Handshake: SymKey [32]: d338aeb1cb5a462f6c11785ff346398dc8d219c25883ca807b2ca2deeb48d062 server | 1.025 DEBUG Writing transport parameters, msg=8 server | 1.025 DEBUG Read secret available for ApplicationData: SymKey [32]: 802d263aa400ce1f71561c6de2cd745091673eaf1c44e528f0beee3674307df6 server | 1.025 DEBUG Write secret available for ApplicationData: SymKey [32]: 321be5469796a708e8d0e4911338de420333bdc762ed2ab1ab4e33a1c7181be7 server | 1.025 DEBUG [Agent 0x55c474e431d0] state -> InProgress server | 1.025 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.025 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.025 DEBUG [Crypto] Handshake keys installed server | 1.025 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.026 DEBUG [Crypto] Application write key installed server | 1.026 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 486740796 } server | 1.026 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:52809] Make permanent server | 1.026 DEBUG [unv-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809] set as primary path server | 1.026 DEBUG [Server 03d23a0cd414cbb7] State change from WaitInitial -> Handshaking server | 1.026 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.026 DEBUG Building Initial dcid Some(CID [8]: db7848a7774e638a) scid Some(CID [10]: 196dc87962ca7e15fb49) server | 1.026 DEBUG CRYPTO for in offset=0, len=123 server | 1.026 DEBUG [Server 03d23a0cd414cbb7] pn=0 type=Initial pri-unv-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 183 server | TX -> Ack { largest_acknowledged: 116460, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 123 } server | 1.026 DEBUG Building Handshake dcid Some(CID [8]: db7848a7774e638a) scid Some(CID [10]: 196dc87962ca7e15fb49) server | 1.026 DEBUG CRYPTO for hs offset=0, len=721 server | 1.026 DEBUG [Server 03d23a0cd414cbb7] pn=0 type=Handshake pri-unv-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 952 server | TX -> Crypto { offset: 0, len: 721 } server | 1.026 DEBUG packet_sent this=0x55c474e42b40, pn=0, ps=769 server | 1.026 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.026 DEBUG [Server 03d23a0cd414cbb7] pad Initial from 952 to PLPMTU 1232 server | 1.026 DEBUG packet_sent this=0x55c474e42b40, pn=0, ps=463 server | 1.026 DEBUG ECN probing: sent 1 probes server | 1.026 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.026 DEBUG Building Initial dcid Some(CID [8]: db7848a7774e638a) scid Some(CID [10]: 196dc87962ca7e15fb49) server | 1.026 DEBUG Building Handshake dcid Some(CID [8]: db7848a7774e638a) scid Some(CID [10]: 196dc87962ca7e15fb49) server | 1.026 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.026 DEBUG [Server 03d23a0cd414cbb7] delay duration 296.718209ms server | 1.026 DEBUG Setting timeout of 296.718209ms server | 1.026 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.026 DEBUG Building Initial dcid Some(CID [8]: db7848a7774e638a) scid Some(CID [10]: 196dc87962ca7e15fb49) server | 1.026 DEBUG Building Handshake dcid Some(CID [8]: db7848a7774e638a) scid Some(CID [10]: 196dc87962ca7e15fb49) server | 1.026 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.026 DEBUG [Server 03d23a0cd414cbb7] delay duration 296.683214ms server | 1.026 DEBUG Setting timeout of 296.683214ms client | Client port (AF=2): 18894. client | Negotiated ALPN: hq-interop client | Almost ready! client | client | Connection established. Version = 1, I-CID: 3d23a0cd414cbb7, verified: 1 client | Opening stream 0 to GET /hpkivgzoei server | 1.058 DEBUG [Server 03d23a0cd414cbb7] Drop packet number space in server | 1.058 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.058 INFO [pri-unv-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809] discarding a packet without an RTT estimate; guessing RTT=35.370796ms server | 1.058 DEBUG [Server 03d23a0cd414cbb7] pn=0 type=Handshake pri-unv-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 94 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 31, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | -> RX Crypto { offset: 0, len: 36 } server | 1.058 DEBUG [Server 03d23a0cd414cbb7] Rx ACK space=hs, ranges=[0..=0] server | 1.058 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.058 DEBUG on_packets_acked this=0x55c474e42b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=769 server | 1.058 DEBUG Acked crypto frame space=hs offset=0 length=721 server | 1.058 DEBUG Read Ok(36) bytes server | 1.058 DEBUG [Agent 0x55c474e431d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 1.058 DEBUG [Server 03d23a0cd414cbb7] TLS connection complete server | 1.058 DEBUG [pri-unv-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809] Path validated Instant { tv_sec: 336, tv_nsec: 522111592 } server | 1.058 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.058 DEBUG [Crypto] application read keys installed server | 1.058 DEBUG [Server 03d23a0cd414cbb7] State change from Handshaking -> Connected server | 1.058 DEBUG [Server 03d23a0cd414cbb7] State change from Connected -> Confirmed server | 1.058 DEBUG PMTUD started with probe size 1380 server | 1.058 INFO [Server 03d23a0cd414cbb7] Connection established server | 1.058 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 522111592 } server | 1.058 DEBUG [Server 03d23a0cd414cbb7] pn=0 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 311 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [85, 228, 88, 234, 77, 238, 128, 106], stateless_reset_token: [211, 131, 153, 193, 160, 92, 67, 117, 64, 64, 0, 183, 81, 215, 211, 28] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [163, 65, 112, 156, 223, 59, 24, 76], stateless_reset_token: [122, 200, 150, 13, 254, 72, 118, 8, 45, 175, 92, 157, 13, 73, 192, 242] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [214, 39, 229, 250, 174, 22, 220, 191], stateless_reset_token: [95, 103, 115, 233, 92, 55, 52, 154, 223, 237, 178, 70, 31, 225, 15, 25] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [178, 188, 120, 181, 161, 120, 189, 134], stateless_reset_token: [137, 205, 182, 117, 58, 76, 193, 240, 29, 58, 247, 97, 148, 26, 17, 27] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [146, 223, 239, 130, 106, 63, 129, 235], stateless_reset_token: [58, 186, 128, 168, 163, 245, 76, 5, 219, 189, 211, 190, 25, 119, 67, 114] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [210, 59, 197, 225, 48, 193, 174, 254], stateless_reset_token: [185, 52, 232, 36, 19, 162, 169, 81, 193, 157, 44, 206, 243, 132, 53, 155] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [180, 204, 10, 212, 116, 2, 5, 242], stateless_reset_token: [54, 118, 73, 15, 158, 79, 194, 248, 229, 133, 141, 122, 155, 241, 25, 62] } server | -> RX Padding { len: 87 } server | 1.058 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 542111592 } server | 1.058 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Handshake dcid Some(CID [8]: db7848a7774e638a) scid Some(CID [10]: 196dc87962ca7e15fb49) server | 1.058 DEBUG [Server 03d23a0cd414cbb7] pn=1 type=Handshake pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 52 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | 1.058 DEBUG [Server 03d23a0cd414cbb7] Drop packet number space hs server | 1.058 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.058 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.058 DEBUG [Server 03d23a0cd414cbb7] pn=0 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [45, 16, 142, 44, 155, 125, 127, 248, 42, 247], stateless_reset_token: [203, 240, 28, 207, 184, 118, 60, 104, 10, 163, 192, 142, 125, 77, 87, 22] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [14, 151, 153, 116, 84, 220, 248, 11, 12, 175], stateless_reset_token: [191, 67, 176, 250, 98, 118, 218, 18, 76, 20, 152, 76, 22, 106, 254, 237] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [220, 51, 41, 35, 86, 49, 58, 22, 190, 2], stateless_reset_token: [6, 128, 46, 63, 51, 22, 160, 245, 51, 20, 223, 3, 8, 48, 2, 230] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [43, 197, 68, 52, 33, 116, 176, 155, 4, 67], stateless_reset_token: [122, 129, 16, 117, 26, 24, 77, 222, 226, 27, 112, 202, 228, 24, 139, 208] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [174, 17, 17, 165, 196, 69, 151, 240, 61, 149], stateless_reset_token: [108, 25, 125, 103, 175, 157, 183, 130, 30, 79, 112, 2, 75, 97, 178, 65] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [53, 19, 5, 200, 53, 209, 54, 148, 33, 160], stateless_reset_token: [227, 105, 172, 210, 2, 66, 108, 180, 166, 20, 240, 101, 71, 118, 121, 226] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [48, 210, 100, 56, 51, 107, 73, 40, 221, 242], stateless_reset_token: [97, 22, 78, 77, 202, 203, 59, 130, 45, 63, 236, 102, 24, 153, 139, 154] } server | TX -> Padding { len: 1043 } server | 1.058 DEBUG packet_sent this=0x55c474e42b40, pn=0, ps=1280 server | 1.058 DEBUG ECN probing: sent 2 probes server | 1.058 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.058 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.058 DEBUG [Server 03d23a0cd414cbb7] pn=1 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 1.058 DEBUG packet_sent this=0x55c474e42b40, pn=1, ps=1332 server | 1.058 DEBUG ECN probing: sent 3 probes server | 1.058 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.058 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.058 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.058 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.456628ms server | 1.058 DEBUG Setting timeout of 1.456628ms server | 1.058 DEBUG [Server 03d23a0cd414cbb7] pn=1 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 55 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 7 } server | 1.058 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 522659033 } server | 1.058 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.058 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.058 DEBUG [Server 03d23a0cd414cbb7] pn=2 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 34 server | TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) } server | 1.058 DEBUG ECN probing: sent 4 probes server | 1.058 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.058 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.058 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.058 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.410049ms server | 1.058 DEBUG Setting timeout of 1.410049ms server | 1.059 DEBUG [Server 03d23a0cd414cbb7] send session ticket 4063000803d23a0cd414cbb70104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a196dc87962ca7e15fb49110c000000010ada0a5a000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.059 DEBUG Path = 'hpkivgzoei' server | 1.059 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.059 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.059 DEBUG [Server 03d23a0cd414cbb7] delay duration 463.273µs server | 1.059 DEBUG Setting timeout of 463.273µs server | 1.060 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.060 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.060 DEBUG [Server 03d23a0cd414cbb7] pn=3 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.060 DEBUG packet_sent this=0x55c474e42b40, pn=3, ps=1232 server | 1.060 DEBUG ECN probing: sent 5 probes server | 1.061 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.061 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.061 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.061 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.064846ms server | 1.061 DEBUG Setting timeout of 1.064846ms server | 1.061 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.061 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.061 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.061 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.04531ms server | 1.061 DEBUG Setting timeout of 1.04531ms server | 1.063 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.063 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.063 DEBUG [Server 03d23a0cd414cbb7] pn=4 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.063 DEBUG packet_sent this=0x55c474e42b40, pn=4, ps=1232 server | 1.063 DEBUG ECN probing: sent 6 probes server | 1.063 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.063 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.063 DEBUG [Server 03d23a0cd414cbb7] pn=5 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 1.063 DEBUG packet_sent this=0x55c474e42b40, pn=5, ps=1232 server | 1.063 DEBUG ECN probing: sent 7 probes server | 1.063 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.063 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.063 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.63ms server | 1.063 DEBUG Setting timeout of 1.63ms server | 1.063 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.063 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.063 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.58672ms server | 1.063 DEBUG Setting timeout of 1.58672ms server | 1.066 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.066 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.066 DEBUG [Server 03d23a0cd414cbb7] pn=6 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false } server | 1.066 DEBUG packet_sent this=0x55c474e42b40, pn=6, ps=1232 server | 1.066 DEBUG ECN probing: sent 8 probes server | 1.066 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.066 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.066 DEBUG CRYPTO for ap offset=0, len=305 server | 1.066 DEBUG [Server 03d23a0cd414cbb7] pn=7 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 696 server | TX -> Stream { stream_id: 0, offset: 4810, len: 310, fin: true } server | TX -> Crypto { offset: 0, len: 305 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 67, 245, 223, 41, 105, 171, 63, 73, 220, 115, 205, 158, 3, 42, 154, 200, 12, 80, 222, 221, 2, 42, 243, 218, 4, 154, 96, 208, 254, 77, 73, 165, 73, 20, 83, 207] } server | 1.066 DEBUG packet_sent this=0x55c474e42b40, pn=7, ps=696 server | 1.066 DEBUG ECN probing: sent 9 probes server | 1.066 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.066 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.066 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.066 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.12986ms server | 1.066 DEBUG Setting timeout of 1.12986ms server | 1.066 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.066 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.066 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.066 DEBUG [Server 03d23a0cd414cbb7] delay duration 1.107899ms server | 1.066 DEBUG Setting timeout of 1.107899ms server | 1.068 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.068 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.068 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.068 DEBUG [Server 03d23a0cd414cbb7] delay duration 96.241492ms server | 1.068 DEBUG Setting timeout of 96.241492ms server | 1.068 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.068 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.068 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.068 DEBUG [Server 03d23a0cd414cbb7] delay duration 96.217738ms server | 1.068 DEBUG Setting timeout of 96.217738ms client | Stream 0 ended after 5120 bytes client | All done, Closing the connection. server | 1.091 DEBUG [Server 03d23a0cd414cbb7] pn=2 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 2, ack_delay: 2, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) } server | -> RX Padding { len: 19 } server | 1.091 DEBUG [Server 03d23a0cd414cbb7] Rx ACK space=ap, ranges=[0..=2] server | 1.091 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2 server | 1.091 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5624/14932 ssthresh 18446744073709551615] slow start += 2612 server | 1.091 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5624/14932 ssthresh 18446744073709551615] on_packets_acked this=0x55c474e42b40, limited=0, bytes_in_flight=5624, cwnd=14932, state=SlowStart, new_acked=2612 server | 1.091 DEBUG PMTUD probe of size 1380 succeeded server | 1.091 DEBUG PMTUD started with probe size 1420 server | 1.091 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.091 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.091 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.091 DEBUG [Server 03d23a0cd414cbb7] pn=8 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 1.091 DEBUG packet_sent this=0x55c474e42b40, pn=8, ps=1372 server | 1.091 DEBUG ECN probing: sent 10 probes server | 1.091 DEBUG ECN probing concluded with 10 probes sent server | 1.091 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.091 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG [Server 03d23a0cd414cbb7] delay duration 87.228092ms server | 1.091 DEBUG Setting timeout of 87.228092ms server | 1.091 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.091 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.091 DEBUG [Server 03d23a0cd414cbb7] delay duration 87.201883ms server | 1.091 DEBUG Setting timeout of 87.201883ms server | 1.094 DEBUG [Server 03d23a0cd414cbb7] pn=3 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 3, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) } server | -> RX Padding { len: 19 } server | 1.094 DEBUG [Server 03d23a0cd414cbb7] Rx ACK space=ap, ranges=[0..=4] server | 1.094 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4 server | 1.094 INFO ECN validation succeeded, path is capable server | 1.094 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4532/17396 ssthresh 18446744073709551615] slow start += 2464 server | 1.094 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4532/17396 ssthresh 18446744073709551615] on_packets_acked this=0x55c474e42b40, limited=0, bytes_in_flight=4532, cwnd=17396, state=SlowStart, new_acked=2464 server | 1.094 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.094 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG [Server 03d23a0cd414cbb7] delay duration 77.011324ms server | 1.094 DEBUG Setting timeout of 77.011324ms server | 1.094 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.094 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.094 DEBUG [Server 03d23a0cd414cbb7] delay duration 76.954788ms server | 1.094 DEBUG Setting timeout of 76.954788ms server | 1.097 DEBUG [Server 03d23a0cd414cbb7] pn=4 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 4, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) } server | -> RX Padding { len: 19 } server | 1.097 DEBUG [Server 03d23a0cd414cbb7] Rx ACK space=ap, ranges=[0..=6] server | 1.097 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6 server | 1.097 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2068/19860 ssthresh 18446744073709551615] slow start += 2464 server | 1.097 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2068/19860 ssthresh 18446744073709551615] on_packets_acked this=0x55c474e42b40, limited=0, bytes_in_flight=2068, cwnd=19860, state=SlowStart, new_acked=2464 server | 1.097 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.097 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG [Server 03d23a0cd414cbb7] delay duration 67.607999ms server | 1.097 DEBUG Setting timeout of 67.607999ms server | 1.097 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.097 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.097 DEBUG [Server 03d23a0cd414cbb7] delay duration 67.557324ms server | 1.097 DEBUG Setting timeout of 67.557324ms server | 1.098 DEBUG [Server 03d23a0cd414cbb7] pn=5 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect1) len 39 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 10, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 1.098 DEBUG [Server 03d23a0cd414cbb7] Rx ACK space=ap, ranges=[0..=7] server | 1.098 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7 server | 1.098 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20556 ssthresh 18446744073709551615] slow start += 696 server | 1.098 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20556 ssthresh 18446744073709551615] on_packets_acked this=0x55c474e42b40, limited=0, bytes_in_flight=1372, cwnd=20556, state=SlowStart, new_acked=696 server | 1.098 DEBUG Acked crypto frame space=ap offset=0 length=305 server | 1.098 INFO [Server 03d23a0cd414cbb7] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 1.098 DEBUG [Server 03d23a0cd414cbb7] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 336, tv_nsec: 767607168 } } server | 1.098 DEBUG [pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809] Path validated Instant { tv_sec: 336, tv_nsec: 562414557 } server | 1.098 DEBUG [Server 03d23a0cd414cbb7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.098 DEBUG Building Short dcid Some(CID [8]: db7848a7774e638a) server | 1.098 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 336, tv_nsec: 562414557 } server | 1.098 DEBUG [Server 03d23a0cd414cbb7] pn=9 type=Short pri-path:db7848a7774e638a [::]:443->[::ffff:193.167.0.100]:52809 IpTos(Cs0, Ect0) len 38 server | TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 6, Ect0: 0, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 1.098 DEBUG Setting timeout of 205.084259ms server | 1.098 DEBUG Setting timeout of 205.071445ms client | Received a request to close the connection. client | The connection is closed! client | Address Discovery mode: 0 / 0 (0:0) client | Quic Bit was greased by the client. client | Quic Bit was greased by the server. client | ECN was received (ect0: 9, ect1: 0, ce: 0). client | ECN was acknowledged (ect0: 0, ect1: 2, ce: 0). client | Received 5120 bytes in 0.114365 seconds, 0.358152 Mbps. client | Sent 17 bytes in 0.114365 seconds, 0.001189 Mbps. client | max_data_local: 1048576 client | max_stream_data_local: 2097152 client | max_data_remote: 4611686018427387903 client | max_stream_data_remote: 1048576 client | ack_delay_remote: 1000 ... 4090 client | max_ack_gap_remote: 2 client | ack_delay_local: 25000 ... 25000 client | max_ack_gap_local: 2 client | max_mtu_sent: 1252 client | max_mtu_received: 1372 client | System call duration max: 6 client | System call duration smoothed: 0 client | System call duration deviation: 0 client | Received ticket from server4 (hq-interop): client | ticket time = 1746002661733, kx = 17, suite = 1301, 301 ticket, 32 secret. client | lifetime = 172800, age_add = 5d5b85d, 2 nonce, 274 ticket, 12 extensions. client | ticket extensions: 47802 (0 bytes),42(ED: ffffffff), client | Client exit with code = 0 client | /picoquic/picoquicdemo -8 -L -l /logs/test_log.txt -q /logs/qlog -o /downloads -0 -d 180000 -a hq-interop -v 00000001 server4 443 -:/pyexhbwjco; client | Starting Picoquic (v1.1.26.1) connection to server = server4, port = 443 client | Testing scenario: <-:/pyexhbwjco;> client | Max stream id bidir remote before start = 0 (0) client | Starting client connection. Version = 1, I-CID: 9395f02fdaa7d599 client | Max stream id bidir remote after start = 60 (16) client | Max stream id bidir remote after 0rtt = 60 (16) client | Opening stream 0 to GET /pyexhbwjco client | Waiting for packets. server | 1.136 DEBUG [Server] Handle initial server | 1.136 INFO AddressValidation: valid NEW_TOKEN token; accepting server | 1.136 INFO [Server] Accept connection CID [8]: 9395f02fdaa7d599 server | 1.136 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.137 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.137 INFO [Server ...] Received valid Initial packet with scid CID [8]: 032b2261e91ea87f dcid CID [8]: 9395f02fdaa7d599 server | 1.137 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=9395f02fdaa7d599 server | 1.137 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 server | 1.137 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 server | 1.137 DEBUG [Server 9395f02fdaa7d599] State change from Init -> WaitInitial server | 1.137 DEBUG [Server 9395f02fdaa7d599] pn=86979 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 704 server | -> RX Ping server | -> RX Crypto { offset: 0, len: 610 } server | 1.137 DEBUG unmark 0-length range at 0 server | 1.137 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 336, tv_nsec: 600026691 } server | 1.137 DEBUG Read Ok(610) bytes server | 1.137 INFO 0-RTT: transport parameters OK, passing to application checker server | 1.137 WARN AllowZeroRtt accepting 0-RTT server | 1.138 DEBUG Read secret available for ZeroRtt: SymKey [32]: ba91dcb7c3284ce07a50109fd616ca51e51262c1dc6f8202d49ce21ac516cbbc server | 1.138 DEBUG Read secret available for Handshake: SymKey [32]: 17e61d4f77ad9e50bb24029f99cfce79bac2519161791ae349910b0d00965aba server | 1.138 DEBUG Write secret available for Handshake: SymKey [32]: 22e0972145e7839a3df34a5975063232a8762c96af66c7db907f7e6a1c448fc8 server | 1.138 DEBUG Writing transport parameters, msg=8 server | 1.138 DEBUG Read secret available for ApplicationData: SymKey [32]: 51bcc6fecbc1278f7cf7a68d91953ad7dd8a5945a25e7c6ae8f2bea988b5d461 server | 1.138 DEBUG Write secret available for ApplicationData: SymKey [32]: 79d7b73bbc9a86f6f5c57d753d059e67e63df6c5891eb1e06bf64379e7d64a2a server | 1.138 DEBUG [Agent 0x55c474e76880] state -> InProgress server | 1.138 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.138 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 server | 1.138 DEBUG [Crypto] Handshake keys installed server | 1.138 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.138 DEBUG [Crypto] Application write key installed server | 1.138 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 600026691 } server | 1.138 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:38618] Make permanent server | 1.138 DEBUG [unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618] set as primary path server | 1.138 DEBUG [Server 9395f02fdaa7d599] State change from WaitInitial -> Handshaking server | 1.138 DEBUG Making Read ZeroRtt CryptoDxState, v=Version1 cipher=4865 server | 1.138 DEBUG [Server 9395f02fdaa7d599] Accepted 0-RTT server | 1.138 DEBUG [Server 9395f02fdaa7d599] pn=0 type=ZeroRtt pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 548 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | -> RX Padding { len: 483 } server | 1.138 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 620026691 } server | 1.138 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.138 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.138 DEBUG CRYPTO for in offset=0, len=129 server | 1.138 DEBUG [Server 9395f02fdaa7d599] pn=0 type=Initial pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 189 server | TX -> Ack { largest_acknowledged: 86979, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | TX -> Crypto { offset: 0, len: 129 } server | 1.138 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.138 DEBUG CRYPTO for hs offset=0, len=176 server | 1.138 DEBUG [Server 9395f02fdaa7d599] pn=0 type=Handshake pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 413 server | TX -> Crypto { offset: 0, len: 176 } server | 1.138 DEBUG packet_sent this=0x55c474d80590, pn=0, ps=224 server | 1.138 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.138 DEBUG [Server 9395f02fdaa7d599] pad Initial from 413 to PLPMTU 1232 server | 1.138 DEBUG packet_sent this=0x55c474d80590, pn=0, ps=1008 server | 1.138 DEBUG ECN probing: sent 1 probes server | 1.138 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.138 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.138 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.138 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.138 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.138 DEBUG [Server 9395f02fdaa7d599] delay duration 17.311075ms server | 1.138 DEBUG Setting timeout of 17.311075ms server | 1.138 DEBUG Path = 'pyexhbwjco' server | 1.138 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.138 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.138 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.138 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.138 DEBUG [Server 9395f02fdaa7d599] pn=0 type=Short pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.139 DEBUG packet_sent this=0x55c474d80590, pn=0, ps=1232 server | 1.139 DEBUG ECN probing: sent 2 probes server | 1.139 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.139 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.139 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.139 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.139 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.139 DEBUG [Server 9395f02fdaa7d599] delay duration 2.106135ms server | 1.139 DEBUG Setting timeout of 2.106135ms server | 1.142 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.142 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.142 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.142 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.142 DEBUG [Server 9395f02fdaa7d599] pn=1 type=Short pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.142 DEBUG packet_sent this=0x55c474d80590, pn=1, ps=1232 server | 1.142 DEBUG ECN probing: sent 3 probes server | 1.142 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.142 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.142 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.142 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.142 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.142 DEBUG [Server 9395f02fdaa7d599] delay duration 13.924938ms server | 1.142 DEBUG Setting timeout of 13.924938ms server | 1.142 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.142 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.142 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.142 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.142 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.142 DEBUG [Server 9395f02fdaa7d599] delay duration 13.897717ms server | 1.142 DEBUG Setting timeout of 13.897717ms client | Client port (AF=2): 55958. client | The session was properly resumed! client | Zero RTT data is accepted! client | Negotiated ALPN: hq-interop client | Almost ready! client | client | Connection established. Version = 1, I-CID: 9395f02fdaa7d599, verified: 1 server | 1.157 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.157 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.157 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.157 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.157 DEBUG [Server 9395f02fdaa7d599] pn=2 type=Short pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 35 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 2658, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | 1.157 DEBUG ECN probing: sent 4 probes server | 1.157 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.157 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.157 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.157 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.157 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.157 DEBUG [Server 9395f02fdaa7d599] delay duration 29.978659218s server | 1.157 DEBUG Setting timeout of 146.239695ms server | 1.157 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.157 DEBUG Building Initial dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.157 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.157 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.157 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.157 DEBUG [Server 9395f02fdaa7d599] delay duration 29.978631336s server | 1.157 DEBUG Setting timeout of 146.211813ms server | 1.170 DEBUG [Server 9395f02fdaa7d599] Drop packet number space in server | 1.171 DEBUG [LossRecovery] Reset loss recovery state for Initial server | 1.171 INFO [pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618] discarding a packet without an RTT estimate; guessing RTT=34.789283ms server | 1.171 DEBUG [Server 9395f02fdaa7d599] pn=0 type=Handshake pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 94 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 32, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | -> RX Crypto { offset: 0, len: 36 } server | 1.171 DEBUG [Server 9395f02fdaa7d599] Rx ACK space=hs, ranges=[0..=0] server | 1.171 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0 server | 1.171 DEBUG on_packets_acked this=0x55c474d80590, limited=1, bytes_in_flight=2464, cwnd=12320, state=SlowStart, new_acked=224 server | 1.171 DEBUG Acked crypto frame space=hs offset=0 length=176 server | 1.171 DEBUG Read Ok(36) bytes server | 1.171 DEBUG [Agent 0x55c474e76880] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: true, early_data: true, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 }) server | 1.171 DEBUG [Server 9395f02fdaa7d599] TLS connection complete server | 1.171 DEBUG [pri-unv-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618] Path validated Instant { tv_sec: 336, tv_nsec: 634815974 } server | 1.171 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 server | 1.171 DEBUG [Crypto] application read keys installed server | 1.171 DEBUG [Server 9395f02fdaa7d599] State change from Handshaking -> Connected server | 1.171 DEBUG [Server 9395f02fdaa7d599] State change from Connected -> Confirmed server | 1.171 DEBUG PMTUD started with probe size 1380 server | 1.171 INFO [Server 9395f02fdaa7d599] Connection established server | 1.171 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 634815974 } server | 1.171 DEBUG [Server 9395f02fdaa7d599] pn=1 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 311 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [113, 172, 4, 55, 19, 170, 147, 133], stateless_reset_token: [15, 42, 152, 55, 36, 153, 59, 255, 224, 205, 240, 194, 22, 161, 132, 183] } server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [246, 58, 35, 166, 124, 246, 179, 58], stateless_reset_token: [164, 126, 90, 77, 239, 113, 22, 38, 144, 181, 234, 115, 141, 163, 122, 42] } server | -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [19, 229, 128, 7, 107, 29, 100, 244], stateless_reset_token: [5, 73, 40, 30, 69, 126, 133, 55, 213, 255, 20, 102, 233, 11, 169, 0] } server | -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [68, 159, 29, 20, 224, 27, 21, 207], stateless_reset_token: [170, 138, 21, 102, 36, 146, 246, 49, 202, 29, 34, 242, 175, 106, 100, 159] } server | -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [248, 188, 103, 251, 181, 7, 108, 171], stateless_reset_token: [229, 196, 222, 213, 253, 110, 10, 5, 83, 26, 103, 134, 242, 246, 137, 92] } server | -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [78, 205, 218, 229, 228, 24, 145, 8], stateless_reset_token: [128, 88, 234, 229, 149, 176, 69, 182, 144, 108, 89, 72, 176, 78, 34, 180] } server | -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [127, 116, 22, 12, 139, 242, 200, 136], stateless_reset_token: [118, 94, 149, 144, 123, 31, 213, 92, 139, 18, 23, 233, 90, 212, 118, 12] } server | -> RX Padding { len: 87 } server | 1.171 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 336, tv_nsec: 654815974 } server | 1.171 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.171 DEBUG Building Handshake dcid Some(CID [8]: 032b2261e91ea87f) scid Some(CID [10]: 34a28e4554eb66df74d9) server | 1.171 DEBUG [Server 9395f02fdaa7d599] pn=1 type=Handshake pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 52 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) } server | 1.171 DEBUG [Server 9395f02fdaa7d599] Drop packet number space hs server | 1.171 DEBUG [LossRecovery] Reset loss recovery state for Handshake server | 1.171 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.171 DEBUG [Server 9395f02fdaa7d599] pn=3 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [214, 95, 25, 141, 75, 106, 216, 150, 126, 60], stateless_reset_token: [246, 200, 173, 221, 13, 77, 51, 213, 176, 177, 143, 29, 43, 244, 204, 239] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [65, 3, 233, 202, 37, 184, 105, 121, 240, 156], stateless_reset_token: [101, 202, 87, 167, 31, 175, 169, 35, 78, 142, 39, 138, 238, 124, 162, 116] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [27, 158, 150, 56, 172, 51, 210, 220, 151, 61], stateless_reset_token: [249, 83, 105, 255, 185, 84, 9, 173, 45, 68, 25, 225, 253, 44, 77, 90] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [44, 45, 64, 57, 118, 209, 224, 123, 225, 91], stateless_reset_token: [231, 9, 62, 231, 156, 137, 152, 36, 63, 189, 21, 235, 132, 210, 55, 11] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [1, 239, 235, 154, 150, 63, 65, 30, 18, 210], stateless_reset_token: [143, 18, 68, 217, 102, 66, 2, 0, 1, 88, 119, 15, 48, 58, 21, 238] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [10, 219, 191, 176, 200, 6, 58, 189, 79, 82], stateless_reset_token: [158, 74, 132, 87, 85, 17, 141, 80, 95, 186, 106, 242, 185, 233, 99, 147] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [30, 34, 173, 6, 54, 136, 163, 254, 78, 68], stateless_reset_token: [83, 249, 86, 172, 255, 82, 116, 215, 241, 187, 135, 97, 224, 165, 226, 230] } server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1039, fin: false } server | 1.171 DEBUG packet_sent this=0x55c474d80590, pn=3, ps=1280 server | 1.171 DEBUG ECN probing: sent 5 probes server | 1.171 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.171 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.171 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.171 DEBUG [Server 9395f02fdaa7d599] pn=4 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 3445, len: >>1301, fin: false } server | 1.171 DEBUG packet_sent this=0x55c474d80590, pn=4, ps=1332 server | 1.171 DEBUG ECN probing: sent 6 probes server | 1.171 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.171 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.171 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.171 DEBUG [Server 9395f02fdaa7d599] delay duration 1.334676ms server | 1.171 DEBUG Setting timeout of 1.334676ms server | 1.171 DEBUG [Server 9395f02fdaa7d599] send session ticket 406300089395f02fdaa7d5990104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a34a28e4554eb66df74d9110c000000010aca7a9a000000016ab200c0000000ff02de1a0243e8200100686921 server | 1.171 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.171 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.171 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.171 DEBUG [Server 9395f02fdaa7d599] delay duration 1.217508ms server | 1.171 DEBUG Setting timeout of 1.217508ms server | 1.172 DEBUG [Server 9395f02fdaa7d599] pn=2 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 0, ack_delay: 132, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) } server | -> RX Padding { len: 18 } server | 1.172 DEBUG [Server 9395f02fdaa7d599] Rx ACK space=ap, ranges=[0..=0] server | 1.172 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0 server | 1.172 DEBUG on_packets_acked this=0x55c474d80590, limited=1, bytes_in_flight=3844, cwnd=12320, state=SlowStart, new_acked=1232 server | 1.172 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.172 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.172 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.172 DEBUG [Server 9395f02fdaa7d599] delay duration 501.818µs server | 1.172 DEBUG Setting timeout of 501.818µs server | 1.172 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.172 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.172 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.172 DEBUG [Server 9395f02fdaa7d599] delay duration 419.705µs server | 1.172 DEBUG Setting timeout of 419.705µs server | 1.174 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.174 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.174 DEBUG [Server 9395f02fdaa7d599] pn=5 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 4746, len: >>1202, fin: false } server | 1.174 DEBUG packet_sent this=0x55c474d80590, pn=5, ps=1232 server | 1.174 DEBUG ECN probing: sent 7 probes server | 1.174 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.174 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.174 DEBUG [Server 9395f02fdaa7d599] pn=6 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 5948, len: >>1202, fin: false } server | 1.174 DEBUG packet_sent this=0x55c474d80590, pn=6, ps=1232 server | 1.174 DEBUG ECN probing: sent 8 probes server | 1.174 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.174 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG [Server 9395f02fdaa7d599] delay duration 1.644677ms server | 1.174 DEBUG Setting timeout of 1.644677ms server | 1.174 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.174 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG [Server 9395f02fdaa7d599] delay duration 1.609271ms server | 1.174 DEBUG Setting timeout of 1.609271ms server | 1.174 DEBUG [Server 9395f02fdaa7d599] pn=3 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 132, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) } server | -> RX Padding { len: 18 } server | 1.174 DEBUG [Server 9395f02fdaa7d599] Rx ACK space=ap, ranges=[0..=1] server | 1.174 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1 server | 1.174 DEBUG on_packets_acked this=0x55c474d80590, limited=1, bytes_in_flight=5076, cwnd=12320, state=SlowStart, new_acked=1232 server | 1.174 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.174 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG [Server 9395f02fdaa7d599] delay duration 1.573048ms server | 1.174 DEBUG Setting timeout of 1.573048ms server | 1.174 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.174 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.174 DEBUG [Server 9395f02fdaa7d599] delay duration 1.522964ms server | 1.174 DEBUG Setting timeout of 1.522964ms server | 1.176 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.176 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.176 DEBUG [Server 9395f02fdaa7d599] pn=7 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 7150, len: >>1202, fin: false } server | 1.176 DEBUG packet_sent this=0x55c474d80590, pn=7, ps=1232 server | 1.176 DEBUG ECN probing: sent 9 probes server | 1.176 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.177 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.177 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.177 DEBUG [Server 9395f02fdaa7d599] delay duration 1.101016ms server | 1.177 DEBUG Setting timeout of 1.101016ms server | 1.177 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.177 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.177 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.177 DEBUG [Server 9395f02fdaa7d599] delay duration 1.080799ms server | 1.177 DEBUG Setting timeout of 1.080799ms server | 1.179 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.179 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.179 DEBUG [Server 9395f02fdaa7d599] pn=8 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 8352, len: >>1202, fin: false } server | 1.179 DEBUG packet_sent this=0x55c474d80590, pn=8, ps=1232 server | 1.179 DEBUG ECN probing: sent 10 probes server | 1.179 DEBUG ECN probing concluded with 10 probes sent server | 1.179 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.179 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.179 DEBUG CRYPTO for ap offset=0, len=305 server | 1.179 DEBUG [Server 9395f02fdaa7d599] pn=9 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, NotEct) len 1072 server | TX -> Stream { stream_id: 0, offset: 9554, len: 686, fin: true } server | TX -> Crypto { offset: 0, len: 305 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 251, 248, 33, 225, 228, 180, 12, 103, 205, 213, 174, 89, 243, 212, 78, 211, 102, 90, 198, 203, 170, 47, 254, 240, 10, 147, 17, 102, 146, 81, 144, 71, 192, 68, 169, 186] } server | 1.179 DEBUG packet_sent this=0x55c474d80590, pn=9, ps=1072 server | 1.179 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.179 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.179 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.179 DEBUG [Server 9395f02fdaa7d599] delay duration 1.596804ms server | 1.179 DEBUG Setting timeout of 1.596804ms server | 1.179 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.179 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.179 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.179 DEBUG [Server 9395f02fdaa7d599] delay duration 1.574722ms server | 1.179 DEBUG Setting timeout of 1.574722ms server | 1.182 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.182 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.182 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.182 DEBUG [Server 9395f02fdaa7d599] delay duration 8.593597ms server | 1.182 DEBUG Setting timeout of 8.593597ms server | 1.182 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.182 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.182 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.182 DEBUG [Server 9395f02fdaa7d599] delay duration 8.57392ms server | 1.182 DEBUG Setting timeout of 8.57392ms server | 1.192 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.192 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.192 DEBUG [Server 9395f02fdaa7d599] pn=10 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, NotEct) len 35 server | TX -> Ack { largest_acknowledged: 3, ack_delay: 2210, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 4, Ect0: 0, Ce: 0})) } server | 1.192 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.192 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.192 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.192 DEBUG [Server 9395f02fdaa7d599] delay duration 63.351039ms server | 1.192 DEBUG Setting timeout of 63.351039ms server | 1.192 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.192 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.192 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.192 DEBUG [Server 9395f02fdaa7d599] delay duration 63.327044ms server | 1.192 DEBUG Setting timeout of 63.327044ms client | Stream 0 ended after 10240 bytes client | All done, Closing the connection. server | 1.203 DEBUG [Server 9395f02fdaa7d599] pn=4 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 8, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) } server | -> RX Padding { len: 19 } server | 1.203 DEBUG [Server 9395f02fdaa7d599] Rx ACK space=ap, ranges=[0..=3] server | 1.203 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3 server | 1.203 INFO ECN validation succeeded, path is capable server | 1.203 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 7332/13600 ssthresh 18446744073709551615] slow start += 1280 server | 1.203 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 7332/13600 ssthresh 18446744073709551615] on_packets_acked this=0x55c474d80590, limited=0, bytes_in_flight=7332, cwnd=13600, state=SlowStart, new_acked=1280 server | 1.203 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.203 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.203 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.203 DEBUG [Server 9395f02fdaa7d599] delay duration 46.421553ms server | 1.203 DEBUG Setting timeout of 46.421553ms server | 1.203 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.203 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.203 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.203 DEBUG [Server 9395f02fdaa7d599] delay duration 46.342836ms server | 1.203 DEBUG Setting timeout of 46.342836ms server | 1.206 DEBUG [Server 9395f02fdaa7d599] pn=5 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 3, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) } server | -> RX Padding { len: 19 } server | 1.206 DEBUG [Server 9395f02fdaa7d599] Rx ACK space=ap, ranges=[0..=5] server | 1.206 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5 server | 1.206 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4768/16164 ssthresh 18446744073709551615] slow start += 2564 server | 1.206 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4768/16164 ssthresh 18446744073709551615] on_packets_acked this=0x55c474d80590, limited=0, bytes_in_flight=4768, cwnd=16164, state=SlowStart, new_acked=2564 server | 1.206 DEBUG PMTUD probe of size 1380 succeeded server | 1.206 DEBUG PMTUD started with probe size 1420 server | 1.206 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.206 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.206 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.206 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.206 DEBUG [Server 9395f02fdaa7d599] pn=11 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 1.206 DEBUG packet_sent this=0x55c474d80590, pn=11, ps=1372 server | 1.206 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.206 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.206 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.206 DEBUG [Server 9395f02fdaa7d599] delay duration 51.746957ms server | 1.206 DEBUG Setting timeout of 51.746957ms server | 1.206 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.206 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.206 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.206 DEBUG [Server 9395f02fdaa7d599] delay duration 51.715518ms server | 1.206 DEBUG Setting timeout of 51.715518ms server | 1.208 DEBUG [Server 9395f02fdaa7d599] pn=6 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 55 server | -> RX Ack { largest_acknowledged: 7, ack_delay: 7, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) } server | -> RX Padding { len: 19 } server | 1.208 DEBUG [Server 9395f02fdaa7d599] Rx ACK space=ap, ranges=[0..=7] server | 1.208 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7 server | 1.208 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3676/18628 ssthresh 18446744073709551615] slow start += 2464 server | 1.208 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3676/18628 ssthresh 18446744073709551615] on_packets_acked this=0x55c474d80590, limited=0, bytes_in_flight=3676, cwnd=18628, state=SlowStart, new_acked=2464 server | 1.208 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.208 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.208 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.208 DEBUG [Server 9395f02fdaa7d599] delay duration 49.494707ms server | 1.208 DEBUG Setting timeout of 49.494707ms server | 1.208 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.208 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.208 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.208 DEBUG [Server 9395f02fdaa7d599] delay duration 49.432521ms server | 1.208 DEBUG Setting timeout of 49.432521ms server | 1.211 DEBUG [Server 9395f02fdaa7d599] pn=7 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect1) len 39 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 7, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) } server | -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" } server | 1.211 DEBUG [Server 9395f02fdaa7d599] Rx ACK space=ap, ranges=[0..=9] server | 1.211 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9 server | 1.211 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20932 ssthresh 18446744073709551615] slow start += 2304 server | 1.211 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20932 ssthresh 18446744073709551615] on_packets_acked this=0x55c474d80590, limited=0, bytes_in_flight=1372, cwnd=20932, state=SlowStart, new_acked=2304 server | 1.211 DEBUG Acked crypto frame space=ap offset=0 length=305 server | 1.211 INFO [Server 9395f02fdaa7d599] ConnectionClose received. Error code: Application(0) frame type 0 reason server | 1.211 DEBUG [Server 9395f02fdaa7d599] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 336, tv_nsec: 851882357 } } server | 1.211 DEBUG [pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618] Path validated Instant { tv_sec: 336, tv_nsec: 675342575 } server | 1.211 DEBUG [Server 9395f02fdaa7d599] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.211 DEBUG Building Short dcid Some(CID [8]: 032b2261e91ea87f) server | 1.211 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 336, tv_nsec: 675342575 } server | 1.211 DEBUG [Server 9395f02fdaa7d599] pn=12 type=Short pri-path:032b2261e91ea87f [::]:443->[::ffff:193.167.0.100]:38618 IpTos(Cs0, Ect0) len 38 server | TX -> Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 8, Ect0: 0, Ce: 0})) } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" } server | 1.211 DEBUG Setting timeout of 92.12373ms server | 1.211 DEBUG Setting timeout of 92.112209ms client | Received a request to close the connection. client | The connection is closed! client | Out of 1 zero RTT packets, 1 were acked by the server. client | Address Discovery mode: 0 / 0 (0:0) client | Quic Bit was greased by the client. client | Quic Bit was greased by the server. client | ECN was received (ect0: 10, ect1: 0, ce: 0). client | ECN was acknowledged (ect0: 0, ect1: 1, ce: 0). client | Received 10240 bytes in 0.108910 seconds, 0.752181 Mbps. client | Sent 17 bytes in 0.108910 seconds, 0.001249 Mbps. client | max_data_local: 1048576 client | max_stream_data_local: 2097152 client | max_data_remote: 4611686018427387903 client | max_stream_data_remote: 1048576 client | ack_delay_remote: 1000 ... 4070 client | max_ack_gap_remote: 2 client | ack_delay_local: 25000 ... 25000 client | max_ack_gap_local: 2 client | max_mtu_sent: 1252 client | max_mtu_received: 1372 client | System call duration max: 24 client | System call duration smoothed: 1 client | System call duration deviation: 0 client | Received ticket from server4 (hq-interop): client | ticket time = 1746002661846, kx = 17, suite = 1301, 301 ticket, 32 secret. client | lifetime = 172800, age_add = 7ad18f95, 2 nonce, 274 ticket, 12 extensions. client | ticket extensions: 39578 (0 bytes),42(ED: ffffffff), client | Client exit with code = 0 server | 1.304 DEBUG [Server 03d23a0cd414cbb7] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 336, tv_nsec: 767607168 } } -> Closed(Transport(PeerApplicationError(0))) server | 1.304 INFO Closing timer expired server | 1.304 DEBUG Setting timeout of 83.922104ms server | 1.304 DEBUG Setting timeout of 83.550069ms client exited with code 0 Aborting on container exit... Container client Stopping Container server Stopping Container client Stopped Container server Stopped Container sim Stopping Container sim Stopped 2025-04-30 08:44:32,299 Using the client's key log file. 2025-04-30 08:44:32,308 2025-04-30 08:44:32,309 Using the client's key log file. 2025-04-30 08:44:32,309 Using the client's key log file. 2025-04-30 08:44:32,317 2025-04-30 08:44:32,317 Using the client's key log file. 2025-04-30 08:44:32,317 Using the client's key log file. 2025-04-30 08:44:33,173 Check of downloaded files succeeded.