2025/09/12 06:15:10 Requesting https://server4:443/fxwspghlft.
2025/09/12 06:15:10 Increased receive buffer size to 14336 kiB
2025/09/12 06:15:10 Increased send buffer size to 14336 kiB
2025/09/12 06:15:10 Setting DF for IPv4 and IPv6.
2025/09/12 06:15:10 Activating reading of ECN bits for IPv4 and IPv6.
2025/09/12 06:15:10 Activating reading of packet info for IPv4 and IPv6.
2025/09/12 06:15:10 Created qlog file: /logs/qlog/511add81d33431d1372451b9f37e85c4f9cc2f.sqlog
2025/09/12 06:15:10 client Starting new connection to server4 ([::]:60931 -> 193.167.100.100:443), source connection ID (empty), destination connection ID 511add81d33431d1372451b9f37e85c4f9cc2f, version v1
2025/09/12 06:15:10 client Not doing 0-RTT. Has sealer: false, has params: false
2025/09/12 06:15:10 client -> Sending packet 0 (1280 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, Initial
2025/09/12 06:15:10 client 	Long Header{Type: Initial, DestConnectionID: 511add81d33431d1372451b9f37e85c4f9cc2f, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1251, Version: v1}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 63, Offset + Data length: 63}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 67, Data length: 1162, Offset + Data length: 1229}
2025/09/12 06:15:10 client -> Sending packet 1 (1280 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, Initial
2025/09/12 06:15:10 client 	Long Header{Type: Initial, DestConnectionID: 511add81d33431d1372451b9f37e85c4f9cc2f, SrcConnectionID: (empty), Token: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 1251, Version: v1}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 63, Data length: 4, Offset + Data length: 67}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 1229, Data length: 283, Offset + Data length: 1512}
2025/09/12 06:15:10 client 	updated RTT: 36.688768ms (σ: 18.344384ms)
2025/09/12 06:15:10 client <- Received Retry:
2025/09/12 06:15:10 client 	Long Header{Type: Retry, DestConnectionID: (empty), SrcConnectionID: 94efddd9ec764214, Token: 0x1af2f46720a3c0ba75c68c5be85c2338a5002895c020277d2d64d8a9d558a3e9b120adfa15a0fea20bbe787b0b692ae0084169329ffbc8a5b15df8ea0a1462, Version: v1}
2025/09/12 06:15:10 client Switching destination connection ID to: 94efddd9ec764214
2025/09/12 06:15:10 client -> Sending packet 2 (1280 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, Initial
2025/09/12 06:15:10 client 	Long Header{Type: Initial, DestConnectionID: 94efddd9ec764214, SrcConnectionID: (empty), Token: 0x1af2f46720a3c0ba75c68c5be85c2338a5002895c020277d2d64d8a9d558a3e9b120adfa15a0fea20bbe787b0b692ae0084169329ffbc8a5b15df8ea0a1462, PacketNumber: 2, PacketNumberLen: 2, Length: 1199, Version: v1}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 67, Data length: 1110, Offset + Data length: 1177}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 63, Offset + Data length: 63}
2025/09/12 06:15:10 client -> Sending packet 3 (1280 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, Initial
2025/09/12 06:15:10 client 	Long Header{Type: Initial, DestConnectionID: 94efddd9ec764214, SrcConnectionID: (empty), Token: 0x1af2f46720a3c0ba75c68c5be85c2338a5002895c020277d2d64d8a9d558a3e9b120adfa15a0fea20bbe787b0b692ae0084169329ffbc8a5b15df8ea0a1462, PacketNumber: 3, PacketNumberLen: 2, Length: 1199, Version: v1}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 63, Data length: 4, Offset + Data length: 67}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 1177, Data length: 52, Offset + Data length: 1229}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 1229, Data length: 283, Offset + Data length: 1512}
2025/09/12 06:15:10 client <- Reading packet 0 (40 bytes) for connection (empty), Initial
2025/09/12 06:15:10 client 	Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: 165e6b807a62c84e, Token: (empty), PacketNumber: 0, PacketNumberLen: 1, Length: 22, Version: v1}
2025/09/12 06:15:10 client Received first packet. Switching destination connection ID to: 165e6b807a62c84e
2025/09/12 06:15:10 client 	<- &wire.AckFrame{LargestAcked: 2, LowestAcked: 2, DelayTime: 112µs}
2025/09/12 06:15:10 client 	newly acked packets (1): [2]
2025/09/12 06:15:10 client 	updated RTT: 36.078ms (σ: 14.978ms)
2025/09/12 06:15:10 client Parsed a coalesced packet. Part 1: 134 bytes. Remaining: 1066 bytes.
2025/09/12 06:15:10 client <- Reading packet 1 (134 bytes) for connection (empty), Initial
2025/09/12 06:15:10 client 	Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: 165e6b807a62c84e, Token: (empty), PacketNumber: 1, PacketNumberLen: 1, Length: 116, Version: v1}
2025/09/12 06:15:10 client 	<- &wire.AckFrame{LargestAcked: 3, LowestAcked: 2, DelayTime: 272µs}
2025/09/12 06:15:10 client 	newly acked packets (1): [3]
2025/09/12 06:15:10 client 	updated RTT: 35.785ms (σ: 11.818ms)
2025/09/12 06:15:10 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90}
2025/09/12 06:15:10 client Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256)
2025/09/12 06:15:10 client Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256)
2025/09/12 06:15:10 client Parsed a coalesced packet. Part 2: 1066 bytes. Remaining: 0 bytes.
2025/09/12 06:15:10 client <- Reading packet 0 (1066 bytes) for connection (empty), Handshake
2025/09/12 06:15:10 client 	Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: 165e6b807a62c84e, PacketNumber: 0, PacketNumberLen: 1, Length: 1049, Version: v1}
2025/09/12 06:15:10 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 746, Offset + Data length: 746}
2025/09/12 06:15:10 client Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256)
2025/09/12 06:15:10 client Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256)
2025/09/12 06:15:10 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: 511add81d33431d1372451b9f37e85c4f9cc2f, InitialSourceConnectionID: 165e6b807a62c84e, RetrySourceConnectionID: 94efddd9ec764214, InitialMaxStreamDataBidiLocal: 1250000, InitialMaxStreamDataBidiRemote: 1250000, InitialMaxStreamDataUni: 1250000, InitialMaxData: 4611686018427387903, MaxBidiStreamNum: 100, MaxUniStreamNum: 100, MaxIdleTimeout: 30s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 5, StatelessResetToken: 0xf3d6a8c3e225dfad4ab1a93b76c8bf2c, MaxDatagramFrameSize: 65535, EnableResetStreamAt: false, MinAckDelay: 1ms}
2025/09/12 06:15:10 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f
2025/09/12 06:15:10 client 	Long Header{Type: Initial, DestConnectionID: 165e6b807a62c84e, SrcConnectionID: (empty), Token: 0x1af2f46720a3c0ba75c68c5be85c2338a5002895c020277d2d64d8a9d558a3e9b120adfa15a0fea20bbe787b0b692ae0084169329ffbc8a5b15df8ea0a1462, PacketNumber: 4, PacketNumberLen: 2, Length: 1125, Version: v1}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 1, LowestAcked: 0, DelayTime: 0s, ECT0: 2, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client 	Long Header{Type: Handshake, DestConnectionID: 165e6b807a62c84e, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 57, Version: v1}
2025/09/12 06:15:10 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36}
2025/09/12 06:15:10 client Dropping Initial keys.
2025/09/12 06:15:10 client <- Reading packet 0 (102 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: 35455d802c68a40c, StatelessResetToken: 0xcf9e76cde1bf01536b93785f7f08c5d1}
2025/09/12 06:15:10 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 0, ConnectionID: efd5c228a64cf1c2, StatelessResetToken: 0x467d16b53416dd0bc26ca6d5040dc907}
2025/09/12 06:15:10 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 0, ConnectionID: d3df5a31ea940c53, StatelessResetToken: 0x58fa4c1887e4a2fb739efc1e2d6c8520}
2025/09/12 06:15:10 client 	Queueing ACK because the first packet should be acknowledged.
2025/09/12 06:15:10 client -> Sending packet 1 (91 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, Handshake
2025/09/12 06:15:10 client 	Long Header{Type: Handshake, DestConnectionID: 35455d802c68a40c, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 26, Version: v1}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s, ECT0: 1, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.RetireConnectionIDFrame{SequenceNumber: 0}
2025/09/12 06:15:10 client 	-> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17}
2025/09/12 06:15:10 client -> Sending packet 1 (36 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 649.634µs, ECT0: 1, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client <- Reading packet 1 (22 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.HandshakeDoneFrame{}
2025/09/12 06:15:10 client Dropping Handshake keys.
2025/09/12 06:15:10 client 	Setting ACK timer to max ack delay: 25ms
2025/09/12 06:15:10 client <- Reading packet 2 (46 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 4, RetirePriorTo: 0, ConnectionID: 672db1f12901cc4f, StatelessResetToken: 0xe3d693822d6265ca56fe1a12e6eb8a82}
2025/09/12 06:15:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/09/12 06:15:10 client -> Sending packet 2 (35 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 2, LowestAcked: 0, DelayTime: 110.197µs, ECT0: 3, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client <- Reading packet 3 (1452 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 3, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1432, Offset + Data length: 1432}
2025/09/12 06:15:10 client 	Setting ACK timer to max ack delay: 25ms
2025/09/12 06:15:10 client <- Reading packet 4 (1452 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1432, Data length: 1430, Offset + Data length: 2862}
2025/09/12 06:15:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/09/12 06:15:10 client -> Sending packet 3 (35 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 4, LowestAcked: 0, DelayTime: 47.77µs, ECT0: 5, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client <- Reading packet 5 (1452 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2862, Data length: 1430, Offset + Data length: 4292}
2025/09/12 06:15:10 client 	Setting ACK timer to max ack delay: 25ms
2025/09/12 06:15:10 client <- Reading packet 6 (1452 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 4292, Data length: 1430, Offset + Data length: 5722}
2025/09/12 06:15:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/09/12 06:15:10 client -> Sending packet 4 (35 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 6, LowestAcked: 0, DelayTime: 41.298µs, ECT0: 7, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client <- Reading packet 7 (1452 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 5722, Data length: 1430, Offset + Data length: 7152}
2025/09/12 06:15:10 client 	Setting ACK timer to max ack delay: 25ms
2025/09/12 06:15:10 client <- Reading packet 8 (1064 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7152, Data length: 1040, Offset + Data length: 8192}
2025/09/12 06:15:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/09/12 06:15:10 client -> Sending packet 5 (35 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 8, LowestAcked: 0, DelayTime: 47.249µs, ECT0: 9, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client <- Reading packet 10 (1452 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 10, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8192, Data length: 1430, Offset + Data length: 9622}
2025/09/12 06:15:10 client 	Queuing ACK because there's a new missing packet to report.
2025/09/12 06:15:10 client -> Sending packet 6 (37 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 10, LowestAcked: 0, AckRanges: {{Largest: 10, Smallest: 10}, {Largest: 8, Smallest: 0}}, DelayTime: 42.62µs, ECT0: 10, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client <- Reading packet 11 (642 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 11, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 9622, Data length: 618, Offset + Data length: 10240}
2025/09/12 06:15:10 client 	Setting ACK timer to max ack delay: 25ms
2025/09/12 06:15:10 client <- Reading packet 12 (23 bytes) for connection (empty), 1-RTT
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 12, PacketNumberLen: 1, KeyPhase: 0}
2025/09/12 06:15:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 10240, Data length: 0, Offset + Data length: 10240}
2025/09/12 06:15:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/09/12 06:15:10 client -> Sending packet 7 (37 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.AckFrame{LargestAcked: 12, LowestAcked: 0, AckRanges: {{Largest: 12, Smallest: 10}, {Largest: 8, Smallest: 0}}, DelayTime: 59.332µs, ECT0: 12, ECT1: 0, CE: 0}
2025/09/12 06:15:10 client Closing connection with error: Application error 0x0 (local)
2025/09/12 06:15:10 client -> Sending packet 8 (30 bytes) for connection 511add81d33431d1372451b9f37e85c4f9cc2f, 1-RTT (ECN: ECT(0))
2025/09/12 06:15:10 client 	Short Header{DestConnectionID: 35455d802c68a40c, PacketNumber: 8, PacketNumberLen: 2, KeyPhase: 0}
2025/09/12 06:15:10 client 	-> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""}
2025/09/12 06:15:10 Replacing connection for connection IDs [(empty)] with a closed connection.
2025/09/12 06:15:10 client Connection 511add81d33431d1372451b9f37e85c4f9cc2f closed.