2025/04/30 10:32:33 Requesting https://server4:443/tfwoxmszcr.
2025/04/30 10:32:33 Increased receive buffer size to 14336 kiB
2025/04/30 10:32:33 Increased send buffer size to 14336 kiB
2025/04/30 10:32:33 Setting DF for IPv4 and IPv6.
2025/04/30 10:32:33 Activating reading of ECN bits for IPv4 and IPv6.
2025/04/30 10:32:33 Activating reading of packet info for IPv4 and IPv6.
2025/04/30 10:32:33 Created qlog file: /logs/qlog/e195411d809fbc4bbaf676ace6b45fc47fb8.sqlog
2025/04/30 10:32:33 client Starting new connection to server4 ([::]:34689 -> 193.167.100.100:443), source connection ID (empty), destination connection ID e195411d809fbc4bbaf676ace6b45fc47fb8, version v1
2025/04/30 10:32:33 Adding connection ID (empty).
2025/04/30 10:32:33 client Not doing 0-RTT. Has sealer: false, has params: false
2025/04/30 10:32:33 client -> Sending packet 0 (1280 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, Initial
2025/04/30 10:32:33 client 	Long Header{Type: Initial, DestConnectionID: e195411d809fbc4bbaf676ace6b45fc47fb8, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1252, Version: v1}
2025/04/30 10:32:33 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 270, Offset + Data length: 270}
2025/04/30 10:32:33 client 	updated RTT: 36.803834ms (σ: 18.401917ms)
2025/04/30 10:32:33 client <- Received Retry:
2025/04/30 10:32:33 client 	Long Header{Type: Retry, DestConnectionID: (empty), SrcConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, Token: 0x71756963686500000000000000000000ffffc1a70064e195411d809fbc4bbaf676ace6b45fc47fb8, Version: v1}
2025/04/30 10:32:33 client Switching destination connection ID to: a426fae5bfe81d1a508f7b40aa2313a657e8a74e
2025/04/30 10:32:33 client -> Sending packet 1 (1280 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, Initial
2025/04/30 10:32:33 client 	Long Header{Type: Initial, DestConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, SrcConnectionID: (empty), Token: 0x71756963686500000000000000000000ffffc1a70064e195411d809fbc4bbaf676ace6b45fc47fb8, PacketNumber: 1, PacketNumberLen: 2, Length: 1210, Version: v1}
2025/04/30 10:32:33 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 270, Offset + Data length: 270}
2025/04/30 10:32:33 client Parsed a coalesced packet. Part 1: 146 bytes. Remaining: 1054 bytes.
2025/04/30 10:32:33 client <- Reading packet 0 (146 bytes) for connection (empty), Initial
2025/04/30 10:32:33 client 	Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, Token: (empty), PacketNumber: 0, PacketNumberLen: 1, Length: 116, Version: v1}
2025/04/30 10:32:33 client 	<- &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 352µs}
2025/04/30 10:32:33 client 	newly acked packets (1): [1]
2025/04/30 10:32:33 client 	updated RTT: 36.347ms (σ: 14.711ms)
2025/04/30 10:32:33 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90}
2025/04/30 10:32:33 client Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 10:32:33 client Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 10:32:33 client Parsed a coalesced packet. Part 2: 777 bytes. Remaining: 277 bytes.
2025/04/30 10:32:33 client <- Reading packet 1 (777 bytes) for connection (empty), Handshake
2025/04/30 10:32:33 client 	Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, PacketNumber: 1, PacketNumberLen: 1, Length: 748, Version: v1}
2025/04/30 10:32:33 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 727, Offset + Data length: 727}
2025/04/30 10:32:33 client Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 10:32:33 client Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 10:32:33 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: e195411d809fbc4bbaf676ace6b45fc47fb8, InitialSourceConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, RetrySourceConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, InitialMaxStreamDataBidiLocal: 1000000, InitialMaxStreamDataBidiRemote: 1000000, InitialMaxStreamDataUni: 1000000, InitialMaxData: 10000000, MaxBidiStreamNum: 100, MaxUniStreamNum: 100, MaxIdleTimeout: 30s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 8}
2025/04/30 10:32:33 client Dropping %!s(logging.PacketType=5) packet (277 bytes) for which we couldn't unpack the header. Error: not a QUIC packet
2025/04/30 10:32:33 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8
2025/04/30 10:32:33 client 	Long Header{Type: Initial, DestConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, SrcConnectionID: (empty), Token: 0x71756963686500000000000000000000ffffc1a70064e195411d809fbc4bbaf676ace6b45fc47fb8, PacketNumber: 2, PacketNumberLen: 2, Length: 1124, Version: v1}
2025/04/30 10:32:33 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s}
2025/04/30 10:32:33 client 	Long Header{Type: Handshake, DestConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 57, Version: v1}
2025/04/30 10:32:33 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36}
2025/04/30 10:32:33 client Dropping Initial keys.
2025/04/30 10:32:33 client -> Sending packet 1 (52 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, Handshake
2025/04/30 10:32:33 client 	Long Header{Type: Handshake, DestConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 23, Version: v1}
2025/04/30 10:32:33 client 	-> &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 0s}
2025/04/30 10:32:33 client -> Sending packet 0 (58 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, 1-RTT (ECN: ECT(0))
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: a426fae5bfe81d1a508f7b40aa2313a657e8a74e, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 10:32:33 client 	-> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17}
2025/04/30 10:32:33 client <- Reading packet 2 (1350 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 56µs}
2025/04/30 10:32:33 client Peer doesn't await address validation any longer.
2025/04/30 10:32:33 client 	newly acked packets (1): [0]
2025/04/30 10:32:33 client 	updated RTT: 35.928ms (σ: 11.87ms)
2025/04/30 10:32:33 client Disabling ECN. ECN-marked packet acknowledged, but no ECN counts on ACK frame.
2025/04/30 10:32:33 client Canceling loss detection timer.
2025/04/30 10:32:33 client Dropping Handshake keys.
2025/04/30 10:32:33 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: ddc25d874039b669edc1d7578551f8f27263d949, StatelessResetToken: 0xd75b6f99755abbd08379e9cf22e66f53}
2025/04/30 10:32:33 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 0, ConnectionID: fbab9cdbe4ca652cabcef67f11924513a561f621, StatelessResetToken: 0x25100e021ee3eb497c5d7968a502ce5e}
2025/04/30 10:32:33 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 0, ConnectionID: 4b65d95a0341bc7ea1a3fa14e2c3b849e1dbd5f3, StatelessResetToken: 0x2f43378f332fd3cac26f7fb832166bd2}
2025/04/30 10:32:33 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1201, Offset + Data length: 1201}
2025/04/30 10:32:33 client 	Queueing ACK because the first packet should be acknowledged.
2025/04/30 10:32:33 client -> Sending packet 1 (46 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, 1-RTT (ECN: Not-ECT)
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: ddc25d874039b669edc1d7578551f8f27263d949, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 10:32:33 client 	-> &wire.AckFrame{LargestAcked: 2, LowestAcked: 2, DelayTime: 190.044µs}
2025/04/30 10:32:33 client 	-> &wire.RetireConnectionIDFrame{SequenceNumber: 0}
2025/04/30 10:32:33 client <- Reading packet 3 (1350 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 3, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1201, Data length: 1326, Offset + Data length: 2527}
2025/04/30 10:32:33 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 10:32:33 client <- Reading packet 4 (1350 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2527, Data length: 1326, Offset + Data length: 3853}
2025/04/30 10:32:33 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 10:32:33 client -> Sending packet 2 (44 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, 1-RTT (ECN: Not-ECT)
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: ddc25d874039b669edc1d7578551f8f27263d949, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 10:32:33 client 	-> &wire.AckFrame{LargestAcked: 4, LowestAcked: 2, DelayTime: 43.281µs}
2025/04/30 10:32:33 client <- Reading packet 5 (1350 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3853, Data length: 1326, Offset + Data length: 5179}
2025/04/30 10:32:33 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 10:32:33 client <- Reading packet 6 (1350 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 5179, Data length: 1326, Offset + Data length: 6505}
2025/04/30 10:32:33 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 10:32:33 client -> Sending packet 3 (44 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, 1-RTT (ECN: Not-ECT)
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: ddc25d874039b669edc1d7578551f8f27263d949, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 10:32:33 client 	-> &wire.AckFrame{LargestAcked: 6, LowestAcked: 2, DelayTime: 46.937µs}
2025/04/30 10:32:33 client <- Reading packet 7 (1350 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6505, Data length: 1326, Offset + Data length: 7831}
2025/04/30 10:32:33 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 10:32:33 client <- Reading packet 8 (1350 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7831, Data length: 1326, Offset + Data length: 9157}
2025/04/30 10:32:33 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 10:32:33 client -> Sending packet 4 (44 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, 1-RTT (ECN: Not-ECT)
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: ddc25d874039b669edc1d7578551f8f27263d949, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 10:32:33 client 	-> &wire.AckFrame{LargestAcked: 8, LowestAcked: 2, DelayTime: 44.472µs}
2025/04/30 10:32:33 client <- Reading packet 9 (1107 bytes) for connection (empty), 1-RTT
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: (empty), PacketNumber: 9, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 10:32:33 client 	<- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 9157, Data length: 1083, Offset + Data length: 10240}
2025/04/30 10:32:33 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 10:32:33 client Closing connection with error: Application error 0x0 (local)
2025/04/30 10:32:33 client -> Sending packet 5 (42 bytes) for connection e195411d809fbc4bbaf676ace6b45fc47fb8, 1-RTT (ECN: Not-ECT)
2025/04/30 10:32:33 client 	Short Header{DestConnectionID: ddc25d874039b669edc1d7578551f8f27263d949, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 10:32:33 client 	-> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""}
2025/04/30 10:32:33 Replacing connection for connection IDs [(empty)] with a closed connection.
2025/04/30 10:32:33 client Connection e195411d809fbc4bbaf676ace6b45fc47fb8 closed.