2025-04-30 12:32:55,447 Generated random file: ugxmlnrzzd of size: 5120
2025-04-30 12:32:55,448 Generated random file: viikfjfjnb of size: 10240
2025-04-30 12:32:55,448 Requests: https://server4:443/ugxmlnrzzd https://server4:443/viikfjfjnb
2025-04-30 12:32:55,506 
2025-04-30 12:32:55,506 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_fb5s35v_/ TESTCASE_SERVER=resumption TESTCASE_CLIENT=resumption WWW=/tmp/www_xn26a0de/ DOWNLOADS=/tmp/download_h3jhe9hk/ SERVER_LOGS=/tmp/logs_server_wbsngty3 CLIENT_LOGS=/tmp/logs_client_he2zxel5 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=peterdoornbosch/kwik_n_flupke-interop SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/ugxmlnrzzd https://server4:443/viikfjfjnb"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:33:08,916  Container sim  Created
 Container client  Recreate
 Container server  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
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.wXcaPfvEMC
server  | + mkdir -p /neqo/db
client  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | tx-tcp-segmentation: off [not requested]
client  | tx-tcp-ecn-segmentation: off [not requested]
client  | tx-tcp-mangleid-segmentation: off [not requested]
client  | tx-tcp6-segmentation: off [not requested]
client  | tx-udp-segmentation: off [not requested]
client  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.wXcaPfvEMC
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.wXcaPfvEMC -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  |             2d:61:88:48:0a:c2:27:c1:dd:d8:86:8f:4d:d4:20:b6:
server  |             1e:5b:7b:96
server  |         Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |         Issuer: "O=interop runner Root Certificate Authority"
server  |         Validity:
server  |             Not Before: Wed Apr 30 12:32:55 2025
server  |             Not After : Sat May 10 12:32: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:aa:3b:6f:b2:41:69:be:2e:8f:3e:ab:ad:ae:d3:7a:
server  |                     e5:73:b2:53:da:24:a9:41:a8:fe:1e:f3:02:54:89:2f:
server  |                     e5:28:47:ca:0a:71:ab:39:b1:f5:cd:e4:1f:65:49:0c:
server  |                     f1:0e:bb:be:a7:7e:24:1e:d1:5f:08:c9:ca:db:3a:8a:
server  |                     57
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  |                 ec:2e:9b:f5:08:a5:b7:ab:dc:62:3a:c8:87:61:dc:04:
server  |                 99:1b:df:13
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 d8:c3:a1:18:43:06:86:63:2c:f5:fd:35:f0:c7:12:b5:
server  |                 12:64:9e:c8
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:46:02:21:00:8e:31:22:c8:64:d4:db:fe:40:a0:8e:
server  |         c2:e6:19:4a:5a:70:e3:0a:6e:12:24:df:f0:84:78:42:
server  |         15:07:51:9f:69:02:21:00:97:e2:df:8c:45:26:15:10:
server  |         fa:92:67:ab:51:20:09:e1:7c:b6:38:e9:81:80:e7:f3:
server  |         1d:75:93:24:83:39:d8:d4
server  |     Fingerprint (SHA-256):
server  |         A2:50:0C:77:12:BF:99:B8:5D:B6:D0:2F:E8:92:16:F9:41:E6:FE:AD:46:5C:4D:50:09:93:57:63:FE:42:9C:F3
server  |     Fingerprint (SHA1):
server  |         98:B9:82:52:7F:FB:55:B1:87:96:70:18:13:E6:2F:F8:50:63:D0:56
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.004 DEBUG Default socket send buffer size is 212992
server  | 0.004 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.004 INFO Server waiting for connection on: [::]:443
client  | Certificate was added to keystore
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | 0.759 DEBUG [Server] Unsupported version: 57414954
server  | 0.759 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48963 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.013004731s
server  | 0.759 DEBUG [Server] Unsupported version: 57414954
server  | 0.759 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48963 IpTos(Cs0, NotEct) len 15
server  | 0.759 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.759 DEBUG [Server] Unsupported version: 57414954
server  | 0.759 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48963 IpTos(Cs0, NotEct) len 15
server  | 0.759 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 eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | Writing keys to /logs/keys.log
server  | 1.852 DEBUG [Server] Handle initial
server  | 1.852 INFO AddressValidation: no token; accepting
server  | 1.852 INFO [Server] Accept connection CID [8]: c2201c4a68ceb18c
server  | 1.852 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.853 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.854 INFO [Server ...] Received valid Initial packet with scid CID [8]: fdd51f78e8bca81e dcid CID [8]: c2201c4a68ceb18c
server  | 1.854 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=c2201c4a68ceb18c
server  | 1.854 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.854 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.854 DEBUG [Server c2201c4a68ceb18c] State change from Init -> WaitInitial
server  | 1.854 DEBUG [Server c2201c4a68ceb18c] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 1200
server  |   -> RX Crypto { offset: 0, len: 257 }
server  |   -> RX Padding { len: 896 }
server  | 1.854 DEBUG Read Ok(257) bytes
server  | 1.854 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.855 DEBUG Read secret available for Handshake: SymKey [32]: a70ee601772c2ab081f5af5c4b0fef3a93137cab2f3b1483f0a4139ee18fa6b1
server  | 1.855 DEBUG Write secret available for Handshake: SymKey [32]: dc43f925c6da625888d21042706486e9aa7653d7377c4a059c0067d11a11baa3
server  | 1.855 DEBUG Writing transport parameters, msg=8
server  | 1.855 DEBUG Read secret available for ApplicationData: SymKey [32]: 12f262ad6c4c19c3e4766b81c258f67477b6689a31fdd386d56a6523ad9e3adc
server  | 1.855 DEBUG Write secret available for ApplicationData: SymKey [32]: 2312282c5bb1f3f2653b58938b392d4afef2b1e6cefb7e2f97c22ce622079ee1
server  | 1.855 DEBUG [Agent 0x56135afca1d0] state -> InProgress
server  | 1.855 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.855 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.855 DEBUG [Crypto] Handshake keys installed
server  | 1.855 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.855 DEBUG [Crypto] Application write key installed
server  | 1.855 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 536, tv_nsec: 393896894 }
server  | 1.855 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:59850] Make permanent
server  | 1.855 DEBUG [unv-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850] set as primary path
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] State change from WaitInitial -> Handshaking
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.855 DEBUG Building Initial dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.855 DEBUG CRYPTO for in offset=0, len=123
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] pn=0 type=Initial pri-unv-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 177
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 123 }
server  | 1.855 DEBUG Building Handshake dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.855 DEBUG CRYPTO for hs offset=0, len=721
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] pn=0 type=Handshake pri-unv-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 946
server  |   TX -> Crypto { offset: 0, len: 721 }
server  | 1.855 DEBUG packet_sent this=0x56135afc9b40, pn=0, ps=769
server  | 1.855 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] pad Initial from 946 to PLPMTU 1232
server  | 1.855 DEBUG packet_sent this=0x56135afc9b40, pn=0, ps=463
server  | 1.855 DEBUG ECN probing: sent 1 probes
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.855 DEBUG Building Initial dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.855 DEBUG Building Handshake dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.855 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.855 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] delay duration 296.380159ms
server  | 1.855 DEBUG Setting timeout of 296.380159ms
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.855 DEBUG Building Initial dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.855 DEBUG Building Handshake dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.855 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.855 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.855 DEBUG [Server c2201c4a68ceb18c] delay duration 296.349693ms
server  | 1.855 DEBUG Setting timeout of 296.349693ms
server  | 1.941 DEBUG [Server c2201c4a68ceb18c] pn=1 type=Initial pri-unv-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 1201
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 1151 }
server  | 1.941 DEBUG [Server c2201c4a68ceb18c] Rx ACK space=in, ranges=[0..=0]
server  | 1.941 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 1.941 DEBUG on_packets_acked this=0x56135afc9b40, limited=1, bytes_in_flight=769, cwnd=12320, state=SlowStart, new_acked=463
server  | 1.941 DEBUG Acked crypto frame space=in offset=0 length=123
server  | 1.941 DEBUG [pri-unv-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850] Path validated Instant { tv_sec: 536, tv_nsec: 483190716 }
server  | 1.941 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.941 DEBUG Building Initial dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.941 DEBUG Building Handshake dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.941 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.941 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.941 DEBUG [Server c2201c4a68ceb18c] delay duration 178.587644ms
server  | 1.941 DEBUG Setting timeout of 178.587644ms
server  | 1.941 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.941 DEBUG Building Initial dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.941 DEBUG Building Handshake dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.941 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.941 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.941 DEBUG [Server c2201c4a68ceb18c] delay duration 178.422767ms
server  | 1.941 DEBUG Setting timeout of 178.422767ms
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] Drop packet number space in
server  | 1.977 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] pn=0 type=Handshake pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 82
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 1.977 DEBUG Read Ok(36) bytes
server  | 1.977 DEBUG [Agent 0x56135afca1d0] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: false, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] TLS connection complete
server  | 1.977 DEBUG [pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850] Path validated Instant { tv_sec: 536, tv_nsec: 519245430 }
server  | 1.977 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.977 DEBUG [Crypto] application read keys installed
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] State change from Handshaking -> Connected
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] State change from Connected -> Confirmed
server  | 1.977 DEBUG PMTUD started with probe size 1380
server  | 1.977 INFO [Server c2201c4a68ceb18c] Connection established
server  | 1.977 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 536, tv_nsec: 519245430 }
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.977 DEBUG Building Handshake dcid Some(CID [8]: fdd51f78e8bca81e) scid Some(CID [10]: 20bfba5a6dd3b80026d7)
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] pn=1 type=Handshake pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 49
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] Drop packet number space hs
server  | 1.977 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.977 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] pn=0 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [64, 104, 222, 199, 154, 16, 30, 220, 154, 124], stateless_reset_token: [79, 35, 227, 118, 74, 66, 73, 129, 219, 99, 128, 123, 13, 31, 199, 69] }
server  |   TX -> Padding { len: 1226 }
server  | 1.977 DEBUG packet_sent this=0x56135afc9b40, pn=0, ps=1283
server  | 1.977 DEBUG ECN probing: sent 2 probes
server  | 1.977 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.977 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.978 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.978 DEBUG [Server c2201c4a68ceb18c] pn=1 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 1.978 DEBUG packet_sent this=0x56135afc9b40, pn=1, ps=1332
server  | 1.978 DEBUG ECN probing: sent 3 probes
server  | 1.978 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.978 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.978 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.978 DEBUG [Server c2201c4a68ceb18c] delay duration 4.408096ms
server  | 1.978 DEBUG Setting timeout of 4.408096ms
server  | 1.979 DEBUG [Server c2201c4a68ceb18c] send session ticket 40630008c2201c4a68ceb18c0104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a20bfba5a6dd3b80026d7110c000000016aea3aea000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.979 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.979 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.979 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.979 DEBUG [Server c2201c4a68ceb18c] delay duration 3.34172ms
server  | 1.979 DEBUG Setting timeout of 3.34172ms
server  | 1.983 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.983 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.983 DEBUG CRYPTO for ap offset=0, len=305
server  | 1.983 DEBUG [Server c2201c4a68ceb18c] pn=2 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 380
server  |   TX -> Crypto { offset: 0, len: 305 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 29, 160, 140, 222, 0, 234, 106, 171, 105, 207, 149, 214, 28, 212, 182, 120, 183, 77, 221, 249, 58, 62, 143, 207, 162, 43, 232, 74, 15, 32, 254, 0, 94, 255, 125, 181] }
server  | 1.983 DEBUG packet_sent this=0x56135afc9b40, pn=2, ps=380
server  | 1.983 DEBUG ECN probing: sent 4 probes
server  | 1.983 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.983 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.983 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.983 DEBUG [Server c2201c4a68ceb18c] delay duration 292.693427ms
server  | 1.983 DEBUG Setting timeout of 292.693427ms
server  | 1.983 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.983 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.983 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.983 DEBUG [Server c2201c4a68ceb18c] delay duration 292.673599ms
server  | 1.983 DEBUG Setting timeout of 292.673599ms
server  | 1.983 WARN [Server ...] Dropped received packet: Decryption failure; Total: 1
server  | 1.984 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.984 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.984 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.984 DEBUG [Server c2201c4a68ceb18c] delay duration 292.116392ms
server  | 1.984 DEBUG Setting timeout of 292.116392ms
server  | 1.984 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.984 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 1.984 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.984 DEBUG [Server c2201c4a68ceb18c] delay duration 292.083791ms
server  | 1.984 DEBUG Setting timeout of 292.083791ms
server  | 2.005 DEBUG [Server c2201c4a68ceb18c] pn=0 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 49
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 2.005 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 536, tv_nsec: 567509717 }
server  | 2.005 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.005 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG [Server c2201c4a68ceb18c] delay duration 20ms
server  | 2.005 DEBUG Setting timeout of 20ms
server  | 2.005 DEBUG Path = 'ugxmlnrzzd'
server  | 2.005 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.005 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.005 DEBUG [Server c2201c4a68ceb18c] pn=3 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false }
server  | 2.005 DEBUG packet_sent this=0x56135afc9b40, pn=3, ps=1232
server  | 2.006 DEBUG ECN probing: sent 5 probes
server  | 2.006 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.006 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.006 DEBUG [Server c2201c4a68ceb18c] pn=4 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false }
server  | 2.006 DEBUG packet_sent this=0x56135afc9b40, pn=4, ps=1232
server  | 2.006 DEBUG ECN probing: sent 6 probes
server  | 2.006 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.006 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.006 DEBUG [Server c2201c4a68ceb18c] pn=5 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false }
server  | 2.006 DEBUG packet_sent this=0x56135afc9b40, pn=5, ps=1232
server  | 2.006 DEBUG ECN probing: sent 7 probes
server  | 2.006 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.006 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.006 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.006 DEBUG [Server c2201c4a68ceb18c] delay duration 4.265211ms
server  | 2.006 DEBUG Setting timeout of 4.265211ms
server  | 2.011 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.011 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.011 DEBUG [Server c2201c4a68ceb18c] pn=6 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false }
server  | 2.011 DEBUG packet_sent this=0x56135afc9b40, pn=6, ps=1232
server  | 2.011 DEBUG ECN probing: sent 8 probes
server  | 2.011 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.011 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.011 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.011 DEBUG [Server c2201c4a68ceb18c] delay duration 3.521223ms
server  | 2.011 DEBUG Setting timeout of 3.521223ms
server  | 2.011 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.011 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.011 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.011 DEBUG [Server c2201c4a68ceb18c] delay duration 3.464831ms
server  | 2.011 DEBUG Setting timeout of 3.464831ms
server  | 2.012 DEBUG [Server c2201c4a68ceb18c] pn=1 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 33
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 2.012 DEBUG [Server c2201c4a68ceb18c] Rx ACK space=ap, ranges=[0..=1]
server  | 2.012 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 2.012 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5308/14935 ssthresh 18446744073709551615] slow start += 2615
server  | 2.012 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 5308/14935 ssthresh 18446744073709551615] on_packets_acked this=0x56135afc9b40, limited=0, bytes_in_flight=5308, cwnd=14935, state=SlowStart, new_acked=2615
server  | 2.012 DEBUG PMTUD probe of size 1380 succeeded
server  | 2.012 DEBUG PMTUD started with probe size 1420
server  | 2.012 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 2.012 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.012 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.012 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.012 DEBUG [Server c2201c4a68ceb18c] delay duration 1.479766ms
server  | 2.012 DEBUG Setting timeout of 1.479766ms
server  | 2.012 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.012 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.012 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.012 DEBUG [Server c2201c4a68ceb18c] delay duration 1.41191ms
server  | 2.012 DEBUG Setting timeout of 1.41191ms
server  | 2.015 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.015 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.015 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 2.015 DEBUG [Server c2201c4a68ceb18c] pn=7 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 4810, len: 310, fin: true }
server  |   TX -> Padding { len: 1029 }
server  | 2.016 DEBUG packet_sent this=0x56135afc9b40, pn=7, ps=1372
server  | 2.016 DEBUG ECN probing: sent 9 probes
server  | 2.016 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.016 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.016 DEBUG [Server c2201c4a68ceb18c] delay duration 2.040777ms
server  | 2.016 DEBUG Setting timeout of 2.040777ms
server  | 2.016 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.016 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.016 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.016 DEBUG [Server c2201c4a68ceb18c] delay duration 2.020169ms
server  | 2.016 DEBUG Setting timeout of 2.020169ms
server  | 2.019 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.019 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.019 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.019 DEBUG [Server c2201c4a68ceb18c] delay duration 6.642123ms
server  | 2.019 DEBUG Setting timeout of 6.642123ms
server  | 2.019 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.019 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.019 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.019 DEBUG [Server c2201c4a68ceb18c] delay duration 6.617507ms
server  | 2.019 DEBUG Setting timeout of 6.617507ms
server  | 2.026 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.026 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.026 DEBUG [Server c2201c4a68ceb18c] pn=8 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, Ect0) len 32
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 1681, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 2.026 DEBUG ECN probing: sent 10 probes
server  | 2.026 DEBUG ECN probing concluded with 10 probes sent
server  | 2.026 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.026 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.026 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.026 DEBUG [Server c2201c4a68ceb18c] delay duration 285.491207ms
server  | 2.026 DEBUG Setting timeout of 285.491207ms
server  | 2.026 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.026 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.026 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.026 DEBUG [Server c2201c4a68ceb18c] delay duration 285.468485ms
server  | 2.026 DEBUG Setting timeout of 285.468485ms
server  | 2.035 DEBUG [Server c2201c4a68ceb18c] pn=2 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 2, ack_delay: 2375, first_ack_range: 2, ack_ranges: [], ecn_count: None }
server  | 2.035 DEBUG [Server c2201c4a68ceb18c] Rx ACK space=ap, ranges=[0..=2]
server  | 2.035 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=2
server  | 2.035 WARN ECN validation failed, no ECN counts in ACK frame
server  | 2.035 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6300/15315 ssthresh 18446744073709551615] slow start += 380
server  | 2.035 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6300/15315 ssthresh 18446744073709551615] on_packets_acked this=0x56135afc9b40, limited=0, bytes_in_flight=6300, cwnd=15315, state=SlowStart, new_acked=380
server  | 2.035 DEBUG Acked crypto frame space=ap offset=0 length=305
server  | 2.035 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.035 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.035 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.035 DEBUG [Server c2201c4a68ceb18c] delay duration 256.379646ms
server  | 2.035 DEBUG Setting timeout of 256.379646ms
server  | 2.035 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.035 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.035 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.035 DEBUG [Server c2201c4a68ceb18c] delay duration 256.308534ms
server  | 2.035 DEBUG Setting timeout of 256.308534ms
server  | 2.039 DEBUG [Server c2201c4a68ceb18c] pn=3 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 33
server  |   -> RX Ack { largest_acknowledged: 4, ack_delay: 0, first_ack_range: 4, ack_ranges: [], ecn_count: None }
server  | 2.039 DEBUG [Server c2201c4a68ceb18c] Rx ACK space=ap, ranges=[0..=4]
server  | 2.039 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=4
server  | 2.039 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3836/17779 ssthresh 18446744073709551615] slow start += 2464
server  | 2.039 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3836/17779 ssthresh 18446744073709551615] on_packets_acked this=0x56135afc9b40, limited=0, bytes_in_flight=3836, cwnd=17779, state=SlowStart, new_acked=2464
server  | 2.039 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.039 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.039 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.039 DEBUG [Server c2201c4a68ceb18c] delay duration 248.696469ms
server  | 2.039 DEBUG Setting timeout of 248.696469ms
server  | 2.039 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.039 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.039 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.039 DEBUG [Server c2201c4a68ceb18c] delay duration 248.643149ms
server  | 2.039 DEBUG Setting timeout of 248.643149ms
server  | 2.043 DEBUG [Server c2201c4a68ceb18c] pn=4 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 6, ack_delay: 250, first_ack_range: 6, ack_ranges: [], ecn_count: None }
server  | 2.043 DEBUG [Server c2201c4a68ceb18c] Rx ACK space=ap, ranges=[0..=6]
server  | 2.043 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=6
server  | 2.043 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20243 ssthresh 18446744073709551615] slow start += 2464
server  | 2.043 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/20243 ssthresh 18446744073709551615] on_packets_acked this=0x56135afc9b40, limited=0, bytes_in_flight=1372, cwnd=20243, state=SlowStart, new_acked=2464
server  | 2.043 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.043 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.043 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.043 DEBUG [Server c2201c4a68ceb18c] delay duration 236.269625ms
server  | 2.043 DEBUG Setting timeout of 236.269625ms
server  | 2.043 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.043 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.043 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.043 DEBUG [Server c2201c4a68ceb18c] delay duration 236.172834ms
server  | 2.043 DEBUG Setting timeout of 236.172834ms
server  | 2.057 DEBUG [Server c2201c4a68ceb18c] pn=5 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 38
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 1000, first_ack_range: 7, ack_ranges: [], ecn_count: None }
server  | 2.057 INFO [Server c2201c4a68ceb18c] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 2.057 DEBUG [Server c2201c4a68ceb18c] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 537, tv_nsec: 390881853 } }
server  | 2.057 DEBUG [Server c2201c4a68ceb18c] Rx ACK space=ap, ranges=[0..=7]
server  | 2.057 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
server  | 2.057 DEBUG on_packets_acked this=0x56135afc9b40, limited=1, bytes_in_flight=0, cwnd=20243, state=SlowStart, new_acked=1372
server  | 2.057 DEBUG PMTUD probe of size 1420 succeeded
server  | 2.057 DEBUG PMTUD started with probe size 1470
server  | 2.057 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 2.057 DEBUG [pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850] Path validated Instant { tv_sec: 536, tv_nsec: 598679845 }
server  | 2.057 DEBUG [Server c2201c4a68ceb18c] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 2.057 DEBUG Building Short dcid Some(CID [8]: fdd51f78e8bca81e)
server  | 2.057 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 536, tv_nsec: 598679845 }
server  | 2.057 DEBUG [Server c2201c4a68ceb18c] pn=9 type=Short pri-path:fdd51f78e8bca81e [::]:443->[::ffff:193.167.0.100]:59850 IpTos(Cs0, NotEct) len 35
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 5, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 2.057 DEBUG Setting timeout of 792.023245ms
server  | 2.057 DEBUG Setting timeout of 792.012575ms
server  | 2.058 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 537, tv_nsec: 390881853 } }; Total: 2
server  | 2.058 DEBUG Setting timeout of 791.068888ms
server  | 2.058 DEBUG Setting timeout of 791.051616ms
server  | 2.065 DEBUG [Server] Handle initial
server  | 2.065 INFO AddressValidation: no token; accepting
server  | 2.065 INFO [Server] Accept connection CID [8]: d4853f6551a94d51
server  | 2.065 DEBUG Overwrite initial version Version1 ==> Version1
server  | 2.067 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 2.067 INFO [Server ...] Received valid Initial packet with scid CID [8]: 0e3e8adda5cf4db8 dcid CID [8]: d4853f6551a94d51
server  | 2.067 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=d4853f6551a94d51
server  | 2.067 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 2.067 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 2.067 DEBUG [Server d4853f6551a94d51] State change from Init -> WaitInitial
server  | 2.067 DEBUG [Server d4853f6551a94d51] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 1200
server  |   -> RX Crypto { offset: 0, len: 578 }
server  |   -> RX Padding { len: 575 }
server  | 2.067 DEBUG Read Ok(578) bytes
server  | 2.067 INFO 0-RTT: transport parameters OK, passing to application checker
server  | 2.067 WARN AllowZeroRtt accepting 0-RTT
server  | 2.068 DEBUG Read secret available for Handshake: SymKey [32]: ef6ec1dad8ccdf5f47456725b322c6ed2e085248654f282ff53efb5a0c41ba47
server  | 2.068 DEBUG Write secret available for Handshake: SymKey [32]: 5445452e5e56b436cc7248ee582ee7f30a15136ba8451a0c051cc3c40578069c
server  | 2.068 DEBUG Writing transport parameters, msg=8
server  | 2.069 DEBUG Read secret available for ApplicationData: SymKey [32]: a4c76cc01fdf0f21e53e54fc8707533cefd73018e153286d7b9ec7e564359ff8
server  | 2.069 DEBUG Write secret available for ApplicationData: SymKey [32]: 7d49932b6c8d0a565b67b1e1539e1229f0ddc1f7f94c4a09dbb7e47ffa7a0844
server  | 2.069 DEBUG [Agent 0x56135affd880] state -> InProgress
server  | 2.069 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 2.069 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 2.069 DEBUG [Crypto] Handshake keys installed
server  | 2.069 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 2.069 DEBUG [Crypto] Application write key installed
server  | 2.069 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 536, tv_nsec: 607653675 }
server  | 2.069 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:46511] Make permanent
server  | 2.069 DEBUG [unv-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511] set as primary path
server  | 2.069 DEBUG [Server d4853f6551a94d51] State change from WaitInitial -> Handshaking
server  | 2.069 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.069 DEBUG Building Initial dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.069 DEBUG CRYPTO for in offset=0, len=129
server  | 2.069 DEBUG [Server d4853f6551a94d51] pn=0 type=Initial pri-unv-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 183
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   TX -> Crypto { offset: 0, len: 129 }
server  | 2.069 DEBUG Building Handshake dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.069 DEBUG CRYPTO for hs offset=0, len=170
server  | 2.069 DEBUG [Server d4853f6551a94d51] pn=0 type=Handshake pri-unv-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 401
server  |   TX -> Crypto { offset: 0, len: 170 }
server  | 2.069 DEBUG packet_sent this=0x56135af07840, pn=0, ps=218
server  | 2.069 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.069 DEBUG [Server d4853f6551a94d51] pad Initial from 401 to PLPMTU 1232
server  | 2.069 DEBUG packet_sent this=0x56135af07840, pn=0, ps=1014
server  | 2.069 DEBUG ECN probing: sent 1 probes
server  | 2.069 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.069 DEBUG Building Initial dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.069 DEBUG Building Handshake dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.069 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.069 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.069 DEBUG [Server d4853f6551a94d51] delay duration 296.529828ms
server  | 2.069 DEBUG Setting timeout of 296.529828ms
server  | 2.069 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.069 DEBUG Building Initial dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.069 DEBUG Building Handshake dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.069 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.069 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.069 DEBUG [Server d4853f6551a94d51] delay duration 296.501395ms
server  | 2.069 DEBUG Setting timeout of 296.501395ms
server  | 2.105 DEBUG [Server d4853f6551a94d51] pn=1 type=Initial pri-unv-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 1201
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 1151 }
server  | 2.105 DEBUG [Server d4853f6551a94d51] Rx ACK space=in, ranges=[0..=0]
server  | 2.105 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 2.105 DEBUG on_packets_acked this=0x56135af07840, limited=1, bytes_in_flight=218, cwnd=12320, state=SlowStart, new_acked=1014
server  | 2.105 DEBUG Acked crypto frame space=in offset=0 length=129
server  | 2.105 DEBUG [pri-unv-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511] Path validated Instant { tv_sec: 536, tv_nsec: 647593486 }
server  | 2.105 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.106 DEBUG Building Initial dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.106 DEBUG Building Handshake dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.106 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.106 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.106 DEBUG [Server d4853f6551a94d51] delay duration 79.87962ms
server  | 2.106 DEBUG Setting timeout of 79.87962ms
server  | 2.106 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.106 DEBUG Building Initial dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.106 DEBUG Building Handshake dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.106 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.106 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.106 DEBUG [Server d4853f6551a94d51] delay duration 79.734811ms
server  | 2.106 DEBUG Setting timeout of 79.734811ms
server  | 2.106 DEBUG [Server d4853f6551a94d51] Drop packet number space in
server  | 2.106 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 2.106 DEBUG [Server d4853f6551a94d51] pn=0 type=Handshake pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 82
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 2.106 DEBUG Read Ok(36) bytes
server  | 2.107 DEBUG [Agent 0x56135affd880] state -> Complete(SecretAgentInfo { version: 772, cipher: 4865, group: 23, resumed: true, early_data: false, ech_accepted: false, alpn: Some("hq-interop"), signature_scheme: 1027 })
server  | 2.107 DEBUG [Server d4853f6551a94d51] TLS connection complete
server  | 2.107 DEBUG [pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511] Path validated Instant { tv_sec: 536, tv_nsec: 648535820 }
server  | 2.107 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 2.107 DEBUG [Crypto] application read keys installed
server  | 2.107 DEBUG [Server d4853f6551a94d51] State change from Handshaking -> Connected
server  | 2.107 DEBUG [Server d4853f6551a94d51] State change from Connected -> Confirmed
server  | 2.107 DEBUG PMTUD started with probe size 1380
server  | 2.107 INFO [Server d4853f6551a94d51] Connection established
server  | 2.107 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 536, tv_nsec: 648535820 }
server  | 2.107 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.107 DEBUG Building Handshake dcid Some(CID [8]: 0e3e8adda5cf4db8) scid Some(CID [10]: 48dc25e79f9a0e954336)
server  | 2.107 DEBUG [Server d4853f6551a94d51] pn=1 type=Handshake pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 49
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 2.107 DEBUG [Server d4853f6551a94d51] Drop packet number space hs
server  | 2.107 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 2.107 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.107 DEBUG [Server d4853f6551a94d51] pn=0 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [133, 70, 214, 141, 226, 82, 125, 27, 247, 64], stateless_reset_token: [114, 247, 34, 37, 210, 251, 15, 10, 53, 156, 101, 62, 220, 176, 121, 216] }
server  |   TX -> Padding { len: 1226 }
server  | 2.107 DEBUG packet_sent this=0x56135af07840, pn=0, ps=1283
server  | 2.107 DEBUG ECN probing: sent 2 probes
server  | 2.107 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.107 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.107 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 2.107 DEBUG [Server d4853f6551a94d51] pn=1 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1305 }
server  | 2.107 DEBUG packet_sent this=0x56135af07840, pn=1, ps=1332
server  | 2.107 DEBUG ECN probing: sent 3 probes
server  | 2.107 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.107 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.107 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.107 DEBUG [Server d4853f6551a94d51] delay duration 1.712837ms
server  | 2.107 DEBUG Setting timeout of 1.712837ms
server  | 2.107 DEBUG [Server d4853f6551a94d51] send session ticket 40630008d4853f6551a94d510104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a48dc25e79f9a0e954336110c000000018a5aea3a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 2.107 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.107 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.107 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.107 DEBUG [Server d4853f6551a94d51] delay duration 1.582895ms
server  | 2.107 DEBUG Setting timeout of 1.582895ms
server  | 2.108 WARN [Server ...] Dropped received packet: Decryption failure; Total: 1
server  | 2.108 DEBUG [Server d4853f6551a94d51] pn=0 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 49
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 2.108 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 536, tv_nsec: 669700697 }
server  | 2.108 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.108 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.108 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.108 DEBUG [Server d4853f6551a94d51] delay duration 1.078368ms
server  | 2.108 DEBUG Setting timeout of 1.078368ms
server  | 2.108 DEBUG Path = 'viikfjfjnb'
server  | 2.108 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.108 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.108 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.108 DEBUG [Server d4853f6551a94d51] delay duration 943.216µs
server  | 2.108 DEBUG Setting timeout of 943.216µs
server  | 2.109 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.109 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.109 DEBUG [Server d4853f6551a94d51] pn=2 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1204, fin: false }
server  | 2.109 DEBUG packet_sent this=0x56135af07840, pn=2, ps=1232
server  | 2.109 DEBUG ECN probing: sent 4 probes
server  | 2.109 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.109 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.109 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.109 DEBUG [Server d4853f6551a94d51] delay duration 1.753998ms
server  | 2.109 DEBUG Setting timeout of 1.753998ms
server  | 2.109 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.109 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.109 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.109 DEBUG [Server d4853f6551a94d51] delay duration 1.732117ms
server  | 2.109 DEBUG Setting timeout of 1.732117ms
server  | 2.111 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.111 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.111 DEBUG [Server d4853f6551a94d51] pn=3 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1204, len: >>1202, fin: false }
server  | 2.111 DEBUG packet_sent this=0x56135af07840, pn=3, ps=1232
server  | 2.111 DEBUG ECN probing: sent 5 probes
server  | 2.111 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.111 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.111 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.111 DEBUG [Server d4853f6551a94d51] delay duration 1.576494ms
server  | 2.111 DEBUG Setting timeout of 1.576494ms
server  | 2.111 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.111 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.111 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.111 DEBUG [Server d4853f6551a94d51] delay duration 1.555305ms
server  | 2.111 DEBUG Setting timeout of 1.555305ms
server  | 2.113 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.113 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.113 DEBUG [Server d4853f6551a94d51] pn=4 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2406, len: >>1202, fin: false }
server  | 2.113 DEBUG packet_sent this=0x56135af07840, pn=4, ps=1232
server  | 2.113 DEBUG ECN probing: sent 6 probes
server  | 2.113 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.113 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.113 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.113 DEBUG [Server d4853f6551a94d51] delay duration 1.405006ms
server  | 2.113 DEBUG Setting timeout of 1.405006ms
server  | 2.113 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.113 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.113 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.113 DEBUG [Server d4853f6551a94d51] delay duration 1.39127ms
server  | 2.113 DEBUG Setting timeout of 1.39127ms
server  | 2.115 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.115 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.115 DEBUG [Server d4853f6551a94d51] pn=5 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3608, len: >>1202, fin: false }
server  | 2.115 DEBUG packet_sent this=0x56135af07840, pn=5, ps=1232
server  | 2.115 DEBUG ECN probing: sent 7 probes
server  | 2.115 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.115 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.115 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.115 DEBUG [Server d4853f6551a94d51] delay duration 1.2428ms
server  | 2.115 DEBUG Setting timeout of 1.2428ms
server  | 2.115 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.115 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.115 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.115 DEBUG [Server d4853f6551a94d51] delay duration 1.222451ms
server  | 2.115 DEBUG Setting timeout of 1.222451ms
server  | 2.117 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.117 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.117 DEBUG [Server d4853f6551a94d51] pn=6 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 4810, len: >>1202, fin: false }
server  | 2.117 DEBUG packet_sent this=0x56135af07840, pn=6, ps=1232
server  | 2.117 DEBUG ECN probing: sent 8 probes
server  | 2.118 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.118 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.118 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.118 DEBUG [Server d4853f6551a94d51] delay duration 1.066041ms
server  | 2.118 DEBUG Setting timeout of 1.066041ms
server  | 2.118 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.118 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.118 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.118 DEBUG [Server d4853f6551a94d51] delay duration 1.048338ms
server  | 2.118 DEBUG Setting timeout of 1.048338ms
server  | 2.120 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.120 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.120 DEBUG [Server d4853f6551a94d51] pn=7 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 6012, len: >>1202, fin: false }
server  | 2.120 DEBUG packet_sent this=0x56135af07840, pn=7, ps=1232
server  | 2.120 DEBUG ECN probing: sent 9 probes
server  | 2.120 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.120 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.120 DEBUG [Server d4853f6551a94d51] pn=8 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 7214, len: >>1202, fin: false }
server  | 2.120 DEBUG packet_sent this=0x56135af07840, pn=8, ps=1232
server  | 2.120 DEBUG ECN probing: sent 10 probes
server  | 2.120 DEBUG ECN probing concluded with 10 probes sent
server  | 2.120 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1081, pto: None, probe: EnumSet(), paced: false }
server  | 2.120 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.120 DEBUG [Server d4853f6551a94d51] pn=9 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 1081
server  |   TX -> Stream { stream_id: 0, offset: 8416, len: >>1051, fin: false }
server  | 2.120 DEBUG packet_sent this=0x56135af07840, pn=9, ps=1081
server  | 2.120 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.120 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.120 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.120 DEBUG [Server d4853f6551a94d51] delay duration 7.716748ms
server  | 2.120 DEBUG Setting timeout of 7.716748ms
server  | 2.120 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.120 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.120 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.120 DEBUG [Server d4853f6551a94d51] delay duration 7.684498ms
server  | 2.120 DEBUG Setting timeout of 7.684498ms
server  | 2.128 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.128 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.128 DEBUG [Server d4853f6551a94d51] pn=10 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 32
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 2550, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  | 2.128 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.128 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.128 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.128 DEBUG [Server d4853f6551a94d51] delay duration 136.552116ms
server  | 2.128 DEBUG Setting timeout of 136.552116ms
server  | 2.128 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.128 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.128 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 2.128 DEBUG [Server d4853f6551a94d51] delay duration 136.533432ms
server  | 2.128 DEBUG Setting timeout of 136.533432ms
server  | 2.141 DEBUG [Server d4853f6551a94d51] pn=1 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 33
server  |   -> RX Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: None }
server  | 2.141 DEBUG [Server d4853f6551a94d51] Rx ACK space=ap, ranges=[0..=1]
server  | 2.141 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=1
server  | 2.141 WARN ECN validation failed, no ECN counts in ACK frame
server  | 2.141 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9705/14935 ssthresh 18446744073709551615] slow start += 2615
server  | 2.141 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 9705/14935 ssthresh 18446744073709551615] on_packets_acked this=0x56135af07840, limited=0, bytes_in_flight=9705, cwnd=14935, state=SlowStart, new_acked=2615
server  | 2.141 DEBUG PMTUD probe of size 1380 succeeded
server  | 2.141 DEBUG PMTUD started with probe size 1420
server  | 2.141 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 2.141 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.141 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.141 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 2.141 DEBUG CRYPTO for ap offset=0, len=305
server  | 2.141 DEBUG [Server d4853f6551a94d51] pn=11 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 1372
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 9467, len: 773, fin: true }
server  |   TX -> Crypto { offset: 0, len: 305 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 189, 203, 42, 146, 240, 231, 104, 24, 230, 86, 114, 226, 79, 193, 225, 47, 56, 137, 105, 107, 0, 11, 48, 147, 250, 217, 243, 197, 23, 106, 100, 122, 143, 7, 35, 132] }
server  |   TX -> Padding { len: 212 }
server  | 2.141 DEBUG packet_sent this=0x56135af07840, pn=11, ps=1372
server  | 2.142 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.142 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.142 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.142 DEBUG [Server d4853f6551a94d51] delay duration 129.355125ms
server  | 2.142 DEBUG Setting timeout of 129.355125ms
server  | 2.142 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.142 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.142 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.142 DEBUG [Server d4853f6551a94d51] delay duration 129.318818ms
server  | 2.142 DEBUG Setting timeout of 129.318818ms
server  | 2.143 DEBUG [Server d4853f6551a94d51] pn=2 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 3, ack_delay: 125, first_ack_range: 3, ack_ranges: [], ecn_count: None }
server  | 2.143 DEBUG [Server d4853f6551a94d51] Rx ACK space=ap, ranges=[0..=3]
server  | 2.143 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=3
server  | 2.143 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8613/17399 ssthresh 18446744073709551615] slow start += 2464
server  | 2.143 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 8613/17399 ssthresh 18446744073709551615] on_packets_acked this=0x56135af07840, limited=0, bytes_in_flight=8613, cwnd=17399, state=SlowStart, new_acked=2464
server  | 2.143 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.143 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.143 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.143 DEBUG [Server d4853f6551a94d51] delay duration 117.687865ms
server  | 2.143 DEBUG Setting timeout of 117.687865ms
server  | 2.143 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.143 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.143 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.143 DEBUG [Server d4853f6551a94d51] delay duration 117.588129ms
server  | 2.143 DEBUG Setting timeout of 117.588129ms
server  | 2.147 DEBUG [Server d4853f6551a94d51] pn=3 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 5, ack_delay: 250, first_ack_range: 5, ack_ranges: [], ecn_count: None }
server  | 2.147 DEBUG [Server d4853f6551a94d51] Rx ACK space=ap, ranges=[0..=5]
server  | 2.147 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=5
server  | 2.147 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6149/19863 ssthresh 18446744073709551615] slow start += 2464
server  | 2.147 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 6149/19863 ssthresh 18446744073709551615] on_packets_acked this=0x56135af07840, limited=0, bytes_in_flight=6149, cwnd=19863, state=SlowStart, new_acked=2464
server  | 2.147 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.147 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.147 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.147 DEBUG [Server d4853f6551a94d51] delay duration 104.753547ms
server  | 2.147 DEBUG Setting timeout of 104.753547ms
server  | 2.147 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.147 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.147 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.148 DEBUG [Server d4853f6551a94d51] delay duration 104.692343ms
server  | 2.148 DEBUG Setting timeout of 104.692343ms
server  | 2.152 DEBUG [Server d4853f6551a94d51] pn=4 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 34
server  |   -> RX Ack { largest_acknowledged: 7, ack_delay: 250, first_ack_range: 7, ack_ranges: [], ecn_count: None }
server  | 2.152 DEBUG [Server d4853f6551a94d51] Rx ACK space=ap, ranges=[0..=7]
server  | 2.152 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=7
server  | 2.152 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3685/22327 ssthresh 18446744073709551615] slow start += 2464
server  | 2.152 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 3685/22327 ssthresh 18446744073709551615] on_packets_acked this=0x56135af07840, limited=0, bytes_in_flight=3685, cwnd=22327, state=SlowStart, new_acked=2464
server  | 2.152 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.152 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.152 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.152 DEBUG [Server d4853f6551a94d51] delay duration 93.05937ms
server  | 2.152 DEBUG Setting timeout of 93.05937ms
server  | 2.152 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.152 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.152 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.152 DEBUG [Server d4853f6551a94d51] delay duration 92.988187ms
server  | 2.152 DEBUG Setting timeout of 92.988187ms
server  | 2.153 DEBUG [Server d4853f6551a94d51] pn=5 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 33
server  |   -> RX Ack { largest_acknowledged: 9, ack_delay: 0, first_ack_range: 9, ack_ranges: [], ecn_count: None }
server  | 2.153 DEBUG [Server d4853f6551a94d51] Rx ACK space=ap, ranges=[0..=9]
server  | 2.153 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=9
server  | 2.153 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] slow start += 2313
server  | 2.153 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1372/24640 ssthresh 18446744073709551615] on_packets_acked this=0x56135af07840, limited=0, bytes_in_flight=1372, cwnd=24640, state=SlowStart, new_acked=2313
server  | 2.153 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.153 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.153 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.153 DEBUG [Server d4853f6551a94d51] delay duration 83.795642ms
server  | 2.153 DEBUG Setting timeout of 83.795642ms
server  | 2.153 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.153 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.154 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 2.154 DEBUG [Server d4853f6551a94d51] delay duration 83.749857ms
server  | 2.154 DEBUG Setting timeout of 83.749857ms
server  | 2.175 DEBUG [Server d4853f6551a94d51] pn=6 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 37
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  |   -> RX Ack { largest_acknowledged: 11, ack_delay: 0, first_ack_range: 11, ack_ranges: [], ecn_count: None }
server  | 2.175 INFO [Server d4853f6551a94d51] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 2.175 DEBUG [Server d4853f6551a94d51] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 537, tv_nsec: 5177807 } }
server  | 2.175 DEBUG [Server d4853f6551a94d51] Rx ACK space=ap, ranges=[0..=11]
server  | 2.175 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=11
server  | 2.175 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] slow start += 1372
server  | 2.175 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 0/26012 ssthresh 18446744073709551615] on_packets_acked this=0x56135af07840, limited=0, bytes_in_flight=0, cwnd=26012, state=SlowStart, new_acked=1372
server  | 2.175 DEBUG PMTUD probe of size 1420 succeeded
server  | 2.175 DEBUG PMTUD started with probe size 1470
server  | 2.175 DEBUG PLPMTU changed from 1332 to 1372, updating pacer
server  | 2.175 DEBUG Acked crypto frame space=ap offset=0 length=305
server  | 2.175 DEBUG [pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511] Path validated Instant { tv_sec: 536, tv_nsec: 717351831 }
server  | 2.175 DEBUG [Server d4853f6551a94d51] output_path send_profile SendProfile { limit: 1372, pto: None, probe: EnumSet(), paced: false }
server  | 2.175 DEBUG Building Short dcid Some(CID [8]: 0e3e8adda5cf4db8)
server  | 2.175 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 536, tv_nsec: 717351831 }
server  | 2.175 DEBUG [Server d4853f6551a94d51] pn=12 type=Short pri-path:0e3e8adda5cf4db8 [::]:443->[::ffff:193.167.0.100]:46511 IpTos(Cs0, NotEct) len 35
server  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 6, ack_ranges: [], ecn_count: None }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 2.175 DEBUG Setting timeout of 287.61312ms
server  | 2.175 DEBUG Setting timeout of 287.594495ms
server  | 2.464 DEBUG [Server d4853f6551a94d51] State change from Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 537, tv_nsec: 5177807 } } -> Closed(Transport(PeerError(0)))
server  | 2.464 INFO Closing timer expired
server  | 2.464 DEBUG Setting timeout of 384.89379ms
server  | 2.464 DEBUG Setting timeout of 384.637623ms

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 12:33:09,020 Using the client's key log file.
2025-04-30 12:33:09,031 
2025-04-30 12:33:09,031 Using the client's key log file.
2025-04-30 12:33:09,031 Using the client's key log file.
2025-04-30 12:33:09,041 
2025-04-30 12:33:09,041 Using the client's key log file.
2025-04-30 12:33:09,041 Using the client's key log file.
2025-04-30 12:33:09,348 At least one QUIC packet could not be decrypted
2025-04-30 12:33:09,348 Packet (Length: 1374)
Layer ETH
:	Destination: 02:42:c1:a7:64:02
	.... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default)
	.... ...0 .... .... .... .... = IG bit: Individual address (unicast)
	Source: 02:42:c1:a7:64:64
	.... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default)
	.... ...0 .... .... .... .... = IG bit: Individual address (unicast)
	Type: IPv4 (0x0800)
	Stream index: 8
