2025/04/30 13:27:44 Requesting https://server4:443/blnudtfnsm.
2025/04/30 13:27:44 Increased receive buffer size to 14336 kiB
2025/04/30 13:27:44 Increased send buffer size to 14336 kiB
2025/04/30 13:27:44 Setting DF for IPv4 and IPv6.
2025/04/30 13:27:44 Activating reading of ECN bits for IPv4 and IPv6.
2025/04/30 13:27:44 Activating reading of packet info for IPv4 and IPv6.
2025/04/30 13:27:44 Created qlog file: /logs/qlog/f8e459b5244350ecc52f644dc61d.sqlog
2025/04/30 13:27:44 client Starting new connection to server4 ([::]:47692 -> 193.167.100.100:443), source connection ID (empty), destination connection ID f8e459b5244350ecc52f644dc61d, version v1
2025/04/30 13:27:44 Adding connection ID (empty).
2025/04/30 13:27:44 client Not doing 0-RTT. Has sealer: false, has params: false
2025/04/30 13:27:44 client -> Sending packet 0 (1280 bytes) for connection f8e459b5244350ecc52f644dc61d, Initial
2025/04/30 13:27:44 client 	Long Header{Type: Initial, DestConnectionID: f8e459b5244350ecc52f644dc61d, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1256, Version: v1}
2025/04/30 13:27:44 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 272, Offset + Data length: 272}
2025/04/30 13:27:44 client 	updated RTT: 37.293909ms (σ: 18.646954ms)
2025/04/30 13:27:44 client <- Received Retry:
2025/04/30 13:27:44 client 	Long Header{Type: Retry, DestConnectionID: (empty), SrcConnectionID: 610784047ac9c15d0fc889aee24bb586, Token: 0x600ef8e459b5244350ecc52f644dc61d00000000000017129a8de11559c3e26261d68da5f8e0101902cbbebde1465c149a7ca7cdf7f3a94eb9d8045d7e4f733e79c6f3b2781713d9e621a9fecc3f909ce2e3f8224f9d, Version: v1}
2025/04/30 13:27:44 client Switching destination connection ID to: 610784047ac9c15d0fc889aee24bb586
2025/04/30 13:27:44 client -> Sending packet 1 (1280 bytes) for connection f8e459b5244350ecc52f644dc61d, Initial
2025/04/30 13:27:44 client 	Long Header{Type: Initial, DestConnectionID: 610784047ac9c15d0fc889aee24bb586, SrcConnectionID: (empty), Token: 0x600ef8e459b5244350ecc52f644dc61d00000000000017129a8de11559c3e26261d68da5f8e0101902cbbebde1465c149a7ca7cdf7f3a94eb9d8045d7e4f733e79c6f3b2781713d9e621a9fecc3f909ce2e3f8224f9d, PacketNumber: 1, PacketNumberLen: 2, Length: 1167, Version: v1}
2025/04/30 13:27:44 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 272, Offset + Data length: 272}
2025/04/30 13:27:44 client Parsed a coalesced packet. Part 1: 143 bytes. Remaining: 1089 bytes.
2025/04/30 13:27:44 client <- Reading packet 0 (143 bytes) for connection (empty), Initial
2025/04/30 13:27:44 client 	Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: 610784047ac9c15d0fc889aee24bb586, Token: (empty), PacketNumber: 0, PacketNumberLen: 1, Length: 117, Version: v1}
2025/04/30 13:27:44 client 	<- &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 71.24ms}
2025/04/30 13:27:44 client 	newly acked packets (1): [1]
2025/04/30 13:27:44 client 	updated RTT: 37.881ms (σ: 15.161ms)
2025/04/30 13:27:44 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90}
2025/04/30 13:27:44 client Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 13:27:44 client Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 13:27:44 client Parsed a coalesced packet. Part 2: 1089 bytes. Remaining: 0 bytes.
2025/04/30 13:27:44 client <- Reading packet 0 (1089 bytes) for connection (empty), Handshake
2025/04/30 13:27:44 client 	Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: 610784047ac9c15d0fc889aee24bb586, PacketNumber: 0, PacketNumberLen: 1, Length: 1064, Version: v1}
2025/04/30 13:27:44 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 709, Offset + Data length: 709}
2025/04/30 13:27:44 client Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 13:27:44 client Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 13:27:44 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: f8e459b5244350ecc52f644dc61d, InitialSourceConnectionID: 610784047ac9c15d0fc889aee24bb586, RetrySourceConnectionID: 610784047ac9c15d0fc889aee24bb586, InitialMaxStreamDataBidiLocal: 3750000, InitialMaxStreamDataBidiRemote: 3750000, InitialMaxStreamDataUni: 3750000, InitialMaxData: 3750000, MaxBidiStreamNum: 100, MaxUniStreamNum: 100, MaxIdleTimeout: 5m0s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 3}
2025/04/30 13:27:44 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection f8e459b5244350ecc52f644dc61d
2025/04/30 13:27:44 client 	Long Header{Type: Initial, DestConnectionID: 610784047ac9c15d0fc889aee24bb586, SrcConnectionID: (empty), Token: 0x600ef8e459b5244350ecc52f644dc61d00000000000017129a8de11559c3e26261d68da5f8e0101902cbbebde1465c149a7ca7cdf7f3a94eb9d8045d7e4f733e79c6f3b2781713d9e621a9fecc3f909ce2e3f8224f9d, PacketNumber: 2, PacketNumberLen: 2, Length: 1085, Version: v1}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s, ECT0: 1, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client 	Long Header{Type: Handshake, DestConnectionID: 610784047ac9c15d0fc889aee24bb586, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 57, Version: v1}
2025/04/30 13:27:44 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36}
2025/04/30 13:27:44 client Dropping Initial keys.
2025/04/30 13:27:44 client -> Sending packet 1 (105 bytes) for connection f8e459b5244350ecc52f644dc61d, Handshake
2025/04/30 13:27:44 client 	Long Header{Type: Handshake, DestConnectionID: 610784047ac9c15d0fc889aee24bb586, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 26, Version: v1}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s, ECT0: 1, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 610784047ac9c15d0fc889aee24bb586, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17}
2025/04/30 13:27:44 client <- Reading packet 0 (1452 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.PingFrame{}
2025/04/30 13:27:44 client 	Queueing ACK because the first packet should be acknowledged.
2025/04/30 13:27:44 client -> Sending packet 1 (43 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 610784047ac9c15d0fc889aee24bb586, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 84.929µs, ECT0: 1, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client <- Reading packet 1 (133 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 1.12ms}
2025/04/30 13:27:44 client Peer doesn't await address validation any longer.
2025/04/30 13:27:44 client 	newly acked packets (1): [0]
2025/04/30 13:27:44 client 	updated RTT: 37.467ms (σ: 12.197ms)
2025/04/30 13:27:44 client Canceling loss detection timer.
2025/04/30 13:27:44 client Dropping Handshake keys.
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 1, ConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, StatelessResetToken: 0xc2c5babbd7872ba4f4ed73c6c7007f59}
2025/04/30 13:27:44 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 1, ConnectionID: ba0ebc531a04ba70f34f36a559bb6071, StatelessResetToken: 0x0962067d2a0946305b13ad0638cb5ad7}
2025/04/30 13:27:44 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 1, ConnectionID: cddadce0326967eadc5b85b9d119dd92, StatelessResetToken: 0xaaecfdd05b361f8a3d65a9439d6e716d}
2025/04/30 13:27:44 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 13:27:44 client -> Sending packet 2 (45 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 1, LowestAcked: 0, DelayTime: 185.187µs, ECT0: 2, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client 	-> &wire.RetireConnectionIDFrame{SequenceNumber: 0}
2025/04/30 13:27:44 client <- Reading packet 2 (1232 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1211, Offset + Data length: 1211}
2025/04/30 13:27:44 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 13:27:44 client <- Reading packet 3 (1232 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 3, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1211, Data length: 1209, Offset + Data length: 2420}
2025/04/30 13:27:44 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 13:27:44 client -> Sending packet 3 (43 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 3, LowestAcked: 0, DelayTime: 48.901µs, ECT0: 4, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client <- Reading packet 4 (1232 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2420, Data length: 1209, Offset + Data length: 3629}
2025/04/30 13:27:44 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 13:27:44 client <- Reading packet 5 (492 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3629, Data length: 467, Offset + Data length: 4096}
2025/04/30 13:27:44 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 13:27:44 client -> Sending packet 4 (43 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 5, LowestAcked: 0, DelayTime: 44.824µs, ECT0: 6, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client <- Reading packet 6 (1232 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 4096, Data length: 1209, Offset + Data length: 5305}
2025/04/30 13:27:44 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 13:27:44 client <- Reading packet 7 (1232 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 5305, Data length: 1209, Offset + Data length: 6514}
2025/04/30 13:27:44 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 13:27:44 client -> Sending packet 5 (43 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 7, LowestAcked: 0, DelayTime: 54.412µs, ECT0: 8, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client <- Reading packet 8 (1232 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6514, Data length: 1209, Offset + Data length: 7723}
2025/04/30 13:27:44 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 13:27:44 client <- Reading packet 9 (494 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 9, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7723, Data length: 469, Offset + Data length: 8192}
2025/04/30 13:27:44 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 13:27:44 client -> Sending packet 6 (43 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 9, LowestAcked: 0, DelayTime: 43.341µs, ECT0: 8, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client <- Reading packet 10 (1232 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 10, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8192, Data length: 1209, Offset + Data length: 9401}
2025/04/30 13:27:44 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 13:27:44 client <- Reading packet 11 (864 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 11, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 9401, Data length: 839, Offset + Data length: 10240}
2025/04/30 13:27:44 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 13:27:44 client -> Sending packet 7 (43 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 11, LowestAcked: 0, DelayTime: 52.439µs, ECT0: 8, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client <- Reading packet 13 (43 bytes) for connection (empty), 1-RTT
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: (empty), PacketNumber: 13, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 13:27:44 client 	<- &wire.AckFrame{LargestAcked: 3, LowestAcked: 1, DelayTime: 232µs, ECT0: 3, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client 	newly acked packets (1): [2]
2025/04/30 13:27:44 client ECN capability confirmed.
2025/04/30 13:27:44 client Canceling loss detection timer.
2025/04/30 13:27:44 client 	<- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 10240, Data length: 0, Offset + Data length: 10240}
2025/04/30 13:27:44 client 	Queuing ACK because there's a new missing packet to report.
2025/04/30 13:27:44 client 	Ignoring all packets below 2.
2025/04/30 13:27:44 client -> Sending packet 8 (45 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 8, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.AckFrame{LargestAcked: 13, LowestAcked: 2, AckRanges: {{Largest: 13, Smallest: 13}, {Largest: 11, Smallest: 2}}, DelayTime: 87.434µs, ECT0: 9, ECT1: 0, CE: 0}
2025/04/30 13:27:44 client Closing connection with error: Application error 0x0 (local)
2025/04/30 13:27:44 client -> Sending packet 9 (38 bytes) for connection f8e459b5244350ecc52f644dc61d, 1-RTT (ECN: ECT(0))
2025/04/30 13:27:44 client 	Short Header{DestConnectionID: 6a3850e1e334431ea2dc4ba6529cc578, PacketNumber: 9, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 13:27:44 client 	-> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""}
2025/04/30 13:27:44 Replacing connection for connection IDs [(empty)] with a closed connection.
2025/04/30 13:27:44 client Connection f8e459b5244350ecc52f644dc61d closed.