2025-09-12 04:09:36,639 Generated random file: rkylqawqgb of size: 1024
2025-09-12 04:09:36,639 Generated random file: aktggegoep of size: 1024
2025-09-12 04:09:36,639 Generated random file: qrrgvswgil of size: 1024
2025-09-12 04:09:36,639 Generated random file: imerqxmwwn of size: 1024
2025-09-12 04:09:36,639 Generated random file: jumyvvqotq of size: 1024
2025-09-12 04:09:36,640 Generated random file: csankqcplv of size: 1024
2025-09-12 04:09:36,640 Generated random file: opbynvwarg of size: 1024
2025-09-12 04:09:36,640 Generated random file: kvityvjhuq of size: 1024
2025-09-12 04:09:36,640 Generated random file: woweoxrwkx of size: 1024
2025-09-12 04:09:36,640 Generated random file: vdyjeofgzt of size: 1024
2025-09-12 04:09:36,640 Generated random file: nuwoothryd of size: 1024
2025-09-12 04:09:36,640 Generated random file: vxaglynjxa of size: 1024
2025-09-12 04:09:36,640 Generated random file: ynclrqpfxs of size: 1024
2025-09-12 04:09:36,641 Generated random file: gvsajsrxio of size: 1024
2025-09-12 04:09:36,641 Generated random file: mxitzmhigr of size: 1024
2025-09-12 04:09:36,641 Generated random file: uqmiyvvvdc of size: 1024
2025-09-12 04:09:36,641 Generated random file: ihdwhiujeo of size: 1024
2025-09-12 04:09:36,641 Generated random file: wgpwblrbxx of size: 1024
2025-09-12 04:09:36,641 Generated random file: ywwvdormwl of size: 1024
2025-09-12 04:09:36,641 Generated random file: kccewfaozc of size: 1024
2025-09-12 04:09:36,641 Generated random file: ryrwxlyqcq of size: 1024
2025-09-12 04:09:36,641 Generated random file: rxkgbohzzb of size: 1024
2025-09-12 04:09:36,642 Generated random file: ucszxrzdmt of size: 1024
2025-09-12 04:09:36,642 Generated random file: tehpssmluc of size: 1024
2025-09-12 04:09:36,642 Generated random file: lmahkwvtel of size: 1024
2025-09-12 04:09:36,642 Generated random file: xrubgvbbhw of size: 1024
2025-09-12 04:09:36,642 Generated random file: mimfbthkqk of size: 1024
2025-09-12 04:09:36,642 Generated random file: cdmmbdgqtt of size: 1024
2025-09-12 04:09:36,642 Generated random file: ttnzuwcnns of size: 1024
2025-09-12 04:09:36,642 Generated random file: vjsbdjjqly of size: 1024
2025-09-12 04:09:36,643 Generated random file: nmzokkzvmd of size: 1024
2025-09-12 04:09:36,643 Generated random file: tgfrzspyrr of size: 1024
2025-09-12 04:09:36,643 Generated random file: jpqinlqkmm of size: 1024
2025-09-12 04:09:36,643 Generated random file: llzsbrfjia of size: 1024
2025-09-12 04:09:36,643 Generated random file: brcqoxbnxq of size: 1024
2025-09-12 04:09:36,643 Generated random file: utfxafbwle of size: 1024
2025-09-12 04:09:36,643 Generated random file: xfowphuhkh of size: 1024
2025-09-12 04:09:36,643 Generated random file: njpctwnfvl of size: 1024
2025-09-12 04:09:36,643 Generated random file: iakdrzvbqc of size: 1024
2025-09-12 04:09:36,644 Generated random file: admtakuwug of size: 1024
2025-09-12 04:09:36,644 Generated random file: xdtsnprkru of size: 1024
2025-09-12 04:09:36,644 Generated random file: sybmvcaygo of size: 1024
2025-09-12 04:09:36,644 Generated random file: kgrmdtkccq of size: 1024
2025-09-12 04:09:36,644 Generated random file: spaaaungbc of size: 1024
2025-09-12 04:09:36,644 Generated random file: uiirtnxfze of size: 1024
2025-09-12 04:09:36,644 Generated random file: skqwwaslxp of size: 1024
2025-09-12 04:09:36,644 Generated random file: kahjbeoanu of size: 1024
2025-09-12 04:09:36,645 Generated random file: mwjlgcdhxf of size: 1024
2025-09-12 04:09:36,645 Generated random file: hswynulfpj of size: 1024
2025-09-12 04:09:36,645 Generated random file: ibwoapqxia of size: 1024
2025-09-12 04:09:36,645 Requests: https://server4:443/rkylqawqgb https://server4:443/aktggegoep https://server4:443/qrrgvswgil https://server4:443/imerqxmwwn https://server4:443/jumyvvqotq https://server4:443/csankqcplv https://server4:443/opbynvwarg https://server4:443/kvityvjhuq https://server4:443/woweoxrwkx https://server4:443/vdyjeofgzt https://server4:443/nuwoothryd https://server4:443/vxaglynjxa https://server4:443/ynclrqpfxs https://server4:443/gvsajsrxio https://server4:443/mxitzmhigr https://server4:443/uqmiyvvvdc https://server4:443/ihdwhiujeo https://server4:443/wgpwblrbxx https://server4:443/ywwvdormwl https://server4:443/kccewfaozc https://server4:443/ryrwxlyqcq https://server4:443/rxkgbohzzb https://server4:443/ucszxrzdmt https://server4:443/tehpssmluc https://server4:443/lmahkwvtel https://server4:443/xrubgvbbhw https://server4:443/mimfbthkqk https://server4:443/cdmmbdgqtt https://server4:443/ttnzuwcnns https://server4:443/vjsbdjjqly https://server4:443/nmzokkzvmd https://server4:443/tgfrzspyrr https://server4:443/jpqinlqkmm https://server4:443/llzsbrfjia https://server4:443/brcqoxbnxq https://server4:443/utfxafbwle https://server4:443/xfowphuhkh https://server4:443/njpctwnfvl https://server4:443/iakdrzvbqc https://server4:443/admtakuwug https://server4:443/xdtsnprkru https://server4:443/sybmvcaygo https://server4:443/kgrmdtkccq https://server4:443/spaaaungbc https://server4:443/uiirtnxfze https://server4:443/skqwwaslxp https://server4:443/kahjbeoanu https://server4:443/mwjlgcdhxf https://server4:443/hswynulfpj https://server4:443/ibwoapqxia
2025-09-12 04:09:36,704 
2025-09-12 04:09:36,704 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_o_tvjsxt/ TESTCASE_SERVER=multiconnect TESTCASE_CLIENT=multiconnect WWW=/tmp/www_c6i8i5yr/ DOWNLOADS=/tmp/download_ghvmir_v/ SERVER_LOGS=/tmp/logs_server_iavzq2wx CLIENT_LOGS=/tmp/logs_client_5lx0zwpr 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=ghcr.io/alibaba/xquic/xquic-interop:latest REQUESTS="https://server4:443/rkylqawqgb https://server4:443/aktggegoep https://server4:443/qrrgvswgil https://server4:443/imerqxmwwn https://server4:443/jumyvvqotq https://server4:443/csankqcplv https://server4:443/opbynvwarg https://server4:443/kvityvjhuq https://server4:443/woweoxrwkx https://server4:443/vdyjeofgzt https://server4:443/nuwoothryd https://server4:443/vxaglynjxa https://server4:443/ynclrqpfxs https://server4:443/gvsajsrxio https://server4:443/mxitzmhigr https://server4:443/uqmiyvvvdc https://server4:443/ihdwhiujeo https://server4:443/wgpwblrbxx https://server4:443/ywwvdormwl https://server4:443/kccewfaozc https://server4:443/ryrwxlyqcq https://server4:443/rxkgbohzzb https://server4:443/ucszxrzdmt https://server4:443/tehpssmluc https://server4:443/lmahkwvtel https://server4:443/xrubgvbbhw https://server4:443/mimfbthkqk https://server4:443/cdmmbdgqtt https://server4:443/ttnzuwcnns https://server4:443/vjsbdjjqly https://server4:443/nmzokkzvmd https://server4:443/tgfrzspyrr https://server4:443/jpqinlqkmm https://server4:443/llzsbrfjia https://server4:443/brcqoxbnxq https://server4:443/utfxafbwle https://server4:443/xfowphuhkh https://server4:443/njpctwnfvl https://server4:443/iakdrzvbqc https://server4:443/admtakuwug https://server4:443/xdtsnprkru https://server4:443/sybmvcaygo https://server4:443/kgrmdtkccq https://server4:443/spaaaungbc https://server4:443/uiirtnxfze https://server4:443/skqwwaslxp https://server4:443/kahjbeoanu https://server4:443/mwjlgcdhxf https://server4:443/hswynulfpj https://server4:443/ibwoapqxia"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-09-12 04:09:53,915  Container sim  Recreate
 Container sim  Recreated
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | Setting up routes...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
server  | Endpoint's IPv4 address is 193.167.100.100
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
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | ./demo_server -l d -L /logs/server.log -p 443 -D /www -k /logs/keys.log -i -M
server  | option log level :d
server  | option log directory :/logs/server.log
server  | option port :443
server  | option read dir :/www
server  | option key output file: /logs/keys.log
server  | set interop mode
server  | option multipath enabled
server  | /logs/keys.log 4
server  | create ipv4 socket fd: 9
server  | create ipv6 socket fd: 10
sim     | server:443 is available after 501.779914ms
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 eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" --output-dir /downloads)
client  | + '[' 'https://server4:443/rkylqawqgb https://server4:443/aktggegoep https://server4:443/qrrgvswgil https://server4:443/imerqxmwwn https://server4:443/jumyvvqotq https://server4:443/csankqcplv https://server4:443/opbynvwarg https://server4:443/kvityvjhuq https://server4:443/woweoxrwkx https://server4:443/vdyjeofgzt https://server4:443/nuwoothryd https://server4:443/vxaglynjxa https://server4:443/ynclrqpfxs https://server4:443/gvsajsrxio https://server4:443/mxitzmhigr https://server4:443/uqmiyvvvdc https://server4:443/ihdwhiujeo https://server4:443/wgpwblrbxx https://server4:443/ywwvdormwl https://server4:443/kccewfaozc https://server4:443/ryrwxlyqcq https://server4:443/rxkgbohzzb https://server4:443/ucszxrzdmt https://server4:443/tehpssmluc https://server4:443/lmahkwvtel https://server4:443/xrubgvbbhw https://server4:443/mimfbthkqk https://server4:443/cdmmbdgqtt https://server4:443/ttnzuwcnns https://server4:443/vjsbdjjqly https://server4:443/nmzokkzvmd https://server4:443/tgfrzspyrr https://server4:443/jpqinlqkmm https://server4:443/llzsbrfjia https://server4:443/brcqoxbnxq https://server4:443/utfxafbwle https://server4:443/xfowphuhkh https://server4:443/njpctwnfvl https://server4:443/iakdrzvbqc https://server4:443/admtakuwug https://server4:443/xdtsnprkru https://server4:443/sybmvcaygo https://server4:443/kgrmdtkccq https://server4:443/spaaaungbc https://server4:443/uiirtnxfze https://server4:443/skqwwaslxp https://server4:443/kahjbeoanu https://server4:443/mwjlgcdhxf https://server4:443/hswynulfpj https://server4:443/ibwoapqxia' ']'
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/rkylqawqgb https://server4:443/aktggegoep https://server4:443/qrrgvswgil https://server4:443/imerqxmwwn https://server4:443/jumyvvqotq https://server4:443/csankqcplv https://server4:443/opbynvwarg https://server4:443/kvityvjhuq https://server4:443/woweoxrwkx https://server4:443/vdyjeofgzt https://server4:443/nuwoothryd https://server4:443/vxaglynjxa https://server4:443/ynclrqpfxs https://server4:443/gvsajsrxio https://server4:443/mxitzmhigr https://server4:443/uqmiyvvvdc https://server4:443/ihdwhiujeo https://server4:443/wgpwblrbxx https://server4:443/ywwvdormwl https://server4:443/kccewfaozc https://server4:443/ryrwxlyqcq https://server4:443/rxkgbohzzb https://server4:443/ucszxrzdmt https://server4:443/tehpssmluc https://server4:443/lmahkwvtel https://server4:443/xrubgvbbhw https://server4:443/mimfbthkqk https://server4:443/cdmmbdgqtt https://server4:443/ttnzuwcnns https://server4:443/vjsbdjjqly https://server4:443/nmzokkzvmd https://server4:443/tgfrzspyrr https://server4:443/jpqinlqkmm https://server4:443/llzsbrfjia https://server4:443/brcqoxbnxq https://server4:443/utfxafbwle https://server4:443/xfowphuhkh https://server4:443/njpctwnfvl https://server4:443/iakdrzvbqc https://server4:443/admtakuwug https://server4:443/xdtsnprkru https://server4:443/sybmvcaygo https://server4:443/kgrmdtkccq https://server4:443/spaaaungbc https://server4:443/uiirtnxfze https://server4:443/skqwwaslxp https://server4:443/kahjbeoanu https://server4:443/mwjlgcdhxf https://server4:443/hswynulfpj 'https://server4:443/ibwoapqxia
client  | '
client  | ++ tee -i -a /logs/client.log
client  | 0.000 DEBUG Logging initialized
client  | 0.001 DEBUG Default socket send buffer size is Ok(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:57000 -> 193.167.100.100:443
client  | 0.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=d57e8b899bc3d65d
client  | 0.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=5
client  | 0.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0
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:57000->193.167.100.100:443] Make permanent
client  | 0.001 DEBUG [unv-path:d57e8b899bc3d65d 0.0.0.0:57000->193.167.100.100:443] set as primary path
client  | 0.001 DEBUG [pri-unv-path:d57e8b899bc3d65d 0.0.0.0:57000->193.167.100.100:443] Path validated Instant { tv_sec: 828, tv_nsec: 69987356 }
client  | 0.001 DEBUG [Client d57e8b899bc3d65d] client_start
client  | 0.002 DEBUG Writing transport parameters, msg=1
client  | 0.002 DEBUG [Agent 0x5653c3fa7480] state -> InProgress
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] State change from Init -> WaitInitial
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] pn=5 type=Initial pri-path:d57e8b899bc3d65d 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.002 DEBUG packet_sent this=0x5653c3fbaaa0, pn=5, ps=1252
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) scid Some(CID [0]: )
client  | 0.002 DEBUG CRYPTO for in offset=1213, len=327
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] pn=6 type=Initial pri-path:d57e8b899bc3d65d 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 367
client  |   TX -> Crypto { offset: 1213, len: 327 }
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] pad Initial from 367 to PLPMTU 1252
client  | 0.002 DEBUG packet_sent this=0x5653c3fbaaa0, pn=6, ps=1252
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) 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 d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) 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 d57e8b899bc3d65d] delay duration 3.878547ms
client  | 0.002 DEBUG Setting timeout of 3.878547ms
client  | 0.002 DEBUG StateChange(WaitInitial)
client  | 0.002 WARN Cannot create stream ConnectionState
client  | 0.002 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 0.002 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) 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 d57e8b899bc3d65d] delay duration 3.842701ms
client  | 0.002 DEBUG Setting timeout of 3.842701ms
client  | 0.007 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.007 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) 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 d57e8b899bc3d65d] delay duration 294.330416ms
client  | 0.007 DEBUG Setting timeout of 294.330416ms
sim     | Dropping 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 1/1 (100.0%)
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 1/2 (50.0%)
sim     | Dropping 47 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 1/1 (100.0%)
client  | 0.303 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1213 })
client  | 0.303 INFO Lost crypto frame space=in offset=0 length=1213
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 327 })
client  | 0.303 INFO Lost crypto frame space=in offset=1213 length=327
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) scid Some(CID [0]: )
client  | 0.303 DEBUG CRYPTO for in offset=0, len=1213
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] pn=7 type=Initial pri-path:d57e8b899bc3d65d 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 0.303 DEBUG packet_sent this=0x5653c3fbaaa0, pn=7, ps=1252
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) scid Some(CID [0]: )
client  | 0.303 DEBUG CRYPTO for in offset=1213, len=327
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] pn=8 type=Initial pri-path:d57e8b899bc3d65d 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 367
client  |   TX -> Crypto { offset: 1213, len: 327 }
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] pad Initial from 367 to PLPMTU 1252
client  | 0.303 DEBUG packet_sent this=0x5653c3fbaaa0, pn=8, ps=1252
client  | 0.303 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) 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 d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.303 DEBUG Building Initial dcid Some(CID [8]: d57e8b899bc3d65d) 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 d57e8b899bc3d65d] delay duration 599.824974ms
client  | 0.303 DEBUG Setting timeout of 599.824974ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 1/3 (33.3%)
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 1/4 (25.0%)
sim     | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 1/2 (50.0%)
sim     | Dropping 183 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 2/3 (66.7%)
client  | 0.337 DEBUG [Client d57e8b899bc3d65d] pn=1 type=Initial pri-path:d57e8b899bc3d65d 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1200
client  |   -> RX Crypto { offset: 0, len: 90 }
client  |   -> RX Padding { len: 1064 }
client  | 0.337 DEBUG Write secret available for Handshake: SymKey [32]: f5b5a186f8a5cc39aad282bbbd730638aa8c192c0b35d81d5197eb389ca8edfc
client  | 0.337 DEBUG Read secret available for Handshake: SymKey [32]: 5860c71b3d814f539468f4558e11718f9324c5e25d8b8fa57d3ffe5c69e42291
sim     | Forwarding 517 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 2/4 (50.0%)
sim     | Dropping 126 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 3/5 (60.0%)
sim     | Forwarding 81 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 3/6 (50.0%)
sim     | Forwarding 48 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 3/7 (42.9%)
client  | 0.338 DEBUG [Agent 0x5653c3fa7480] state -> InProgress
client  | 0.338 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.338 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.338 DEBUG [Crypto] Handshake keys installed
client  | 0.338 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 406299634 }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] Changing to use Server CID=42d33fcb023019d4050b0f30
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] State change from WaitInitial -> Handshaking
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Initial dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] pn=9 type=Initial pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 85, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] pad Initial from 45 to PLPMTU 1252
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Initial dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
sim     | Dropping 47 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 4/8 (50.0%)
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Initial dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] delay duration 565.328486ms
client  | 0.338 DEBUG Setting timeout of 565.328486ms
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] pn=1 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 517
client  |   -> RX Crypto { offset: 138, len: 471 }
client  | 0.338 DEBUG unmark 0-length range at 0
client  | 0.338 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 407097783 }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Initial dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] pn=0 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 2, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] Drop packet number space in
client  | 0.338 DEBUG [recovery::Loss] Reset loss recovery state for Initial
client  | 0.338 INFO [pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443] discarding a packet without an RTT estimate; guessing RTT=336.559411ms
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] delay duration 1.009608951s
client  | 0.338 DEBUG Setting timeout of 1.009608951s
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] pn=3 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 81
client  |   -> RX Crypto { offset: 689, len: 36 }
client  | 0.338 DEBUG unmark 0-length range at 0
client  | 0.338 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 407206466 }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] pn=1 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 2, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] delay duration 1.009534944s
client  | 0.338 DEBUG Setting timeout of 1.009534944s
client  | 0.338 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] pn=2 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 0.338 DEBUG packet_sent this=0x5653c3fbaaa0, pn=2, ps=41
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] delay duration 1.009613981s
client  | 0.338 DEBUG Setting timeout of 1.009613981s
client  | 0.338 DEBUG StateChange(Handshaking)
client  | 0.338 WARN Cannot create stream ConnectionState
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.338 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.338 DEBUG [Client d57e8b899bc3d65d] delay duration 1.009589065s
client  | 0.338 DEBUG Setting timeout of 1.009589065s
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 1/5 (20.0%)
sim     | Dropping 43 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/6 (33.3%)
sim     | Forwarding 45 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/7 (28.6%)
sim     | Forwarding 41 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/8 (25.0%)
sim     | Dropping 183 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 5/9 (55.6%)
sim     | Forwarding 46 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 5/10 (50.0%)
client  | 0.370 DEBUG [Client d57e8b899bc3d65d] pn=5 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 46
client  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 11, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 0.370 DEBUG [Client d57e8b899bc3d65d] Rx ACK space=hs, ranges=[1..=2]
client  | 0.370 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=2
client  | 0.370 DEBUG on_packets_acked this=0x5653c3fbaaa0, limited=1, bytes_in_flight=0, cwnd=12520, state=SlowStart, new_acked=41
client  | 0.370 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.370 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.370 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.370 DEBUG [Client d57e8b899bc3d65d] delay duration 303.381454ms
client  | 0.370 DEBUG Setting timeout of 303.381454ms
client  | 0.370 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.370 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.370 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.370 DEBUG [Client d57e8b899bc3d65d] delay duration 303.355205ms
client  | 0.370 DEBUG Setting timeout of 303.355205ms
sim     | Dropping 126 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 6/11 (54.5%)
sim     | Forwarding 183 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 6/12 (50.0%)
sim     | Forwarding 126 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 6/13 (46.2%)
client  | 0.459 DEBUG [Client d57e8b899bc3d65d] pn=7 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 183
client  |   -> RX Crypto { offset: 0, len: 138 }
client  | 0.459 DEBUG [Agent 0x5653c3fa7480] state -> AuthenticationPending
client  | 0.459 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 528448241 }
client  | 0.459 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.459 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.459 DEBUG [Client d57e8b899bc3d65d] pn=3 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 36, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  | 0.459 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.459 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.459 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.459 DEBUG [Client d57e8b899bc3d65d] delay duration 214.142438ms
client  | 0.459 DEBUG Setting timeout of 214.142438ms
client  | 0.460 DEBUG [Client d57e8b899bc3d65d] pn=8 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 126
client  |   -> RX Crypto { offset: 609, len: 80 }
client  | 0.460 DEBUG [Agent 0x5653c3fa7480] state -> AuthenticationPending
client  | 0.460 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 528836504 }
client  | 0.460 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.460 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.460 DEBUG [Client d57e8b899bc3d65d] pn=4 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 46
client  |   TX -> Ack { largest_acknowledged: 8, ack_delay: 90, first_ack_range: 1, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  | 0.460 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.460 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.460 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.460 DEBUG [Client d57e8b899bc3d65d] delay duration 213.333494ms
client  | 0.460 DEBUG Setting timeout of 213.333494ms
client  | 0.460 DEBUG [Client d57e8b899bc3d65d] Authenticated Ok
client  | 0.460 DEBUG Write secret available for ApplicationData: SymKey [32]: 95895076ad819adb1f096f95bed996d54333fb34268762df9a75b42a47a0aa52
client  | 0.460 DEBUG Read secret available for ApplicationData: SymKey [32]: e2139185def62a468d2ad187a46d6af31ee009e54660136f60c2a0ed27e014bb
client  | 0.460 DEBUG [Agent 0x5653c3fa7480] SSL_AuthCertificateComplete: Ok(())
client  | 0.461 DEBUG [Agent 0x5653c3fa7480] 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.461 DEBUG [Client d57e8b899bc3d65d] TLS connection complete
client  | 0.461 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.461 DEBUG [Crypto] Application write key installed
client  | 0.461 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 0.461 DEBUG [Crypto] application read keys installed
client  | 0.461 DEBUG [Client d57e8b899bc3d65d] State change from Handshaking -> Connected
client  | 0.461 INFO [Client d57e8b899bc3d65d] Connection established
client  | 0.461 DEBUG stream BiDi creatable
client  | 0.461 INFO Created stream 0 for https://server4/rkylqawqgb
client  | 0.461 INFO Saving https://server4/rkylqawqgb to "/downloads/rkylqawqgb"
client  | 0.461 DEBUG stream UniDi creatable
client  | 0.461 DEBUG StateChange(Connected)
client  | 0.461 DEBUG stream 0 writable
client  | 0.461 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.461 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.461 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.461 DEBUG [Client d57e8b899bc3d65d] pn=5 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 77
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.461 DEBUG packet_sent this=0x5653c3fbaaa0, pn=5, ps=77
client  | 0.461 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.461 DEBUG [Client d57e8b899bc3d65d] pn=0 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 127
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 0.461 DEBUG packet_sent this=0x5653c3fbaaa0, pn=0, ps=50
client  | 0.461 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.461 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.461 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.461 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.461 DEBUG [Client d57e8b899bc3d65d] delay duration 212.789861ms
client  | 0.461 DEBUG Setting timeout of 212.789861ms
sim     | Forwarding 45 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/9 (22.2%)
sim     | Forwarding 46 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/10 (20.0%)
sim     | Forwarding 127 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/11 (18.2%)
server  | xqc_hq_parse_req|247||hq recv CR LF|
sim     | Forwarding 126 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 6/14 (42.9%)
client  | 0.490 DEBUG [Client d57e8b899bc3d65d] pn=9 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 126
client  |   -> RX Crypto { offset: 609, len: 80 }
client  | 0.490 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 559746453 }
client  | 0.490 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.490 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.490 DEBUG CRYPTO for hs offset=0, len=36
client  | 0.490 DEBUG [Client d57e8b899bc3d65d] pn=6 type=Handshake pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 84
client  |   TX -> Ack { largest_acknowledged: 9, ack_delay: 4, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 0.490 DEBUG packet_sent this=0x5653c3fbaaa0, pn=6, ps=84
client  | 0.490 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.491 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.491 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.491 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.491 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.491 DEBUG [Client d57e8b899bc3d65d] delay duration 183.084694ms
client  | 0.491 DEBUG Setting timeout of 183.084694ms
client  | 0.491 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.491 DEBUG Building Handshake dcid Some(CID [12]: 42d33fcb023019d4050b0f30) scid Some(CID [0]: )
client  | 0.491 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.491 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.491 DEBUG [Client d57e8b899bc3d65d] delay duration 183.054708ms
client  | 0.491 DEBUG Setting timeout of 183.054708ms
sim     | Forwarding 22 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 6/15 (40.0%)
sim     | Dropping 32 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 7/16 (43.8%)
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] pn=0 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 22
client  |   -> RX HandshakeDone
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] State change from Connected -> Confirmed
client  | 0.492 DEBUG PMTUD started with probe size 1380
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] Drop packet number space hs
client  | 0.492 DEBUG [recovery::Loss] Reset loss recovery state for Handshake
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] No preferred address to migrate to
client  | 0.492 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 581343339 }
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.492 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.492 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] pn=1 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1321 }
client  | 0.492 DEBUG packet_sent this=0x5653c3fbaaa0, pn=1, ps=1352
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.492 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.492 DEBUG ECN probing: sent 1 probes
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.492 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] delay duration 19.880288ms
client  | 0.492 DEBUG Setting timeout of 19.880288ms
client  | 0.492 WARN Unhandled event StateChange(Confirmed)
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.492 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.492 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] delay duration 19.858166ms
client  | 0.492 DEBUG Setting timeout of 19.858166ms
sim     | Forwarding 282 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 7/17 (41.2%)
client  | 0.492 DEBUG [Client d57e8b899bc3d65d] pn=2 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 282
client  |   -> RX Crypto { offset: 0, len: 257 }
sim     | Dropping 283 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 8/18 (44.4%)
sim     | Dropping 47 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 9/19 (47.4%)
client  | 0.493 DEBUG [0x5653c3fa7480] Got resumption token [921]: 0200063e92d289d7..66a284979663c961
sim     | Forwarding 53 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 9/20 (45.0%)
client  | 0.493 DEBUG [Agent 0x5653c3fa7480] 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.493 DEBUG setting max_stream_data to 16777216
client  | 0.493 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 561794759 }
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] pn=2 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 37
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 32, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }], ecn_count: None }
client  | 0.493 DEBUG ECN probing: sent 2 probes
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
sim     | Forwarding 53 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 9/21 (42.9%)
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] delay duration 1.131859606s
client  | 0.493 DEBUG Setting timeout of 1.131859606s
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] pn=4 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [94, 86, 138, 68, 77, 211, 188, 33, 79, 181, 141, 57], stateless_reset_token: [1, 182, 45, 3, 78, 21, 114, 168, 220, 199, 242, 103, 14, 147, 86, 251] }
client  | 0.493 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 562271287 }
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] pn=3 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 39
client  |   TX -> Ack { largest_acknowledged: 4, ack_delay: 12, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None }
client  | 0.493 DEBUG ECN probing: sent 3 probes
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] delay duration 1.131616319s
client  | 0.493 DEBUG Setting timeout of 1.131616319s
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] pn=5 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [11, 180, 91, 131, 74, 56, 138, 180, 219, 69, 101, 229], stateless_reset_token: [125, 34, 53, 97, 113, 99, 158, 245, 230, 243, 89, 134, 53, 195, 144, 62] }
client  | 0.493 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 582532235 }
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] delay duration 19.975664ms
client  | 0.493 DEBUG Setting timeout of 19.975664ms
client  | 0.493 DEBUG stream BiDi creatable
client  | 0.493 DEBUG stream UniDi creatable
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.493 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.493 DEBUG [Client d57e8b899bc3d65d] delay duration 19.943394ms
client  | 0.493 DEBUG Setting timeout of 19.943394ms
sim     | Forwarding 1049 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 9/22 (40.9%)
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] pn=6 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1049
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  | 0.494 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 828, tv_nsec: 563047561 }
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.494 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] pn=4 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 39
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 3, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None }
client  | 0.494 DEBUG ECN probing: sent 4 probes
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.494 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.494 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] delay duration 1.130942748s
client  | 0.494 DEBUG Setting timeout of 1.130942748s
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.494 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.494 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] delay duration 1.130881934s
client  | 0.494 DEBUG Setting timeout of 1.130881934s
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 831, tv_nsec: 961278288 } }
client  | 0.494 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 831, tv_nsec: 961278288 } })
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.494 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.494 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 828, tv_nsec: 563229692 }
client  | 0.494 DEBUG [Client d57e8b899bc3d65d] pn=5 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 50
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 22, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.494 DEBUG ECN probing: sent 5 probes
client  | 0.494 DEBUG Setting timeout of 3.397985749s
sim     | Forwarding 84 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/12 (16.7%)
sim     | Forwarding 1352 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/13 (15.4%)
sim     | Forwarding 37 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/14 (14.3%)
sim     | Forwarding 39 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/15 (13.3%)
sim     | Forwarding 39 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/16 (12.5%)
sim     | Forwarding 50 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/17 (11.8%)
sim     | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 9/23 (39.1%)
client  | 0.517 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.517 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.517 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 828, tv_nsec: 586222286 }
client  | 0.517 DEBUG [Client d57e8b899bc3d65d] pn=6 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 51
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 2896, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.517 DEBUG ECN probing: sent 6 probes
client  | 0.517 DEBUG Setting timeout of 3.374969481s
client  | 0.517 DEBUG Setting timeout of 3.374957539s
sim     | Forwarding 46 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 9/24 (37.5%)
client  | 0.521 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.521 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.521 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 828, tv_nsec: 590738869 }
client  | 0.521 DEBUG [Client d57e8b899bc3d65d] pn=7 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 51
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 3461, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.521 DEBUG ECN probing: sent 7 probes
client  | 0.521 DEBUG Setting timeout of 3.370455663s
client  | 0.522 DEBUG Setting timeout of 3.370440655s
sim     | Forwarding 32 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 9/25 (36.0%)
client  | 0.525 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.525 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.525 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 828, tv_nsec: 593832372 }
client  | 0.525 DEBUG [Client d57e8b899bc3d65d] pn=8 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 51
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 3848, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.525 DEBUG ECN probing: sent 8 probes
client  | 0.525 DEBUG Setting timeout of 3.367352973s
client  | 0.525 DEBUG Setting timeout of 3.367342543s
sim     | Dropping 283 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 10/26 (38.5%)
sim     | Forwarding 25 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 10/27 (37.0%)
client  | 0.525 DEBUG [Client d57e8b899bc3d65d] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 0.525 DEBUG Building Short dcid Some(CID [12]: 42d33fcb023019d4050b0f30)
client  | 0.525 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 828, tv_nsec: 594367577 }
client  | 0.525 DEBUG [Client d57e8b899bc3d65d] pn=9 type=Short pri-path:42d33fcb023019d4050b0f30 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 51
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 3915, first_ack_range: 2, ack_ranges: [AckRange { gap: 0, range: 0 }, AckRange { gap: 0, range: 0 }], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 0.525 DEBUG ECN probing: sent 9 probes
client  | 0.525 DEBUG Setting timeout of 3.366804621s
client  | 0.525 DEBUG Setting timeout of 3.366794353s
sim     | Forwarding 51 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/18 (11.1%)
sim     | Forwarding 51 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/19 (10.5%)
sim     | Forwarding 51 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/20 (10.0%)
sim     | Forwarding 51 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/21 (9.5%)
server  | send_count:19, lost_count:5, tlp_count:0, recv_count:19, srtt:32420 early_data_flag:0, conn_err:0, ack_info:#9-0#0-0#0-0#v0429, path_info:0/1/0/a/0,1,1,1,0,0,0,0,0,0,0,27,24,19,1024,0,17,i32,4,38,51,0,7,512,0,4,38,51,0,8,515,0,4,38,51,0,9,516,0,4,16,40,8,485,4,267,20,14,485,4,9,40000,15,485,0-2,0-2,0-0,0-0,0-0,0-32,0-5273,0-7117,0-19,0-19,0-5,0-0,0-0,0-0,0-0,0-0,0-0,0-0
client  | 3.893 DEBUG [Client d57e8b899bc3d65d] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 831, tv_nsec: 961278288 } } -> Closed(Application(0))
client  | 3.893 INFO Closing timer expired
client  | 3.893 DEBUG Output::None
client  | 3.893 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 3.893 DEBUG Timer fired while closed
client  | 3.893 DEBUG Output::None
client  | 3.893 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=bfc0d38c8e819a54
client  | 3.893 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=9
client  | 3.893 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 3.893 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500
client  | 3.893 DEBUG [unv-path 0.0.0.0:57000->193.167.100.100:443] Make permanent
client  | 3.893 DEBUG [unv-path:bfc0d38c8e819a54 0.0.0.0:57000->193.167.100.100:443] set as primary path
client  | 3.893 DEBUG [pri-unv-path:bfc0d38c8e819a54 0.0.0.0:57000->193.167.100.100:443] Path validated Instant { tv_sec: 831, tv_nsec: 962350428 }
client  | 3.893 DEBUG [Client bfc0d38c8e819a54] client_start
client  | 3.894 DEBUG Writing transport parameters, msg=1
client  | 3.894 DEBUG [Agent 0x5653c3fd6ee0] state -> InProgress
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] State change from Init -> WaitInitial
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.894 DEBUG Building Initial dcid Some(CID [8]: bfc0d38c8e819a54) scid Some(CID [0]: )
client  | 3.894 DEBUG CRYPTO for in offset=0, len=1213
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] pn=9 type=Initial pri-path:bfc0d38c8e819a54 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1213 }
client  | 3.894 DEBUG packet_sent this=0x5653c3fbaaa0, pn=9, ps=1252
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.894 DEBUG Building Initial dcid Some(CID [8]: bfc0d38c8e819a54) scid Some(CID [0]: )
client  | 3.894 DEBUG CRYPTO for in offset=1213, len=327
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] pn=10 type=Initial pri-path:bfc0d38c8e819a54 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 367
client  |   TX -> Crypto { offset: 1213, len: 327 }
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] pad Initial from 367 to PLPMTU 1252
client  | 3.894 DEBUG packet_sent this=0x5653c3fbaaa0, pn=10, ps=1252
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 3.894 DEBUG Building Initial dcid Some(CID [8]: bfc0d38c8e819a54) scid Some(CID [0]: )
client  | 3.894 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 3.894 DEBUG Building Initial dcid Some(CID [8]: bfc0d38c8e819a54) scid Some(CID [0]: )
client  | 3.894 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] delay duration 4.088012ms
client  | 3.894 DEBUG Setting timeout of 4.088012ms
client  | 3.894 DEBUG StateChange(WaitInitial)
client  | 3.894 WARN Cannot create stream ConnectionState
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 3.894 DEBUG Building Initial dcid Some(CID [8]: bfc0d38c8e819a54) scid Some(CID [0]: )
client  | 3.894 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
client  | 3.894 DEBUG [Client bfc0d38c8e819a54] delay duration 4.052866ms
client  | 3.894 DEBUG Setting timeout of 4.052866ms
client  | 3.899 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.899 DEBUG Building Initial dcid Some(CID [8]: bfc0d38c8e819a54) scid Some(CID [0]: )
client  | 3.899 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.899 DEBUG [Client bfc0d38c8e819a54] delay duration 294.389608ms
client  | 3.899 DEBUG Setting timeout of 294.389608ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 2/22 (9.1%)
sim     | Dropping 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/23 (13.0%)
sim     | Forwarding 48 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 10/28 (35.7%)
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] pn=0 type=Initial pri-path:bfc0d38c8e819a54 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 48
client  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 73, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] Rx ACK space=in, ranges=[9..=9]
client  | 3.926 DEBUG [recovery::Loss] ACK for Initial - largest_acked=9
client  | 3.926 DEBUG on_packets_acked this=0x5653c3fbaaa0, limited=1, bytes_in_flight=1252, cwnd=12520, state=SlowStart, new_acked=1252
client  | 3.926 DEBUG Acked crypto frame space=in offset=0 length=1213
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] Changing to use Server CID=61ce0acfac515d3c95af5bb2
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] State change from WaitInitial -> WaitVersion
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.926 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 3.926 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] delay duration 65.24254ms
client  | 3.926 DEBUG Setting timeout of 65.24254ms
client  | 3.926 WARN Unhandled event StateChange(WaitVersion)
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.926 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 3.926 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.926 DEBUG [Client bfc0d38c8e819a54] delay duration 65.211933ms
client  | 3.926 DEBUG Setting timeout of 65.211933ms
client  | 3.992 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 3.992 DEBUG [Client bfc0d38c8e819a54] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1213, length: 327 })
client  | 3.992 INFO Lost crypto frame space=in offset=1213 length=327
client  | 3.992 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial), paced: false }
client  | 3.992 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 3.992 DEBUG CRYPTO for in offset=1213, len=327
client  | 3.992 DEBUG [Client bfc0d38c8e819a54] pn=11 type=Initial pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 371
client  |   TX -> Crypto { offset: 1213, len: 327 }
client  | 3.992 DEBUG [Client bfc0d38c8e819a54] pad Initial from 371 to PLPMTU 1252
client  | 3.992 DEBUG packet_sent this=0x5653c3fbaaa0, pn=11, ps=1252
client  | 3.992 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false }
client  | 3.992 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 3.992 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(), paced: false }
client  | 3.993 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.993 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 3.993 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 3.993 DEBUG [Client bfc0d38c8e819a54] delay duration 195.820225ms
client  | 3.993 DEBUG Setting timeout of 195.820225ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/24 (12.5%)
sim     | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 10/29 (34.5%)
sim     | Forwarding 183 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 10/30 (33.3%)
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] pn=1 type=Initial pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1200
client  |   -> RX Crypto { offset: 0, len: 90 }
client  |   -> RX Padding { len: 1064 }
client  | 4.026 DEBUG Write secret available for Handshake: SymKey [32]: 100e92a6c8fb55b5d8506c77d57044ca613d8d75ead96374b0fbc3a2f1adb0a3
sim     | Dropping 517 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 11/31 (35.5%)
client  | 4.026 DEBUG Read secret available for Handshake: SymKey [32]: 1f435cc2583c45343f5bd877dca6b3ca6462f804c0091d498d229807e4adbba4
sim     | Dropping 125 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 12/32 (37.5%)
sim     | Dropping 81 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 13/33 (39.4%)
sim     | Forwarding 50 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 13/34 (38.2%)
client  | 4.026 DEBUG [Agent 0x5653c3fd6ee0] state -> InProgress
client  | 4.026 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 4.026 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 4.026 DEBUG [Crypto] Handshake keys installed
client  | 4.026 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 95124685 }
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] State change from WaitVersion -> Handshaking
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.026 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] pn=12 type=Initial pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 44
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 62, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 4.026 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] pad Initial from 44 to PLPMTU 1252
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.026 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.026 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] delay duration 161.98044ms
client  | 4.026 DEBUG Setting timeout of 161.98044ms
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] pn=0 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 183
client  |   -> RX Crypto { offset: 0, len: 138 }
client  | 4.026 DEBUG [Agent 0x5653c3fd6ee0] state -> InProgress
client  | 4.026 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 95752765 }
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.026 DEBUG Building Initial dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.026 DEBUG [Client bfc0d38c8e819a54] pn=0 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 43
client  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 5, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] Drop packet number space in
client  | 4.027 DEBUG [recovery::Loss] Reset loss recovery state for Initial
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.027 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] delay duration 97.889494ms
client  | 4.027 DEBUG Setting timeout of 97.889494ms
client  | 4.027 DEBUG StateChange(Handshaking)
client  | 4.027 WARN Cannot create stream ConnectionState
client  | 4.027 DEBUG stream BiDi creatable
client  | 4.027 WARN Cannot create stream ConnectionState
client  | 4.027 DEBUG stream UniDi creatable
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.027 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] delay duration 97.86109ms
client  | 4.027 DEBUG Setting timeout of 97.86109ms
client  | 4.027 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 1
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] pn=1 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 4.027 DEBUG packet_sent this=0x5653c3fbaaa0, pn=1, ps=41
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.027 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] delay duration 97.927704ms
client  | 4.027 DEBUG Setting timeout of 97.927704ms
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.027 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.027 DEBUG [Client bfc0d38c8e819a54] delay duration 97.889573ms
client  | 4.027 DEBUG Setting timeout of 97.889573ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/25 (12.0%)
sim     | Forwarding 43 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/26 (11.5%)
sim     | Forwarding 41 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/27 (11.1%)
sim     | Dropping 46 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/35 (40.0%)
sim     | Forwarding 517 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/36 (38.9%)
sim     | Forwarding 125 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/37 (37.8%)
client  | 4.110 DEBUG [Client bfc0d38c8e819a54] pn=5 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 517
client  |   -> RX Crypto { offset: 138, len: 471 }
client  | 4.110 DEBUG [Agent 0x5653c3fd6ee0] state -> AuthenticationPending
client  | 4.110 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 179134998 }
client  | 4.110 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.110 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.110 DEBUG [Client bfc0d38c8e819a54] pn=2 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 45
client  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 16, first_ack_range: 0, ack_ranges: [AckRange { gap: 3, range: 0 }], ecn_count: None }
client  | 4.110 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.110 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.110 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.110 DEBUG [Client bfc0d38c8e819a54] delay duration 14.574065ms
client  | 4.110 DEBUG Setting timeout of 14.574065ms
client  | 4.110 DEBUG [Client bfc0d38c8e819a54] pn=6 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 125
client  |   -> RX Crypto { offset: 609, len: 79 }
client  | 4.111 DEBUG [Agent 0x5653c3fd6ee0] state -> AuthenticationPending
client  | 4.111 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 179384102 }
client  | 4.111 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.111 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.111 DEBUG [Client bfc0d38c8e819a54] pn=3 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 46
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 91, first_ack_range: 1, ack_ranges: [AckRange { gap: 3, range: 0 }], ecn_count: None }
client  | 4.111 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.111 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.111 DEBUG [Client bfc0d38c8e819a54] delay duration 13.722227ms
client  | 4.111 DEBUG Setting timeout of 13.722227ms
client  | 4.111 DEBUG [Client bfc0d38c8e819a54] Authenticated Ok
client  | 4.111 DEBUG [Agent 0x5653c3fd6ee0] SSL_AuthCertificateComplete: Ok(())
client  | 4.111 DEBUG [Agent 0x5653c3fd6ee0] state -> InProgress
client  | 4.111 DEBUG stream BiDi creatable
client  | 4.111 WARN Cannot create stream ConnectionState
client  | 4.111 DEBUG stream UniDi creatable
client  | 4.111 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.111 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.111 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.111 DEBUG [Client bfc0d38c8e819a54] delay duration 13.682453ms
client  | 4.111 DEBUG Setting timeout of 13.682453ms
sim     | Forwarding 45 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/28 (10.7%)
sim     | Forwarding 46 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/29 (10.3%)
client  | 4.126 DEBUG [recovery::Loss] PTO timer fired for Handshake
client  | 4.126 DEBUG [Client bfc0d38c8e819a54] Lost: Ack(AckToken { space: Handshake, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
client  | 4.126 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 832, tv_nsec: 95797028 }
client  | 4.126 DEBUG [Client bfc0d38c8e819a54] Lost: Ack(AckToken { space: Handshake, ranges: [PacketRange { largest: 5, smallest: 5, ack_needed: true }, PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
client  | 4.126 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 832, tv_nsec: 179269138 }
client  | 4.126 DEBUG [Client bfc0d38c8e819a54] Lost: Ack(AckToken { space: Handshake, ranges: [PacketRange { largest: 6, smallest: 5, ack_needed: true }, PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
client  | 4.126 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 832, tv_nsec: 180115566 }
client  | 4.126 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(Handshake), paced: false }
client  | 4.126 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.126 DEBUG [Client bfc0d38c8e819a54] pn=4 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 47
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 1992, first_ack_range: 1, ack_ranges: [AckRange { gap: 3, range: 0 }], ecn_count: None }
client  |   TX -> Ping
client  | 4.126 DEBUG packet_sent this=0x5653c3fbaaa0, pn=4, ps=47
client  | 4.126 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(), paced: false }
client  | 4.126 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.126 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: Some(Handshake), probe: EnumSet(), paced: false }
client  | 4.126 DEBUG [Client bfc0d38c8e819a54] delay duration 195.856111ms
client  | 4.126 DEBUG Setting timeout of 195.856111ms
sim     | Forwarding 81 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/38 (36.8%)
client  | 4.141 DEBUG [Client bfc0d38c8e819a54] pn=7 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 81
client  |   -> RX Crypto { offset: 688, len: 36 }
client  | 4.141 DEBUG Write secret available for ApplicationData: SymKey [32]: 0037beddc0880b0f202cb4a49475581ead0f9dec5aaf2229f0fc66f2ff326e7b
client  | 4.141 DEBUG Read secret available for ApplicationData: SymKey [32]: e9101ddb71799b8d5d5a443a38131a140caf96105b40900ef849ce1c5364ee78
client  | 4.141 DEBUG [Agent 0x5653c3fd6ee0] 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.141 DEBUG [Client bfc0d38c8e819a54] TLS connection complete
sim     | Forwarding 47 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/30 (10.0%)
client  | 4.141 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 4.142 DEBUG [Crypto] Application write key installed
client  | 4.142 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 4.142 DEBUG [Crypto] application read keys installed
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] State change from Handshaking -> Connected
client  | 4.142 INFO [Client bfc0d38c8e819a54] Connection established
client  | 4.142 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 210473019 }
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.142 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.142 DEBUG CRYPTO for hs offset=0, len=36
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] pn=5 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 85
client  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 84, first_ack_range: 2, ack_ranges: [AckRange { gap: 3, range: 0 }], ecn_count: None }
client  |   TX -> Crypto { offset: 0, len: 36 }
client  | 4.142 DEBUG packet_sent this=0x5653c3fbaaa0, pn=5, ps=85
client  | 4.142 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.142 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.142 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.142 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] delay duration 195.862442ms
client  | 4.142 DEBUG Setting timeout of 195.862442ms
client  | 4.142 DEBUG stream BiDi creatable
client  | 4.142 INFO Created stream 0 for https://server4/aktggegoep
client  | 4.142 INFO Saving https://server4/aktggegoep to "/downloads/aktggegoep"
client  | 4.142 DEBUG stream UniDi creatable
client  | 4.142 DEBUG StateChange(Connected)
client  | 4.142 DEBUG stream 0 writable
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.142 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.142 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] pn=0 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 50
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 4.142 DEBUG packet_sent this=0x5653c3fbaaa0, pn=0, ps=50
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.142 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.142 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.142 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.142 DEBUG [Client bfc0d38c8e819a54] delay duration 195.672849ms
client  | 4.142 DEBUG Setting timeout of 195.672849ms
sim     | Forwarding 46 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/39 (35.9%)
client  | 4.157 DEBUG [Client bfc0d38c8e819a54] pn=8 type=Handshake pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 46
client  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 27, first_ack_range: 4, ack_ranges: [], ecn_count: None }
client  | 4.157 DEBUG [Client bfc0d38c8e819a54] Rx ACK space=hs, ranges=[0..=4]
client  | 4.157 DEBUG [recovery::Loss] ACK for Handshake - largest_acked=4
client  | 4.157 DEBUG on_packets_acked this=0x5653c3fbaaa0, limited=1, bytes_in_flight=135, cwnd=12520, state=SlowStart, new_acked=88
client  | 4.157 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.157 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.157 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.157 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.157 DEBUG [Client bfc0d38c8e819a54] delay duration 68.016148ms
client  | 4.157 DEBUG Setting timeout of 68.016148ms
client  | 4.157 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
sim     | Forwarding 85 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/31 (9.7%)
client  | 4.157 DEBUG Building Handshake dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2) scid Some(CID [0]: )
client  | 4.157 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.157 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.157 DEBUG [Client bfc0d38c8e819a54] delay duration 67.993976ms
client  | 4.157 DEBUG Setting timeout of 67.993976ms
sim     | Forwarding 50 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 3/32 (9.4%)
server  | xqc_hq_parse_req|247||hq recv CR LF|
sim     | Forwarding 22 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/40 (35.0%)
sim     | Forwarding 32 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/41 (34.1%)
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] pn=0 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 22
client  |   -> RX HandshakeDone
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] State change from Connected -> Confirmed
client  | 4.173 DEBUG PMTUD started with probe size 1380
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] Drop packet number space hs
client  | 4.173 DEBUG [recovery::Loss] Reset loss recovery state for Handshake
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] No preferred address to migrate to
client  | 4.173 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 262351922 }
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.173 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.173 DEBUG Sending PMTUD probe of size 1380, count 1
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] pn=1 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 1352
client  |   TX -> Ping
client  |   TX -> Padding { len: 1321 }
client  | 4.173 DEBUG packet_sent this=0x5653c3fbaaa0, pn=1, ps=1352
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.173 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.173 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.173 DEBUG ECN probing: sent 1 probes
sim     | Forwarding 282 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/42 (33.3%)
sim     | Forwarding 283 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/43 (32.6%)
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
sim     | Forwarding 46 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/44 (31.8%)
sim     | Forwarding 53 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/45 (31.1%)
sim     | Forwarding 53 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/46 (30.4%)
client  | 4.173 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.173 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] delay duration 19.848146ms
client  | 4.173 DEBUG Setting timeout of 19.848146ms
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] pn=1 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 32
client  |   -> RX NewToken { token: [0, 193, 167, 0, 100, 104, 204, 215, 134] }
client  | 4.173 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 242600987 }
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.173 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] pn=2 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 35
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 8, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 4.173 DEBUG ECN probing: sent 2 probes
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.173 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.173 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] delay duration 107.846656ms
client  | 4.173 DEBUG Setting timeout of 107.846656ms
client  | 4.173 DEBUG [Client bfc0d38c8e819a54] pn=2 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 282
client  |   -> RX Crypto { offset: 0, len: 257 }
client  | 4.173 DEBUG [0x5653c3fd6ee0] Got resumption token [921]: 0200063e92d2c202..3bd0db30b6c085da
client  | 4.173 DEBUG [Agent 0x5653c3fd6ee0] 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.173 DEBUG setting max_stream_data to 16777216
client  | 4.174 DEBUG resumption token [1025]: 0000000120405700..3bd0db30b6c085da
client  | 4.174 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 262749824 }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] delay duration 19.90383ms
client  | 4.174 DEBUG Setting timeout of 19.90383ms
client  | 4.174 WARN Unhandled event StateChange(Confirmed)
client  | 4.174 DEBUG stream BiDi creatable
client  | 4.174 DEBUG stream UniDi creatable
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] delay duration 19.874676ms
client  | 4.174 DEBUG Setting timeout of 19.874676ms
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] pn=3 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 283
client  |   -> RX Crypto { offset: 257, len: 257 }
client  | 4.174 DEBUG [0x5653c3fd6ee0] Got resumption token [921]: 0200063e92d2c203..c8790850ce2c3a76
client  | 4.174 DEBUG [Agent 0x5653c3fd6ee0] 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.174 DEBUG setting max_stream_data to 16777216
client  | 4.174 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 243116474 }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] pn=3 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 35
client  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 14, first_ack_range: 3, ack_ranges: [], ecn_count: None }
client  | 4.174 DEBUG ECN probing: sent 3 probes
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] delay duration 107.185026ms
client  | 4.174 DEBUG Setting timeout of 107.185026ms
client  | 4.174 DEBUG [Client ...] Dropped received packet: Decryption failure; Total: 2
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] delay duration 107.140123ms
client  | 4.174 DEBUG Setting timeout of 107.140123ms
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] pn=4 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [237, 159, 68, 240, 230, 108, 46, 134, 78, 95, 95, 143], stateless_reset_token: [99, 100, 120, 190, 68, 27, 159, 120, 77, 188, 229, 13, 68, 102, 17, 12] }
client  | 4.174 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 263448423 }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] delay duration 19.977759ms
client  | 4.174 DEBUG Setting timeout of 19.977759ms
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] pn=5 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 53
client  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [165, 53, 126, 228, 140, 53, 7, 87, 201, 182, 109, 164], stateless_reset_token: [22, 8, 137, 58, 193, 188, 29, 21, 115, 228, 1, 172, 226, 164, 95, 0] }
client  | 4.174 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 243486063 }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] pn=4 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 35
client  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 1, first_ack_range: 5, ack_ranges: [], ecn_count: None }
client  | 4.174 DEBUG ECN probing: sent 4 probes
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
sim     | Forwarding 1049 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/47 (29.8%)
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] delay duration 107.032522ms
client  | 4.174 DEBUG Setting timeout of 107.032522ms
client  | 4.174 DEBUG stream BiDi creatable
client  | 4.174 DEBUG stream UniDi creatable
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.174 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.174 DEBUG [Client bfc0d38c8e819a54] delay duration 107.005973ms
client  | 4.174 DEBUG Setting timeout of 107.005973ms
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] pn=6 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1049
client  |   -> RX Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
client  | 4.175 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 264054885 }
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.175 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.175 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] delay duration 19.971056ms
client  | 4.175 DEBUG Setting timeout of 19.971056ms
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.175 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.175 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] delay duration 19.926994ms
client  | 4.175 DEBUG Setting timeout of 19.926994ms
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] State change from Confirmed -> Closing { error: Application(0), timeout: Instant { tv_sec: 832, tv_nsec: 568611821 } }
client  | 4.175 WARN Unhandled event StateChange(Closing { error: Application(0), timeout: Instant { tv_sec: 832, tv_nsec: 568611821 } })
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.175 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.175 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 832, tv_nsec: 244153769 }
client  | 4.175 DEBUG [Client bfc0d38c8e819a54] pn=5 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 46
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 12, first_ack_range: 6, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 4.175 DEBUG ECN probing: sent 5 probes
client  | 4.175 DEBUG Setting timeout of 324.403089ms
sim     | Dropping 1352 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 4/33 (12.1%)
sim     | Forwarding 35 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 4/34 (11.8%)
sim     | Dropping 35 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 5/35 (14.3%)
sim     | Dropping 35 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 6/36 (16.7%)
sim     | Forwarding 46 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 6/37 (16.2%)
sim     | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 14/48 (29.2%)
client  | 4.199 DEBUG [Client bfc0d38c8e819a54] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.199 DEBUG Building Short dcid Some(CID [12]: 61ce0acfac515d3c95af5bb2)
client  | 4.199 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 832, tv_nsec: 268505143 }
client  | 4.199 DEBUG [Client bfc0d38c8e819a54] pn=6 type=Short pri-path:61ce0acfac515d3c95af5bb2 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, Ect0) len 47
client  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 3056, first_ack_range: 6, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "kthxbye!" }
client  | 4.199 DEBUG ECN probing: sent 6 probes
client  | 4.199 DEBUG Setting timeout of 299.994529ms
client  | 4.199 DEBUG Setting timeout of 299.982086ms
sim     | Dropping 25 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 15/49 (30.6%)
sim     | Forwarding 47 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 6/38 (15.8%)
server  | send_count:15, lost_count:1, tlp_count:0, recv_count:13, srtt:32181 early_data_flag:0, conn_err:0, ack_info:#6-5#2-2#0-0#v0429, path_info:0/1/0/a/0,1,1,1,0,0,0,0,0,0,0,22,15,13,1024,0,17,i32,4,22,35,4,2,279,0,4,33,46,40004,5,279,0,4,34,47,0,6,304,0,4,9,40000,8,280,4,1033,80,6,248,4,11,4,7,273,0-2,0-2,0-0,0-0,0-0,0-32,0-4891,0-4241,0-15,0-13,0-1,0-0,0-0,0-0,0-0,0-0,0-0,0-0
client  | 4.501 DEBUG [Client bfc0d38c8e819a54] State change from Closing { error: Application(0), timeout: Instant { tv_sec: 832, tv_nsec: 568611821 } } -> Closed(Application(0))
client  | 4.501 INFO Closing timer expired
client  | 4.501 DEBUG Output::None
client  | 4.501 WARN Unhandled event StateChange(Closed(Application(0)))
client  | 4.501 DEBUG Timer fired while closed
client  | 4.501 DEBUG Output::None
client  | 4.501 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Client dcid=f34c4f1c6bd47cc0
client  | 4.501 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865 min_pn=6
client  | 4.501 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 4.501 DEBUG Outbound interface eth0 for destination 193.167.100.100 has MTU 1500
client  | 4.501 DEBUG [unv-path 0.0.0.0:57000->193.167.100.100:443] Make permanent
client  | 4.501 DEBUG [unv-path:f34c4f1c6bd47cc0 0.0.0.0:57000->193.167.100.100:443] set as primary path
client  | 4.501 DEBUG [pri-unv-path:f34c4f1c6bd47cc0 0.0.0.0:57000->193.167.100.100:443] Path validated Instant { tv_sec: 832, tv_nsec: 570252300 }
client  | 4.501 INFO [Client f34c4f1c6bd47cc0] resumption token [1025]: 0000000120405700..3bd0db30b6c085da
client  | 4.501 DEBUG Overwrite initial version Version1 ==> Version1
client  | 4.501 DEBUG Overwrite initial version Version1 ==> Version1
client  | 4.501 DEBUG [Client f34c4f1c6bd47cc0] client_start
client  | 4.502 DEBUG Writing transport parameters, msg=1
client  | 4.502 DEBUG Write secret available for ZeroRtt: SymKey [32]: f53c191f652c1067d0ff65bd7e246810db9e58f3ad34d59b0183572370f889f7
client  | 4.502 DEBUG [Agent 0x5653c3fa7810] state -> InProgress
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] State change from Init -> WaitInitial
client  | 4.502 DEBUG Making Write ZeroRtt CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] Enabled 0-RTT
client  | 4.502 DEBUG stream BiDi creatable
client  | 4.502 INFO Created stream 0 for https://server4/qrrgvswgil
client  | 4.502 INFO Saving https://server4/qrrgvswgil to "/downloads/qrrgvswgil"
client  | 4.502 DEBUG stream UniDi creatable
client  | 4.502 DEBUG StateChange(WaitInitial)
client  | 4.502 DEBUG stream 0 writable
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.502 DEBUG Building Initial dcid Some(CID [8]: f34c4f1c6bd47cc0) scid Some(CID [0]: )
client  | 4.502 DEBUG CRYPTO for in offset=0, len=1204
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] pn=6 type=Initial pri-path:f34c4f1c6bd47cc0 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1204 }
client  | 4.502 DEBUG packet_sent this=0x5653c3fbaaa0, pn=6, ps=1252
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.502 DEBUG Building Initial dcid Some(CID [8]: f34c4f1c6bd47cc0) scid Some(CID [0]: )
client  | 4.502 DEBUG CRYPTO for in offset=1204, len=611
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] pn=7 type=Initial pri-path:f34c4f1c6bd47cc0 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 660
client  |   TX -> Crypto { offset: 1204, len: 611 }
client  | 4.502 DEBUG Building ZeroRtt dcid Some(CID [8]: f34c4f1c6bd47cc0) scid Some(CID [0]: )
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] pn=0 type=ZeroRtt pri-path:f34c4f1c6bd47cc0 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 714
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 4.502 DEBUG packet_sent this=0x5653c3fbaaa0, pn=0, ps=54
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] pad Initial from 714 to PLPMTU 1252
client  | 4.502 DEBUG packet_sent this=0x5653c3fbaaa0, pn=7, ps=1198
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.502 DEBUG Building Initial dcid Some(CID [8]: f34c4f1c6bd47cc0) scid Some(CID [0]: )
client  | 4.502 DEBUG Building ZeroRtt dcid Some(CID [8]: f34c4f1c6bd47cc0) scid Some(CID [0]: )
client  | 4.502 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.502 DEBUG Building Initial dcid Some(CID [8]: f34c4f1c6bd47cc0) scid Some(CID [0]: )
client  | 4.502 DEBUG Building ZeroRtt dcid Some(CID [8]: f34c4f1c6bd47cc0) scid Some(CID [0]: )
client  | 4.502 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.502 DEBUG [Client f34c4f1c6bd47cc0] delay duration 95.822489ms
client  | 4.502 DEBUG Setting timeout of 95.822489ms
sim     | Dropping 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/39 (17.9%)
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/40 (17.5%)
sim     | Forwarding 56 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 15/50 (30.0%)
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] pn=0 type=Initial pri-path:f34c4f1c6bd47cc0 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 56
client  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 54, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] Rx ACK space=in, ranges=[7..=7]
client  | 4.535 DEBUG [recovery::Loss] ACK for Initial - largest_acked=7
client  | 4.535 DEBUG on_packets_acked this=0x5653c3fbaaa0, limited=1, bytes_in_flight=1306, cwnd=12520, state=SlowStart, new_acked=1198
client  | 4.535 DEBUG Acked crypto frame space=in offset=1204 length=611
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] Changing to use Server CID=abcd816c2ffb2981ba9e1643
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] State change from WaitInitial -> WaitVersion
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.535 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.535 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.535 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] delay duration 4.072559ms
client  | 4.535 DEBUG Setting timeout of 4.072559ms
client  | 4.535 WARN Unhandled event StateChange(WaitVersion)
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.535 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.535 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.535 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.535 DEBUG [Client f34c4f1c6bd47cc0] delay duration 4.036041ms
client  | 4.535 DEBUG Setting timeout of 4.036041ms
client  | 4.541 DEBUG packet_lost this=0x5653c3fbaaa0, pn=6, ps=1252
client  | 4.541 INFO [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 54/8764 ssthresh 8764] Cong event -> recovery; cwnd 8764, ssthresh 8764
client  | 4.541 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 54/8764 ssthresh 8764] state -> RecoveryStart
client  | 4.541 DEBUG on_packets_lost this=0x5653c3fbaaa0, bytes_in_flight=54, cwnd=8764, state=RecoveryStart
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 1204 })
client  | 4.541 INFO Lost crypto frame space=in offset=0 length=1204
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.541 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.541 DEBUG CRYPTO for in offset=0, len=1200
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] pn=8 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1252
client  |   TX -> Crypto { offset: 0, len: 1200 }
client  | 4.541 DEBUG packet_sent this=0x5653c3fbaaa0, pn=8, ps=1252
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.541 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.541 DEBUG CRYPTO for in offset=1200, len=4
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] pn=9 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 56
client  |   TX -> Crypto { offset: 1200, len: 4 }
client  | 4.541 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] pad Initial from 56 to PLPMTU 1252
client  | 4.541 DEBUG packet_sent this=0x5653c3fbaaa0, pn=9, ps=1252
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.541 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.541 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.541 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.541 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.541 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.541 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.541 DEBUG [Client f34c4f1c6bd47cc0] delay duration 99.384343ms
client  | 4.541 DEBUG Setting timeout of 99.384343ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/41 (17.1%)
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/42 (16.7%)
server  | xqc_hq_parse_req|247||hq recv CR LF|
sim     | Forwarding 56 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 15/51 (29.4%)
client  | 4.573 DEBUG [Client f34c4f1c6bd47cc0] pn=1 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 56
client  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 33, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  | 4.573 DEBUG [Client f34c4f1c6bd47cc0] Rx ACK space=in, ranges=[7..=8]
client  | 4.573 DEBUG [recovery::Loss] ACK for Initial - largest_acked=8
client  | 4.573 DEBUG [Cubic [last_max_cwnd: 12520, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1306/8764 ssthresh 8764] state -> CongestionAvoidance
client  | 4.573 DEBUG on_packets_acked this=0x5653c3fbaaa0, limited=1, bytes_in_flight=1306, cwnd=8764, state=CongestionAvoidance, new_acked=1252
client  | 4.573 DEBUG Acked crypto frame space=in offset=0 length=1200
client  | 4.573 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.573 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.573 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.573 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.573 DEBUG [Client f34c4f1c6bd47cc0] delay duration 52.065703ms
client  | 4.573 DEBUG Setting timeout of 52.065703ms
client  | 4.573 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.573 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.573 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.573 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.573 DEBUG [Client f34c4f1c6bd47cc0] delay duration 52.041598ms
client  | 4.573 DEBUG Setting timeout of 52.041598ms
sim     | Dropping 1200 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 16/52 (30.8%)
sim     | Dropping 187 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 17/53 (32.1%)
sim     | Forwarding 81 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 17/54 (31.5%)
client  | 4.575 DEBUG saving Handshake datagram of 81 bytes
client  | 4.575 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
sim     | Dropping 57 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 18/55 (32.7%)
client  | 4.575 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.575 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.575 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.575 DEBUG [Client f34c4f1c6bd47cc0] delay duration 49.265275ms
client  | 4.575 DEBUG Setting timeout of 49.265275ms
client  | 4.575 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.575 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.575 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.575 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.575 DEBUG [Client f34c4f1c6bd47cc0] delay duration 49.229508ms
client  | 4.575 DEBUG Setting timeout of 49.229508ms
sim     | Forwarding 1049 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 18/56 (32.1%)
client  | 4.576 DEBUG saving ApplicationData datagram of 1049 bytes
client  | 4.576 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.576 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.576 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.576 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.576 DEBUG [Client f34c4f1c6bd47cc0] delay duration 48.363553ms
client  | 4.576 DEBUG Setting timeout of 48.363553ms
client  | 4.576 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.576 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.576 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.576 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.576 DEBUG [Client f34c4f1c6bd47cc0] delay duration 48.339689ms
client  | 4.576 DEBUG Setting timeout of 48.339689ms
client  | 4.626 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 4.626 DEBUG [recovery::Loss] PTO timer fired for ApplicationData
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1200, length: 4 })
client  | 4.626 INFO Lost crypto frame space=in offset=1200 length=4
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] Lost: Stream(Stream(RecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | ApplicationData), paced: false }
client  | 4.626 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.626 DEBUG CRYPTO for in offset=1200, len=4
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] pn=10 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 56
client  |   TX -> Crypto { offset: 1200, len: 4 }
client  | 4.626 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] pn=1 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 114
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 4.626 DEBUG packet_sent this=0x5653c3fbaaa0, pn=1, ps=58
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] pad Initial from 114 to PLPMTU 1252
client  | 4.626 DEBUG packet_sent this=0x5653c3fbaaa0, pn=10, ps=1194
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(ApplicationData), paced: false }
client  | 4.626 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.626 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] pn=2 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 4.626 DEBUG packet_sent this=0x5653c3fbaaa0, pn=2, ps=41
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.626 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.626 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.626 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.626 DEBUG [Client f34c4f1c6bd47cc0] delay duration 167.959826ms
client  | 4.626 DEBUG Setting timeout of 167.959826ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/43 (16.3%)
sim     | Forwarding 41 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/44 (15.9%)
sim     | Dropping 56 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 19/57 (33.3%)
sim     | Dropping 26 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 20/58 (34.5%)
sim     | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 20/59 (33.9%)
client  | 4.683 DEBUG saving ApplicationData datagram of 27 bytes
client  | 4.683 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.683 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.683 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.683 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.683 DEBUG [Client f34c4f1c6bd47cc0] delay duration 110.711435ms
client  | 4.683 DEBUG Setting timeout of 110.711435ms
client  | 4.683 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.683 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.683 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.683 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.683 DEBUG [Client f34c4f1c6bd47cc0] delay duration 110.675798ms
client  | 4.683 DEBUG Setting timeout of 110.675798ms
client  | 4.795 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 4.795 DEBUG [recovery::Loss] PTO timer fired for ApplicationData
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1200, length: 4 })
client  | 4.795 INFO Lost crypto frame space=in offset=1200 length=4
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] Lost: Stream(Stream(RecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | ApplicationData), paced: false }
client  | 4.795 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.795 DEBUG CRYPTO for in offset=1200, len=4
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] pn=11 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 56
client  |   TX -> Crypto { offset: 1200, len: 4 }
client  | 4.795 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] pn=3 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 114
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 4.795 DEBUG packet_sent this=0x5653c3fbaaa0, pn=3, ps=58
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] pad Initial from 114 to PLPMTU 1252
client  | 4.795 DEBUG packet_sent this=0x5653c3fbaaa0, pn=11, ps=1194
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(ApplicationData), paced: false }
client  | 4.795 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.795 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] pn=4 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 4.795 DEBUG packet_sent this=0x5653c3fbaaa0, pn=4, ps=41
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.795 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.795 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.795 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.795 DEBUG [Client f34c4f1c6bd47cc0] delay duration 336.124024ms
client  | 4.795 DEBUG Setting timeout of 336.124024ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/45 (15.6%)
sim     | Forwarding 41 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/46 (15.2%)
sim     | Dropping 56 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 21/60 (35.0%)
sim     | Forwarding 26 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 21/61 (34.4%)
client  | 4.827 DEBUG saving ApplicationData datagram of 26 bytes
client  | 4.827 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.827 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.827 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.827 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.827 DEBUG [Client f34c4f1c6bd47cc0] delay duration 304.203808ms
client  | 4.827 DEBUG Setting timeout of 304.203808ms
client  | 4.827 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.827 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.827 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 4.827 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 4.827 DEBUG [Client f34c4f1c6bd47cc0] delay duration 304.168422ms
client  | 4.827 DEBUG Setting timeout of 304.168422ms
client  | 5.133 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 5.133 DEBUG [recovery::Loss] PTO timer fired for ApplicationData
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 1200, length: 4 })
client  | 5.133 INFO Lost crypto frame space=in offset=1200 length=4
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] Lost: Stream(Stream(RecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | ApplicationData), paced: false }
client  | 5.133 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.133 DEBUG CRYPTO for in offset=1200, len=4
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] pn=12 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 56
client  |   TX -> Crypto { offset: 1200, len: 4 }
client  | 5.133 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] pn=5 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 114
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 5.133 DEBUG packet_sent this=0x5653c3fbaaa0, pn=5, ps=58
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] pad Initial from 114 to PLPMTU 1252
client  | 5.133 DEBUG packet_sent this=0x5653c3fbaaa0, pn=12, ps=1194
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(ApplicationData), paced: false }
client  | 5.133 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.133 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] pn=6 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 5.133 DEBUG packet_sent this=0x5653c3fbaaa0, pn=6, ps=41
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.133 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.133 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.133 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.133 DEBUG [Client f34c4f1c6bd47cc0] delay duration 672.45825ms
client  | 5.133 DEBUG Setting timeout of 672.45825ms
sim     | Forwarding 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 7/47 (14.9%)
sim     | Dropping 41 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 8/48 (16.7%)
sim     | Forwarding 56 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 21/62 (33.9%)
client  | 5.165 DEBUG [Client f34c4f1c6bd47cc0] pn=6 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 56
client  |   -> RX Ack { largest_acknowledged: 12, ack_delay: 30, first_ack_range: 5, ack_ranges: [], ecn_count: None }
client  | 5.165 DEBUG [Client f34c4f1c6bd47cc0] Rx ACK space=in, ranges=[7..=12]
client  | 5.165 DEBUG [recovery::Loss] ACK for Initial - largest_acked=12
client  | 5.165 DEBUG on_packets_acked this=0x5653c3fbaaa0, limited=1, bytes_in_flight=351, cwnd=8764, state=CongestionAvoidance, new_acked=4834
client  | 5.165 DEBUG Acked crypto frame space=in offset=1200 length=4
client  | 5.165 DEBUG Acked crypto frame space=in offset=1200 length=4
client  | 5.165 DEBUG Acked crypto frame space=in offset=1200 length=4
client  | 5.165 DEBUG Acked crypto frame space=in offset=1200 length=4
client  | 5.165 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.165 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.165 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.165 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.165 DEBUG [Client f34c4f1c6bd47cc0] delay duration 40.05612ms
client  | 5.165 DEBUG Setting timeout of 40.05612ms
client  | 5.165 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.165 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.165 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.165 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.165 DEBUG [Client f34c4f1c6bd47cc0] delay duration 39.999785ms
client  | 5.165 DEBUG Setting timeout of 39.999785ms
sim     | Forwarding 27 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 21/63 (33.3%)
client  | 5.190 DEBUG saving ApplicationData datagram of 27 bytes
client  | 5.190 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.190 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.190 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.190 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.190 DEBUG [Client f34c4f1c6bd47cc0] delay duration 14.85387ms
client  | 5.190 DEBUG Setting timeout of 14.85387ms
client  | 5.190 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.190 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.190 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.190 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.190 DEBUG [Client f34c4f1c6bd47cc0] delay duration 14.818184ms
client  | 5.190 DEBUG Setting timeout of 14.818184ms
client  | 5.205 DEBUG [recovery::Loss] PTO timer fired for Initial
client  | 5.205 DEBUG [recovery::Loss] PTO timer fired for ApplicationData
client  | 5.205 DEBUG [Client f34c4f1c6bd47cc0] Lost: Stream(Stream(RecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 5.205 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(Initial | ApplicationData), paced: false }
client  | 5.205 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.205 DEBUG [Client f34c4f1c6bd47cc0] pn=13 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 49
client  |   TX -> Ping
client  | 5.205 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.205 DEBUG [Client f34c4f1c6bd47cc0] pn=7 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 109
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 5.205 DEBUG packet_sent this=0x5653c3fbaaa0, pn=7, ps=58
client  | 5.205 DEBUG [Client f34c4f1c6bd47cc0] pad Initial from 109 to PLPMTU 1252
client  | 5.205 DEBUG packet_sent this=0x5653c3fbaaa0, pn=13, ps=1194
client  | 5.206 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(Initial), probe: EnumSet(ApplicationData), paced: false }
client  | 5.206 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.206 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.206 DEBUG [Client f34c4f1c6bd47cc0] pn=8 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 5.206 DEBUG packet_sent this=0x5653c3fbaaa0, pn=8, ps=41
client  | 5.206 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.206 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.206 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.206 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.206 DEBUG [Client f34c4f1c6bd47cc0] delay duration 144.156064ms
client  | 5.206 DEBUG Setting timeout of 144.156064ms
sim     | Dropping 1252 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 9/49 (18.4%)
sim     | Dropping 41 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 10/50 (20.0%)
sim     | Forwarding 1200 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 21/64 (32.8%)
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] pn=7 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 1200
client  |   -> RX Crypto { offset: 0, len: 96 }
client  |   -> RX Padding { len: 1049 }
client  | 5.326 DEBUG Write secret available for Handshake: SymKey [32]: b70105706356759a6ddc46a0f13ac00950f63764f3a777b65f64be4328c9325f
client  | 5.326 DEBUG Read secret available for Handshake: SymKey [32]: b1f4c8b4d2a7425457ef011010d03550c98db1f759648e48debe087fb2b32797
client  | 5.326 DEBUG [Agent 0x5653c3fa7810] state -> InProgress
client  | 5.326 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 5.326 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865 min_pn=0
client  | 5.326 DEBUG [Crypto] Handshake keys installed
client  | 5.326 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 833, tv_nsec: 394969940 }
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] State change from WaitVersion -> Handshaking
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] process saved for epoch Handshake
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] pn=1 type=Handshake pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 81
client  |   -> RX Crypto { offset: 142, len: 36 }
client  | 5.326 DEBUG unmark 0-length range at 0
client  | 5.326 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 832, tv_nsec: 644718066 }
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.326 DEBUG Building Initial dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] pn=14 type=Initial pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 55
client  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 59, first_ack_range: 1, ack_ranges: [AckRange { gap: 3, range: 1 }], ecn_count: None }
client  | 5.326 DEBUG Building Handshake dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] pn=0 type=Handshake pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 101
client  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 93840, first_ack_range: 0, ack_ranges: [], ecn_count: None }
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] Drop packet number space in
client  | 5.326 DEBUG [recovery::Loss] Reset loss recovery state for Initial
client  | 5.326 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
sim     | Dropping 1200 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 22/65 (33.8%)
client  | 5.326 INFO [recovery::Loss] ignoring in-14 from dropped space
client  | 5.326 DEBUG [recovery::Loss] PTO timer fired for ApplicationData
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] Lost: Stream(Stream(RecoveryToken { id: StreamId(0), offset: 0, length: 17, fin: true }))
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(ApplicationData), probe: EnumSet(Handshake | ApplicationData), paced: false }
client  | 5.326 DEBUG Building Handshake dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] pn=9 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 58
client  |   TX -> Stream { stream_id: 0, offset: 0, len: 17, fin: true }
client  | 5.326 DEBUG packet_sent this=0x5653c3fbaaa0, pn=9, ps=58
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: Some(ApplicationData), probe: EnumSet(Handshake | ApplicationData), paced: false }
client  | 5.326 DEBUG Building Handshake dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] pn=10 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 39
client  |   TX -> Ping
client  | 5.326 DEBUG packet_sent this=0x5653c3fbaaa0, pn=10, ps=41
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.326 DEBUG Building Handshake dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] delay duration 144.114617ms
client  | 5.326 DEBUG Setting timeout of 144.114617ms
client  | 5.326 DEBUG StateChange(Handshaking)
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.326 DEBUG Building Handshake dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.326 DEBUG TX blocked, profile=SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.326 DEBUG [Client f34c4f1c6bd47cc0] delay duration 144.079021ms
client  | 5.326 DEBUG Setting timeout of 144.079021ms
sim     | Forwarding 101 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 10/51 (19.6%)
sim     | Forwarding 58 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 10/52 (19.2%)
sim     | Forwarding 41 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 10/53 (18.9%)
sim     | Forwarding 45 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 22/66 (33.3%)
sim     | Forwarding 45 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 22/67 (32.8%)
sim     | Forwarding 45 bytes 193.167.100.100:443 -> 193.167.0.100:57000, dropped 22/68 (32.4%)
client  | 5.357 DEBUG [Client f34c4f1c6bd47cc0] pn=2 type=Handshake pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 45
client  |   -> RX ConnectionClose { error_code: Transport(10), frame_type: 0, reason_phrase: "" }
client  | 5.357 INFO [Client f34c4f1c6bd47cc0] ConnectionClose received. Error code: Transport(10) frame type 0 reason 
client  | 5.357 DEBUG [Client f34c4f1c6bd47cc0] State change from Handshaking -> Draining { error: Transport(Peer(10)), timeout: Instant { tv_sec: 833, tv_nsec: 642971092 } }
client  | 5.357 DEBUG [Client f34c4f1c6bd47cc0] output_path send_profile SendProfile { limit: 1252, pto: None, probe: EnumSet(), paced: false }
client  | 5.357 DEBUG Building Handshake dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.357 DEBUG [Recvd-hs] immediate_ack at Instant { tv_sec: 833, tv_nsec: 426460886 }
client  | 5.357 DEBUG [Client f34c4f1c6bd47cc0] pn=1 type=Handshake pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 47
client  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 5, first_ack_range: 1, ack_ranges: [], ecn_count: None }
client  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
client  | 5.357 DEBUG Building ZeroRtt dcid Some(CID [12]: abcd816c2ffb2981ba9e1643) scid Some(CID [0]: )
client  | 5.357 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 833, tv_nsec: 426460886 }
client  | 5.357 DEBUG [Client f34c4f1c6bd47cc0] pn=11 type=ZeroRtt pri-path:abcd816c2ffb2981ba9e1643 0.0.0.0:57000->193.167.100.100:443 Tos(Cs0, NotEct) len 89
client  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
client  | 5.357 DEBUG Setting timeout of 216.426641ms
client  | 5.357 WARN Unhandled event StateChange(Draining { error: Transport(Peer(10)), timeout: Instant { tv_sec: 833, tv_nsec: 642971092 } })
client  | 5.357 DEBUG Setting timeout of 216.415851ms
client  | Error: Transport(Peer(10))
sim     | Forwarding 89 bytes 193.167.0.100:57000 -> 193.167.100.100:443, dropped 10/54 (18.5%)

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