Layer IP
:	0100 .... = Version: 4
	.... 0101 = Header Length: 20 bytes (5)
	Differentiated Services Field: 0x02 (DSCP: CS0, ECN: ECT(0))
	0000 00.. = Differentiated Services Codepoint: Default (0)
	.... ..10 = Explicit Congestion Notification: ECN-Capable Transport codepoint '10' (2)
	Total Length: 1360
	Identification: 0x0000 (0)
	010. .... = Flags: 0x2, Don't fragment
	0... .... = Reserved bit: Not set
	.1.. .... = Don't fragment: Set
	..0. .... = More fragments: Not set
	...0 0000 0000 0000 = Fragment Offset: 0
	Time to Live: 64
	Protocol: UDP (17)
	Header Checksum: 0x4d84 [validation disabled]
	Header checksum status: Unverified
	Source Address: 193.167.100.100
	Destination Address: 193.167.0.100
	Stream index: 0
Layer UDP
:	Source Port: 443
	Destination Port: 46511
	Length: 1340
	Checksum: 0x51bc [unverified]
	Checksum Status: Unverified
	Stream index: 1
	Stream Packet Number: 5
	Timestamps
	Time since first frame: 41.362000 milliseconds
	Time since previous frame: 461.000 microseconds
	UDP payload (1332 bytes)
