2025-04-30 08:43:38,454 Generated random file: ccrjbecaye of size: 10240
2025-04-30 08:43:38,454 Requests: https://server4:443/ccrjbecaye
2025-04-30 08:43:38,515 
2025-04-30 08:43:38,516 Command: WAITFORSERVER=server:443 CERTS=/tmp/certs_vd03x4wi/ TESTCASE_SERVER=retry TESTCASE_CLIENT=retry WWW=/tmp/www_zrjp_8z2/ DOWNLOADS=/tmp/download_1l_vb3b9/ SERVER_LOGS=/tmp/logs_server_zhxk2ije CLIENT_LOGS=/tmp/logs_client_l1sbhld4 SCENARIO="simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25" CLIENT=ghcr.io/aws/s2n-quic/s2n-quic-qns:latest SERVER=ghcr.io/mozilla/neqo-qns:latest REQUESTS="https://server4:443/ccrjbecaye"  docker compose --env-file empty.env up --abort-on-container-exit --timeout 1 sim client server 
2025-04-30 08:43:50,980  Container sim  Created
 Container server  Recreate
 Container client  Recreate
 Container client  Recreated
 Container server  Recreated
Attaching to client, server, sim
sim     | waiting 10s for server:443
client  | Setting up routes...
server  | Setting up routes...
client  | Actual changes:
client  | tx-checksum-ip-generic: off
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
server  | Actual changes:
server  | tx-checksum-ip-generic: off
server  | tx-tcp-segmentation: off [not requested]
server  | tx-tcp-ecn-segmentation: off [not requested]
server  | tx-tcp-mangleid-segmentation: off [not requested]
server  | tx-tcp6-segmentation: off [not requested]
server  | tx-udp-segmentation: off [not requested]
server  | tx-checksum-sctp: off
client  | Endpoint's IPv4 address is 193.167.0.100
server  | Endpoint's IPv4 address is 193.167.100.100
server  | Endpoint's IPv6 address is fd00:cafe:cafe:100::100
client  | Endpoint's IPv6 address is fd00:cafe:cafe::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 retry ']'
server  | + '[' -n /logs/qlog/ ']'
server  | + case "$ROLE" in
server  | + DB=/neqo/db
server  | + CERT=cert
server  | ++ mktemp
server  | + P12CERT=/tmp/tmp.IADhXpEXCo
server  | + mkdir -p /neqo/db
client  | x86_64
server  | + certutil -N -d sql:/neqo/db --empty-password
client  | wait-for-it.sh: waiting 30 seconds for sim:57832
server  | + openssl pkcs12 -export -nodes -in /certs/cert.pem -inkey /certs/priv.key -name cert -passout pass: -out /tmp/tmp.IADhXpEXCo
server  | Warning: output encryption option -nodes ignored with -export
server  | + pk12util -d sql:/neqo/db -i /tmp/tmp.IADhXpEXCo -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  |             02:47:22:22:00:41:ed:ed:25:27:35:98:f6:ef:42:08:
server  |             4a:79:da:dd
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 08:43:38 2025
server  |             Not After : Sat May 10 08:43:38 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:8d:6b:a1:38:10:a0:35:3a:95:f4:54:c6:01:78:a3:
server  |                     e2:ed:ae:c2:46:c4:aa:e3:43:f6:78:b3:ed:43:89:4f:
server  |                     30:71:ba:e7:25:68:6b:0b:38:21:10:86:00:52:04:40:
server  |                     50:37:5b:eb:87:7d:88:37:67:29:e8:d0:fa:06:4d:47:
server  |                     0c
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  |                 f3:52:06:e2:88:6d:c8:de:0b:e6:55:7d:a0:92:3b:eb:
server  |                 b7:bb:00:71
server  | 
server  |             Name: Certificate Authority Key Identifier
server  |             Key ID:
server  |                 fe:e0:50:c6:d1:47:0a:c3:0c:24:d3:8c:dc:c3:1e:30:
server  |                 fb:30:77:88
server  | 
server  |     Signature Algorithm: X9.62 ECDSA signature with SHA256
server  |     Signature:
server  |         30:45:02:20:06:b0:89:28:b2:a4:90:69:35:6f:fc:58:
server  |         b9:be:bf:65:78:4e:af:e1:29:14:e4:66:32:d4:de:88:
server  |         7a:e9:29:3e:02:21:00:f0:a7:5c:c1:ab:74:eb:05:3a:
server  |         7b:64:b1:ed:18:05:32:f6:4a:b1:68:59:65:03:de:45:
server  |         25:8b:04:2e:43:d5:a6
server  |     Fingerprint (SHA-256):
server  |         23:DE:CA:F1:B3:4E:96:E3:81:35:A5:30:B0:83:99:32:FC:DC:B4:2B:BF:20:B9:2C:74:C3:71:62:A9:CC:F7:82
server  |     Fingerprint (SHA1):
server  |         08:37:A8:AE:D6:9C:A3:9D:77:FB:42:20:D4:0B:67:83:41:81:BB:55
server  | 
server  |     Mozilla-CA-Policy: false (attribute missing)
server  |     Certificate Trust Flags:
server  |         SSL Flags:
server  |             User
server  |         Email Flags:
server  |             User
server  |         Object Signing Flags:
server  |             User
server  | 
server  | + OPTIONS=(--cc cubic --qns-test "$TESTCASE" --qlog-dir "$QLOGDIR" -d "$DB" -k "$CERT")
server  | + '[' retry = connectionmigration ']'
server  | + RUST_LOG=debug
server  | + RUST_BACKTRACE=1
server  | + neqo-server --cc cubic --qns-test retry --qlog-dir /logs/qlog/ -d /neqo/db -k cert '[::]:443'
server  | ++ tee -i -a /logs/server.log
server  | 0.000 DEBUG Logging initialized
server  | 0.003 DEBUG Default socket send buffer size is 212992
server  | 0.003 DEBUG Default socket receive buffer size is 1048576, not changing
server  | 0.003 INFO Server waiting for connection on: [::]:443
server  | 0.743 DEBUG [Server] Unsupported version: 57414954
server  | 0.743 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:51596 IpTos(Cs0, NotEct) len 15
sim     | server:443 is available after 1.036946824s
server  | 0.744 DEBUG [Server] Unsupported version: 57414954
server  | 0.744 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:51596 IpTos(Cs0, NotEct) len 15
server  | 0.744 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
server  | 0.744 DEBUG [Server] Unsupported version: 57414954
server  | 0.744 DEBUG [Server] type=VersionNegotiation path: [::]:443->[::ffff:193.167.100.2]:51596 IpTos(Cs0, NotEct) len 15
server  | 0.744 ERROR failed to create NeqoQlog: IoError(Os { code: 17, kind: AlreadyExists, message: "File exists" })
sim     | Using scenario: simple-p2p --delay=15ms --bandwidth=10Mbps --queue=25
sim     | tcpdump: listening on eth0, link-type EN10MB (Ethernet), snapshot length 262144 bytes
sim     | tcpdump: listening on eth1, link-type EN10MB (Ethernet), snapshot length 262144 bytes
client  | wait-for-it.sh: sim:57832 is available after 1 seconds
client  | connecting to server4 at 193.167.100.100:443
server  | 1.039 DEBUG [Server] Handle initial
server  | 1.039 INFO AddressValidation: no token; validating
server  | 1.039 INFO [Server] Send retry for CID [8]: bde35cdb8ac71643
server  | 1.040 DEBUG [Server] type=Retry path:bde35cdb8ac71643 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, NotEct) len 100
server  | 1.072 DEBUG [Server] Handle initial
server  | 1.072 INFO AddressValidation: valid Retry token for bde35cdb8ac71643
server  | 1.072 INFO [Server] Accept connection CID [8]: bde35cdb8ac71643
server  | 1.072 DEBUG Overwrite initial version Version1 ==> Version1
server  | 1.074 DEBUG Outbound interface eth0 for destination ::ffff:193.167.0.100 has MTU 1500
server  | 1.074 INFO [Server ...] Received valid Initial packet with scid CID [16]: a466752b3130128f19045e5c60c9ba38 dcid CID [10]: 3ccd2b6786c8d1defa86
server  | 1.074 DEBUG [CryptoStates] Creating initial cipher state v=Version1, role=Server dcid=3ccd2b6786c8d1defa86
server  | 1.074 DEBUG Making Write Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.074 DEBUG Making Read Initial CryptoDxState, v=Version1 cipher=4865
server  | 1.074 DEBUG [Server 3ccd2b6786c8d1defa86] State change from Init -> WaitInitial
server  | 1.074 DEBUG [Server 3ccd2b6786c8d1defa86] pn=1 type=Initial unv-path [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1252
server  |   -> RX Crypto { offset: 0, len: 287 }
server  |   -> RX Padding { len: 857 }
server  | 1.074 DEBUG Read Ok(287) bytes
server  | 1.074 DEBUG 0-RTT: no token, no 0-RTT
server  | 1.075 DEBUG Read secret available for Handshake: SymKey [32]: 49f316fb6dcffb4efe143db8dd15754c33dbd4240e598c41fc351d4320017df0
server  | 1.075 DEBUG Write secret available for Handshake: SymKey [32]: c68f9a1dc8d0e4379b393837fbe0dcffcb59bda28a7c766a0b946df1c2b8abbe
server  | 1.075 DEBUG Writing transport parameters, msg=8
server  | 1.076 DEBUG Read secret available for ApplicationData: SymKey [32]: a08d03512ff40c198b590813e81e3ea19308432ead2d5a9f8f7338c021916f95
server  | 1.076 DEBUG Write secret available for ApplicationData: SymKey [32]: 7e6e28c9bd448ff16b6a951aba1f41dd9d2c0ae4ad5c82539f5c3af45136d47a
server  | 1.076 DEBUG [Agent 0x5609a8e6d710] state -> InProgress
server  | 1.076 DEBUG Making Write Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.076 DEBUG Making Read Handshake CryptoDxState, v=Version1 cipher=4865
server  | 1.076 DEBUG [Crypto] Handshake keys installed
server  | 1.076 DEBUG Making Write ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.076 DEBUG [Crypto] Application write key installed
server  | 1.076 DEBUG [Recvd-in] Set ACK timer to Instant { tv_sec: 283, tv_nsec: 426161334 }
server  | 1.076 DEBUG [unv-path [::]:443->[::ffff:193.167.0.100]:41435] Make permanent
server  | 1.076 DEBUG [unv-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435] set as primary path
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] State change from WaitInitial -> Handshaking
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.076 DEBUG Building Initial dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38) scid Some(CID [10]: e45fb9e1d816e0666a52)
server  | 1.076 DEBUG CRYPTO for in offset=0, len=123
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] pn=0 type=Initial pri-unv-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 188
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   TX -> Crypto { offset: 0, len: 123 }
server  | 1.076 DEBUG Building Handshake dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38) scid Some(CID [10]: e45fb9e1d816e0666a52)
server  | 1.076 DEBUG CRYPTO for hs offset=0, len=738
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] pn=0 type=Handshake pri-unv-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 982
server  |   TX -> Crypto { offset: 0, len: 738 }
server  | 1.076 DEBUG packet_sent this=0x5609a8eb4b40, pn=0, ps=794
server  | 1.076 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] pad Initial from 982 to PLPMTU 1232
server  | 1.076 DEBUG packet_sent this=0x5609a8eb4b40, pn=0, ps=438
server  | 1.076 DEBUG ECN probing: sent 1 probes
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.076 DEBUG Building Initial dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38) scid Some(CID [10]: e45fb9e1d816e0666a52)
server  | 1.076 DEBUG Building Handshake dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38) scid Some(CID [10]: e45fb9e1d816e0666a52)
server  | 1.076 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.076 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 296.259728ms
server  | 1.076 DEBUG Setting timeout of 296.259728ms
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.076 DEBUG Building Initial dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38) scid Some(CID [10]: e45fb9e1d816e0666a52)
server  | 1.076 DEBUG Building Handshake dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38) scid Some(CID [10]: e45fb9e1d816e0666a52)
server  | 1.076 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.076 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.076 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 296.218191ms
server  | 1.076 DEBUG Setting timeout of 296.218191ms
client  | GET /ccrjbecaye
server  | 1.111 DEBUG [Server 3ccd2b6786c8d1defa86] pn=2 type=Initial pri-unv-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 123
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 1894, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX Padding { len: 10 }
server  | 1.111 DEBUG [Server 3ccd2b6786c8d1defa86] Rx ACK space=in, ranges=[0..=0]
server  | 1.111 DEBUG [LossRecovery] ACK for Initial - largest_acked=0
server  | 1.111 DEBUG on_packets_acked this=0x5609a8eb4b40, limited=1, bytes_in_flight=794, cwnd=12320, state=SlowStart, new_acked=438
server  | 1.112 DEBUG Acked crypto frame space=in offset=0 length=123
server  | 1.112 DEBUG [pri-unv-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435] Path validated Instant { tv_sec: 283, tv_nsec: 465273133 }
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] Drop packet number space in
server  | 1.112 DEBUG [LossRecovery] Reset loss recovery state for Initial
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] pn=0 type=Handshake pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1129
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 1894, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX Crypto { offset: 0, len: 36 }
server  |   -> RX Padding { len: 1029 }
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] Rx ACK space=hs, ranges=[0..=0]
server  | 1.112 DEBUG [LossRecovery] ACK for Handshake - largest_acked=0
server  | 1.112 DEBUG on_packets_acked this=0x5609a8eb4b40, limited=1, bytes_in_flight=0, cwnd=12320, state=SlowStart, new_acked=794
server  | 1.112 DEBUG Acked crypto frame space=hs offset=0 length=738
server  | 1.112 DEBUG Read Ok(36) bytes
server  | 1.112 DEBUG [Agent 0x5609a8e6d710] 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.112 DEBUG [Server 3ccd2b6786c8d1defa86] TLS connection complete
server  | 1.112 DEBUG [pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435] Path validated Instant { tv_sec: 283, tv_nsec: 465273133 }
server  | 1.112 DEBUG Making Read ApplicationData CryptoDxState, v=Version1 cipher=4865
server  | 1.112 DEBUG [Crypto] application read keys installed
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] State change from Handshaking -> Connected
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] State change from Connected -> Confirmed
server  | 1.112 DEBUG PMTUD started with probe size 1380
server  | 1.112 INFO [Server 3ccd2b6786c8d1defa86] Connection established
server  | 1.112 DEBUG [Recvd-hs] Set ACK timer to Instant { tv_sec: 283, tv_nsec: 465273133 }
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.112 DEBUG Building Handshake dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38) scid Some(CID [10]: e45fb9e1d816e0666a52)
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] pn=1 type=Handshake pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 60
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 0, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] Drop packet number space hs
server  | 1.112 DEBUG [LossRecovery] Reset loss recovery state for Handshake
server  | 1.112 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] pn=0 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1332
server  |   TX -> HandshakeDone
server  |   TX -> NewConnectionId { sequence_number: 1, retire_prior: 0, connection_id: [177, 84, 105, 245, 91, 139, 85, 62, 115, 53], stateless_reset_token: [127, 27, 193, 208, 141, 2, 50, 172, 83, 47, 233, 47, 220, 34, 244, 142] }
server  |   TX -> NewConnectionId { sequence_number: 2, retire_prior: 0, connection_id: [44, 101, 253, 170, 35, 160, 1, 17, 89, 0], stateless_reset_token: [150, 136, 133, 121, 244, 95, 158, 54, 133, 132, 212, 210, 216, 34, 30, 107] }
server  |   TX -> Padding { len: 1177 }
server  | 1.112 DEBUG packet_sent this=0x5609a8eb4b40, pn=0, ps=1272
server  | 1.112 DEBUG ECN probing: sent 2 probes
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.112 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.112 DEBUG Sending PMTUD probe of size 1380, count 1
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] pn=1 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1332
server  |   TX -> Ping
server  |   TX -> Padding { len: 1297 }
server  | 1.112 DEBUG packet_sent this=0x5609a8eb4b40, pn=1, ps=1332
server  | 1.112 DEBUG ECN probing: sent 3 probes
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.512025ms
server  | 1.112 DEBUG Setting timeout of 1.512025ms
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] pn=0 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 51
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 0, fin: false }
server  |   -> RX Stream { stream_id: 0, offset: 0, len: 17, fin: true }
server  | 1.112 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 283, tv_nsec: 485968402 }
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.112 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.112 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.483061ms
server  | 1.112 DEBUG Setting timeout of 1.483061ms
server  | 1.113 DEBUG [Server 3ccd2b6786c8d1defa86] send session ticket 406f0008bde35cdb8ac716430104800075300408ffffffffffffffff0504801000000604801000000704801000000801100901100b01140e01080f0ae45fb9e1d816e0666a52100a3ccd2b6786c8d1defa86110c00000001daaafaea000000016ab200c0000000ff02de1a0243e8200100686921
server  | 1.113 DEBUG Path = 'ccrjbecaye'
server  | 1.113 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.113 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.113 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.113 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 349.522µs
server  | 1.113 DEBUG Setting timeout of 349.522µs
server  | 1.115 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.115 DEBUG [Server 3ccd2b6786c8d1defa86] pn=2 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 0, len: >>1196, fin: false }
server  | 1.115 DEBUG packet_sent this=0x5609a8eb4b40, pn=2, ps=1232
server  | 1.115 DEBUG ECN probing: sent 4 probes
server  | 1.115 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.115 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.115 DEBUG [Server 3ccd2b6786c8d1defa86] pn=3 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 1196, len: >>1194, fin: false }
server  | 1.115 DEBUG packet_sent this=0x5609a8eb4b40, pn=3, ps=1232
server  | 1.115 DEBUG ECN probing: sent 5 probes
server  | 1.116 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.116 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.116 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.116 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.894926ms
server  | 1.116 DEBUG Setting timeout of 1.894926ms
server  | 1.116 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.116 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.116 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.116 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.874718ms
server  | 1.116 DEBUG Setting timeout of 1.874718ms
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.119 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] pn=4 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 2390, len: >>1194, fin: false }
server  | 1.119 DEBUG packet_sent this=0x5609a8eb4b40, pn=4, ps=1232
server  | 1.119 DEBUG ECN probing: sent 6 probes
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.119 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] pn=5 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 3584, len: >>1194, fin: false }
server  | 1.119 DEBUG packet_sent this=0x5609a8eb4b40, pn=5, ps=1232
server  | 1.119 DEBUG ECN probing: sent 7 probes
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.119 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.119 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.874149ms
server  | 1.119 DEBUG Setting timeout of 1.874149ms
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.119 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.119 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.119 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.852599ms
server  | 1.119 DEBUG Setting timeout of 1.852599ms
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.122 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] pn=6 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 4778, len: >>1194, fin: false }
server  | 1.122 DEBUG packet_sent this=0x5609a8eb4b40, pn=6, ps=1232
server  | 1.122 DEBUG ECN probing: sent 8 probes
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.122 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] pn=7 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 5972, len: >>1194, fin: false }
server  | 1.122 DEBUG packet_sent this=0x5609a8eb4b40, pn=7, ps=1232
server  | 1.122 DEBUG ECN probing: sent 9 probes
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.122 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.122 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.875028ms
server  | 1.122 DEBUG Setting timeout of 1.875028ms
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.122 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.122 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: true }
server  | 1.122 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 1.842087ms
server  | 1.122 DEBUG Setting timeout of 1.842087ms
server  | 1.124 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.124 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.124 DEBUG [Server 3ccd2b6786c8d1defa86] pn=8 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1232
server  |   TX -> Stream { stream_id: 0, offset: 7166, len: >>1194, fin: false }
server  | 1.124 DEBUG packet_sent this=0x5609a8eb4b40, pn=8, ps=1232
server  | 1.124 DEBUG ECN probing: sent 10 probes
server  | 1.124 DEBUG ECN probing concluded with 10 probes sent
server  | 1.125 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1092, pto: None, probe: EnumSet(), paced: false }
server  | 1.125 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.125 DEBUG [Server 3ccd2b6786c8d1defa86] pn=9 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, NotEct) len 1092
server  |   TX -> Stream { stream_id: 0, offset: 8360, len: >>1054, fin: false }
server  | 1.125 DEBUG packet_sent this=0x5609a8eb4b40, pn=9, ps=1092
server  | 1.125 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.125 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.125 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.125 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 7.503846ms
server  | 1.125 DEBUG Setting timeout of 7.503846ms
server  | 1.125 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.125 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.125 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.125 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 7.477236ms
server  | 1.125 DEBUG Setting timeout of 7.477236ms
server  | 1.133 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.133 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.133 DEBUG [Server 3ccd2b6786c8d1defa86] pn=10 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, NotEct) len 43
server  |   TX -> Ack { largest_acknowledged: 0, ack_delay: 2578, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  | 1.133 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.133 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.133 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.133 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 114.4406ms
server  | 1.133 DEBUG Setting timeout of 114.4406ms
server  | 1.133 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.133 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.133 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.133 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 114.418619ms
server  | 1.133 DEBUG Setting timeout of 114.418619ms
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] pn=1 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1472
server  |   -> RX Ping
server  |   -> RX Padding { len: 1443 }
server  | 1.147 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 283, tv_nsec: 500651225 }
server  | 1.147 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 283, tv_nsec: 500651225 }
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] pn=11 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, NotEct) len 42
server  |   TX -> Ack { largest_acknowledged: 1, ack_delay: 0, first_ack_range: 1, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 2, Ce: 0})) }
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG Building Short dcid Some(CID [16]: a466752b3130128f19045e5c60c9ba38)
server  | 1.147 DEBUG TX blocked, profile=SendProfile { limit: 255, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 100.32745ms
server  | 1.147 DEBUG Setting timeout of 100.32745ms
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] pn=2 type=Short pri-path:a466752b3130128f19045e5c60c9ba38 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 147
server  |   -> RX Ack { largest_acknowledged: 0, ack_delay: 115, first_ack_range: 0, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 1, Ce: 0})) }
server  |   -> RX NewConnectionId { sequence_number: 1, retire_prior: 1, connection_id: [200, 255, 168, 184, 183, 81, 190, 242, 187, 151, 66, 69, 239, 198, 75, 164], stateless_reset_token: [123, 152, 104, 139, 91, 31, 239, 242, 43, 110, 43, 62, 16, 86, 139, 147] }
server  |   -> RX NewConnectionId { sequence_number: 2, retire_prior: 1, connection_id: [119, 154, 75, 12, 230, 33, 159, 59, 70, 78, 36, 3, 101, 27, 240, 148], stateless_reset_token: [181, 191, 60, 39, 224, 170, 251, 200, 226, 164, 45, 65, 176, 115, 252, 243] }
server  |   -> RX NewConnectionId { sequence_number: 3, retire_prior: 1, connection_id: [172, 0, 88, 41, 26, 161, 232, 3, 253, 59, 20, 198, 37, 198, 35, 104], stateless_reset_token: [222, 5, 91, 124, 9, 128, 120, 14, 27, 167, 114, 36, 31, 75, 86, 174] }
server  |   -> RX RetireConnectionId { sequence_number: 0 }
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] Rx ACK space=ap, ranges=[0..=0]
server  | 1.147 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=0
server  | 1.147 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11048/13592 ssthresh 18446744073709551615] slow start += 1272
server  | 1.147 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 11048/13592 ssthresh 18446744073709551615] on_packets_acked this=0x5609a8eb4b40, limited=0, bytes_in_flight=11048, cwnd=13592, state=SlowStart, new_acked=1272
server  | 1.147 DEBUG [Recvd-ap] Set ACK timer to Instant { tv_sec: 283, tv_nsec: 520864734 }
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.147 DEBUG CRYPTO for ap offset=0, len=321
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] pn=12 type=Short pri-path:c8ffa8b8b751bef2bb974245efc64ba4 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, NotEct) len 1223
server  |   TX -> NewConnectionId { sequence_number: 3, retire_prior: 0, connection_id: [135, 252, 117, 85, 129, 246, 226, 79, 210, 51], stateless_reset_token: [140, 125, 11, 105, 217, 38, 157, 225, 193, 95, 47, 2, 247, 72, 1, 60] }
server  |   TX -> RetireConnectionId { sequence_number: 0 }
server  |   TX -> Stream { stream_id: 0, offset: 9414, len: 826, fin: true }
server  |   TX -> Crypto { offset: 0, len: 321 }
server  | 1.147 DEBUG packet_sent this=0x5609a8eb4b40, pn=12, ps=1223
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] pn=13 type=Short pri-path:c8ffa8b8b751bef2bb974245efc64ba4 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, NotEct) len 79
server  |   TX -> NewToken { token: [173, 154, 139, 141, 134, 1, 0, 205, 178, 119, 224, 114, 50, 21, 211, 1, 202, 145, 171, 64, 181, 240, 132, 111, 140, 192, 164, 241, 122, 173, 23, 227, 255, 239, 251, 10, 77, 141, 191, 37, 192, 91, 198] }
server  | 1.147 DEBUG packet_sent this=0x5609a8eb4b40, pn=13, ps=79
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.147 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 19.685432ms
server  | 1.147 DEBUG Setting timeout of 19.685432ms
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.147 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.147 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 19.666557ms
server  | 1.147 DEBUG Setting timeout of 19.666557ms
client  | Request /ccrjbecaye completed successfully
server  | 1.168 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.168 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.168 DEBUG [Server 3ccd2b6786c8d1defa86] pn=14 type=Short pri-path:c8ffa8b8b751bef2bb974245efc64ba4 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, NotEct) len 43
server  |   TX -> Ack { largest_acknowledged: 2, ack_delay: 2562, first_ack_range: 2, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 3, Ce: 0})) }
server  | 1.168 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.168 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.168 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.168 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 90.785476ms
server  | 1.168 DEBUG Setting timeout of 90.785476ms
server  | 1.168 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.168 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.168 DEBUG TX blocked, profile=SendProfile { limit: 1232, pto: None, probe: EnumSet(), paced: false }
server  | 1.168 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 90.75573ms
server  | 1.168 DEBUG Setting timeout of 90.75573ms
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] pn=3 type=Short pri-path:c8ffa8b8b751bef2bb974245efc64ba4 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 47
server  |   -> RX Ack { largest_acknowledged: 10, ack_delay: 1105, first_ack_range: 10, ack_ranges: [], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 9, Ce: 0})) }
server  |   -> RX Padding { len: 10 }
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] Rx ACK space=ap, ranges=[0..=10]
server  | 1.173 DEBUG [LossRecovery] ACK for ApplicationData - largest_acked=10
server  | 1.173 INFO ECN validation succeeded, path is capable
server  | 1.173 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1302/24640 ssthresh 18446744073709551615] slow start += 11048
server  | 1.173 DEBUG [Cubic [last_max_cwnd: 0, k: 0, w_max: 0, ca_epoch_start: None] CongCtrl 1302/24640 ssthresh 18446744073709551615] on_packets_acked this=0x5609a8eb4b40, limited=0, bytes_in_flight=1302, cwnd=24640, state=SlowStart, new_acked=11048
server  | 1.173 DEBUG PMTUD probe of size 1380 succeeded
server  | 1.173 DEBUG PMTUD started with probe size 1420
server  | 1.173 DEBUG PLPMTU changed from 1232 to 1332, updating pacer
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.173 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.173 DEBUG Sending PMTUD probe of size 1420, count 1
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] pn=15 type=Short pri-path:c8ffa8b8b751bef2bb974245efc64ba4 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 1372
server  |   TX -> Ping
server  |   TX -> Padding { len: 1337 }
server  | 1.173 DEBUG packet_sent this=0x5609a8eb4b40, pn=15, ps=1372
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.173 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.173 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 100.702426ms
server  | 1.173 DEBUG Setting timeout of 100.702426ms
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.173 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.173 DEBUG TX blocked, profile=SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.173 DEBUG [Server 3ccd2b6786c8d1defa86] delay duration 100.675075ms
server  | 1.173 DEBUG Setting timeout of 100.675075ms
server  | 1.180 DEBUG [Server 3ccd2b6786c8d1defa86] pn=5 type=Short pri-path:c8ffa8b8b751bef2bb974245efc64ba4 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 47
server  |   -> RX ConnectionClose { error_code: Transport(0), frame_type: 0, reason_phrase: "" }
server  |   -> RX Padding { len: 15 }
server  | 1.180 INFO [Server 3ccd2b6786c8d1defa86] ConnectionClose received. Error code: Transport(0) frame type 0 reason 
server  | 1.180 DEBUG [Server 3ccd2b6786c8d1defa86] State change from Confirmed -> Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 283, tv_nsec: 836592467 } }
server  | 1.180 DEBUG [Server 3ccd2b6786c8d1defa86] output_path send_profile SendProfile { limit: 1332, pto: None, probe: EnumSet(), paced: false }
server  | 1.180 DEBUG Building Short dcid Some(CID [16]: c8ffa8b8b751bef2bb974245efc64ba4)
server  | 1.180 DEBUG [Recvd-ap] immediate_ack at Instant { tv_sec: 283, tv_nsec: 534029417 }
server  | 1.180 DEBUG [Server 3ccd2b6786c8d1defa86] pn=16 type=Short pri-path:c8ffa8b8b751bef2bb974245efc64ba4 [::]:443->[::ffff:193.167.0.100]:41435 IpTos(Cs0, Ect0) len 48
server  |   TX -> Ack { largest_acknowledged: 5, ack_delay: 0, first_ack_range: 0, ack_ranges: [AckRange { gap: 0, range: 3 }], ecn_count: Some(Count({NotEct: 0, Ect1: 0, Ect0: 5, Ce: 0})) }
server  |   TX -> ConnectionClose { error_code: Transport(0), frame_type: 28, reason_phrase: "" }
server  | 1.180 DEBUG Setting timeout of 302.397932ms
server  | 1.180 DEBUG Setting timeout of 302.379337ms
server  | 1.232 WARN [Server ...] Dropped received packet: State Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 283, tv_nsec: 836592467 } }; Total: 1
server  | 1.232 DEBUG Setting timeout of 250.525051ms
server  | 1.232 DEBUG Setting timeout of 250.490877ms
server  | 1.484 DEBUG [Server 3ccd2b6786c8d1defa86] State change from Draining { error: Transport(PeerError(0)), timeout: Instant { tv_sec: 283, tv_nsec: 836592467 } } -> Closed(Transport(PeerError(0)))
server  | 1.484 INFO Closing timer expired

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

2025-04-30 08:43:51,076 Using the client's key log file.
2025-04-30 08:43:51,086 
2025-04-30 08:43:51,087 Using the client's key log file.
2025-04-30 08:43:51,087 Using the client's key log file.
2025-04-30 08:43:51,096 
2025-04-30 08:43:51,096 Using the client's key log file.
2025-04-30 08:43:51,684 Check of downloaded files succeeded.
2025-04-30 08:43:52,281 Check of Retry succeeded. Token used: 526574727901008a4635904294cbe200834c6e9ba985a68d70935d0658f3157b8da7489cd81118dea948a9ffda62ad3f5bc269