2025-04-30 12:55:25,268 Generated random file: mgboelfdlr of size: 5120
2025-04-30 12:55:25,269 Generated random file: jmzhlaqhus of size: 10240
2025-04-30 12:55:25,269 Requests: https://server6:443/mgboelfdlr https://server6:443/jmzhlaqhus
2025-04-30 12:55:25,333 
2025-04-30 12:55:25,334 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_nh387x9j/ TESTCASE_SERVER=transfer TESTCASE_CLIENT=transfer WWW=/tmp/www_e26_4zg6/ DOWNLOADS=/tmp/download_b1s9m1sl/ SERVER_LOGS=/tmp/logs_server_b82jkkzt CLIENT_LOGS=/tmp/logs_client_3pk7c299 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server6:443/mgboelfdlr https://server6:443/jmzhlaqhus"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:55:37,713  Container sim  Recreate
 Container sim  Recreated
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
server  | Setting up routes...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
server  | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
server  | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
server  | + '[' -n transfer ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.hL9i16Dmv5
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
client  | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
client  | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
client  | + '[' -n transfer ']'
client  | + '[' -n /logs/qlog/ ']'
client  | + case "$ROLE" in
client  | + /wait-for-it.sh sim:57832 -s -t 30
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.hL9i16Dmv5
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.hL9i16Dmv5 -W ''
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | pk12util: PKCS12 IMPORT SUCCESSFUL
server  | + certutil -L -d sql:/neqo/db -n cert
server  | Certificate:
server  |     Data:
server  |         Version: 3 (0x2)
server  |         Serial Number:
server  |             22:e8:d3:b4:79:6b:fe:e5:39:86:31:3b:d2:73:e9:76:
server  |             d1:c3:45:9f
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 12:55:25 2025
server  |             Not After : Sat May 10 12:55:25 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:7d:b0:e0:63:f2:cc:9e:02:94:45:32:e9:b4:66:20:
server  |                     89:69:0d:9b:19:b8:0c:1a:67:99:40:33:bb:ab:dd:d4:
server  |                     8d:4e:9f:8e:64:26:9d:81:3c:31:88:75:18:12:3a:19:
server  |                     0e:14:82:80:21:b8:39:4d:4a:a3:1c:fb:59:b2:19:5e:
server  |                     8f
server  |                 Curve: ANSI X9.62 elliptic curve prime256v1 (aka secp256r1, NIST P-256)
server  |         Signed Extensions:
server  |             Name: Certificate Subject Alt Name
server  |             DNS name: "server"
server  |             DNS name: "server4"
server  |             DNS name: "server6"
server  |             DNS name: "server46"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 14:47:6a:5b:fe:72:62:70:27:0a:8b:da:31:3e:13:cd:
server  |                 43:61:7c:61
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 6c:d4:ce:e8:22:fd:d3:ec:6d:db:14:23:e1:d3:cc:07:
server  |                 b8:ea:00:3f
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:e4:52:2c:61:d2:16:cf:0f:2e:c4:66:
server  |         13:ce:78:10:da:7b:fd:a7:78:fc:f5:ee:3c:b8:15:cb:
server  |         1f:9a:f5:49:cd:02:21:00:c3:d8:08:ef:be:89:4c:17:
server  |         43:95:25:56:86:8e:b5:5c:3b:4f:44:f0:fc:27:f5:f2:
server  |         24:ea:ba:57:ed:a7:a2:9e
server  |     Fingerprint (SHA-256):
server  |         78:C0:E4:9A:9D:D5:93:CB:3B:98:CE:8C:84:57:81:26:B0:CE:ED:19:68:99:6D:A7:C2:88:72:7E:A3:9B:FB:D6
server  |     Fingerprint (SHA1):
server  |         DE:F3:92:C5:26:B6:18:3C:DD:99:37:3D:63:13:D9:44:6B:68:9F:E9
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.738 DEBUG [Server] Unsupported version: 57414954
server  | 0.739 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48007 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.015222201s
server  | 0.739 DEBUG [Server] Unsupported version: 57414954
server  | 0.739 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48007 IpTos(Cs0, NotEct) len 15
server  | 0.739 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.739 DEBUG [Server] Unsupported version: 57414954
server  | 0.739 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48007 IpTos(Cs0, NotEct) len 15
server  | 0.739 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 eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads)
client  | + '[' 'https://server6:443/mgboelfdlr https://server6:443/jmzhlaqhus' ']'
client  | + mapfile -d ' ' -t URLS
client  | + OPTIONS+=("${URLS[@]}")
client  | + RUST_LOG=debug
client  | + RUST_BACKTRACE=1
client  | + neqo-client --cc cubic --qns-test transfer --qlog-dir /logs/qlog/ --output-dir /downloads https://server6:443/mgboelfdlr 'https://server6:443/jmzhlaqhus
client  | '
client  | ++ tee -i -a /logs/client.log
client  | 0.000 DEBUG Logging initialized
client  | 0.000 DEBUG Default socket send buffer size is 212992
client  | 0.001 DEBUG Default socket receive buffer size is 1048576, not changing
client  | 0.001 INFO hq-interop Client connecting: [::]:54295 -> [fd00:cafe:cafe:100::100]:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=6acea62306d08bcf002b73a9d5180765c6
client  | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
client  | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
client  | 0.001 DEBUG Outbound interface eth0 for destination fd00:cafe:cafe:100::100 has MTU 1500
client  | 0.001 DEBUG [unv-path [::]:54295->[fd00:cafe:cafe:100::100]:443] Make permanent
client  | 0.001 DEBUG [unv-path:6acea62306d08bcf002b73a9d5180765c6 [::]:54295->[fd00:cafe:cafe:100::100]:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:6acea62306d08bcf002b73a9d5180765c6 [::]:54295->[fd00:cafe:cafe:100::100]:443] Path validated Instant { tv_sec: 723, tv_nsec: 662363168 }
client  | 0.001 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] client_start
client  | 0.001 DEBUG Writing transport parameters, msg=1
client  | 0.001 DEBUG [Agent 0x55ed46149ed0] state -> InProgress
client  | 0.001 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] State change from Init -> WaitInitial
client  | 0.001 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.001 DEBUG Building Initial dcid Some(CID [17]: 6acea62306d08bcf002b73a9d5180765c6) scid Some(CID [0]: )
client  | 0.001 DEBUG CRYPTO for in offset=0, len=1184
client  | 0.001 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Initial pri-path:6acea62306d08bcf002b73a9d5180765c6 [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1232
client  |   TX -> Crypto { offset: 0, len: 1184 }
client  | 0.001 DEBUG packet_sent this=0x55ed4615d6d0, pn=0, ps=1232
client  | 0.001 DEBUG ECN probing: sent 1 probes
client  | 0.002 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [17]: 6acea62306d08bcf002b73a9d5180765c6) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1184, len=355
client  | 0.002 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Initial pri-path:6acea62306d08bcf002b73a9d5180765c6 [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 404
client  |   TX -> Crypto { offset: 1184, len: 355 }
client  | 0.002 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pad Initial from 404 to PLPMTU 1232
client  | 0.002 DEBUG packet_sent this=0x55ed4615d6d0, pn=1, ps=1232
client  | 0.002 DEBUG ECN probing: sent 2 probes
client  | 0.002 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [17]: 6acea62306d08bcf002b73a9d5180765c6) scid Some(CID [0]: )
client  | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 3.937955ms
client  | 0.002 DEBUG Setting timeout of 3.937955ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [17]: 6acea62306d08bcf002b73a9d5180765c6) scid Some(CID [0]: )
client  | 0.002 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 3.912808ms
client  | 0.002 DEBUG Setting timeout of 3.912808ms
client  | 0.007 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [17]: 6acea62306d08bcf002b73a9d5180765c6) scid Some(CID [0]: )
client  | 0.007 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 294.820576ms
client  | 0.007 DEBUG Setting timeout of 294.820576ms
server  | 1.008 DEBUG [Server] Handle initial
server  | 1.008 INFO AddressValidation: no token; accepting
server  | 1.008 INFO [Server] Accept connection CID [17]: 6acea62306d08bcf002b73a9d5180765c6
server  | 1.008 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.010 DEBUG Outbound interface eth0 for destination fd00:cafe:cafe::100 has MTU 1500
server  | 1.010 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [17]: 6acea62306d08bcf002b73a9d5180765c6
server  | 1.010 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=6acea62306d08bcf002b73a9d5180765c6
server  | 1.010 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.010 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] State change from Init -> WaitInitial
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Initial unv-path [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1232
server  |   -> RX Crypto { offset: 0, len: 1184 }
server  | 1.010 DEBUG Read Ok(1184) bytes
server  | 1.010 DEBUG [Agent 0x55c3155861d0] state -> InProgress
server  | 1.010 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 733, tv_nsec: 686719159 }
server  | 1.010 DEBUG [unv-path [::]:443->[fd00:cafe:cafe::100]:54295] Make permanent
server  | 1.010 DEBUG [unv-path: [::]:443->[fd00:cafe:cafe::100]:54295] set as primary path
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] State change from WaitInitial -> WaitVersion
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.010 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Initial pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 45
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  | 1.010 DEBUG ECN probing: sent 1 probes
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.010 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.010 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 298.040227ms
server  | 1.010 DEBUG Setting timeout of 298.040227ms
server  | 1.010 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Initial pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 404
server  |   -> RX Crypto { offset: 1184, len: 355 }
server  | 1.010 DEBUG Read Ok(355) bytes
server  | 1.010 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.011 DEBUG Read secret available for Handshake: SymKey [32]: aef8614653697f64945686636312d70f34a22308bfd84ef0c83782750aa92f4d
server  | 1.011 DEBUG Write secret available for Handshake: SymKey [32]: 66a30f4c96e883c518050897f28954b7dca80cec40eb7ef7db25de6bf750dfc2
server  | 1.011 DEBUG Writing transport parameters, msg=8
server  | 1.011 DEBUG Read secret available for ApplicationData: SymKey [32]: cd595ab38a70ac124265a5df0e53b05e69b48af8b794edfa421c7de7eabd39a9
server  | 1.011 DEBUG Write secret available for ApplicationData: SymKey [32]: ae51b0708d7997ada146333e9dbc313120d5d543dae54a6b1089d70fa9771ee3
server  | 1.011 DEBUG [Agent 0x55c3155861d0] state -> InProgress
server  | 1.011 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.011 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.011 DEBUG [Crypto] Handshake keys installed
server  | 1.011 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.011 DEBUG [Crypto] Application write key installed
server  | 1.011 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 733, tv_nsec: 688708477 }
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] State change from WaitVersion -> Handshaking
server  | 1.011 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 1
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.011 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG CRYPTO for in offset=0, len=1178
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Initial pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1227
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  |   TX -> Crypto { offset: 0, len: 1178 }
server  | 1.011 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pad Initial from 1227 to PLPMTU 1232
server  | 1.011 DEBUG packet_sent this=0x55c315585b40, pn=1, ps=1232
server  | 1.011 DEBUG ECN probing: sent 2 probes
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.011 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG CRYPTO for hs offset=0, len=742
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Handshake pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 782
server  |   TX -> Crypto { offset: 0, len: 742 }
server  | 1.011 DEBUG packet_sent this=0x55c315585b40, pn=0, ps=782
server  | 1.011 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.011 DEBUG ECN probing: sent 3 probes
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.011 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.011 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 298.678507ms
server  | 1.011 DEBUG Setting timeout of 298.678507ms
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.011 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.011 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.011 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.011 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 298.649092ms
server  | 1.011 DEBUG Setting timeout of 298.649092ms
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Initial pri-path:6acea62306d08bcf002b73a9d5180765c6 [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 45
client  |   -> RX 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})) }
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=in, ranges=[0..=0]
client  | 0.042 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
client  | 0.042 DEBUG on_packets_acked this=0x55ed4615d6d0, limited=1, bytes_in_flight=1232, cwnd=12320, state=SlowStart, new_acked=1232
client  | 0.042 DEBUG Acked crypto frame space=in offset=0 length=1184
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Changing to use Server CID=a3f73c267c82586cf20a
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] State change from WaitInitial -> WaitVersion
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG Building Initial dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.042 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 82.53907ms
client  | 0.042 DEBUG Setting timeout of 82.53907ms
client  | 0.042 WARN Unhandled event StateChange(WaitVersion)
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG Building Initial dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.042 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.042 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 82.500369ms
client  | 0.042 DEBUG Setting timeout of 82.500369ms
client  | 0.044 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Initial pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1227
client  |   -> RX 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})) }
client  |   -> RX Crypto { offset: 0, len: 1178 }
client  | 0.044 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=in, ranges=[0..=1]
client  | 0.044 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
client  | 0.044 DEBUG on_packets_acked this=0x55ed4615d6d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1232
client  | 0.044 DEBUG Acked crypto frame space=in offset=1184 length=355
client  | 0.044 DEBUG Read Ok(1178) bytes
client  | 0.045 DEBUG Write secret available for Handshake: SymKey [32]: aef8614653697f64945686636312d70f34a22308bfd84ef0c83782750aa92f4d
client  | 0.045 DEBUG Read secret available for Handshake: SymKey [32]: 66a30f4c96e883c518050897f28954b7dca80cec40eb7ef7db25de6bf750dfc2
client  | 0.045 DEBUG [Agent 0x55ed46149ed0] state -> InProgress
client  | 0.045 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.045 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.045 DEBUG [Crypto] Handshake keys installed
client  | 0.045 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 706082592 }
client  | 0.045 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] State change from WaitVersion -> Handshaking
client  | 0.045 INFO [Client 6acea62306d08bcf002b73a9d5180765c6] Garbage packet: Transport error: InvalidPacket
client  | 0.045 WARN [Client ...] Dropped received packet: Garbage packet; Total: 1
client  | 0.045 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.045 DEBUG Building Initial dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.045 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=2 type=Initial pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 46
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 86, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.045 DEBUG Building Handshake dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.045 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pad Initial from 46 to PLPMTU 1232
client  | 0.045 DEBUG ECN probing: sent 3 probes
client  | 0.045 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.045 DEBUG Building Initial dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.045 DEBUG Building Handshake dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.045 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.045 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 61.055152ms
client  | 0.045 DEBUG Setting timeout of 61.055152ms
client  | 0.045 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Handshake pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 782
client  |   -> RX Crypto { offset: 0, len: 742 }
client  | 0.045 DEBUG Read Ok(742) bytes
client  | 0.046 DEBUG [Agent 0x55ed46149ed0] state -> AuthenticationPending
client  | 0.046 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 706895838 }
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.046 DEBUG Building Initial dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.046 DEBUG Building Handshake dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Handshake pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 45
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 90, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Drop packet number space in
client  | 0.046 DEBUG [LossRecovery] Reset loss recovery state for Initial
client  | 0.046 DEBUG ECN probing: sent 4 probes
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.046 DEBUG Building Handshake dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.046 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 104.480568ms
client  | 0.046 DEBUG Setting timeout of 104.480568ms
client  | 0.046 DEBUG StateChange(Handshaking)
client  | 0.046 WARN Cannot create stream ConnectionState
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Authenticated Ok
client  | 0.046 DEBUG Write secret available for ApplicationData: SymKey [32]: cd595ab38a70ac124265a5df0e53b05e69b48af8b794edfa421c7de7eabd39a9
client  | 0.046 DEBUG Read secret available for ApplicationData: SymKey [32]: ae51b0708d7997ada146333e9dbc313120d5d543dae54a6b1089d70fa9771ee3
client  | 0.046 DEBUG [Agent 0x55ed46149ed0] SSL_AuthCertificateComplete: Ok(())
client  | 0.046 DEBUG [Agent 0x55ed46149ed0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] TLS connection complete
client  | 0.046 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.046 DEBUG [Crypto] Application write key installed
client  | 0.046 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.046 DEBUG [Crypto] application read keys installed
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] State change from Handshaking -> Connected
client  | 0.046 INFO [Client 6acea62306d08bcf002b73a9d5180765c6] Connection established
client  | 0.046 DEBUG stream BiDi creatable
client  | 0.046 INFO Created stream 0 for https://server6/mgboelfdlr
client  | 0.046 INFO Saving https://server6/mgboelfdlr to "/downloads/mgboelfdlr"
client  | 0.046 INFO Created stream 4 for https://server6/jmzhlaqhus
client  | 0.046 INFO Saving https://server6/jmzhlaqhus to "/downloads/jmzhlaqhus"
client  | 0.046 DEBUG stream UniDi creatable
client  | 0.046 DEBUG StateChange(Connected)
client  | 0.046 DEBUG stream 0 writable
client  | 0.046 DEBUG stream 4 writable
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.046 DEBUG Building Handshake dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.046 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Handshake pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 75
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.046 DEBUG packet_sent this=0x55ed4615d6d0, pn=1, ps=75
client  | 0.046 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 150
client  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10323, ignore_order: false }
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  |   TX -> Stream { stream_id: 4, offset: 0, len: 17, fin: true }
client  | 0.046 DEBUG packet_sent this=0x55ed4615d6d0, pn=0, ps=75
client  | 0.046 DEBUG ECN probing: sent 5 probes
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.046 DEBUG Building Handshake dcid Some(CID [10]: a3f73c267c82586cf20a) scid Some(CID [0]: )
client  | 0.046 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.046 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.046 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 104.471511ms
client  | 0.046 DEBUG Setting timeout of 104.471511ms
server  | 1.044 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=2 type=Initial pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 46
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 86, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.044 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=in, ranges=[0..=1]
server  | 1.044 DEBUG [LossRecovery] ACK for Initial - largest_acked=1
server  | 1.044 DEBUG on_packets_acked this=0x55c315585b40, limited=1, bytes_in_flight=782, cwnd=12320, state=SlowStart, new_acked=1232
server  | 1.044 DEBUG Acked crypto frame space=in offset=0 length=1178
server  | 1.044 DEBUG [pri-unv-path: [::]:443->[fd00:cafe:cafe::100]:54295] Path validated Instant { tv_sec: 733, tv_nsec: 723088507 }
server  | 1.044 WARN [Server ...] Dropped received packet: Coalesced packet has different DCID; Total: 2
server  | 1.044 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.044 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.044 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.044 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.044 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.044 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 68.76006ms
server  | 1.044 DEBUG Setting timeout of 68.76006ms
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Drop packet number space in
server  | 1.045 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Handshake pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 45
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 90, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=hs, ranges=[0..=0]
server  | 1.045 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.045 DEBUG on_packets_acked this=0x55c315585b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=782
server  | 1.045 DEBUG Acked crypto frame space=hs offset=0 length=742
server  | 1.045 DEBUG [pri-path: [::]:443->[fd00:cafe:cafe::100]:54295] Path validated Instant { tv_sec: 733, tv_nsec: 723222697 }
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.045 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.045 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.045 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 53.628449ms
server  | 1.045 DEBUG Setting timeout of 53.628449ms
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.045 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.045 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.045 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 53.53264ms
server  | 1.045 DEBUG Setting timeout of 53.53264ms
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Handshake pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 75
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.045 DEBUG Read Ok(36) bytes
server  | 1.045 DEBUG [Agent 0x55c3155861d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] TLS connection complete
server  | 1.045 DEBUG [pri-path: [::]:443->[fd00:cafe:cafe::100]:54295] Path validated Instant { tv_sec: 733, tv_nsec: 723604266 }
server  | 1.045 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.045 DEBUG [Crypto] application read keys installed
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] State change from Handshaking -> Connected
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] State change from Connected -> Confirmed
server  | 1.045 DEBUG PMTUD started with probe size 1380
server  | 1.045 INFO [Server 6acea62306d08bcf002b73a9d5180765c6] Connection established
server  | 1.045 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 733, tv_nsec: 723604266 }
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 75
server  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 10323, ignore_order: false }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  |   -> RX Stream { stream_id: 4, offset: 0, len: 17, fin: true }
server  | 1.045 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 733, tv_nsec: 733927266 }
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.045 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: a3f73c267c82586cf20a)
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Handshake pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 44
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  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Drop packet number space hs
server  | 1.045 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.045 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [11, 101, 137, 184, 0, 126, 247, 33, 207, 115], stateless_reset_token: [20, 224, 243, 100, 252, 62, 126, 66, 73, 174, 203, 51, 99, 231, 232, 183] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [200, 56, 146, 43, 105, 80, 174, 127, 194, 82], stateless_reset_token: [6, 126, 61, 98, 80, 197, 175, 213, 173, 2, 103, 153, 170, 209, 231, 236] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [209, 94, 181, 14, 193, 120, 9, 205, 200, 197], stateless_reset_token: [90, 198, 249, 103, 170, 119, 225, 230, 83, 42, 117, 236, 246, 54, 73, 13] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [59, 113, 182, 81, 176, 82, 15, 2, 64, 180], stateless_reset_token: [57, 119, 156, 228, 74, 55, 180, 125, 120, 178, 179, 20, 154, 177, 163, 254] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [131, 249, 103, 142, 123, 42, 102, 82, 154, 109], stateless_reset_token: [37, 155, 106, 137, 204, 110, 61, 60, 228, 168, 33, 236, 178, 2, 43, 173] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [243, 100, 226, 74, 24, 43, 42, 53, 170, 163], stateless_reset_token: [152, 15, 117, 12, 163, 20, 5, 1, 213, 204, 17, 243, 117, 135, 53, 32] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [76, 91, 160, 83, 158, 132, 30, 72, 67, 125], stateless_reset_token: [198, 104, 227, 242, 57, 229, 80, 152, 120, 229, 119, 127, 116, 209, 12, 197] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 8560, ignore_order: false }
server  |   TX -> Padding { len: 1052 }
server  | 1.045 DEBUG packet_sent this=0x55c315585b40, pn=0, ps=1288
server  | 1.045 DEBUG ECN probing: sent 4 probes
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.045 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.045 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.045 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.045 DEBUG packet_sent this=0x55c315585b40, pn=1, ps=1332
server  | 1.045 DEBUG ECN probing: sent 5 probes
server  | 1.046 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.046 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.046 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.046 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.261844ms
server  | 1.046 DEBUG Setting timeout of 1.261844ms
server  | 1.047 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] send session ticket 406c00116acea62306d08bcf002b73a9d5180765c60104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0aa3f73c267c82586cf20a110c000000015a2adaea000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.047 DEBUG Path = 'mgboelfdlr'
server  | 1.047 DEBUG Path = 'jmzhlaqhus'
server  | 1.047 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.047 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.047 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.047 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 114.312µs
server  | 1.047 DEBUG Setting timeout of 114.312µs
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=2 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1212, fin: false }
server  | 1.048 DEBUG packet_sent this=0x55c315585b40, pn=2, ps=1232
server  | 1.048 DEBUG ECN probing: sent 6 probes
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=3 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1212, len: >>1210, fin: false }
server  | 1.048 DEBUG packet_sent this=0x55c315585b40, pn=3, ps=1232
server  | 1.048 DEBUG ECN probing: sent 7 probes
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.660494ms
server  | 1.048 DEBUG Setting timeout of 1.660494ms
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.048 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.048 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.64222ms
server  | 1.048 DEBUG Setting timeout of 1.64222ms
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.051 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=4 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2422, len: >>1210, fin: false }
server  | 1.051 DEBUG packet_sent this=0x55c315585b40, pn=4, ps=1232
server  | 1.051 DEBUG ECN probing: sent 8 probes
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.051 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=5 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3632, len: >>1210, fin: false }
server  | 1.051 DEBUG packet_sent this=0x55c315585b40, pn=5, ps=1232
server  | 1.051 DEBUG ECN probing: sent 9 probes
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.051 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.051 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.626951ms
server  | 1.051 DEBUG Setting timeout of 1.626951ms
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.051 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.051 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.051 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.602956ms
server  | 1.051 DEBUG Setting timeout of 1.602956ms
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=6 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 4842, len: 278, fin: true }
server  |   TX -> Stream { stream_id: 4, offset: 0, len: >>928, fin: false }
server  | 1.054 DEBUG packet_sent this=0x55c315585b40, pn=6, ps=1232
server  | 1.054 DEBUG ECN probing: sent 10 probes
server  | 1.054 DEBUG ECN probing concluded with 10 probes sent
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=7 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 4, offset: 928, len: >>1210, fin: false }
server  | 1.054 DEBUG packet_sent this=0x55c315585b40, pn=7, ps=1232
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 728.915µs
server  | 1.054 DEBUG Setting timeout of 728.915µs
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.054 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.054 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 704.639µs
server  | 1.054 DEBUG Setting timeout of 704.639µs
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.056 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=8 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 27
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 1338, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.056 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=9 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 4, offset: 2138, len: >>1210, fin: false }
server  | 1.056 DEBUG packet_sent this=0x55c315585b40, pn=9, ps=1232
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1076, pto: None, probe: EnumSet(), paced: false }
server  | 1.056 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=10 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 1076
server  |   TX -> Stream { stream_id: 4, offset: 3348, len: >>1054, fin: false }
server  | 1.056 DEBUG packet_sent this=0x55c315585b40, pn=10, ps=1076
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.056 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.056 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 106.863354ms
server  | 1.056 DEBUG Setting timeout of 106.863354ms
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.056 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.056 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.056 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 106.797624ms
server  | 1.056 DEBUG Setting timeout of 106.797624ms
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Handshake pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 44
client  |   -> RX 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})) }
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=hs, ranges=[0..=1]
client  | 0.079 DEBUG [LossRecovery] ACK for Handshake - largest_acked=1
client  | 0.079 DEBUG on_packets_acked this=0x55ed4615d6d0, limited=1, bytes_in_flight=75, cwnd=12320, state=SlowStart, new_acked=75
client  | 0.079 DEBUG Acked crypto frame space=hs offset=0 length=36
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=0 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1288
client  |   -> RX HandshakeDone
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [11, 101, 137, 184, 0, 126, 247, 33, 207, 115], stateless_reset_token: [20, 224, 243, 100, 252, 62, 126, 66, 73, 174, 203, 51, 99, 231, 232, 183] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [200, 56, 146, 43, 105, 80, 174, 127, 194, 82], stateless_reset_token: [6, 126, 61, 98, 80, 197, 175, 213, 173, 2, 103, 153, 170, 209, 231, 236] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [209, 94, 181, 14, 193, 120, 9, 205, 200, 197], stateless_reset_token: [90, 198, 249, 103, 170, 119, 225, 230, 83, 42, 117, 236, 246, 54, 73, 13] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [59, 113, 182, 81, 176, 82, 15, 2, 64, 180], stateless_reset_token: [57, 119, 156, 228, 74, 55, 180, 125, 120, 178, 179, 20, 154, 177, 163, 254] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [131, 249, 103, 142, 123, 42, 102, 82, 154, 109], stateless_reset_token: [37, 155, 106, 137, 204, 110, 61, 60, 228, 168, 33, 236, 178, 2, 43, 173] }
client  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [243, 100, 226, 74, 24, 43, 42, 53, 170, 163], stateless_reset_token: [152, 15, 117, 12, 163, 20, 5, 1, 213, 204, 17, 243, 117, 135, 53, 32] }
client  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [76, 91, 160, 83, 158, 132, 30, 72, 67, 125], stateless_reset_token: [198, 104, 227, 242, 57, 229, 80, 152, 120, 229, 119, 127, 116, 209, 12, 197] }
client  |   -> RX AckFrequency { seqno: 0, tolerance: 2, delay: 8560, ignore_order: false }
client  |   -> RX Padding { len: 1052 }
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] State change from Connected -> Confirmed
client  | 0.079 DEBUG PMTUD started with probe size 1380
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Drop packet number space hs
client  | 0.079 DEBUG [LossRecovery] Reset loss recovery state for Handshake
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] No preferred address to migrate to
client  | 0.079 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 748988199 }
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.079 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1332
client  |   TX -> Ping
client  |   TX -> Padding { len: 1303 }
client  | 0.079 DEBUG packet_sent this=0x55ed4615d6d0, pn=1, ps=1332
client  | 0.079 DEBUG ECN probing: sent 6 probes
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.268326ms
client  | 0.079 DEBUG Setting timeout of 8.268326ms
client  | 0.079 WARN Unhandled event StateChange(Confirmed)
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.079 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.079 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.234162ms
client  | 0.079 DEBUG Setting timeout of 8.234162ms
client  | 0.080 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Ping
client  |   -> RX Padding { len: 1313 }
client  | 0.080 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 741496791 }
client  | 0.080 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 741496791 }
client  | 0.080 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.080 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.080 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=2 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
client  | 0.080 DEBUG ECN probing: sent 7 probes
client  | 0.080 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.080 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.080 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.080 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 115.697413ms
client  | 0.080 DEBUG Setting timeout of 115.697413ms
client  | 0.080 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.080 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.080 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.080 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 115.671063ms
client  | 0.080 DEBUG Setting timeout of 115.671063ms
client  | 0.081 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=2 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 0, len: >>1212, fin: false }
client  | 0.081 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 751285672 }
client  | 0.081 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.081 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.081 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.081 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.535113ms
client  | 0.081 DEBUG Setting timeout of 8.535113ms
client  | 0.081 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.081 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.081 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.081 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.506129ms
client  | 0.081 DEBUG Setting timeout of 8.506129ms
client  | 0.082 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=3 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 1212, len: >>1210, fin: false }
client  | 0.082 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 743756925 }
client  | 0.082 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.082 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.082 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=3 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 2, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
client  | 0.082 DEBUG ECN probing: sent 8 probes
client  | 0.082 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.082 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.082 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.082 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 113.44312ms
client  | 0.082 DEBUG Setting timeout of 113.44312ms
client  | 0.082 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.082 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.082 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.082 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 113.415809ms
client  | 0.082 DEBUG Setting timeout of 113.415809ms
client  | 0.084 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=4 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 2422, len: >>1210, fin: false }
client  | 0.084 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 754524968 }
client  | 0.084 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.084 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.084 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.084 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.534492ms
client  | 0.084 DEBUG Setting timeout of 8.534492ms
client  | 0.084 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.084 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.084 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.084 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.497824ms
client  | 0.084 DEBUG Setting timeout of 8.497824ms
client  | 0.085 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=5 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 3632, len: >>1210, fin: false }
client  | 0.085 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 746984419 }
client  | 0.085 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.085 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.085 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=4 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 9, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
client  | 0.085 DEBUG ECN probing: sent 9 probes
client  | 0.085 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.085 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.085 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.085 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 110.161204ms
client  | 0.085 DEBUG Setting timeout of 110.161204ms
client  | 0.085 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.085 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.085 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.085 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 110.130016ms
client  | 0.085 DEBUG Setting timeout of 110.130016ms
client  | 0.087 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=6 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1232
client  |   -> RX Stream { stream_id: 0, offset: 4842, len: 278, fin: true }
client  |   -> RX Stream { stream_id: 4, offset: 0, len: >>928, fin: false }
client  | 0.087 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 757815873 }
client  | 0.087 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.087 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.087 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.087 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.533941ms
client  | 0.087 DEBUG Setting timeout of 8.533941ms
client  | 0.087 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.088 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.088 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.088 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.480091ms
client  | 0.088 DEBUG Setting timeout of 8.480091ms
client  | 0.088 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=7 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 1232
client  |   -> RX Stream { stream_id: 4, offset: 928, len: >>1210, fin: false }
client  | 0.088 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 750292776 }
client  | 0.088 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.088 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.088 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=5 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
client  | 0.089 DEBUG ECN probing: sent 10 probes
client  | 0.089 DEBUG ECN probing concluded with 10 probes sent
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.089 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.089 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 106.867975ms
client  | 0.089 DEBUG Setting timeout of 106.867975ms
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=8 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 27
client  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 1338, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=0]
client  | 0.089 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0
client  | 0.089 DEBUG on_packets_acked this=0x55ed4615d6d0, limited=1, bytes_in_flight=1332, cwnd=12320, state=SlowStart, new_acked=75
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.089 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.089 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 85.345983ms
client  | 0.089 DEBUG Setting timeout of 85.345983ms
client  | 0.089 DEBUG stream 0 complete
client  | 0.089 DEBUG stream 4 complete
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.089 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.089 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.089 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 85.30668ms
client  | 0.089 DEBUG Setting timeout of 85.30668ms
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=9 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 1232
client  |   -> RX Stream { stream_id: 4, offset: 2138, len: >>1210, fin: false }
client  | 0.090 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 759938360 }
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.090 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.090 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.532919ms
client  | 0.090 DEBUG Setting timeout of 8.532919ms
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.090 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.090 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.516699ms
client  | 0.090 DEBUG Setting timeout of 8.516699ms
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=10 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 1076
client  |   -> RX Stream { stream_id: 4, offset: 3348, len: >>1054, fin: false }
client  | 0.090 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 752272347 }
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.090 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=6 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 36
client  |   TX -> Ack { largest_acknowledged: 10, ack_delay: 2, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
client  | 0.090 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.090 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.091 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.091 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 83.486406ms
client  | 0.091 DEBUG Setting timeout of 83.486406ms
client  | 0.091 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.091 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.091 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
client  | 0.091 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 83.459155ms
client  | 0.091 DEBUG Setting timeout of 83.459155ms
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=1 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1332
server  |   -> RX Ping
server  |   -> RX Padding { len: 1303 }
server  | 1.078 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 733, tv_nsec: 756949045 }
server  | 1.078 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 733, tv_nsec: 756949045 }
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=11 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 26
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  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 84.273445ms
server  | 1.078 DEBUG Setting timeout of 84.273445ms
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=2 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 3, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=1]
server  | 1.078 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.078 INFO ECN validation succeeded, path is capable
server  | 1.078 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9700/14940 ssthresh 18446744073709551615] slow start += 2620
server  | 1.078 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9700/14940 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=9700, cwnd=14940, state=SlowStart, new_acked=2620
server  | 1.078 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.078 DEBUG PMTUD started with probe size 1420
server  | 1.078 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=12 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 4, offset: 4402, len: >>1349, fin: false }
server  | 1.078 DEBUG packet_sent this=0x55c315585b40, pn=12, ps=1372
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.078 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.078 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=13 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 5751, len: >>1310, fin: false }
server  | 1.079 DEBUG packet_sent this=0x55c315585b40, pn=13, ps=1332
server  | 1.079 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.079 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.079 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.079 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.47601ms
server  | 1.079 DEBUG Setting timeout of 1.47601ms
server  | 1.079 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.079 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.079 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.079 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.456163ms
server  | 1.079 DEBUG Setting timeout of 1.456163ms
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=3 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 2, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=3]
server  | 1.080 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 1.080 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9940/17404 ssthresh 18446744073709551615] slow start += 2464
server  | 1.080 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9940/17404 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=9940, cwnd=17404, state=SlowStart, new_acked=2464
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.080 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=14 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 7061, len: >>1310, fin: false }
server  | 1.080 DEBUG packet_sent this=0x55c315585b40, pn=14, ps=1332
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.080 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=15 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 8371, len: >>1310, fin: false }
server  | 1.080 DEBUG packet_sent this=0x55c315585b40, pn=15, ps=1332
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.080 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.080 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.229012ms
server  | 1.080 DEBUG Setting timeout of 1.229012ms
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.080 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.080 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.080 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 1.203695ms
server  | 1.080 DEBUG Setting timeout of 1.203695ms
server  | 1.083 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.083 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.083 DEBUG CRYPTO for ap offset=0, len=321
server  | 1.083 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=16 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 953
server  |   TX -> Stream { stream_id: 4, offset: 9681, len: 559, fin: true }
server  |   TX -> Crypto { offset: 0, len: 321 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 216, 233, 248, 193, 5, 165, 145, 240, 71, 58, 240, 193, 252, 142, 190, 111, 73, 129, 150, 1, 179, 211, 143, 235, 18, 199, 22, 173, 198, 150, 191, 69, 116, 173, 132, 119] }
server  | 1.083 DEBUG packet_sent this=0x55c315585b40, pn=16, ps=953
server  | 1.083 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.083 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.083 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.083 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 74.586417ms
server  | 1.083 DEBUG Setting timeout of 74.586417ms
server  | 1.083 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.083 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.083 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.083 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 74.568534ms
server  | 1.083 DEBUG Setting timeout of 74.568534ms
server  | 1.084 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=4 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 9, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
server  | 1.084 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=5]
server  | 1.084 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5
server  | 1.084 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11093/19868 ssthresh 18446744073709551615] slow start += 2464
server  | 1.084 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11093/19868 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=11093, cwnd=19868, state=SlowStart, new_acked=2464
server  | 1.084 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.084 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 66.811682ms
server  | 1.084 DEBUG Setting timeout of 66.811682ms
server  | 1.084 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.084 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.084 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 66.759655ms
server  | 1.084 DEBUG Setting timeout of 66.759655ms
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=5 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 4, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=7]
server  | 1.087 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
server  | 1.087 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8629/22332 ssthresh 18446744073709551615] slow start += 2464
server  | 1.087 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8629/22332 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=8629, cwnd=22332, state=SlowStart, new_acked=2464
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=17 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 20
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.087 DEBUG packet_sent this=0x55c315585b40, pn=17, ps=21
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.087 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 62.405643ms
server  | 1.087 DEBUG Setting timeout of 62.405643ms
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.087 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.087 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 62.372661ms
server  | 1.087 DEBUG Setting timeout of 62.372661ms
server  | 1.089 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=6 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 36
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 2, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 1.089 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=10]
server  | 1.089 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=10
server  | 1.089 WARN ECN validation failed, no ECT(0) packets were newly acked
server  | 1.089 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6342/24640 ssthresh 18446744073709551615] slow start += 2308
server  | 1.089 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6342/24640 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=6342, cwnd=24640, state=SlowStart, new_acked=2308
server  | 1.089 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.089 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.089 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.089 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 55.865923ms
server  | 1.089 DEBUG Setting timeout of 55.865923ms
server  | 1.089 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.089 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.089 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.089 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 55.809007ms
server  | 1.089 DEBUG Setting timeout of 55.809007ms
client  | 0.110 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=11 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, NotEct) len 26
client  |   -> RX 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})) }
client  | 0.110 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=1]
client  | 0.110 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
client  | 0.110 INFO ECN validation succeeded, path is capable
client  | 0.110 DEBUG on_packets_acked this=0x55ed4615d6d0, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=1332
client  | 0.110 DEBUG PMTUD probe of size 1380 succeeded
client  | 0.110 DEBUG PMTUD started with probe size 1420
client  | 0.110 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
client  | 0.110 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.110 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.110 DEBUG Sending PMTUD probe of size 1420, count 1
client  | 0.110 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=7 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1372
client  |   TX -> Ping
client  |   TX -> Padding { len: 1343 }
client  | 0.111 DEBUG packet_sent this=0x55ed4615d6d0, pn=7, ps=1372
client  | 0.111 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 91.65745ms
client  | 0.111 DEBUG Setting timeout of 91.65745ms
client  | 0.111 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.111 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.111 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 91.62536ms
client  | 0.111 DEBUG Setting timeout of 91.62536ms
client  | 0.112 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=12 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1372
client  |   -> RX Ping
client  |   -> RX Stream { stream_id: 4, offset: 4402, len: >>1349, fin: false }
client  | 0.112 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 773591022 }
client  | 0.112 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 773591022 }
client  | 0.112 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.112 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=8 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 12, ack_delay: 3, first_ack_range: 12, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
client  | 0.112 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 90.421165ms
client  | 0.112 DEBUG Setting timeout of 90.421165ms
client  | 0.112 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.112 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.112 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 90.388213ms
client  | 0.112 DEBUG Setting timeout of 90.388213ms
client  | 0.113 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=13 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Stream { stream_id: 4, offset: 5751, len: >>1310, fin: false }
client  | 0.113 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 783255951 }
client  | 0.113 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.113 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.113 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.529263ms
client  | 0.113 DEBUG Setting timeout of 8.529263ms
client  | 0.113 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.113 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.113 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.113 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.507512ms
client  | 0.113 DEBUG Setting timeout of 8.507512ms
client  | 0.114 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=14 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Stream { stream_id: 4, offset: 7061, len: >>1310, fin: false }
client  | 0.114 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 775774021 }
client  | 0.114 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.114 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.114 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=9 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 14, ack_delay: 2, first_ack_range: 14, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) }
client  | 0.114 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.114 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.114 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.114 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 88.245699ms
client  | 0.114 DEBUG Setting timeout of 88.245699ms
client  | 0.114 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.114 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.114 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.114 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 88.219991ms
client  | 0.114 DEBUG Setting timeout of 88.219991ms
client  | 0.115 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=15 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 1332
client  |   -> RX Stream { stream_id: 4, offset: 8371, len: >>1310, fin: false }
client  | 0.115 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 785469458 }
client  | 0.115 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.538961ms
client  | 0.115 DEBUG Setting timeout of 8.538961ms
client  | 0.115 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.115 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.115 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 8.518824ms
client  | 0.115 DEBUG Setting timeout of 8.518824ms
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=16 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 953
client  |   -> RX Stream { stream_id: 4, offset: 9681, len: 559, fin: true }
client  |   -> RX Crypto { offset: 0, len: 321 }
client  |   -> RX NewToken { token: [173, 154, 139, 141, 134, 1, 0, 216, 233, 248, 193, 5, 165, 145, 240, 71, 58, 240, 193, 252, 142, 190, 111, 73, 129, 150, 1, 179, 211, 143, 235, 18, 199, 22, 173, 198, 150, 191, 69, 116, 173, 132, 119] }
client  | 0.116 DEBUG Read Ok(321) bytes
client  | 0.116 DEBUG [0x55ed46149ed0] Got resumption token [987]: 02000633fe6e64ff..b2df3040eab0977c
client  | 0.116 DEBUG [Agent 0x55ed46149ed0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 4588, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.116 DEBUG resumption token [1146]: 0000000127406c00..b2df3040eab0977c
client  | 0.116 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 723, tv_nsec: 777710822 }
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=10 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 36
client  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 21, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 86.151065ms
client  | 0.116 DEBUG Setting timeout of 86.151065ms
client  | 0.116 DEBUG stream BiDi creatable
client  | 0.116 DEBUG stream UniDi creatable
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.116 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] delay duration 86.034177ms
client  | 0.116 DEBUG Setting timeout of 86.034177ms
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 724, tv_nsec: 53335096 } }
client  | 0.116 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 724, tv_nsec: 53335096 } })
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.116 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.116 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 778079990 }
client  | 0.116 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=11 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 46, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.116 DEBUG Setting timeout of 275.199352ms
client  | 0.119 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.119 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.119 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 780858049 }
client  | 0.119 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=12 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 393, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.119 DEBUG Setting timeout of 272.390987ms
client  | 0.119 DEBUG Setting timeout of 272.381148ms
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=7 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 1372
server  |   -> RX Ping
server  |   -> RX Padding { len: 1343 }
server  | 1.110 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 733, tv_nsec: 788676384 }
server  | 1.110 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 733, tv_nsec: 788676384 }
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=18 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 26
server  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 34.622411ms
server  | 1.110 DEBUG Setting timeout of 34.622411ms
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 34.59484ms
server  | 1.110 DEBUG Setting timeout of 34.59484ms
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=8 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 12, ack_delay: 3, first_ack_range: 12, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=12]
server  | 1.110 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=12
server  | 1.110 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4970/26012 ssthresh 18446744073709551615] slow start += 1372
server  | 1.110 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4970/26012 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=4970, cwnd=26012, state=SlowStart, new_acked=1372
server  | 1.110 DEBUG PMTUD probe of size 1420 succeeded
server  | 1.110 DEBUG PMTUD started with probe size 1470
server  | 1.110 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=19 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 1422
server  |   TX -> Ping
server  |   TX -> Padding { len: 1403 }
server  | 1.110 DEBUG packet_sent this=0x55c315585b40, pn=19, ps=1422
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 55.238427ms
server  | 1.110 DEBUG Setting timeout of 55.238427ms
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 55.209703ms
server  | 1.110 DEBUG Setting timeout of 55.209703ms
server  | 1.112 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=9 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 14, ack_delay: 2, first_ack_range: 14, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) }
server  | 1.112 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=14]
server  | 1.112 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=14
server  | 1.112 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3728/28676 ssthresh 18446744073709551615] slow start += 2664
server  | 1.112 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3728/28676 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=3728, cwnd=28676, state=SlowStart, new_acked=2664
server  | 1.112 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.112 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.112 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 50.957296ms
server  | 1.112 DEBUG Setting timeout of 50.957296ms
server  | 1.112 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.112 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.112 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 50.904447ms
server  | 1.112 DEBUG Setting timeout of 50.904447ms
server  | 1.114 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=10 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 16, ack_delay: 21, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
server  | 1.114 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=16]
server  | 1.114 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=16
server  | 1.114 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1443/30961 ssthresh 18446744073709551615] slow start += 2285
server  | 1.114 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1443/30961 ssthresh 18446744073709551615] on_packets_acked this=0x55c315585b40, limited=0, bytes_in_flight=1443, cwnd=30961, state=SlowStart, new_acked=2285
server  | 1.114 DEBUG Acked crypto frame space=ap offset=0 length=321
server  | 1.114 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.114 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.114 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=20 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 20
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 18 }
server  | 1.114 DEBUG packet_sent this=0x55c315585b40, pn=20, ps=21
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.115 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 51.240175ms
server  | 1.115 DEBUG Setting timeout of 51.240175ms
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.115 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] delay duration 51.213485ms
server  | 1.115 DEBUG Setting timeout of 51.213485ms
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=11 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, Ect0) len 47
server  |   -> RX Ack { largest_acknowledged: 16, ack_delay: 46, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] Rx ACK space=ap, ranges=[0..=16]
server  | 1.115 INFO [Server 6acea62306d08bcf002b73a9d5180765c6] ConnectionClose received. Error code: Application(0) frame type 0 reason kthxbye!
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 733, tv_nsec: 947466257 } }
server  | 1.115 DEBUG [pri-path: [::]:443->[fd00:cafe:cafe::100]:54295] Path validated Instant { tv_sec: 733, tv_nsec: 793355426 }
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.115 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 733, tv_nsec: 793355426 }
server  | 1.115 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] pn=21 type=Short pri-path: [::]:443->[fd00:cafe:cafe::100]:54295 IpTos(Cs0, NotEct) len 30
server  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 0, first_ack_range: 11, 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  | 1.115 DEBUG Setting timeout of 153.877115ms
server  | 1.115 DEBUG Setting timeout of 153.864101ms
server  | 1.117 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 733, tv_nsec: 947466257 } }; Total: 3
server  | 1.117 DEBUG Setting timeout of 151.340265ms
server  | 1.117 DEBUG Setting timeout of 151.323484ms
client  | 0.142 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.142 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.142 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 803999960 }
client  | 0.142 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=13 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 3286, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.142 DEBUG Setting timeout of 249.201677ms
client  | 0.142 DEBUG Setting timeout of 249.189494ms
client  | 0.143 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.144 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.144 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 805336242 }
client  | 0.144 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=14 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 3453, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.144 DEBUG Setting timeout of 247.909708ms
client  | 0.144 DEBUG Setting timeout of 247.898447ms
client  | 0.147 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.147 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.147 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 808517903 }
client  | 0.147 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=15 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 3850, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.147 DEBUG Setting timeout of 244.758434ms
client  | 0.147 DEBUG Setting timeout of 244.748185ms
client  | 0.147 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
client  | 0.147 DEBUG Building Short dcid Some(CID [10]: a3f73c267c82586cf20a)
client  | 0.147 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 723, tv_nsec: 808675336 }
client  | 0.147 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] pn=16 type=Short pri-path:a3f73c267c82586cf20a [::]:54295->[fd00:cafe:cafe:100::100]:443 IpTos(Cs0, Ect0) len 48
client  |   TX -> Ack { largest_acknowledged: 16, ack_delay: 3870, first_ack_range: 16, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 12, Ce: 0})) }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.147 DEBUG Setting timeout of 244.571014ms
client  | 0.147 DEBUG Setting timeout of 244.56332ms
server  | 1.141 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 733, tv_nsec: 947466257 } }; Total: 4
server  | 1.141 DEBUG Setting timeout of 128.045609ms
server  | 1.141 DEBUG Setting timeout of 128.018168ms
server  | 1.142 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 733, tv_nsec: 947466257 } }; Total: 5
server  | 1.142 DEBUG Setting timeout of 126.861041ms
server  | 1.142 DEBUG Setting timeout of 126.847285ms
server  | 1.145 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 733, tv_nsec: 947466257 } }; Total: 6
server  | 1.145 DEBUG Setting timeout of 123.655017ms
server  | 1.145 DEBUG Setting timeout of 123.630501ms
server  | 1.145 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 733, tv_nsec: 947466257 } }; Total: 7
server  | 1.145 DEBUG Setting timeout of 123.521358ms
server  | 1.145 DEBUG Setting timeout of 123.50647ms
server  | 1.270 DEBUG [Server 6acea62306d08bcf002b73a9d5180765c6] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 733, tv_nsec: 947466257 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 1.270 INFO Closing timer expired
client  | 0.393 DEBUG [Client 6acea62306d08bcf002b73a9d5180765c6] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 724, tv_nsec: 53335096 } } -> Closed(Application(0))
client  | 0.393 INFO Closing timer expired
client  | 0.393 DEBUG Output::None
client  | 0.393 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 0.393 DEBUG Timer fired while closed
client  | 0.393 DEBUG Output::None

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 12:55:37,823 Using the client's key log file.
2025-04-30 12:55:37,835 
2025-04-30 12:55:37,836 Using the client's key log file.
2025-04-30 12:55:37,836 Using the client's key log file.
2025-04-30 12:55:37,847 
2025-04-30 12:55:37,848 Using the client's key log file.
2025-04-30 12:55:38,436 Check of downloaded files succeeded.
2025-04-30 12:55:39,026 Check of downloaded files succeeded.