Layer QUIC
:	QUIC Connection information
	Connection Number: 1
	Packet Length: 49
	1... .... = Header Form: Long Header (1)
	.1.. .... = Fixed Bit: True
	..10 .... = Packet Type: Handshake (2)
	Version: 1 (0x00000001)
	Destination Connection ID Length: 8
	Destination Connection ID: 0e3e8adda5cf4db8
	Source Connection ID Length: 10
	Source Connection ID: 48dc25e79f9a0e954336
	Length: 22
	Expert Info (Warning/Decryption): Failed to create decryption context: Secrets are not available
	Failed to create decryption context: Secrets are not available
	Severity level: Warning
	Group: Decryption
	Remaining Payload: f3d94075b14ab678afcaa51560f18761844f435cc9b1
Layer QUIC
:	Packet Length: 1283
	QUIC Short Header DCID=0e3e8adda5cf4db8
	0... .... = Header Form: Short Header (0)
	.1.. .... = Fixed Bit: True
	..1. .... = Spin Bit: True
	Destination Connection ID: 0e3e8adda5cf4db8
	Expert Info (Warning/Decryption): Failed to create decryption context: Secrets are not available
	Failed to create decryption context: Secrets are not available
	Severity level: Warning
	Group: Decryption
	Remaining Payload […]: 892b8f317006af79a5ead80dde38b029438150ce420fe5f12547794f091be9bc2ba009508a148e2a84e23741b329f9c7e7333fdc53a5c1861bd57fd6743fefe22c33f53ebd99335a25eeb01261f8c14968212f4fbe9a2a56ede0ce1607e0f58e5df135748bb6eeda749cc1

