2025-09-11 19:39:29,002 Generated random file: yclukzismw of size: 5120 2025-09-11 19:39:29,002 Generated random file: rwzibgjmee of size: 10240 2025-09-11 19:39:29,002 Requests: https://server4:443/yclukzismw https://server4:443/rwzibgjmee 2025-09-11 19:39:29,058 2025-09-11 19:39:29,058 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_izlhmkck/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_0ucfcrdr/ DOWNLOADS=/tmp/download_d56jo5sc/ SERVER_LOGS=/tmp/logs_server_qtoae7pn CLIENT_LOGS=/tmp/logs_client_fyd2h1na SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/alibaba/xquic/xquic-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/yclukzismw https://server4:443/rwzibgjmee" docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 2025-09-11 19:39:42,551 Container client Recreate Container server Recreate Container server Recreated Container client Recreated Attaching to client, server, sim sim | waiting 10s for server:443 client | Setting up routes... client | Actual changes: client | tx-checksum-ip-generic: off client | tx-tcp-segmentation: off [not requested] client | tx-tcp-ecn-segmentation: off [not requested] client | tx-tcp-mangleid-segmentation: off [not requested] client | tx-tcp6-segmentation: off [not requested] client | tx-udp-segmentation: off [not requested] client | tx-checksum-sctp: off client | Endpoint's IPv4 address is 193.167.0.100 client | Endpoint's IPv6 address is fd00:cafe:cafe::100 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/ ']' client | wait-for-it.sh: waiting 30 seconds for sim:57832 server | + case "$ROLE" in server | + DB=/neqo/db server | + CERT=cert server | ++ mktemp server | + P12CERT=/tmp/tmp.oPAUWDWkPF server | + mkdir -p /neqo/db server | + certutil -N -d sql:/neqo/db --empty-password server | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.oPAUWDWkPF server | Warning: output encryption option -nodes ignored with -export server | + pk12util -d sql:/neqo/db -i /tmp/tmp.oPAUWDWkPF -W '' server | pk12util: PKCS12 IMPORT SUCCESSFUL server | + certutil -L -d sql:/neqo/db -n cert server | Certificate: server | Data: server | Version: 3 (0x2) server | Serial Number: server | 2f:78:8b:79:91:3c:a2:96:9f:40:06:65:d2:42:33:29: server | 69:53:29:39 server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Issuer: "O=interop runner Root Certificate Authority" server | Validity: server | Not Before: Thu Sep 11 19:39:29 2025 server | Not After : Sun Sep 21 19:39: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:31:20:81:1c:b2:b2:6c:4e:81:90:d2:4c:16:97:ab: server | 1d:20:24:08:5c:db:46:ec:22:53:20:87:07:93:6b:2d: server | 66:a6:e5:e0:6a:ee:33:c5:4e:8e:7c:b0:1d:5b:2e:7f: server | 68:dd:4f:57:8e:af:df:66:45:e1:ae:dc:f8:da:5a:04: server | 75 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 | 19:e1:92:50:12:dc:7b:27:6f:62:48:ba:5d:80:1a:db: server | 82:b1:ea:10 server | server | Name: Certificate Authority Key Identifier server | Key ID: server | 72:a1:d2:40:84:cb:b9:87:74:70:47:5b:86:1c:a8:93: server | 07:d2:a2:c6 server | server | Signature Algorithm: X9.62 ECDSA signature with SHA256 server | Signature: server | 30:44:02:20:3d:9d:ee:5b:fd:e1:0e:43:14:16:01:07: server | 50:e1:88:f4:36:f2:02:99:f4:c5:06:94:00:4d:21:2a: server | f0:a8:43:84:02:20:31:cf:bc:dd:ef:1e:47:08:a3:80: server | 38:81:36:09:77:c9:e6:16:75:0f:c3:d4:c7:68:39:0f: server | 0e:a7:4c:8f:6d:46 server | Fingerprint (SHA-256): server | A5:31:35:6B:03:80:E8:C1:63:5D:35:F8:0A:13:D7:46:EA:62:CE:19:88:C0:23:FB:BF:65:A9:A9:C9:74:E7:E7 server | Fingerprint (SHA1): server | 83:27:A8:59:F6:89:30:65:4A:4F:54:F4:C9:C8:89:83:65:6E:FD:6B 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 Ok(212992) server | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing server | 0.002 INFO Server waiting for connection on: Ok([::]:443) server | 0.291 DEBUG [Server] Unsupported version: 57414954 server | 0.291 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:32902 Tos(Cs0, NotEct) len 15 sim | server:443 is available after 501.61024ms sim | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25 sim | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes sim | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes client | wait-for-it.sh: sim:57832 is available after 1 seconds client | option log level :d client | option log directory :/logs/client.log client | option save body dir: /downloads client | key output file: /logs/keys.log client | client life circle time: 30 client | set interop mode client | option url only:https://server4:443/yclukzismw client | server[server4] addr: 193.167.100.100:443. client | No.0 path created id = 0 client | open file[/downloads/yclukzismw] suc server | 0.988 DEBUG [Server] Handle initial server | 0.988 INFO AddressValidation: no token; accepting server | 0.988 INFO [Server] Accept connection CID [8]: 9c568bb9570c1b80 server | 0.988 DEBUG Overwrite initial version Version1 ==> Version1 server | 0.989 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 0.989 INFO [Server ...] Received valid Initial packet with scid CID [8]: 8bc1fd4ac31d2a00 dcid CID [8]: 9c568bb9570c1b80 server | 0.989 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=9c568bb9570c1b80 server | 0.990 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=31 server | 0.990 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.990 DEBUG [Server 9c568bb9570c1b80] State change from Init -> WaitInitial server | 0.990 DEBUG [Server 9c568bb9570c1b80] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 1200 server | -> RX Crypto { offset: 0, len: 305 } server | -> RX Padding { len: 845 } server | 0.990 DEBUG 0-RTT: no token, no 0-RTT server | 0.990 DEBUG Read secret available for Handshake: SymKey [32]: a8666993913f489dec0a11e367056178e93bdd8b7b68a87ba690193a022bc059 server | 0.990 DEBUG Write secret available for Handshake: SymKey [32]: 70809b877a71e976fd6cbf5fe64bb29977e9335f411e2e4436cf8fab33a03412 server | 0.991 DEBUG Writing transport parameters, msg=8 server | 0.991 DEBUG Read secret available for ApplicationData: SymKey [32]: ecbe57559fcaa6ffda7091df58a596a3082f8971f8e24eb2d3e0d49a1b833651 server | 0.991 DEBUG Write secret available for ApplicationData: SymKey [32]: 03a56fab1d48885e431241868b0b6d5b0e4a380508cdfd67dbc8754bda4ef00e server | 0.991 DEBUG [Agent 0x55603e274650] state -> InProgress server | 0.991 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.991 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.991 DEBUG [Crypto] Handshake keys installed server | 0.991 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 0.991 DEBUG [Crypto] Application write key installed server | 0.991 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 383, tv_nsec: 375231492 } server | 0.991 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:45316] Make permanent server | 0.991 DEBUG [unv-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316] set as primary path server | 0.991 DEBUG [Server 9c568bb9570c1b80] State change from WaitInitial -> Handshaking server | 0.991 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.991 DEBUG Building Initial dcid Some(CID [8]: 8bc1fd4ac31d2a00) scid Some(CID [10]: 0748dc7d899602204453) server | 0.991 DEBUG CRYPTO for in offset=0, len=123 server | 0.991 DEBUG [Server 9c568bb9570c1b80] pn=31 type=Initial pri-unv-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 177 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 123 } server | 0.991 DEBUG Building Handshake dcid Some(CID [8]: 8bc1fd4ac31d2a00) scid Some(CID [10]: 0748dc7d899602204453) server | 0.991 DEBUG CRYPTO for hs offset=0, len=724 server | 0.991 DEBUG [Server 9c568bb9570c1b80] pn=0 type=Handshake pri-unv-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 949 server | TX -> Crypto { offset: 0, len: 724 } server | 0.991 DEBUG packet_sent this=0x55603e2746d0, pn=0, ps=772 server | 0.991 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 0.991 DEBUG [Server 9c568bb9570c1b80] pad Initial from 949 to PLPMTU 1232 server | 0.991 DEBUG packet_sent this=0x55603e2746d0, pn=31, ps=460 server | 0.991 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.991 DEBUG Building Initial dcid Some(CID [8]: 8bc1fd4ac31d2a00) scid Some(CID [10]: 0748dc7d899602204453) server | 0.991 DEBUG Building Handshake dcid Some(CID [8]: 8bc1fd4ac31d2a00) scid Some(CID [10]: 0748dc7d899602204453) server | 0.991 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 0.991 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.991 DEBUG [Server 9c568bb9570c1b80] delay duration 296.804228ms server | 0.991 DEBUG Setting timeout of 296.804228ms server | 0.991 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.991 DEBUG Building Initial dcid Some(CID [8]: 8bc1fd4ac31d2a00) scid Some(CID [10]: 0748dc7d899602204453) server | 0.991 DEBUG Building Handshake dcid Some(CID [8]: 8bc1fd4ac31d2a00) scid Some(CID [10]: 0748dc7d899602204453) server | 0.991 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 0.991 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 0.991 DEBUG [Server 9c568bb9570c1b80] delay duration 296.768281ms server | 0.991 DEBUG Setting timeout of 296.768281ms server | 1.025 DEBUG [Server 9c568bb9570c1b80] Drop packet number space in server | 1.025 DEBUG [recovery::Loss] Reset loss recovery state for Initial server | 1.025 INFO [pri-unv-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316] discarding a packet without an RTT estimate; guessing RTT=37.068387ms server | 1.025 DEBUG [Server 9c568bb9570c1b80] pn=0 type=Handshake pri-unv-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 86 server | -> RX Crypto { offset: 0, len: 36 } server | 1.025 DEBUG [Agent 0x55603e274650] 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.025 DEBUG [Server 9c568bb9570c1b80] TLS connection complete server | 1.025 DEBUG [pri-unv-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316] Path validated Instant { tv_sec: 383, tv_nsec: 412299879 } server | 1.025 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.025 DEBUG [Crypto] application read keys installed server | 1.025 DEBUG [Server 9c568bb9570c1b80] State change from Handshaking -> Connected server | 1.025 DEBUG [Server 9c568bb9570c1b80] State change from Connected -> Confirmed server | 1.025 DEBUG PMTUD started with probe size 1380 server | 1.025 INFO [Server 9c568bb9570c1b80] Connection established server | 1.025 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 383, tv_nsec: 412299879 } server | 1.025 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.025 DEBUG Building Handshake dcid Some(CID [8]: 8bc1fd4ac31d2a00) scid Some(CID [10]: 0748dc7d899602204453) server | 1.025 DEBUG [Server 9c568bb9570c1b80] pn=1 type=Handshake pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 49 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.025 DEBUG [Server 9c568bb9570c1b80] Drop packet number space hs server | 1.025 DEBUG [recovery::Loss] Reset loss recovery state for Handshake server | 1.025 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.025 DEBUG [Server 9c568bb9570c1b80] pn=0 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [51, 69, 238, 243, 251, 173, 113, 13, 254, 223], stateless_reset_token: [33, 224, 255, 230, 107, 128, 41, 142, 114, 117, 32, 190, 177, 89, 172, 93] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [134, 170, 128, 135, 87, 17, 205, 103, 198, 241], stateless_reset_token: [210, 5, 141, 143, 223, 248, 18, 35, 7, 237, 255, 34, 58, 213, 60, 51] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [204, 43, 187, 73, 58, 37, 165, 141, 141, 49], stateless_reset_token: [111, 127, 160, 247, 7, 204, 117, 238, 149, 18, 39, 205, 114, 226, 132, 110] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [237, 209, 83, 198, 86, 89, 226, 50, 159, 178], stateless_reset_token: [124, 126, 249, 52, 10, 76, 105, 169, 104, 223, 64, 186, 40, 199, 170, 73] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [180, 215, 200, 13, 176, 84, 3, 0, 172, 103], stateless_reset_token: [88, 16, 122, 130, 83, 59, 245, 51, 139, 149, 113, 174, 128, 114, 46, 88] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [5, 55, 56, 220, 177, 32, 59, 1, 242, 63], stateless_reset_token: [94, 242, 25, 24, 175, 237, 146, 171, 186, 138, 44, 169, 119, 88, 245, 29] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [16, 185, 207, 130, 214, 105, 150, 58, 237, 33], stateless_reset_token: [232, 29, 109, 26, 87, 58, 69, 29, 237, 244, 172, 145, 208, 79, 215, 168] } server | TX -> Padding { len: 1046 } server | 1.025 DEBUG packet_sent this=0x55603e2746d0, pn=0, ps=1283 server | 1.025 DEBUG ECN probing: sent 1 probes server | 1.025 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.025 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.025 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.025 DEBUG [Server 9c568bb9570c1b80] pn=1 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Padding { len: 1305 } server | 1.025 DEBUG packet_sent this=0x55603e2746d0, pn=1, ps=1332 server | 1.025 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.025 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.025 DEBUG ECN probing: sent 2 probes server | 1.026 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG [Server 9c568bb9570c1b80] delay duration 1.661798ms server | 1.026 DEBUG Setting timeout of 1.661798ms server | 1.026 DEBUG [Server 9c568bb9570c1b80] send session ticket 406400089c568bb9570c1b800104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a0748dc7d899602204453110c00000001cada2aba000000016ab200c0000000ff02de1a0243e8200244b0686921 server | 1.026 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG [Server 9c568bb9570c1b80] delay duration 743.108µs server | 1.026 DEBUG Setting timeout of 743.108µs server | 1.026 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 1 server | 1.026 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG [Server 9c568bb9570c1b80] delay duration 705.308µs server | 1.026 DEBUG Setting timeout of 705.308µs server | 1.026 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 2 server | 1.026 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.026 DEBUG [Server 9c568bb9570c1b80] delay duration 680.05µs server | 1.026 DEBUG Setting timeout of 680.05µs server | 1.027 DEBUG [Server 9c568bb9570c1b80] pn=0 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 52 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 1.027 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 383, tv_nsec: 433720568 } server | 1.027 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.027 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.027 DEBUG [Server 9c568bb9570c1b80] delay duration 660.624µs server | 1.027 DEBUG Setting timeout of 660.624µs server | 1.027 DEBUG Path = 'yclukzismw' server | 1.027 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.027 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.027 DEBUG [Server 9c568bb9570c1b80] delay duration 576.086µs server | 1.027 DEBUG Setting timeout of 576.086µs server | 1.028 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.028 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.028 DEBUG [Server 9c568bb9570c1b80] pn=2 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.028 DEBUG packet_sent this=0x55603e2746d0, pn=2, ps=1232 server | 1.028 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.028 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.028 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.028 DEBUG ECN probing: sent 3 probes server | 1.028 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.028 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.028 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.028 DEBUG [Server 9c568bb9570c1b80] delay duration 1.272057ms server | 1.028 DEBUG Setting timeout of 1.272057ms server | 1.028 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.028 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.028 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.028 DEBUG [Server 9c568bb9570c1b80] delay duration 1.254775ms server | 1.028 DEBUG Setting timeout of 1.254775ms server | 1.030 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.030 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.030 DEBUG [Server 9c568bb9570c1b80] pn=3 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.030 DEBUG packet_sent this=0x55603e2746d0, pn=3, ps=1232 server | 1.030 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.030 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.030 DEBUG ECN probing: sent 4 probes server | 1.030 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.030 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.030 DEBUG [Server 9c568bb9570c1b80] delay duration 959.607µs server | 1.030 DEBUG Setting timeout of 959.607µs server | 1.030 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.030 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.030 DEBUG [Server 9c568bb9570c1b80] delay duration 942.886µs server | 1.030 DEBUG Setting timeout of 942.886µs server | 1.032 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.032 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.032 DEBUG [Server 9c568bb9570c1b80] pn=4 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false } server | 1.032 DEBUG packet_sent this=0x55603e2746d0, pn=4, ps=1232 server | 1.032 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.032 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.032 DEBUG [Server 9c568bb9570c1b80] pn=5 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false } server | 1.032 DEBUG packet_sent this=0x55603e2746d0, pn=5, ps=1232 server | 1.032 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG ECN probing: sent 6 probes server | 1.032 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG [Server 9c568bb9570c1b80] delay duration 1.718897ms server | 1.032 DEBUG Setting timeout of 1.718897ms server | 1.032 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.032 DEBUG [Server 9c568bb9570c1b80] delay duration 1.697166ms server | 1.032 DEBUG Setting timeout of 1.697166ms server | 1.035 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.035 DEBUG CRYPTO for ap offset=0, len=305 server | 1.035 DEBUG [Server 9c568bb9570c1b80] pn=6 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(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, 168, 70, 251, 91, 215, 79, 9, 211, 192, 212, 89, 213, 193, 176, 95, 46, 97, 37, 182, 119, 118, 233, 202, 114, 133, 19, 235, 153, 118, 201, 104, 174, 171, 239, 54, 172] } server | 1.035 DEBUG packet_sent this=0x55603e2746d0, pn=6, ps=696 server | 1.035 DEBUG ECN probing: sent 7 probes server | 1.035 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.035 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG [Server 9c568bb9570c1b80] delay duration 11.139633ms server | 1.035 DEBUG Setting timeout of 11.139633ms server | 1.035 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.035 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.035 DEBUG [Server 9c568bb9570c1b80] delay duration 11.11086ms server | 1.035 DEBUG Setting timeout of 11.11086ms server | 1.047 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.047 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.047 DEBUG [Server 9c568bb9570c1b80] pn=7 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 32 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 2622, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.048 DEBUG ECN probing: sent 8 probes server | 1.048 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.048 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.048 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.048 DEBUG [Server 9c568bb9570c1b80] delay duration 123.923809ms server | 1.048 DEBUG Setting timeout of 123.923809ms server | 1.048 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.048 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.048 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.048 DEBUG [Server 9c568bb9570c1b80] delay duration 123.901377ms server | 1.048 DEBUG Setting timeout of 123.901377ms client | >>>>>>>> request time cost:84404 us, speed:60 K/s client | >>>>>>>> user_stream[0x55749c5cb2f0], req: /yclukzismw, send_body_size:11, recv_body_size:5120 server | 1.057 DEBUG [Server 9c568bb9570c1b80] pn=1 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [19, 7, 121, 64, 183, 9, 53, 213], stateless_reset_token: [32, 5, 82, 66, 229, 182, 134, 161, 96, 237, 43, 37, 231, 141, 118, 41] } server | 1.057 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 383, tv_nsec: 464500558 } server | 1.057 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.057 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.057 DEBUG [Server 9c568bb9570c1b80] delay duration 20ms server | 1.057 DEBUG Setting timeout of 20ms server | 1.057 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.057 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.057 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.057 DEBUG [Server 9c568bb9570c1b80] delay duration 19.950046ms server | 1.057 DEBUG Setting timeout of 19.950046ms server | 1.057 DEBUG [Server 9c568bb9570c1b80] pn=2 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [117, 121, 3, 41, 160, 9, 176, 48], stateless_reset_token: [56, 77, 49, 208, 140, 159, 141, 126, 46, 25, 52, 116, 121, 203, 69, 75] } server | 1.057 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 383, tv_nsec: 444608320 } server | 1.057 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.057 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.057 DEBUG [Server 9c568bb9570c1b80] pn=8 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 31 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 1.057 DEBUG ECN probing: sent 9 probes server | 1.057 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.058 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG [Server 9c568bb9570c1b80] delay duration 113.972249ms server | 1.058 DEBUG Setting timeout of 113.972249ms server | 1.058 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.058 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG [Server 9c568bb9570c1b80] delay duration 113.945269ms server | 1.058 DEBUG Setting timeout of 113.945269ms server | 1.058 DEBUG [Server 9c568bb9570c1b80] pn=3 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 14, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.058 DEBUG [Server 9c568bb9570c1b80] Rx ACK space=ap, ranges=[0..=1] server | 1.058 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=1 server | 1.058 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5624/14935 ssthresh 18446744073709551615] slow start += 2615 server | 1.058 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5624/14935 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=5624, cwnd=14935, state=SlowStart, new_acked=2615 server | 1.058 DEBUG PMTUD probe of size 1380 succeeded server | 1.058 DEBUG PMTUD started with probe size 1420 server | 1.058 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.058 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.058 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.058 DEBUG [Server 9c568bb9570c1b80] pn=9 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 1.058 DEBUG packet_sent this=0x55603e2746d0, pn=9, ps=1372 server | 1.058 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.058 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG ECN probing: sent 10 probes server | 1.058 DEBUG ECN probing concluded with 10 probes sent server | 1.058 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.058 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG [Server 9c568bb9570c1b80] delay duration 121.384258ms server | 1.058 DEBUG Setting timeout of 121.384258ms server | 1.058 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.058 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.058 DEBUG [Server 9c568bb9570c1b80] delay duration 121.357739ms server | 1.058 DEBUG Setting timeout of 121.357739ms server | 1.061 DEBUG [Server 9c568bb9570c1b80] pn=11 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 3, ack_delay: 13, first_ack_range: 3, ack_ranges: [], ecn_count: None } server | 1.061 DEBUG [Server 9c568bb9570c1b80] Rx ACK space=ap, ranges=[0..=3] server | 1.061 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=3 server | 1.061 WARN ECN validation failed, no ECN counts in ACK frame server | 1.061 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4532/17399 ssthresh 18446744073709551615] slow start += 2464 server | 1.061 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4532/17399 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=4532, cwnd=17399, state=SlowStart, new_acked=2464 server | 1.061 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.061 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.061 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.061 DEBUG [Server 9c568bb9570c1b80] delay duration 107.600239ms server | 1.061 DEBUG Setting timeout of 107.600239ms server | 1.061 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.061 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.061 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.061 DEBUG [Server 9c568bb9570c1b80] delay duration 107.541129ms server | 1.061 DEBUG Setting timeout of 107.541129ms server | 1.065 DEBUG [Server 9c568bb9570c1b80] pn=12 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 5, ack_delay: 13, first_ack_range: 5, ack_ranges: [], ecn_count: None } server | 1.065 DEBUG [Server 9c568bb9570c1b80] Rx ACK space=ap, ranges=[0..=5] server | 1.065 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=5 server | 1.065 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2068/19863 ssthresh 18446744073709551615] slow start += 2464 server | 1.065 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2068/19863 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=2068, cwnd=19863, state=SlowStart, new_acked=2464 server | 1.065 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.065 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG [Server 9c568bb9570c1b80] delay duration 94.915161ms server | 1.065 DEBUG Setting timeout of 94.915161ms server | 1.065 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.065 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.065 DEBUG [Server 9c568bb9570c1b80] delay duration 94.860899ms server | 1.065 DEBUG Setting timeout of 94.860899ms server | 1.090 DEBUG [Server 9c568bb9570c1b80] pn=13 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 9, ack_delay: 10, first_ack_range: 9, ack_ranges: [], ecn_count: None } server | 1.090 DEBUG [Server 9c568bb9570c1b80] Rx ACK space=ap, ranges=[0..=9] server | 1.090 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=9 server | 1.090 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/21931 ssthresh 18446744073709551615] slow start += 2068 server | 1.090 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/21931 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=0, cwnd=21931, state=SlowStart, new_acked=2068 server | 1.090 DEBUG PMTUD probe of size 1420 succeeded server | 1.090 DEBUG PMTUD started with probe size 1470 server | 1.090 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 1.090 DEBUG Acked crypto frame space=ap offset=0 length=305 server | 1.090 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.090 DEBUG Sending PMTUD probe of size 1470, count 1 server | 1.090 DEBUG [Server 9c568bb9570c1b80] pn=10 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 1422 server | TX -> Ping server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | TX -> Padding { len: 1393 } server | 1.090 DEBUG packet_sent this=0x55603e2746d0, pn=10, ps=1422 server | 1.090 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.090 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.090 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG [Server 9c568bb9570c1b80] delay duration 94.327422ms server | 1.090 DEBUG Setting timeout of 94.327422ms server | 1.090 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.090 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.090 DEBUG [Server 9c568bb9570c1b80] delay duration 94.245047ms server | 1.090 DEBUG Setting timeout of 94.245047ms server | 1.147 DEBUG [Server 9c568bb9570c1b80] pn=14 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 10, ack_delay: 3210, first_ack_range: 10, ack_ranges: [], ecn_count: None } server | 1.147 DEBUG [Server 9c568bb9570c1b80] Rx ACK space=ap, ranges=[0..=10] server | 1.147 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=10 server | 1.147 DEBUG on_packets_acked this=0x55603e2746d0, limited=1, bytes_in_flight=0, cwnd=21931, state=SlowStart, new_acked=1422 server | 1.147 DEBUG PMTUD probe of size 1470 succeeded server | 1.147 DEBUG PMTUD started with probe size 1500 server | 1.147 DEBUG PLPMTU changed from 1372 to 1422, updating pacer server | 1.147 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.147 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.147 DEBUG Sending PMTUD probe of size 1500, count 1 server | 1.147 DEBUG [Server 9c568bb9570c1b80] pn=11 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 1452 server | TX -> Ping server | TX -> Padding { len: 1425 } server | 1.147 DEBUG packet_sent this=0x55603e2746d0, pn=11, ps=1452 server | 1.147 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.147 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.147 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.147 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.147 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.147 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.147 DEBUG [Server 9c568bb9570c1b80] delay duration 87.975534ms server | 1.147 DEBUG Setting timeout of 87.975534ms server | 1.147 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.147 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.147 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.147 DEBUG [Server 9c568bb9570c1b80] delay duration 87.945889ms server | 1.147 DEBUG Setting timeout of 87.945889ms server | 1.205 DEBUG [Server 9c568bb9570c1b80] pn=15 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 11, ack_delay: 3216, first_ack_range: 11, ack_ranges: [], ecn_count: None } server | 1.205 DEBUG [Server 9c568bb9570c1b80] Rx ACK space=ap, ranges=[0..=11] server | 1.205 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=11 server | 1.205 DEBUG on_packets_acked this=0x55603e2746d0, limited=1, bytes_in_flight=0, cwnd=21931, state=SlowStart, new_acked=1452 server | 1.205 DEBUG PMTUD probe of size 1500 succeeded server | 1.205 INFO PMTUD stopped, PLPMTU is now 1500, raise timer Some(Instant { tv_sec: 983, tv_nsec: 591923263 }) server | 1.205 DEBUG PLPMTU changed from 1422 to 1452, updating pacer server | 1.205 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.205 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.205 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.205 DEBUG [Server 9c568bb9570c1b80] delay duration 30s server | 1.205 DEBUG Setting timeout of 30s server | 1.205 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.205 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.205 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.205 DEBUG [Server 9c568bb9570c1b80] delay duration 29.999934608s server | 1.205 DEBUG Setting timeout of 29.999934608s client | [HQ-req] send_bytes:17, recv_bytes:5120, path_info:0-1-5-17-5120 client | task[0], fin_cnt: 1, fin_flag: 1 server | 1.482 DEBUG [Server 9c568bb9570c1b80] pn=16 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 35 server | -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" } server | 1.482 INFO [Server 9c568bb9570c1b80] ConnectionClose received. Error code: Transport(0) frame type 0 reason server | 1.482 DEBUG [Server 9c568bb9570c1b80] State change from Confirmed -> Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 384, tv_nsec: 118225763 } } server | 1.482 DEBUG [pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316] Path validated Instant { tv_sec: 383, tv_nsec: 869519760 } server | 1.482 DEBUG [Server 9c568bb9570c1b80] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 1.482 DEBUG Building Short dcid Some(CID [8]: 8bc1fd4ac31d2a00) server | 1.482 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 383, tv_nsec: 869519760 } server | 1.482 DEBUG [Server 9c568bb9570c1b80] pn=12 type=Short pri-path:8bc1fd4ac31d2a00 [::]:443->[::ffff:193.167.0.100]:45316 Tos(Cs0, NotEct) len 37 server | TX -> Ack { largest_acknowledged: 16, ack_delay: 0, first_ack_range: 5, ack_ranges: [AckRange { gap: 6, range: 3 }], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" } server | 1.482 DEBUG Setting timeout of 248.594806ms server | 1.482 DEBUG Setting timeout of 248.583174ms server | 1.513 DEBUG [Server ...] Dropped received packet: State Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 384, tv_nsec: 118225763 } }; Total: 3 server | 1.513 DEBUG Setting timeout of 218.072177ms server | 1.513 DEBUG Setting timeout of 218.050947ms server | 1.732 DEBUG [Server 9c568bb9570c1b80] State change from Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 384, tv_nsec: 118225763 } } -> Closed(Transport(Peer(0))) server | 1.732 INFO Closing timer expired client | task finished, total task_req_cnt: 1, req_fin_cnt: 1, req_sent_cnt: 0, req_create_cnt: 1 client | all tasks are finished, will break loop and exit client | client | option log level :d client | option log directory :/logs/client.log client | option save body dir: /downloads client | key output file: /logs/keys.log client | client life circle time: 30 client | set interop mode client | option url only:https://server4:443/rwzibgjmee client | server[server4] addr: 193.167.100.100:443. client | No.0 path created id = 0 client | open file[/downloads/rwzibgjmee] suc server | 1.842 DEBUG [Server] Handle initial server | 1.842 INFO AddressValidation: valid NEW_TOKEN token; accepting server | 1.842 INFO [Server] Accept connection CID [8]: 40ee20c76806f255 server | 1.842 DEBUG Overwrite initial version Version1 ==> Version1 server | 1.844 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500 server | 1.844 INFO [Server ...] Received valid Initial packet with scid CID [8]: 76971f5c6febcb76 dcid CID [8]: 40ee20c76806f255 server | 1.844 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=40ee20c76806f255 server | 1.844 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=18 server | 1.844 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.844 DEBUG [Server 40ee20c76806f255] State change from Init -> WaitInitial server | 1.844 DEBUG [Server 40ee20c76806f255] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 1200 server | -> RX Crypto { offset: 0, len: 630 } server | -> RX Padding { len: 477 } server | 1.844 INFO 0-RTT: transport parameters OK, passing to application checker server | 1.844 WARN AllowZeroRtt accepting 0-RTT server | 1.844 DEBUG Read secret available for ZeroRtt: SymKey [32]: 989abe31e16718528781e5d79ad88a1a4ac0344074bbbb5e69e9fdf418bb324e server | 1.844 DEBUG Read secret available for Handshake: SymKey [32]: bc82b56135bd519794e3700cca92dbb7de91d579dbef2060cc3ce73e3e740056 server | 1.844 DEBUG Write secret available for Handshake: SymKey [32]: bf1df3fabad22fccde140d4b5b51f408934bb5bdb9e389ce3ae2f25d94248520 server | 1.845 DEBUG Writing transport parameters, msg=8 server | 1.845 DEBUG Read secret available for ApplicationData: SymKey [32]: 9e73b58be95a24965755fcc81316fbede7ab311847b3dba526622cd3c5de9606 server | 1.845 DEBUG Write secret available for ApplicationData: SymKey [32]: 1c3d35008db130d63bb09c2c1332c5e4486a1d476066fe54e3ef6e1e09b7473a server | 1.845 DEBUG [Agent 0x55603e295dd0] state -> InProgress server | 1.845 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.845 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.845 DEBUG [Crypto] Handshake keys installed server | 1.845 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.845 DEBUG [Crypto] Application write key installed server | 1.845 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 229315719 } server | 1.845 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:42282] Make permanent server | 1.845 DEBUG [unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282] set as primary path server | 1.845 DEBUG [Server 40ee20c76806f255] State change from WaitInitial -> Handshaking server | 1.845 DEBUG Making Read ZeroRtt CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.845 DEBUG [Server 40ee20c76806f255] Accepted 0-RTT server | 1.845 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.845 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG CRYPTO for in offset=0, len=129 server | 1.845 DEBUG [Server 40ee20c76806f255] pn=18 type=Initial pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 183 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | TX -> Crypto { offset: 0, len: 129 } server | 1.845 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG CRYPTO for hs offset=0, len=181 server | 1.845 DEBUG [Server 40ee20c76806f255] pn=0 type=Handshake pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 412 server | TX -> Crypto { offset: 0, len: 181 } server | 1.845 DEBUG packet_sent this=0x55603e2746d0, pn=0, ps=229 server | 1.845 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.845 DEBUG [Server 40ee20c76806f255] pad Initial from 412 to PLPMTU 1232 server | 1.845 DEBUG packet_sent this=0x55603e2746d0, pn=18, ps=1003 server | 1.845 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.845 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.845 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.845 DEBUG [Server 40ee20c76806f255] delay duration 297.092968ms server | 1.845 DEBUG Setting timeout of 297.092968ms server | 1.845 DEBUG [Server 40ee20c76806f255] pn=0 type=ZeroRtt pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 66 server | -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true } server | 1.845 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 252260792 } server | 1.845 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.845 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.845 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.845 DEBUG [Server 40ee20c76806f255] delay duration 20ms server | 1.845 DEBUG Setting timeout of 20ms server | 1.845 DEBUG Path = 'rwzibgjmee' server | 1.845 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.845 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.845 DEBUG [Server 40ee20c76806f255] pn=0 type=Short pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 1232 server | TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false } server | 1.845 DEBUG packet_sent this=0x55603e2746d0, pn=0, ps=1232 server | 1.845 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.845 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.845 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.845 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.845 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.845 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.845 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.845 DEBUG [Server 40ee20c76806f255] delay duration 1.868125ms server | 1.845 DEBUG Setting timeout of 1.868125ms server | 1.848 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.848 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.848 DEBUG [Server 40ee20c76806f255] pn=1 type=Short pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 1232 server | TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false } server | 1.848 DEBUG packet_sent this=0x55603e2746d0, pn=1, ps=1232 server | 1.848 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.848 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.848 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.848 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.848 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.848 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.848 DEBUG [Server 40ee20c76806f255] delay duration 16.641944ms server | 1.848 DEBUG Setting timeout of 16.641944ms server | 1.848 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.848 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.848 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.848 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.848 DEBUG [Server 40ee20c76806f255] delay duration 16.613561ms server | 1.848 DEBUG Setting timeout of 16.613561ms server | 1.866 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.866 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.866 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.866 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.866 DEBUG [Server 40ee20c76806f255] pn=2 type=Short pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 32 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 2567, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.866 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.866 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.866 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.866 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.866 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.866 DEBUG [Server 40ee20c76806f255] delay duration 29.979494972s server | 1.866 DEBUG Setting timeout of 29.979494972s server | 1.866 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.866 DEBUG Building Initial dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.866 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.866 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.866 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false } server | 1.866 DEBUG [Server 40ee20c76806f255] delay duration 29.979470316s server | 1.866 DEBUG Setting timeout of 29.979470316s server | 1.877 DEBUG [Server 40ee20c76806f255] Drop packet number space in server | 1.877 DEBUG [recovery::Loss] Reset loss recovery state for Initial server | 1.877 INFO [pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282] discarding a packet without an RTT estimate; guessing RTT=35.272953ms server | 1.877 DEBUG [Server 40ee20c76806f255] pn=0 type=Handshake pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 86 server | -> RX Crypto { offset: 0, len: 36 } server | 1.877 DEBUG [Agent 0x55603e295dd0] 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.877 DEBUG [Server 40ee20c76806f255] TLS connection complete server | 1.877 DEBUG [pri-unv-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282] Path validated Instant { tv_sec: 384, tv_nsec: 264588672 } server | 1.877 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0 server | 1.878 DEBUG [Crypto] application read keys installed server | 1.878 DEBUG [Server 40ee20c76806f255] State change from Handshaking -> Connected server | 1.878 DEBUG [Server 40ee20c76806f255] State change from Connected -> Confirmed server | 1.878 DEBUG PMTUD started with probe size 1380 server | 1.878 INFO [Server 40ee20c76806f255] Connection established server | 1.878 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 264588672 } server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.878 DEBUG Building Handshake dcid Some(CID [8]: 76971f5c6febcb76) scid Some(CID [10]: a1e2d09fb396bb7ca26f) server | 1.878 DEBUG [Server 40ee20c76806f255] pn=1 type=Handshake pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 49 server | TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None } server | 1.878 DEBUG [Server 40ee20c76806f255] Drop packet number space hs server | 1.878 DEBUG [recovery::Loss] Reset loss recovery state for Handshake server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG [Server 40ee20c76806f255] pn=3 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1332 server | TX -> HandshakeDone server | TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [164, 219, 116, 119, 127, 203, 254, 177, 9, 28], stateless_reset_token: [16, 209, 76, 59, 197, 123, 98, 197, 72, 76, 253, 168, 175, 62, 74, 99] } server | TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [54, 186, 242, 52, 84, 110, 28, 43, 236, 55], stateless_reset_token: [69, 3, 166, 18, 190, 7, 225, 46, 75, 8, 207, 220, 219, 12, 117, 131] } server | TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [105, 142, 224, 100, 88, 216, 41, 148, 113, 176], stateless_reset_token: [8, 65, 19, 196, 40, 190, 221, 13, 0, 231, 185, 154, 87, 106, 226, 40] } server | TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [134, 130, 97, 200, 38, 3, 86, 140, 132, 162], stateless_reset_token: [37, 133, 92, 158, 248, 163, 208, 116, 37, 4, 241, 243, 6, 97, 63, 131] } server | TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [65, 207, 210, 232, 180, 51, 9, 214, 211, 83], stateless_reset_token: [214, 45, 62, 71, 155, 128, 111, 58, 24, 84, 222, 133, 137, 211, 53, 62] } server | TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [43, 236, 12, 230, 19, 137, 10, 86, 165, 155], stateless_reset_token: [231, 117, 118, 216, 34, 221, 46, 94, 28, 88, 192, 21, 85, 137, 71, 183] } server | TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [207, 177, 137, 78, 237, 128, 127, 70, 79, 24], stateless_reset_token: [155, 169, 158, 47, 165, 245, 30, 232, 95, 74, 122, 156, 184, 168, 105, 250] } server | TX -> Stream { stream_id: 0, offset: 2406, len: >>1042, fin: false } server | 1.878 DEBUG packet_sent this=0x55603e2746d0, pn=3, ps=1283 server | 1.878 DEBUG ECN probing: sent 1 probes server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG Sending PMTUD probe of size 1380, count 1 server | 1.878 DEBUG [Server 40ee20c76806f255] pn=4 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1332 server | TX -> Ping server | TX -> Stream { stream_id: 0, offset: 3448, len: >>1301, fin: false } server | 1.878 DEBUG packet_sent this=0x55603e2746d0, pn=4, ps=1332 server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG ECN probing: sent 2 probes server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG [Server 40ee20c76806f255] delay duration 1.537607ms server | 1.878 DEBUG Setting timeout of 1.537607ms server | 1.878 DEBUG [Server 40ee20c76806f255] send session ticket 4064000840ee20c76806f2550104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0aa1e2d09fb396bb7ca26f110c000000017afa3a8a000000016ab200c0000000ff02de1a0243e8200244b0686921 server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG [Server 40ee20c76806f255] delay duration 1.438281ms server | 1.878 DEBUG Setting timeout of 1.438281ms server | 1.878 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 1 server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG [Server 40ee20c76806f255] delay duration 976.577µs server | 1.878 DEBUG Setting timeout of 976.577µs server | 1.878 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 2 server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG [Server 40ee20c76806f255] delay duration 920.301µs server | 1.878 DEBUG Setting timeout of 920.301µs server | 1.878 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.878 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.878 DEBUG [Server 40ee20c76806f255] delay duration 899.092µs server | 1.878 DEBUG Setting timeout of 899.092µs server | 1.880 DEBUG [Server 40ee20c76806f255] pn=1 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 1, ack_delay: 13, first_ack_range: 1, ack_ranges: [], ecn_count: None } server | 1.880 DEBUG [Server 40ee20c76806f255] Rx ACK space=ap, ranges=[0..=1] server | 1.880 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=1 server | 1.880 DEBUG on_packets_acked this=0x55603e2746d0, limited=1, bytes_in_flight=2615, cwnd=12320, state=SlowStart, new_acked=2464 server | 1.880 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.880 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.880 DEBUG [Server 40ee20c76806f255] pn=5 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 4749, len: >>1202, fin: false } server | 1.880 DEBUG packet_sent this=0x55603e2746d0, pn=5, ps=1232 server | 1.880 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.880 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.880 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.880 DEBUG ECN probing: sent 3 probes server | 1.880 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.880 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.880 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.880 DEBUG [Server 40ee20c76806f255] delay duration 1.063652ms server | 1.880 DEBUG Setting timeout of 1.063652ms server | 1.880 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.880 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.880 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.880 DEBUG [Server 40ee20c76806f255] delay duration 1.043735ms server | 1.880 DEBUG Setting timeout of 1.043735ms server | 1.882 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.882 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.882 DEBUG [Server 40ee20c76806f255] pn=6 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 5951, len: >>1202, fin: false } server | 1.882 DEBUG packet_sent this=0x55603e2746d0, pn=6, ps=1232 server | 1.882 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.882 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.882 DEBUG [Server 40ee20c76806f255] pn=7 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 7153, len: >>1202, fin: false } server | 1.882 DEBUG packet_sent this=0x55603e2746d0, pn=7, ps=1232 server | 1.882 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.882 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.882 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.882 DEBUG ECN probing: sent 5 probes server | 1.882 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.882 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.882 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.882 DEBUG [Server 40ee20c76806f255] delay duration 1.628536ms server | 1.882 DEBUG Setting timeout of 1.628536ms server | 1.882 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.882 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.882 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.882 DEBUG [Server 40ee20c76806f255] delay duration 1.608258ms server | 1.882 DEBUG Setting timeout of 1.608258ms server | 1.885 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.885 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.885 DEBUG [Server 40ee20c76806f255] pn=8 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1232 server | TX -> Stream { stream_id: 0, offset: 8355, len: >>1202, fin: false } server | 1.885 DEBUG packet_sent this=0x55603e2746d0, pn=8, ps=1232 server | 1.885 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.885 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.885 DEBUG CRYPTO for ap offset=0, len=305 server | 1.885 DEBUG [Server 40ee20c76806f255] pn=9 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1069 server | TX -> Stream { stream_id: 0, offset: 9557, len: 683, fin: true } server | TX -> Crypto { offset: 0, len: 305 } server | TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 249, 222, 221, 118, 44, 176, 124, 167, 103, 237, 10, 200, 47, 32, 185, 89, 170, 72, 182, 165, 175, 135, 15, 56, 101, 41, 136, 22, 137, 62, 103, 131, 165, 50, 92, 150] } server | 1.885 DEBUG packet_sent this=0x55603e2746d0, pn=9, ps=1069 server | 1.885 DEBUG ECN probing: sent 7 probes server | 1.885 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.885 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.885 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.885 DEBUG [Server 40ee20c76806f255] delay duration 1.608248ms server | 1.885 DEBUG Setting timeout of 1.608248ms server | 1.886 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.886 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.886 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true } server | 1.886 DEBUG [Server 40ee20c76806f255] delay duration 1.482245ms server | 1.886 DEBUG Setting timeout of 1.482245ms server | 1.888 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.888 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.888 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.888 DEBUG [Server 40ee20c76806f255] delay duration 95.560798ms server | 1.888 DEBUG Setting timeout of 95.560798ms server | 1.888 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.888 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.888 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.888 DEBUG [Server 40ee20c76806f255] delay duration 95.541322ms server | 1.888 DEBUG Setting timeout of 95.541322ms client | >>>>>>>> request time cost:76648 us, speed:133 K/s client | >>>>>>>> user_stream[0x559056e15b50], req: /rwzibgjmee, send_body_size:11, recv_body_size:10240 server | 1.910 DEBUG [Server 40ee20c76806f255] pn=2 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [122, 100, 33, 104, 46, 149, 106, 120], stateless_reset_token: [161, 30, 131, 117, 102, 116, 93, 46, 130, 76, 199, 208, 52, 132, 149, 96] } server | 1.910 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 316784311 } server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG [Server 40ee20c76806f255] pn=10 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 31 server | TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None } server | 1.910 DEBUG ECN probing: sent 8 probes server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG [Server 40ee20c76806f255] delay duration 73.523644ms server | 1.910 DEBUG Setting timeout of 73.523644ms server | 1.910 DEBUG [Server 40ee20c76806f255] pn=8 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 59 server | -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [21, 16, 139, 78, 211, 92, 178, 160], stateless_reset_token: [30, 71, 138, 3, 180, 234, 12, 135, 234, 145, 158, 75, 130, 67, 71, 130] } server | 1.910 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 384, tv_nsec: 296908330 } server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG [Server 40ee20c76806f255] pn=11 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 33 server | TX -> Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 4, range: 2 }], ecn_count: None } server | 1.910 DEBUG ECN probing: sent 9 probes server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG [Server 40ee20c76806f255] delay duration 73.430239ms server | 1.910 DEBUG Setting timeout of 73.430239ms server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG [Server 40ee20c76806f255] delay duration 73.411905ms server | 1.910 DEBUG Setting timeout of 73.411905ms server | 1.910 DEBUG [Server 40ee20c76806f255] pn=9 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 4, ack_delay: 15, first_ack_range: 4, ack_ranges: [], ecn_count: None } server | 1.910 DEBUG [Server 40ee20c76806f255] Rx ACK space=ap, ranges=[0..=4] server | 1.910 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=4 server | 1.910 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5997/14935 ssthresh 18446744073709551615] slow start += 2615 server | 1.910 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5997/14935 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=5997, cwnd=14935, state=SlowStart, new_acked=2615 server | 1.910 DEBUG PMTUD probe of size 1380 succeeded server | 1.910 DEBUG PMTUD started with probe size 1420 server | 1.910 DEBUG PLPMTU changed from 1232 to 1332, updating pacer server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG Sending PMTUD probe of size 1420, count 1 server | 1.910 DEBUG [Server 40ee20c76806f255] pn=12 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, Ect0) len 1372 server | TX -> Ping server | TX -> Padding { len: 1345 } server | 1.910 DEBUG packet_sent this=0x55603e2746d0, pn=12, ps=1372 server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG ECN probing: sent 10 probes server | 1.910 DEBUG ECN probing concluded with 10 probes sent server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG [Server 40ee20c76806f255] delay duration 72.721039ms server | 1.910 DEBUG Setting timeout of 72.721039ms server | 1.910 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.910 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.910 DEBUG [Server 40ee20c76806f255] delay duration 72.701683ms server | 1.911 DEBUG Setting timeout of 72.701683ms server | 1.914 DEBUG [Server 40ee20c76806f255] pn=10 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 6, ack_delay: 13, first_ack_range: 6, ack_ranges: [], ecn_count: None } server | 1.914 DEBUG [Server 40ee20c76806f255] Rx ACK space=ap, ranges=[0..=6] server | 1.914 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=6 server | 1.914 WARN ECN validation failed, no ECN counts in ACK frame server | 1.914 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4905/17399 ssthresh 18446744073709551615] slow start += 2464 server | 1.914 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4905/17399 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=4905, cwnd=17399, state=SlowStart, new_acked=2464 server | 1.914 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.914 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.914 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.914 DEBUG [Server 40ee20c76806f255] delay duration 69.584027ms server | 1.914 DEBUG Setting timeout of 69.584027ms server | 1.914 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.914 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.914 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.914 DEBUG [Server 40ee20c76806f255] delay duration 69.520969ms server | 1.914 DEBUG Setting timeout of 69.520969ms server | 1.917 DEBUG [Server 40ee20c76806f255] pn=11 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 8, ack_delay: 13, first_ack_range: 8, ack_ranges: [], ecn_count: None } server | 1.917 DEBUG [Server 40ee20c76806f255] Rx ACK space=ap, ranges=[0..=8] server | 1.917 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=8 server | 1.917 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2441/19863 ssthresh 18446744073709551615] slow start += 2464 server | 1.917 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2441/19863 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=2441, cwnd=19863, state=SlowStart, new_acked=2464 server | 1.917 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.917 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.917 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.917 DEBUG [Server 40ee20c76806f255] delay duration 66.328793ms server | 1.917 DEBUG Setting timeout of 66.328793ms server | 1.917 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.917 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.917 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false } server | 1.917 DEBUG [Server 40ee20c76806f255] delay duration 66.285642ms server | 1.917 DEBUG Setting timeout of 66.285642ms server | 1.942 DEBUG [Server 40ee20c76806f255] pn=12 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 36 server | -> RX Ack { largest_acknowledged: 12, ack_delay: 13, first_ack_range: 10, ack_ranges: [], ecn_count: None } server | 1.942 DEBUG [Server 40ee20c76806f255] Rx ACK space=ap, ranges=[2..=12] server | 1.942 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=12 server | 1.942 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/22304 ssthresh 18446744073709551615] slow start += 2441 server | 1.942 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/22304 ssthresh 18446744073709551615] on_packets_acked this=0x55603e2746d0, limited=0, bytes_in_flight=0, cwnd=22304, state=SlowStart, new_acked=2441 server | 1.942 DEBUG PMTUD probe of size 1420 succeeded server | 1.942 DEBUG PMTUD started with probe size 1470 server | 1.942 DEBUG PLPMTU changed from 1332 to 1372, updating pacer server | 1.942 DEBUG Acked crypto frame space=ap offset=0 length=305 server | 1.942 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.942 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.942 DEBUG Sending PMTUD probe of size 1470, count 1 server | 1.942 DEBUG [Server 40ee20c76806f255] pn=13 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 1422 server | TX -> Ping server | TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 } server | TX -> Padding { len: 1393 } server | 1.942 DEBUG packet_sent this=0x55603e2746d0, pn=13, ps=1422 server | 1.942 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.942 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.942 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.942 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.942 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.942 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.942 DEBUG [Server 40ee20c76806f255] delay duration 40.99597ms server | 1.942 DEBUG Setting timeout of 40.99597ms server | 1.942 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.942 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.942 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.942 DEBUG [Server 40ee20c76806f255] delay duration 40.972205ms server | 1.942 DEBUG Setting timeout of 40.972205ms server | 1.984 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.984 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.984 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.984 DEBUG [Server 40ee20c76806f255] delay duration 41.021466ms server | 1.984 DEBUG Setting timeout of 41.021466ms server | 1.984 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.984 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.984 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false } server | 1.984 DEBUG [Server 40ee20c76806f255] delay duration 40.988364ms server | 1.984 DEBUG Setting timeout of 40.988364ms server | 1.999 DEBUG [Server 40ee20c76806f255] pn=13 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 13, ack_delay: 3210, first_ack_range: 11, ack_ranges: [], ecn_count: None } server | 1.999 DEBUG [Server 40ee20c76806f255] Rx ACK space=ap, ranges=[2..=13] server | 1.999 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=13 server | 1.999 DEBUG on_packets_acked this=0x55603e2746d0, limited=1, bytes_in_flight=0, cwnd=22304, state=SlowStart, new_acked=1422 server | 1.999 DEBUG PMTUD probe of size 1470 succeeded server | 1.999 DEBUG PMTUD started with probe size 1500 server | 1.999 DEBUG PLPMTU changed from 1372 to 1422, updating pacer server | 1.999 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.999 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.999 DEBUG Sending PMTUD probe of size 1500, count 1 server | 1.999 DEBUG [Server 40ee20c76806f255] pn=14 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 1452 server | TX -> Ping server | TX -> Padding { len: 1425 } server | 1.999 DEBUG packet_sent this=0x55603e2746d0, pn=14, ps=1452 server | 1.999 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 1.999 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 1.999 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 2.000 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 2.000 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 2.000 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 2.000 DEBUG [Server 40ee20c76806f255] delay duration 78.022367ms server | 2.000 DEBUG Setting timeout of 78.022367ms server | 2.000 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 2.000 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 2.000 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false } server | 2.000 DEBUG [Server 40ee20c76806f255] delay duration 77.999404ms server | 2.000 DEBUG Setting timeout of 77.999404ms server | 2.057 DEBUG [Server 40ee20c76806f255] pn=14 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 37 server | -> RX Ack { largest_acknowledged: 14, ack_delay: 3212, first_ack_range: 12, ack_ranges: [], ecn_count: None } server | 2.057 DEBUG [Server 40ee20c76806f255] Rx ACK space=ap, ranges=[2..=14] server | 2.057 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=14 server | 2.057 DEBUG on_packets_acked this=0x55603e2746d0, limited=1, bytes_in_flight=0, cwnd=22304, state=SlowStart, new_acked=1452 server | 2.057 DEBUG PMTUD probe of size 1500 succeeded server | 2.057 INFO PMTUD stopped, PLPMTU is now 1500, raise timer Some(Instant { tv_sec: 984, tv_nsec: 444068819 }) server | 2.057 DEBUG PLPMTU changed from 1422 to 1452, updating pacer server | 2.057 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 2.057 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 2.057 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 2.057 DEBUG [Server 40ee20c76806f255] delay duration 30s server | 2.057 DEBUG Setting timeout of 30s server | 2.057 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 2.057 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 2.057 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 2.057 DEBUG [Server 40ee20c76806f255] delay duration 29.999923286s server | 2.057 DEBUG Setting timeout of 29.999923286s client | [HQ-req] send_bytes:17, recv_bytes:10240, path_info:0-1-9-17-10240 client | task[0], fin_cnt: 1, fin_flag: 1 server | 2.285 DEBUG [Server 40ee20c76806f255] pn=15 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 35 server | -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" } server | 2.285 INFO [Server 40ee20c76806f255] ConnectionClose received. Error code: Transport(0) frame type 0 reason server | 2.285 DEBUG [Server 40ee20c76806f255] State change from Confirmed -> Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 384, tv_nsec: 893833117 } } server | 2.285 DEBUG [pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282] Path validated Instant { tv_sec: 384, tv_nsec: 671697439 } server | 2.285 DEBUG [Server 40ee20c76806f255] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false } server | 2.285 DEBUG Building Short dcid Some(CID [8]: 76971f5c6febcb76) server | 2.285 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 384, tv_nsec: 671697439 } server | 2.285 DEBUG [Server 40ee20c76806f255] pn=15 type=Short pri-path:76971f5c6febcb76 [::]:443->[::ffff:193.167.0.100]:42282 Tos(Cs0, NotEct) len 35 server | TX -> Ack { largest_acknowledged: 15, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: None } server | TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" } server | 2.285 DEBUG Setting timeout of 221.996698ms server | 2.285 DEBUG Setting timeout of 221.985818ms server | 2.315 DEBUG [Server ...] Dropped received packet: State Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 384, tv_nsec: 893833117 } }; Total: 3 server | 2.315 DEBUG Setting timeout of 191.502046ms server | 2.315 DEBUG Setting timeout of 191.485927ms server | 2.507 DEBUG [Server 40ee20c76806f255] State change from Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 384, tv_nsec: 893833117 } } -> Closed(Transport(Peer(0))) server | 2.507 INFO Closing timer expired client | task finished, total task_req_cnt: 1, req_fin_cnt: 1, req_sent_cnt: 0, req_create_cnt: 1 client | all tasks are finished, will break loop and exit client | 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-09-11 19:39:42,634 Using the client's key log file. 2025-09-11 19:39:42,643 2025-09-11 19:39:42,643 Using the client's key log file. 2025-09-11 19:39:42,643 Using the client's key log file. 2025-09-11 19:39:42,651 2025-09-11 19:39:42,651 Using the client's key log file. 2025-09-11 19:39:42,651 Using the client's key log file. 2025-09-11 19:39:43,270 Check of downloaded files succeeded.