2025-04-30 11:47:42,728 Generated random file: elpjzknnrs of size: 5120
2025-04-30 11:47:42,728 Generated random file: pnnrycfqhd of size: 10240
2025-04-30 11:47:42,728 Requests: https://server4:443/elpjzknnrs https://server4:443/pnnrycfqhd
2025-04-30 11:47:42,788 
2025-04-30 11:47:42,789 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_f8kj6awc/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_khhwt7dn/ DOWNLOADS=/tmp/download_dgduop5s/ SERVER_LOGS=/tmp/logs_server_2cmpv7lp CLIENT_LOGS=/tmp/logs_client_xuahitag SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/microsoft/msquic/qns:main SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/elpjzknnrs https://server4:443/pnnrycfqhd"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 11:47:59,943  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
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
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.CiZKOrFPwJ
server  | + mkdir -p /neqo/db
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
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.CiZKOrFPwJ
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.CiZKOrFPwJ -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  |             27:d8:99:1e:26:f6:2e:e3:8c:2e:63:85:ed:0a:db:31:
server  |             94:82:71:c6
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 11:47:42 2025
server  |             Not After : Sat May 10 11:47:42 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:93:f8:56:bc:36:c4:e5:ee:ce:c0:7d:7e:40:a3:4d:
server  |                     0c:d7:8b:8d:bf:55:99:b2:3b:f3:75:74:3e:8c:85:96:
server  |                     f9:7b:65:e9:e5:8f:4b:11:79:6b:5b:95:ad:34:75:e6:
server  |                     92:f4:0e:87:7b:48:cd:f5:55:30:05:6c:50:6e:9e:53:
server  |                     43
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  |                 87:84:0c:78:9d:2e:15:80:2b:be:f7:5a:b7:bb:38:2b:
server  |                 09:3f:87:ca
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 76:d0:b4:64:a8:cc:45:2d:f7:d5:32:1a:c9:c0:1e:c1:
server  |                 1f:dd:04:e2
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:d9:e5:a9:c5:d8:6b:c7:75:f4:ea:ff:
server  |         53:df:0e:a3:59:6c:8d:6b:0c:59:7c:7a:b6:2b:9c:09:
server  |         b1:0c:41:10:62:02:21:00:ed:7e:f5:b3:c8:2a:60:b1:
server  |         58:c9:5e:f3:60:b8:e7:63:ef:88:88:94:c3:ca:33:df:
server  |         f9:8a:89:c9:b2:a9:50:5f
server  |     Fingerprint (SHA-256):
server  |         41:66:8A:9B:7B:EC:44:86:59:38:17:01:70:74:FF:37:A0:84:69:A8:06:A3:2B:2D:B8:D8:46:DC:6A:7D:B2:CE
server  |     Fingerprint (SHA1):
server  |         1A:C7:B8:6C:37:DB:36:5C:B7:CE:22:3D:D8:7B:E3:BF:8D:D2:98:DB
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.796 DEBUG [Server] Unsupported version: 57414954
server  | 0.796 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46687 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.059468601s
server  | 0.796 DEBUG [Server] Unsupported version: 57414954
server  | 0.796 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46687 IpTos(Cs0, NotEct) len 15
server  | 0.796 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.796 DEBUG [Server] Unsupported version: 57414954
server  | 0.796 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:46687 IpTos(Cs0, NotEct) len 15
server  | 0.796 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  | Connecting to server4
client  | Client params (before files):-test:R -sslkeylogfile:/logs/keys.log 
server  | 0.999 DEBUG [Server] Handle initial
server  | 0.999 INFO AddressValidation: no token; accepting
server  | 0.999 INFO [Server] Accept connection CID [8]: af22cc6e4166a6b5
server  | 0.999 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.000 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.001 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: af22cc6e4166a6b5
server  | 1.001 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=af22cc6e4166a6b5
server  | 1.001 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.001 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.001 DEBUG [Server af22cc6e4166a6b5] State change from Init -> WaitInitial
server  | 1.001 DEBUG [Server af22cc6e4166a6b5] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, NotEct) len 1220
server  |   -> RX Crypto { offset: 0, len: 297 }
server  |   -> RX Padding { len: 881 }
server  | 1.001 DEBUG Read Ok(297) bytes
server  | 1.001 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.001 DEBUG Read secret available for Handshake: SymKey [32]: 489ffaf19764021fcf093b6c3bf029347e9facc0669b9fc2c354ba12236261cd
server  | 1.001 DEBUG Write secret available for Handshake: SymKey [32]: e3471047566d673187145f6fcbb9a308d2e0291ce110636328bc973c8351dfb3
server  | 1.001 DEBUG Writing transport parameters, msg=8
server  | 1.002 DEBUG Read secret available for ApplicationData: SymKey [32]: 66357af86aced5bed62c7ee2b30a8afa4afa1d201501512a388fa6b8ccc8872c
server  | 1.002 DEBUG Write secret available for ApplicationData: SymKey [32]: 6e5356082cf25782118ac66c4f9d152a33c585a0a8f3aef97a53683f668c66e5
server  | 1.002 DEBUG [Agent 0x5619a78ec1d0] state -> InProgress
server  | 1.002 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.002 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.002 DEBUG [Crypto] Handshake keys installed
server  | 1.002 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.002 DEBUG [Crypto] Application write key installed
server  | 1.002 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 136954067 }
server  | 1.002 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:56954] Make permanent
server  | 1.002 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:56954] set as primary path
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] State change from WaitInitial -> Handshaking
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.002 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 2e8cb7a8935fbe8d3b7c)
server  | 1.002 DEBUG CRYPTO for in offset=0, len=90
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 136
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 1.002 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2e8cb7a8935fbe8d3b7c)
server  | 1.002 DEBUG CRYPTO for hs offset=0, len=725
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 901
server  |   TX -> Crypto { offset: 0, len: 725 }
server  | 1.002 DEBUG packet_sent this=0x5619a78ebb40, pn=0, ps=765
server  | 1.002 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] pad Initial from 901 to PLPMTU 1232
server  | 1.002 DEBUG packet_sent this=0x5619a78ebb40, pn=0, ps=467
server  | 1.002 DEBUG ECN probing: sent 1 probes
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.002 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 2e8cb7a8935fbe8d3b7c)
server  | 1.002 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2e8cb7a8935fbe8d3b7c)
server  | 1.002 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.002 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] delay duration 296.851065ms
server  | 1.002 DEBUG Setting timeout of 296.851065ms
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.002 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 2e8cb7a8935fbe8d3b7c)
server  | 1.002 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2e8cb7a8935fbe8d3b7c)
server  | 1.002 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.002 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.002 DEBUG [Server af22cc6e4166a6b5] delay duration 296.818614ms
server  | 1.002 DEBUG Setting timeout of 296.818614ms
server  | 1.034 DEBUG [Server af22cc6e4166a6b5] Drop packet number space in
server  | 1.034 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.034 INFO [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:56954] discarding a packet without an RTT estimate; guessing RTT=35.494614ms
server  | 1.034 DEBUG [Server af22cc6e4166a6b5] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, NotEct) len 86
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 2, 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  | 1.034 DEBUG [Server af22cc6e4166a6b5] Rx ACK space=hs, ranges=[0..=0]
server  | 1.034 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.034 DEBUG on_packets_acked this=0x5619a78ebb40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=765
server  | 1.034 DEBUG Acked crypto frame space=hs offset=0 length=725
server  | 1.034 DEBUG Read Ok(36) bytes
server  | 1.035 DEBUG [Agent 0x5619a78ec1d0] 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.035 DEBUG [Server af22cc6e4166a6b5] TLS connection complete
server  | 1.035 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:56954] Path validated Instant { tv_sec: 377, tv_nsec: 172448681 }
server  | 1.035 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.035 DEBUG [Crypto] application read keys installed
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] State change from Handshaking -> Connected
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] State change from Connected -> Confirmed
server  | 1.035 DEBUG PMTUD started with probe size 1380
server  | 1.035 INFO [Server af22cc6e4166a6b5] Connection established
server  | 1.035 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 172448681 }
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.035 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 2e8cb7a8935fbe8d3b7c)
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 41
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] Drop packet number space hs
server  | 1.035 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.035 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [166, 207, 221, 28, 222, 2, 163, 163, 35, 36], stateless_reset_token: [213, 16, 20, 126, 87, 132, 168, 224, 121, 104, 98, 68, 243, 129, 207, 6] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [163, 227, 245, 38, 84, 13, 248, 166, 65, 181], stateless_reset_token: [2, 77, 238, 42, 115, 246, 167, 104, 15, 75, 241, 139, 72, 184, 215, 83] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [74, 92, 14, 240, 244, 247, 238, 209, 84, 240], stateless_reset_token: [201, 115, 61, 133, 31, 232, 193, 177, 12, 71, 224, 36, 153, 2, 31, 111] }
server  |   TX -> Padding { len: 1182 }
server  | 1.035 DEBUG packet_sent this=0x5619a78ebb40, pn=0, ps=1291
server  | 1.035 DEBUG ECN probing: sent 2 probes
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.035 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.035 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.035 DEBUG packet_sent this=0x5619a78ebb40, pn=1, ps=1332
server  | 1.035 DEBUG ECN probing: sent 3 probes
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.035 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.035 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.035 DEBUG [Server af22cc6e4166a6b5] delay duration 1.345157ms
server  | 1.035 DEBUG Setting timeout of 1.345157ms
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] send session ticket 40630008af22cc6e4166a6b50104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a2e8cb7a8935fbe8d3b7c110c00000001eaea7a0a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.036 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] delay duration 352.629µs
server  | 1.036 DEBUG Setting timeout of 352.629µs
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, NotEct) len 1252
server  |   -> RX Ping
server  |   -> RX Padding { len: 1220 }
server  | 1.036 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 377, tv_nsec: 174141150 }
server  | 1.036 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 174141150 }
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.036 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.036 DEBUG ECN probing: sent 4 probes
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.036 DEBUG CRYPTO for ap offset=0, len=305
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 372
server  |   TX -> Crypto { offset: 0, len: 305 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 25, 113, 50, 61, 133, 78, 138, 218, 54, 27, 94, 99, 194, 224, 72, 73, 201, 29, 231, 10, 211, 149, 120, 179, 167, 168, 84, 242, 25, 101, 224, 238, 83, 130, 178, 197] }
server  | 1.036 DEBUG packet_sent this=0x5619a78ebb40, pn=3, ps=372
server  | 1.036 DEBUG ECN probing: sent 5 probes
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] delay duration 114.669519ms
server  | 1.036 DEBUG Setting timeout of 114.669519ms
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.036 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.036 DEBUG [Server af22cc6e4166a6b5] delay duration 114.647858ms
server  | 1.036 DEBUG Setting timeout of 114.647858ms
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, NotEct) len 39
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] Rx ACK space=ap, ranges=[0..=1]
server  | 1.068 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.068 DEBUG on_packets_acked this=0x5619a78ebb40, limited=1, bytes_in_flight=372, cwnd=12320, state=SlowStart, new_acked=2623
server  | 1.068 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.068 DEBUG PMTUD started with probe size 1420
server  | 1.068 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.068 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.068 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1353 }
server  | 1.068 DEBUG packet_sent this=0x5619a78ebb40, pn=4, ps=1372
server  | 1.068 DEBUG ECN probing: sent 6 probes
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.068 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.068 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] delay duration 103.711259ms
server  | 1.068 DEBUG Setting timeout of 103.711259ms
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.068 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.068 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.068 DEBUG [Server af22cc6e4166a6b5] delay duration 103.678769ms
server  | 1.068 DEBUG Setting timeout of 103.678769ms
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, NotEct) len 1332
server  |   -> RX Ping
server  |   -> RX Padding { len: 1300 }
server  | 1.069 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 377, tv_nsec: 206740093 }
server  | 1.069 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 206740093 }
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  | 1.069 DEBUG ECN probing: sent 7 probes
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.069 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] delay duration 102.78771ms
server  | 1.069 DEBUG Setting timeout of 102.78771ms
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, NotEct) len 42
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 1, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 4, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" }
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] Rx ACK space=ap, ranges=[0..=3]
server  | 1.069 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 1.069 DEBUG on_packets_acked this=0x5619a78ebb40, limited=1, bytes_in_flight=1372, cwnd=12320, state=SlowStart, new_acked=372
server  | 1.069 DEBUG Acked crypto frame space=ap offset=0 length=305
server  | 1.069 INFO [Server af22cc6e4166a6b5] ConnectionClose received. Error code: Application(0) frame type 0 reason 
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 377, tv_nsec: 493123143 } }
server  | 1.069 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:56954] Path validated Instant { tv_sec: 377, tv_nsec: 206847735 }
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.069 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.069 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 377, tv_nsec: 206847735 }
server  | 1.069 DEBUG [Server af22cc6e4166a6b5] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:56954 IpTos(Cs0, Ect0) len 27
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 1.069 DEBUG ECN probing: sent 8 probes
server  | 1.069 DEBUG Setting timeout of 286.138001ms
server  | 1.069 DEBUG Setting timeout of 286.12664ms
server  | 1.101 DEBUG [Server] Handle initial
server  | 1.101 INFO AddressValidation: no token; accepting
server  | 1.101 INFO [Server] Accept connection CID [8]: 2bc9fa9c5cf7694e
server  | 1.101 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.103 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.103 INFO [Server ...] Received valid Initial packet with scid CID [0]:  dcid CID [8]: 2bc9fa9c5cf7694e
server  | 1.103 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=2bc9fa9c5cf7694e
server  | 1.103 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.103 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.103 DEBUG [Server 2bc9fa9c5cf7694e] State change from Init -> WaitInitial
server  | 1.103 DEBUG [Server 2bc9fa9c5cf7694e] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 1220
server  |   -> RX Crypto { offset: 0, len: 622 }
server  |   -> RX Padding { len: 556 }
server  | 1.103 DEBUG Read Ok(622) bytes
server  | 1.103 INFO 0-RTT: transport parameters OK, passing to application checker
server  | 1.103 WARN AllowZeroRtt accepting 0-RTT
server  | 1.103 DEBUG Read secret available for ZeroRtt: SymKey [32]: 4ff81f92b718804431fa03171ce270be44b8d71aa869bf78b22606bb667a098e
server  | 1.103 DEBUG Read secret available for Handshake: SymKey [32]: f5ca6ab1602ec738667bf4bd14ad3671d2661af15446d1d735a587a91e7fba86
server  | 1.103 DEBUG Write secret available for Handshake: SymKey [32]: 3799ef8519c4cd8c81726acec257afab9f9c4a023d606e53e91bbe5cdd4a1b67
server  | 1.103 DEBUG Writing transport parameters, msg=8
server  | 1.103 DEBUG Read secret available for ApplicationData: SymKey [32]: 1c5aef18f0608b34e2f1cff9931efa48e1d3e0302e688e767f9677ed7a8d05e1
server  | 1.103 DEBUG Write secret available for ApplicationData: SymKey [32]: 75ea31e9811c5f505bb8f2b4b5fa1e36fe4664c815edb981c43e790f4adb891b
server  | 1.103 DEBUG [Agent 0x5619a78e9ab0] state -> InProgress
server  | 1.103 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.104 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.104 DEBUG [Crypto] Handshake keys installed
server  | 1.104 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.104 DEBUG [Crypto] Application write key installed
server  | 1.104 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 239414367 }
server  | 1.104 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:46921] Make permanent
server  | 1.104 DEBUG [unv-path: [::]:443->[::ffff:193.167.0.100]:46921] set as primary path
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] State change from WaitInitial -> Handshaking
server  | 1.104 DEBUG Making Read ZeroRtt CryptoDxState, v=Version1 cipher=4865
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] Accepted 0-RTT
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.104 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7c398a658ebadd689905)
server  | 1.104 DEBUG CRYPTO for in offset=0, len=96
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] pn=0 type=Initial pri-unv-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 142
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 96 }
server  | 1.104 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7c398a658ebadd689905)
server  | 1.104 DEBUG CRYPTO for hs offset=0, len=180
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] pn=0 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 362
server  |   TX -> Crypto { offset: 0, len: 180 }
server  | 1.104 DEBUG packet_sent this=0x5619a7829840, pn=0, ps=220
server  | 1.104 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] pad Initial from 362 to PLPMTU 1232
server  | 1.104 DEBUG packet_sent this=0x5619a7829840, pn=0, ps=1012
server  | 1.104 DEBUG ECN probing: sent 1 probes
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.104 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7c398a658ebadd689905)
server  | 1.104 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7c398a658ebadd689905)
server  | 1.104 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.104 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 297.426422ms
server  | 1.104 DEBUG Setting timeout of 251.135198ms
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.104 DEBUG Building Initial dcid Some(CID [0]: ) scid Some(CID [10]: 7c398a658ebadd689905)
server  | 1.104 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7c398a658ebadd689905)
server  | 1.104 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.104 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.104 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 297.37235ms
server  | 1.104 DEBUG Setting timeout of 251.081126ms
client  | Sending request: GET /elpjzknnrs