2025-04-30 12:33:09,651 At least one QUIC packet could not be decrypted
2025-04-30 12:33:09,651 Packet (Length: 1374)
Layer ETH
:	Destination: 02:42:c1:a7:00:64
	.... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default)
	.... ...0 .... .... .... .... = IG bit: Individual address (unicast)
	Source: 02:42:c1:a7:00:02
	.... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default)
	.... ...0 .... .... .... .... = IG bit: Individual address (unicast)
	Type: IPv4 (0x0800)
	Stream index: 4
Layer IP
:	0100 .... = Version: 4
	.... 0101 = Header Length: 20 bytes (5)
	Differentiated Services Field: 0x02 (DSCP: CS0, ECN: ECT(0))
	0000 00.. = Differentiated Services Codepoint: Default (0)
	.... ..10 = Explicit Congestion Notification: ECN-Capable Transport codepoint '10' (2)
	Total Length: 1360
	Identification: 0x0000 (0)
	010. .... = Flags: 0x2, Don't fragment
	0... .... = Reserved bit: Not set
	.1.. .... = Don't fragment: Set
	..0. .... = More fragments: Not set
	...0 0000 0000 0000 = Fragment Offset: 0
	Time to Live: 62
	Protocol: UDP (17)
	Header Checksum: 0x4f84 [validation disabled]
	Header checksum status: Unverified
	Source Address: 193.167.100.100
	Destination Address: 193.167.0.100
	Stream index: 1
