2025-04-30 12:25:18,078 Generated random file: dnxuqavbgs of size: 5120
2025-04-30 12:25:18,078 Generated random file: mdddzhvnsi of size: 10240
2025-04-30 12:25:18,078 Requests: https://server4:443/dnxuqavbgs https://server4:443/mdddzhvnsi
2025-04-30 12:25:18,138 
2025-04-30 12:25:18,139 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_q6mrbmjb/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_d7ty5k20/ DOWNLOADS=/tmp/download_lczckpu7/ SERVER_LOGS=/tmp/logs_server_1kqv1nyu CLIENT_LOGS=/tmp/logs_client_f7gs6nht SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=litespeedtech/lsquic-qir:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/dnxuqavbgs https://server4:443/mdddzhvnsi"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:25:30,189  Container sim  Created
 Container client  Recreate
 Container server  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksumming: off
client  | 	tx-checksum-ip-generic: off
client  | 	tx-checksum-sctp: off
client  | tcp-segmentation-offload: off
client  | 	tx-tcp-segmentation: off [requested on]
client  | 	tx-tcp-ecn-segmentation: off [requested on]
client  | 	tx-tcp-mangleid-segmentation: off [requested on]
client  | 	tx-tcp6-segmentation: off [requested on]
client  | tx-udp-segmentation: off [requested on]
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | Setting up routes...
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
server  | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/neqo/bin
server  | + '[' -n resumption ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.2AevisCMyP
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.2AevisCMyP
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.2AevisCMyP -W ''
server  | pk12util: PKCS12 IMPORT SUCCESSFUL
server  | + certutil -L -d sql:/neqo/db -n cert
server  | Certificate:
server  |     Data:
server  |         Version: 3 (0x2)
server  |         Serial Number:
server  |             37:39:2b:af:6a:fd:b9:e6:82:47:7e:f9:73:3e:50:b3:
server  |             40:92:83:e1
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner Root Certificate Authority"
server  |         Validity:
server  |             Not Before: Wed Apr 30 12:25:18 2025
server  |             Not After : Sat May 10 12:25:18 2025
server  |         Subject: "O=interop runner leaf"
server  |         Subject Public Key Info:
server  |             Public Key Algorithm: X9.62 elliptic curve public key
server  |                 Args:
server  |                     06:08:2a:86:48:ce:3d:03:01:07
server  |             EC Public Key:
server  |                 PublicValue:
server  |                     04:1d:f1:07:93:b7:14:c1:ed:0f:73:c4:ab:f6:d1:95:
server  |                     5a:db:d5:f9:ff:67:1e:87:f8:b3:57:96:46:32:14:eb:
server  |                     88:3b:c1:64:00:f2:f2:49:28:a4:4c:8b:48:43:bb:24:
server  |                     e8:3d:f6:c9:99:b0:5e:c4:3d:6f:5d:f4:1e:90:b6:50:
server  |                     78
server  |                 Curve: ANSI X9.62 elliptic curve prime256v1 (aka secp256r1, NIST P-256)
server  |         Signed Extensions:
server  |             Name: Certificate Subject Alt Name
server  |             DNS name: "server"
server  |             DNS name: "server4"
server  |             DNS name: "server6"
server  |             DNS name: "server46"
server  | 
server  |             Name: Certificate Subject Key ID
server  |             Data:
server  |                 9c:85:5c:99:6e:50:c1:50:c6:b2:a7:5c:b3:a7:2f:ed:
server  |                 7f:a6:6d:53
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 8a:f8:7e:93:8e:d3:d9:7b:05:49:33:a6:dc:3f:b9:00:
server  |                 22:7c:4d:8d
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:d3:82:c1:32:00:d4:06:d8:42:8e:71:
server  |         a3:1e:43:02:be:3e:28:ed:98:d1:66:59:1b:71:e2:28:
server  |         8a:3c:10:76:03:02:21:00:af:d2:82:72:6b:c8:56:41:
server  |         7a:3a:f3:4e:f6:53:ec:2b:79:bb:ea:8e:f4:d2:1e:72:
server  |         c5:30:bd:21:60:b1:42:61
server  |     Fingerprint (SHA-256):
server  |         CB:76:E7:DE:2E:44:95:1A:CC:DB:89:1D:C9:55:B2:48:BB:7B:D7:E5:2F:49:6C:C7:B4:97:4E:5A:7D:CD:82:26
server  |     Fingerprint (SHA1):
server  |         FD:6E:7A:29:B9:BB:6F:F0:EE:7E:CA:87:89:DA:4E:C9:5B:0A:45:15
server  | 
server  |     Mozilla-CA-Policy: false (attribute missing)
server  |     Certificate Trust Flags:
server  |         SSL Flags:
server  |             User
server  |         Email Flags:
server  |             User
server  |         Object Signing Flags:
server  |             User
server  | 
server  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT")
server  | + '[' resumption = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test resumption --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443'
server  | ++ tee -i -a /logs/server.log
server  | 0.000 DEBUG Logging initialized
server  | 0.002 DEBUG Default socket send buffer size is 212992
server  | 0.002 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.002 INFO Server waiting for connection on: [::]:443
server  | 0.768 DEBUG [Server] Unsupported version: 57414954
server  | 0.768 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:56944 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.037122841s
server  | 0.768 DEBUG [Server] Unsupported version: 57414954
server  | 0.768 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:56944 IpTos(Cs0, NotEct) len 15
server  | 0.768 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.768 DEBUG [Server] Unsupported version: 57414954
server  | 0.768 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:56944 IpTos(Cs0, NotEct) len 15
server  | 0.768 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | TEST_PARAMS:
client  | REQUESTS: 'https://server4:443/dnxuqavbgs https://server4:443/mdddzhvnsi'
client  | paths: -p /dnxuqavbgs -p /mdddzhvnsi
client  | server: server4
client  | port: 443
client  | CLIENT_PARAMS:
server  | 0.987 DEBUG [Server] Handle initial
server  | 0.987 INFO AddressValidation: no token; accepting
server  | 0.987 INFO [Server] Accept connection CID [18]: 6d3c8a4968972f6f1e7974836d899262cb68
server  | 0.987 DEBUG Overwrite initial version Version1 ==> Version1
server  | 0.988 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 0.988 INFO [Server ...] Received valid Initial packet with scid CID [8]: f292d7b111f84a6a dcid CID [18]: 6d3c8a4968972f6f1e7974836d899262cb68
server  | 0.988 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=6d3c8a4968972f6f1e7974836d899262cb68
server  | 0.988 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.988 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 0.988 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] State change from Init -> WaitInitial
server  | 0.988 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 1200
server  |   -> RX Crypto { offset: 0, len: 248 }
server  |   -> RX Padding { len: 895 }
server  | 0.988 DEBUG Read Ok(248) bytes
server  | 0.988 DEBUG 0-RTT: no token, no 0-RTT
server  | 0.989 DEBUG Read secret available for Handshake: SymKey [32]: d4962adf8ed362622440cfee04dc33626c88eff36b0e1943ca64d323cb7ee51a
server  | 0.989 DEBUG Write secret available for Handshake: SymKey [32]: 39812367add2ec04fd7d850c0de086bfde85c7410560d3752de76fc3c8f2c973
server  | 0.989 DEBUG Writing transport parameters, msg=8
server  | 0.989 DEBUG Read secret available for ApplicationData: SymKey [32]: 84c300bf64312692ed702bf81e7c9fcd72c411ab01a4e14dfb49868b1fe91611
server  | 0.989 DEBUG Write secret available for ApplicationData: SymKey [32]: 1ad7eb9d6516750012ef20d2dd1293fd5382486f64f32d4eb51e833208c708ed
server  | 0.989 DEBUG [Agent 0x560ce04731d0] state -> InProgress
server  | 0.989 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.989 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 0.989 DEBUG [Crypto] Handshake keys installed
server  | 0.989 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 0.989 DEBUG [Crypto] Application write key installed
server  | 0.989 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 388, tv_nsec: 19704359 }
server  | 0.989 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:51953] Make permanent
server  | 0.989 DEBUG [unv-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953] set as primary path
server  | 0.989 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] State change from WaitInitial -> Handshaking
server  | 0.989 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.989 DEBUG Building Initial dcid Some(CID [8]: f292d7b111f84a6a) scid Some(CID [10]: c3cc7c7576e3be3805d3)
server  | 0.989 DEBUG CRYPTO for in offset=0, len=90
server  | 0.989 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=0 type=Initial pri-unv-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 147
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 0.989 DEBUG Building Handshake dcid Some(CID [8]: f292d7b111f84a6a) scid Some(CID [10]: c3cc7c7576e3be3805d3)
server  | 0.989 DEBUG CRYPTO for hs offset=0, len=734
server  | 0.989 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=0 type=Handshake pri-unv-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 929
server  |   TX -> Crypto { offset: 0, len: 734 }
server  | 0.989 DEBUG packet_sent this=0x560ce0472b40, pn=0, ps=782
server  | 0.989 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 0.989 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pad Initial from 929 to PLPMTU 1232
server  | 0.989 DEBUG packet_sent this=0x560ce0472b40, pn=0, ps=450
server  | 0.989 DEBUG ECN probing: sent 1 probes
server  | 0.990 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.990 DEBUG Building Initial dcid Some(CID [8]: f292d7b111f84a6a) scid Some(CID [10]: c3cc7c7576e3be3805d3)
server  | 0.990 DEBUG Building Handshake dcid Some(CID [8]: f292d7b111f84a6a) scid Some(CID [10]: c3cc7c7576e3be3805d3)
server  | 0.990 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 0.990 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.990 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 297.046346ms
server  | 0.990 DEBUG Setting timeout of 297.046346ms
server  | 0.990 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.990 DEBUG Building Initial dcid Some(CID [8]: f292d7b111f84a6a) scid Some(CID [10]: c3cc7c7576e3be3805d3)
server  | 0.990 DEBUG Building Handshake dcid Some(CID [8]: f292d7b111f84a6a) scid Some(CID [10]: c3cc7c7576e3be3805d3)
server  | 0.990 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 0.990 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 0.990 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 297.012432ms
server  | 0.990 DEBUG Setting timeout of 297.012432ms
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=1 type=Initial pri-unv-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 1035
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 195, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 983 }
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Rx ACK space=in, ranges=[0..=0]
server  | 1.024 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 1.024 DEBUG on_packets_acked this=0x560ce0472b40, limited=1, bytes_in_flight=782, cwnd=12320, state=SlowStart, new_acked=450
server  | 1.024 DEBUG Acked crypto frame space=in offset=0 length=90
server  | 1.024 DEBUG [pri-unv-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953] Path validated Instant { tv_sec: 388, tv_nsec: 56960088 }
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Drop packet number space in
server  | 1.024 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=2 type=Handshake pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 89
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 92, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Rx ACK space=hs, ranges=[0..=0]
server  | 1.024 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.024 DEBUG on_packets_acked this=0x560ce0472b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=782
server  | 1.024 DEBUG Acked crypto frame space=hs offset=0 length=734
server  | 1.024 DEBUG Read Ok(36) bytes
server  | 1.024 DEBUG [Agent 0x560ce04731d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] TLS connection complete
server  | 1.024 DEBUG [pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953] Path validated Instant { tv_sec: 388, tv_nsec: 56960088 }
server  | 1.024 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.024 DEBUG [Crypto] application read keys installed
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] State change from Handshaking -> Connected
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] State change from Connected -> Confirmed
server  | 1.024 DEBUG PMTUD started with probe size 1380
server  | 1.024 INFO [Server 6d3c8a4968972f6f1e7974836d899262cb68] Connection established
server  | 1.024 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 388, tv_nsec: 56960088 }
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=3 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 76
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [79, 150, 65, 219, 99, 243, 95, 48], stateless_reset_token: [163, 147, 136, 230, 196, 197, 48, 101, 52, 197, 240, 250, 223, 160, 174, 117] }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.024 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 388, tv_nsec: 56960088 }
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.024 DEBUG Building Handshake dcid Some(CID [8]: f292d7b111f84a6a) scid Some(CID [10]: c3cc7c7576e3be3805d3)
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=1 type=Handshake pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 52
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Drop packet number space hs
server  | 1.024 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.024 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.024 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=0 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [52, 95, 13, 81, 215, 91, 23, 66, 191, 158], stateless_reset_token: [25, 82, 215, 153, 131, 173, 69, 166, 31, 241, 92, 227, 152, 34, 4, 64] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [238, 34, 177, 1, 150, 150, 135, 195, 237, 92], stateless_reset_token: [190, 50, 113, 222, 211, 13, 162, 134, 118, 154, 13, 177, 102, 30, 252, 206] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [60, 93, 156, 215, 98, 39, 187, 73, 9, 10], stateless_reset_token: [73, 7, 4, 173, 221, 202, 199, 169, 48, 88, 93, 229, 103, 77, 34, 231] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [186, 187, 191, 200, 50, 9, 168, 101, 177, 58], stateless_reset_token: [157, 213, 141, 197, 75, 224, 237, 20, 111, 224, 248, 160, 214, 190, 208, 13] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [203, 236, 180, 228, 106, 206, 246, 33, 251, 184], stateless_reset_token: [149, 165, 49, 10, 195, 113, 43, 49, 23, 235, 237, 204, 58, 151, 81, 77] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [61, 65, 204, 161, 183, 131, 243, 79, 240, 96], stateless_reset_token: [143, 184, 202, 15, 78, 210, 184, 130, 54, 53, 167, 147, 202, 218, 92, 244] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [115, 185, 85, 117, 145, 104, 164, 94, 203, 110], stateless_reset_token: [82, 37, 186, 102, 215, 148, 162, 55, 176, 42, 80, 190, 84, 110, 249, 9] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false }
server  |   TX -> Padding { len: 1028 }
server  | 1.024 DEBUG packet_sent this=0x560ce0472b40, pn=0, ps=1280
server  | 1.024 DEBUG ECN probing: sent 2 probes
server  | 1.025 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.025 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.025 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.025 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=1 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 1.025 DEBUG packet_sent this=0x560ce0472b40, pn=1, ps=1332
server  | 1.025 DEBUG ECN probing: sent 3 probes
server  | 1.025 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.025 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.025 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.025 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 1.285648ms
server  | 1.025 DEBUG Setting timeout of 1.285648ms
server  | 1.026 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] send session ticket 406d00126d3c8a4968972f6f1e7974836d899262cb680104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ac3cc7c7576e3be3805d3110c000000014a3a0aba000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.026 DEBUG Path = 'dnxuqavbgs'
server  | 1.026 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.026 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.026 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.026 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 152.409µs
server  | 1.026 DEBUG Setting timeout of 152.409µs
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.027 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=2 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false }
server  | 1.027 DEBUG packet_sent this=0x560ce0472b40, pn=2, ps=1232
server  | 1.027 DEBUG ECN probing: sent 4 probes
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.027 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=3 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false }
server  | 1.027 DEBUG packet_sent this=0x560ce0472b40, pn=3, ps=1232
server  | 1.027 DEBUG ECN probing: sent 5 probes
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 1.752971ms
server  | 1.027 DEBUG Setting timeout of 1.752971ms
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.027 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.027 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 1.72574ms
server  | 1.027 DEBUG Setting timeout of 1.72574ms
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.030 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=4 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false }
server  | 1.030 DEBUG packet_sent this=0x560ce0472b40, pn=4, ps=1232
server  | 1.030 DEBUG ECN probing: sent 6 probes
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.030 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=5 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false }
server  | 1.030 DEBUG packet_sent this=0x560ce0472b40, pn=5, ps=1232
server  | 1.030 DEBUG ECN probing: sent 7 probes
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.030 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 1.798156ms
server  | 1.030 DEBUG Setting timeout of 1.798156ms
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.030 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.030 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.030 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 1.780512ms
server  | 1.030 DEBUG Setting timeout of 1.780512ms
server  | 1.032 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.032 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.032 DEBUG CRYPTO for ap offset=0, len=321
server  | 1.032 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=6 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 712
server  |   TX -> Stream { stream_id: 0, offset: 4810, len: 310, fin: true }
server  |   TX -> Crypto { offset: 0, len: 321 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 154, 100, 56, 177, 22, 92, 30, 45, 232, 138, 65, 146, 80, 139, 85, 136, 13, 226, 94, 89, 246, 27, 114, 181, 244, 224, 8, 167, 87, 44, 193, 17, 155, 83, 63, 103] }
server  | 1.032 DEBUG packet_sent this=0x560ce0472b40, pn=6, ps=712
server  | 1.032 DEBUG ECN probing: sent 8 probes
server  | 1.033 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.033 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.033 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.033 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 118.061125ms
server  | 1.033 DEBUG Setting timeout of 118.061125ms
server  | 1.033 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.033 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.033 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.033 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 118.038353ms
server  | 1.033 DEBUG Setting timeout of 118.038353ms
client  | first request successful, new args: 1; 1; -p /mdddzhvnsi
server  | 1.058 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=4 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 5, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 1.058 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Rx ACK space=ap, ranges=[0..=1]
server  | 1.058 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.058 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5640/14932 ssthresh 18446744073709551615] slow start += 2612
server  | 1.058 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5640/14932 ssthresh 18446744073709551615] on_packets_acked this=0x560ce0472b40, limited=0, bytes_in_flight=5640, cwnd=14932, state=SlowStart, new_acked=2612
server  | 1.058 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.058 DEBUG PMTUD started with probe size 1420
server  | 1.058 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.058 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.058 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.058 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.058 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=7 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1345 }
server  | 1.059 DEBUG packet_sent this=0x560ce0472b40, pn=7, ps=1372
server  | 1.059 DEBUG ECN probing: sent 9 probes
server  | 1.059 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.059 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.059 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 91.928629ms
server  | 1.059 DEBUG Setting timeout of 91.928629ms
server  | 1.059 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.059 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.059 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.059 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 91.897099ms
server  | 1.059 DEBUG Setting timeout of 91.897099ms
server  | 1.060 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=5 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 5, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 1.060 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Rx ACK space=ap, ranges=[0..=3]
server  | 1.060 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 1.060 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4548/17396 ssthresh 18446744073709551615] slow start += 2464
server  | 1.060 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 4548/17396 ssthresh 18446744073709551615] on_packets_acked this=0x560ce0472b40, limited=0, bytes_in_flight=4548, cwnd=17396, state=SlowStart, new_acked=2464
server  | 1.060 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.060 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.060 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.060 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 81.854744ms
server  | 1.060 DEBUG Setting timeout of 81.854744ms
server  | 1.060 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.060 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.060 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.060 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 81.791015ms
server  | 1.060 DEBUG Setting timeout of 81.791015ms
server  | 1.063 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=6 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 6, first_ack_range: 5, ack_ranges: [], ecn_count: None }
server  | 1.063 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Rx ACK space=ap, ranges=[0..=5]
server  | 1.063 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5
server  | 1.063 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2084/19860 ssthresh 18446744073709551615] slow start += 2464
server  | 1.063 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2084/19860 ssthresh 18446744073709551615] on_packets_acked this=0x560ce0472b40, limited=0, bytes_in_flight=2084, cwnd=19860, state=SlowStart, new_acked=2464
server  | 1.063 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 73.514022ms
server  | 1.063 DEBUG Setting timeout of 73.514022ms
server  | 1.063 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.063 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.063 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 73.456876ms
server  | 1.063 DEBUG Setting timeout of 73.456876ms
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=7 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 10, first_ack_range: 6, ack_ranges: [], ecn_count: None }
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] Rx ACK space=ap, ranges=[0..=6]
server  | 1.064 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6
server  | 1.064 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20572 ssthresh 18446744073709551615] slow start += 712
server  | 1.064 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20572 ssthresh 18446744073709551615] on_packets_acked this=0x560ce0472b40, limited=0, bytes_in_flight=1372, cwnd=20572, state=SlowStart, new_acked=712
server  | 1.064 DEBUG Acked crypto frame space=ap offset=0 length=321
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=8 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect0) len 28
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.064 DEBUG packet_sent this=0x560ce0472b40, pn=8, ps=29
server  | 1.064 DEBUG ECN probing: sent 10 probes
server  | 1.064 DEBUG ECN probing concluded with 10 probes sent
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.064 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] delay duration 73.625697ms
server  | 1.064 DEBUG Setting timeout of 73.625697ms
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=8 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, Ect1) len 32
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  | 1.064 INFO [Server 6d3c8a4968972f6f1e7974836d899262cb68] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 388, tv_nsec: 318468151 } }
server  | 1.064 DEBUG [pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953] Path validated Instant { tv_sec: 388, tv_nsec: 97237840 }
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.064 DEBUG Building Short dcid Some(CID [8]: f292d7b111f84a6a)
server  | 1.064 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 388, tv_nsec: 97237840 }
server  | 1.064 DEBUG [Server 6d3c8a4968972f6f1e7974836d899262cb68] pn=9 type=Short pri-path:f292d7b111f84a6a [::]:443->[::ffff:193.167.0.100]:51953 IpTos(Cs0, NotEct) len 38
server  |   TX -> Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 6, Ect0: 0, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 1.064 DEBUG Setting timeout of 221.136265ms
server  | 1.064 DEBUG Setting timeout of 221.128361ms
server  | 1.070 DEBUG [Server] Handle initial
server  | 1.070 INFO AddressValidation: no token; accepting
server  | 1.070 INFO [Server] Accept connection CID [16]: 4c7a927efaabea0a2d2621e118398f4d
server  | 1.070 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.072 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.072 INFO [Server ...] Received valid Initial packet with scid CID [8]: c14b847d790a92e4 dcid CID [16]: 4c7a927efaabea0a2d2621e118398f4d
server  | 1.072 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=4c7a927efaabea0a2d2621e118398f4d
server  | 1.072 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.072 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.072 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] State change from Init -> WaitInitial
server  | 1.072 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 1130
server  |   -> RX Crypto { offset: 0, len: 589 }
server  |   -> RX Padding { len: 486 }
server  | 1.072 DEBUG Read Ok(589) bytes
server  | 1.072 INFO 0-RTT: transport parameters OK, passing to application checker
server  | 1.072 WARN AllowZeroRtt accepting 0-RTT
server  | 1.072 DEBUG Read secret available for ZeroRtt: SymKey [32]: 99b71dc692261d8a49adeeb7be8c668c8f8ea5e0512a9377beff7b2adeab7d6a
server  | 1.072 DEBUG Read secret available for Handshake: SymKey [32]: aa170d7c144b514656e0ce251d60ddca00108d2f80accf6ca9663fc9dfa80db3
server  | 1.072 DEBUG Write secret available for Handshake: SymKey [32]: 5814495eebdfb6f2f49070f8aebf1056f41d7bcd0ec788bfae26e819fb3ce8ff
server  | 1.072 DEBUG Writing transport parameters, msg=8
server  | 1.072 DEBUG Read secret available for ApplicationData: SymKey [32]: f0b8678a0303a3a3c550f2dc411d61dc0f7d3ddc7006524dbd90c778e5ea1062
server  | 1.072 DEBUG Write secret available for ApplicationData: SymKey [32]: 3d8bd706b4e46ab2944ac0837ae61da2e3ccb20901ecd20866d08e5eede90d26
server  | 1.072 DEBUG [Agent 0x560ce04a6c70] state -> InProgress
server  | 1.072 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.072 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.072 DEBUG [Crypto] Handshake keys installed
server  | 1.072 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.073 DEBUG [Crypto] Application write key installed
server  | 1.073 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 388, tv_nsec: 103599899 }
server  | 1.073 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:47677] Make permanent
server  | 1.073 DEBUG [unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677] set as primary path
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] State change from WaitInitial -> Handshaking
server  | 1.073 DEBUG Making Read ZeroRtt CryptoDxState, v=Version1 cipher=4865
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Accepted 0-RTT
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=1 type=ZeroRtt pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 70
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.073 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 388, tv_nsec: 103599899 }
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.073 DEBUG Building Initial dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG CRYPTO for in offset=0, len=96
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=0 type=Initial pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 153
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 96 }
server  | 1.073 DEBUG Building Handshake dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG CRYPTO for hs offset=0, len=186
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=0 type=Handshake pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 387
server  |   TX -> Crypto { offset: 0, len: 186 }
server  | 1.073 DEBUG packet_sent this=0x560ce03b0840, pn=0, ps=234
server  | 1.073 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=0 type=Short pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 421
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pad Initial from 421 to PLPMTU 1232
server  | 1.073 DEBUG packet_sent this=0x560ce03b0840, pn=0, ps=964
server  | 1.073 DEBUG ECN probing: sent 1 probes
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.073 DEBUG Building Initial dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Handshake dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.073 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 297.710939ms
server  | 1.073 DEBUG Setting timeout of 212.579191ms
server  | 1.073 DEBUG Path = 'mdddzhvnsi'
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.073 DEBUG Building Initial dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Handshake dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=1 type=Short pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false }
server  | 1.073 DEBUG packet_sent this=0x560ce03b0840, pn=1, ps=1232
server  | 1.073 DEBUG ECN probing: sent 2 probes
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1136, pto: None, probe: EnumSet(), paced: false }
server  | 1.073 DEBUG Building Initial dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Handshake dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=2 type=Short pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1136
server  |   TX -> Stream { stream_id: 0, offset: 1204, len: >>1106, fin: false }
server  | 1.073 DEBUG packet_sent this=0x560ce03b0840, pn=2, ps=1136
server  | 1.073 DEBUG ECN probing: sent 3 probes
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.073 DEBUG Building Initial dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Handshake dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.073 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.073 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.073 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 29.997499293s
server  | 1.073 DEBUG Setting timeout of 212.367545ms
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=2 type=Initial pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 1056
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 150, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 1004 }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Rx ACK space=in, ranges=[0..=0]
server  | 1.107 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 1.107 DEBUG on_packets_acked this=0x560ce03b0840, limited=1, bytes_in_flight=2602, cwnd=12320, state=SlowStart, new_acked=964
server  | 1.107 DEBUG Acked crypto frame space=in offset=0 length=96
server  | 1.107 DEBUG [pri-unv-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677] Path validated Instant { tv_sec: 388, tv_nsec: 139913548 }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Drop packet number space in
server  | 1.107 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=3 type=Handshake pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 88
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 53, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Rx ACK space=hs, ranges=[0..=0]
server  | 1.107 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.107 DEBUG on_packets_acked this=0x560ce03b0840, limited=1, bytes_in_flight=2368, cwnd=12320, state=SlowStart, new_acked=234
server  | 1.107 DEBUG Acked crypto frame space=hs offset=0 length=186
server  | 1.107 DEBUG Read Ok(36) bytes
server  | 1.107 DEBUG [Agent 0x560ce04a6c70] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: true, early_data: true, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] TLS connection complete
server  | 1.107 DEBUG [pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677] Path validated Instant { tv_sec: 388, tv_nsec: 139913548 }
server  | 1.107 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.107 DEBUG [Crypto] application read keys installed
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] State change from Handshaking -> Connected
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] State change from Connected -> Confirmed
server  | 1.107 DEBUG PMTUD started with probe size 1380
server  | 1.107 INFO [Server 4c7a927efaabea0a2d2621e118398f4d] Connection established
server  | 1.107 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 388, tv_nsec: 139913548 }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=4 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 56
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [40, 133, 85, 101, 248, 154, 163, 233], stateless_reset_token: [191, 215, 227, 113, 119, 247, 5, 149, 8, 218, 29, 230, 233, 134, 215, 145] }
server  | 1.107 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 388, tv_nsec: 139913548 }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.107 DEBUG Building Handshake dcid Some(CID [8]: c14b847d790a92e4) scid Some(CID [10]: 3caf4614e11404d80ea9)
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=1 type=Handshake pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 52
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Drop packet number space hs
server  | 1.107 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.107 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=3 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 1, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) }
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [178, 3, 87, 232, 28, 221, 81, 175, 206, 124], stateless_reset_token: [173, 17, 162, 44, 134, 163, 36, 116, 47, 16, 34, 121, 162, 25, 208, 148] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [213, 172, 84, 14, 25, 140, 93, 31, 98, 64], stateless_reset_token: [44, 212, 254, 154, 18, 203, 250, 29, 128, 195, 203, 79, 2, 29, 253, 87] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [241, 250, 21, 155, 176, 108, 219, 60, 53, 239], stateless_reset_token: [93, 97, 57, 64, 168, 41, 46, 106, 8, 247, 71, 212, 92, 24, 76, 8] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [53, 251, 47, 85, 42, 202, 43, 22, 214, 196], stateless_reset_token: [93, 223, 251, 132, 98, 88, 189, 79, 131, 17, 249, 239, 244, 125, 3, 108] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [252, 213, 46, 55, 70, 87, 13, 179, 178, 33], stateless_reset_token: [188, 145, 211, 164, 172, 221, 42, 210, 174, 248, 205, 17, 18, 5, 231, 86] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [150, 58, 118, 223, 154, 169, 83, 83, 78, 213], stateless_reset_token: [51, 146, 83, 113, 75, 173, 10, 40, 223, 68, 27, 73, 7, 99, 147, 204] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [190, 201, 151, 191, 183, 15, 236, 234, 89, 56], stateless_reset_token: [1, 100, 220, 37, 181, 142, 86, 98, 72, 225, 109, 189, 109, 230, 75, 217] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 10000, ignore_order: false }
server  |   TX -> Stream { stream_id: 0, offset: 2310, len: >>1022, fin: false }
server  | 1.107 DEBUG packet_sent this=0x560ce03b0840, pn=3, ps=1280
server  | 1.107 DEBUG ECN probing: sent 4 probes
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.107 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.107 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=4 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 3332, len: >>1301, fin: false }
server  | 1.107 DEBUG packet_sent this=0x560ce03b0840, pn=4, ps=1332
server  | 1.107 DEBUG ECN probing: sent 5 probes
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.107 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.107 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 1.441704ms
server  | 1.107 DEBUG Setting timeout of 1.441704ms
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=5 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 33
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 6, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Rx ACK space=ap, ranges=[1..=2]
server  | 1.107 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 1.107 DEBUG on_packets_acked this=0x560ce03b0840, limited=1, bytes_in_flight=2612, cwnd=12320, state=SlowStart, new_acked=2368
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.107 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.107 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.107 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 1.404676ms
server  | 1.107 DEBUG Setting timeout of 1.404676ms
server  | 1.108 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] send session ticket 406b00104c7a927efaabea0a2d2621e118398f4d0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a3caf4614e11404d80ea9110c000000018a3afa9a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.108 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.108 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.108 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.108 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 1.259865ms
server  | 1.108 DEBUG Setting timeout of 1.259865ms
server  | 1.110 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.110 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.110 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=5 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 4633, len: >>1202, fin: false }
server  | 1.110 DEBUG packet_sent this=0x560ce03b0840, pn=5, ps=1232
server  | 1.110 DEBUG ECN probing: sent 6 probes
server  | 1.110 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.110 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.110 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 940.138µs
server  | 1.110 DEBUG Setting timeout of 940.138µs
server  | 1.110 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.110 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.110 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.110 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 912.386µs
server  | 1.110 DEBUG Setting timeout of 912.386µs
server  | 1.112 DEBUG [Cubic [last_max_cwnd: 12320, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3844/8624 ssthresh 8624] Cong event -> recovery; cwnd 8624, ssthresh 8624
server  | 1.112 DEBUG [Cubic [last_max_cwnd: 12320, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3844/8624 ssthresh 8624] state -> RecoveryStart
server  | 1.112 DEBUG on_packets_lost this=0x560ce03b0840, bytes_in_flight=3844, cwnd=8624, state=RecoveryStart
server  | 1.112 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Lost: Ack(AckToken { space: ApplicationData, ranges: [PacketRange { largest: 1, smallest: 1, ack_needed: true }] })
server  | 1.112 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.112 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.112 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=6 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 5835, len: >>1202, fin: false }
server  | 1.112 DEBUG packet_sent this=0x560ce03b0840, pn=6, ps=1232
server  | 1.112 DEBUG ECN probing: sent 7 probes
server  | 1.112 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 1.637123ms
server  | 1.112 DEBUG Setting timeout of 1.637123ms
server  | 1.112 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 1.610794ms
server  | 1.112 DEBUG Setting timeout of 1.610794ms
server  | 1.115 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.115 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=7 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 7037, len: >>1202, fin: false }
server  | 1.115 DEBUG packet_sent this=0x560ce03b0840, pn=7, ps=1232
server  | 1.115 DEBUG ECN probing: sent 8 probes
server  | 1.115 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.115 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.115 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.115 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 1.013932ms
server  | 1.115 DEBUG Setting timeout of 1.013932ms
server  | 1.115 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.115 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.115 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.115 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 987.813µs
server  | 1.115 DEBUG Setting timeout of 987.813µs
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=8 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 8239, len: >>1202, fin: false }
server  | 1.117 DEBUG packet_sent this=0x560ce03b0840, pn=8, ps=1232
server  | 1.117 DEBUG ECN probing: sent 9 probes
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1084, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.117 DEBUG CRYPTO for ap offset=0, len=249
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=9 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect0) len 1084
server  |   TX -> Stream { stream_id: 0, offset: 9441, len: 799, fin: true }
server  |   TX -> Crypto { offset: 0, len: 249 }
server  | 1.117 DEBUG packet_sent this=0x560ce03b0840, pn=9, ps=1084
server  | 1.117 DEBUG ECN probing: sent 10 probes
server  | 1.117 DEBUG ECN probing concluded with 10 probes sent
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.117 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 80.124296ms
server  | 1.117 DEBUG Setting timeout of 80.124296ms
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.117 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.117 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 80.107053ms
server  | 1.117 DEBUG Setting timeout of 80.107053ms
client  | /logs/C14B847D790A92E4.keys  /logs/F292D7B111F84A6A.keys
client  | /logs/keys.log exists
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=6 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 6, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Rx ACK space=ap, ranges=[1..=4]
server  | 1.140 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 1.140 WARN ECN validation failed, no ECN counts in ACK frame
server  | 1.140 DEBUG [Cubic [last_max_cwnd: 12320, k: 1.9574338205844317, w_max: 12320, ca_epoch_start: Some(Instant { tv_sec: 388, tv_nsec: 173176705 })] CongCtrl 6012/8624 ssthresh 8624] on_packets_acked this=0x560ce03b0840, limited=0, bytes_in_flight=6012, cwnd=8624, state=Recovery, new_acked=0
server  | 1.140 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.140 DEBUG PMTUD started with probe size 1420
server  | 1.140 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.140 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.140 DEBUG CRYPTO for ap offset=249, len=72
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=10 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, NotEct) len 1372
server  |   TX -> Ping
server  |   TX -> Crypto { offset: 249, len: 72 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 70, 28, 167, 116, 79, 245, 18, 67, 108, 255, 95, 204, 239, 159, 157, 96, 57, 239, 222, 226, 56, 195, 170, 158, 124, 58, 130, 35, 71, 248, 65, 70, 180, 63, 173, 221] }
server  |   TX -> Padding { len: 1223 }
server  | 1.140 DEBUG packet_sent this=0x560ce03b0840, pn=10, ps=1372
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1240, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.140 DEBUG TX blocked, profile=SendProfile { limit: 1240, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 57.275665ms
server  | 1.140 DEBUG Setting timeout of 57.275665ms
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1240, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.140 DEBUG TX blocked, profile=SendProfile { limit: 1240, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 57.211725ms
server  | 1.140 DEBUG Setting timeout of 57.211725ms
server  | 1.143 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=7 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 6, first_ack_range: 5, ack_ranges: [], ecn_count: None }
server  | 1.144 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Rx ACK space=ap, ranges=[1..=6]
server  | 1.144 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6
server  | 1.144 DEBUG [Cubic [last_max_cwnd: 12320, k: 1.9574338205844317, w_max: 12320, ca_epoch_start: Some(Instant { tv_sec: 388, tv_nsec: 173176705 })] CongCtrl 6152/8624 ssthresh 8624] state -> CongestionAvoidance
server  | 1.144 DEBUG [Cubic [last_max_cwnd: 12320, k: 1.9574338205844317, w_max: 12320, ca_epoch_start: Some(Instant { tv_sec: 388, tv_nsec: 173176705 })] CongCtrl 4920/8624 ssthresh 8624] on_packets_acked this=0x560ce03b0840, limited=0, bytes_in_flight=4920, cwnd=8624, state=CongestionAvoidance, new_acked=1232
server  | 1.144 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.144 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.144 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.144 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 54.086036ms
server  | 1.144 DEBUG Setting timeout of 54.086036ms
server  | 1.144 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.144 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.144 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.144 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 53.971953ms
server  | 1.144 DEBUG Setting timeout of 53.971953ms
server  | 1.149 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=8 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 5, first_ack_range: 7, ack_ranges: [], ecn_count: None }
server  | 1.149 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Rx ACK space=ap, ranges=[1..=8]
server  | 1.149 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8
server  | 1.149 DEBUG [Cubic [last_max_cwnd: 12320, k: 1.9574338205844317, w_max: 12320, ca_epoch_start: Some(Instant { tv_sec: 388, tv_nsec: 173176705 })] CongCtrl 2456/8624 ssthresh 8624] on_packets_acked this=0x560ce03b0840, limited=0, bytes_in_flight=2456, cwnd=8624, state=CongestionAvoidance, new_acked=2464
server  | 1.149 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.149 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.149 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.149 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 48.741891ms
server  | 1.149 DEBUG Setting timeout of 48.741891ms
server  | 1.149 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.149 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.149 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.149 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 48.667522ms
server  | 1.149 DEBUG Setting timeout of 48.667522ms
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=9 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 34
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 12, first_ack_range: 8, ack_ranges: [], ecn_count: None }
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] Rx ACK space=ap, ranges=[1..=9]
server  | 1.150 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9
server  | 1.150 DEBUG [Cubic [last_max_cwnd: 12320, k: 1.9574338205844317, w_max: 12320, ca_epoch_start: Some(Instant { tv_sec: 388, tv_nsec: 173176705 })] CongCtrl 1372/8624 ssthresh 8624] on_packets_acked this=0x560ce03b0840, limited=0, bytes_in_flight=1372, cwnd=8624, state=CongestionAvoidance, new_acked=1084
server  | 1.150 DEBUG Acked crypto frame space=ap offset=0 length=249
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.150 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=11 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, NotEct) len 28
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.150 DEBUG packet_sent this=0x560ce03b0840, pn=11, ps=29
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.150 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.150 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] delay duration 47.385173ms
server  | 1.150 DEBUG Setting timeout of 47.385173ms
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=10 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, Ect1) len 32
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  | 1.150 INFO [Server 4c7a927efaabea0a2d2621e118398f4d] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 388, tv_nsec: 382917597 } }
server  | 1.150 DEBUG [pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677] Path validated Instant { tv_sec: 388, tv_nsec: 183343944 }
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.150 DEBUG Building Short dcid Some(CID [8]: c14b847d790a92e4)
server  | 1.150 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 388, tv_nsec: 183343944 }
server  | 1.150 DEBUG [Server 4c7a927efaabea0a2d2621e118398f4d] pn=12 type=Short pri-path:c14b847d790a92e4 [::]:443->[::ffff:193.167.0.100]:47677 IpTos(Cs0, NotEct) len 38
server  |   TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 8, Ect0: 0, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 1.150 DEBUG Setting timeout of 135.030431ms
server  | 1.150 DEBUG Setting timeout of 135.022597ms

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

2025-04-30 12:25:30,283 Using the client's key log file.
2025-04-30 12:25:30,292 
2025-04-30 12:25:30,293 Using the client's key log file.
2025-04-30 12:25:30,293 Using the client's key log file.
2025-04-30 12:25:30,301 
2025-04-30 12:25:30,302 Using the client's key log file.
2025-04-30 12:25:30,302 Using the client's key log file.
2025-04-30 12:25:31,174 Check of downloaded files succeeded.