2025-04-30 12:24:12,789 Generated random file: rewuggrorg of size: 1024
2025-04-30 12:24:12,789 Requests: https://server4:443/rewuggrorg
2025-04-30 12:24:12,847 
2025-04-30 12:24:12,848 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_t20enbbr/ TESTCASE_SERVER=handshake TESTCASE_CLIENT=handshake WWW=/tmp/www_h_bhlgjz/ DOWNLOADS=/tmp/download_x5rwvbz8/ SERVER_LOGS=/tmp/logs_server_d_6mxzav CLIENT_LOGS=/tmp/logs_client_bb7chxgm SCENARIO="simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25" CLIENT=litespeedtech/lsquic-qir:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/rewuggrorg"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 12:24:27,830  Container sim  Recreate
 Container sim  Recreated
 Container client  Recreate
 Container server  Recreate
 Container server  Recreated
 Container client  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 handshake ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.94fH81op7F
server  | + mkdir -p /neqo/db
server  | + certutil -N -d sql:/neqo/db --empty-password
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
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.94fH81op7F
server  | Warning: output encryption option -nodes ignored with -export
client  | Endpoint's IPv6 address is fd00:cafe:cafe::100
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.94fH81op7F -W ''
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
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  |             05:6c:e1:f2:f9:b2:cf:1b:92:4c:38:d9:62:c5:45:e5:
server  |             bf:e8:64:8d
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:24:12 2025
server  |             Not After : Sat May 10 12:24:12 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:e4:01:a5:25:3c:2c:5c:14:17:b9:61:88:50:41:e8:
server  |                     bb:27:85:c3:00:70:26:c4:e1:bf:b1:ac:64:a8:30:91:
server  |                     52:5f:5e:a3:76:38:a2:07:f7:64:ee:46:51:ce:68:20:
server  |                     29:f9:ae:8b:98:da:2c:81:02:61:b7:87:e4:bc:d7:cb:
server  |                     51
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  |                 a4:22:43:92:43:8c:c2:a6:6a:cb:eb:74:47:13:12:0a:
server  |                 2f:ca:39:42
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 e9:06:08:cc:77:10:91:67:0d:67:fa:f4:6d:f7:20:68:
server  |                 5a:6d:ab:ab
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:44:02:20:5e:d3:bc:99:ca:4f:d6:c9:81:94:ab:73:
server  |         7a:9d:70:f5:6a:e3:a2:0f:75:ba:e3:69:66:55:34:09:
server  |         ee:7e:95:cf:02:20:1f:9b:dc:6d:17:52:d7:37:53:b8:
server  |         ff:77:45:10:50:44:04:c1:e1:c1:78:a2:d3:73:01:4a:
server  |         20:4a:5a:a2:60:da
server  |     Fingerprint (SHA-256):
server  |         90:CB:4A:07:C3:63:8E:26:C7:1D:C9:1D:76:74:C7:2E:68:A7:AD:89:38:9B:E7:32:38:E9:77:B0:F4:86:C4:82
server  |     Fingerprint (SHA1):
server  |         22:E0:23:D1:4A:1C:25:33:7F:8B:BB:B1:64:8F:53:9F:9E:E4:D3:7C
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  | + '[' handshake = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test handshake --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
sim     | server:443 is available after 1.027660878s
server  | 0.790 DEBUG [Server] Unsupported version: 57414954
server  | 0.790 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48176 IpTos(Cs0, NotEct) len 15
server  | 0.791 DEBUG [Server] Unsupported version: 57414954
server  | 0.791 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48176 IpTos(Cs0, NotEct) len 15
server  | 0.791 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.791 DEBUG [Server] Unsupported version: 57414954
server  | 0.791 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:48176 IpTos(Cs0, NotEct) len 15
server  | 0.791 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=750ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: tcpdump: listening on eth1listening on eth0, link-type EN10MB (Ethernet), link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | , snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | TEST_PARAMS:
client  | REQUESTS: 'https://server4:443/rewuggrorg'
client  | paths: -p /rewuggrorg
client  | server: server4
client  | port: 443
client  | CLIENT_PARAMS:
server  | 1.753 DEBUG [Server] Handle initial
server  | 1.753 INFO AddressValidation: no token; accepting
server  | 1.753 INFO [Server] Accept connection CID [18]: 7adf9fc01f088210da5cd54b9ee64aca90a9
server  | 1.753 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.755 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.755 INFO [Server ...] Received valid Initial packet with scid CID [8]: 027c2b2fe6b86668 dcid CID [18]: 7adf9fc01f088210da5cd54b9ee64aca90a9
server  | 1.755 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=7adf9fc01f088210da5cd54b9ee64aca90a9
server  | 1.755 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.755 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.755 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] State change from Init -> WaitInitial
server  | 1.755 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=0 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 1200
server  |   -> RX Crypto { offset: 0, len: 248 }
server  |   -> RX Padding { len: 895 }
server  | 1.755 DEBUG Read Ok(248) bytes
server  | 1.755 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.755 DEBUG Read secret available for Handshake: SymKey [32]: f7b78d638716d339e384175d1a50732d8e2a13662ff6174f616071e8ca1d446b
server  | 1.755 DEBUG Write secret available for Handshake: SymKey [32]: f4e6e40ed04a2038f9f6f27f29b2fc339f596627646583a457f8539efb434d70
server  | 1.755 DEBUG Writing transport parameters, msg=8
server  | 1.756 DEBUG Read secret available for ApplicationData: SymKey [32]: cc84835b0f756128029a51f2741991ed42de821e570cd55dc911c76f7e62f452
server  | 1.756 DEBUG Write secret available for ApplicationData: SymKey [32]: e656413eaeb4616389e150e24134ce27dc1c6ad6fb71c7d10ad8fcf761feecbf
server  | 1.756 DEBUG [Agent 0x55b809cef1d0] state -> InProgress
server  | 1.756 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.756 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.756 DEBUG [Crypto] Handshake keys installed
server  | 1.756 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.756 DEBUG [Crypto] Application write key installed
server  | 1.756 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 547638607 }
server  | 1.756 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:56485] Make permanent
server  | 1.756 DEBUG [unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485] set as primary path
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] State change from WaitInitial -> Handshaking
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.756 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.756 DEBUG CRYPTO for in offset=0, len=90
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=0 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 147
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 1.756 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.756 DEBUG CRYPTO for hs offset=0, len=732
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=0 type=Handshake pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 927
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 1.756 DEBUG packet_sent this=0x55b809ceeb40, pn=0, ps=780
server  | 1.756 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pad Initial from 927 to PLPMTU 1232
server  | 1.756 DEBUG packet_sent this=0x55b809ceeb40, pn=0, ps=452
server  | 1.756 DEBUG ECN probing: sent 1 probes
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.756 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.756 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.756 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 1.756 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 296.817348ms
server  | 1.756 DEBUG Setting timeout of 296.817348ms
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.756 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.756 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.756 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 1.756 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.756 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 296.785648ms
server  | 1.756 DEBUG Setting timeout of 296.785648ms
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=1 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 1200
server  |   -> RX Crypto { offset: 0, len: 248 }
server  |   -> RX Padding { len: 895 }
server  | 1.898 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 693105632 }
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.898 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.898 DEBUG CRYPTO for in offset=0, len=90
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=1 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 147
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 1.898 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.898 DEBUG CRYPTO for hs offset=0, len=732
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=1 type=Handshake pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 927
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 1.898 DEBUG packet_sent this=0x55b809ceeb40, pn=1, ps=780
server  | 1.898 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pad Initial from 927 to PLPMTU 1232
server  | 1.898 DEBUG packet_sent this=0x55b809ceeb40, pn=1, ps=452
server  | 1.898 DEBUG ECN probing: sent 2 probes
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.898 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.898 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.898 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 1.898 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 299.833468ms
server  | 1.898 DEBUG Setting timeout of 299.833468ms
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.898 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.898 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 1.898 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 1.898 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.898 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 299.793273ms
server  | 1.898 DEBUG Setting timeout of 299.793273ms
server  | 2.199 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 2.199 DEBUG [LossRecovery] PTO timer fired for Handshake
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 0, smallest: 0, ack_needed: true }] })
server  | 2.199 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 323, tv_nsec: 547638607 }
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 2.199 INFO Lost crypto frame space=in offset=0 length=90
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: EcnEct0
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1, smallest: 0, ack_needed: true }] })
server  | 2.199 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 323, tv_nsec: 693105632 }
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 2.199 INFO Lost crypto frame space=in offset=0 length=90
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: EcnEct0
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 732 })
server  | 2.199 INFO Lost crypto frame space=hs offset=0 length=732
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 732 })
server  | 2.199 INFO Lost crypto frame space=hs offset=0 length=732
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 2.199 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG CRYPTO for in offset=0, len=90
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=2 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 150
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 37585, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 2.199 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG CRYPTO for hs offset=0, len=732
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=2 type=Handshake pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 930
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 2.199 DEBUG packet_sent this=0x55b809ceeb40, pn=2, ps=780
server  | 2.199 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pad Initial from 930 to PLPMTU 1232
server  | 2.199 DEBUG packet_sent this=0x55b809ceeb40, pn=2, ps=452
server  | 2.199 DEBUG ECN probing: sent 3 probes
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.199 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.199 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 599.710739ms
server  | 2.199 DEBUG Setting timeout of 599.710739ms
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.199 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.199 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 599.672948ms
server  | 2.199 DEBUG Setting timeout of 599.672948ms
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=2 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 1200
server  |   -> RX Crypto { offset: 0, len: 248 }
server  |   -> RX Padding { len: 895 }
server  | 2.199 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 323, tv_nsec: 994156675 }
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.199 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG CRYPTO for in offset=0, len=90
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=3 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 147
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 0, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 3, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 2.199 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG CRYPTO for hs offset=0, len=732
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=3 type=Handshake pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect0) len 927
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 2.199 DEBUG packet_sent this=0x55b809ceeb40, pn=3, ps=780
server  | 2.199 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pad Initial from 927 to PLPMTU 1232
server  | 2.199 DEBUG packet_sent this=0x55b809ceeb40, pn=3, ps=452
server  | 2.199 DEBUG ECN probing: sent 4 probes
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.199 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.199 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 1.335354ms
server  | 2.199 DEBUG Setting timeout of 1.335354ms
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.199 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.199 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.199 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 2.199 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 1.307793ms
server  | 2.199 DEBUG Setting timeout of 1.307793ms
server  | 2.202 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.202 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.202 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.202 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.202 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.202 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 597.746074ms
server  | 2.202 DEBUG Setting timeout of 597.746074ms
server  | 2.202 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.202 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.202 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.202 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.202 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.202 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 597.711209ms
server  | 2.202 DEBUG Setting timeout of 597.711209ms
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=3 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 1200
server  |   -> RX Crypto { offset: 0, len: 248 }
server  |   -> RX Padding { len: 895 }
server  | 2.800 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 324, tv_nsec: 594739682 }
server  | 2.800 DEBUG [LossRecovery] PTO timer fired for Initial
server  | 2.800 DEBUG [LossRecovery] PTO timer fired for Handshake
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 1, smallest: 0, ack_needed: true }] })
server  | 2.800 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 323, tv_nsec: 993786412 }
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 2.800 INFO Lost crypto frame space=in offset=0 length=90
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: EcnEct0
server  | 2.800 DEBUG ECN validation failed, all 3 initial marked packets were lost
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Ack(AckToken { space: Initial, ranges: [PacketRange { largest: 2, smallest: 0, ack_needed: true }] })
server  | 2.800 DEBUG [Recvd-in] immediate_ack at Instant { tv_sec: 323, tv_nsec: 994156675 }
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Initial, offset: 0, length: 90 })
server  | 2.800 INFO Lost crypto frame space=in offset=0 length=90
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: EcnEct0
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 732 })
server  | 2.800 INFO Lost crypto frame space=hs offset=0 length=732
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Lost: Crypto(CryptoRecoveryToken { space: Handshake, offset: 0, length: 732 })
server  | 2.800 INFO Lost crypto frame space=hs offset=0 length=732
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: Some(Initial), probe: EnumSet(Initial | Handshake), paced: false }
server  | 2.800 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.800 DEBUG CRYPTO for in offset=0, len=90
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=4 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, NotEct) len 147
server  |   TX -> Ack { largest_acknowledged: 3, ack_delay: 0, first_ack_range: 3, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 4, Ect0: 0, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 90 }
server  | 2.800 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.800 DEBUG CRYPTO for hs offset=0, len=732
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=4 type=Handshake pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, NotEct) len 927
server  |   TX -> Crypto { offset: 0, len: 732 }
server  | 2.800 DEBUG packet_sent this=0x55b809ceeb40, pn=4, ps=780
server  | 2.800 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pad Initial from 927 to PLPMTU 1232
server  | 2.800 DEBUG packet_sent this=0x55b809ceeb40, pn=4, ps=452
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.800 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.800 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.800 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.800 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 1.199710539s
server  | 2.800 DEBUG Setting timeout of 1.199710539s
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.800 DEBUG Building Initial dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.800 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 2.800 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 2.800 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 2.800 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 1.199666908s
server  | 2.800 DEBUG Setting timeout of 1.199666908s
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=4 type=Initial pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 1035
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 198, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Padding { len: 983 }
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Rx ACK space=in, ranges=[0..=0]
server  | 3.261 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 3.261 DEBUG on_packets_acked this=0x55b809ceeb40, limited=1, bytes_in_flight=5708, cwnd=12320, state=SlowStart, new_acked=452
server  | 3.261 DEBUG Acked crypto frame space=in offset=0 length=90
server  | 3.261 DEBUG [pri-unv-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485] Path validated Instant { tv_sec: 325, tv_nsec: 55382223 }
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Drop packet number space in
server  | 3.261 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=5 type=Handshake pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 89
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 89, first_ack_range: 0, ack_ranges: [], ecn_count: None }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Rx ACK space=hs, ranges=[0..=0]
server  | 3.261 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 3.261 DEBUG on_packets_acked this=0x55b809ceeb40, limited=1, bytes_in_flight=3120, cwnd=12320, state=SlowStart, new_acked=780
server  | 3.261 DEBUG Acked crypto frame space=hs offset=0 length=732
server  | 3.261 DEBUG Read Ok(36) bytes
server  | 3.261 DEBUG [Agent 0x55b809cef1d0] 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  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] TLS connection complete
server  | 3.261 DEBUG [pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485] Path validated Instant { tv_sec: 325, tv_nsec: 55382223 }
server  | 3.261 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 3.261 DEBUG [Crypto] application read keys installed
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] State change from Handshaking -> Connected
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] State change from Connected -> Confirmed
server  | 3.261 DEBUG PMTUD started with probe size 1380
server  | 3.261 INFO [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Connection established
server  | 3.261 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 325, tv_nsec: 55382223 }
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=6 type=Short pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 76
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [160, 144, 112, 179, 53, 187, 134, 69], stateless_reset_token: [5, 139, 108, 125, 225, 87, 175, 46, 24, 24, 80, 3, 110, 251, 57, 96] }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 3.261 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 325, tv_nsec: 55382223 }
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.261 DEBUG Building Handshake dcid Some(CID [8]: 027c2b2fe6b86668) scid Some(CID [10]: 8375936f434317040a35)
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=5 type=Handshake pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, NotEct) len 52
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] Drop packet number space hs
server  | 3.261 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 3.261 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=0 type=Short pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, NotEct) len 1332
server  |   TX -> Ack { largest_acknowledged: 6, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 1, Ect0: 0, Ce: 0})) }
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [103, 9, 112, 17, 44, 98, 146, 211, 148, 26], stateless_reset_token: [255, 51, 42, 148, 243, 192, 150, 75, 58, 51, 199, 48, 78, 147, 127, 50] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [153, 150, 242, 219, 234, 208, 90, 251, 97, 101], stateless_reset_token: [118, 88, 101, 68, 123, 7, 194, 104, 225, 61, 250, 234, 252, 138, 229, 154] }
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [157, 3, 30, 139, 168, 97, 64, 39, 202, 84], stateless_reset_token: [71, 242, 216, 71, 156, 36, 92, 67, 180, 76, 174, 120, 65, 16, 112, 253] }
server  |   TX -> NewConnectionId { sequence_number: 4, retire_prior: 0, connection_id: [231, 83, 253, 64, 126, 181, 97, 147, 255, 245], stateless_reset_token: [56, 212, 47, 148, 186, 168, 173, 222, 221, 28, 136, 73, 98, 32, 111, 11] }
server  |   TX -> NewConnectionId { sequence_number: 5, retire_prior: 0, connection_id: [136, 178, 83, 117, 130, 79, 190, 167, 34, 202], stateless_reset_token: [228, 147, 67, 61, 21, 112, 7, 95, 165, 16, 161, 66, 225, 107, 40, 86] }
server  |   TX -> NewConnectionId { sequence_number: 6, retire_prior: 0, connection_id: [221, 177, 132, 105, 137, 183, 22, 157, 79, 7], stateless_reset_token: [120, 37, 229, 209, 110, 87, 28, 191, 36, 124, 61, 220, 84, 169, 98, 254] }
server  |   TX -> NewConnectionId { sequence_number: 7, retire_prior: 0, connection_id: [224, 44, 247, 245, 113, 37, 54, 24, 147, 145], stateless_reset_token: [93, 190, 183, 232, 179, 231, 137, 26, 37, 225, 170, 70, 70, 168, 49, 243] }
server  |   TX -> AckFrequency { seqno: 0, tolerance: 2, delay: 50000, ignore_order: false }
server  |   TX -> Padding { len: 1026 }
server  | 3.261 DEBUG packet_sent this=0x55b809ceeb40, pn=0, ps=1280
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.261 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.261 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.261 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 2.158668ms
server  | 3.261 DEBUG Setting timeout of 2.158668ms
server  | 3.262 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] send session ticket 406d00127adf9fc01f088210da5cd54b9ee64aca90a90104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0a8375936f434317040a35110c000000018aaa9a4a000000016ab200c0000000ff02de1a0243e8200100686921
server  | 3.262 DEBUG Path = 'rewuggrorg'
server  | 3.262 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.262 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.262 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.262 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 1.116619ms
server  | 3.262 DEBUG Setting timeout of 1.116619ms
server  | 3.265 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.265 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.265 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 3.265 DEBUG CRYPTO for ap offset=0, len=273
server  | 3.265 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=1 type=Short pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, NotEct) len 1332
server  |   TX -> Ping
server  |   TX -> Stream { stream_id: 0, offset: 0, len: 1024, fin: true }
server  |   TX -> Crypto { offset: 0, len: 273 }
server  | 3.265 DEBUG packet_sent this=0x55b809ceeb40, pn=1, ps=1332
server  | 3.265 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.265 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.265 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.265 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 75.242177ms
server  | 3.265 DEBUG Setting timeout of 75.242177ms
server  | 3.265 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.265 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.265 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.265 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 75.221007ms
server  | 3.265 DEBUG Setting timeout of 75.221007ms
server  | 3.341 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.341 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.341 DEBUG CRYPTO for ap offset=273, len=48
server  | 3.341 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=2 type=Short pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, NotEct) len 123
server  |   TX -> Crypto { offset: 273, len: 48 }
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 235, 124, 229, 108, 62, 158, 174, 34, 226, 0, 226, 19, 242, 72, 169, 89, 62, 142, 79, 60, 155, 55, 227, 229, 188, 72, 43, 93, 243, 65, 51, 175, 150, 161, 170, 5] }
server  | 3.341 DEBUG packet_sent this=0x55b809ceeb40, pn=2, ps=123
server  | 3.341 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.341 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.341 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.341 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 6.432598ms
server  | 3.341 DEBUG Setting timeout of 6.432598ms
server  | 3.341 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.341 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.341 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 3.341 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 6.404966ms
server  | 3.341 DEBUG Setting timeout of 6.404966ms
server  | 3.349 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.349 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.349 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.349 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.808150898s
server  | 3.349 DEBUG Setting timeout of 3.808150898s
server  | 3.349 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.349 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.349 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.349 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.80813012s
server  | 3.349 DEBUG Setting timeout of 3.80813012s
server  | 3.400 WARN [Server ...] Dropped received packet: Decryption failure; Total: 1
server  | 3.400 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.400 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.400 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.400 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.75689469s
server  | 3.400 DEBUG Setting timeout of 3.75689469s
server  | 3.400 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.400 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.400 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.400 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.75684615s
server  | 3.400 DEBUG Setting timeout of 3.75684615s
server  | 3.701 WARN [Server ...] Dropped received packet: Decryption failure; Total: 2
server  | 3.701 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.701 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.701 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.701 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.456099394s
server  | 3.701 DEBUG Setting timeout of 3.456099394s
server  | 3.701 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.701 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.701 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.701 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.455975011s
server  | 3.701 DEBUG Setting timeout of 3.455975011s
server  | 3.702 WARN [Server ...] Dropped received packet: Decryption failure; Total: 3
server  | 3.702 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.702 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.702 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.702 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.455408923s
server  | 3.702 DEBUG Setting timeout of 3.455408923s
server  | 3.702 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.702 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 3.702 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 3.702 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.455376351s
server  | 3.702 DEBUG Setting timeout of 3.455376351s
server  | 4.004 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=10 type=Short pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, Ect1) len 56
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [174, 137, 167, 223, 165, 213, 191, 29], stateless_reset_token: [183, 31, 59, 126, 230, 216, 31, 200, 108, 110, 205, 51, 79, 178, 245, 238] }
server  | 4.004 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 325, tv_nsec: 798519756 }
server  | 4.004 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.004 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 4.004 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] pn=3 type=Short pri-path:027c2b2fe6b86668 [::]:443->[::ffff:193.167.0.100]:56485 IpTos(Cs0, NotEct) len 36
server  |   TX -> Ack { largest_acknowledged: 10, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 2, range: 0 }], ecn_count: Some(Count({NotEct: 0, Ect1: 2, Ect0: 0, Ce: 0})) }
server  | 4.004 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.004 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 4.004 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.004 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.153370768s
server  | 4.004 DEBUG Setting timeout of 3.153370768s
server  | 4.004 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.004 DEBUG Building Short dcid Some(CID [8]: 027c2b2fe6b86668)
server  | 4.004 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 4.004 DEBUG [Server 7adf9fc01f088210da5cd54b9ee64aca90a9] delay duration 3.153335773s
server  | 4.004 DEBUG Setting timeout of 3.153335773s
client  | /logs/027C2B2FE6B86668.keys
client  | /logs/keys.log exists

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:24:27,924 Using the client's key log file.
2025-04-30 12:24:27,933 
2025-04-30 12:24:27,934 Using the client's key log file.
2025-04-30 12:24:27,934 Using the client's key log file.
2025-04-30 12:24:27,941 
2025-04-30 12:24:27,942 Using the client's key log file.
2025-04-30 12:24:28,229 Check of downloaded files succeeded.
2025-04-30 12:24:29,436 Check of downloaded files succeeded.