Layer UDP
:	Source Port: 443
	Destination Port: 46511
	Length: 1340
	Checksum: 0x51bc [unverified]
	Checksum Status: Unverified
	Stream index: 1
	Stream Packet Number: 6
	Timestamps
	Time since first frame: 73.939000 milliseconds
	Time since previous frame: 30.944000 milliseconds
	UDP payload (1332 bytes)
Layer QUIC
:	QUIC Connection information
	Connection Number: 1
	Packet Length: 49
	1... .... = Header Form: Long Header (1)
	.1.. .... = Fixed Bit: True
	..10 .... = Packet Type: Handshake (2)
	Version: 1 (0x00000001)
	Destination Connection ID Length: 8
	Destination Connection ID: 0e3e8adda5cf4db8
	Source Connection ID Length: 10
	Source Connection ID: 48dc25e79f9a0e954336
	Length: 22
	Expert Info (Warning/Decryption): Failed to create decryption context: Secrets are not available
	Failed to create decryption context: Secrets are not available
	Severity level: Warning
	Group: Decryption
	Remaining Payload: f3d94075b14ab678afcaa51560f18761844f435cc9b1
Layer QUIC
:	Packet Length: 1283
	QUIC Short Header DCID=0e3e8adda5cf4db8
	0... .... = Header Form: Short Header (0)
	.1.. .... = Fixed Bit: True
	..1. .... = Spin Bit: True
	Destination Connection ID: 0e3e8adda5cf4db8
	Remaining Payload […]: 892b8f317006af79a5ead80dde38b029438150ce420fe5f12547794f091be9bc2ba009508a148e2a84e23741b329f9c7e7333fdc53a5c1861bd57fd6743fefe22c33f53ebd99335a25eeb01261f8c14968212f4fbe9a2a56ede0ce1607e0f58e5df135748bb6eeda749cc1