server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] Drop packet number space in
server  | 1.136 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.136 INFO [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:46921] discarding a packet without an RTT estimate; guessing RTT=34.541007ms
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] pn=1 type=Handshake pri-unv-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 86
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=hs, ranges=[0..=0]
server  | 1.136 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.136 DEBUG on_packets_acked this=0x5619a7829840, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=220
server  | 1.136 DEBUG Acked crypto frame space=hs offset=0 length=180
server  | 1.136 DEBUG Read Ok(36) bytes
server  | 1.136 DEBUG [Agent 0x5619a78e9ab0] 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.136 DEBUG [Server 2bc9fa9c5cf7694e] TLS connection complete
server  | 1.136 DEBUG [pri-unv-path: [::]:443->[::ffff:193.167.0.100]:46921] Path validated Instant { tv_sec: 377, tv_nsec: 273955374 }
server  | 1.136 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.136 DEBUG [Crypto] application read keys installed
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] State change from Handshaking -> Connected
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] State change from Connected -> Confirmed
server  | 1.136 DEBUG PMTUD started with probe size 1380
server  | 1.136 INFO [Server 2bc9fa9c5cf7694e] Connection established
server  | 1.136 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 273955374 }
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.136 DEBUG Building Handshake dcid Some(CID [0]: ) scid Some(CID [10]: 7c398a658ebadd689905)
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] pn=1 type=Handshake pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 41
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] Drop packet number space hs
server  | 1.136 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.136 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] pn=0 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [20, 32, 148, 212, 85, 225, 116, 158, 91, 213], stateless_reset_token: [190, 82, 82, 56, 121, 34, 154, 206, 192, 222, 26, 55, 145, 128, 157, 201] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [42, 148, 0, 98, 147, 219, 27, 50, 165, 19], stateless_reset_token: [19, 108, 187, 253, 116, 206, 220, 100, 14, 181, 242, 3, 120, 55, 155, 232] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [57, 53, 225, 14, 62, 247, 46, 229, 152, 94], stateless_reset_token: [229, 61, 191, 90, 180, 104, 225, 91, 225, 40, 184, 9, 24, 250, 61, 5] }
server  |   TX -> Padding { len: 1182 }
server  | 1.136 DEBUG packet_sent this=0x5619a7829840, pn=0, ps=1291
server  | 1.136 DEBUG ECN probing: sent 2 probes
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.136 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.136 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] pn=1 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1313 }
server  | 1.136 DEBUG packet_sent this=0x5619a7829840, pn=1, ps=1332
server  | 1.136 DEBUG ECN probing: sent 3 probes
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.136 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.136 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.336709ms
server  | 1.136 DEBUG Setting timeout of 1.336709ms
server  | 1.136 DEBUG [Server 2bc9fa9c5cf7694e] send session ticket 406300082bc9fa9c5cf7694e0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a7c398a658ebadd689905110c000000012a6acada000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.214661ms
server  | 1.137 DEBUG Setting timeout of 1.214661ms
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 1252
server  |   -> RX Ping
server  |   -> RX Padding { len: 1220 }
server  | 1.137 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 377, tv_nsec: 274993771 }
server  | 1.137 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 274993771 }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] pn=2 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.137 DEBUG ECN probing: sent 4 probes
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG CRYPTO for ap offset=0, len=305
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 372
server  |   TX -> Crypto { offset: 0, len: 305 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 3, 129, 161, 59, 255, 48, 96, 94, 150, 61, 167, 17, 94, 167, 139, 41, 20, 131, 176, 52, 50, 18, 150, 88, 36, 192, 8, 22, 47, 230, 139, 226, 28, 162, 90, 74] }
server  | 1.137 DEBUG packet_sent this=0x5619a7829840, pn=3, ps=372
server  | 1.137 DEBUG ECN probing: sent 5 probes
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.289018ms
server  | 1.137 DEBUG Setting timeout of 1.289018ms
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] pn=3 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 35
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 0, fin: false }
server  | 1.137 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 295194135 }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.26847ms
server  | 1.137 DEBUG Setting timeout of 1.26847ms
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 52
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.137 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 275240322 }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] pn=4 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  | 1.137 DEBUG ECN probing: sent 6 probes
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.162722ms
server  | 1.137 DEBUG Setting timeout of 1.162722ms
server  | 1.137 DEBUG Path = 'elpjzknnrs'
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.137 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.137 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.070569ms
server  | 1.137 DEBUG Setting timeout of 1.070569ms
server  | 1.140 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.140 DEBUG [Server 2bc9fa9c5cf7694e] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1212, fin: false }
server  | 1.140 DEBUG packet_sent this=0x5619a7829840, pn=5, ps=1232
server  | 1.140 DEBUG ECN probing: sent 7 probes
server  | 1.140 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.140 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.140 DEBUG [Server 2bc9fa9c5cf7694e] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1212, len: >>1210, fin: false }
server  | 1.140 DEBUG packet_sent this=0x5619a7829840, pn=6, ps=1232
server  | 1.140 DEBUG ECN probing: sent 8 probes
server  | 1.141 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.141 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.141 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.141 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.300559ms
server  | 1.141 DEBUG Setting timeout of 1.300559ms
server  | 1.141 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.141 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.141 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.141 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.276203ms
server  | 1.141 DEBUG Setting timeout of 1.276203ms
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.143 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2422, len: >>1210, fin: false }
server  | 1.143 DEBUG packet_sent this=0x5619a7829840, pn=7, ps=1232
server  | 1.143 DEBUG ECN probing: sent 9 probes
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.143 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] pn=8 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3632, len: >>1210, fin: false }
server  | 1.143 DEBUG packet_sent this=0x5619a7829840, pn=8, ps=1232
server  | 1.143 DEBUG ECN probing: sent 10 probes
server  | 1.143 DEBUG ECN probing concluded with 10 probes sent
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.143 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.143 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.661488ms
server  | 1.143 DEBUG Setting timeout of 1.661488ms
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.143 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.143 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.143 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 1.644937ms
server  | 1.143 DEBUG Setting timeout of 1.644937ms
server  | 1.146 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.146 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.146 DEBUG [Server 2bc9fa9c5cf7694e] pn=9 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 302
server  |   TX -> Stream { stream_id: 0, offset: 4842, len: 278, fin: true }
server  | 1.146 DEBUG packet_sent this=0x5619a7829840, pn=9, ps=302
server  | 1.146 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.146 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.146 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.146 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 76.225865ms
server  | 1.146 DEBUG Setting timeout of 76.225865ms
server  | 1.146 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.146 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.146 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.146 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 76.203253ms
server  | 1.146 DEBUG Setting timeout of 76.203253ms
client  | elpjzknnrs: Wrote 1212 bytes.(0 ms/0 ms/0 ms)
client  | elpjzknnrs: Wrote 1210 bytes.(1 ms/1 ms/1 ms)
client  | elpjzknnrs: Wrote 1210 bytes.(2 ms/1 ms/0 ms)
client  | elpjzknnrs: Wrote 1210 bytes.(3 ms/1 ms/0 ms)
client  | elpjzknnrs: Wrote 278 bytes.(5 ms/2 ms/1 ms)
client  | elpjzknnrs: Completed download! (5 ms)
client  | Sending request: GET /pnnrycfqhd

