2025-04-30 10:52:00,534 Generated random file: hipeimvjsi of size: 1024
2025-04-30 10:52:00,534 Generated random file: ilsjzkoxmb of size: 1024
2025-04-30 10:52:00,534 Generated random file: oxbpbjzlav of size: 1024
2025-04-30 10:52:00,534 Generated random file: tzvebfcsmy of size: 1024
2025-04-30 10:52:00,534 Generated random file: oogoeczkgt of size: 1024
2025-04-30 10:52:00,534 Generated random file: afqkakebpe of size: 1024
2025-04-30 10:52:00,534 Generated random file: egzjyoszlc of size: 1024
2025-04-30 10:52:00,534 Generated random file: sbdacqdimj of size: 1024
2025-04-30 10:52:00,535 Generated random file: mkqxivzvtc of size: 1024
2025-04-30 10:52:00,535 Generated random file: ifikklecqw of size: 1024
2025-04-30 10:52:00,535 Generated random file: kdllsplbyy of size: 1024
2025-04-30 10:52:00,535 Generated random file: nmsnpynzpa of size: 1024
2025-04-30 10:52:00,535 Generated random file: uqcrfawqwl of size: 1024
2025-04-30 10:52:00,535 Generated random file: lzsglixtpn of size: 1024
2025-04-30 10:52:00,535 Generated random file: wkihnupfme of size: 1024
2025-04-30 10:52:00,535 Generated random file: nnmgnsovib of size: 1024
2025-04-30 10:52:00,535 Generated random file: bogxcvgdcj of size: 1024
2025-04-30 10:52:00,536 Generated random file: rephebebfc of size: 1024
2025-04-30 10:52:00,536 Generated random file: lbhbeljpqa of size: 1024
2025-04-30 10:52:00,536 Generated random file: pgnluuadgg of size: 1024
2025-04-30 10:52:00,536 Generated random file: ovjtghzkft of size: 1024
2025-04-30 10:52:00,536 Generated random file: vuhqskmthw of size: 1024
2025-04-30 10:52:00,536 Generated random file: pvobjxhmvt of size: 1024
2025-04-30 10:52:00,536 Generated random file: ersmtppwrk of size: 1024
2025-04-30 10:52:00,536 Generated random file: wumjlsggmw of size: 1024
2025-04-30 10:52:00,537 Generated random file: glfbytpopq of size: 1024
2025-04-30 10:52:00,537 Generated random file: dmyylrcqzl of size: 1024
2025-04-30 10:52:00,537 Generated random file: dopqkelome of size: 1024
2025-04-30 10:52:00,537 Generated random file: siusnrgcva of size: 1024
2025-04-30 10:52:00,537 Generated random file: svpewfilkm of size: 1024
2025-04-30 10:52:00,537 Generated random file: ebzjzetbzj of size: 1024
2025-04-30 10:52:00,537 Generated random file: yszpeuceer of size: 1024
2025-04-30 10:52:00,537 Generated random file: funygokvpu of size: 1024
2025-04-30 10:52:00,537 Generated random file: fjrblmwvmg of size: 1024
2025-04-30 10:52:00,538 Generated random file: sjxehehlky of size: 1024
2025-04-30 10:52:00,538 Generated random file: ruwecgmqyf of size: 1024
2025-04-30 10:52:00,538 Generated random file: sougqfhors of size: 1024
2025-04-30 10:52:00,538 Generated random file: pafaibdysa of size: 1024
2025-04-30 10:52:00,538 Generated random file: ufivkaswtw of size: 1024
2025-04-30 10:52:00,538 Generated random file: wmrxqgwlea of size: 1024
2025-04-30 10:52:00,538 Generated random file: lycmirtnrl of size: 1024
2025-04-30 10:52:00,538 Generated random file: nffubsrirz of size: 1024
2025-04-30 10:52:00,539 Generated random file: mlbghgznpo of size: 1024
2025-04-30 10:52:00,539 Generated random file: ephcbfnnyh of size: 1024
2025-04-30 10:52:00,539 Generated random file: yxoxwijgde of size: 1024
2025-04-30 10:52:00,539 Generated random file: xnnyuaclzp of size: 1024
2025-04-30 10:52:00,539 Generated random file: uxndpkrpjo of size: 1024
2025-04-30 10:52:00,539 Generated random file: scohyrdpnd of size: 1024
2025-04-30 10:52:00,539 Generated random file: bnmutxdfcr of size: 1024
2025-04-30 10:52:00,539 Generated random file: wctrquyiku of size: 1024
2025-04-30 10:52:00,539 Requests: https://server4:443/hipeimvjsi https://server4:443/ilsjzkoxmb https://server4:443/oxbpbjzlav https://server4:443/tzvebfcsmy https://server4:443/oogoeczkgt https://server4:443/afqkakebpe https://server4:443/egzjyoszlc https://server4:443/sbdacqdimj https://server4:443/mkqxivzvtc https://server4:443/ifikklecqw https://server4:443/kdllsplbyy https://server4:443/nmsnpynzpa https://server4:443/uqcrfawqwl https://server4:443/lzsglixtpn https://server4:443/wkihnupfme https://server4:443/nnmgnsovib https://server4:443/bogxcvgdcj https://server4:443/rephebebfc https://server4:443/lbhbeljpqa https://server4:443/pgnluuadgg https://server4:443/ovjtghzkft https://server4:443/vuhqskmthw https://server4:443/pvobjxhmvt https://server4:443/ersmtppwrk https://server4:443/wumjlsggmw https://server4:443/glfbytpopq https://server4:443/dmyylrcqzl https://server4:443/dopqkelome https://server4:443/siusnrgcva https://server4:443/svpewfilkm https://server4:443/ebzjzetbzj https://server4:443/yszpeuceer https://server4:443/funygokvpu https://server4:443/fjrblmwvmg https://server4:443/sjxehehlky https://server4:443/ruwecgmqyf https://server4:443/sougqfhors https://server4:443/pafaibdysa https://server4:443/ufivkaswtw https://server4:443/wmrxqgwlea https://server4:443/lycmirtnrl https://server4:443/nffubsrirz https://server4:443/mlbghgznpo https://server4:443/ephcbfnnyh https://server4:443/yxoxwijgde https://server4:443/xnnyuaclzp https://server4:443/uxndpkrpjo https://server4:443/scohyrdpnd https://server4:443/bnmutxdfcr https://server4:443/wctrquyiku
2025-04-30 10:52:00,600 
2025-04-30 10:52:00,601 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_z9zbvts4/ TESTCASE_SERVER=multiconnect TESTCASE_CLIENT=multiconnect WWW=/tmp/www_onrzpa8o/ DOWNLOADS=/tmp/download_woojxjr7/ SERVER_LOGS=/tmp/logs_server_v50_fols CLIENT_LOGS=/tmp/logs_client_xj9_6g2s SCENARIO="drop-rate --delay=15ms --bandwidth=10Mbps --queue=25 --rate_to_server=30 --rate_to_client=30 --burst_to_server=3 --burst_to_client=3" CLIENT=ghcr.io/mozilla/neqo-qns:latest SERVER=peterdoornbosch/kwik_n_flupke-interop REQUESTS="https://server4:443/hipeimvjsi https://server4:443/ilsjzkoxmb https://server4:443/oxbpbjzlav https://server4:443/tzvebfcsmy https://server4:443/oogoeczkgt https://server4:443/afqkakebpe https://server4:443/egzjyoszlc https://server4:443/sbdacqdimj https://server4:443/mkqxivzvtc https://server4:443/ifikklecqw https://server4:443/kdllsplbyy https://server4:443/nmsnpynzpa https://server4:443/uqcrfawqwl https://server4:443/lzsglixtpn https://server4:443/wkihnupfme https://server4:443/nnmgnsovib https://server4:443/bogxcvgdcj https://server4:443/rephebebfc https://server4:443/lbhbeljpqa https://server4:443/pgnluuadgg https://server4:443/ovjtghzkft https://server4:443/vuhqskmthw https://server4:443/pvobjxhmvt https://server4:443/ersmtppwrk https://server4:443/wumjlsggmw https://server4:443/glfbytpopq https://server4:443/dmyylrcqzl https://server4:443/dopqkelome https://server4:443/siusnrgcva https://server4:443/svpewfilkm https://server4:443/ebzjzetbzj https://server4:443/yszpeuceer https://server4:443/funygokvpu https://server4:443/fjrblmwvmg https://server4:443/sjxehehlky https://server4:443/ruwecgmqyf https://server4:443/sougqfhors https://server4:443/pafaibdysa https://server4:443/ufivkaswtw https://server4:443/wmrxqgwlea https://server4:443/lycmirtnrl https://server4:443/nffubsrirz https://server4:443/mlbghgznpo https://server4:443/ephcbfnnyh https://server4:443/yxoxwijgde https://server4:443/xnnyuaclzp https://server4:443/uxndpkrpjo https://server4:443/scohyrdpnd https://server4:443/bnmutxdfcr https://server4:443/wctrquyiku"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 10:52:49,087  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
server  | Setting up routes...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | 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 multiconnect ']'
client  | + '[' -n /logs/qlog/ ']'
client  | + case "$ROLE" in
client  | + /wait-for-it.sh sim:57832 -s -t 30
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | Importing keystore /server.p12 to /server.keystore...
server  | running kwik server version  0.10.2
server  | Old Flupke plugin not found
sim     | server:443 is available after 1.027441894s
sim     | Using scenario: drop-rate --delay=15ms --bandwidth=10Mbps --queue=25 --rate_to_server=30 --rate_to_client=30 --burst_to_server=3 --burst_to_client=3
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads)
client  | + '[' 'https://server4:443/hipeimvjsi https://server4:443/ilsjzkoxmb https://server4:443/oxbpbjzlav https://server4:443/tzvebfcsmy https://server4:443/oogoeczkgt https://server4:443/afqkakebpe https://server4:443/egzjyoszlc https://server4:443/sbdacqdimj https://server4:443/mkqxivzvtc https://server4:443/ifikklecqw https://server4:443/kdllsplbyy https://server4:443/nmsnpynzpa https://server4:443/uqcrfawqwl https://server4:443/lzsglixtpn https://server4:443/wkihnupfme https://server4:443/nnmgnsovib https://server4:443/bogxcvgdcj https://server4:443/rephebebfc https://server4:443/lbhbeljpqa https://server4:443/pgnluuadgg https://server4:443/ovjtghzkft https://server4:443/vuhqskmthw https://server4:443/pvobjxhmvt https://server4:443/ersmtppwrk https://server4:443/wumjlsggmw https://server4:443/glfbytpopq https://server4:443/dmyylrcqzl https://server4:443/dopqkelome https://server4:443/siusnrgcva https://server4:443/svpewfilkm https://server4:443/ebzjzetbzj https://server4:443/yszpeuceer https://server4:443/funygokvpu https://server4:443/fjrblmwvmg https://server4:443/sjxehehlky https://server4:443/ruwecgmqyf https://server4:443/sougqfhors https://server4:443/pafaibdysa https://server4:443/ufivkaswtw https://server4:443/wmrxqgwlea https://server4:443/lycmirtnrl https://server4:443/nffubsrirz https://server4:443/mlbghgznpo https://server4:443/ephcbfnnyh https://server4:443/yxoxwijgde https://server4:443/xnnyuaclzp https://server4:443/uxndpkrpjo https://server4:443/scohyrdpnd https://server4:443/bnmutxdfcr https://server4:443/wctrquyiku' ']'
client  | + mapfile -d ' ' -t URLS
client  | + OPTIONS+=("${URLS[@]}")
client  | + RUST_LOG=debug
client  | + RUST_BACKTRACE=1
client  | + neqo-client --cc cubic --qns-test multiconnect --qlog-dir /logs/qlog/ --output-dir /downloads https://server4:443/hipeimvjsi https://server4:443/ilsjzkoxmb https://server4:443/oxbpbjzlav https://server4:443/tzvebfcsmy https://server4:443/oogoeczkgt https://server4:443/afqkakebpe https://server4:443/egzjyoszlc https://server4:443/sbdacqdimj https://server4:443/mkqxivzvtc https://server4:443/ifikklecqw https://server4:443/kdllsplbyy https://server4:443/nmsnpynzpa https://server4:443/uqcrfawqwl https://server4:443/lzsglixtpn https://server4:443/wkihnupfme https://server4:443/nnmgnsovib https://server4:443/bogxcvgdcj https://server4:443/rephebebfc https://server4:443/lbhbeljpqa https://server4:443/pgnluuadgg https://server4:443/ovjtghzkft https://server4:443/vuhqskmthw https://server4:443/pvobjxhmvt https://server4:443/ersmtppwrk https://server4:443/wumjlsggmw https://server4:443/glfbytpopq https://server4:443/dmyylrcqzl https://server4:443/dopqkelome https://server4:443/siusnrgcva https://server4:443/svpewfilkm https://server4:443/ebzjzetbzj https://server4:443/yszpeuceer https://server4:443/funygokvpu https://server4:443/fjrblmwvmg https://server4:443/sjxehehlky https://server4:443/ruwecgmqyf https://server4:443/sougqfhors https://server4:443/pafaibdysa https://server4:443/ufivkaswtw https://server4:443/wmrxqgwlea https://server4:443/lycmirtnrl https://server4:443/nffubsrirz https://server4:443/mlbghgznpo https://server4:443/ephcbfnnyh https://server4:443/yxoxwijgde https://server4:443/xnnyuaclzp https://server4:443/uxndpkrpjo https://server4:443/scohyrdpnd https://server4:443/bnmutxdfcr 'https://server4:443/wctrquyiku
client  | '
client  | ++ tee -i -a /logs/client.log
client  | 0.000 DEBUG Logging initialized
client  | 0.001 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: 0.0.0.0:48305 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=c27d328aa031363d15a8edd62703b91ed3
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 193.167.100.100 has MTU 1500
client  | 0.001 DEBUG [unv-path 0.0.0.0:48305->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:c27d328aa031363d15a8edd62703b91ed3 0.0.0.0:48305->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:c27d328aa031363d15a8edd62703b91ed3 0.0.0.0:48305->193.167.100.100:443] Path validated Instant { tv_sec: 536, tv_nsec: 823909732 }
client  | 0.001 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] client_start
client  | 0.002 DEBUG Writing transport parameters, msg=1
client  | 0.002 DEBUG [Agent 0x55a26ae7f2d0] state -> InProgress
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] State change from Init -> WaitInitial
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=0, len=1204
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=0 type=Initial pri-path:c27d328aa031363d15a8edd62703b91ed3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 0.002 DEBUG packet_sent this=0x55a26ae92be0, pn=0, ps=1252
client  | 0.002 DEBUG ECN probing: sent 1 probes
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1204, len=335
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=1 type=Initial pri-path:c27d328aa031363d15a8edd62703b91ed3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pad Initial from 384 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=1252
client  | 0.002 DEBUG ECN probing: sent 2 probes
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) 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 c27d328aa031363d15a8edd62703b91ed3] delay duration 3.769928ms
client  | 0.002 DEBUG Setting timeout of 3.769928ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) 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 c27d328aa031363d15a8edd62703b91ed3] delay duration 3.7439ms
client  | 0.002 DEBUG Setting timeout of 3.7439ms
client  | 0.007 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) scid Some(CID [0]: )
client  | 0.007 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 294.815774ms
client  | 0.007 DEBUG Setting timeout of 294.815774ms
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 1/1 (100.0%)
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 2/2 (100.0%)
client  | 0.302 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 0.302 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 0.302 INFO Lost crypto frame space=in offset=0 length=1204
client  | 0.302 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: EcnEct0
client  | 0.302 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1204, length: 335 })
client  | 0.302 INFO Lost crypto frame space=in offset=1204 length=335
client  | 0.302 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: EcnEct0
client  | 0.302 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 0.302 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) scid Some(CID [0]: )
client  | 0.302 DEBUG CRYPTO for in offset=0, len=1204
client  | 0.302 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=2 type=Initial pri-path:c27d328aa031363d15a8edd62703b91ed3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 0.302 DEBUG packet_sent this=0x55a26ae92be0, pn=2, ps=1252
client  | 0.302 DEBUG ECN probing: sent 3 probes
client  | 0.303 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) scid Some(CID [0]: )
client  | 0.303 DEBUG CRYPTO for in offset=1204, len=335
client  | 0.303 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=3 type=Initial pri-path:c27d328aa031363d15a8edd62703b91ed3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 0.303 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pad Initial from 384 to PLPMTU 1252
client  | 0.303 DEBUG packet_sent this=0x55a26ae92be0, pn=3, ps=1252
client  | 0.303 DEBUG ECN probing: sent 4 probes
client  | 0.303 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [17]: c27d328aa031363d15a8edd62703b91ed3) scid Some(CID [0]: )
client  | 0.303 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.303 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 599.879032ms
client  | 0.303 DEBUG Setting timeout of 599.879032ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 2/3 (66.7%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 2/4 (50.0%)
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=0 type=Initial pri-path:c27d328aa031363d15a8edd62703b91ed3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1201
client  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  |   -> RX Padding { len: 1161 }
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Rx ACK space=in, ranges=[2..=2]
client  | 0.478 DEBUG [LossRecovery] ACK for Initial - largest_acked=2
client  | 0.478 DEBUG packet_lost this=0x55a26ae92be0, pn=0, ps=1252
client  | 0.478 DEBUG packet_lost this=0x55a26ae92be0, pn=1, ps=1252
client  | 0.478 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2504/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764
client  | 0.478 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2504/8764 ssthresh 8764] state -> RecoveryStart
client  | 0.478 DEBUG on_packets_lost this=0x55a26ae92be0, bytes_in_flight=2504, cwnd=8764, state=RecoveryStart
client  | 0.478 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=1252, cwnd=8764, state=RecoveryStart, new_acked=0
client  | 0.478 DEBUG Acked crypto frame space=in offset=0 length=1204
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 0.478 INFO Lost crypto frame space=in offset=0 length=1204
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: EcnEct0
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1204, length: 335 })
client  | 0.478 INFO Lost crypto frame space=in offset=1204 length=335
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: EcnEct0
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Changing to use Server CID=667f2584819588c3
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] State change from WaitInitial -> WaitVersion
sim     | Forwarding 1201 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 0/1 (0.0%)
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.478 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.478 DEBUG CRYPTO for in offset=1204, len=335
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=4 type=Initial pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 375
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 0.478 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pad Initial from 375 to PLPMTU 1252
client  | 0.478 DEBUG packet_sent this=0x55a26ae92be0, pn=4, ps=1252
client  | 0.479 DEBUG ECN probing: sent 5 probes
client  | 0.479 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.479 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.479 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.479 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 527.748513ms
client  | 0.479 DEBUG Setting timeout of 527.748513ms
client  | 0.479 WARN Unhandled event StateChange(WaitVersion)
client  | 0.479 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.479 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.479 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.479 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 527.718186ms
client  | 0.479 DEBUG Setting timeout of 527.718186ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 2/5 (40.0%)
sim     | Dropping 1200 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 1/2 (50.0%)
sim     | Forwarding 1201 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 1/3 (33.3%)
client  | 0.622 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=2 type=Initial pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1201
client  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None }
client  |   -> RX Padding { len: 1161 }
client  | 0.622 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Rx ACK space=in, ranges=[2..=4]
client  | 0.622 DEBUG [LossRecovery] ACK for Initial - largest_acked=4
client  | 0.622 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1252/8764 ssthresh 8764] state -> CongestionAvoidance
client  | 0.622 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=0, cwnd=8764, state=CongestionAvoidance, new_acked=1252
client  | 0.622 DEBUG Acked crypto frame space=in offset=1204 length=335
client  | 0.622 DEBUG Acked crypto frame space=in offset=1204 length=335
client  | 0.622 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.622 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.622 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.622 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 325.437996ms
client  | 0.622 DEBUG Setting timeout of 325.437996ms
client  | 0.622 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.622 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.622 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.622 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 325.408341ms
client  | 0.622 DEBUG Setting timeout of 325.408341ms
sim     | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 1/4 (25.0%)
client  | 0.817 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=3 type=Initial pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1200
client  |   -> RX Crypto { offset: 0, len: 90 }
client  |   -> RX Padding { len: 1071 }
client  | 0.818 DEBUG Read Ok(90) bytes
client  | 0.818 DEBUG Write secret available for Handshake: SymKey [32]: 3a68a8162e78f7f2604ac42be37614daf1dd60d2e803a1fb7a48342a41a2a573
client  | 0.818 DEBUG Read secret available for Handshake: SymKey [32]: 8282d5ca29282b24a33456a69e1df2b3a206d385a63be8aa1bfbaad6d744af86
client  | 0.818 DEBUG [Agent 0x55a26ae7f2d0] state -> InProgress
client  | 0.818 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.818 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
client  | 0.818 DEBUG [Crypto] Handshake keys installed
client  | 0.818 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 537, tv_nsec: 640750871 }
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] State change from WaitVersion -> Handshaking
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.818 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=5 type=Initial pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 43
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 81, first_ack_range: 1, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  | 0.818 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pad Initial from 43 to PLPMTU 1252
client  | 0.818 DEBUG ECN probing: sent 6 probes
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.818 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.818 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.818 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 128.81818ms
client  | 0.818 DEBUG Setting timeout of 128.81818ms
client  | 0.818 DEBUG StateChange(Handshaking)
client  | 0.818 WARN Cannot create stream ConnectionState
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.818 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.818 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.818 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.818 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 128.781191ms
client  | 0.818 DEBUG Setting timeout of 128.781191ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 2/6 (33.3%)
sim     | Dropping 1200 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 2/5 (40.0%)
sim     | Forwarding 744 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 2/6 (33.3%)
client  | 0.860 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=1 type=Handshake pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 744
client  |   -> RX Crypto { offset: 0, len: 706 }
client  | 0.860 DEBUG Read Ok(706) bytes
client  | 0.861 DEBUG [Agent 0x55a26ae7f2d0] state -> AuthenticationPending
client  | 0.861 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 537, tv_nsec: 683545298 }
client  | 0.861 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.861 DEBUG Building Initial dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.861 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.861 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=0 type=Handshake pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 40
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 107, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.861 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Drop packet number space in
client  | 0.861 DEBUG [LossRecovery] Reset loss recovery state for Initial
client  | 0.861 DEBUG ECN probing: sent 7 probes
client  | 0.861 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.861 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.861 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.861 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 468.477913ms
client  | 0.861 DEBUG Setting timeout of 468.477913ms
client  | 0.861 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Authenticated Ok
client  | 0.861 DEBUG Write secret available for ApplicationData: SymKey [32]: b5ce289290e64631e8ee49bb2db67fc38257e5ae8c95ab4826ee737dd368783e
client  | 0.861 DEBUG Read secret available for ApplicationData: SymKey [32]: f97d5f0e7224beeb3ac24a6e85d03c52489ea6473b5ed0a483d63076342dce4c
client  | 0.862 DEBUG [Agent 0x55a26ae7f2d0] SSL_AuthCertificateComplete: Ok(())
client  | 0.862 DEBUG [Agent 0x55a26ae7f2d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.862 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] TLS connection complete
client  | 0.862 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.862 DEBUG [Crypto] Application write key installed
client  | 0.862 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 0.862 DEBUG [Crypto] application read keys installed
client  | 0.862 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] State change from Handshaking -> Connected
client  | 0.862 INFO [Client c27d328aa031363d15a8edd62703b91ed3] Connection established
client  | 0.862 DEBUG stream BiDi creatable
client  | 0.862 INFO Created stream 0 for https://server4/hipeimvjsi
client  | 0.862 INFO Saving https://server4/hipeimvjsi to "/downloads/hipeimvjsi"
client  | 0.862 DEBUG StateChange(Connected)
client  | 0.862 DEBUG stream 0 writable
client  | 0.862 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.862 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.862 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.862 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=1 type=Handshake pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 73
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.862 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=73
client  | 0.862 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.862 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=0 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 119
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 0.862 DEBUG packet_sent this=0x55a26ae92be0, pn=0, ps=46
client  | 0.862 DEBUG ECN probing: sent 8 probes
client  | 0.862 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.862 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.862 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.862 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.862 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 468.540152ms
client  | 0.862 DEBUG Setting timeout of 468.540152ms
sim     | Forwarding 40 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 2/7 (28.6%)
sim     | Dropping 119 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 3/8 (37.5%)
sim     | Forwarding 744 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 2/7 (28.6%)
client  | 0.900 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=2 type=Handshake pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 744
client  |   -> RX Crypto { offset: 0, len: 706 }
client  | 0.900 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 537, tv_nsec: 723302508 }
client  | 0.900 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.900 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.900 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.900 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=2 type=Handshake pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 78
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 7, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.900 DEBUG packet_sent this=0x55a26ae92be0, pn=2, ps=78
client  | 0.900 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.900 DEBUG ECN probing: sent 9 probes
client  | 0.900 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.900 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.900 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.900 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.900 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 468.494556ms
client  | 0.900 DEBUG Setting timeout of 468.494556ms
client  | 0.900 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.900 DEBUG Building Handshake dcid Some(CID [8]: 667f2584819588c3) scid Some(CID [0]: )
client  | 0.900 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.900 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.900 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 468.461495ms
client  | 0.900 DEBUG Setting timeout of 468.461495ms
sim     | Forwarding 78 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 3/9 (33.3%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 2/8 (25.0%)
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=0 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 204
client  |   -> RX HandshakeDone
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [230, 14, 31, 204, 121, 89, 99, 236], stateless_reset_token: [9, 177, 39, 117, 175, 28, 162, 242, 240, 64, 28, 229, 111, 101, 126, 37] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [70, 190, 80, 32, 161, 97, 39, 73], stateless_reset_token: [206, 168, 102, 144, 110, 223, 215, 170, 56, 137, 175, 165, 86, 252, 246, 194] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [215, 217, 213, 224, 75, 7, 20, 161], stateless_reset_token: [2, 118, 192, 230, 69, 45, 238, 59, 4, 203, 80, 159, 218, 187, 15, 119] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [24, 126, 102, 227, 242, 124, 220, 131], stateless_reset_token: [150, 148, 52, 53, 4, 63, 106, 119, 240, 200, 234, 239, 160, 165, 240, 211] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [5, 90, 202, 182, 223, 43, 93, 197], stateless_reset_token: [204, 119, 204, 183, 41, 180, 188, 15, 3, 216, 63, 95, 10, 136, 137, 199] }
client  |   -> RX Crypto { offset: 0, len: 42 }
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] State change from Connected -> Confirmed
client  | 0.938 DEBUG PMTUD started with probe size 1380
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Drop packet number space hs
client  | 0.938 DEBUG [LossRecovery] Reset loss recovery state for Handshake
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] No preferred address to migrate to
client  | 0.938 DEBUG Read Ok(42) bytes
client  | 0.938 DEBUG [0x55a26ae7f2d0] Got resumption token [711]: 02000633fcb515ed..e150e52477c9f4e2
client  | 0.938 DEBUG [Agent 0x55a26ae7f2d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 0.938 DEBUG setting max_stream_data to 1000000
client  | 0.938 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 537, tv_nsec: 780963702 }
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.938 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.938 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=1 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1325 }
client  | 0.938 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=1352
client  | 0.938 DEBUG ECN probing: sent 10 probes
client  | 0.938 DEBUG ECN probing concluded with 10 probes sent
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.938 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.938 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 19.516997ms
client  | 0.938 DEBUG Setting timeout of 19.516997ms
client  | 0.938 WARN Unhandled event StateChange(Confirmed)
client  | 0.938 DEBUG stream BiDi creatable
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.938 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.938 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.938 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 19.479317ms
client  | 0.938 DEBUG Setting timeout of 19.479317ms
sim     | Forwarding 1352 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 3/10 (30.0%)
client  | 0.959 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.959 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.959 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=2 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 32
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 2703, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.959 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.959 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.959 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.959 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 472.267098ms
client  | 0.959 DEBUG Setting timeout of 472.267098ms
sim     | Dropping 32 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 4/11 (36.4%)
sim     | Forwarding 24 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 2/9 (22.2%)
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=1 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 24
client  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 2375, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Rx ACK space=ap, ranges=[1..=1]
client  | 0.991 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
client  | 0.991 WARN ECN validation failed, no ECN counts in ACK frame
client  | 0.991 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=46, cwnd=8764, state=CongestionAvoidance, new_acked=0
client  | 0.991 DEBUG PMTUD probe of size 1380 succeeded
client  | 0.991 DEBUG PMTUD started with probe size 1420
client  | 0.991 DEBUG PLPMTU changed from 1252 to 1352, updating pacer
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.991 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.991 DEBUG Sending PMTUD probe of size 1420, count 1
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=3 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1392
client  |   TX -> Ping
client  |   TX -> Padding { len: 1365 }
client  | 0.991 DEBUG packet_sent this=0x55a26ae92be0, pn=3, ps=1392
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.991 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.991 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 47.294868ms
client  | 0.991 DEBUG Setting timeout of 47.294868ms
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.991 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 0.991 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 0.991 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 47.259823ms
client  | 0.991 DEBUG Setting timeout of 47.259823ms
sim     | Forwarding 1392 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 4/12 (33.3%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 2/10 (20.0%)
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=2 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 204
client  |   -> RX HandshakeDone
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [230, 14, 31, 204, 121, 89, 99, 236], stateless_reset_token: [9, 177, 39, 117, 175, 28, 162, 242, 240, 64, 28, 229, 111, 101, 126, 37] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [70, 190, 80, 32, 161, 97, 39, 73], stateless_reset_token: [206, 168, 102, 144, 110, 223, 215, 170, 56, 137, 175, 165, 86, 252, 246, 194] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [215, 217, 213, 224, 75, 7, 20, 161], stateless_reset_token: [2, 118, 192, 230, 69, 45, 238, 59, 4, 203, 80, 159, 218, 187, 15, 119] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [24, 126, 102, 227, 242, 124, 220, 131], stateless_reset_token: [150, 148, 52, 53, 4, 63, 106, 119, 240, 200, 234, 239, 160, 165, 240, 211] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [5, 90, 202, 182, 223, 43, 93, 197], stateless_reset_token: [204, 119, 204, 183, 41, 180, 188, 15, 3, 216, 63, 95, 10, 136, 137, 199] }
client  |   -> RX Crypto { offset: 0, len: 42 }
client  | 1.031 DEBUG PMTUD started with probe size 1472
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] No preferred address to migrate to
client  | 1.031 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 537, tv_nsec: 874174903 }
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.031 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.031 DEBUG Sending PMTUD probe of size 1472, count 1
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=4 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1444
client  |   TX -> Ping
client  |   TX -> Padding { len: 1417 }
client  | 1.031 DEBUG packet_sent this=0x55a26ae92be0, pn=4, ps=1444
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.031 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.031 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 7.387886ms
client  | 1.031 DEBUG Setting timeout of 7.387886ms
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.031 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.031 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.031 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 7.359924ms
client  | 1.031 DEBUG Setting timeout of 7.359924ms
client  | 1.040 DEBUG packet_lost this=0x55a26ae92be0, pn=0, ps=46
client  | 1.040 DEBUG on_packets_lost this=0x55a26ae92be0, bytes_in_flight=2836, cwnd=8764, state=CongestionAvoidance
client  | 1.040 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 1.040 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.040 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.040 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=5 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 46
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 1.040 DEBUG packet_sent this=0x55a26ae92be0, pn=5, ps=46
client  | 1.040 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.040 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.040 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.040 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 10.59022ms
client  | 1.040 DEBUG Setting timeout of 10.59022ms
sim     | Dropping 26 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 3/11 (27.3%)
sim     | Forwarding 1444 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 4/13 (30.8%)
client  | 1.051 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.051 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.051 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=6 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 32
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 2564, first_ack_range: 2, ack_ranges: [], ecn_count: None }
client  | 1.051 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.051 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.051 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 1.051 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 512.176976ms
client  | 1.052 DEBUG Setting timeout of 512.176976ms
sim     | Dropping 46 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 5/14 (35.7%)
sim     | Forwarding 32 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 5/15 (33.3%)
sim     | Forwarding 24 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 3/12 (25.0%)
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=4 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 24
client  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 2375, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Rx ACK space=ap, ranges=[3..=4]
client  | 1.083 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
client  | 1.083 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=46, cwnd=8764, state=CongestionAvoidance, new_acked=1444
client  | 1.083 DEBUG PMTUD probe of size 1472 succeeded
client  | 1.083 DEBUG PMTUD started with probe size 1500
client  | 1.083 DEBUG PLPMTU changed from 1352 to 1444, updating pacer
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1444, pto: None, probe: EnumSet(), paced: false }
client  | 1.083 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.083 DEBUG Sending PMTUD probe of size 1500, count 1
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=7 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1472
client  |   TX -> Ping
client  |   TX -> Padding { len: 1445 }
client  | 1.083 DEBUG packet_sent this=0x55a26ae92be0, pn=7, ps=1472
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1444, pto: None, probe: EnumSet(), paced: false }
client  | 1.083 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.083 DEBUG TX blocked, profile=SendProfile { limit: 1444, pto: None, probe: EnumSet(), paced: false }
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 38.170531ms
client  | 1.083 DEBUG Setting timeout of 38.170531ms
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1444, pto: None, probe: EnumSet(), paced: false }
client  | 1.083 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.083 DEBUG TX blocked, profile=SendProfile { limit: 1444, pto: None, probe: EnumSet(), paced: false }
client  | 1.083 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 38.144222ms
client  | 1.083 DEBUG Setting timeout of 38.144222ms
sim     | Forwarding 1472 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 5/16 (31.2%)
client  | 1.122 DEBUG on_packets_lost this=0x55a26ae92be0, bytes_in_flight=1518, cwnd=8764, state=CongestionAvoidance
client  | 1.122 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: Ack(AckToken { space: ApplicationData, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
client  | 1.122 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1444, pto: None, probe: EnumSet(), paced: false }
client  | 1.122 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.122 DEBUG TX blocked, profile=SendProfile { limit: 1444, pto: None, probe: EnumSet(), paced: false }
client  | 1.122 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 490.844467ms
client  | 1.122 DEBUG Setting timeout of 490.844467ms
sim     | Forwarding 26 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 3/13 (23.1%)
client  | 1.135 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=5 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 26
client  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 2375, first_ack_range: 1, ack_ranges: [AckRange { gap: 0, range: 1 }], ecn_count: None }
client  | 1.135 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Rx ACK space=ap, ranges=[6..=7, 3..=4]
client  | 1.135 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
client  | 1.135 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=46, cwnd=8764, state=CongestionAvoidance, new_acked=1472
client  | 1.135 DEBUG PMTUD probe of size 1500 succeeded
client  | 1.135 INFO PMTUD stopped, PLPMTU is now 1500, raise timer Some(Instant { tv_sec: 1137, tv_nsec: 958054307 })
client  | 1.135 DEBUG PLPMTU changed from 1444 to 1472, updating pacer
client  | 1.135 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.135 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.135 DEBUG TX blocked, profile=SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.135 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 54.257119ms
client  | 1.135 DEBUG Setting timeout of 54.257119ms
client  | 1.135 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.135 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.135 DEBUG TX blocked, profile=SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.135 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 54.228525ms
client  | 1.135 DEBUG Setting timeout of 54.228525ms
client  | 1.190 DEBUG packet_lost this=0x55a26ae92be0, pn=5, ps=46
client  | 1.190 DEBUG [Cubic [last_max_cwnd: 7449.4, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/6134 ssthresh 6134] Cong event -> recovery; cwnd 6134, ssthresh 6134
client  | 1.190 DEBUG [Cubic [last_max_cwnd: 7449.4, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/6134 ssthresh 6134] state -> RecoveryStart
client  | 1.190 DEBUG on_packets_lost this=0x55a26ae92be0, bytes_in_flight=0, cwnd=6134, state=RecoveryStart
client  | 1.190 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 1.190 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.190 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.190 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=8 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 46
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 1.190 DEBUG packet_sent this=0x55a26ae92be0, pn=8, ps=46
client  | 1.190 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.190 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.190 DEBUG TX blocked, profile=SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.190 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 520.096797ms
client  | 1.190 DEBUG Setting timeout of 520.096797ms
sim     | Forwarding 46 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 5/17 (29.4%)
sim     | Forwarding 1058 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 3/14 (21.4%)
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=6 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1058
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 250, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 1 }], ecn_count: None }
client  |   -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 }
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] Rx ACK space=ap, ranges=[6..=8, 3..=4]
client  | 1.231 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8
client  | 1.231 DEBUG [Cubic [last_max_cwnd: 7449.4, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/6134 ssthresh 6134] state -> CongestionAvoidance
client  | 1.231 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=0, cwnd=6134, state=CongestionAvoidance, new_acked=46
client  | 1.231 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 538, tv_nsec: 73762191 }
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.231 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=9 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 31
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 26, first_ack_range: 2, ack_ranges: [], ecn_count: None }
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.231 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.231 DEBUG TX blocked, profile=SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 469.187379ms
client  | 1.231 DEBUG Setting timeout of 469.187379ms
client  | 1.231 DEBUG stream 0 complete
client  | 1.231 DEBUG stream BiDi creatable
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.231 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.231 DEBUG TX blocked, profile=SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] delay duration 469.112739ms
client  | 1.231 DEBUG Setting timeout of 469.112739ms
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 539, tv_nsec: 584117425 } }
client  | 1.231 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 539, tv_nsec: 584117425 } })
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.231 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.231 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 538, tv_nsec: 54298267 }
client  | 1.231 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=10 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 67, first_ack_range: 2, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 1.231 DEBUG Setting timeout of 1.529749027s
sim     | Forwarding 31 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 5/18 (27.8%)
sim     | Forwarding 43 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 5/19 (26.3%)
sim     | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 3/15 (20.0%)
client  | 1.269 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] output_path send_profile SendProfile { limit: 1472, pto: None, probe: EnumSet(), paced: false }
client  | 1.269 DEBUG Building Short dcid Some(CID [8]: 667f2584819588c3)
client  | 1.269 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 538, tv_nsec: 92714983 }
client  | 1.269 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] pn=11 type=Short pri-path:667f2584819588c3 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 4869, first_ack_range: 2, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 1.270 DEBUG Setting timeout of 1.491272999s
client  | 1.270 DEBUG Setting timeout of 1.491257581s
sim     | Forwarding 43 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 5/20 (25.0%)
server  | QLog: done with c27d328aa031363d15a8edd62703b91ed3.qlog
client  | 2.762 DEBUG [Client c27d328aa031363d15a8edd62703b91ed3] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 539, tv_nsec: 584117425 } } -> Closed(Application(0))
client  | 2.762 INFO Closing timer expired
client  | 2.762 DEBUG Output::None
client  | 2.762 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 2.762 DEBUG Timer fired while closed
client  | 2.762 DEBUG Output::None
client  | 2.762 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=b90b55d9cf1e1aad46e978cf80
client  | 2.763 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
client  | 2.763 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
client  | 2.763 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500
client  | 2.763 DEBUG [unv-path 0.0.0.0:48305->193.167.100.100:443] Make permanent
client  | 2.763 DEBUG [unv-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443] set as primary path
client  | 2.763 DEBUG [pri-unv-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443] Path validated Instant { tv_sec: 539, tv_nsec: 585737755 }
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] client_start
client  | 2.763 DEBUG Writing transport parameters, msg=1
client  | 2.763 DEBUG [Agent 0x55a26aec5f40] state -> InProgress
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] State change from Init -> WaitInitial
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.763 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 2.763 DEBUG CRYPTO for in offset=0, len=1208
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=0 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1208 }
client  | 2.763 DEBUG packet_sent this=0x55a26ae92be0, pn=0, ps=1252
client  | 2.763 DEBUG ECN probing: sent 1 probes
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.763 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 2.763 DEBUG CRYPTO for in offset=1208, len=331
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=1 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 376
client  |   TX -> Crypto { offset: 1208, len: 331 }
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pad Initial from 376 to PLPMTU 1252
client  | 2.763 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=1252
client  | 2.763 DEBUG ECN probing: sent 2 probes
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 2.763 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 2.763 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 4.011868ms
client  | 2.763 DEBUG Setting timeout of 4.011868ms
client  | 2.763 DEBUG StateChange(WaitInitial)
client  | 2.763 WARN Cannot create stream ConnectionState
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 2.763 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 2.763 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 2.763 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 3.984497ms
client  | 2.763 DEBUG Setting timeout of 3.984497ms
client  | 2.769 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.769 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 2.769 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 2.769 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 294.789456ms
client  | 2.769 DEBUG Setting timeout of 294.789456ms
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 6/21 (28.6%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 6/22 (27.3%)
client  | 3.065 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 })
client  | 3.065 INFO Lost crypto frame space=in offset=0 length=1208
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 })
client  | 3.065 INFO Lost crypto frame space=in offset=1208 length=331
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 3.065 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 3.065 DEBUG CRYPTO for in offset=0, len=1208
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=2 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1208 }
client  | 3.065 DEBUG packet_sent this=0x55a26ae92be0, pn=2, ps=1252
client  | 3.065 DEBUG ECN probing: sent 3 probes
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.065 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 3.065 DEBUG CRYPTO for in offset=1208, len=331
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=3 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 376
client  |   TX -> Crypto { offset: 1208, len: 331 }
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pad Initial from 376 to PLPMTU 1252
client  | 3.065 DEBUG packet_sent this=0x55a26ae92be0, pn=3, ps=1252
client  | 3.065 DEBUG ECN probing: sent 4 probes
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.065 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 3.065 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.065 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 599.922275ms
client  | 3.065 DEBUG Setting timeout of 599.922275ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 6/23 (26.1%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 6/24 (25.0%)
client  | 3.666 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 })
client  | 3.666 INFO Lost crypto frame space=in offset=0 length=1208
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 3.666 DEBUG ECN validation failed, all 3 initial marked packets were lost
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 })
client  | 3.666 INFO Lost crypto frame space=in offset=1208 length=331
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 3.666 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 3.666 DEBUG CRYPTO for in offset=0, len=1208
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=4 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1208 }
client  | 3.666 DEBUG packet_sent this=0x55a26ae92be0, pn=4, ps=1252
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.666 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 3.666 DEBUG CRYPTO for in offset=1208, len=331
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=5 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 376
client  |   TX -> Crypto { offset: 1208, len: 331 }
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pad Initial from 376 to PLPMTU 1252
client  | 3.666 DEBUG packet_sent this=0x55a26ae92be0, pn=5, ps=1252
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.666 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 3.666 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.666 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 1.199897729s
client  | 3.666 DEBUG Setting timeout of 1.199897729s
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 6/25 (24.0%)
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/26 (26.9%)
client  | 4.868 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 })
client  | 4.868 INFO Lost crypto frame space=in offset=0 length=1208
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 })
client  | 4.868 INFO Lost crypto frame space=in offset=1208 length=331
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 4.868 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 4.868 DEBUG CRYPTO for in offset=0, len=1208
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=6 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1208 }
client  | 4.868 DEBUG packet_sent this=0x55a26ae92be0, pn=6, ps=1252
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.868 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 4.868 DEBUG CRYPTO for in offset=1208, len=331
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=7 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 376
client  |   TX -> Crypto { offset: 1208, len: 331 }
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pad Initial from 376 to PLPMTU 1252
client  | 4.868 DEBUG packet_sent this=0x55a26ae92be0, pn=7, ps=1252
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.868 DEBUG Building Initial dcid Some(CID [13]: b90b55d9cf1e1aad46e978cf80) scid Some(CID [0]: )
client  | 4.868 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.868 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 2.399917455s
client  | 4.868 DEBUG Setting timeout of 2.399917455s
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/27 (25.9%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/28 (25.0%)
sim     | Dropping 1201 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 4/16 (25.0%)
sim     | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 4/17 (23.5%)
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=1 type=Initial pri-path:b90b55d9cf1e1aad46e978cf80 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 461
client  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   -> RX Crypto { offset: 0, len: 90 }
client  |   -> RX Padding { len: 327 }
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Rx ACK space=in, ranges=[6..=7]
client  | 4.914 DEBUG [LossRecovery] ACK for Initial - largest_acked=7
client  | 4.914 DEBUG packet_lost this=0x55a26ae92be0, pn=0, ps=1252
client  | 4.914 DEBUG packet_lost this=0x55a26ae92be0, pn=1, ps=1252
client  | 4.914 DEBUG packet_lost this=0x55a26ae92be0, pn=2, ps=1252
client  | 4.914 DEBUG packet_lost this=0x55a26ae92be0, pn=3, ps=1252
client  | 4.914 DEBUG packet_lost this=0x55a26ae92be0, pn=4, ps=1252
client  | 4.914 DEBUG packet_lost this=0x55a26ae92be0, pn=5, ps=1252
client  | 4.914 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2504/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764
client  | 4.914 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2504/8764 ssthresh 8764] state -> RecoveryStart
client  | 4.914 DEBUG on_packets_lost this=0x55a26ae92be0, bytes_in_flight=2504, cwnd=8764, state=RecoveryStart
client  | 4.914 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 541, tv_nsec: 737115469 })] CongCtrl 0/8764 ssthresh 8764] on_packets_acked this=0x55a26ae92be0, limited=0, bytes_in_flight=0, cwnd=8764, state=RecoveryStart, new_acked=0
client  | 4.914 DEBUG Acked crypto frame space=in offset=1208 length=331
client  | 4.914 DEBUG Acked crypto frame space=in offset=0 length=1208
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 })
client  | 4.914 INFO Lost crypto frame space=in offset=0 length=1208
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 })
client  | 4.914 INFO Lost crypto frame space=in offset=1208 length=331
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 })
client  | 4.914 INFO Lost crypto frame space=in offset=0 length=1208
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 })
client  | 4.914 INFO Lost crypto frame space=in offset=1208 length=331
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: EcnEct0
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1208 })
client  | 4.914 INFO Lost crypto frame space=in offset=0 length=1208
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1208, length: 331 })
client  | 4.914 INFO Lost crypto frame space=in offset=1208 length=331
client  | 4.914 DEBUG Read Ok(90) bytes
client  | 4.914 DEBUG Write secret available for Handshake: SymKey [32]: b473139e9c872170a74b7e288c407fdfb39893a2a0c558cd488897de5d6d304c
client  | 4.914 DEBUG Read secret available for Handshake: SymKey [32]: ca8fb9d5934b17a2413f13e932844610c32ae435dc79c646a644487a81806c8b
client  | 4.914 DEBUG [Agent 0x55a26aec5f40] state -> InProgress
client  | 4.914 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
client  | 4.914 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
client  | 4.914 DEBUG [Crypto] Handshake keys installed
client  | 4.914 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 541, tv_nsec: 737115469 }
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Changing to use Server CID=402067375ea1bb9b
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] State change from WaitInitial -> Handshaking
client  | 4.914 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=0 type=Handshake pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 739
client  |   -> RX Crypto { offset: 0, len: 701 }
client  | 4.914 DEBUG Read Ok(701) bytes
client  | 4.915 DEBUG [Agent 0x55a26aec5f40] state -> AuthenticationPending
client  | 4.915 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 541, tv_nsec: 737115469 }
client  | 4.915 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.915 DEBUG Building Initial dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.915 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=8 type=Initial pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 41
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 175, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 4.915 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.915 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=0 type=Handshake pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 81
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 175, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 4.915 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Drop packet number space in
client  | 4.915 DEBUG [LossRecovery] Reset loss recovery state for Initial
client  | 4.915 WARN [LossRecovery] ignoring in-8 from dropped space
client  | 4.915 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.915 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.915 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.915 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 137.424897ms
client  | 4.915 DEBUG Setting timeout of 137.424897ms
client  | 4.915 DEBUG StateChange(Handshaking)
client  | 4.915 WARN Cannot create stream ConnectionState
client  | 4.915 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Authenticated Ok
client  | 4.915 DEBUG Write secret available for ApplicationData: SymKey [32]: 3fa7c252574cdb525900c9750bc61a3f0284b0b48298c56cc332576c7b228818
client  | 4.915 DEBUG Read secret available for ApplicationData: SymKey [32]: a8c54a1c0edcc8268505f7a3006535e300f820bde1006f39702850b98778e04a
client  | 4.916 DEBUG [Agent 0x55a26aec5f40] SSL_AuthCertificateComplete: Ok(())
client  | 4.916 DEBUG [Agent 0x55a26aec5f40] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 4.916 DEBUG [Client b90b55d9cf1e1aad46e978cf80] TLS connection complete
client  | 4.916 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 4.916 DEBUG [Crypto] Application write key installed
client  | 4.916 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 4.916 DEBUG [Crypto] application read keys installed
client  | 4.916 DEBUG [Client b90b55d9cf1e1aad46e978cf80] State change from Handshaking -> Connected
client  | 4.916 INFO [Client b90b55d9cf1e1aad46e978cf80] Connection established
client  | 4.916 DEBUG stream BiDi creatable
client  | 4.916 INFO Created stream 0 for https://server4/ilsjzkoxmb
client  | 4.916 INFO Saving https://server4/ilsjzkoxmb to "/downloads/ilsjzkoxmb"
client  | 4.916 DEBUG StateChange(Connected)
client  | 4.916 DEBUG stream 0 writable
client  | 4.916 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.916 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.916 DEBUG CRYPTO for hs offset=0, len=36
client  | 4.916 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=1 type=Handshake pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 73
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 4.916 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=73
client  | 4.916 DEBUG Building Short dcid Some(CID [8]: 402067375ea1bb9b)
client  | 4.916 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=0 type=Short pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 119
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 4.916 DEBUG packet_sent this=0x55a26ae92be0, pn=0, ps=46
client  | 4.916 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.916 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.916 DEBUG Building Short dcid Some(CID [8]: 402067375ea1bb9b)
client  | 4.916 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.916 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 137.457147ms
client  | 4.916 DEBUG Setting timeout of 137.457147ms
sim     | Forwarding 81 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/29 (24.1%)
sim     | Forwarding 119 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/30 (23.3%)
sim     | Dropping 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 5/18 (27.8%)
sim     | Forwarding 1055 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 5/19 (26.3%)
client  | 4.950 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=1 type=Short pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1055
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  |   -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 }
client  | 4.950 DEBUG [Client b90b55d9cf1e1aad46e978cf80] Rx ACK space=ap, ranges=[0..=0]
client  | 4.950 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0
client  | 4.950 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 541, tv_nsec: 737115469 })] CongCtrl 73/8764 ssthresh 8764] state -> CongestionAvoidance
client  | 4.951 DEBUG [Cubic [last_max_cwnd: 12520, k: 1.9574338205844317, w_max: 12520, ca_epoch_start: Some(Instant { tv_sec: 541, tv_nsec: 737115469 })] CongCtrl 73/8764 ssthresh 8764] on_packets_acked this=0x55a26ae92be0, limited=0, bytes_in_flight=73, cwnd=8764, state=CongestionAvoidance, new_acked=46
client  | 4.951 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 541, tv_nsec: 773676684 }
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.951 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.951 DEBUG Building Short dcid Some(CID [8]: 402067375ea1bb9b)
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=1 type=Short pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 31
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 21, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.951 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.951 DEBUG Building Short dcid Some(CID [8]: 402067375ea1bb9b)
client  | 4.951 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 89.556928ms
client  | 4.951 DEBUG Setting timeout of 89.556928ms
client  | 4.951 DEBUG stream 0 complete
client  | 4.951 DEBUG stream BiDi creatable
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.951 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.951 DEBUG Building Short dcid Some(CID [8]: 402067375ea1bb9b)
client  | 4.951 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] delay duration 89.500232ms
client  | 4.951 DEBUG Setting timeout of 89.500232ms
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] State change from Connected -> Closing { error: Application(0), timeout: Instant { tv_sec: 542, tv_nsec: 147315425 } }
client  | 4.951 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 542, tv_nsec: 147315425 } })
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.951 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 4.951 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 541, tv_nsec: 774029153 }
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=2 type=Handshake pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 44
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 4614, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" }
client  | 4.951 DEBUG Building Short dcid Some(CID [8]: 402067375ea1bb9b)
client  | 4.951 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 541, tv_nsec: 774029153 }
client  | 4.951 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=2 type=Short pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 86
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 44, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 4.951 DEBUG Setting timeout of 373.184201ms
sim     | Forwarding 31 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/31 (22.6%)
sim     | Forwarding 86 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/32 (21.9%)
sim     | Dropping 209 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 6/20 (30.0%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 6/21 (28.6%)
client  | 5.172 DEBUG [Client b90b55d9cf1e1aad46e978cf80] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.172 DEBUG Building Handshake dcid Some(CID [8]: 402067375ea1bb9b) scid Some(CID [0]: )
client  | 5.172 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 541, tv_nsec: 995478684 }
client  | 5.172 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=3 type=Handshake pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 46
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 32295, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Transport(12), frame_type: 0, reason_phrase: "" }
client  | 5.172 DEBUG Building Short dcid Some(CID [8]: 402067375ea1bb9b)
client  | 5.172 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 541, tv_nsec: 995478684 }
client  | 5.172 DEBUG [Client b90b55d9cf1e1aad46e978cf80] pn=3 type=Short pri-path:402067375ea1bb9b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 91
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 27725, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 5.172 DEBUG Setting timeout of 151.679226ms
client  | 5.172 DEBUG Setting timeout of 151.66565ms
sim     | Forwarding 91 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/33 (21.2%)
client  | 5.325 DEBUG [Client b90b55d9cf1e1aad46e978cf80] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 542, tv_nsec: 147315425 } } -> Closed(Application(0))
client  | 5.325 INFO Closing timer expired
client  | 5.325 DEBUG Output::None
client  | 5.325 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 5.325 DEBUG Timer fired while closed
client  | 5.325 DEBUG Output::None
client  | 5.325 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=ba2fabca86c98f1c83c69ef3df3b52fa4a
client  | 5.325 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
client  | 5.325 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
client  | 5.325 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500
client  | 5.325 DEBUG [unv-path 0.0.0.0:48305->193.167.100.100:443] Make permanent
client  | 5.325 DEBUG [unv-path:ba2fabca86c98f1c83c69ef3df3b52fa4a 0.0.0.0:48305->193.167.100.100:443] set as primary path
client  | 5.325 DEBUG [pri-unv-path:ba2fabca86c98f1c83c69ef3df3b52fa4a 0.0.0.0:48305->193.167.100.100:443] Path validated Instant { tv_sec: 542, tv_nsec: 148198952 }
client  | 5.325 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] client_start
client  | 5.326 DEBUG Writing transport parameters, msg=1
client  | 5.326 DEBUG [Agent 0x55a26ae70040] state -> InProgress
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] State change from Init -> WaitInitial
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.326 DEBUG Building Initial dcid Some(CID [17]: ba2fabca86c98f1c83c69ef3df3b52fa4a) scid Some(CID [0]: )
client  | 5.326 DEBUG CRYPTO for in offset=0, len=1204
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=0 type=Initial pri-path:ba2fabca86c98f1c83c69ef3df3b52fa4a 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 5.326 DEBUG packet_sent this=0x55a26ae92be0, pn=0, ps=1252
client  | 5.326 DEBUG ECN probing: sent 1 probes
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.326 DEBUG Building Initial dcid Some(CID [17]: ba2fabca86c98f1c83c69ef3df3b52fa4a) scid Some(CID [0]: )
client  | 5.326 DEBUG CRYPTO for in offset=1204, len=335
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=1 type=Initial pri-path:ba2fabca86c98f1c83c69ef3df3b52fa4a 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pad Initial from 384 to PLPMTU 1252
client  | 5.326 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=1252
client  | 5.326 DEBUG ECN probing: sent 2 probes
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 5.326 DEBUG Building Initial dcid Some(CID [17]: ba2fabca86c98f1c83c69ef3df3b52fa4a) scid Some(CID [0]: )
client  | 5.326 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 3.983583ms
client  | 5.326 DEBUG Setting timeout of 3.983583ms
client  | 5.326 DEBUG StateChange(WaitInitial)
client  | 5.326 WARN Cannot create stream ConnectionState
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 5.326 DEBUG Building Initial dcid Some(CID [17]: ba2fabca86c98f1c83c69ef3df3b52fa4a) scid Some(CID [0]: )
client  | 5.326 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 5.326 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 3.960139ms
client  | 5.326 DEBUG Setting timeout of 3.960139ms
client  | 5.331 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.331 DEBUG Building Initial dcid Some(CID [17]: ba2fabca86c98f1c83c69ef3df3b52fa4a) scid Some(CID [0]: )
client  | 5.331 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.331 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 294.823609ms
client  | 5.331 DEBUG Setting timeout of 294.823609ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/34 (20.6%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/35 (20.0%)
sim     | Forwarding 1201 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 6/22 (27.3%)
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=0 type=Initial pri-path:ba2fabca86c98f1c83c69ef3df3b52fa4a 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1201
client  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  |   -> RX Padding { len: 1161 }
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Rx ACK space=in, ranges=[0..=0]
client  | 5.365 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
client  | 5.365 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 5.365 DEBUG Acked crypto frame space=in offset=0 length=1204
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Changing to use Server CID=5a9f105e0887c18b
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] State change from WaitInitial -> WaitVersion
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.365 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.365 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 78.807906ms
client  | 5.365 DEBUG Setting timeout of 78.807906ms
client  | 5.365 WARN Unhandled event StateChange(WaitVersion)
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.365 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.365 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.365 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 78.769514ms
client  | 5.365 DEBUG Setting timeout of 78.769514ms
sim     | Dropping 1200 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 7/23 (30.4%)
sim     | Dropping 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 8/24 (33.3%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 8/25 (32.0%)
client  | 5.395 DEBUG saving datagram of 204 bytes
client  | 5.395 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.395 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.395 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.395 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 48.513376ms
client  | 5.395 DEBUG Setting timeout of 48.513376ms
client  | 5.395 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.395 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.395 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.395 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 48.481596ms
client  | 5.395 DEBUG Setting timeout of 48.481596ms
client  | 5.445 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 5.445 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1204, length: 335 })
client  | 5.445 INFO Lost crypto frame space=in offset=1204 length=335
client  | 5.445 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Lost: EcnEct0
client  | 5.445 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 5.445 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.445 DEBUG CRYPTO for in offset=1204, len=335
client  | 5.445 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=2 type=Initial pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 375
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 5.445 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pad Initial from 375 to PLPMTU 1252
client  | 5.445 DEBUG packet_sent this=0x55a26ae92be0, pn=2, ps=1252
client  | 5.445 DEBUG ECN probing: sent 3 probes
client  | 5.445 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.445 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.445 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.445 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 235.004242ms
client  | 5.445 DEBUG Setting timeout of 235.004242ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/36 (19.4%)
sim     | Dropping 1201 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 9/26 (34.6%)
sim     | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 9/27 (33.3%)
client  | 5.570 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=3 type=Initial pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1200
client  |   -> RX Crypto { offset: 0, len: 90 }
client  |   -> RX Padding { len: 1071 }
client  | 5.570 DEBUG Read Ok(90) bytes
client  | 5.570 DEBUG Write secret available for Handshake: SymKey [32]: f4d45510eb99f4cd24d18c25cd52d18c5a2c08bbda39f00c02496a69c1d64908
client  | 5.570 DEBUG Read secret available for Handshake: SymKey [32]: 2a2a19764297b7668b6385799588debf8b09632dcc7ca7d5d4e101ccfad22911
client  | 5.570 DEBUG [Agent 0x55a26ae70040] state -> InProgress
client  | 5.570 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
client  | 5.570 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
client  | 5.571 DEBUG [Crypto] Handshake keys installed
client  | 5.571 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 542, tv_nsec: 393408314 }
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] State change from WaitVersion -> Handshaking
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.571 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=3 type=Initial pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 42
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 55, first_ack_range: 0, ack_ranges: [AckRange { gap: 1, range: 0 }], ecn_count: None }
client  | 5.571 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pad Initial from 42 to PLPMTU 1252
client  | 5.571 DEBUG ECN probing: sent 4 probes
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.571 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.571 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.571 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 109.62098ms
client  | 5.571 DEBUG Setting timeout of 109.62098ms
client  | 5.571 DEBUG StateChange(Handshaking)
client  | 5.571 WARN Cannot create stream ConnectionState
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.571 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.571 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.571 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.571 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 109.509171ms
client  | 5.571 DEBUG Setting timeout of 109.509171ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/37 (18.9%)
sim     | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 9/28 (32.1%)
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=4 type=Initial pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1200
client  |   -> RX Crypto { offset: 0, len: 90 }
client  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   -> RX Padding { len: 1066 }
client  | 5.605 DEBUG unmark 0-length range at 0
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Rx ACK space=in, ranges=[1..=2]
client  | 5.605 DEBUG [LossRecovery] ACK for Initial - largest_acked=2
client  | 5.605 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=2504
client  | 5.605 DEBUG Acked crypto frame space=in offset=1204 length=335
client  | 5.605 DEBUG Acked crypto frame space=in offset=1204 length=335
client  | 5.605 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 542, tv_nsec: 427936125 }
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.605 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=4 type=Initial pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 42
client  |   TX -> Ack { largest_acknowledged: 4, ack_delay: 18, first_ack_range: 1, ack_ranges: [AckRange { gap: 1, range: 0 }], ecn_count: None }
client  | 5.605 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pad Initial from 42 to PLPMTU 1252
client  | 5.605 DEBUG ECN probing: sent 5 probes
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
sim     | Forwarding 744 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 9/29 (31.0%)
client  | 5.605 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.605 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.605 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 73.577426ms
client  | 5.605 DEBUG Setting timeout of 73.577426ms
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.605 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.605 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.605 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 73.544915ms
client  | 5.605 DEBUG Setting timeout of 73.544915ms
client  | 5.605 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=1 type=Handshake pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 744
client  |   -> RX Crypto { offset: 0, len: 706 }
client  | 5.605 DEBUG Read Ok(706) bytes
client  | 5.606 DEBUG [Agent 0x55a26ae70040] state -> AuthenticationPending
client  | 5.606 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 542, tv_nsec: 428622429 }
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.606 DEBUG Building Initial dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.606 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=0 type=Handshake pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 40
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 83, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Drop packet number space in
client  | 5.606 DEBUG [LossRecovery] Reset loss recovery state for Initial
client  | 5.606 DEBUG ECN probing: sent 6 probes
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.606 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.606 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 233.268087ms
client  | 5.606 DEBUG Setting timeout of 233.268087ms
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Authenticated Ok
client  | 5.606 DEBUG Write secret available for ApplicationData: SymKey [32]: 0bcf8b682dfd98592b90f6af5e68eb000ed52f3106e599fecb09069e991bcc17
client  | 5.606 DEBUG Read secret available for ApplicationData: SymKey [32]: bd503ca8a49ce4ee262abe7e225066087ed8dab712cd3d7918da07d06169ab47
client  | 5.606 DEBUG [Agent 0x55a26ae70040] SSL_AuthCertificateComplete: Ok(())
client  | 5.606 DEBUG [Agent 0x55a26ae70040] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] TLS connection complete
client  | 5.606 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 5.606 DEBUG [Crypto] Application write key installed
client  | 5.606 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
client  | 5.606 DEBUG [Crypto] application read keys installed
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] State change from Handshaking -> Connected
client  | 5.606 INFO [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Connection established
client  | 5.606 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] process saved for epoch ApplicationData
client  | 5.606 WARN Crypto operation failed NssError { name: "SEC_ERROR_BAD_DATA", code: -8190, desc: "security library: received bad data." }
client  | 5.606 WARN [Client ...] Dropped received packet: Decryption failure; Total: 1
client  | 5.606 DEBUG stream BiDi creatable
client  | 5.606 INFO Created stream 0 for https://server4/oxbpbjzlav
client  | 5.606 INFO Saving https://server4/oxbpbjzlav to "/downloads/oxbpbjzlav"
client  | 5.607 DEBUG StateChange(Connected)
client  | 5.607 DEBUG stream 0 writable
client  | 5.607 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.607 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.607 DEBUG CRYPTO for hs offset=0, len=36
client  | 5.607 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=1 type=Handshake pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 73
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 5.607 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=73
client  | 5.607 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.607 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=0 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 119
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 5.607 DEBUG packet_sent this=0x55a26ae92be0, pn=0, ps=46
client  | 5.607 DEBUG ECN probing: sent 7 probes
client  | 5.607 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.607 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.607 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.607 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.607 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 233.276854ms
client  | 5.607 DEBUG Setting timeout of 233.276854ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/38 (18.4%)
sim     | Forwarding 40 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 7/39 (17.9%)
sim     | Dropping 119 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 8/40 (20.0%)
sim     | Dropping 744 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 10/30 (33.3%)
sim     | Forwarding 744 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 10/31 (32.3%)
client  | 5.708 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=3 type=Handshake pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 744
client  |   -> RX Crypto { offset: 0, len: 706 }
client  | 5.708 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 542, tv_nsec: 531283521 }
client  | 5.708 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.708 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.708 DEBUG CRYPTO for hs offset=0, len=36
client  | 5.708 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=2 type=Handshake pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 80
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 9, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 5.708 DEBUG packet_sent this=0x55a26ae92be0, pn=2, ps=80
client  | 5.708 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.708 DEBUG ECN probing: sent 8 probes
client  | 5.708 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.708 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.708 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.708 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.708 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 233.218635ms
client  | 5.708 DEBUG Setting timeout of 233.218635ms
client  | 5.708 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.708 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.708 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.708 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.708 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 233.187046ms
client  | 5.708 DEBUG Setting timeout of 233.187046ms
sim     | Forwarding 80 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 8/41 (19.5%)
sim     | Dropping 749 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 11/32 (34.4%)
sim     | Dropping 21 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 12/33 (36.4%)
sim     | Forwarding 203 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 12/34 (35.3%)
client  | 5.741 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=1 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 203
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [40, 99, 110, 13, 82, 94, 234, 110], stateless_reset_token: [156, 145, 222, 11, 194, 230, 226, 99, 128, 15, 190, 83, 225, 46, 174, 29] }
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [193, 56, 154, 184, 152, 64, 240, 160], stateless_reset_token: [135, 86, 226, 247, 187, 141, 135, 77, 85, 103, 85, 73, 13, 87, 4, 222] }
client  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [13, 98, 101, 118, 107, 137, 253, 181], stateless_reset_token: [200, 241, 151, 171, 250, 92, 34, 16, 220, 38, 10, 110, 110, 233, 198, 183] }
client  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [139, 180, 240, 32, 66, 16, 71, 108], stateless_reset_token: [233, 220, 120, 163, 99, 189, 188, 103, 107, 244, 39, 23, 16, 109, 230, 8] }
client  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [121, 97, 234, 188, 120, 79, 117, 164], stateless_reset_token: [168, 59, 33, 35, 81, 27, 108, 242, 237, 121, 240, 3, 199, 83, 155, 178] }
client  |   -> RX Crypto { offset: 0, len: 42 }
client  | 5.741 DEBUG Read Ok(42) bytes
client  | 5.741 DEBUG [0x55a26ae70040] Got resumption token [711]: 02000633fcb55f39..8756e636ddd7662a
client  | 5.741 DEBUG [Agent 0x55a26ae70040] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
client  | 5.741 DEBUG setting max_stream_data to 1000000
client  | 5.741 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 542, tv_nsec: 564543245 }
client  | 5.741 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.741 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.741 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.741 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=1 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 31
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 28, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 5.742 DEBUG ECN probing: sent 9 probes
client  | 5.742 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.742 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.742 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.742 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.742 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 199.85141ms
client  | 5.742 DEBUG Setting timeout of 199.85141ms
client  | 5.742 DEBUG stream BiDi creatable
client  | 5.742 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.742 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.742 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.742 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.742 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 199.818799ms
client  | 5.742 DEBUG Setting timeout of 199.818799ms
sim     | Forwarding 31 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 8/42 (19.0%)
sim     | Dropping 24 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 13/35 (37.1%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 13/36 (36.1%)
client  | 5.839 WARN Crypto operation failed NssError { name: "SEC_ERROR_BAD_DATA", code: -8190, desc: "security library: received bad data." }
client  | 5.839 WARN [Client ...] Dropped received packet: Decryption failure; Total: 2
client  | 5.839 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.839 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.839 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.839 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.839 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 102.501527ms
client  | 5.839 DEBUG Setting timeout of 102.501527ms
client  | 5.839 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.839 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.839 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.839 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.839 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 102.453047ms
client  | 5.839 DEBUG Setting timeout of 102.453047ms
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 13/37 (35.1%)
client  | 5.840 WARN Crypto operation failed NssError { name: "SEC_ERROR_BAD_DATA", code: -8190, desc: "security library: received bad data." }
client  | 5.840 WARN [Client ...] Dropped received packet: Decryption failure; Total: 3
client  | 5.840 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.840 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.840 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.840 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.840 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 101.583711ms
client  | 5.840 DEBUG Setting timeout of 101.583711ms
client  | 5.840 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.840 DEBUG Building Handshake dcid Some(CID [8]: 5a9f105e0887c18b) scid Some(CID [0]: )
client  | 5.840 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.840 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.840 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 101.557291ms
client  | 5.840 DEBUG Setting timeout of 101.557291ms
sim     | Forwarding 21 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 13/38 (34.2%)
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=3 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 21
client  |   -> RX HandshakeDone
client  |   -> RX Padding { len: 2 }
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] State change from Connected -> Confirmed
client  | 5.855 DEBUG PMTUD started with probe size 1380
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Drop packet number space hs
client  | 5.855 DEBUG [LossRecovery] Reset loss recovery state for Handshake
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] No preferred address to migrate to
client  | 5.855 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 542, tv_nsec: 678117352 }
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.855 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.855 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=2 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1352
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 11, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> Ping
client  |   TX -> Padding { len: 1318 }
client  | 5.855 DEBUG packet_sent this=0x55a26ae92be0, pn=2, ps=1352
client  | 5.855 DEBUG ECN probing: sent 10 probes
client  | 5.855 DEBUG ECN probing concluded with 10 probes sent
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.855 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.855 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 258.231419ms
client  | 5.855 DEBUG Setting timeout of 258.231419ms
client  | 5.855 WARN Unhandled event StateChange(Confirmed)
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.855 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.855 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.855 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 258.0878ms
client  | 5.855 DEBUG Setting timeout of 258.0878ms
sim     | Forwarding 1352 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 8/43 (18.6%)
sim     | Dropping 24 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 14/39 (35.9%)
sim     | Forwarding 24 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 14/40 (35.0%)
client  | 5.907 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=5 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 24
client  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 2375, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 5.907 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Rx ACK space=ap, ranges=[1..=2]
client  | 5.907 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
client  | 5.907 DEBUG packet_lost this=0x55a26ae92be0, pn=0, ps=46
client  | 5.907 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1352/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764
client  | 5.907 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1352/8764 ssthresh 8764] state -> RecoveryStart
client  | 5.907 DEBUG on_packets_lost this=0x55a26ae92be0, bytes_in_flight=1352, cwnd=8764, state=RecoveryStart
client  | 5.907 WARN ECN validation failed, no ECN counts in ACK frame
client  | 5.907 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=0, cwnd=8764, state=RecoveryStart, new_acked=0
client  | 5.907 DEBUG PMTUD probe of size 1380 succeeded
client  | 5.907 DEBUG PMTUD started with probe size 1420
client  | 5.907 DEBUG PLPMTU changed from 1252 to 1352, updating pacer
client  | 5.907 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Lost: Stream(Stream(SendStreamRecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 5.907 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 5.907 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.907 DEBUG Sending PMTUD probe of size 1420, count 1
client  | 5.907 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=3 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1392
client  |   TX -> Ping
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  |   TX -> Padding { len: 1345 }
client  | 5.907 DEBUG packet_sent this=0x55a26ae92be0, pn=3, ps=1392
client  | 5.908 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 5.908 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.908 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 5.908 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 215.077207ms
client  | 5.908 DEBUG Setting timeout of 215.077207ms
client  | 5.908 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 5.908 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.908 DEBUG TX blocked, profile=SendProfile { limit: 1352, pto: None, probe: EnumSet(), paced: false }
client  | 5.908 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 215.03582ms
client  | 5.908 DEBUG Setting timeout of 215.03582ms
sim     | Forwarding 1392 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 8/44 (18.2%)
sim     | Forwarding 1055 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 14/41 (34.1%)
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=6 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1055
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None }
client  |   -> RX MaxStreams { stream_type: BiDi, maximum_streams: 101 }
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] Rx ACK space=ap, ranges=[1..=3]
client  | 5.942 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
client  | 5.942 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/8764 ssthresh 8764] state -> CongestionAvoidance
client  | 5.942 DEBUG on_packets_acked this=0x55a26ae92be0, limited=1, bytes_in_flight=0, cwnd=8764, state=CongestionAvoidance, new_acked=1392
client  | 5.942 DEBUG PMTUD probe of size 1420 succeeded
client  | 5.942 DEBUG PMTUD started with probe size 1472
client  | 5.942 DEBUG PLPMTU changed from 1352 to 1392, updating pacer
client  | 5.942 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 542, tv_nsec: 785266764 }
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 5.942 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.942 DEBUG Sending PMTUD probe of size 1472, count 1
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=4 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1444
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 15, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> Ping
client  |   TX -> Padding { len: 1412 }
client  | 5.942 DEBUG packet_sent this=0x55a26ae92be0, pn=4, ps=1444
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 5.942 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.942 DEBUG TX blocked, profile=SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 198.058898ms
client  | 5.942 DEBUG Setting timeout of 198.058898ms
client  | 5.942 DEBUG stream 0 complete
client  | 5.942 DEBUG stream BiDi creatable
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 5.942 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.942 DEBUG TX blocked, profile=SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] delay duration 197.981424ms
client  | 5.942 DEBUG Setting timeout of 197.981424ms
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 543, tv_nsec: 360120475 } }
client  | 5.942 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 543, tv_nsec: 360120475 } })
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 5.942 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.942 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 542, tv_nsec: 765614034 }
client  | 5.942 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=5 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 42
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 43, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 5.942 DEBUG Setting timeout of 594.43609ms
sim     | Forwarding 1444 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 8/45 (17.8%)
sim     | Dropping 42 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 9/46 (19.6%)
sim     | Dropping 21 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/42 (35.7%)
sim     | Forwarding 24 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/43 (34.9%)
client  | 5.995 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 5.995 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 5.995 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 542, tv_nsec: 817992389 }
client  | 5.995 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=6 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 6590, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 5.995 DEBUG Setting timeout of 542.010957ms
client  | 5.995 DEBUG Setting timeout of 541.995088ms
sim     | Dropping 43 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 10/47 (21.3%)
sim     | Forwarding 21 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/44 (34.1%)
client  | 6.044 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 6.044 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 6.044 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 542, tv_nsec: 867172821 }
client  | 6.044 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=7 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 12738, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 6.044 DEBUG Setting timeout of 492.824053ms
client  | 6.044 DEBUG Setting timeout of 492.810197ms
sim     | Dropping 43 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 11/48 (22.9%)
sim     | Forwarding 21 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/45 (33.3%)
client  | 6.182 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 6.182 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 6.182 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 543, tv_nsec: 5064364 }
client  | 6.182 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=8 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 29974, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 6.182 DEBUG Setting timeout of 354.93815ms
client  | 6.182 DEBUG Setting timeout of 354.924885ms
sim     | Forwarding 21 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/46 (32.6%)
client  | 6.183 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 6.183 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 6.183 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 543, tv_nsec: 5872242 }
client  | 6.183 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=9 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 30075, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 6.183 DEBUG Setting timeout of 354.17703ms
client  | 6.183 DEBUG Setting timeout of 354.16617ms
sim     | Forwarding 45 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 11/49 (22.4%)
sim     | Forwarding 45 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 11/50 (22.0%)
sim     | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/47 (31.9%)
client  | 6.214 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] output_path send_profile SendProfile { limit: 1392, pto: None, probe: EnumSet(), paced: false }
client  | 6.214 DEBUG Building Short dcid Some(CID [8]: 5a9f105e0887c18b)
client  | 6.214 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 543, tv_nsec: 37374426 }
client  | 6.214 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] pn=10 type=Short pri-path:5a9f105e0887c18b 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 34013, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 6.214 DEBUG Setting timeout of 322.637015ms
client  | 6.214 DEBUG Setting timeout of 322.628369ms
sim     | Forwarding 45 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 11/51 (21.6%)
server  | QLog: done with ba2fabca86c98f1c83c69ef3df3b52fa4a.qlog
client  | 6.538 DEBUG [Client ba2fabca86c98f1c83c69ef3df3b52fa4a] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 543, tv_nsec: 360120475 } } -> Closed(Application(0))
client  | 6.538 INFO Closing timer expired
client  | 6.538 DEBUG Output::None
client  | 6.538 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 6.538 DEBUG Timer fired while closed
client  | 6.538 DEBUG Output::None
client  | 6.538 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=9d465dcdb539da635e1e36266c2b458cd0
client  | 6.538 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
client  | 6.538 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
client  | 6.538 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500
client  | 6.538 DEBUG [unv-path 0.0.0.0:48305->193.167.100.100:443] Make permanent
client  | 6.538 DEBUG [unv-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443] set as primary path
client  | 6.538 DEBUG [pri-unv-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443] Path validated Instant { tv_sec: 543, tv_nsec: 361191194 }
client  | 6.538 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] client_start
client  | 6.539 DEBUG Writing transport parameters, msg=1
client  | 6.539 DEBUG [Agent 0x55a26ae8bb60] state -> InProgress
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] State change from Init -> WaitInitial
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.539 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.539 DEBUG CRYPTO for in offset=0, len=1204
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=0 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 6.539 DEBUG packet_sent this=0x55a26ae92be0, pn=0, ps=1252
client  | 6.539 DEBUG ECN probing: sent 1 probes
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.539 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.539 DEBUG CRYPTO for in offset=1204, len=335
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=1 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pad Initial from 384 to PLPMTU 1252
client  | 6.539 DEBUG packet_sent this=0x55a26ae92be0, pn=1, ps=1252
client  | 6.539 DEBUG ECN probing: sent 2 probes
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.539 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.539 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 4.040633ms
client  | 6.539 DEBUG Setting timeout of 4.040633ms
client  | 6.539 DEBUG StateChange(WaitInitial)
client  | 6.539 WARN Cannot create stream ConnectionState
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.539 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.539 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.539 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 4.014544ms
client  | 6.539 DEBUG Setting timeout of 4.014544ms
client  | 6.544 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.544 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.544 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.544 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 294.78372ms
client  | 6.544 DEBUG Setting timeout of 294.78372ms
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 12/52 (23.1%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 12/53 (22.6%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/48 (31.2%)
client  | 6.728 DEBUG saving datagram of 204 bytes
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.728 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.728 DEBUG CRYPTO for in offset=0, len=1204
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=2 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 6.728 DEBUG packet_sent this=0x55a26ae92be0, pn=2, ps=1252
client  | 6.728 DEBUG ECN probing: sent 3 probes
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.728 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.728 DEBUG CRYPTO for in offset=1204, len=335
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=3 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pad Initial from 384 to PLPMTU 1252
client  | 6.728 DEBUG packet_sent this=0x55a26ae92be0, pn=3, ps=1252
client  | 6.728 DEBUG ECN probing: sent 4 probes
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.728 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.728 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 299.919219ms
client  | 6.728 DEBUG Setting timeout of 299.919219ms
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.728 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.728 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 299.890005ms
client  | 6.728 DEBUG Setting timeout of 299.890005ms
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/49 (30.6%)
client  | 6.728 DEBUG saving datagram of 204 bytes
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.728 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.728 DEBUG CRYPTO for in offset=0, len=1204
client  | 6.728 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=4 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 6.729 DEBUG packet_sent this=0x55a26ae92be0, pn=4, ps=1252
client  | 6.729 DEBUG ECN probing: sent 5 probes
client  | 6.729 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.729 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.729 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.729 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 4.24217ms
client  | 6.729 DEBUG Setting timeout of 4.24217ms
client  | 6.729 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.729 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.729 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.729 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 4.22101ms
client  | 6.729 DEBUG Setting timeout of 4.22101ms
client  | 6.734 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.734 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.734 DEBUG CRYPTO for in offset=1204, len=335
client  | 6.734 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=5 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 6.734 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pad Initial from 384 to PLPMTU 1252
client  | 6.734 DEBUG packet_sent this=0x55a26ae92be0, pn=5, ps=1252
client  | 6.734 DEBUG ECN probing: sent 6 probes
client  | 6.734 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.734 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.734 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 6.734 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 4.06982ms
client  | 6.734 DEBUG Setting timeout of 4.06982ms
client  | 6.739 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.739 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 6.739 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 6.739 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 294.816256ms
client  | 6.739 DEBUG Setting timeout of 294.816256ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 12/54 (22.2%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 12/55 (21.8%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 12/56 (21.4%)
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 13/57 (22.8%)
client  | 7.035 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 7.035 INFO Lost crypto frame space=in offset=0 length=1204
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: EcnEct0
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1204, length: 335 })
client  | 7.035 INFO Lost crypto frame space=in offset=1204 length=335
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: EcnEct0
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 7.035 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 7.035 DEBUG CRYPTO for in offset=0, len=1204
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=6 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 7.035 DEBUG packet_sent this=0x55a26ae92be0, pn=6, ps=1252
client  | 7.035 DEBUG ECN probing: sent 7 probes
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 7.035 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 7.035 DEBUG CRYPTO for in offset=1204, len=335
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=7 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, Ect0) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 7.035 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pad Initial from 384 to PLPMTU 1252
client  | 7.035 DEBUG packet_sent this=0x55a26ae92be0, pn=7, ps=1252
client  | 7.036 DEBUG ECN probing: sent 8 probes
client  | 7.036 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 7.036 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 7.036 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 7.036 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 599.919619ms
client  | 7.036 DEBUG Setting timeout of 599.919619ms
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 13/58 (22.4%)
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 13/59 (22.0%)
client  | 7.637 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 7.637 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1204, length: 335 })
client  | 7.637 INFO Lost crypto frame space=in offset=1204 length=335
client  | 7.637 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: EcnEct0
client  | 7.637 DEBUG ECN validation failed, all 3 initial marked packets were lost
client  | 7.637 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 7.637 INFO Lost crypto frame space=in offset=0 length=1204
client  | 7.637 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: EcnEct0
client  | 7.637 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 7.637 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 7.637 DEBUG CRYPTO for in offset=0, len=1204
client  | 7.637 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=8 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 7.637 DEBUG packet_sent this=0x55a26ae92be0, pn=8, ps=1252
client  | 7.638 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 7.638 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 7.638 DEBUG CRYPTO for in offset=1204, len=335
client  | 7.638 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=9 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 384
client  |   TX -> Crypto { offset: 1204, len: 335 }
client  | 7.638 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pad Initial from 384 to PLPMTU 1252
client  | 7.638 DEBUG packet_sent this=0x55a26ae92be0, pn=9, ps=1252
client  | 7.638 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 7.638 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 7.638 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 7.638 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 1.199915953s
client  | 7.638 DEBUG Setting timeout of 1.199915953s
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 13/60 (21.7%)
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 14/61 (23.0%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/50 (30.0%)
client  | 8.504 DEBUG saving datagram of 204 bytes
client  | 8.504 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.504 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 8.504 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.504 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 333.207108ms
client  | 8.504 DEBUG Setting timeout of 333.207108ms
client  | 8.504 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.504 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 8.504 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.504 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 333.163447ms
client  | 8.504 DEBUG Setting timeout of 333.163447ms
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 15/51 (29.4%)
client  | 8.505 DEBUG unmark 0-length range at 0
client  | 8.505 DEBUG saving datagram of 204 bytes
client  | 8.505 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.505 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 8.505 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.505 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 332.454312ms
client  | 8.505 DEBUG Setting timeout of 332.454312ms
client  | 8.505 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.505 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 8.505 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.505 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 332.433223ms
client  | 8.505 DEBUG Setting timeout of 332.433223ms
client  | 8.839 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 8.839 INFO Lost crypto frame space=in offset=0 length=1204
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: EcnEct0
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1204, length: 335 })
client  | 8.839 INFO Lost crypto frame space=in offset=1204 length=335
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: EcnEct0
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 8.839 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 8.839 DEBUG CRYPTO for in offset=0, len=1204
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=10 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 8.839 DEBUG packet_sent this=0x55a26ae92be0, pn=10, ps=1252
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.839 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 8.839 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 8.839 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 2.399783916s
client  | 8.839 DEBUG Setting timeout of 2.399783916s
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 14/62 (22.6%)
client  | 11.240 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 11.240 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1204, length: 335 })
client  | 11.240 INFO Lost crypto frame space=in offset=1204 length=335
client  | 11.240 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 11.240 INFO Lost crypto frame space=in offset=0 length=1204
client  | 11.240 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 11.240 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 11.240 DEBUG CRYPTO for in offset=0, len=1204
client  | 11.240 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=11 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 11.240 DEBUG packet_sent this=0x55a26ae92be0, pn=11, ps=1252
client  | 11.240 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 11.240 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 11.240 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 11.240 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 4.799784196s
client  | 11.240 DEBUG Setting timeout of 4.799784196s
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 14/63 (22.2%)
sim     | Dropping 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 16/52 (30.8%)
sim     | Dropping 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 17/53 (32.1%)
client  | 16.041 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 16.041 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 16.041 INFO Lost crypto frame space=in offset=0 length=1204
client  | 16.041 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 16.041 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 16.041 DEBUG CRYPTO for in offset=0, len=1204
client  | 16.041 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=12 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 16.041 DEBUG packet_sent this=0x55a26ae92be0, pn=12, ps=1252
client  | 16.041 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 16.041 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 16.041 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 16.041 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 9.599789747s
client  | 16.041 DEBUG Setting timeout of 9.599789747s
sim     | Forwarding 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 14/64 (21.9%)
sim     | Dropping 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 18/54 (33.3%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 18/55 (32.7%)
client  | 19.163 INFO not saving datagram of 204 bytes
client  | 19.163 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 19.163 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 19.163 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 19.163 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 6.478199165s
client  | 19.163 DEBUG Setting timeout of 6.478199165s
client  | 19.163 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 19.163 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 19.163 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 19.163 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 6.478136198s
client  | 19.163 DEBUG Setting timeout of 6.478136198s
client  | 25.642 DEBUG [LossRecovery] PTO timer fired for Initial
client  | 25.642 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 25.642 INFO Lost crypto frame space=in offset=0 length=1204
client  | 25.642 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 25.642 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 25.642 DEBUG CRYPTO for in offset=0, len=1204
client  | 25.642 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] pn=13 type=Initial pri-path:9d465dcdb539da635e1e36266c2b458cd0 0.0.0.0:48305->193.167.100.100:443 IpTos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 25.642 DEBUG packet_sent this=0x55a26ae92be0, pn=13, ps=1252
client  | 25.642 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 25.642 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 25.643 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 25.643 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 10.895806667s
client  | 25.643 DEBUG Setting timeout of 10.895806667s
sim     | Dropping 1252 bytes 193.167.0.100:48305 -> 193.167.100.100:443, dropped 15/65 (23.1%)
sim     | Forwarding 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 18/56 (32.1%)
client  | 33.371 INFO not saving datagram of 204 bytes
client  | 33.371 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 33.371 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 33.371 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 33.371 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 3.1676475s
client  | 33.371 DEBUG Setting timeout of 3.1676475s
client  | 33.371 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 33.371 DEBUG Building Initial dcid Some(CID [17]: 9d465dcdb539da635e1e36266c2b458cd0) scid Some(CID [0]: )
client  | 33.371 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
client  | 33.371 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] delay duration 3.167609619s
client  | 33.371 DEBUG Setting timeout of 3.167609619s
sim     | Dropping 204 bytes 193.167.100.100:443 -> 193.167.0.100:48305, dropped 19/57 (33.3%)
server  | QLog: done with b90b55d9cf1e1aad46e978cf80.qlog
client  | 36.540 INFO [Client 9d465dcdb539da635e1e36266c2b458cd0] idle timeout expired
client  | 36.540 DEBUG [Client 9d465dcdb539da635e1e36266c2b458cd0] State change from WaitInitial -> Closed(Transport(IdleTimeout))
client  | 36.540 DEBUG Output::None
client  | 36.540 WARN Unhandled event StateChange(Closed(Transport(IdleTimeout)))
client  | 36.540 DEBUG Timer fired while closed
client  | 36.540 DEBUG Output::None
client  | Error: TransportError(IdleTimeout)

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