2025-04-30 12:33:09,948 At least one QUIC packet could not be decrypted
2025-04-30 12:33:09,948 Packet (Length: 1374)
Layer ETH
:	Destination: 02:42:c1:a7:64:02
	.... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default)
	.... ...0 .... .... .... .... = IG bit: Individual address (unicast)
	Source: 02:42:c1:a7:64:64
	.... ..1. .... .... .... .... = LG bit: Locally administered address (this is NOT the factory default)
	.... ...0 .... .... .... .... = IG bit: Individual address (unicast)
	Type: IPv4 (0x0800)
	Stream index: 8
Layer IP
:	0100 .... = Version: 4
	.... 0101 = Header Length: 20 bytes (5)
	Differentiated Services Field: 0x02 (DSCP: CS0, ECN: ECT(0))
	0000 00.. = Differentiated Services Codepoint: Default (0)
	.... ..10 = Explicit Congestion Notification: ECN-Capable Transport codepoint '10' (2)
	Total Length: 1360
	Identification: 0x0000 (0)
	010. .... = Flags: 0x2, Don't fragment
	0... .... = Reserved bit: Not set
	.1.. .... = Don't fragment: Set
	..0. .... = More fragments: Not set
	...0 0000 0000 0000 = Fragment Offset: 0
	Time to Live: 64
	Protocol: UDP (17)
	Header Checksum: 0x4d84 [validation disabled]
	Header checksum status: Unverified
	Source Address: 193.167.100.100
	Destination Address: 193.167.0.100
	Stream index: 0
