2025-04-30 09:12:55,239 Generated random file: jojrbhnyfu of size: 10240
2025-04-30 09:12:55,239 Requests: https://server4:443/jojrbhnyfu
2025-04-30 09:12:55,297 
2025-04-30 09:12:55,298 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_wp_80soj/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_ov4sas1v/ DOWNLOADS=/tmp/download_oavcfavb/ SERVER_LOGS=/tmp/logs_server_1l5pp8km CLIENT_LOGS=/tmp/logs_client_uo7cqjz6 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=stammw/quinn-interop:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/jojrbhnyfu"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 09:13:10,629  Container sim  Created
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
server  | Setting up routes...
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
client  | tx-tcp-segmentation: off [not requested]
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
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
server  | Endpoint's IPv4 address is 193.167.100.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | Executing client
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 retry ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.CLFI7T4EoV
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.CLFI7T4EoV
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.CLFI7T4EoV -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  |             57:45:9d:e8:57:db:a3:39:f7:26:ae:b9:c4:b9:a1:b7:
server  |             13:cd:59:4d
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner Root Certificate Authority"
server  |         Validity:
server  |             Not Before: Wed Apr 30 09:12:55 2025
server  |             Not After : Sat May 10 09:12:55 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:f4:12:fb:91:b6:13:29:8e:e9:64:f1:9c:6a:33:e2:
server  |                     9d:9d:2f:be:a1:6e:31:40:6b:1e:57:09:4f:bc:3b:b1:
server  |                     39:69:0c:86:14:19:5e:4f:24:59:5b:2c:11:ab:8b:3e:
server  |                     9e:12:f0:80:4c:b0:6b:f7:6d:fb:25:74:9e:4f:9d:0e:
server  |                     c1
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  |                 9d:2c:76:11:b8:8e:ed:09:b3:14:0b:e2:72:c3:58:15:
server  |                 2e:64:f8:4b
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 dc:83:c6:de:12:7a:23:84:e5:65:06:e5:af:72:9d:c8:
server  |                 a8:79:8d:5e
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:20:05:53:2f:62:cb:4f:ef:6b:bb:85:21:dc:
server  |         fe:2d:23:e9:ab:35:7d:74:bb:04:e1:e6:0b:5a:68:9a:
server  |         e5:5d:78:11:02:21:00:f1:51:dc:4b:66:a6:12:2e:d3:
server  |         d6:49:2b:16:a4:25:33:ce:d8:d2:10:8c:6b:e4:de:53:
server  |         f2:98:af:75:0a:cb:48
server  |     Fingerprint (SHA-256):
server  |         22:0E:A4:4E:5E:88:06:2C:81:C1:2E:B5:1A:D5:FC:BC:08:69:40:5A:C0:36:F3:AB:54:EA:2C:AE:41:54:3D:20
server  |     Fingerprint (SHA1):
server  |         2C:21:B1:6D:4E:DE:16:C7:35:24:7B:BC:1E:B9:E1:4B:77:96:16:83
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  | + '[' retry = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test retry --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.744 DEBUG [Server] Unsupported version: 57414954
server  | 0.744 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:54723 IpTos(Cs0, NotEct) len 15
server  | 0.744 DEBUG [Server] Unsupported version: 57414954
server  | 0.744 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:54723 IpTos(Cs0, NotEct) len 15
server  | 0.744 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.744 DEBUG [Server] Unsupported version: 57414954
server  | 0.744 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:54723 IpTos(Cs0, NotEct) len 15
server  | 0.744 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | server:443 is available after 1.002835361s
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: tcpdump: listening on eth0listening on eth1, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | , snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
server  | 3.996 DEBUG [Server] Handle initial
server  | 3.996 INFO AddressValidation: no token; validating
server  | 3.996 INFO [Server] Send retry for CID [20]: 24b8ae1d131cfdf76083f1c9ad4dbe70e9ea794e
server  | 3.996 DEBUG [Server] type=Retry path:24b8ae1d131cfdf76083f1c9ad4dbe70e9ea794e [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, NotEct) len 104
server  | 4.028 DEBUG [Server] Handle initial
server  | 4.028 INFO AddressValidation: valid Retry token for 24b8ae1d131cfdf76083f1c9ad4dbe70e9ea794e
server  | 4.028 INFO [Server] Accept connection CID [20]: 24b8ae1d131cfdf76083f1c9ad4dbe70e9ea794e
server  | 4.028 DEBUG Overwrite initial version Version1 ==> Version1
server  | 4.029 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 4.029 INFO [Server ...] Received valid Initial packet with scid CID [8]: 4a988881b8bcdffc dcid CID [10]: 49e8e39b1a547bd2ace7
server  | 4.029 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=49e8e39b1a547bd2ace7
server  | 4.029 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 4.029 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 4.029 DEBUG [Server 49e8e39b1a547bd2ace7] State change from Init -> WaitInitial
server  | 4.029 DEBUG [Server 49e8e39b1a547bd2ace7] pn=1 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1200
server  |   -> RX Crypto { offset: 0, len: 286 }
server  |   -> RX Padding { len: 802 }
server  | 4.030 DEBUG Read Ok(286) bytes
server  | 4.030 DEBUG 0-RTT: no token, no 0-RTT
server  | 4.030 DEBUG Read secret available for Handshake: SymKey [32]: 347428ce5d92a0449c8a3faa00023dd21d99fde45bfef83b0aff44f15c8b3d05
server  | 4.030 DEBUG Write secret available for Handshake: SymKey [32]: 84ab09df25769888288cb9c4025c2d01434db53528a6d9d43a7fb4f32b02f9c5
server  | 4.030 DEBUG Writing transport parameters, msg=8
server  | 4.030 DEBUG Read secret available for ApplicationData: SymKey [32]: 2d04f28486e2c33b79d4b4fa51039b7cc241233e87e344aaef09de6fa957c60a
server  | 4.030 DEBUG Write secret available for ApplicationData: SymKey [32]: 1c9669adc59f4f7a5e43d52e10f7a8a41e3a35f21578a76e4848e945f3134dd1
server  | 4.030 DEBUG [Agent 0x559a88a15710] state -> InProgress
server  | 4.030 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 4.030 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 4.030 DEBUG [Crypto] Handshake keys installed
server  | 4.030 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 4.030 DEBUG [Crypto] Application write key installed
server  | 4.030 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 307, tv_nsec: 121267828 }
server  | 4.030 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:43789] Make permanent
server  | 4.031 DEBUG [unv-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789] set as primary path
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] State change from WaitInitial -> Handshaking
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.031 DEBUG Building Initial dcid Some(CID [8]: 4a988881b8bcdffc) scid Some(CID [10]: 152f412bc7afcbdf13c4)
server  | 4.031 DEBUG CRYPTO for in offset=0, len=90
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] pn=0 type=Initial pri-unv-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 147
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 4.031 DEBUG Building Handshake dcid Some(CID [8]: 4a988881b8bcdffc) scid Some(CID [10]: 152f412bc7afcbdf13c4)
server  | 4.031 DEBUG CRYPTO for hs offset=0, len=747
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] pn=0 type=Handshake pri-unv-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 942
server  |   TX -> Crypto { offset: 0, len: 747 }
server  | 4.031 DEBUG packet_sent this=0x559a88a5cb40, pn=0, ps=795
server  | 4.031 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] pad Initial from 942 to PLPMTU 1232
server  | 4.031 DEBUG packet_sent this=0x559a88a5cb40, pn=0, ps=437
server  | 4.031 DEBUG ECN probing: sent 1 probes
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.031 DEBUG Building Initial dcid Some(CID [8]: 4a988881b8bcdffc) scid Some(CID [10]: 152f412bc7afcbdf13c4)
server  | 4.031 DEBUG Building Handshake dcid Some(CID [8]: 4a988881b8bcdffc) scid Some(CID [10]: 152f412bc7afcbdf13c4)
server  | 4.031 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.031 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 297.168934ms
server  | 4.031 DEBUG Setting timeout of 297.168934ms
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.031 DEBUG Building Initial dcid Some(CID [8]: 4a988881b8bcdffc) scid Some(CID [10]: 152f412bc7afcbdf13c4)
server  | 4.031 DEBUG Building Handshake dcid Some(CID [8]: 4a988881b8bcdffc) scid Some(CID [10]: 152f412bc7afcbdf13c4)
server  | 4.031 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.031 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.031 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 297.135662ms
server  | 4.031 DEBUG Setting timeout of 297.135662ms
server  | 4.063 DEBUG [Server 49e8e39b1a547bd2ace7] pn=2 type=Initial pri-unv-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 116
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 37, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 4.063 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=in, ranges=[0..=0]
server  | 4.063 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 4.063 DEBUG on_packets_acked this=0x559a88a5cb40, limited=1, bytes_in_flight=795, cwnd=12320, state=SlowStart, new_acked=437
server  | 4.063 DEBUG Acked crypto frame space=in offset=0 length=90
server  | 4.063 DEBUG [pri-unv-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789] Path validated Instant { tv_sec: 307, tv_nsec: 156857088 }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] Drop packet number space in
server  | 4.064 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] pn=0 type=Handshake pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1084
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 37, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  |   -> RX Padding { len: 993 }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=hs, ranges=[0..=0]
server  | 4.064 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 4.064 DEBUG on_packets_acked this=0x559a88a5cb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=795
server  | 4.064 DEBUG Acked crypto frame space=hs offset=0 length=747
server  | 4.064 DEBUG Read Ok(36) bytes
server  | 4.064 DEBUG [Agent 0x559a88a15710] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 29, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] TLS connection complete
server  | 4.064 DEBUG [pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789] Path validated Instant { tv_sec: 307, tv_nsec: 156857088 }
server  | 4.064 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 4.064 DEBUG [Crypto] application read keys installed
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] State change from Handshaking -> Connected
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] State change from Connected -> Confirmed
server  | 4.064 DEBUG PMTUD started with probe size 1380
server  | 4.064 INFO [Server 49e8e39b1a547bd2ace7] Connection established
server  | 4.064 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 307, tv_nsec: 156857088 }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.064 DEBUG Building Handshake dcid Some(CID [8]: 4a988881b8bcdffc) scid Some(CID [10]: 152f412bc7afcbdf13c4)
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] pn=1 type=Handshake pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 52
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] Drop packet number space hs
server  | 4.064 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 4.064 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] pn=0 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [15, 55, 167, 207, 209, 195, 237, 195, 130, 168], stateless_reset_token: [230, 14, 12, 244, 182, 160, 0, 56, 249, 13, 6, 40, 85, 114, 81, 121] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [167, 85, 65, 85, 254, 226, 208, 117, 250, 150], stateless_reset_token: [59, 68, 167, 255, 117, 3, 73, 93, 253, 136, 89, 148, 89, 61, 73, 248] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [16, 182, 87, 25, 233, 45, 66, 187, 203, 163], stateless_reset_token: [100, 232, 119, 124, 203, 238, 252, 63, 213, 119, 222, 52, 14, 207, 249, 27] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [78, 188, 206, 42, 26, 191, 194, 243, 171, 198], stateless_reset_token: [24, 93, 212, 210, 31, 152, 137, 196, 137, 196, 155, 229, 39, 26, 47, 41] }
server  |   TX -> Padding { len: 1133 }
server  | 4.064 DEBUG packet_sent this=0x559a88a5cb40, pn=0, ps=1280
server  | 4.064 DEBUG ECN probing: sent 2 probes
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.064 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.064 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] pn=1 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 4.064 DEBUG packet_sent this=0x559a88a5cb40, pn=1, ps=1332
server  | 4.064 DEBUG ECN probing: sent 3 probes
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.064 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.064 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.391082ms
server  | 4.064 DEBUG Setting timeout of 1.391082ms
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] pn=0 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 224
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [255, 235, 168, 123, 134, 16, 186, 245], stateless_reset_token: [246, 229, 128, 122, 150, 90, 140, 25, 22, 173, 123, 33, 157, 44, 167, 205] }
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [244, 225, 248, 172, 73, 69, 129, 42], stateless_reset_token: [107, 142, 144, 92, 44, 37, 218, 147, 108, 203, 71, 206, 220, 10, 102, 180] }
server  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [24, 176, 136, 66, 176, 137, 217, 35], stateless_reset_token: [13, 150, 73, 164, 165, 175, 116, 49, 84, 77, 35, 107, 84, 26, 214, 155] }
server  |   -> RX NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [117, 33, 189, 132, 40, 199, 109, 35], stateless_reset_token: [195, 192, 88, 238, 208, 122, 104, 219, 201, 27, 48, 137, 164, 1, 79, 38] }
server  |   -> RX NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [243, 194, 46, 22, 175, 191, 149, 124], stateless_reset_token: [109, 34, 194, 116, 61, 100, 0, 187, 124, 128, 234, 109, 208, 22, 244, 54] }
server  |   -> RX NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [34, 73, 22, 14, 39, 165, 17, 155], stateless_reset_token: [45, 251, 7, 154, 173, 249, 248, 233, 176, 184, 100, 33, 8, 194, 169, 50] }
server  |   -> RX NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [92, 12, 33, 63, 9, 232, 185, 59], stateless_reset_token: [69, 128, 117, 130, 180, 176, 66, 174, 100, 147, 41, 26, 247, 224, 195, 53] }
server  | 4.064 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 307, tv_nsec: 177512028 }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.064 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.064 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.339626ms
server  | 4.064 DEBUG Setting timeout of 1.339626ms
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] pn=1 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 48
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 4.064 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 307, tv_nsec: 157609199 }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.064 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] pn=2 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 34
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 4.064 DEBUG ECN probing: sent 4 probes
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.064 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.064 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.064 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.169069ms
server  | 4.064 DEBUG Setting timeout of 1.169069ms
server  | 4.065 DEBUG [Server 49e8e39b1a547bd2ace7] send session ticket 407b001424b8ae1d131cfdf76083f1c9ad4dbe70e9ea794e0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a152f412bc7afcbdf13c4100a49e8e39b1a547bd2ace7110c000000010a9a7aea000000016ab200c0000000ff02de1a0243e8200100686921
server  | 4.065 DEBUG Path = 'jojrbhnyfu'
server  | 4.065 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.065 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.065 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.065 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 151.152µs
server  | 4.065 DEBUG Setting timeout of 151.152µs
server  | 4.067 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.067 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.067 DEBUG [Server 49e8e39b1a547bd2ace7] pn=3 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false }
server  | 4.067 DEBUG packet_sent this=0x559a88a5cb40, pn=3, ps=1232
server  | 4.067 DEBUG ECN probing: sent 5 probes
server  | 4.067 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.067 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.068 DEBUG [Server 49e8e39b1a547bd2ace7] pn=4 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false }
server  | 4.068 DEBUG packet_sent this=0x559a88a5cb40, pn=4, ps=1232
server  | 4.068 DEBUG ECN probing: sent 6 probes
server  | 4.068 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.068 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.068 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.068 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.42729ms
server  | 4.068 DEBUG Setting timeout of 1.42729ms
server  | 4.068 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.068 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.068 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.068 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.404006ms
server  | 4.068 DEBUG Setting timeout of 1.404006ms
server  | 4.070 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.070 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.070 DEBUG [Server 49e8e39b1a547bd2ace7] pn=5 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false }
server  | 4.070 DEBUG packet_sent this=0x559a88a5cb40, pn=5, ps=1232
server  | 4.070 DEBUG ECN probing: sent 7 probes
server  | 4.070 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.070 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.070 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.070 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.034941ms
server  | 4.070 DEBUG Setting timeout of 1.034941ms
server  | 4.070 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.070 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.070 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.070 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.012289ms
server  | 4.070 DEBUG Setting timeout of 1.012289ms
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.072 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] pn=6 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false }
server  | 4.072 DEBUG packet_sent this=0x559a88a5cb40, pn=6, ps=1232
server  | 4.072 DEBUG ECN probing: sent 8 probes
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.072 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] pn=7 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 4810, len: >>1202, fin: false }
server  | 4.072 DEBUG packet_sent this=0x559a88a5cb40, pn=7, ps=1232
server  | 4.072 DEBUG ECN probing: sent 9 probes
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.072 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.072 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.67567ms
server  | 4.072 DEBUG Setting timeout of 1.67567ms
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.072 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.072 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 4.072 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 1.656414ms
server  | 4.072 DEBUG Setting timeout of 1.656414ms
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.075 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] pn=8 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 6012, len: >>1202, fin: false }
server  | 4.075 DEBUG packet_sent this=0x559a88a5cb40, pn=8, ps=1232
server  | 4.075 DEBUG ECN probing: sent 10 probes
server  | 4.075 DEBUG ECN probing concluded with 10 probes sent
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.075 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] pn=9 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, NotEct) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 7214, len: >>1202, fin: false }
server  | 4.075 DEBUG packet_sent this=0x559a88a5cb40, pn=9, ps=1232
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1084, pto: None, probe: EnumSet(), paced: false }
server  | 4.075 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] pn=10 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, NotEct) len 1084
server  |   TX -> Stream { stream_id: 0, offset: 8416, len: >>1054, fin: false }
server  | 4.075 DEBUG packet_sent this=0x559a88a5cb40, pn=10, ps=1084
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.075 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.075 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 113.913276ms
server  | 4.075 DEBUG Setting timeout of 113.913276ms
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.075 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.075 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 4.075 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 113.887288ms
server  | 4.075 DEBUG Setting timeout of 113.887288ms
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] pn=2 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 4, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=ap, ranges=[0..=1]
server  | 4.097 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 4.097 INFO ECN validation succeeded, path is capable
server  | 4.097 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] slow start += 2612
server  | 4.097 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9708/14932 ssthresh 18446744073709551615] on_packets_acked this=0x559a88a5cb40, limited=0, bytes_in_flight=9708, cwnd=14932, state=SlowStart, new_acked=2612
server  | 4.097 DEBUG PMTUD probe of size 1380 succeeded
server  | 4.097 DEBUG PMTUD started with probe size 1420
server  | 4.097 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.097 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.097 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 4.097 DEBUG CRYPTO for ap offset=0, len=337
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] pn=11 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 9470, len: 770, fin: true }
server  |   TX -> Crypto { offset: 0, len: 337 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 60, 178, 73, 180, 78, 197, 27, 230, 142, 133, 20, 138, 29, 173, 157, 131, 249, 140, 45, 52, 204, 214, 45, 71, 7, 200, 50, 57, 67, 209, 66, 61, 114, 91, 120, 182] }
server  |   TX -> Padding { len: 183 }
server  | 4.097 DEBUG packet_sent this=0x559a88a5cb40, pn=11, ps=1372
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.097 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.097 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 103.053486ms
server  | 4.097 DEBUG Setting timeout of 103.053486ms
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.097 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.097 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.097 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 103.022589ms
server  | 4.097 DEBUG Setting timeout of 103.022589ms
server  | 4.100 DEBUG [Server 49e8e39b1a547bd2ace7] pn=3 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 1, first_ack_range: 4, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  | 4.100 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=ap, ranges=[0..=4]
server  | 4.100 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 4.100 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8616/17396 ssthresh 18446744073709551615] slow start += 2464
server  | 4.100 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8616/17396 ssthresh 18446744073709551615] on_packets_acked this=0x559a88a5cb40, limited=0, bytes_in_flight=8616, cwnd=17396, state=SlowStart, new_acked=2464
server  | 4.100 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.100 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.100 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.100 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 91.71596ms
server  | 4.100 DEBUG Setting timeout of 91.71596ms
server  | 4.100 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.100 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.100 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.100 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 91.644988ms
server  | 4.100 DEBUG Setting timeout of 91.644988ms
server  | 4.103 DEBUG [Server 49e8e39b1a547bd2ace7] pn=4 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 1, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 7, Ce: 0})) }
server  | 4.103 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=ap, ranges=[0..=6]
server  | 4.103 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6
server  | 4.103 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6152/19860 ssthresh 18446744073709551615] slow start += 2464
server  | 4.103 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6152/19860 ssthresh 18446744073709551615] on_packets_acked this=0x559a88a5cb40, limited=0, bytes_in_flight=6152, cwnd=19860, state=SlowStart, new_acked=2464
server  | 4.103 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.103 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.103 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.103 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 82.531192ms
server  | 4.103 DEBUG Setting timeout of 82.531192ms
server  | 4.103 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.103 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.103 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.103 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 82.460972ms
server  | 4.103 DEBUG Setting timeout of 82.460972ms
server  | 4.107 DEBUG [Server 49e8e39b1a547bd2ace7] pn=5 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 8, ack_delay: 1, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  | 4.107 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=ap, ranges=[0..=8]
server  | 4.107 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=8
server  | 4.107 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3688/22324 ssthresh 18446744073709551615] slow start += 2464
server  | 4.107 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3688/22324 ssthresh 18446744073709551615] on_packets_acked this=0x559a88a5cb40, limited=0, bytes_in_flight=3688, cwnd=22324, state=SlowStart, new_acked=2464
server  | 4.107 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.107 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.107 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.107 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 74.362421ms
server  | 4.107 DEBUG Setting timeout of 74.362421ms
server  | 4.107 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.107 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.107 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.107 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 74.304943ms
server  | 4.107 DEBUG Setting timeout of 74.304943ms
server  | 4.108 DEBUG [Server 49e8e39b1a547bd2ace7] pn=6 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 36
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 1, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  | 4.108 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=ap, ranges=[0..=10]
server  | 4.108 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=10
server  | 4.108 WARN ECN validation failed, no ECT(0) packets were newly acked
server  | 4.108 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] slow start += 2316
server  | 4.108 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] on_packets_acked this=0x559a88a5cb40, limited=0, bytes_in_flight=1372, cwnd=24640, state=SlowStart, new_acked=2316
server  | 4.108 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.108 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.108 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.109 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 66.980748ms
server  | 4.109 DEBUG Setting timeout of 66.980748ms
server  | 4.109 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.109 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.109 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 4.109 DEBUG [Server 49e8e39b1a547bd2ace7] delay duration 66.934662ms
server  | 4.109 DEBUG Setting timeout of 66.934662ms
server  | 4.128 DEBUG [Server 49e8e39b1a547bd2ace7] pn=7 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, Ect0) len 39
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 19, first_ack_range: 11, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 10, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" }
server  | 4.128 DEBUG [Server 49e8e39b1a547bd2ace7] Rx ACK space=ap, ranges=[0..=11]
server  | 4.128 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=11
server  | 4.128 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] slow start += 1372
server  | 4.128 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] on_packets_acked this=0x559a88a5cb40, limited=0, bytes_in_flight=0, cwnd=26012, state=SlowStart, new_acked=1372
server  | 4.128 DEBUG PMTUD probe of size 1420 succeeded
server  | 4.128 DEBUG PMTUD started with probe size 1470
server  | 4.128 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 4.128 DEBUG Acked crypto frame space=ap offset=0 length=337
server  | 4.128 INFO [Server 49e8e39b1a547bd2ace7] ConnectionClose received. Error code: Application(0) frame type 0 reason 
server  | 4.128 DEBUG [Server 49e8e39b1a547bd2ace7] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 307, tv_nsec: 449477723 } }
server  | 4.128 DEBUG [pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789] Path validated Instant { tv_sec: 307, tv_nsec: 221817569 }
server  | 4.128 DEBUG [Server 49e8e39b1a547bd2ace7] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 4.128 DEBUG Building Short dcid Some(CID [8]: 4a988881b8bcdffc)
server  | 4.128 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 307, tv_nsec: 221817569 }
server  | 4.128 DEBUG [Server 49e8e39b1a547bd2ace7] pn=12 type=Short pri-path:4a988881b8bcdffc [::]:443->[::ffff:193.167.0.100]:43789 IpTos(Cs0, NotEct) len 38
server  |   TX -> Ack { largest_acknowledged: 7, ack_delay: 0, first_ack_range: 7, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 8, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 4.129 DEBUG Setting timeout of 227.514703ms
server  | 4.129 DEBUG Setting timeout of 227.503262ms
server  | 4.357 DEBUG [Server 49e8e39b1a547bd2ace7] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 307, tv_nsec: 449477723 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 4.357 INFO Closing timer expired

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

2025-04-30 09:13:10,722 Using the client's key log file.
2025-04-30 09:13:10,731 
2025-04-30 09:13:10,731 Using the client's key log file.
2025-04-30 09:13:10,732 Using the client's key log file.
2025-04-30 09:13:10,739 
2025-04-30 09:13:10,740 Using the client's key log file.
2025-04-30 09:13:11,289 Check of downloaded files succeeded.
2025-04-30 09:13:11,849 Check of Retry succeeded. Token used: 5265747279010083a4dd46412c88e78945c0260cba2cddb7757bd42440cc8d51e4f5d5c8c235ae28a0dd68ec807d74a6bd1b9cfbf3a29f3c5050254a87990c