2025/04/30 08:44:32 Increased receive buffer size to 14336 kiB
2025/04/30 08:44:32 Increased send buffer size to 14336 kiB
2025/04/30 08:44:32 Setting DF for IPv4 and IPv6.
2025/04/30 08:44:32 Activating reading of ECN bits for IPv4 and IPv6.
2025/04/30 08:44:32 Activating reading of packet info for IPv4 and IPv6.
2025/04/30 08:44:32 server Listening for udp connections on [::]:443
2025/04/30 08:44:33 server Client offered version 0x57414954, sending Version Negotiation
2025/04/30 08:44:33 server Client offered version 0x57414954, sending Version Negotiation
2025/04/30 08:44:33 server Client offered version 0x57414954, sending Version Negotiation
2025/04/30 08:44:33 server <- Received Initial packet.
2025/04/30 08:44:33 server 	Long Header{Type: Initial, DestConnectionID: 1f11e8397aea1ecc, SrcConnectionID: 0424e6ca82f98841, Token: (empty), PacketNumber: 0, PacketNumberLen: 0, Length: 1174, Version: v1}
2025/04/30 08:44:33 server Changing connection ID to c1049bfe.
2025/04/30 08:44:33 server -> Sending Retry
2025/04/30 08:44:33 server 	Long Header{Type: Retry, DestConnectionID: 0424e6ca82f98841, SrcConnectionID: c1049bfe, Token: 0xc77b5d0489bf489cdbc0127561466bfc01f9f63b6fd9d7e291782630a9932ce0a245e13f880bef569115f1fcfc549a52138c8a48f6dca958cdc131794c3400f9c66833769f43b4a7d12563fea3bbd28703451b7b2717daf8a22af1d18264e61181d3, Version: v1}
2025/04/30 08:44:33 server <- Received Initial packet.
2025/04/30 08:44:33 Created qlog file: /logs/qlog/1f11e8397aea1ecc.sqlog
2025/04/30 08:44:33 server Changing connection ID to 866cb764.
2025/04/30 08:44:33 Adding connection IDs c1049bfe and 866cb764 for a new connection.
2025/04/30 08:44:33 server <- Reading packet 1 (1200 bytes) for connection c1049bfe, Initial
2025/04/30 08:44:33 server 	Long Header{Type: Initial, DestConnectionID: c1049bfe, SrcConnectionID: 0424e6ca82f98841, Token: 0xc77b5d0489bf489cdbc0127561466bfc01f9f63b6fd9d7e291782630a9932ce0a245e13f880bef569115f1fcfc549a52138c8a48f6dca958cdc131794c3400f9c66833769f43b4a7d12563fea3bbd28703451b7b2717daf8a22af1d18264e61181d3, PacketNumber: 1, PacketNumberLen: 4, Length: 1079, Version: v1}
2025/04/30 08:44:33 server 	<- &wire.CryptoFrame{Offset: 0, Data length: 305, Offset + Data length: 305}
2025/04/30 08:44:33 server Installed Handshake Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:44:33 server Installed Handshake Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:44:33 server Installed 1-RTT Write keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:44:33 server Processed Transport Parameters: &wire.TransportParameters{OriginalDestinationConnectionID: (empty), InitialSourceConnectionID: 0424e6ca82f98841, InitialMaxStreamDataBidiLocal: 16777216, InitialMaxStreamDataBidiRemote: 16777216, InitialMaxStreamDataUni: 16777216, InitialMaxData: 1048576, MaxBidiStreamNum: 128, MaxUniStreamNum: 128, MaxIdleTimeout: 2m0s, AckDelayExponent: 3, MaxAckDelay: 25ms, ActiveConnectionIDLimit: 8}
2025/04/30 08:44:33 Adding connection ID a93fddcd.
2025/04/30 08:44:33 Adding connection ID ba9e4252.
2025/04/30 08:44:33 Adding connection ID 9b453747.
2025/04/30 08:44:33 Adding connection ID 904bc826.
2025/04/30 08:44:33 Adding connection ID 1e2280a3.
2025/04/30 08:44:33 server -> Sending coalesced packet (2 parts, 1280 bytes) for connection 1f11e8397aea1ecc
2025/04/30 08:44:33 server 	Long Header{Type: Initial, DestConnectionID: 0424e6ca82f98841, SrcConnectionID: 866cb764, Token: (empty), PacketNumber: 0, PacketNumberLen: 2, Length: 360, Version: v1}
2025/04/30 08:44:33 server 	-> &wire.AckFrame{LargestAcked: 1, LowestAcked: 1, DelayTime: 0s}
2025/04/30 08:44:33 server 	-> &wire.CryptoFrame{Offset: 0, Data length: 123, Offset + Data length: 123}
2025/04/30 08:44:33 server 	Long Header{Type: Handshake, DestConnectionID: 0424e6ca82f98841, SrcConnectionID: 866cb764, PacketNumber: 0, PacketNumberLen: 2, Length: 730, Version: v1}
2025/04/30 08:44:33 server 	-> &wire.CryptoFrame{Offset: 0, Data length: 708, Offset + Data length: 708}
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 0, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.NewConnectionIDFrame{SequenceNumber: 4, RetirePriorTo: 0, ConnectionID: 904bc826, StatelessResetToken: 0x3ff7d25a8896ca8532842129d59eaaea}
2025/04/30 08:44:33 server 	-> &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: a93fddcd, StatelessResetToken: 0x25efac1967b54c92bfd0e6ef0883233a}
2025/04/30 08:44:33 server 	-> &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 0, ConnectionID: ba9e4252, StatelessResetToken: 0xdefbd6d503d6be3346546352ee65102d}
2025/04/30 08:44:33 server 	-> &wire.NewConnectionIDFrame{SequenceNumber: 5, RetirePriorTo: 0, ConnectionID: 1e2280a3, StatelessResetToken: 0x3e61765adf0f8eb2d1ab9e94716070e7}
2025/04/30 08:44:33 server 	-> &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 0, ConnectionID: 9b453747, StatelessResetToken: 0x587ee32375f7685d678e64c266659722}
2025/04/30 08:44:33 server <- Reading packet 0 (80 bytes) for connection 866cb764, Handshake
2025/04/30 08:44:33 server 	Long Header{Type: Handshake, DestConnectionID: 866cb764, SrcConnectionID: 0424e6ca82f98841, PacketNumber: 0, PacketNumberLen: 4, Length: 59, Version: v1}
2025/04/30 08:44:33 server Dropping Initial keys.
2025/04/30 08:44:33 server 	<- &wire.CryptoFrame{Offset: 0, Data length: 36, Offset + Data length: 36}
2025/04/30 08:44:33 server Installed 1-RTT Read keys (using TLS_AES_128_GCM_SHA256)
2025/04/30 08:44:33 Retiring connection ID c1049bfe in 5s.
2025/04/30 08:44:33 server Dropping Handshake keys.
2025/04/30 08:44:33 server -> Sending packet 1 (256 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 1, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.CryptoFrame{Offset: 0, Data length: 135, Offset + Data length: 135}
2025/04/30 08:44:33 server 	-> &wire.HandshakeDoneFrame{}
2025/04/30 08:44:33 server 	-> &wire.NewTokenFrame{Token: 0x5c0309a00bac981dc157748f4fdfb9f983ea07965e9698e776707b47b874bf93432352ad9b2d6d2ffbbe6986feb369f31cf9695030dfd732af95c0153d5b77cc4aa8cb9797b379a9097213fbe9ff5684059c33ee24ac}
2025/04/30 08:44:33 server Dropping %!s(logging.PacketType=0) packet (1200 bytes) because we already dropped the keys.
2025/04/30 08:44:33 server Dropping %!s(logging.PacketType=1) packet (47 bytes) because we already dropped the keys.
2025/04/30 08:44:33 server <- Reading packet 0 (46 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 0, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 0, Data length: 17, Offset + Data length: 17}
2025/04/30 08:44:33 server 	Queueing ACK because the first packet should be acknowledged.
2025/04/30 08:44:33 server -> Sending packet 2 (32 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 2, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 56.125µs}
2025/04/30 08:44:33 Received request: GET /cwpmirtcld
2025/04/30 08:44:33 server -> Sending packet 3 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 3, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 0, Data length: 1251, Offset + Data length: 1251}
2025/04/30 08:44:33 server -> Sending packet 4 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 4, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 1251, Data length: 1249, Offset + Data length: 2500}
2025/04/30 08:44:33 server -> Sending packet 5 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 5, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 2500, Data length: 1249, Offset + Data length: 3749}
2025/04/30 08:44:33 server -> Sending packet 6 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 6, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 3749, Data length: 1249, Offset + Data length: 4998}
2025/04/30 08:44:33 server -> Sending packet 7 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 7, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 4998, Data length: 1249, Offset + Data length: 6247}
2025/04/30 08:44:33 server -> Sending packet 8 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 8, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 6247, Data length: 1249, Offset + Data length: 7496}
2025/04/30 08:44:33 server -> Sending packet 9 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 9, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 7496, Data length: 1249, Offset + Data length: 8745}
2025/04/30 08:44:33 server -> Sending packet 10 (1280 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: ECT(0))
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 10, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 8745, Data length: 1249, Offset + Data length: 9994}
2025/04/30 08:44:33 server -> Sending packet 11 (277 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: Not-ECT)
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 11, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: false, Offset: 9994, Data length: 246, Offset + Data length: 10240}
2025/04/30 08:44:33 server -> Sending packet 12 (31 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: Not-ECT)
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 0424e6ca82f98841, PacketNumber: 12, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.StreamFrame{StreamID: 0, Fin: true, Offset: 10240, Data length: 0, Offset + Data length: 10240}
2025/04/30 08:44:33 server <- Reading packet 3 (31 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 3, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.AckFrame{LargestAcked: 0, LowestAcked: 0, DelayTime: 25.976ms}
2025/04/30 08:44:33 server 	newly acked packets (1): [0]
2025/04/30 08:44:33 server 	updated RTT: 57.564966ms (σ: 28.782483ms)
2025/04/30 08:44:33 server <- Reading packet 4 (53 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 4, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.NewConnectionIDFrame{SequenceNumber: 1, RetirePriorTo: 0, ConnectionID: b1aed9ced87dd5c8, StatelessResetToken: 0x585c2ba3ba1ff4f4e45f43da203cb869}
2025/04/30 08:44:33 server 	Setting ACK timer to max ack delay: 25ms
2025/04/30 08:44:33 server <- Reading packet 5 (53 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 5, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.NewConnectionIDFrame{SequenceNumber: 2, RetirePriorTo: 0, ConnectionID: 504352b88a88d43c, StatelessResetToken: 0x6896194e0794940701ff6f7ecafaba69}
2025/04/30 08:44:33 server 	Queueing ACK because packet 2 packets were received after the last ACK (using initial threshold: 2).
2025/04/30 08:44:33 server -> Sending packet 13 (36 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: Not-ECT)
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: b1aed9ced87dd5c8, PacketNumber: 13, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.AckFrame{LargestAcked: 5, LowestAcked: 0, AckRanges: {{Largest: 5, Smallest: 3}, {Largest: 0, Smallest: 0}}, DelayTime: 66.795µs}
2025/04/30 08:44:33 server 	-> &wire.RetireConnectionIDFrame{SequenceNumber: 0}
2025/04/30 08:44:33 server <- Reading packet 6 (30 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 6, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.AckFrame{LargestAcked: 3, LowestAcked: 0, DelayTime: 112µs}
2025/04/30 08:44:33 server 	newly acked packets (2): [1 3]
2025/04/30 08:44:33 server 	updated RTT: 54.348ms (σ: 28.017ms)
2025/04/30 08:44:33 server Disabling ECN. ECN-marked packet acknowledged, but no ECN counts on ACK frame.
2025/04/30 08:44:33 server <- Reading packet 7 (30 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 7, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.AckFrame{LargestAcked: 5, LowestAcked: 0, DelayTime: 96µs}
2025/04/30 08:44:33 server 	newly acked packets (2): [4 5]
2025/04/30 08:44:33 server 	updated RTT: 51.782ms (σ: 26.143ms)
2025/04/30 08:44:33 server <- Reading packet 8 (30 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 8, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.AckFrame{LargestAcked: 7, LowestAcked: 0, DelayTime: 96µs}
2025/04/30 08:44:33 server 	newly acked packets (2): [6 7]
2025/04/30 08:44:33 server 	updated RTT: 49.799ms (σ: 23.572ms)
2025/04/30 08:44:33 server <- Reading packet 9 (30 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 9, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.AckFrame{LargestAcked: 9, LowestAcked: 0, DelayTime: 112µs}
2025/04/30 08:44:33 server 	newly acked packets (2): [8 9]
2025/04/30 08:44:33 server 	updated RTT: 48.327ms (σ: 20.622ms)
2025/04/30 08:44:33 server <- Reading packet 10 (30 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 10, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.AckFrame{LargestAcked: 12, LowestAcked: 0, DelayTime: 128µs}
2025/04/30 08:44:33 server 	newly acked packets (3): [10 11 12]
2025/04/30 08:44:33 server 	updated RTT: 47.181ms (σ: 17.757ms)
2025/04/30 08:44:33 server -> Sending packet 14 (30 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: Not-ECT)
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: b1aed9ced87dd5c8, PacketNumber: 14, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.MaxStreamsFrame{Type: bidi, MaxStreamNum: 101}
2025/04/30 08:44:33 server <- Reading packet 11 (53 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 11, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.NewConnectionIDFrame{SequenceNumber: 3, RetirePriorTo: 0, ConnectionID: c81a80d05fdd7d78, StatelessResetToken: 0xea3762f39727775706bf7c9a80d485b3}
2025/04/30 08:44:33 server 	Setting ACK timer to max ack delay: 25ms
2025/04/30 08:44:33 server <- Reading packet 12 (30 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 12, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:33 server 	<- &wire.AckFrame{LargestAcked: 14, LowestAcked: 0, DelayTime: 80µs}
2025/04/30 08:44:33 server 	newly acked packets (2): [13 14]
2025/04/30 08:44:33 server 	updated RTT: 45.103ms (σ: 17.472ms)
2025/04/30 08:44:33 server Canceling loss detection timer.
2025/04/30 08:44:33 server 	Ignoring all packets below 6.
2025/04/30 08:44:33 server Sending ACK because the ACK timer expired.
2025/04/30 08:44:33 server -> Sending packet 15 (33 bytes) for connection 1f11e8397aea1ecc, 1-RTT (ECN: Not-ECT)
2025/04/30 08:44:33 server 	Short Header{DestConnectionID: b1aed9ced87dd5c8, PacketNumber: 15, PacketNumberLen: 2, KeyPhase: 0}
2025/04/30 08:44:33 server 	-> &wire.AckFrame{LargestAcked: 12, LowestAcked: 6, DelayTime: 16.280964ms}
2025/04/30 08:44:34 server <- Reading packet 13 (29 bytes) for connection 866cb764, 1-RTT
2025/04/30 08:44:34 server 	Short Header{DestConnectionID: 866cb764, PacketNumber: 13, PacketNumberLen: 4, KeyPhase: 0}
2025/04/30 08:44:34 server 	<- &wire.ConnectionCloseFrame{IsApplicationError:false, ErrorCode:0x0, FrameType:0x0, ReasonPhrase:""}
2025/04/30 08:44:34 server Closing connection with error: NO_ERROR (remote)
2025/04/30 08:44:34 Replacing connection for connection IDs [904bc826 1e2280a3 866cb764 a93fddcd ba9e4252 9b453747] with a closed connection.
2025/04/30 08:44:34 Error accepting stream: NO_ERROR (remote)
2025/04/30 08:44:34 server Connection 1f11e8397aea1ecc closed.
2025/04/30 08:44:34 Tracking 7 connection IDs and 0 reset tokens.
2025/04/30 08:44:36 Tracking 7 connection IDs and 0 reset tokens.
2025/04/30 08:44:38 Tracking 7 connection IDs and 0 reset tokens.
2025/04/30 08:44:38 Removing connection ID c1049bfe after it has been retired.
2025/04/30 08:44:39 Removing connection IDs [904bc826 1e2280a3 866cb764 a93fddcd ba9e4252 9b453747] for a closed connection after it has been retired.
2025/04/30 08:44:40 Tracking 0 connection IDs and 0 reset tokens.