2025/04/30 11:16:04 Requesting https://server4:443/dfkndxmhhw.
2025/04/30 11:16:04 Increased receive buffer size to 14336 kiB
2025/04/30 11:16:04 Increased send buffer size to 14336 kiB
2025/04/30 11:16:04 Setting DF for IPv4 and IPv6.
2025/04/30 11:16:04 Activating reading of ECN bits for IPv4 and IPv6.
2025/04/30 11:16:04 Activating reading of packet info for IPv4 and IPv6.
2025/04/30 11:16:04 Created qlog file: /logs/qlog/0acd71bc4915f3cac02178a41f3927.sqlog
2025/04/30 11:16:04 client Starting new connection to server4 ([::]:58094 -> 193.167.100.100:443), source connection ID (empty), destination connection ID 0acd71bc4915f3cac02178a41f3927, version v1
2025/04/30 11:16:04 Adding connection ID (empty).
2025/04/30 11:16:04 client Not doing 0-RTT. Has sealer: false, has params: false
2025/04/30 11:16:04 client -> Sending packet 0 (1280 bytes) for connection 0acd71bc4915f3cac02178a41f3927, Initial
2025/04/30 11:16:04 client 	Long Header{Type: Initial, DestConnectionID: 0acd71bc4915f3cac02178a41f3927, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1255, Version: v1}
2025/04/30 11:16:04 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 271, Offset + Data length: 271}
2025/04/30 11:16:04 client 	updated RTT: 37.049461ms (σ: 18.52473ms)
2025/04/30 11:16:04 client <- Received Retry:
2025/04/30 11:16:04 client 	Long Header{Type: Retry, DestConnectionID: (empty), SrcConnectionID: e79fdd584bd51c77, Token: 0x9c8d0b6486db0119d98bb888e456a9ae8c04031902ad20bb9f12c64223ec6b81c08bc0fecce1e024635b37173ec69aa286e330bddf, Version: v1}
2025/04/30 11:16:04 client Switching destination connection ID to: e79fdd584bd51c77
2025/04/30 11:16:04 client -> Sending packet 1 (1280 bytes) for connection 0acd71bc4915f3cac02178a41f3927, Initial
2025/04/30 11:16:04 client 	Long Header{Type: Initial, DestConnectionID: e79fdd584bd51c77, SrcConnectionID: (empty), Token: 0x9c8d0b6486db0119d98bb888e456a9ae8c04031902ad20bb9f12c64223ec6b81c08bc0fecce1e024635b37173ec69aa286e330bddf, PacketNumber: 1, PacketNumberLen: 2, Length: 1209, Version: v1}
2025/04/30 11:16:04 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 271, Offset + Data length: 271}
2025/04/30 11:16:04 client Parsed a coalesced packet. Part 1: 135 bytes. Remaining: 1065 bytes.
2025/04/30 11:16:04 client <- Reading packet 0 (135 bytes) for connection (empty), Initial
2025/04/30 11:16:04 client 	Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: 84afe3d6225cfb27, Token: (empty), PacketNumber: 0, PacketNumberLen: 1, Length: 117, Version: v1}
2025/04/30 11:16:04 client Received first packet. Switching destination connection ID to: 84afe3d6225cfb27
2025/04/30 11:16:04 client 	<- &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 1ms}
2025/04/30 11:16:04 client 	newly acked packets (1): [1]
2025/04/30 11:16:04 client 	updated RTT: 36.81ms (σ: 14.37ms)
2025/04/30 11:16:04 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90}
2025/04/30 11:16:04 client Installed Handshake Write keys (using TLS_AES_256_GCM_SHA384)
2025/04/30 11:16:04 client Installed Handshake Read keys (using TLS_AES_256_GCM_SHA384)
2025/04/30 11:16:04 client Parsed a coalesced packet. Part 2: 1065 bytes. Remaining: 0 bytes.
2025/04/30 11:16:04 client <- Reading packet 0 (1065 bytes) for connection (empty), Handshake
2025/04/30 11:16:04 client 	Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: 84afe3d6225cfb27, PacketNumber: 0, PacketNumberLen: 1, Length: 1048, Version: v1}
2025/04/30 11:16:04 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 746, Offset + Data length: 746}
2025/04/30 11:16:04 client Installed 1-RTT Write keys (using TLS_AES_256_GCM_SHA384)
2025/04/30 11:16:04 client Installed 1-RTT Read keys (using TLS_AES_256_GCM_SHA384)
2025/04/30 11:16:04 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: 0acd71bc4915f3cac02178a41f3927, InitialSourceConnectionID: 84afe3d6225cfb27, RetrySourceConnectionID: e79fdd584bd51c77, InitialMaxStreamDataBidiLocal: 16380, InitialMaxStreamDataBidiRemote: 1474200, InitialMaxStreamDataUni: 16380, InitialMaxData: 1638000, MaxBidiStreamNum: 100, MaxUniStreamNum: 3, MaxIdleTimeout: 30s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 8, StatelessResetToken: 0x6601af698ad904e5479e98fb06a69a17}
2025/04/30 11:16:04 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection 0acd71bc4915f3cac02178a41f3927
2025/04/30 11:16:04 client 	Long Header{Type: Initial, DestConnectionID: 84afe3d6225cfb27, SrcConnectionID: (empty), Token: 0x9c8d0b6486db0119d98bb888e456a9ae8c04031902ad20bb9f12c64223ec6b81c08bc0fecce1e024635b37173ec69aa286e330bddf, PacketNumber: 2, PacketNumberLen: 2, Length: 1119, Version: v1}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s}
2025/04/30 11:16:04 client 	Long Header{Type: Handshake, DestConnectionID: 84afe3d6225cfb27, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 73, Version: v1}
2025/04/30 11:16:04 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 52, Offset + Data length: 52}
2025/04/30 11:16:04 client Dropping Initial keys.
2025/04/30 11:16:04 client -> Sending packet 1 (40 bytes) for connection 0acd71bc4915f3cac02178a41f3927, Handshake
2025/04/30 11:16:04 client 	Long Header{Type: Handshake, DestConnectionID: 84afe3d6225cfb27, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 23, Version: v1}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s}
2025/04/30 11:16:04 client -> Sending packet 0 (46 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: ECT(0))
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 84afe3d6225cfb27, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17}
2025/04/30 11:16:04 client Parsed a coalesced packet. Part 1: 38 bytes. Remaining: 568 bytes.
2025/04/30 11:16:04 client <- Reading packet 1 (38 bytes) for connection (empty), Handshake
2025/04/30 11:16:04 client 	Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: 84afe3d6225cfb27, PacketNumber: 1, PacketNumberLen: 1, Length: 22, Version: v1}
2025/04/30 11:16:04 client 	<- &wire.AckFrame{LargestAcked: 1, LowestAcked: 0, DelayTime: 0s}
2025/04/30 11:16:04 client Peer doesn't await address validation any longer.
2025/04/30 11:16:04 client 	newly acked packets (1): [0]
2025/04/30 11:16:04 client Canceling loss detection timer.
2025/04/30 11:16:04 client <- Reading packet 0 (568 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 546, Offset + Data length: 546}
2025/04/30 11:16:04 client 	Queueing ACK because the first packet should be acknowledged.
2025/04/30 11:16:04 client -> Sending packet 1 (32 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: ECT(0))
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 84afe3d6225cfb27, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 86.282µs}
2025/04/30 11:16:04 client <- Reading packet 1 (148 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 2ms}
2025/04/30 11:16:04 client 	newly acked packets (1): [0]
2025/04/30 11:16:04 client 	updated RTT: 36.563ms (σ: 11.27ms)
2025/04/30 11:16:04 client Disabling ECN. ECN-marked packet acknowledged, but no ECN counts on ACK frame.
2025/04/30 11:16:04 client Dropping Handshake keys.
2025/04/30 11:16:04 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 11:16:04 client 	<- &wire.NewTokenFrame{Token: 0xb7e350313bf9f9524fdb1b6b548fafbe09b27fb6a442fff119a12b83d22be529fa6dded928}
2025/04/30 11:16:04 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: 5356210618236c48, StatelessResetToken: 0x7fd7a7f0d58895f779cf0add9413cef4}
2025/04/30 11:16:04 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 0, ConnectionID: 1e8024cba860c639, StatelessResetToken: 0x9bdcecce0777c30caa091b8bfa4d6e95}
2025/04/30 11:16:04 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 0, ConnectionID: 9280fe3d00b49fdb, StatelessResetToken: 0x55f7d8091eae8156c846f314aa853111}
2025/04/30 11:16:04 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:16:04 client -> Sending packet 2 (34 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: Not-ECT)
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 5356210618236c48, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 1, LowestAcked: 0, DelayTime: 85.85µs}
2025/04/30 11:16:04 client 	-> &wire.RetireConnectionIDFrame{SequenceNumber: 0}
2025/04/30 11:16:04 client <- Reading packet 2 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1230, Offset + Data length: 1230}
2025/04/30 11:16:04 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:16:04 client <- Reading packet 3 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 3, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1230, Data length: 1228, Offset + Data length: 2458}
2025/04/30 11:16:04 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:16:04 client -> Sending packet 3 (32 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: Not-ECT)
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 5356210618236c48, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 3, LowestAcked: 0, DelayTime: 45.556µs}
2025/04/30 11:16:04 client <- Reading packet 4 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2458, Data length: 1228, Offset + Data length: 3686}
2025/04/30 11:16:04 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:16:04 client <- Reading packet 5 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3686, Data length: 1228, Offset + Data length: 4914}
2025/04/30 11:16:04 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:16:04 client -> Sending packet 4 (32 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: Not-ECT)
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 5356210618236c48, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 5, LowestAcked: 0, DelayTime: 58.35µs}
2025/04/30 11:16:04 client <- Reading packet 6 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 4914, Data length: 1228, Offset + Data length: 6142}
2025/04/30 11:16:04 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:16:04 client <- Reading packet 7 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6142, Data length: 1228, Offset + Data length: 7370}
2025/04/30 11:16:04 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:16:04 client -> Sending packet 5 (32 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: Not-ECT)
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 5356210618236c48, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 7, LowestAcked: 0, DelayTime: 53.701µs}
2025/04/30 11:16:04 client <- Reading packet 8 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7370, Data length: 1228, Offset + Data length: 8598}
2025/04/30 11:16:04 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:16:04 client <- Reading packet 9 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 9, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8598, Data length: 1228, Offset + Data length: 9826}
2025/04/30 11:16:04 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:16:04 client -> Sending packet 6 (32 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: Not-ECT)
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 5356210618236c48, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.AckFrame{LargestAcked: 9, LowestAcked: 0, DelayTime: 52.258µs}
2025/04/30 11:16:04 client <- Reading packet 10 (438 bytes) for connection (empty), 1-RTT
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: (empty), PacketNumber: 10, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 11:16:04 client 	<- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 9826, Data length: 414, Offset + Data length: 10240}
2025/04/30 11:16:04 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:16:04 client Closing connection with error: Application error 0x0 (local)
2025/04/30 11:16:04 client -> Sending packet 7 (30 bytes) for connection 0acd71bc4915f3cac02178a41f3927, 1-RTT (ECN: Not-ECT)
2025/04/30 11:16:04 client 	Short Header{DestConnectionID: 5356210618236c48, PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:16:04 client 	-> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""}
2025/04/30 11:16:04 Replacing connection for connection IDs [(empty)] with a closed connection.
2025/04/30 11:16:04 client Connection 0acd71bc4915f3cac02178a41f3927 closed.