Layer UDP
:	Source Port: 443
	Destination Port: 46511
	Length: 1340
	Checksum: 0x51bc [unverified]
	Checksum Status: Unverified
	Stream index: 1
	Stream Packet Number: 5
	Timestamps
	Time since first frame: 41.362000 milliseconds
	Time since previous frame: 461.000 microseconds
	UDP payload (1332 bytes)
Layer QUIC
:	QUIC Connection information
	Connection Number: 1
	Packet Length: 49
	1... .... = Header Form: Long Header (1)
	.1.. .... = Fixed Bit: True
	..10 .... = Packet Type: Handshake (2)
	Version: 1 (0x00000001)
	Destination Connection ID Length: 8
	Destination Connection ID: 0e3e8adda5cf4db8
	Source Connection ID Length: 10
	Source Connection ID: 48dc25e79f9a0e954336
	Length: 22
	Expert Info (Warning/Decryption): Failed to create decryption context: Secrets are not available
	Failed to create decryption context: Secrets are not available
	Severity level: Warning
	Group: Decryption
	Remaining Payload: f3d94075b14ab678afcaa51560f18761844f435cc9b1
Layer QUIC
:	Packet Length: 1283
	QUIC Short Header DCID=0e3e8adda5cf4db8
	0... .... = Header Form: Short Header (0)
	.1.. .... = Fixed Bit: True
	..1. .... = Spin Bit: True
	Destination Connection ID: 0e3e8adda5cf4db8
	Expert Info (Warning/Decryption): Failed to create decryption context: Secrets are not available
	Failed to create decryption context: Secrets are not available
	Severity level: Warning
	Group: Decryption
	Remaining Payload […]: 892b8f317006af79a5ead80dde38b029438150ce420fe5f12547794f091be9bc2ba009508a148e2a84e23741b329f9c7e7333fdc53a5c1861bd57fd6743fefe22c33f53ebd99335a25eeb01261f8c14968212f4fbe9a2a56ede0ce1607e0f58e5df135748bb6eeda749cc1

2025-04-30 12:33:09,950 Check of downloaded files succeeded.