2 * Copyright (c) 2017 Cisco and/or its affiliates.
3 * Licensed under the Apache License, Version 2.0 (the "License");
4 * you may not use this file except in compliance with the License.
5 * You may obtain a copy of the License at:
7 * http://www.apache.org/licenses/LICENSE-2.0
9 * Unless required by applicable law or agreed to in writing, software
10 * distributed under the License is distributed on an "AS IS" BASIS,
11 * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
12 * See the License for the specific language governing permissions and
13 * limitations under the License.
16 #ifndef SRC_VNET_TCP_TCP_DEBUG_H_
17 #define SRC_VNET_TCP_TCP_DEBUG_H_
19 #include <vlib/vlib.h>
22 #define TCP_DEBUG_SM (0)
23 #define TCP_DEBUG_CC (0)
24 #define TCP_DEBUG_CC_STAT (0)
25 #define TCP_DEBUG_BUFFER_ALLOCATION (0)
27 #define foreach_tcp_dbg_evt \
35 _(SYN_SENT, "SYN sent") \
36 _(SYNACK_SENT, "SYNACK sent") \
37 _(SYNACK_RCVD, "SYNACK rcvd") \
38 _(SYN_RXT, "SYN retransmit") \
39 _(FIN_SENT, "FIN sent") \
40 _(ACK_SENT, "ACK sent") \
41 _(DUPACK_SENT, "DUPACK sent") \
42 _(RST_SENT, "RST sent") \
43 _(SYN_RCVD, "SYN rcvd") \
44 _(ACK_RCVD, "ACK rcvd") \
45 _(DUPACK_RCVD, "DUPACK rcvd") \
46 _(FIN_RCVD, "FIN rcvd") \
47 _(RST_RCVD, "RST rcvd") \
48 _(STATE_CHANGE, "state change") \
49 _(PKTIZE, "packetize") \
51 _(SND_WND, "snd_wnd update") \
53 _(TIMER_POP, "timer pop") \
54 _(CC_RTX, "retransmit") \
55 _(CC_EVT, "cc event") \
56 _(CC_PACK, "cc partial ack") \
57 _(CC_STAT, "cc stats") \
58 _(CC_RTO_STAT, "cc rto stats") \
59 _(CC_SCOREBOARD, "scoreboard stats") \
60 _(CC_SACKS, "snd sacks stats") \
61 _(CC_INPUT, "ooo data delivered") \
62 _(SEG_INVALID, "invalid segment") \
63 _(PAWS_FAIL, "failed paws check") \
64 _(ACK_RCV_ERR, "invalid ack") \
65 _(RCV_WND_SHRUNK, "shrunk rcv_wnd") \
69 #define _(sym, str) TCP_DBG_##sym,
74 typedef enum _tcp_dbg_evt
76 #define _(sym, str) TCP_EVT_##sym,
83 #define TRANSPORT_DEBUG (1)
86 * Infra and evt track setup
89 #define TCP_DBG(_fmt, _args...) clib_warning (_fmt, ##_args)
91 #define DECLARE_ETD(_tc, _e, _size) \
96 ed = ELOG_TRACK_DATA (&vlib_global_main.elog_main, \
97 _e, _tc->c_elog_track)
99 #define TCP_DBG_IP_TAG_LCL(_tc) \
103 ELOG_TYPE_DECLARE (_e) = \
105 .format = "lcl: %d.%d.%d.%d:%d", \
106 .format_args = "i4i4i4i4i4", \
108 DECLARE_ETD(_tc, _e, 5); \
109 ed->data[0] = _tc->c_lcl_ip.ip4.as_u8[0]; \
110 ed->data[1] = _tc->c_lcl_ip.ip4.as_u8[1]; \
111 ed->data[2] = _tc->c_lcl_ip.ip4.as_u8[2]; \
112 ed->data[3] = _tc->c_lcl_ip.ip4.as_u8[3]; \
113 ed->data[4] = clib_net_to_host_u16(_tc->c_lcl_port); \
117 #define TCP_DBG_IP_TAG_RMT(_tc) \
121 ELOG_TYPE_DECLARE (_e) = \
123 .format = "rmt: %d.%d.%d.%d:%d", \
124 .format_args = "i4i4i4i4i4", \
126 DECLARE_ETD(_tc, _e, 5); \
127 ed->data[0] = _tc->c_rmt_ip.ip4.as_u8[0]; \
128 ed->data[1] = _tc->c_rmt_ip.ip4.as_u8[1]; \
129 ed->data[2] = _tc->c_rmt_ip.ip4.as_u8[2]; \
130 ed->data[3] = _tc->c_rmt_ip.ip4.as_u8[3]; \
131 ed->data[4] = clib_net_to_host_u16(_tc->c_rmt_port); \
135 #define TCP_EVT_INIT_HANDLER(_tc, _is_l, ...) \
137 char *_fmt = _is_l ? "l[%d].%d:%d%c" : "[%d].%d:%d->.%d:%d%c"; \
140 _tc->c_elog_track.name = \
141 (char *) format (0, _fmt, _tc->c_thread_index, \
142 _tc->c_lcl_ip.ip4.as_u8[3], \
143 clib_net_to_host_u16(_tc->c_lcl_port), \
144 _tc->c_rmt_ip.ip4.as_u8[3], \
145 clib_net_to_host_u16(_tc->c_rmt_port), 0); \
148 _tc->c_elog_track.name = \
149 (char *) format (0, _fmt, _tc->c_thread_index, \
150 _tc->c_lcl_ip.ip6.as_u8[15], \
151 clib_net_to_host_u16(_tc->c_lcl_port), \
152 _tc->c_rmt_ip.ip6.as_u8[15], \
153 clib_net_to_host_u16(_tc->c_rmt_port), 0); \
154 elog_track_register (&vlib_global_main.elog_main, &_tc->c_elog_track);\
155 TCP_DBG_IP_TAG_LCL(_tc); \
156 TCP_DBG_IP_TAG_RMT(_tc); \
159 #define TCP_EVT_DEALLOC_HANDLER(_tc, ...) \
161 vec_free (_tc->c_elog_track.name); \
164 #define TCP_EVT_OPEN_HANDLER(_tc, ...) \
166 TCP_EVT_INIT_HANDLER(_tc, 0); \
167 ELOG_TYPE_DECLARE (_e) = \
169 .format = "open: index %d", \
170 .format_args = "i4", \
172 DECLARE_ETD(_tc, _e, 1); \
173 ed->data[0] = _tc->c_c_index; \
176 #define TCP_EVT_CLOSE_HANDLER(_tc, ...) \
178 ELOG_TYPE_DECLARE (_e) = \
180 .format = "close: %d", \
181 .format_args = "i4", \
183 DECLARE_ETD(_tc, _e, 1); \
184 ed->data[0] = _tc->c_c_index; \
187 #define TCP_EVT_BIND_HANDLER(_tc, ...) \
189 TCP_EVT_INIT_HANDLER(_tc, 1); \
190 ELOG_TYPE_DECLARE (_e) = \
192 .format = "bind: listener %d", \
194 DECLARE_ETD(_tc, _e, 1); \
195 ed->data[0] = _tc->c_c_index; \
198 #define TCP_EVT_SYN_RCVD_HANDLER(_tc,_init, ...) \
201 TCP_EVT_INIT_HANDLER(_tc, 0); \
202 ELOG_TYPE_DECLARE (_e) = \
204 .format = "syn-rx: irs %u", \
205 .format_args = "i4", \
207 DECLARE_ETD(_tc, _e, 1); \
208 ed->data[0] = _tc->irs; \
209 TCP_EVT_STATE_CHANGE_HANDLER(_tc); \
212 #define TCP_EVT_UNBIND_HANDLER(_tc, ...) \
214 TCP_EVT_DEALLOC_HANDLER(_tc); \
215 ELOG_TYPE_DECLARE (_e) = \
217 .format = "unbind: listener %d", \
219 DECLARE_ETD(_tc, _e, 1); \
220 ed->data[0] = _tc->c_c_index; \
221 TCP_EVT_DEALLOC_HANDLER(_tc); \
224 #define TCP_EVT_DELETE_HANDLER(_tc, ...) \
226 ELOG_TYPE_DECLARE (_e) = \
228 .format = "delete: %d", \
229 .format_args = "i4", \
231 DECLARE_ETD(_tc, _e, 1); \
232 ed->data[0] = _tc->c_c_index; \
233 TCP_EVT_DEALLOC_HANDLER(_tc); \
236 #define CONCAT_HELPER(_a, _b) _a##_b
237 #define CC(_a, _b) CONCAT_HELPER(_a, _b)
238 #define TCP_EVT_DBG(_evt, _args...) CC(_evt, _HANDLER)(_args)
240 #define TCP_EVT_DBG(_evt, _args...)
241 #define TCP_DBG(_fmt, _args...)
249 #define TCP_EVT_STATE_CHANGE_HANDLER(_tc, ...) \
251 ELOG_TYPE_DECLARE (_e) = \
253 .format = "state: %s", \
254 .format_args = "t4", \
255 .n_enum_strings = 11, \
270 DECLARE_ETD(_tc, _e, 1); \
271 ed->data[0] = _tc->state; \
274 #define TCP_EVT_SYN_SENT_HANDLER(_tc, ...) \
276 ELOG_TYPE_DECLARE (_e) = \
278 .format = "syn-tx: iss %u snd_una %u snd_una_max %u snd_nxt %u", \
279 .format_args = "i4i4i4i4", \
281 DECLARE_ETD(_tc, _e, 4); \
282 ed->data[0] = _tc->iss; \
283 ed->data[1] = _tc->snd_una - _tc->iss; \
284 ed->data[2] = _tc->snd_una_max - _tc->iss; \
285 ed->data[3] = _tc->snd_nxt - _tc->iss; \
286 TCP_EVT_STATE_CHANGE_HANDLER(_tc); \
289 #define TCP_EVT_SYNACK_SENT_HANDLER(_tc, ...) \
291 ELOG_TYPE_DECLARE (_e) = \
293 .format = "synack-tx: iss %u irs %u snd_una %u snd_nxt %u rcv_nxt %u",\
294 .format_args = "i4i4i4i4i4", \
296 DECLARE_ETD(_tc, _e, 5); \
297 ed->data[0] = _tc->iss; \
298 ed->data[1] = _tc->irs; \
299 ed->data[2] = _tc->snd_una - _tc->iss; \
300 ed->data[3] = _tc->snd_nxt - _tc->iss; \
301 ed->data[4] = _tc->rcv_nxt - _tc->irs; \
304 #define TCP_EVT_SYNACK_RCVD_HANDLER(_tc, ...) \
306 ELOG_TYPE_DECLARE (_e) = \
308 .format = "synack-rx: iss %u irs %u snd_una %u snd_nxt %u rcv_nxt %u",\
309 .format_args = "i4i4i4i4i4", \
311 DECLARE_ETD(_tc, _e, 5); \
312 ed->data[0] = _tc->iss; \
313 ed->data[1] = _tc->irs; \
314 ed->data[2] = _tc->snd_una - _tc->iss; \
315 ed->data[3] = _tc->snd_nxt - _tc->iss; \
316 ed->data[4] = _tc->rcv_nxt - _tc->irs; \
317 TCP_EVT_STATE_CHANGE_HANDLER(_tc); \
320 #define TCP_EVT_FIN_SENT_HANDLER(_tc, ...) \
322 ELOG_TYPE_DECLARE (_e) = \
324 .format = "fin-tx: snd_nxt %d rcv_nxt %d", \
325 .format_args = "i4i4", \
327 DECLARE_ETD(_tc, _e, 2); \
328 ed->data[0] = _tc->snd_nxt - _tc->iss; \
329 ed->data[1] = _tc->rcv_nxt - _tc->irs; \
332 #define TCP_EVT_RST_SENT_HANDLER(_tc, ...) \
336 ELOG_TYPE_DECLARE (_e) = \
338 .format = "rst-tx: snd_nxt %d rcv_nxt %d", \
339 .format_args = "i4i4", \
341 DECLARE_ETD(_tc, _e, 2); \
342 ed->data[0] = _tc->snd_nxt - _tc->iss; \
343 ed->data[1] = _tc->rcv_nxt - _tc->irs; \
344 TCP_EVT_STATE_CHANGE_HANDLER(_tc); \
348 #define TCP_EVT_FIN_RCVD_HANDLER(_tc, ...) \
350 ELOG_TYPE_DECLARE (_e) = \
352 .format = "fin-rx: snd_nxt %d rcv_nxt %d", \
353 .format_args = "i4i4", \
355 DECLARE_ETD(_tc, _e, 2); \
356 ed->data[0] = _tc->snd_nxt - _tc->iss; \
357 ed->data[1] = _tc->rcv_nxt - _tc->irs; \
360 #define TCP_EVT_RST_RCVD_HANDLER(_tc, ...) \
362 ELOG_TYPE_DECLARE (_e) = \
364 .format = "rst-rx: snd_nxt %d rcv_nxt %d", \
365 .format_args = "i4i4", \
367 DECLARE_ETD(_tc, _e, 2); \
368 ed->data[0] = _tc->snd_nxt - _tc->iss; \
369 ed->data[1] = _tc->rcv_nxt - _tc->irs; \
372 #define TCP_EVT_SYN_RXT_HANDLER(_tc, _type, ...) \
374 ELOG_TYPE_DECLARE (_e) = \
376 .format = "%s-rxt: iss %u irs %u snd_nxt %u rcv_nxt %u", \
377 .format_args = "t4i4i4i4i4", \
378 .n_enum_strings = 2, \
384 DECLARE_ETD(_tc, _e, 5); \
385 ed->data[0] = _type; \
386 ed->data[1] = _tc->iss; \
387 ed->data[2] = _tc->irs; \
388 ed->data[3] = _tc->snd_nxt - _tc->iss; \
389 ed->data[4] = _tc->rcv_nxt - _tc->irs; \
392 #define TCP_EVT_SYN_SENT_HANDLER(_tc, ...)
393 #define TCP_EVT_SYNACK_SENT_HANDLER(_tc, ...)
394 #define TCP_EVT_SYNACK_RCVD_HANDLER(_tc, ...)
395 #define TCP_EVT_SYN_RXT_HANDLER(_tc, ...)
396 #define TCP_EVT_FIN_SENT_HANDLER(_tc, ...)
397 #define TCP_EVT_RST_SENT_HANDLER(_tc, ...)
398 #define TCP_EVT_FIN_RCVD_HANDLER(_tc, ...)
399 #define TCP_EVT_RST_RCVD_HANDLER(_tc, ...)
400 #define TCP_EVT_STATE_CHANGE_HANDLER(_tc, ...)
404 #define TCP_EVT_SEG_INVALID_HANDLER(_tc, _btcp, ...) \
406 ELOG_TYPE_DECLARE (_e) = \
408 .format = "seg-inv: seq %u end %u rcv_las %u rcv_nxt %u rcv_wnd %u",\
409 .format_args = "i4i4i4i4i4", \
411 DECLARE_ETD(_tc, _e, 5); \
412 ed->data[0] = _btcp.seq_number - _tc->irs; \
413 ed->data[1] = _btcp.seq_end - _tc->irs; \
414 ed->data[2] = _tc->rcv_las - _tc->irs; \
415 ed->data[3] = _tc->rcv_nxt - _tc->irs; \
416 ed->data[4] = _tc->rcv_wnd; \
419 #define TCP_EVT_PAWS_FAIL_HANDLER(_tc, _seq, _end, ...) \
421 ELOG_TYPE_DECLARE (_e) = \
423 .format = "paws-err: seq %u end %u tsval %u tsval_recent %u", \
424 .format_args = "i4i4i4i4", \
426 DECLARE_ETD(_tc, _e, 4); \
427 ed->data[0] = _seq - _tc->irs; \
428 ed->data[1] = _end - _tc->irs; \
429 ed->data[2] = _tc->rcv_opts.tsval; \
430 ed->data[3] = _tc->tsval_recent; \
433 #define TCP_EVT_ACK_RCV_ERR_HANDLER(_tc, _type, _ack, ...) \
435 ELOG_TYPE_DECLARE (_e) = \
437 .format = "ack-err: %s ack %u snd_una %u snd_nxt %u una_max %u", \
438 .format_args = "t4i4i4i4i4", \
439 .n_enum_strings = 3, \
446 DECLARE_ETD(_tc, _e, 5); \
447 ed->data[0] = _type; \
448 ed->data[1] = _ack - _tc->iss; \
449 ed->data[2] = _tc->snd_una - _tc->iss; \
450 ed->data[3] = _tc->snd_nxt - _tc->iss; \
451 ed->data[4] = _tc->snd_una_max - _tc->iss; \
454 #define TCP_EVT_RCV_WND_SHRUNK_HANDLER(_tc, _obs, _av, ...) \
458 ELOG_TYPE_DECLARE (_e) = \
460 .format = "huh?: rcv_wnd %u obsd %u av %u rcv_nxt %u rcv_las %u", \
461 .format_args = "i4i4i4i4i4", \
463 DECLARE_ETD(_tc, _e, 5); \
464 ed->data[0] = _tc->rcv_wnd; \
465 ed->data[1] = _obs; \
467 ed->data[3] = _tc->rcv_nxt - _tc->irs; \
468 ed->data[4] = _tc->rcv_las - _tc->irs; \
472 #define TCP_EVT_SEG_INVALID_HANDLER(_tc, _btcp, ...)
473 #define TCP_EVT_PAWS_FAIL_HANDLER(_tc, _seq, _end, ...)
474 #define TCP_EVT_ACK_RCV_ERR_HANDLER(_tc, _type, _ack, ...)
475 #define TCP_EVT_RCV_WND_SHRUNK_HANDLER(_tc, _obs, _av, ...)
480 #define TCP_EVT_ACK_SENT_HANDLER(_tc, ...) \
482 ELOG_TYPE_DECLARE (_e) = \
484 .format = "ack-tx: acked %u rcv_nxt %u rcv_wnd %u snd_nxt %u snd_wnd %u",\
485 .format_args = "i4i4i4i4i4", \
487 DECLARE_ETD(_tc, _e, 5); \
488 ed->data[0] = _tc->rcv_nxt - _tc->rcv_las; \
489 ed->data[1] = _tc->rcv_nxt - _tc->irs; \
490 ed->data[2] = _tc->rcv_wnd; \
491 ed->data[3] = _tc->snd_nxt - _tc->iss; \
492 ed->data[4] = _tc->snd_wnd; \
495 #define TCP_EVT_ACK_RCVD_HANDLER(_tc, ...) \
497 ELOG_TYPE_DECLARE (_e) = \
499 .format = "ack-rx: %u snd_una %u snd_wnd %u cwnd %u inflight %u", \
500 .format_args = "i4i4i4i4i4", \
502 DECLARE_ETD(_tc, _e, 5); \
503 ed->data[0] = _tc->bytes_acked; \
504 ed->data[1] = _tc->snd_una - _tc->iss; \
505 ed->data[2] = _tc->snd_wnd; \
506 ed->data[3] = _tc->cwnd; \
507 ed->data[4] = tcp_flight_size(_tc); \
510 #define TCP_EVT_PKTIZE_HANDLER(_tc, ...) \
512 ELOG_TYPE_DECLARE (_e) = \
514 .format = "tx: una %u snd_nxt %u space %u flight %u rcv_wnd %u",\
515 .format_args = "i4i4i4i4i4", \
517 DECLARE_ETD(_tc, _e, 5); \
518 ed->data[0] = _tc->snd_una - _tc->iss; \
519 ed->data[1] = _tc->snd_nxt - _tc->iss; \
520 ed->data[2] = tcp_available_output_snd_space (_tc); \
521 ed->data[3] = tcp_flight_size (_tc); \
522 ed->data[4] = _tc->rcv_wnd; \
525 #define TCP_EVT_INPUT_HANDLER(_tc, _type, _len, _written, ...) \
527 ELOG_TYPE_DECLARE (_e) = \
529 .format = "in: %s len %u written %d rcv_nxt %u rcv_wnd(o) %d", \
530 .format_args = "t4i4i4i4i4", \
531 .n_enum_strings = 2, \
537 DECLARE_ETD(_tc, _e, 5); \
538 ed->data[0] = _type; \
539 ed->data[1] = _len; \
540 ed->data[2] = _written; \
541 ed->data[3] = (_tc->rcv_nxt - _tc->irs) + _written; \
542 ed->data[4] = _tc->rcv_wnd - (_tc->rcv_nxt - _tc->rcv_las); \
545 #define TCP_EVT_TIMER_POP_HANDLER(_tc_index, _timer_id, ...) \
547 tcp_connection_t *_tc; \
548 if (_timer_id == TCP_TIMER_RETRANSMIT_SYN \
549 || _timer_id == TCP_TIMER_ESTABLISH) \
551 _tc = tcp_half_open_connection_get (_tc_index); \
555 u32 _thread_index = vlib_get_thread_index (); \
556 _tc = tcp_connection_get (_tc_index, _thread_index); \
558 ELOG_TYPE_DECLARE (_e) = \
560 .format = "timer-pop: %s (%d)", \
561 .format_args = "t4i4", \
562 .n_enum_strings = 7, \
575 DECLARE_ETD(_tc, _e, 2); \
576 ed->data[0] = _timer_id; \
577 ed->data[1] = _timer_id; \
581 clib_warning ("pop %d for unexisting connection %d", _timer_id, \
586 #define TCP_EVT_ACK_SENT_HANDLER(_tc, ...)
587 #define TCP_EVT_ACK_RCVD_HANDLER(_tc, ...)
588 #define TCP_EVT_PKTIZE_HANDLER(_tc, ...)
589 #define TCP_EVT_INPUT_HANDLER(_tc, _type, _len, _written, ...)
590 #define TCP_EVT_TIMER_POP_HANDLER(_tc_index, _timer_id, ...)
594 * State machine verbose
597 #define TCP_EVT_SND_WND_HANDLER(_tc, ...) \
599 ELOG_TYPE_DECLARE (_e) = \
601 .format = "snd-wnd update: %u ", \
602 .format_args = "i4", \
604 DECLARE_ETD(_tc, _e, 1); \
605 ed->data[0] = _tc->snd_wnd; \
608 #define TCP_EVT_OUTPUT_HANDLER(_tc, flags, n_bytes,...) \
610 ELOG_TYPE_DECLARE (_e) = \
612 .format = "out: flags %x, bytes %u", \
613 .format_args = "i4i4", \
615 DECLARE_ETD(_tc, _e, 2); \
616 ed->data[0] = flags; \
617 ed->data[1] = n_bytes; \
620 #define TCP_EVT_SND_WND_HANDLER(_tc, ...)
621 #define TCP_EVT_OUTPUT_HANDLER(_tc, flags, n_bytes,...)
630 #define TCP_EVT_CC_EVT_PRINT(_tc, _sub_evt) \
632 ELOG_TYPE_DECLARE (_e) = \
634 .format = "cc: %s snd_space %u snd_una %u out %u flight %u", \
635 .format_args = "t4i4i4i4i4", \
636 .n_enum_strings = 7, \
647 DECLARE_ETD(_tc, _e, 5); \
648 ed->data[0] = _sub_evt; \
649 ed->data[1] = tcp_available_cc_snd_space (_tc); \
650 ed->data[2] = _tc->snd_una - _tc->iss; \
651 ed->data[3] = tcp_bytes_out(_tc); \
652 ed->data[4] = tcp_flight_size (_tc); \
655 #define TCP_EVT_CC_EVT_HANDLER(_tc, _sub_evt, ...) \
657 if (_tc->snd_una != _tc->iss) \
658 TCP_EVT_CC_STAT_PRINT (_tc); \
659 if ((_sub_evt <= 1 && TCP_DEBUG_CC > 1) \
660 || (_sub_evt > 1 && TCP_DEBUG_CC > 0)) \
661 TCP_EVT_CC_EVT_PRINT (_tc, _sub_evt); \
664 #define TCP_EVT_CC_EVT_HANDLER(_tc, _sub_evt, ...) \
669 #define TCP_EVT_CC_RTX_HANDLER(_tc, offset, n_bytes, ...) \
671 ELOG_TYPE_DECLARE (_e) = \
673 .format = "rxt: snd_nxt %u offset %u snd %u rxt %u", \
674 .format_args = "i4i4i4i4", \
676 DECLARE_ETD(_tc, _e, 4); \
677 ed->data[0] = _tc->snd_nxt - _tc->iss; \
678 ed->data[1] = offset; \
679 ed->data[2] = n_bytes; \
680 ed->data[3] = _tc->snd_rxt_bytes; \
683 #define TCP_EVT_DUPACK_SENT_HANDLER(_tc, _btcp, ...) \
685 ELOG_TYPE_DECLARE (_e) = \
687 .format = "dack-tx: rcv_nxt %u seq %u rcv_wnd %u snd_nxt %u av_wnd %u",\
688 .format_args = "i4i4i4i4i4", \
690 DECLARE_ETD(_tc, _e, 5); \
691 ed->data[0] = _tc->rcv_nxt - _tc->irs; \
692 ed->data[1] = _btcp.seq_number - _tc->irs; \
693 ed->data[2] = _tc->rcv_wnd; \
694 ed->data[3] = _tc->snd_nxt - _tc->iss; \
695 ed->data[4] = tcp_available_snd_wnd(_tc); \
698 #define TCP_EVT_DUPACK_RCVD_HANDLER(_tc, ...) \
700 ELOG_TYPE_DECLARE (_e) = \
702 .format = "dack-rx: snd_una %u cwnd %u snd_wnd %u flight %u rcv_wnd %u",\
703 .format_args = "i4i4i4i4i4", \
705 DECLARE_ETD(_tc, _e, 5); \
706 ed->data[0] = _tc->snd_una - _tc->iss; \
707 ed->data[1] = _tc->cwnd; \
708 ed->data[2] = _tc->snd_wnd; \
709 ed->data[3] = tcp_flight_size(_tc); \
710 ed->data[4] = _tc->rcv_wnd; \
713 #define TCP_EVT_CC_PACK_HANDLER(_tc, ...) \
715 ELOG_TYPE_DECLARE (_e) = \
717 .format = "pack: snd_una %u snd_una_max %u", \
718 .format_args = "i4i4", \
720 DECLARE_ETD(_tc, _e, 2); \
721 ed->data[0] = _tc->snd_una - _tc->iss; \
722 ed->data[1] = _tc->snd_una_max - _tc->iss; \
724 #define TCP_EVT_CC_SCOREBOARD_HANDLER(_tc, ...) \
726 if (TCP_DEBUG_CC > 1 && _tc->sack_sb.last_sacked_bytes) \
728 ELOG_TYPE_DECLARE (_e) = \
730 .format = "sb1: holes %u lost %u sacked %u high %u highrxt %u", \
731 .format_args = "i4i4i4i4i4", \
733 DECLARE_ETD(_tc, _e, 5); \
734 ed->data[0] = pool_elts(_tc->sack_sb.holes); \
735 ed->data[1] = _tc->sack_sb.lost_bytes; \
736 ed->data[2] = _tc->sack_sb.sacked_bytes; \
737 ed->data[3] = _tc->sack_sb.high_sacked - _tc->iss; \
738 ed->data[4] = _tc->sack_sb.high_rxt - _tc->iss; \
740 if (TCP_DEBUG_CC > 1 && _tc->sack_sb.last_sacked_bytes) \
742 sack_scoreboard_hole_t *hole; \
743 hole = scoreboard_first_hole (&_tc->sack_sb); \
744 ELOG_TYPE_DECLARE (_e) = \
746 .format = "sb2: first start: %u end %u last start %u end %u", \
747 .format_args = "i4i4i4i4", \
749 DECLARE_ETD(_tc, _e, 4); \
750 ed->data[0] = hole ? hole->start - _tc->iss : 0; \
751 ed->data[1] = hole ? hole->end - _tc->iss : 0; \
752 hole = scoreboard_last_hole (&_tc->sack_sb); \
753 ed->data[2] = hole ? hole->start - _tc->iss : 0; \
754 ed->data[3] = hole ? hole->end - _tc->iss : 0; \
757 #define TCP_EVT_CC_SACKS_HANDLER(_tc, ...) \
759 if (TCP_DEBUG_CC > 1) \
761 ELOG_TYPE_DECLARE (_e) = \
763 .format = "sacks: blocks %u bytes %u", \
764 .format_args = "i4i4", \
766 DECLARE_ETD(_tc, _e, 2); \
767 ed->data[0] = vec_len (_tc->snd_sacks); \
768 ed->data[1] = tcp_sack_list_bytes (_tc); \
771 #define TCP_EVT_CC_INPUT_HANDLER(_tc, _len, _written, ...) \
773 ELOG_TYPE_DECLARE (_e) = \
775 .format = "cc input: len %u written %d rcv_nxt %u rcv_wnd(o) %d", \
776 .format_args = "i4i4i4i4", \
778 DECLARE_ETD(_tc, _e, 4); \
779 ed->data[0] = _len; \
780 ed->data[1] = _written; \
781 ed->data[2] = _tc->rcv_nxt - _tc->irs; \
782 ed->data[3] = _tc->rcv_wnd - (_tc->rcv_nxt - _tc->rcv_las); \
785 #define TCP_EVT_CC_RTX_HANDLER(_tc, offset, n_bytes, ...)
786 #define TCP_EVT_DUPACK_SENT_HANDLER(_tc, _btcp, ...)
787 #define TCP_EVT_DUPACK_RCVD_HANDLER(_tc, ...)
788 #define TCP_EVT_CC_PACK_HANDLER(_tc, ...)
789 #define TCP_EVT_CC_SCOREBOARD_HANDLER(_tc, ...)
790 #define TCP_EVT_CC_SACKS_HANDLER(_tc, ...)
791 #define TCP_EVT_CC_INPUT_HANDLER(_tc, _len, _written, ...)
795 * Congestion control stats
797 #if TCP_DEBUG_CC_STAT
799 #define STATS_INTERVAL 1
801 #define tcp_cc_time_to_print_stats(_tc) \
802 _tc->c_cc_stat_tstamp + STATS_INTERVAL < tcp_time_now() \
803 || tcp_in_fastrecovery (_tc) \
805 #define TCP_EVT_CC_RTO_STAT_PRINT(_tc) \
807 ELOG_TYPE_DECLARE (_e) = \
809 .format = "rcv_stat: rto %u srtt %u rttvar %u ", \
810 .format_args = "i4i4i4", \
812 DECLARE_ETD(_tc, _e, 3); \
813 ed->data[0] = _tc->rto; \
814 ed->data[1] = _tc->srtt; \
815 ed->data[2] = _tc->rttvar; \
818 #define TCP_EVT_CC_RTO_STAT_HANDLER(_tc, ...) \
820 if (tcp_cc_time_to_print_stats (_tc)) \
822 TCP_EVT_CC_RTO_STAT_PRINT (_tc); \
826 #define TCP_EVT_CC_SND_STAT_PRINT(_tc) \
828 ELOG_TYPE_DECLARE (_e) = \
830 .format = "snd_stat: cc_space %u sacked %u lost %u out %u rxt %u", \
831 .format_args = "i4i4i4i4i4", \
833 DECLARE_ETD(_tc, _e, 5); \
834 ed->data[0] = tcp_available_cc_snd_space (_tc); \
835 ed->data[1] = _tc->sack_sb.sacked_bytes; \
836 ed->data[2] = _tc->sack_sb.lost_bytes; \
837 ed->data[3] = tcp_bytes_out (_tc); \
838 ed->data[3] = _tc->snd_rxt_bytes; \
841 #define TCP_EVT_CC_SND_STAT_HANDLER(_tc, ...) \
843 if (tcp_cc_time_to_print_stats (_tc)) \
845 TCP_EVT_CC_SND_STAT_PRINT(_tc); \
849 #define TCP_EVT_CC_STAT_PRINT(_tc) \
851 ELOG_TYPE_DECLARE (_e) = \
853 .format = "cc_stat: cwnd %u flight %u space %u ssthresh %u snd_wnd %u",\
854 .format_args = "i4i4i4i4i4", \
856 DECLARE_ETD(_tc, _e, 5); \
857 ed->data[0] = _tc->cwnd; \
858 ed->data[1] = tcp_flight_size (_tc); \
859 ed->data[2] = tcp_snd_space (_tc); \
860 ed->data[3] = _tc->ssthresh; \
861 ed->data[4] = _tc->snd_wnd; \
862 TCP_EVT_CC_RTO_STAT_PRINT (_tc); \
863 TCP_EVT_CC_SND_STAT_PRINT (_tc); \
866 #define TCP_EVT_CC_STAT_HANDLER(_tc, ...) \
868 if (tcp_cc_time_to_print_stats (_tc)) \
870 TCP_EVT_CC_STAT_PRINT (_tc); \
871 _tc->c_cc_stat_tstamp = tcp_time_now(); \
875 #define TCP_EVT_CC_STAT_HANDLER(_tc, ...)
881 #if TCP_DEBUG_BUFFER_ALLOCATION
883 #define TCP_DBG_BUFFER_ALLOC_MAYBE_FAIL(thread_index) \
885 static u32 *buffer_fail_counters; \
886 if (PREDICT_FALSE (buffer_fail_counters == 0)) \
889 vlib_thread_main_t *vtm = vlib_get_thread_main (); \
890 num_threads = 1 /* main thread */ + vtm->n_threads; \
891 vec_validate (buffer_fail_counters, num_threads - 1); \
893 if (PREDICT_FALSE (tcp_main.buffer_fail_fraction != 0.0)) \
895 if (PREDICT_TRUE (buffer_fail_counters[thread_index] > 0)) \
897 if ((1.0 / (f32) (buffer_fail_counters[thread_index])) \
898 < tcp_main.buffer_fail_fraction) \
900 buffer_fail_counters[thread_index] = 0.0000001; \
904 buffer_fail_counters[thread_index] ++; \
908 #define TCP_DBG_BUFFER_ALLOC_MAYBE_FAIL(thread_index)
911 #endif /* SRC_VNET_TCP_TCP_DEBUG_H_ */
913 * fd.io coding-style-patch-verification: ON
916 * eval: (c-set-style "gnu")