2025/04/30 10:41:15 Requesting https://server4:443/tdytfjxzbe. 2025/04/30 10:41:15 Increased receive buffer size to 14336 kiB 2025/04/30 10:41:15 Increased send buffer size to 14336 kiB 2025/04/30 10:41:15 Setting DF for IPv4 and IPv6. 2025/04/30 10:41:15 Activating reading of ECN bits for IPv4 and IPv6. 2025/04/30 10:41:15 Activating reading of packet info for IPv4 and IPv6. 2025/04/30 10:41:15 Created qlog file: /logs/qlog/5917e10a9f1b0c412bbe14e550b1ca1ab3aa56.sqlog 2025/04/30 10:41:15 client Starting new connection to server4 ([::]:51504 -> 193.167.100.100:443), source connection ID (empty), destination connection ID 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, version v1 2025/04/30 10:41:15 Adding connection ID (empty). 2025/04/30 10:41:15 client Not doing 0-RTT. Has sealer: false, has params: false 2025/04/30 10:41:15 client -> Sending packet 0 (1280 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, Initial 2025/04/30 10:41:15 client Long Header{Type: Initial, DestConnectionID: 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1251, Version: v1} 2025/04/30 10:41:15 client -> &wire.CryptoFrame{Offset: 0, Data length: 267, Offset + Data length: 267} 2025/04/30 10:41:15 client updated RTT: 164.140442ms (σ: 82.070221ms) 2025/04/30 10:41:15 client <- Received Retry: 2025/04/30 10:41:15 client Long Header{Type: Retry, DestConnectionID: (empty), SrcConnectionID: ac996b353c378a55, Token: 0xd6f808fd7aa558d88e0ffca5fc03a95ffa3f4628dd33fee8d2da09078a74206f3ce6c4b4e1, Version: v1} 2025/04/30 10:41:15 client Switching destination connection ID to: ac996b353c378a55 2025/04/30 10:41:15 client -> Sending packet 1 (1280 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, Initial 2025/04/30 10:41:15 client Long Header{Type: Initial, DestConnectionID: ac996b353c378a55, SrcConnectionID: (empty), Token: 0xd6f808fd7aa558d88e0ffca5fc03a95ffa3f4628dd33fee8d2da09078a74206f3ce6c4b4e1, PacketNumber: 1, PacketNumberLen: 2, Length: 1225, Version: v1} 2025/04/30 10:41:15 client -> &wire.CryptoFrame{Offset: 0, Data length: 267, Offset + Data length: 267} 2025/04/30 10:41:15 client Parsed a coalesced packet. Part 1: 444 bytes. Remaining: 756 bytes. 2025/04/30 10:41:15 client <- Reading packet 0 (444 bytes) for connection (empty), Initial 2025/04/30 10:41:15 client Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: ac996b353c378a55, Token: (empty), PacketNumber: 0, PacketNumberLen: 1, Length: 426, Version: v1} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 0s} 2025/04/30 10:41:15 client newly acked packets (1): [1] 2025/04/30 10:41:15 client updated RTT: 165.016ms (σ: 63.305ms) 2025/04/30 10:41:15 client <- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90} 2025/04/30 10:41:15 client Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 10:41:15 client Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 10:41:15 client Parsed a coalesced packet. Part 2: 756 bytes. Remaining: 0 bytes. 2025/04/30 10:41:15 client <- Reading packet 0 (756 bytes) for connection (empty), Handshake 2025/04/30 10:41:15 client Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: ac996b353c378a55, PacketNumber: 0, PacketNumberLen: 1, Length: 739, Version: v1} 2025/04/30 10:41:15 client <- &wire.CryptoFrame{Offset: 0, Data length: 718, Offset + Data length: 718} 2025/04/30 10:41:15 client Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 10:41:15 client Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256) 2025/04/30 10:41:15 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, InitialSourceConnectionID: ac996b353c378a55, RetrySourceConnectionID: ac996b353c378a55, InitialMaxStreamDataBidiLocal: 1000000, InitialMaxStreamDataBidiRemote: 1000000, InitialMaxStreamDataUni: 1000000, InitialMaxData: 10000000, MaxBidiStreamNum: 100, MaxUniStreamNum: 0, MaxIdleTimeout: 30s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 3} 2025/04/30 10:41:15 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56 2025/04/30 10:41:15 client Long Header{Type: Initial, DestConnectionID: ac996b353c378a55, SrcConnectionID: (empty), Token: 0xd6f808fd7aa558d88e0ffca5fc03a95ffa3f4628dd33fee8d2da09078a74206f3ce6c4b4e1, PacketNumber: 2, PacketNumberLen: 2, Length: 1151, Version: v1} 2025/04/30 10:41:15 client -> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Long Header{Type: Handshake, DestConnectionID: ac996b353c378a55, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 57, Version: v1} 2025/04/30 10:41:15 client -> &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36} 2025/04/30 10:41:15 client Dropping Initial keys. 2025/04/30 10:41:15 client -> Sending packet 1 (40 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, Handshake 2025/04/30 10:41:15 client Long Header{Type: Handshake, DestConnectionID: ac996b353c378a55, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 23, Version: v1} 2025/04/30 10:41:15 client -> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client -> Sending packet 0 (46 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, 1-RTT (ECN: ECT(0)) 2025/04/30 10:41:15 client Short Header{DestConnectionID: ac996b353c378a55, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 10:41:15 client -> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17} 2025/04/30 10:41:15 client <- Reading packet 0 (103 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.HandshakeDoneFrame{} 2025/04/30 10:41:15 client Dropping Handshake keys. 2025/04/30 10:41:15 client <- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: e616fcc1907ea248, StatelessResetToken: 0xabb49dd746262a0e2317a8eaef493786} 2025/04/30 10:41:15 client <- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 0, ConnectionID: b59f3b93073f2aec, StatelessResetToken: 0xe76482bc844ae76896c5ef6e5eff1a41} 2025/04/30 10:41:15 client <- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 0, ConnectionID: 6a02434db30ad280, StatelessResetToken: 0x28a030ff216f02510b4c5fd31457c421} 2025/04/30 10:41:15 client Queueing ACK because the first packet should be acknowledged. 2025/04/30 10:41:15 client -> Sending packet 1 (34 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, 1-RTT (ECN: ECT(0)) 2025/04/30 10:41:15 client Short Header{DestConnectionID: e616fcc1907ea248, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 10:41:15 client -> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 168.264µs} 2025/04/30 10:41:15 client -> &wire.RetireConnectionIDFrame{SequenceNumber: 0} 2025/04/30 10:41:15 client <- Reading packet 1 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1223, Offset + Data length: 1223} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 2ms} 2025/04/30 10:41:15 client newly acked packets (1): [0] 2025/04/30 10:41:15 client updated RTT: 153.695ms (σ: 70.12ms) 2025/04/30 10:41:15 client Disabling ECN. ECN-marked packet acknowledged, but no ECN counts on ACK frame. 2025/04/30 10:41:15 client Setting ACK timer to max ack delay: 25ms 2025/04/30 10:41:15 client <- Reading packet 2 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1223, Data length: 1223, Offset + Data length: 2446} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 10:41:15 client -> Sending packet 2 (32 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, 1-RTT (ECN: Not-ECT) 2025/04/30 10:41:15 client Short Header{DestConnectionID: e616fcc1907ea248, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 10:41:15 client -> &wire.AckFrame{LargestAcked: 2, LowestAcked: 0, DelayTime: 80.601µs} 2025/04/30 10:41:15 client <- Reading packet 3 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 3, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2446, Data length: 1223, Offset + Data length: 3669} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Setting ACK timer to max ack delay: 25ms 2025/04/30 10:41:15 client <- Reading packet 4 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3669, Data length: 1223, Offset + Data length: 4892} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 10:41:15 client -> Sending packet 3 (32 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, 1-RTT (ECN: Not-ECT) 2025/04/30 10:41:15 client Short Header{DestConnectionID: e616fcc1907ea248, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 10:41:15 client -> &wire.AckFrame{LargestAcked: 4, LowestAcked: 0, DelayTime: 46.006µs} 2025/04/30 10:41:15 client <- Reading packet 5 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 4892, Data length: 1223, Offset + Data length: 6115} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Setting ACK timer to max ack delay: 25ms 2025/04/30 10:41:15 client <- Reading packet 6 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6115, Data length: 1223, Offset + Data length: 7338} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 10:41:15 client -> Sending packet 4 (32 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, 1-RTT (ECN: Not-ECT) 2025/04/30 10:41:15 client Short Header{DestConnectionID: e616fcc1907ea248, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 10:41:15 client -> &wire.AckFrame{LargestAcked: 6, LowestAcked: 0, DelayTime: 44.002µs} 2025/04/30 10:41:15 client <- Reading packet 7 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7338, Data length: 1223, Offset + Data length: 8561} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Setting ACK timer to max ack delay: 25ms 2025/04/30 10:41:15 client <- Reading packet 8 (1252 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8561, Data length: 1223, Offset + Data length: 9784} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2). 2025/04/30 10:41:15 client <- Reading packet 9 (488 bytes) for connection (empty), 1-RTT 2025/04/30 10:41:15 client Short Header{DestConnectionID: (empty), PacketNumber: 9, PacketNumberLen: 1, KeyPhase: 0} 2025/04/30 10:41:15 client <- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 9784, Data length: 456, Offset + Data length: 10240} 2025/04/30 10:41:15 client <- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s} 2025/04/30 10:41:15 client <- &wire.MaxStreamsFrame{Type: bidi, MaxStreamNum: 101} 2025/04/30 10:41:15 client -> Sending packet 5 (32 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, 1-RTT (ECN: Not-ECT) 2025/04/30 10:41:15 client Short Header{DestConnectionID: e616fcc1907ea248, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 10:41:15 client -> &wire.AckFrame{LargestAcked: 9, LowestAcked: 0, DelayTime: 151.873µs} 2025/04/30 10:41:15 client Closing connection with error: Application error 0x0 (local) 2025/04/30 10:41:15 client -> Sending packet 6 (30 bytes) for connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56, 1-RTT (ECN: Not-ECT) 2025/04/30 10:41:15 client Short Header{DestConnectionID: e616fcc1907ea248, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0} 2025/04/30 10:41:15 client -> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""} 2025/04/30 10:41:15 Replacing connection for connection IDs [(empty)] with a closed connection. 2025/04/30 10:41:15 client Connection 5917e10a9f1b0c412bbe14e550b1ca1ab3aa56 closed.