server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] pn=5 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 39
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[0..=1]
server  | 1.169 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 1.169 INFO ECN validation succeeded, path is capable
server  | 1.169 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5602/14943 ssthresh 18446744073709551615] slow start += 2623
server  | 1.169 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5602/14943 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=5602, cwnd=14943, state=SlowStart, new_acked=2623
server  | 1.169 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.169 DEBUG PMTUD started with probe size 1420
server  | 1.169 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.169 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.169 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] pn=10 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1353 }
server  | 1.169 DEBUG packet_sent this=0x5619a7829840, pn=10, ps=1372
server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.169 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.169 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 52.87659ms
server  | 1.169 DEBUG Setting timeout of 52.87659ms
server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.169 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.169 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.169 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 52.838769ms
server  | 1.169 DEBUG Setting timeout of 52.838769ms
server  | 1.170 DEBUG [Server 2bc9fa9c5cf7694e] pn=6 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 1332
server  |   -> RX Ping
server  |   -> RX Padding { len: 1300 }
server  | 1.170 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 377, tv_nsec: 308233732 }
server  | 1.170 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 308233732 }
server  | 1.170 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.170 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.170 DEBUG [Server 2bc9fa9c5cf7694e] pn=11 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: None }
server  | 1.170 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.170 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.170 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.170 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 51.946599ms
server  | 1.170 DEBUG Setting timeout of 51.946599ms
server  | 1.170 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.170 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.170 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.170 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 51.922634ms
server  | 1.170 DEBUG Setting timeout of 51.922634ms
server  | 1.172 DEBUG [Server 2bc9fa9c5cf7694e] pn=7 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 6, Ce: 0})) }
server  |   -> RX MaxData { maximum_data: 16778428 }
server  |   -> RX MaxStreamData { stream_id: StreamId(0), maximum_stream_data: 66748 }
server  | 1.172 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[0..=5]
server  | 1.172 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5
server  | 1.172 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5370/16547 ssthresh 18446744073709551615] slow start += 1604
server  | 1.172 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5370/16547 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=5370, cwnd=16547, state=SlowStart, new_acked=1604
server  | 1.172 DEBUG Acked crypto frame space=ap offset=0 length=305
server  | 1.172 DEBUG setting max_stream_data to 66748
server  | 1.172 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 330055424 }
server  | 1.172 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.172 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.172 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.172 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 20ms
server  | 1.172 DEBUG Setting timeout of 20ms
server  | 1.172 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.172 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.172 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.172 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 19.929888ms
server  | 1.172 DEBUG Setting timeout of 19.929888ms
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] pn=8 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 50
server  |   -> RX 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  |   -> RX MaxData { maximum_data: 16780848 }
server  |   -> RX MaxStreamData { stream_id: StreamId(0), maximum_stream_data: 69168 }
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[0..=7]
server  | 1.174 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
server  | 1.174 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2906/19011 ssthresh 18446744073709551615] slow start += 2464
server  | 1.174 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 2906/19011 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=2906, cwnd=19011, state=SlowStart, new_acked=2464
server  | 1.174 DEBUG setting max_stream_data to 69168
server  | 1.174 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 312215258 }
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.174 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] pn=12 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 8, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: None }
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.174 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.174 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 47.952198ms
server  | 1.174 DEBUG Setting timeout of 47.952198ms
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.174 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.174 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.174 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 47.920779ms
server  | 1.174 DEBUG Setting timeout of 47.920779ms
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=9 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 44
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  |   -> RX MaxData { maximum_data: 16782336 }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[0..=9]
server  | 1.177 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9
server  | 1.177 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20545 ssthresh 18446744073709551615] slow start += 1534
server  | 1.177 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20545 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=1372, cwnd=20545, state=SlowStart, new_acked=1534
server  | 1.177 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 334791241 }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=13 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 20
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 17 }
server  | 1.177 DEBUG packet_sent this=0x5619a7829840, pn=13, ps=21
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 19.904942ms
server  | 1.177 DEBUG Setting timeout of 19.904942ms
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=10 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 35
server  |   -> RX Stream { stream_id: 4, offset: 0, len: 0, fin: false }
server  | 1.177 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 314914822 }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=14 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: None }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 45.319639ms
server  | 1.177 DEBUG Setting timeout of 45.319639ms
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=11 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 52
server  |   -> RX Stream { stream_id: 4, offset: 0, len: 17, fin: true }
server  | 1.177 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 335033955 }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 20ms
server  | 1.177 DEBUG Setting timeout of 20ms
server  | 1.177 DEBUG Path = 'pnnrycfqhd'
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=15 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 0, len: >>1312, fin: false }
server  | 1.177 DEBUG packet_sent this=0x5619a7829840, pn=15, ps=1332
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=16 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 1312, len: >>1310, fin: false }
server  | 1.177 DEBUG packet_sent this=0x5619a7829840, pn=16, ps=1332
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] pn=17 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 2622, len: >>1310, fin: false }
server  | 1.177 DEBUG packet_sent this=0x5619a7829840, pn=17, ps=1332
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.177 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.177 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.177 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 933.117µs
server  | 1.177 DEBUG Setting timeout of 933.117µs
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.180 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] pn=18 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 3932, len: >>1310, fin: false }
server  | 1.180 DEBUG packet_sent this=0x5619a7829840, pn=18, ps=1332
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.180 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] pn=19 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 5242, len: >>1310, fin: false }
server  | 1.180 DEBUG packet_sent this=0x5619a7829840, pn=19, ps=1332
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.180 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] pn=20 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 6552, len: >>1310, fin: false }
server  | 1.180 DEBUG packet_sent this=0x5619a7829840, pn=20, ps=1332
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.180 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.180 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 954.447µs
server  | 1.180 DEBUG Setting timeout of 954.447µs
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.180 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.180 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.180 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 932.897µs
server  | 1.180 DEBUG Setting timeout of 932.897µs
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.182 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] pn=21 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Stream { stream_id: 4, offset: 7862, len: >>1310, fin: false }
server  | 1.182 DEBUG packet_sent this=0x5619a7829840, pn=21, ps=1332
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.182 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] pn=22 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1092
server  |   TX -> Stream { stream_id: 4, offset: 9172, len: 1068, fin: true }
server  | 1.182 DEBUG packet_sent this=0x5619a7829840, pn=22, ps=1092
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.182 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.182 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 14.801758ms
server  | 1.182 DEBUG Setting timeout of 14.801758ms
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.182 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.182 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.182 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 14.783624ms
server  | 1.182 DEBUG Setting timeout of 14.783624ms
client  | pnnrycfqhd: Wrote 1312 bytes.(0 ms/0 ms/0 ms)
client  | pnnrycfqhd: Wrote 1310 bytes.(1 ms/1 ms/1 ms)
client  | pnnrycfqhd: Wrote 1310 bytes.(2 ms/1 ms/0 ms)
client  | pnnrycfqhd: Wrote 1310 bytes.(3 ms/1 ms/0 ms)
client  | pnnrycfqhd: Wrote 1310 bytes.(4 ms/1 ms/0 ms)
server  | 1.198 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.198 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.198 DEBUG [Server 2bc9fa9c5cf7694e] pn=23 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 24
server  |   TX -> Ack { largest_acknowledged: 11, ack_delay: 2661, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  | 1.198 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.198 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.198 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.198 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 23.864202ms
server  | 1.198 DEBUG Setting timeout of 23.864202ms
server  | 1.198 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.198 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.198 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.198 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 23.838263ms
server  | 1.198 DEBUG Setting timeout of 23.838263ms
client  | pnnrycfqhd: Wrote 1310 bytes.(5 ms/1 ms/0 ms)
client  | pnnrycfqhd: Wrote 1310 bytes.(6 ms/1 ms/0 ms)
client  | pnnrycfqhd: Wrote 1068 bytes.(7 ms/1 ms/0 ms)
client  | pnnrycfqhd: Completed download! (7 ms)
server  | 1.202 DEBUG [Server 2bc9fa9c5cf7694e] pn=12 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 1412
server  |   -> RX Ping
server  |   -> RX Padding { len: 1380 }
server  | 1.202 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 377, tv_nsec: 340064669 }
server  | 1.202 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 340064669 }
server  | 1.202 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.202 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.202 DEBUG [Server 2bc9fa9c5cf7694e] pn=24 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 12, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: None }
server  | 1.202 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.202 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.202 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.202 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 20.14161ms
server  | 1.202 DEBUG Setting timeout of 20.14161ms
server  | 1.202 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.202 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.202 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.202 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 20.11523ms
server  | 1.202 DEBUG Setting timeout of 20.11523ms
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] pn=13 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 39
server  |   -> RX Ack { largest_acknowledged: 13, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 13, Ce: 0})) }
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[8..=13]
server  | 1.207 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=13
server  | 1.207 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 10416/21938 ssthresh 18446744073709551615] slow start += 1393
server  | 1.207 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 10416/21938 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=10416, cwnd=21938, state=SlowStart, new_acked=1393
server  | 1.207 DEBUG PMTUD probe of size 1420 succeeded
server  | 1.207 DEBUG PMTUD started with probe size 1470
server  | 1.207 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.207 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.207 DEBUG Sending PMTUD probe of size 1470, count 1
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] pn=25 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 1422
server  |   TX -> Ping
server  |   TX -> Padding { len: 1403 }
server  | 1.207 DEBUG packet_sent this=0x5619a7829840, pn=25, ps=1422
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.207 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.207 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 14.874769ms
server  | 1.207 DEBUG Setting timeout of 14.874769ms
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.207 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.207 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.207 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 14.848761ms
server  | 1.207 DEBUG Setting timeout of 14.848761ms
server  | 1.210 DEBUG [Server 2bc9fa9c5cf7694e] pn=14 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 16, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 16, Ce: 0})) }
server  |   -> RX MaxData { maximum_data: 16784958 }
server  |   -> RX MaxStreamData { stream_id: StreamId(4), maximum_stream_data: 68158 }
server  | 1.210 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[10..=16]
server  | 1.210 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=16
server  | 1.210 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9174/24602 ssthresh 18446744073709551615] slow start += 2664
server  | 1.210 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9174/24602 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=9174, cwnd=24602, state=SlowStart, new_acked=2664
server  | 1.210 DEBUG setting max_stream_data to 68158
server  | 1.210 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 367747493 }
server  | 1.210 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.210 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.210 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.210 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 12.560396ms
server  | 1.210 DEBUG Setting timeout of 12.560396ms
server  | 1.210 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.210 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.210 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.210 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 12.469165ms
server  | 1.210 DEBUG Setting timeout of 12.469165ms
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] pn=15 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 18, ack_delay: 0, first_ack_range: 8, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 18, Ce: 0})) }
server  |   -> RX MaxData { maximum_data: 16787578 }
server  |   -> RX MaxStreamData { stream_id: StreamId(4), maximum_stream_data: 70778 }
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[10..=18]
server  | 1.212 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=18
server  | 1.212 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6510/27266 ssthresh 18446744073709551615] slow start += 2664
server  | 1.212 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6510/27266 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=6510, cwnd=27266, state=SlowStart, new_acked=2664
server  | 1.212 DEBUG setting max_stream_data to 70778
server  | 1.212 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 349952371 }
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.212 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] pn=26 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 23
server  |   TX -> Ack { largest_acknowledged: 15, ack_delay: 0, first_ack_range: 13, ack_ranges: [], ecn_count: None }
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.212 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.212 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 10.198554ms
server  | 1.212 DEBUG Setting timeout of 10.198554ms
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.212 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.212 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.212 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 10.17508ms
server  | 1.212 DEBUG Setting timeout of 10.17508ms
server  | 1.214 DEBUG [Server 2bc9fa9c5cf7694e] pn=16 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 50
server  |   -> RX Ack { largest_acknowledged: 20, ack_delay: 0, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 20, Ce: 0})) }
server  |   -> RX MaxData { maximum_data: 16790198 }
server  |   -> RX MaxStreamData { stream_id: StreamId(4), maximum_stream_data: 73398 }
server  | 1.214 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[10..=20]
server  | 1.214 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=20
server  | 1.214 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3846/29930 ssthresh 18446744073709551615] slow start += 2664
server  | 1.214 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3846/29930 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=3846, cwnd=29930, state=SlowStart, new_acked=2664
server  | 1.214 DEBUG setting max_stream_data to 73398
server  | 1.214 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 372126772 }
server  | 1.214 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.214 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.214 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.214 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 8.181117ms
server  | 1.214 DEBUG Setting timeout of 8.181117ms
server  | 1.214 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.214 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.214 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.214 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 8.112499ms
server  | 1.214 DEBUG Setting timeout of 8.112499ms
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] pn=17 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 44
server  |   -> RX Ack { largest_acknowledged: 22, ack_delay: 0, first_ack_range: 12, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 22, Ce: 0})) }
server  |   -> RX MaxData { maximum_data: 16792576 }
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[10..=22]
server  | 1.216 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=22
server  | 1.216 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1422/32354 ssthresh 18446744073709551615] slow start += 2424
server  | 1.216 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1422/32354 ssthresh 18446744073709551615] on_packets_acked this=0x5619a7829840, limited=0, bytes_in_flight=1422, cwnd=32354, state=SlowStart, new_acked=2424
server  | 1.216 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 377, tv_nsec: 354114653 }
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.216 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] pn=27 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 25
server  |   TX -> Ack { largest_acknowledged: 17, ack_delay: 0, first_ack_range: 15, ack_ranges: [], ecn_count: None }
server  |   TX -> MaxStreams { stream_type: BiDi, maximum_streams: 18 }
server  | 1.216 DEBUG packet_sent this=0x5619a7829840, pn=27, ps=25
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.216 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.216 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 6.088449ms
server  | 1.216 DEBUG Setting timeout of 6.088449ms
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.216 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.216 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.216 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 6.063102ms
server  | 1.216 DEBUG Setting timeout of 6.063102ms
server  | 1.223 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.223 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.223 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.223 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 60.919576ms
server  | 1.223 DEBUG Setting timeout of 60.919576ms
server  | 1.223 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.223 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.223 DEBUG TX blocked, profile=SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.223 DEBUG [Server 2bc9fa9c5cf7694e] delay duration 60.862309ms
server  | 1.223 DEBUG Setting timeout of 60.862309ms
server  | 1.229 DEBUG [Server 2bc9fa9c5cf7694e] pn=18 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, NotEct) len 42
server  |   -> RX Ack { largest_acknowledged: 23, ack_delay: 0, first_ack_range: 13, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 23, Ce: 0})) }
server  |   -> RX ConnectionClose { error_code: Application(0), frame_type: 0, reason_phrase: "" }
server  | 1.229 DEBUG [Server 2bc9fa9c5cf7694e] Rx ACK space=ap, ranges=[10..=23]
server  | 1.229 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=23
server  | 1.229 DEBUG on_packets_acked this=0x5619a7829840, limited=1, bytes_in_flight=1447, cwnd=32354, state=SlowStart, new_acked=0
server  | 1.229 INFO [Server 2bc9fa9c5cf7694e] ConnectionClose received. Error code: Application(0) frame type 0 reason 
server  | 1.229 DEBUG [Server 2bc9fa9c5cf7694e] State change from Confirmed -> Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 377, tv_nsec: 571479146 } }
server  | 1.229 DEBUG [pri-path: [::]:443->[::ffff:193.167.0.100]:46921] Path validated Instant { tv_sec: 377, tv_nsec: 367033739 }
server  | 1.229 DEBUG [Server 2bc9fa9c5cf7694e] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 1.229 DEBUG Building Short dcid Some(CID [0]: )
server  | 1.229 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 377, tv_nsec: 367033739 }
server  | 1.229 DEBUG [Server 2bc9fa9c5cf7694e] pn=28 type=Short pri-path: [::]:443->[::ffff:193.167.0.100]:46921 IpTos(Cs0, Ect0) len 27
server  |   TX -> Ack { largest_acknowledged: 18, ack_delay: 0, first_ack_range: 16, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 29, reason_phrase: "" }
server  | 1.229 DEBUG Setting timeout of 125.940254ms
server  | 1.229 DEBUG Setting timeout of 125.928172ms
client  | 
client  |       TARGET  VHDCRZSQUMBGA2E    VERSION   ALPN
client  |  ============================================
client  |      server4  ----R----------  0x00000001  hq-interop
client  | 
client  | Total execution time: 0.268s
client  | 
server  | 1.356 DEBUG [Server af22cc6e4166a6b5] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 377, tv_nsec: 493123143 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 1.356 INFO Closing timer expired
server  | 1.357 DEBUG Setting timeout of 76.97576ms
server  | 1.357 DEBUG Setting timeout of 76.704612ms
server  | 1.434 DEBUG [Server 2bc9fa9c5cf7694e] State change from Draining { error: Transport(PeerApplicationError(0)), timeout: Instant { tv_sec: 377, tv_nsec: 571479146 } } -> Closed(Transport(PeerApplicationError(0)))
server  | 1.434 INFO Closing timer expired
client  | Client complete.
client  | Script complete.

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 11:48:00,046 Using the client's key log file.
2025-04-30 11:48:00,056 
2025-04-30 11:48:00,057 Using the client's key log file.
2025-04-30 11:48:00,057 Using the client's key log file.
2025-04-30 11:48:00,066 
2025-04-30 11:48:00,067 Using the client's key log file.
2025-04-30 11:48:00,067 Using the client's key log file.
2025-04-30 11:48:00,967 Check of downloaded files succeeded.