2025/04/30 11:00:54 Requesting https://server4:443/dotjyyndsl.
2025/04/30 11:00:54 Increased receive buffer size to 14336 kiB
2025/04/30 11:00:54 Increased send buffer size to 14336 kiB
2025/04/30 11:00:54 Setting DF for IPv4 and IPv6.
2025/04/30 11:00:54 Activating reading of ECN bits for IPv4 and IPv6.
2025/04/30 11:00:54 Activating reading of packet info for IPv4 and IPv6.
2025/04/30 11:00:54 Created qlog file: /logs/qlog/42bd284406f32c52bc5676971e2cc2.sqlog
2025/04/30 11:00:54 client Starting new connection to server4 ([::]:57613 -> 193.167.100.100:443), source connection ID (empty), destination connection ID 42bd284406f32c52bc5676971e2cc2, version v1
2025/04/30 11:00:54 Adding connection ID (empty).
2025/04/30 11:00:54 client Not doing 0-RTT. Has sealer: false, has params: false
2025/04/30 11:00:54 client -> Sending packet 0 (1280 bytes) for connection 42bd284406f32c52bc5676971e2cc2, Initial
2025/04/30 11:00:54 client 	Long Header{Type: Initial, DestConnectionID: 42bd284406f32c52bc5676971e2cc2, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1255, Version: v1}
2025/04/30 11:00:54 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 263, Offset + Data length: 263}
2025/04/30 11:00:54 client 	updated RTT: 36.875596ms (σ: 18.437798ms)
2025/04/30 11:00:54 client <- Received Retry:
2025/04/30 11:00:54 client 	Long Header{Type: Retry, DestConnectionID: (empty), SrcConnectionID: 69d35030, Token: 0x90f51068373830690bb47dc94c3815407a8f51585d50eaf078acd79f9e7c40ad6c64074c458ee5ecc285d9f53818f80d9ec8e9eb5b59188929b436e7f508320a8b75a82188c31a240a24c8cf5cf0f9d0fd704230f718de786f7b7bedccc094f3f31670713f12d0584f, Version: v1}
2025/04/30 11:00:54 client Switching destination connection ID to: 69d35030
2025/04/30 11:00:54 client -> Sending packet 1 (1280 bytes) for connection 42bd284406f32c52bc5676971e2cc2, Initial
2025/04/30 11:00:54 client 	Long Header{Type: Initial, DestConnectionID: 69d35030, SrcConnectionID: (empty), Token: 0x90f51068373830690bb47dc94c3815407a8f51585d50eaf078acd79f9e7c40ad6c64074c458ee5ecc285d9f53818f80d9ec8e9eb5b59188929b436e7f508320a8b75a82188c31a240a24c8cf5cf0f9d0fd704230f718de786f7b7bedccc094f3f31670713f12d0584f, PacketNumber: 1, PacketNumberLen: 2, Length: 1160, Version: v1}
2025/04/30 11:00:54 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 263, Offset + Data length: 263}
2025/04/30 11:00:54 client Parsed a coalesced packet. Part 1: 434 bytes. Remaining: 846 bytes.
2025/04/30 11:00:54 client <- Reading packet 0 (434 bytes) for connection (empty), Initial
2025/04/30 11:00:54 client 	Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: 9747545b, Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 420, Version: v1}
2025/04/30 11:00:54 client Received first packet. Switching destination connection ID to: 9747545b
2025/04/30 11:00:54 client 	<- &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 0s}
2025/04/30 11:00:54 client 	newly acked packets (1): [1]
2025/04/30 11:00:54 client 	updated RTT: 36.486ms (σ: 14.604ms)
2025/04/30 11:00:54 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90}
2025/04/30 11:00:54 client Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 11:00:54 client Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 11:00:54 client Parsed a coalesced packet. Part 2: 755 bytes. Remaining: 91 bytes.
2025/04/30 11:00:54 client <- Reading packet 0 (755 bytes) for connection (empty), Handshake
2025/04/30 11:00:54 client 	Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: 9747545b, PacketNumber: 0, PacketNumberLen: 2, Length: 742, Version: v1}
2025/04/30 11:00:54 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 720, Offset + Data length: 720}
2025/04/30 11:00:54 client Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 11:00:54 client Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 11:00:54 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: 42bd284406f32c52bc5676971e2cc2, InitialSourceConnectionID: 9747545b, RetrySourceConnectionID: 69d35030, InitialMaxStreamDataBidiLocal: 524288, InitialMaxStreamDataBidiRemote: 524288, InitialMaxStreamDataUni: 524288, InitialMaxData: 786432, MaxBidiStreamNum: 100, MaxUniStreamNum: 100, MaxIdleTimeout: 30s, AckDelayExponent: 3, MaxAckDelay: 26ms, ActiveConnectionIDLimit: 4, StatelessResetToken: 0xac27b1083eb109b874418b2b28e2a157}
2025/04/30 11:00:54 client <- Reading packet 0 (91 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 0, ConnectionID: d5c70e90, StatelessResetToken: 0x742bcfd3576d5b0d51c690814f02d4c8}
2025/04/30 11:00:54 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: 646ad1c8, StatelessResetToken: 0x8d0da446dc26edc9f0ce07032313f5cc}
2025/04/30 11:00:54 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 0, ConnectionID: 05ed1a85, StatelessResetToken: 0x7a58c2b5f72e41996a71b4b947adcd66}
2025/04/30 11:00:54 client 	Queueing ACK because the first packet should be acknowledged.
2025/04/30 11:00:54 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection 42bd284406f32c52bc5676971e2cc2
2025/04/30 11:00:54 client 	Long Header{Type: Initial, DestConnectionID: 646ad1c8, SrcConnectionID: (empty), Token: 0x90f51068373830690bb47dc94c3815407a8f51585d50eaf078acd79f9e7c40ad6c64074c458ee5ecc285d9f53818f80d9ec8e9eb5b59188929b436e7f508320a8b75a82188c31a240a24c8cf5cf0f9d0fd704230f718de786f7b7bedccc094f3f31670713f12d0584f, PacketNumber: 2, PacketNumberLen: 2, Length: 1065, Version: v1}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s}
2025/04/30 11:00:54 client 	Long Header{Type: Handshake, DestConnectionID: 646ad1c8, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 57, Version: v1}
2025/04/30 11:00:54 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36}
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.RetireConnectionIDFrame{SequenceNumber: 0}
2025/04/30 11:00:54 client Dropping Initial keys.
2025/04/30 11:00:54 client -> Sending packet 1 (78 bytes) for connection 42bd284406f32c52bc5676971e2cc2, Handshake
2025/04/30 11:00:54 client 	Long Header{Type: Handshake, DestConnectionID: 646ad1c8, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 23, Version: v1}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s}
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17}
2025/04/30 11:00:54 client -> Sending packet 2 (29 bytes) for connection 42bd284406f32c52bc5676971e2cc2, 1-RTT (ECN: ECT(0))
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 995.547µs}
2025/04/30 11:00:54 client <- Reading packet 1 (256 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.AckFrame{LargestAcked: 1, LowestAcked: 0, DelayTime: 424µs}
2025/04/30 11:00:54 client Peer doesn't await address validation any longer.
2025/04/30 11:00:54 client 	newly acked packets (2): [0 1]
2025/04/30 11:00:54 client 	updated RTT: 35.986ms (σ: 11.951ms)
2025/04/30 11:00:54 client Canceling loss detection timer.
2025/04/30 11:00:54 client Dropping Handshake keys.
2025/04/30 11:00:54 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 11:00:54 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 138, Offset + Data length: 138}
2025/04/30 11:00:54 client 	<- &wire.NewTokenFrame{Token: 0x3346ac0d4e086ae8580eca260cec3edbbe9b23d685287c39fea6179c2bdf5b3c992a0a6888552d56dfbc0a9357378e903656b51a2cffe68a6ff698b2b393f7cf249a006b062a8c68edf35cae7593f73565829cb09247}
2025/04/30 11:00:54 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:00:54 client <- Reading packet 2 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1259, Offset + Data length: 1259}
2025/04/30 11:00:54 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:00:54 client -> Sending packet 3 (31 bytes) for connection 42bd284406f32c52bc5676971e2cc2, 1-RTT (ECN: ECT(0))
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 2, LowestAcked: 0, DelayTime: 48.921µs, ECT0: 2, ECT1: 0, CE: 0}
2025/04/30 11:00:54 client <- Reading packet 3 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1259, Data length: 1257, Offset + Data length: 2516}
2025/04/30 11:00:54 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:00:54 client <- Reading packet 4 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2516, Data length: 1257, Offset + Data length: 3773}
2025/04/30 11:00:54 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:00:54 client -> Sending packet 4 (31 bytes) for connection 42bd284406f32c52bc5676971e2cc2, 1-RTT (ECN: ECT(0))
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 4, LowestAcked: 0, DelayTime: 47.078µs, ECT0: 4, ECT1: 0, CE: 0}
2025/04/30 11:00:54 client <- Reading packet 5 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3773, Data length: 1257, Offset + Data length: 5030}
2025/04/30 11:00:54 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:00:54 client <- Reading packet 6 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 5030, Data length: 1257, Offset + Data length: 6287}
2025/04/30 11:00:54 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:00:54 client -> Sending packet 5 (31 bytes) for connection 42bd284406f32c52bc5676971e2cc2, 1-RTT (ECN: ECT(0))
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 6, LowestAcked: 0, DelayTime: 42.039µs, ECT0: 6, ECT1: 0, CE: 0}
2025/04/30 11:00:54 client <- Reading packet 7 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6287, Data length: 1257, Offset + Data length: 7544}
2025/04/30 11:00:54 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:00:54 client <- Reading packet 8 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7544, Data length: 1257, Offset + Data length: 8801}
2025/04/30 11:00:54 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:00:54 client -> Sending packet 6 (31 bytes) for connection 42bd284406f32c52bc5676971e2cc2, 1-RTT (ECN: ECT(0))
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 8, LowestAcked: 0, DelayTime: 54.221µs, ECT0: 8, ECT1: 0, CE: 0}
2025/04/30 11:00:54 client <- Reading packet 9 (1280 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 9, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8801, Data length: 1257, Offset + Data length: 10058}
2025/04/30 11:00:54 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 11:00:54 client <- Reading packet 10 (205 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 10, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 10058, Data length: 182, Offset + Data length: 10240}
2025/04/30 11:00:54 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 11:00:54 client <- Reading packet 11 (23 bytes) for connection (empty), 1-RTT
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: (empty), PacketNumber: 11, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	<- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 10240, Data length: 0, Offset + Data length: 10240}
2025/04/30 11:00:54 client -> Sending packet 7 (31 bytes) for connection 42bd284406f32c52bc5676971e2cc2, 1-RTT (ECN: ECT(0))
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.AckFrame{LargestAcked: 11, LowestAcked: 0, DelayTime: 60.032µs, ECT0: 10, ECT1: 0, CE: 0}
2025/04/30 11:00:54 client Closing connection with error: Application error 0x0 (local)
2025/04/30 11:00:54 client -> Sending packet 8 (26 bytes) for connection 42bd284406f32c52bc5676971e2cc2, 1-RTT (ECN: ECT(0))
2025/04/30 11:00:54 client 	Short Header{DestConnectionID: 646ad1c8, PacketNumber: 8, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 11:00:54 client 	-> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""}
2025/04/30 11:00:54 Replacing connection for connection IDs [(empty)] with a closed connection.
2025/04/30 11:00:54 client Connection 42bd284406f32c52bc5676971e2cc2 closed.