2025-04-30 09:17:19,303 Generated random file: hvatrxufvj of size: 5120
2025-04-30 09:17:19,303 Generated random file: rumebxrhga of size: 10240
2025-04-30 09:17:19,304 Requests: https://server6:443/hvatrxufvj https://server6:443/rumebxrhga
2025-04-30 09:17:19,364 
2025-04-30 09:17:19,364 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_7xmbb82q/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_a129_a78/ DOWNLOADS=/tmp/download_fblgxq5s/ SERVER_LOGS=/tmp/logs_server_g1qk20b5 CLIENT_LOGS=/tmp/logs_client_wpfc6li_ SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=stammw/quinn-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server6:443/hvatrxufvj https://server6:443/rumebxrhga"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 09:17:34,744  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
client  | Executing client
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
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
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 transfer ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.vPTnUujKK5
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.vPTnUujKK5
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.vPTnUujKK5 -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  |             0c:c7:e3:89:ba:6b:9c:db:62:16:c5:12:fc:56:d3:10:
server  |             77:42:67:76
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner Root Certificate Authority"
server  |         Validity:
server  |             Not Before: Wed Apr 30 09:17:19 2025
server  |             Not After : Sat May 10 09:17:19 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:63:d4:d1:10:b8:59:09:89:a3:e9:66:ce:80:f7:aa:
server  |                     75:30:86:31:de:b4:10:27:56:c8:ca:a8:53:70:f4:b1:
server  |                     32:f8:0a:5d:74:a2:db:d5:ac:45:16:c8:29:fd:f5:62:
server  |                     e3:ce:ac:bd:fe:e2:67:00:b3:ed:5b:08:5b:74:33:06:
server  |                     ec
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  |                 d8:cc:46:c1:3a:92:49:93:ab:00:ed:53:ac:87:bc:43:
server  |                 31:25:24:2e
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 eb:a8:3e:ce:35:45:e0:41:45:00:a1:fb:16:1a:91:fc:
server  |                 71:02:3b:6a
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:99:2d:5c:33:05:85:a6:4c:01:af:a1:
server  |         5f:44:4e:f5:dc:76:77:2b:ad:7a:40:31:89:a1:24:81:
server  |         75:af:81:99:3d:02:21:00:e6:74:fe:ae:38:71:b6:16:
server  |         7b:14:ef:8b:af:99:be:bd:6f:c9:60:e7:cb:67:57:02:
server  |         f1:6d:15:2f:e3:cc:07:9e
server  |     Fingerprint (SHA-256):
server  |         FF:6A:3B:C2:F0:4B:D9:C6:44:35:56:13:A3:C3:9E:AF:FC:60:A5:E9:2E:26:C9:AC:CC:CA:9C:01:F5:E9:E7:DD
server  |     Fingerprint (SHA1):
server  |         5F:4B:ED:1E:17:E3:13:8D:35:64:A6:56:E4:52:DC:9F:1A:AD:09:08
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  | + '[' transfer = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test transfer --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443'
server  | ++ tee -i -a /logs/server.log
server  | 0.000 DEBUG Logging initialized
server  | 0.002 DEBUG Default socket send buffer size is 212992
server  | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.002 INFO Server waiting for connection on: [::]:443
server  | 0.801 DEBUG [Server] Unsupported version: 57414954
server  | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:58014 IpTos(Cs0, NotEct) len 15
server  | 0.801 DEBUG [Server] Unsupported version: 57414954
sim     | server:443 is available after 1.048517787s
server  | 0.801 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:58014 IpTos(Cs0, NotEct) len 15
server  | 0.801 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.802 DEBUG [Server] Unsupported version: 57414954
server  | 0.802 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:58014 IpTos(Cs0, NotEct) len 15
server  | 0.802 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
server  | 3.988 DEBUG [Server] Handle initial
server  | 3.988 INFO AddressValidation: no token; accepting
server  | 3.988 INFO [Server] Accept connection CID [20]: 57c84db544d2d0aecae800a08598ec766f9dd3bc
server  | 3.988 DEBUG Overwrite initial version Version1 ==> Version1
server  | 3.990 DEBUG Outbound interface eth0 for destination fd00:cafe:cafe::100 has MTU 1500
server  | 3.990 INFO [Server ...] Received valid Initial packet with scid CID [8]: b8b88c1215f8ee6c dcid CID [20]: 57c84db544d2d0aecae800a08598ec766f9dd3bc
server  | 3.990 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=57c84db544d2d0aecae800a08598ec766f9dd3bc
server  | 3.990 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 3.990 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 3.990 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] State change from Init -> WaitInitial
server  | 3.990 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=0 type=Initial unv-path [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1200
server  |   -> RX Crypto { offset: 0, len: 286 }
server  |   -> RX Padding { len: 855 }
server  | 3.990 DEBUG Read Ok(286) bytes
server  | 3.990 DEBUG 0-RTT: no token, no 0-RTT
server  | 3.990 DEBUG Read secret available for Handshake: SymKey [32]: 355230bcd8a8857a55e35dd1db7e49a03395d5ddb16c6d3dcdecfc5f8acad7d7
server  | 3.990 DEBUG Write secret available for Handshake: SymKey [32]: aef2562a45d7cfd1d59acfe8d775d990953d6dbf4ea3b822c7013a68de98cd4b
server  | 3.990 DEBUG Writing transport parameters, msg=8
server  | 3.991 DEBUG Read secret available for ApplicationData: SymKey [32]: 73658d2906742c73b5e8bb5a55d2cbe128e3e8dcbe701d490e4dfa48b3ce4889
server  | 3.991 DEBUG Write secret available for ApplicationData: SymKey [32]: afadd428612b1f51d3b488e6dd698796e3f601fd698e186a3363167d4e42942e
server  | 3.991 DEBUG [Agent 0x55ab8acf21d0] state -> InProgress
server  | 3.991 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 3.991 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 3.991 DEBUG [Crypto] Handshake keys installed
server  | 3.991 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 3.991 DEBUG [Crypto] Application write key installed
server  | 3.991 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 571, tv_nsec: 152107883 }
server  | 3.991 DEBUG [unv-path [::]:443->[fd00:cafe:cafe::100]:35520] Make permanent
server  | 3.991 DEBUG [unv-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520] set as primary path
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] State change from WaitInitial -> Handshaking
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.991 DEBUG Building Initial dcid Some(CID [8]: b8b88c1215f8ee6c) scid Some(CID [10]: bae3d5fc7be5e9cfda46)
server  | 3.991 DEBUG CRYPTO for in offset=0, len=90
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=0 type=Initial pri-unv-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 147
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 3.991 DEBUG Building Handshake dcid Some(CID [8]: b8b88c1215f8ee6c) scid Some(CID [10]: bae3d5fc7be5e9cfda46)
server  | 3.991 DEBUG CRYPTO for hs offset=0, len=736
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=0 type=Handshake pri-unv-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 931
server  |   TX -> Crypto { offset: 0, len: 736 }
server  | 3.991 DEBUG packet_sent this=0x55ab8acf1b40, pn=0, ps=784
server  | 3.991 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pad Initial from 931 to PLPMTU 1232
server  | 3.991 DEBUG packet_sent this=0x55ab8acf1b40, pn=0, ps=448
server  | 3.991 DEBUG ECN probing: sent 1 probes
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.991 DEBUG Building Initial dcid Some(CID [8]: b8b88c1215f8ee6c) scid Some(CID [10]: bae3d5fc7be5e9cfda46)
server  | 3.991 DEBUG Building Handshake dcid Some(CID [8]: b8b88c1215f8ee6c) scid Some(CID [10]: bae3d5fc7be5e9cfda46)
server  | 3.991 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 3.991 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 296.928879ms
server  | 3.991 DEBUG Setting timeout of 296.928879ms
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.991 DEBUG Building Initial dcid Some(CID [8]: b8b88c1215f8ee6c) scid Some(CID [10]: bae3d5fc7be5e9cfda46)
server  | 3.991 DEBUG Building Handshake dcid Some(CID [8]: b8b88c1215f8ee6c) scid Some(CID [10]: bae3d5fc7be5e9cfda46)
server  | 3.991 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 3.991 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.991 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 296.895487ms
server  | 3.991 DEBUG Setting timeout of 296.895487ms
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=1 type=Initial pri-unv-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 53
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 45, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=in, ranges=[0..=0]
server  | 4.024 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 4.024 DEBUG on_packets_acked this=0x55ab8acf1b40, limited=1, bytes_in_flight=784, cwnd=12320, state=SlowStart, new_acked=448
server  | 4.024 DEBUG Acked crypto frame space=in offset=0 length=90
server  | 4.024 DEBUG [pri-unv-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520] Path validated Instant { tv_sec: 571, tv_nsec: 188041385 }
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Drop packet number space in
server  | 4.024 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=0 type=Handshake pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1147
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 45, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  |   -> RX Padding { len: 1056 }
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=hs, ranges=[0..=0]
server  | 4.024 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 4.024 DEBUG on_packets_acked this=0x55ab8acf1b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=784
server  | 4.024 DEBUG Acked crypto frame space=hs offset=0 length=736
server  | 4.024 DEBUG Read Ok(36) bytes
server  | 4.024 DEBUG [Agent 0x55ab8acf21d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] TLS connection complete
server  | 4.024 DEBUG [pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520] Path validated Instant { tv_sec: 571, tv_nsec: 188041385 }
server  | 4.024 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 4.024 DEBUG [Crypto] application read keys installed
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] State change from Handshaking -> Connected
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] State change from Connected -> Confirmed
server  | 4.024 DEBUG PMTUD started with probe size 1380
server  | 4.024 INFO [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Connection established
server  | 4.024 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 571, tv_nsec: 188041385 }
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.024 DEBUG Building Handshake dcid Some(CID [8]: b8b88c1215f8ee6c) scid Some(CID [10]: bae3d5fc7be5e9cfda46)
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=1 type=Handshake pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 52
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Drop packet number space hs
server  | 4.024 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 4.024 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=0 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [82, 62, 158, 227, 25, 0, 31, 119, 102, 157], stateless_reset_token: [207, 240, 16, 131, 208, 70, 32, 54, 76, 93, 129, 29, 186, 162, 22, 233] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [145, 101, 25, 142, 3, 75, 134, 34, 76, 102], stateless_reset_token: [134, 133, 10, 227, 247, 232, 30, 117, 8, 248, 109, 217, 34, 135, 190, 77] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [71, 241, 222, 220, 114, 122, 85, 154, 220, 129], stateless_reset_token: [161, 254, 109, 215, 161, 249, 188, 206, 38, 59, 69, 96, 39, 56, 32, 251] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [14, 56, 29, 200, 94, 144, 146, 4, 196, 123], stateless_reset_token: [46, 25, 11, 160, 83, 134, 219, 111, 110, 127, 53, 244, 184, 75, 157, 235] }
server  |   TX -> Padding { len: 1133 }
server  | 4.024 DEBUG packet_sent this=0x55ab8acf1b40, pn=0, ps=1280
server  | 4.024 DEBUG ECN probing: sent 2 probes
server  | 4.024 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.024 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.024 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=1 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 4.025 DEBUG packet_sent this=0x55ab8acf1b40, pn=1, ps=1332
server  | 4.025 DEBUG ECN probing: sent 3 probes
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.344832ms
server  | 4.025 DEBUG Setting timeout of 1.344832ms
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=0 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 224
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [220, 105, 121, 218, 73, 124, 70, 95], stateless_reset_token: [140, 168, 196, 48, 241, 27, 8, 86, 172, 124, 56, 3, 5, 135, 115, 186] }
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [194, 185, 83, 189, 106, 75, 171, 135], stateless_reset_token: [162, 141, 2, 71, 0, 91, 173, 74, 226, 102, 128, 189, 105, 241, 118, 33] }
server  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [76, 14, 6, 226, 252, 69, 127, 130], stateless_reset_token: [26, 168, 187, 149, 10, 214, 203, 165, 185, 255, 48, 3, 18, 250, 124, 159] }
server  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [6, 160, 196, 0, 115, 6, 135, 54], stateless_reset_token: [204, 77, 73, 78, 230, 203, 243, 112, 119, 30, 83, 56, 64, 178, 122, 140] }
server  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [51, 86, 6, 153, 136, 85, 9, 236], stateless_reset_token: [243, 57, 79, 124, 131, 44, 227, 191, 32, 237, 251, 27, 201, 167, 94, 81] }
server  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [69, 94, 5, 218, 129, 104, 87, 41], stateless_reset_token: [159, 114, 193, 185, 202, 229, 146, 173, 5, 114, 87, 63, 152, 69, 116, 144] }
server  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [31, 40, 2, 129, 5, 63, 37, 2], stateless_reset_token: [104, 90, 61, 208, 201, 203, 115, 23, 29, 177, 54, 60, 165, 118, 10, 253] }
server  | 4.025 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 571, tv_nsec: 208739465 }
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.315367ms
server  | 4.025 DEBUG Setting timeout of 1.315367ms
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=1 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 48
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 4.025 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 571, tv_nsec: 188830384 }
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=2 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 34
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 4.025 DEBUG ECN probing: sent 4 probes
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.206322ms
server  | 4.025 DEBUG Setting timeout of 1.206322ms
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=2 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 48
server  |   -> RX Stream { stream_id: 4, offset: 0, len: 17, fin: true }
server  | 4.025 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 571, tv_nsec: 208933346 }
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.025 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.171627ms
server  | 4.025 DEBUG Setting timeout of 1.171627ms
server  | 4.026 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] send session ticket 406f001457c84db544d2d0aecae800a08598ec766f9dd3bc0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0abae3d5fc7be5e9cfda46110c00000001fafa9a1a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 4.026 DEBUG Path = 'hvatrxufvj'
server  | 4.026 DEBUG Path = 'rumebxrhga'
server  | 4.026 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.026 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.026 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 71.227µs
server  | 4.026 DEBUG Setting timeout of 71.227µs
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.027 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=3 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false }
server  | 4.027 DEBUG packet_sent this=0x55ab8acf1b40, pn=3, ps=1232
server  | 4.027 DEBUG ECN probing: sent 5 probes
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.027 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=4 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false }
server  | 4.027 DEBUG packet_sent this=0x55ab8acf1b40, pn=4, ps=1232
server  | 4.027 DEBUG ECN probing: sent 6 probes
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.027 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.718608ms
server  | 4.027 DEBUG Setting timeout of 1.718608ms
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.027 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.027 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.685556ms
server  | 4.027 DEBUG Setting timeout of 1.685556ms
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.030 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=5 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false }
server  | 4.030 DEBUG packet_sent this=0x55ab8acf1b40, pn=5, ps=1232
server  | 4.030 DEBUG ECN probing: sent 7 probes
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.030 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=6 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false }
server  | 4.030 DEBUG packet_sent this=0x55ab8acf1b40, pn=6, ps=1232
server  | 4.030 DEBUG ECN probing: sent 8 probes
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.030 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.680931ms
server  | 4.030 DEBUG Setting timeout of 1.680931ms
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.030 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.030 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.65376ms
server  | 4.030 DEBUG Setting timeout of 1.65376ms
server  | 4.033 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.033 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.033 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=7 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 4810, len: 310, fin: true }
server  |   TX -> Stream { stream_id: 4, offset: 0, len: >>888, fin: false }
server  | 4.033 DEBUG packet_sent this=0x55ab8acf1b40, pn=7, ps=1232
server  | 4.033 DEBUG ECN probing: sent 9 probes
server  | 4.033 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.033 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.033 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.033 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.232396ms
server  | 4.033 DEBUG Setting timeout of 1.232396ms
server  | 4.033 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.033 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.033 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.033 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 1.209383ms
server  | 4.033 DEBUG Setting timeout of 1.209383ms
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.035 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=8 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 4, offset: 888, len: >>1202, fin: false }
server  | 4.035 DEBUG packet_sent this=0x55ab8acf1b40, pn=8, ps=1232
server  | 4.035 DEBUG ECN probing: sent 10 probes
server  | 4.035 DEBUG ECN probing concluded with 10 probes sent
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.035 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=9 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 4, offset: 2090, len: >>1202, fin: false }
server  | 4.035 DEBUG packet_sent this=0x55ab8acf1b40, pn=9, ps=1232
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1084, pto: None, probe: EnumSet(), paced: false }
server  | 4.035 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=10 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, NotEct) len 1084
server  |   TX -> Stream { stream_id: 4, offset: 3292, len: >>1054, fin: false }
server  | 4.035 DEBUG packet_sent this=0x55ab8acf1b40, pn=10, ps=1084
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.035 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.035 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 9.818552ms
server  | 4.035 DEBUG Setting timeout of 9.818552ms
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.035 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.035 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.035 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 9.794437ms
server  | 4.035 DEBUG Setting timeout of 9.794437ms
server  | 4.046 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.046 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.046 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=11 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, NotEct) len 35
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 2662, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  | 4.046 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.046 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.046 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.046 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 103.600387ms
server  | 4.046 DEBUG Setting timeout of 103.600387ms
server  | 4.046 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.046 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.046 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.046 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 103.573677ms
server  | 4.046 DEBUG Setting timeout of 103.573677ms
server  | 4.057 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=3 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 7, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=2]
server  | 4.058 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 4.058 INFO ECN validation succeeded, path is capable
server  | 4.058 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] slow start += 2612
server  | 4.058 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=9708, cwnd=14932, state=SlowStart, new_acked=2612
server  | 4.058 DEBUG PMTUD probe of size 1380 succeeded
server  | 4.058 DEBUG PMTUD started with probe size 1420
server  | 4.058 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.058 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=12 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 4, offset: 4346, len: >>1341, fin: false }
server  | 4.058 DEBUG packet_sent this=0x55ab8acf1b40, pn=12, ps=1372
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=13 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 5687, len: >>1302, fin: false }
server  | 4.058 DEBUG packet_sent this=0x55ab8acf1b40, pn=13, ps=1332
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=14 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 6989, len: >>1302, fin: false }
server  | 4.058 DEBUG packet_sent this=0x55ab8acf1b40, pn=14, ps=1332
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1188, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=15 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1188
server  |   TX -> Stream { stream_id: 4, offset: 8291, len: >>1158, fin: false }
server  | 4.058 DEBUG packet_sent this=0x55ab8acf1b40, pn=15, ps=1188
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.058 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 104.029843ms
server  | 4.058 DEBUG Setting timeout of 104.029843ms
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.058 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.058 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 104.003795ms
server  | 4.058 DEBUG Setting timeout of 104.003795ms
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=4 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 2, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=4]
server  | 4.060 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 4.060 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 12468/17396 ssthresh 18446744073709551615] slow start += 2464
server  | 4.060 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 12468/17396 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=12468, cwnd=17396, state=SlowStart, new_acked=2464
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.060 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.060 DEBUG CRYPTO for ap offset=0, len=321
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=16 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 1193
server  |   TX -> Stream { stream_id: 4, offset: 9449, len: 791, fin: true }
server  |   TX -> Crypto { offset: 0, len: 321 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 70, 110, 230, 63, 240, 85, 221, 120, 74, 38, 160, 186, 15, 251, 232, 38, 38, 147, 43, 181, 123, 138, 94, 184, 124, 136, 202, 195, 169, 214, 189, 212, 62, 152, 200, 56] }
server  | 4.060 DEBUG packet_sent this=0x55ab8acf1b40, pn=16, ps=1193
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.060 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.060 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 95.716167ms
server  | 4.060 DEBUG Setting timeout of 95.716167ms
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.060 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.060 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.060 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 95.681432ms
server  | 4.060 DEBUG Setting timeout of 95.681432ms
server  | 4.063 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=5 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 2, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 4.063 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=6]
server  | 4.063 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6
server  | 4.063 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11197/19860 ssthresh 18446744073709551615] slow start += 2464
server  | 4.063 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11197/19860 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=11197, cwnd=19860, state=SlowStart, new_acked=2464
server  | 4.063 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.063 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.063 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.063 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 86.173002ms
server  | 4.063 DEBUG Setting timeout of 86.173002ms
server  | 4.063 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.063 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.063 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.063 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 86.105035ms
server  | 4.063 DEBUG Setting timeout of 86.105035ms
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=6 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 2, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=8]
server  | 4.066 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8
server  | 4.066 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8733/22324 ssthresh 18446744073709551615] slow start += 2464
server  | 4.066 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8733/22324 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=8733, cwnd=22324, state=SlowStart, new_acked=2464
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.066 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=17 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 28
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 4.066 DEBUG packet_sent this=0x55ab8acf1b40, pn=17, ps=29
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.066 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.066 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 84.773946ms
server  | 4.066 DEBUG Setting timeout of 84.773946ms
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.066 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.066 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.066 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 84.747888ms
server  | 4.066 DEBUG Setting timeout of 84.747888ms
server  | 4.068 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=7 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 1, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  | 4.068 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=10]
server  | 4.068 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=10
server  | 4.068 WARN ECN validation failed, no ECT(0) packets were newly acked
server  | 4.068 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6446/24640 ssthresh 18446744073709551615] slow start += 2316
server  | 4.068 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6446/24640 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=6446, cwnd=24640, state=SlowStart, new_acked=2316
server  | 4.068 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.068 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.068 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.068 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 77.631143ms
server  | 4.068 DEBUG Setting timeout of 77.631143ms
server  | 4.068 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.068 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.068 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.068 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 77.565761ms
server  | 4.068 DEBUG Setting timeout of 77.565761ms
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=8 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 13, ack_delay: 1, first_ack_range: 13, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) }
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=13]
server  | 4.090 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=13
server  | 4.090 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3742/27344 ssthresh 18446744073709551615] slow start += 2704
server  | 4.090 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3742/27344 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=3742, cwnd=27344, state=SlowStart, new_acked=2704
server  | 4.090 DEBUG PMTUD probe of size 1420 succeeded
server  | 4.090 DEBUG PMTUD started with probe size 1470
server  | 4.090 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.090 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.090 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=18 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, NotEct) len 1422
server  |   TX -> Ping
server  |   TX -> Padding { len: 1395 }
server  | 4.090 DEBUG packet_sent this=0x55ab8acf1b40, pn=18, ps=1422
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.090 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.090 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 75.71355ms
server  | 4.090 DEBUG Setting timeout of 75.71355ms
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.090 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.090 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.090 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 75.681169ms
server  | 4.090 DEBUG Setting timeout of 75.681169ms
server  | 4.092 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=9 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 15, ack_delay: 2, first_ack_range: 15, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 13, Ce: 0})) }
server  | 4.092 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=15]
server  | 4.092 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=15
server  | 4.092 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2644/29864 ssthresh 18446744073709551615] slow start += 2520
server  | 4.092 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2644/29864 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=2644, cwnd=29864, state=SlowStart, new_acked=2520
server  | 4.092 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.092 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.092 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.092 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 70.210733ms
server  | 4.092 DEBUG Setting timeout of 70.210733ms
server  | 4.092 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.092 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.092 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.092 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] delay duration 70.148006ms
server  | 4.092 DEBUG Setting timeout of 70.148006ms
server  | 4.093 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=10 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, Ect0) len 39
server  |   -> RX Ack { largest_acknowledged: 16, ack_delay: 14, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 14, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" }
server  | 4.093 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] Rx ACK space=ap, ranges=[0..=16]
server  | 4.093 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=16
server  | 4.093 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1451/31057 ssthresh 18446744073709551615] slow start += 1193
server  | 4.093 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1451/31057 ssthresh 18446744073709551615] on_packets_acked this=0x55ab8acf1b40, limited=0, bytes_in_flight=1451, cwnd=31057, state=SlowStart, new_acked=1193
server  | 4.093 DEBUG Acked crypto frame space=ap offset=0 length=321
server  | 4.093 INFO [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] ConnectionClose received. Error code: Application(0) frame type 0 reason 
server  | 4.093 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 571, tv_nsec: 465536586 } }
server  | 4.094 DEBUG [pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520] Path validated Instant { tv_sec: 571, tv_nsec: 257606028 }
server  | 4.094 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.094 DEBUG Building Short dcid Some(CID [8]: b8b88c1215f8ee6c)
server  | 4.094 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 571, tv_nsec: 257606028 }
server  | 4.094 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] pn=19 type=Short pri-path:b8b88c1215f8ee6c [::]:443->[fd00:cafe:cafe::100]:35520 IpTos(Cs0, NotEct) len 38
server  |   TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 11, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 4.094 DEBUG Setting timeout of 207.772193ms
server  | 4.094 DEBUG Setting timeout of 207.760401ms
server  | 4.097 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 571, tv_nsec: 465536586 } }; Total: 1
server  | 4.097 DEBUG Setting timeout of 204.553036ms
server  | 4.097 DEBUG Setting timeout of 204.513512ms
server  | 4.122 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 571, tv_nsec: 465536586 } }; Total: 2
server  | 4.122 DEBUG Setting timeout of 179.253223ms
server  | 4.122 DEBUG Setting timeout of 179.23574ms
server  | 4.302 DEBUG [Server 57c84db544d2d0aecae800a08598ec766f9dd3bc] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 571, tv_nsec: 465536586 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 4.302 INFO Closing timer expired

client exited with code 0
Aborting on container exit...
 Container client  Stopping
 Container server  Stopping
 Container client  Stopped
 Container server  Stopped
 Container sim  Stopping
 Container sim  Stopped

2025-04-30 09:17:34,849 Using the client's key log file.
2025-04-30 09:17:34,861 
2025-04-30 09:17:34,861 Using the client's key log file.
2025-04-30 09:17:34,861 Using the client's key log file.
2025-04-30 09:17:34,872 
2025-04-30 09:17:34,872 Using the client's key log file.
2025-04-30 09:17:35,438 Check of downloaded files succeeded.
2025-04-30 09:17:36,007 Check of downloaded files succeeded.