2025/04/30 13:32:16 Requesting https://server6:443/bbzzwmalxs. 2025/04/30 13:32:16 Requesting https://server6:443/vnawwhprso. 2025/04/30 13:32:16 Increased receive buffer size to 14336 kiB 2025/04/30 13:32:16 Increased send buffer size to 14336 kiB 2025/04/30 13:32:16 Setting DF for IPv4 and IPv6. 2025/04/30 13:32:16 Activating reading of ECN bits for IPv4 and IPv6. 2025/04/30 13:32:16 Activating reading of packet info for IPv4 and IPv6. 2025/04/30 13:32:16 Created qlog file: /logs/qlog/464a179d95348e9c77b324260b.sqlog 2025/04/30 13:32:16 client Starting new connection to server6 ([::]:54587 -> [fd00:cafe:cafe:100::100]:443), source connection ID (empty), destination connection ID 464a179d95348e9c77b324260b, version v1 2025/04/30 13:32:16 Adding connection ID (empty). 2025/04/30 13:32:16 client Not doing 0-RTT. Has sealer: false, has params: false 2025/04/30 13:32:16 client -> Sending packet 0 (1280 bytes) for connection 464a179d95348e9c77b324260b, Initial 2025/04/30 13:32:16 client Long Header{Type: Initial, DestConnectionID: 464a179d95348e9c77b324260b, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1257, Version: v1} 2025/04/30 13:32:16 client -> &wire.CryptoFrame{Offset: 0, Data length: 266, Offset + Data length: 266} 2025/04/30 13:32:16 client Parsed a coalesced packet. Part 1: 143 bytes. Remaining: 1089 bytes. 2025/04/30 13:32:16 client <- Reading packet 0 (143 bytes) for connection (empty), Initial 2025/04/30 13:32:16 client Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: e04e0e20d1f60684a36ee83b80e69478, Token: (empty), PacketNumber: 0, PacketNumberLen: 1, Length: 117, Version: v1} 2025/04/30 13:32:16 client Received first packet. Switching destination connection ID to: e04e0e20d1f60684a36ee83b80e69478 2025/04/30 13:32:16 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 13.168ms} 2025/04/30 13:32:16 client newly acked packets (1): [0] 2025/04/30 13:32:16 client updated RTT: 42.044546ms (σ: 21.022273ms) 2025/04/30 13:32:16 client <- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90} 2025/04/30 13:32:16 client Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 13:32:16 client Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 13:32:16 client Parsed a coalesced packet. Part 2: 1089 bytes. Remaining: 0 bytes. 2025/04/30 13:32:16 client <- Reading packet 0 (1089 bytes) for connection (empty), Handshake 2025/04/30 13:32:16 client Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: e04e0e20d1f60684a36ee83b80e69478, PacketNumber: 0, PacketNumberLen: 1, Length: 1064, Version: v1} 2025/04/30 13:32:16 client <- &wire.CryptoFrame{Offset: 0, Data length: 691, Offset + Data length: 691} 2025/04/30 13:32:16 client Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 13:32:16 client Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 13:32:16 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: 464a179d95348e9c77b324260b, InitialSourceConnectionID: e04e0e20d1f60684a36ee83b80e69478, InitialMaxStreamDataBidiLocal: 3750000, InitialMaxStreamDataBidiRemote: 3750000, InitialMaxStreamDataUni: 3750000, InitialMaxData: 3750000, MaxBidiStreamNum: 100, MaxUniStreamNum: 100, MaxIdleTimeout: 5m0s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 3} 2025/04/30 13:32:16 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection 464a179d95348e9c77b324260b 2025/04/30 13:32:16 client Long Header{Type: Initial, DestConnectionID: e04e0e20d1f60684a36ee83b80e69478, SrcConnectionID: (empty), Token: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 1172, Version: v1} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s, ECT0: 1, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client Long Header{Type: Handshake, DestConnectionID: e04e0e20d1f60684a36ee83b80e69478, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 57, Version: v1} 2025/04/30 13:32:16 client -> &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36} 2025/04/30 13:32:16 client Dropping Initial keys. 2025/04/30 13:32:16 client -> Sending packet 1 (51 bytes) for connection 464a179d95348e9c77b324260b, Handshake 2025/04/30 13:32:16 client Long Header{Type: Handshake, DestConnectionID: e04e0e20d1f60684a36ee83b80e69478, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 26, Version: v1} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s, ECT0: 1, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client -> Sending packet 0 (74 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: e04e0e20d1f60684a36ee83b80e69478, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17} 2025/04/30 13:32:16 client -> &wire.StreamFrame{StreamID: 4, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17} 2025/04/30 13:32:16 client <- Reading packet 0 (1452 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.PingFrame{} 2025/04/30 13:32:16 client Queueing ACK because the first packet should be acknowledged. 2025/04/30 13:32:16 client -> Sending packet 1 (43 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: e04e0e20d1f60684a36ee83b80e69478, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 111.329µs, ECT0: 1, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 1 (127 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client Dropping Handshake keys. 2025/04/30 13:32:16 client <- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 1, ConnectionID: 987083083db07aac859efadcdc1da37a, StatelessResetToken: 0xc53650142642a487f823d06f87b014d6} 2025/04/30 13:32:16 client <- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 1, ConnectionID: feddb01665bb92d06059f8eef5e119dd, StatelessResetToken: 0xc6680d4b8ccd1d79473432bda034b666} 2025/04/30 13:32:16 client <- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 1, ConnectionID: cf141937b533453e17831cbd0da51e06, StatelessResetToken: 0x4aa2d706473ffc2d4930d3bee6f81ef7} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client -> Sending packet 2 (45 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 1, LowestAcked: 0, DelayTime: 128.411µs, ECT0: 2, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client -> &wire.RetireConnectionIDFrame{SequenceNumber: 0} 2025/04/30 13:32:16 client <- Reading packet 2 (1232 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 1.216ms, ECT0: 1, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client newly acked packets (1): [0] 2025/04/30 13:32:16 client updated RTT: 41.358ms (σ: 17.138ms) 2025/04/30 13:32:16 client ECN capability confirmed. 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 4, Fin: false, Offset: 0, Data length: 1202, Offset + Data length: 1202} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client <- Reading packet 3 (1232 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 3, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 4, Fin: false, Offset: 1202, Data length: 1209, Offset + Data length: 2411} 2025/04/30 13:32:16 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 13:32:16 client -> Sending packet 3 (43 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 3, LowestAcked: 0, DelayTime: 54.562µs, ECT0: 4, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 4 (1232 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 4, Fin: false, Offset: 2411, Data length: 1209, Offset + Data length: 3620} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client <- Reading packet 5 (501 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 4, Fin: false, Offset: 3620, Data length: 476, Offset + Data length: 4096} 2025/04/30 13:32:16 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 13:32:16 client -> Sending packet 4 (43 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 5, LowestAcked: 0, DelayTime: 47.87µs, ECT0: 6, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 6 (1232 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1211, Offset + Data length: 1211} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client <- Reading packet 7 (1232 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1211, Data length: 1209, Offset + Data length: 2420} 2025/04/30 13:32:16 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 13:32:16 client -> Sending packet 5 (43 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 7, LowestAcked: 0, DelayTime: 52.007µs, ECT0: 8, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 8 (1232 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2420, Data length: 1209, Offset + Data length: 3629} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client <- Reading packet 9 (492 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 9, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3629, Data length: 467, Offset + Data length: 4096} 2025/04/30 13:32:16 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 13:32:16 client -> Sending packet 6 (43 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 9, LowestAcked: 0, DelayTime: 49.613µs, ECT0: 8, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 10 (1049 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 10, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 4, Fin: false, Offset: 4096, Data length: 1024, Offset + Data length: 5120} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client <- Reading packet 11 (1232 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 11, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.HandshakeDoneFrame{} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 4096, Data length: 1209, Offset + Data length: 5305} 2025/04/30 13:32:16 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 13:32:16 client -> Sending packet 7 (43 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 11, LowestAcked: 0, DelayTime: 37.611µs, ECT0: 8, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 13 (1452 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 13, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.AckFrame{LargestAcked: 3, LowestAcked: 1, DelayTime: 224µs, ECT0: 4, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client newly acked packets (1): [2] 2025/04/30 13:32:16 client Canceling loss detection timer. 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 4, Fin: true, Offset: 5120, Data length: 0, Offset + Data length: 5120} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 5305, Data length: 1417, Offset + Data length: 6722} 2025/04/30 13:32:16 client Queuing ACK because there's a new missing packet to report. 2025/04/30 13:32:16 client Ignoring all packets below 2. 2025/04/30 13:32:16 client -> Sending packet 8 (45 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 8, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 13, LowestAcked: 2, AckRanges: {{Largest: 13, Smallest: 13}, {Largest: 11, Smallest: 2}}, DelayTime: 98.945µs, ECT0: 9, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 14 (1452 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 14, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6722, Data length: 1430, Offset + Data length: 8152} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client <- Reading packet 15 (1452 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 15, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8152, Data length: 1430, Offset + Data length: 9582} 2025/04/30 13:32:16 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 13:32:16 client -> Sending packet 9 (45 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 9, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.AckFrame{LargestAcked: 15, LowestAcked: 2, AckRanges: {{Largest: 15, Smallest: 13}, {Largest: 11, Smallest: 2}}, DelayTime: 47.499µs, ECT0: 11, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- Reading packet 16 (690 bytes) for connection (empty), 1-RTT 2025/04/30 13:32:16 client Short Header{DestConnectionID: (empty), PacketNumber: 16, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 13:32:16 client <- &wire.AckFrame{LargestAcked: 5, LowestAcked: 1, DelayTime: 376µs, ECT0: 6, ECT1: 0, CE: 0} 2025/04/30 13:32:16 client <- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 9582, Data length: 658, Offset + Data length: 10240} 2025/04/30 13:32:16 client Setting ACK timer to max ack delay: 25ms 2025/04/30 13:32:16 client Closing connection with error: Application error 0x0 (local) 2025/04/30 13:32:16 client -> Sending packet 10 (38 bytes) for connection 464a179d95348e9c77b324260b, 1-RTT (ECN: ECT(0)) 2025/04/30 13:32:16 client Short Header{DestConnectionID: 987083083db07aac859efadcdc1da37a, PacketNumber: 10, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 13:32:16 client -> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""} 2025/04/30 13:32:16 Replacing connection for connection IDs [(empty)] with a closed connection. 2025/04/30 13:32:16 client Connection 464a179d95348e9c77b324260b closed.