2025/04/30 08:43:10 Requesting https://server4:443/bvszqcyeoz.
2025/04/30 08:43:10 Increased receive buffer size to 14336 kiB
2025/04/30 08:43:10 Increased send buffer size to 14336 kiB
2025/04/30 08:43:10 Setting DF for IPv4 and IPv6.
2025/04/30 08:43:10 Activating reading of ECN bits for IPv4 and IPv6.
2025/04/30 08:43:10 Activating reading of packet info for IPv4 and IPv6.
2025/04/30 08:43:10 Created qlog file: /logs/qlog/0f7d435fe5fee6f48ea5b93063.sqlog
2025/04/30 08:43:10 client Starting new connection to server4 ([::]:60052 -> 193.167.100.100:443), source connection ID (empty), destination connection ID 0f7d435fe5fee6f48ea5b93063, version v1
2025/04/30 08:43:10 Adding connection ID (empty).
2025/04/30 08:43:10 client Not doing 0-RTT. Has sealer: false, has params: false
2025/04/30 08:43:10 client -> Sending packet 0 (1280 bytes) for connection 0f7d435fe5fee6f48ea5b93063, Initial
2025/04/30 08:43:10 client 	Long Header{Type: Initial, DestConnectionID: 0f7d435fe5fee6f48ea5b93063, SrcConnectionID: (empty), Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 1257, Version: v1}
2025/04/30 08:43:10 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 265, Offset + Data length: 265}
2025/04/30 08:43:10 client 	updated RTT: 36.79518ms (σ: 18.39759ms)
2025/04/30 08:43:10 client <- Received Retry:
2025/04/30 08:43:10 client 	Long Header{Type: Retry, DestConnectionID: (empty), SrcConnectionID: f93cb39c1040eae5, Token: 0x008961c36d2db58687e874d18389528e4c5541a4f1973e5e0c234f10f3e72488bfbbcb6c9d2fb1294410975ab3f1e2d0c60e6bfc56233a4e47, Version: v1}
2025/04/30 08:43:10 client Switching destination connection ID to: f93cb39c1040eae5
2025/04/30 08:43:10 client -> Sending packet 1 (1280 bytes) for connection 0f7d435fe5fee6f48ea5b93063, Initial
2025/04/30 08:43:10 client 	Long Header{Type: Initial, DestConnectionID: f93cb39c1040eae5, SrcConnectionID: (empty), Token: 0x008961c36d2db58687e874d18389528e4c5541a4f1973e5e0c234f10f3e72488bfbbcb6c9d2fb1294410975ab3f1e2d0c60e6bfc56233a4e47, PacketNumber: 1, PacketNumberLen: 2, Length: 1205, Version: v1}
2025/04/30 08:43:10 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 265, Offset + Data length: 265}
2025/04/30 08:43:10 client Parsed a coalesced packet. Part 1: 135 bytes. Remaining: 1117 bytes.
2025/04/30 08:43:10 client <- Reading packet 0 (135 bytes) for connection (empty), Initial
2025/04/30 08:43:10 client 	Long Header{Type: Initial, DestConnectionID: (empty), SrcConnectionID: 28265f517fcf150a, Token: (empty), PacketNumber: 0, PacketNumberLen: 1, Length: 117, Version: v1}
2025/04/30 08:43:10 client Received first packet. Switching destination connection ID to: 28265f517fcf150a
2025/04/30 08:43:10 client 	<- &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 1.216ms}
2025/04/30 08:43:10 client 	newly acked packets (1): [1]
2025/04/30 08:43:10 client 	updated RTT: 36.436ms (σ: 14.515ms)
2025/04/30 08:43:10 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 90, Offset + Data length: 90}
2025/04/30 08:43:10 client Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:43:10 client Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:43:10 client Parsed a coalesced packet. Part 2: 1117 bytes. Remaining: 0 bytes.
2025/04/30 08:43:10 client <- Reading packet 1 (1117 bytes) for connection (empty), Handshake
2025/04/30 08:43:10 client 	Long Header{Type: Handshake, DestConnectionID: (empty), SrcConnectionID: 28265f517fcf150a, PacketNumber: 1, PacketNumberLen: 1, Length: 1100, Version: v1}
2025/04/30 08:43:10 client 	<- &wire.CryptoFrame{Offset: 0, Data length: 724, Offset + Data length: 724}
2025/04/30 08:43:10 client Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:43:10 client Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:43:10 client Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: 0f7d435fe5fee6f48ea5b93063, InitialSourceConnectionID: 28265f517fcf150a, RetrySourceConnectionID: f93cb39c1040eae5, InitialMaxStreamDataBidiLocal: 0, InitialMaxStreamDataBidiRemote: 1048576, InitialMaxStreamDataUni: 12288, InitialMaxData: 1572864, MaxBidiStreamNum: 100, MaxUniStreamNum: 3, MaxIdleTimeout: 30s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 8, StatelessResetToken: 0x7765a88b6b78a13aa2bb2321aa984a43}
2025/04/30 08:43:10 client -> Sending coalesced packet (2 parts, 1280 bytes) for connection 0f7d435fe5fee6f48ea5b93063
2025/04/30 08:43:10 client 	Long Header{Type: Initial, DestConnectionID: 28265f517fcf150a, SrcConnectionID: (empty), Token: 0x008961c36d2db58687e874d18389528e4c5541a4f1973e5e0c234f10f3e72488bfbbcb6c9d2fb1294410975ab3f1e2d0c60e6bfc56233a4e47, PacketNumber: 2, PacketNumberLen: 2, Length: 1131, Version: v1}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 0s, ECT0: 0, ECT1: 1, CE: 0}
2025/04/30 08:43:10 client 	Long Header{Type: Handshake, DestConnectionID: 28265f517fcf150a, SrcConnectionID: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 57, Version: v1}
2025/04/30 08:43:10 client 	-> &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36}
2025/04/30 08:43:10 client Dropping Initial keys.
2025/04/30 08:43:10 client -> Sending packet 1 (43 bytes) for connection 0f7d435fe5fee6f48ea5b93063, Handshake
2025/04/30 08:43:10 client 	Long Header{Type: Handshake, DestConnectionID: 28265f517fcf150a, SrcConnectionID: (empty), PacketNumber: 1, PacketNumberLen: 2, Length: 26, Version: v1}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 0s, ECT0: 0, ECT1: 1, CE: 0}
2025/04/30 08:43:10 client -> Sending packet 0 (46 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: ECT(0))
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 28265f517fcf150a, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17}
2025/04/30 08:43:10 client <- Reading packet 2 (21 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 2, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.HandshakeDoneFrame{}
2025/04/30 08:43:10 client Dropping Handshake keys.
2025/04/30 08:43:10 client 	Queueing ACK because the first packet should be acknowledged.
2025/04/30 08:43:10 client -> Sending packet 1 (35 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: ECT(0))
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 28265f517fcf150a, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 2, LowestAcked: 2, DelayTime: 62.908µs, ECT0: 0, ECT1: 1, CE: 0}
2025/04/30 08:43:10 client Parsed a coalesced packet. Part 1: 39 bytes. Remaining: 90 bytes.
2025/04/30 08:43:10 client Dropping %!s(logging.PacketType=1) packet (39 bytes) because we already dropped the keys.
2025/04/30 08:43:10 client <- Reading packet 4 (90 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 4, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: 4c59d0a663128960, StatelessResetToken: 0xa2936cb61d85aee77264a2470fb23bea}
2025/04/30 08:43:10 client 	<- &wire.NewTokenFrame{Token: 0x014da639ad2df2dd2383f930bdb016aca139ef091ebed5f3d3a7de4b9093da07f490ad88695a0993e91e}
2025/04/30 08:43:10 client 	Queuing ACK because there's a new missing packet to report.
2025/04/30 08:43:10 client -> Sending packet 2 (39 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: ECT(0))
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 4c59d0a663128960, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 4, LowestAcked: 2, AckRanges: {{Largest: 4, Smallest: 4}, {Largest: 2, Smallest: 2}}, DelayTime: 126.147µs, ECT0: 0, ECT1: 2, CE: 0}
2025/04/30 08:43:10 client 	-> &wire.RetireConnectionIDFrame{SequenceNumber: 0}
2025/04/30 08:43:10 client <- Reading packet 5 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 5, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 80µs}
2025/04/30 08:43:10 client 	newly acked packets (1): [0]
2025/04/30 08:43:10 client 	updated RTT: 36.1ms (σ: 11.557ms)
2025/04/30 08:43:10 client Disabling ECN. ECN-marked packet acknowledged, but no ECN counts on ACK frame.
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1227, Offset + Data length: 1227}
2025/04/30 08:43:10 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 08:43:10 client <- Reading packet 6 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 6, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1227, Data length: 1230, Offset + Data length: 2457}
2025/04/30 08:43:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 08:43:10 client -> Sending packet 3 (37 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: Not-ECT)
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 4c59d0a663128960, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 6, LowestAcked: 2, AckRanges: {{Largest: 6, Smallest: 4}, {Largest: 2, Smallest: 2}}, DelayTime: 41.087µs, ECT0: 0, ECT1: 4, CE: 0}
2025/04/30 08:43:10 client <- Reading packet 7 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 7, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2457, Data length: 1230, Offset + Data length: 3687}
2025/04/30 08:43:10 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 08:43:10 client <- Reading packet 8 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 8, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3687, Data length: 1230, Offset + Data length: 4917}
2025/04/30 08:43:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 08:43:10 client -> Sending packet 4 (37 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: Not-ECT)
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 4c59d0a663128960, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 8, LowestAcked: 2, AckRanges: {{Largest: 8, Smallest: 4}, {Largest: 2, Smallest: 2}}, DelayTime: 40.936µs, ECT0: 0, ECT1: 6, CE: 0}
2025/04/30 08:43:10 client <- Reading packet 9 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 9, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 4917, Data length: 1230, Offset + Data length: 6147}
2025/04/30 08:43:10 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 08:43:10 client <- Reading packet 10 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 10, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6147, Data length: 1230, Offset + Data length: 7377}
2025/04/30 08:43:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 08:43:10 client -> Sending packet 5 (37 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: Not-ECT)
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 4c59d0a663128960, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 10, LowestAcked: 2, AckRanges: {{Largest: 10, Smallest: 4}, {Largest: 2, Smallest: 2}}, DelayTime: 39.634µs, ECT0: 0, ECT1: 8, CE: 0}
2025/04/30 08:43:10 client <- Reading packet 11 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 11, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7377, Data length: 1230, Offset + Data length: 8607}
2025/04/30 08:43:10 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 08:43:10 client <- Reading packet 12 (1252 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 12, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8607, Data length: 1230, Offset + Data length: 9837}
2025/04/30 08:43:10 client 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 08:43:10 client -> Sending packet 6 (37 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: Not-ECT)
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 4c59d0a663128960, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.AckFrame{LargestAcked: 12, LowestAcked: 2, AckRanges: {{Largest: 12, Smallest: 4}, {Largest: 2, Smallest: 2}}, DelayTime: 45.265µs, ECT0: 0, ECT1: 10, CE: 0}
2025/04/30 08:43:10 client <- Reading packet 13 (427 bytes) for connection (empty), 1-RTT
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: (empty), PacketNumber: 13, PacketNumberLen: 1, KeyPhase: 0}
2025/04/30 08:43:10 client 	<- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 9837, Data length: 403, Offset + Data length: 10240}
2025/04/30 08:43:10 client 	Setting ACK timer to max ack delay: 25ms
2025/04/30 08:43:10 client Closing connection with error: Application error 0x0 (local)
2025/04/30 08:43:10 client -> Sending packet 7 (30 bytes) for connection 0f7d435fe5fee6f48ea5b93063, 1-RTT (ECN: Not-ECT)
2025/04/30 08:43:10 client 	Short Header{DestConnectionID: 4c59d0a663128960, PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:43:10 client 	-> &wire.ConnectionCloseFrame{IsApplicationError:true, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""}
2025/04/30 08:43:10 Replacing connection for connection IDs [(empty)] with a closed connection.
2025/04/30 08:43:10 client Connection 0f7d435fe5fee6f48ea5b93063 closed.