2025-09-11 19:37:33,305 Generated random file: xotbigyqps of size: 1024
2025-09-11 19:37:33,306 Requests: https://server4:443/xotbigyqps
2025-09-11 19:37:33,361 
2025-09-11 19:37:33,362 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs__tcp_b6a/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_sfk0mryb/ DOWNLOADS=/tmp/download_69l9rz1h/ SERVER_LOGS=/tmp/logs_server_qwk3i505 CLIENT_LOGS=/tmp/logs_client_myk5fk41 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/xotbigyqps"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-09-11 19:37:46,240  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
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 handshake ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + P12CERT=/tmp/tmp.lamaHFoJzw
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.lamaHFoJzw
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.lamaHFoJzw -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  |             17:31:59:a9:46:c3:90:83:10:fc:cf:a5:44:d7:c6:5f:
server  |             da:63:95:fa
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:37:33 2025
server  |             Not After : Sun Sep 21 19:37:33 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:78:58:fb:bf:17:7c:fe:f9:4e:02:39:f8:7e:4b:c9:
server  |                     03:93:c3:71:23:d7:3b:15:61:0a:ab:08:e5:b0:77:68:
server  |                     0a:13:95:c2:1e:6e:c0:77:fc:f4:ae:20:ba:cc:a2:e7:
server  |                     c5:d0:8d:5b:d3:20:59:6f:5b:53:e6:31:e1:82:07:d6:
server  |                     d2
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  |                 05:47:03:be:37:1b:ba:3a:d3:86:fb:40:89:ce:c6:86:
server  |                 38:ba:55:d8
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 0e:c3:30:2e:83:87:28:ee:44:74:71:df:26:ec:fc:b2:
server  |                 5a:e0:ec:66
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:20:16:1d:c7:44:7f:c7:f4:e4:9f:d6:de:13:
server  |         1c:93:b3:de:3e:4b:f7:b7:77:bc:1a:00:fb:b0:f1:42:
server  |         36:e9:e9:d3:02:21:00:e3:90:ef:1a:f9:51:31:92:ee:
server  |         92:e6:e4:ea:a4:b5:31:c8:a6:a6:60:02:4b:cf:fb:f3:
server  |         75:38:81:ce:a9:c7:67
server  |     Fingerprint (SHA-256):
server  |         04:5C:16:29:67:AB:DB:53:0C:B5:94:20:2A:F9:41:E8:2F:2F:39:BF:89:22:26:51:20:BC:2D:84:A1:9E:46:C5
server  |     Fingerprint (SHA1):
server  |         49:4A:66:91:37:61:E0:9D:4C:37:DC:9D:99:37:28:F2:BB:16:BE:1F
server  | 
server  |     Mozilla-CA-Policy: false (attribute missing)
server  |     Certificate Trust Flags:
server  |         SSL Flags:
server  |             User
server  |         Email Flags:
server  |             User
server  |         Object Signing Flags:
server  |             User
server  | 
server  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT")
server  | + '[' handshake = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test handshake --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443'
server  | ++ tee -i -a /logs/server.log
server  | 0.000 DEBUG Logging initialized
server  | 0.002 DEBUG Default socket send buffer size is 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.294 DEBUG [Server] Unsupported version: 57414954
server  | 0.294 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:44779 Tos(Cs0, NotEct) len 15
sim     | server:443 is available after 501.11692ms
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  | ./demo_client -l d  -L /logs/client.log -D /downloads -k /logs/keys.log -K 30 -o -U "https://server4:443/xotbigyqps"
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/xotbigyqps
client  | server[server4] addr: 193.167.100.100:443.
client  | No.0 path created id = 0
client  | open file[/downloads/xotbigyqps] suc
server  | 0.994 DEBUG [Server] Handle initial
server  | 0.994 INFO AddressValidation: no token; accepting
server  | 0.994 INFO [Server] Accept connection CID [8]: aed8761d3e4e36cc
server  | 0.994 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.995 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.995 INFO [Server ...] Received valid Initial packet with scid CID [8]: a0c73034deb0e162 dcid CID [8]: aed8761d3e4e36cc
server  | 0.995 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=aed8761d3e4e36cc
server  | 0.995 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=29
server  | 0.995 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 0.995 DEBUG [Server aed8761d3e4e36cc] State change from Init -> WaitInitial
server  | 0.995 DEBUG [Server aed8761d3e4e36cc] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 1200
server  |   -> RX Crypto { offset: 0, len: 305 }
server  |   -> RX Padding { len: 845 }
server  | 0.995 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.996 DEBUG Read secret available for Handshake: SymKey [32]: f0e6af6d60ac5defca25929a0ab1a89958e65cc3300a05c16107127987e761b2
server  | 0.996 DEBUG Write secret available for Handshake: SymKey [32]: 334e6ac2c4c89fc14cc7dac4f5759df3f2a5a84d045d7563c35101db7136b9f0
server  | 0.996 DEBUG Writing transport parameters, msg=8
server  | 0.996 DEBUG Read secret available for ApplicationData: SymKey [32]: c2859bec5a653023fdabf73bb23c5b3bb15870c1cb92b730ccee020c27366c51
server  | 0.996 DEBUG Write secret available for ApplicationData: SymKey [32]: d3b4109c3524f3663d024889094c47acce9df68f110596f892ea320dba35b681
server  | 0.996 DEBUG [Agent 0x560bc7330650] state -> InProgress
server  | 0.997 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 0.997 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 0.997 DEBUG [Crypto] Handshake keys installed
server  | 0.997 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 0.997 DEBUG [Crypto] Application write key installed
server  | 0.997 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 267, tv_nsec: 859161245 }
server  | 0.997 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:36688] Make permanent
server  | 0.997 DEBUG [unv-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688] set as primary path
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] State change from WaitInitial -> Handshaking
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.997 DEBUG Building Initial dcid Some(CID [8]: a0c73034deb0e162) scid Some(CID [10]: b8fed73e161274122978)
server  | 0.997 DEBUG CRYPTO for in offset=0, len=123
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] pn=29 type=Initial pri-unv-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 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.997 DEBUG Building Handshake dcid Some(CID [8]: a0c73034deb0e162) scid Some(CID [10]: b8fed73e161274122978)
server  | 0.997 DEBUG CRYPTO for hs offset=0, len=726
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] pn=0 type=Handshake pri-unv-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 951
server  |   TX -> Crypto { offset: 0, len: 726 }
server  | 0.997 DEBUG packet_sent this=0x560bc73306d0, pn=0, ps=774
server  | 0.997 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] pad Initial from 951 to PLPMTU 1232
server  | 0.997 DEBUG packet_sent this=0x560bc73306d0, pn=29, ps=458
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.997 DEBUG Building Initial dcid Some(CID [8]: a0c73034deb0e162) scid Some(CID [10]: b8fed73e161274122978)
server  | 0.997 DEBUG Building Handshake dcid Some(CID [8]: a0c73034deb0e162) scid Some(CID [10]: b8fed73e161274122978)
server  | 0.997 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 0.997 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] delay duration 296.765604ms
server  | 0.997 DEBUG Setting timeout of 296.765604ms
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.997 DEBUG Building Initial dcid Some(CID [8]: a0c73034deb0e162) scid Some(CID [10]: b8fed73e161274122978)
server  | 0.997 DEBUG Building Handshake dcid Some(CID [8]: a0c73034deb0e162) scid Some(CID [10]: b8fed73e161274122978)
server  | 0.997 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 0.997 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.997 DEBUG [Server aed8761d3e4e36cc] delay duration 296.724487ms
server  | 0.997 DEBUG Setting timeout of 296.724487ms
server  | 1.030 DEBUG [Server aed8761d3e4e36cc] Drop packet number space in
server  | 1.030 DEBUG [recovery::Loss] Reset loss recovery state for Initial
server  | 1.030 INFO [pri-unv-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688] discarding a packet without an RTT estimate; guessing RTT=36.906045ms
server  | 1.030 DEBUG [Server aed8761d3e4e36cc] pn=0 type=Handshake pri-unv-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 86
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.031 DEBUG [Agent 0x560bc7330650] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] TLS connection complete
server  | 1.031 DEBUG [pri-unv-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688] Path validated Instant { tv_sec: 267, tv_nsec: 896067290 }
server  | 1.031 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
server  | 1.031 DEBUG [Crypto] application read keys installed
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] State change from Handshaking -> Connected
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] State change from Connected -> Confirmed
server  | 1.031 DEBUG PMTUD started with probe size 1380
server  | 1.031 INFO [Server aed8761d3e4e36cc] Connection established
server  | 1.031 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 267, tv_nsec: 896067290 }
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.031 DEBUG Building Handshake dcid Some(CID [8]: a0c73034deb0e162) scid Some(CID [10]: b8fed73e161274122978)
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] pn=1 type=Handshake pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 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.031 DEBUG [Server aed8761d3e4e36cc] Drop packet number space hs
server  | 1.031 DEBUG [recovery::Loss] Reset loss recovery state for Handshake
server  | 1.031 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] pn=0 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [225, 252, 56, 221, 81, 215, 1, 209, 132, 66], stateless_reset_token: [81, 55, 38, 95, 62, 148, 108, 208, 147, 59, 251, 41, 205, 184, 230, 216] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [234, 147, 117, 113, 139, 163, 50, 180, 35, 250], stateless_reset_token: [111, 41, 23, 78, 225, 130, 118, 237, 135, 8, 240, 157, 23, 86, 74, 90] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [217, 204, 215, 98, 162, 49, 215, 70, 164, 188], stateless_reset_token: [86, 219, 33, 38, 206, 27, 59, 132, 18, 130, 46, 5, 157, 103, 179, 161] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [144, 166, 150, 161, 164, 218, 197, 19, 51, 215], stateless_reset_token: [132, 151, 36, 111, 171, 11, 65, 241, 88, 90, 116, 164, 129, 229, 209, 70] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [108, 197, 189, 254, 16, 180, 203, 63, 163, 253], stateless_reset_token: [149, 159, 147, 137, 53, 59, 109, 86, 82, 228, 12, 107, 57, 66, 167, 143] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [59, 250, 85, 77, 6, 218, 43, 150, 120, 0], stateless_reset_token: [123, 181, 135, 90, 231, 33, 33, 127, 95, 13, 154, 83, 101, 73, 30, 175] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [78, 30, 207, 79, 119, 233, 3, 234, 209, 22], stateless_reset_token: [14, 209, 234, 243, 32, 128, 168, 240, 187, 172, 237, 190, 246, 137, 96, 110] }
server  |   TX -> Padding { len: 1046 }
server  | 1.031 DEBUG packet_sent this=0x560bc73306d0, pn=0, ps=1283
server  | 1.031 DEBUG ECN probing: sent 1 probes
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.031 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.031 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] pn=1 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 1.031 DEBUG packet_sent this=0x560bc73306d0, pn=1, ps=1332
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.031 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG ECN probing: sent 2 probes
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.031 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] delay duration 1.268896ms
server  | 1.031 DEBUG Setting timeout of 1.268896ms
server  | 1.031 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 1
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.031 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.031 DEBUG [Server aed8761d3e4e36cc] delay duration 1.150153ms
server  | 1.031 DEBUG Setting timeout of 1.150153ms
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] send session ticket 40640008aed8761d3e4e36cc0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ab8fed73e161274122978110c000000019a3a2aaa000000016ab200c0000000ff02de1a0243e8200244b0686921
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] delay duration 253.725µs
server  | 1.032 DEBUG Setting timeout of 253.725µs
server  | 1.032 DEBUG [Server ...] Dropped received packet: Decryption failure; Total: 2
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] delay duration 218.549µs
server  | 1.032 DEBUG Setting timeout of 218.549µs
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] pn=0 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 52
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.032 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 267, tv_nsec: 917943243 }
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] delay duration 195.606µs
server  | 1.032 DEBUG Setting timeout of 195.606µs
server  | 1.032 DEBUG Path = 'xotbigyqps'
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.032 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.032 DEBUG [Server aed8761d3e4e36cc] delay duration 111.478µs
server  | 1.032 DEBUG Setting timeout of 111.478µs
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.034 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.034 DEBUG CRYPTO for ap offset=0, len=174
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] pn=2 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  |   TX -> Crypto { offset: 0, len: 174 }
server  | 1.034 DEBUG packet_sent this=0x560bc73306d0, pn=2, ps=1232
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.034 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.034 DEBUG CRYPTO for ap offset=174, len=131
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] pn=3 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 207
server  |   TX -> Crypto { offset: 174, len: 131 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 205, 92, 75, 199, 180, 23, 63, 21, 2, 44, 197, 195, 233, 89, 54, 157, 221, 186, 200, 217, 19, 228, 147, 122, 0, 26, 201, 44, 247, 116, 29, 211, 139, 157, 208, 83] }
server  | 1.034 DEBUG packet_sent this=0x560bc73306d0, pn=3, ps=207
server  | 1.034 DEBUG ECN probing: sent 4 probes
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.034 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.034 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] delay duration 1.064933ms
server  | 1.034 DEBUG Setting timeout of 1.064933ms
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.034 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.034 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.034 DEBUG [Server aed8761d3e4e36cc] delay duration 1.044335ms
server  | 1.034 DEBUG Setting timeout of 1.044335ms
server  | 1.036 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG [Server aed8761d3e4e36cc] delay duration 16.609872ms
server  | 1.036 DEBUG Setting timeout of 16.609872ms
server  | 1.036 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG [Server aed8761d3e4e36cc] delay duration 16.584915ms
server  | 1.036 DEBUG Setting timeout of 16.584915ms
client  | >>>>>>>> request time cost:77383 us, speed:13 K/s 
client  | >>>>>>>> user_stream[0x562b1cceb2f0], req: /xotbigyqps, send_body_size:11, recv_body_size:1024 
server  | 1.054 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.054 DEBUG [Server aed8761d3e4e36cc] pn=4 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 32
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 2687, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.054 DEBUG ECN probing: sent 5 probes
server  | 1.054 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.054 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG [Server aed8761d3e4e36cc] delay duration 115.302054ms
server  | 1.054 DEBUG Setting timeout of 115.302054ms
server  | 1.054 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.054 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG [Server aed8761d3e4e36cc] delay duration 115.276526ms
server  | 1.054 DEBUG Setting timeout of 115.276526ms
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] pn=3 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 59
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [192, 190, 138, 150, 160, 114, 0, 4], stateless_reset_token: [51, 231, 149, 228, 4, 228, 169, 167, 25, 85, 197, 194, 143, 188, 54, 46] }
server  | 1.063 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 267, tv_nsec: 928605557 }
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] pn=5 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 33
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 1, range: 0 }], ecn_count: None }
server  | 1.063 DEBUG ECN probing: sent 6 probes
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] delay duration 106.12467ms
server  | 1.063 DEBUG Setting timeout of 106.12467ms
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] pn=4 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 59
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [249, 200, 107, 124, 194, 41, 208, 229], stateless_reset_token: [53, 199, 6, 27, 55, 164, 7, 126, 82, 137, 235, 19, 38, 77, 210, 96] }
server  | 1.063 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 267, tv_nsec: 948743726 }
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] delay duration 20ms
server  | 1.063 DEBUG Setting timeout of 20ms
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server aed8761d3e4e36cc] delay duration 19.949596ms
server  | 1.063 DEBUG Setting timeout of 19.949596ms
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] pn=5 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 11, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] Rx ACK space=ap, ranges=[0..=1]
server  | 1.064 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=1
server  | 1.064 DEBUG on_packets_acked this=0x560bc73306d0, limited=1, bytes_in_flight=1439, cwnd=12320, state=SlowStart, new_acked=2615
server  | 1.064 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.064 DEBUG PMTUD started with probe size 1420
server  | 1.064 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.064 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] pn=6 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1345 }
server  | 1.064 DEBUG packet_sent this=0x560bc73306d0, pn=6, ps=1372
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.064 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG ECN probing: sent 7 probes
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.064 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] delay duration 19.275536ms
server  | 1.064 DEBUG Setting timeout of 19.275536ms
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.064 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG [Server aed8761d3e4e36cc] delay duration 19.251411ms
server  | 1.064 DEBUG Setting timeout of 19.251411ms
server  | 1.065 DEBUG [Server aed8761d3e4e36cc] pn=6 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 34, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 1.065 DEBUG [Server aed8761d3e4e36cc] Rx ACK space=ap, ranges=[0..=3]
server  | 1.065 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=3
server  | 1.065 DEBUG on_packets_acked this=0x560bc73306d0, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=1439
server  | 1.065 DEBUG Acked crypto frame space=ap offset=174 length=131
server  | 1.065 DEBUG Acked crypto frame space=ap offset=0 length=174
server  | 1.065 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.065 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.066 DEBUG [Server aed8761d3e4e36cc] pn=7 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 28
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.066 DEBUG packet_sent this=0x560bc73306d0, pn=7, ps=29
server  | 1.066 DEBUG ECN probing: sent 8 probes
server  | 1.066 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.066 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.066 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.066 DEBUG [Server aed8761d3e4e36cc] delay duration 17.558892ms
server  | 1.066 DEBUG Setting timeout of 17.558892ms
server  | 1.066 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.066 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.066 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.066 DEBUG [Server aed8761d3e4e36cc] delay duration 17.534496ms
server  | 1.066 DEBUG Setting timeout of 17.534496ms
server  | 1.084 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.084 DEBUG [Server aed8761d3e4e36cc] pn=8 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 34
server  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 2280, first_ack_range: 3, ack_ranges: [AckRange { gap: 1, range: 0 }], ecn_count: None }
server  | 1.084 DEBUG ECN probing: sent 9 probes
server  | 1.084 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.084 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG [Server aed8761d3e4e36cc] delay duration 91.66406ms
server  | 1.084 DEBUG Setting timeout of 91.66406ms
server  | 1.084 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.084 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG [Server aed8761d3e4e36cc] delay duration 91.63741ms
server  | 1.084 DEBUG Setting timeout of 91.63741ms
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] pn=7 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 12, first_ack_range: 7, ack_ranges: [], ecn_count: None }
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] Rx ACK space=ap, ranges=[0..=7]
server  | 1.096 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=7
server  | 1.096 DEBUG on_packets_acked this=0x560bc73306d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1401
server  | 1.096 DEBUG PMTUD probe of size 1420 succeeded
server  | 1.096 DEBUG PMTUD started with probe size 1470
server  | 1.096 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.096 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.096 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] pn=9 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, Ect0) len 1422
server  |   TX -> Ping
server  |   TX -> Padding { len: 1395 }
server  | 1.096 DEBUG packet_sent this=0x560bc73306d0, pn=9, ps=1422
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.096 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.096 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.096 DEBUG ECN probing: sent 10 probes
server  | 1.096 DEBUG ECN probing concluded with 10 probes sent
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.096 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.096 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] delay duration 102.066818ms
server  | 1.096 DEBUG Setting timeout of 102.066818ms
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.096 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.096 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.096 DEBUG [Server aed8761d3e4e36cc] delay duration 102.040048ms
server  | 1.096 DEBUG Setting timeout of 102.040048ms
server  | 1.153 DEBUG [Server aed8761d3e4e36cc] pn=8 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 37
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 3206, first_ack_range: 7, ack_ranges: [], ecn_count: None }
server  | 1.153 DEBUG [Server aed8761d3e4e36cc] Rx ACK space=ap, ranges=[2..=9]
server  | 1.153 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=9
server  | 1.153 WARN ECN validation failed, no ECN counts in ACK frame
server  | 1.153 DEBUG on_packets_acked this=0x560bc73306d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1422
server  | 1.153 DEBUG PMTUD probe of size 1470 succeeded
server  | 1.153 DEBUG PMTUD started with probe size 1500
server  | 1.153 DEBUG PLPMTU changed from 1372 to 1422, updating pacer
server  | 1.154 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false }
server  | 1.154 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.154 DEBUG Sending PMTUD probe of size 1500, count 1
server  | 1.154 DEBUG [Server aed8761d3e4e36cc] pn=10 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 1452
server  |   TX -> Ping
server  |   TX -> Padding { len: 1425 }
server  | 1.154 DEBUG packet_sent this=0x560bc73306d0, pn=10, ps=1452
server  | 1.154 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false }
server  | 1.154 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.154 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false }
server  | 1.154 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false }
server  | 1.154 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.154 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false }
server  | 1.154 DEBUG [Server aed8761d3e4e36cc] delay duration 94.027222ms
server  | 1.154 DEBUG Setting timeout of 94.027222ms
server  | 1.154 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false }
server  | 1.154 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.154 DEBUG TX blocked, profile=SendProfile { limit: 1422, pto: None, probe: EnumSet(), paced: false }
server  | 1.154 DEBUG [Server aed8761d3e4e36cc] delay duration 94.00442ms
server  | 1.154 DEBUG Setting timeout of 94.00442ms
server  | 1.211 DEBUG [Server aed8761d3e4e36cc] pn=9 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 37
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 3208, first_ack_range: 8, ack_ranges: [], ecn_count: None }
server  | 1.211 DEBUG [Server aed8761d3e4e36cc] Rx ACK space=ap, ranges=[2..=10]
server  | 1.211 DEBUG [recovery::Loss] ACK for ApplicationData - largest_acked=10
server  | 1.211 DEBUG on_packets_acked this=0x560bc73306d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1452
server  | 1.211 DEBUG PMTUD probe of size 1500 succeeded
server  | 1.211 INFO PMTUD stopped, PLPMTU is now 1500, raise timer Some(Instant { tv_sec: 868, tv_nsec: 76452092 })
server  | 1.211 DEBUG PLPMTU changed from 1422 to 1452, updating pacer
server  | 1.211 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false }
server  | 1.211 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.211 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false }
server  | 1.211 DEBUG [Server aed8761d3e4e36cc] delay duration 30s
server  | 1.211 DEBUG Setting timeout of 30s
server  | 1.211 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false }
server  | 1.211 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.211 DEBUG TX blocked, profile=SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false }
server  | 1.211 DEBUG [Server aed8761d3e4e36cc] delay duration 29.99993561s
server  | 1.211 DEBUG Setting timeout of 29.99993561s
client  | [HQ-req] send_bytes:17, recv_bytes:1024, path_info:0-1-1-17-1024
client  | task[0], fin_cnt: 1, fin_flag: 1
server  | 1.487 DEBUG [Server aed8761d3e4e36cc] pn=11 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 35
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  | 1.487 INFO [Server aed8761d3e4e36cc] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 1.487 DEBUG [Server aed8761d3e4e36cc] State change from Confirmed -> Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 268, tv_nsec: 615903914 } }
server  | 1.487 DEBUG [pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688] Path validated Instant { tv_sec: 268, tv_nsec: 352821764 }
server  | 1.487 DEBUG [Server aed8761d3e4e36cc] output_path send_profile SendProfile { limit: 1452, pto: None, probe: EnumSet(), paced: false }
server  | 1.487 DEBUG Building Short dcid Some(CID [8]: a0c73034deb0e162)
server  | 1.487 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 268, tv_nsec: 352821764 }
server  | 1.487 DEBUG [Server aed8761d3e4e36cc] pn=11 type=Short pri-path:a0c73034deb0e162 [::]:443->[::ffff:193.167.0.100]:36688 Tos(Cs0, NotEct) len 37
server  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 6 }], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 1.487 DEBUG Setting timeout of 262.955032ms
server  | 1.487 DEBUG Setting timeout of 262.941066ms
server  | 1.518 DEBUG [Server ...] Dropped received packet: State Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 268, tv_nsec: 615903914 } }; Total: 3
server  | 1.518 DEBUG Setting timeout of 232.42706ms
server  | 1.518 DEBUG Setting timeout of 232.408816ms
server  | 1.751 DEBUG [Server aed8761d3e4e36cc] State change from Draining { error: Transport(Peer(0)), timeout: Instant { tv_sec: 268, tv_nsec: 615903914 } } -> Closed(Transport(Peer(0)))
server  | 1.751 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:37:46,319 Using the client's key log file.
2025-09-11 19:37:46,327 
2025-09-11 19:37:46,328 Using the client's key log file.
2025-09-11 19:37:46,328 Using the client's key log file.
2025-09-11 19:37:46,335 
2025-09-11 19:37:46,335 Using the client's key log file.
2025-09-11 19:37:46,336 Using selector: EpollSelector
2025-09-11 19:37:46,543 Check of downloaded files succeeded.