TCP retransmission attempts doesn't work #68013

lmikl · 2024-01-23T16:34:57Z

lmikl
Jan 23, 2024

I have a problem with TCP retransmission attempts. Sometimes this doesn't work and closes the connection.
If a TCP frame is not acknowledged, a retransmission attempt is made but nothing appears in wireshark. Then after having made all the retransmission attempts the communication closes. None of the attempts appear in wireshark.
During this time the frames received in TCP are well acknowledged.
And a frame was received and acknowledged before the first retransmission attempt.
The configuration is the default except:
CONFIG_NET_TCP_INIT_RETRANSMISSION_TIMEOUT=100
CONFIG_NET_TCP_WORKQ_STACK_SIZE=2048
Here are the TCP part logs :
[[2024-01-19 14:14:29.354] [00:01:25.677,000] net_tcp: tcp_send: (mqtt_client_app_thread_id): ACK,PSH Seq=2753360514 Ack=1448468831 Len=709
[2024-01-19 14:14:29.354] uart:$ --- 7 messages dropped ---
[2024-01-19 14:14:29.354] [00:01:25.692,000] net_tcp: net_tcp_queue_data: (mqtt_client_app_thread_id): conn: 0x200257c8 Queued 214 bytes (total 923)
[2024-01-19 14:14:29.370] uart:$ [00:01:25.692,000] net_tcp: tcp_unsent_len: (mqtt_client_app_thread_id): unsent_len=214
[2024-01-19 14:14:29.370] uart:$ [00:01:25.692,000] net_tcp: tcp_unsent_len: (mqtt_client_app_thread_id): unsent_len=214
[2024-01-19 14:14:29.370] uart:$ [00:01:25.692,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=0
[2024-01-19 14:14:29.370] uart:$ [00:01:25.753,000] net_tcp: tcp_in: (rx_q[0]): Lowering send window from 65535 to 1536
[2024-01-19 14:14:29.370] uart:$ [00:01:25.753,000] net_tcp: tcp_window_full: (rx_q[0]): conn: 0x200257c8 window_full=0
[2024-01-19 14:14:29.370] uart:$ [00:01:25.805,000] net_tcp: tcp_unsent_len: (mqtt_client_app_thread_id): unsent_len=428
[2024-01-19 14:14:29.370] uart:$ [00:01:25.805,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=0
[2024-01-19 14:14:29.370] uart:$ [00:01:25.820,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=0
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_new_reno_log: (tcp_work): conn: 0x200257c8, ca timeout, cwnd=1460, ssthres=2920, fast_pend=0
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_window_full: (tcp_work): conn: 0x200257c8 window_full=0
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1137
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1137
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.401] uart:$ [00:01:25.820,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1137, unacked_len=1137, send_win=1536, mss=1460
[2024-01-19 14:14:29.417] uart:$ [00:01:25.820,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=0
[2024-01-19 14:14:29.417] uart:$ uart:$ uart:$ --- 3 messages dropped ---
[2024-01-19 14:14:29.848] [00:01:26.028,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.848] uart:$ [00:01:26.028,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.848] uart:$ [00:01:26.028,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.848] uart:$ [00:01:26.028,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1137, unacked_len=1137, send_win=1536, mss=1460
[2024-01-19 14:14:29.848] uart:$ [00:01:26.028,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=1
[2024-01-19 14:14:29.848] uart:$ [00:01:26.338,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=2
[2024-01-19 14:14:29.848] uart:$ [00:01:26.338,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1137
[2024-01-19 14:14:29.848] uart:$ [00:01:26.338,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1137
[2024-01-19 14:14:29.864] uart:$ [00:01:26.338,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.864] uart:$ [00:01:26.338,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.864] uart:$ [00:01:26.338,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1137
[2024-01-19 14:14:29.864] uart:$ [00:01:26.339,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1137, unacked_len=1137, send_win=1536, mss=1460
[2024-01-19 14:14:29.864] uart:$ [00:01:26.339,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=2
[2024-01-19 14:14:29.864] uart:$ uart:$ uart:$ [00:01:26.694,000] net_mqtt_client_app: send notification
[2024-01-19 14:14:30.271] uart:$ [00:01:26.694,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=0
[2024-01-19 14:14:30.271] uart:$ [00:01:26.694,000] net_tcp: net_tcp_queue_data: (mqtt_client_app_thread_id): conn: 0x200257c8 Queued 709 bytes (total 1846)
[2024-01-19 14:14:30.271] uart:$ [00:01:26.694,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:30.271] uart:$ [00:01:26.804,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=3
[2024-01-19 14:14:30.271] uart:$ [00:01:26.804,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1460
[2024-01-19 14:14:30.271] uart:$ [00:01:26.804,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:30.271] uart:$ [00:01:26.804,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:30.286] uart:$ [00:01:26.804,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:30.286] uart:$ [00:01:26.804,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1846, unacked_len=1460, send_win=1536, mss=1460
[2024-01-19 14:14:30.286] uart:$ [00:01:26.804,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=3
[2024-01-19 14:14:30.286] uart:$ uart:$ uart:$ [00:01:27.501,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=4
[2024-01-19 14:14:31.180] uart:$ [00:01:27.501,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1460
[2024-01-19 14:14:31.180] uart:$ [00:01:27.501,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:31.180] uart:$ [00:01:27.501,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:31.180] uart:$ [00:01:27.502,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:31.180] uart:$ [00:01:27.502,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1846, unacked_len=1460, send_win=1536, mss=1460
[2024-01-19 14:14:31.180] uart:$ [00:01:27.502,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=4
[2024-01-19 14:14:31.180] uart:$ [00:01:27.704,000] net_mqtt_client_app: send notification
[2024-01-19 14:14:31.195] uart:$ [00:01:27.705,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:31.195] uart:$ [00:01:27.715,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:31.195] uart:$ [00:01:27.735,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:31.195] uart:$ uart:$ uart:$ --- 1 messages dropped ---
[2024-01-19 14:14:32.092] [00:01:27.856,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.092] uart:$ [00:01:27.956,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.092] uart:$ [00:01:28.056,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.092] uart:$ [00:01:28.156,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.092] uart:$ [00:01:28.257,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.092] uart:$ [00:01:28.357,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.092] uart:$ [00:01:28.457,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.107] uart:$ [00:01:28.547,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=5
[2024-01-19 14:14:32.107] uart:$ [00:01:28.547,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1460
[2024-01-19 14:14:32.107] uart:$ [00:01:28.547,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:32.107] uart:$ [00:01:28.547,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:32.107] uart:$ [00:01:28.547,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:32.107] uart:$ [00:01:28.547,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1846, unacked_len=1460, send_win=1536, mss=1460
[2024-01-19 14:14:32.107] uart:$ [00:01:28.547,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=5
[2024-01-19 14:14:32.107] uart:$ [00:01:28.557,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.123] uart:$ [00:01:28.657,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:32.123] uart:$ uart:$ uart:$ [00:01:28.758,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:28.858,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:28.958,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:29.058,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:29.158,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:29.259,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:29.359,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:29.459,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:29.559,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ [00:01:29.659,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.130] uart:$ uart:$ uart:$ [00:01:29.760,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.608] uart:$ [00:01:29.860,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.608] uart:$ [00:01:29.960,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.608] uart:$ [00:01:30.060,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.608] uart:$ [00:01:30.114,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=6
[2024-01-19 14:14:33.608] uart:$ [00:01:30.114,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1460
[2024-01-19 14:14:33.608] uart:$ [00:01:30.115,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:33.608] uart:$ [00:01:30.115,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:33.624] uart:$ [00:01:30.115,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469045 Len=1460
[2024-01-19 14:14:33.624] uart:$ [00:01:30.115,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1846, unacked_len=1460, send_win=1536, mss=1460
[2024-01-19 14:14:33.624] uart:$ [00:01:30.115,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=6
[2024-01-19 14:14:33.624] uart:$ [00:01:30.160,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:33.624] uart:$ uart:$ uart:$ [00:01:30.261,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.625] uart:$ [00:01:30.361,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.625] uart:$ [00:01:30.461,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.625] uart:$ [00:01:30.561,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.625] uart:$ [00:01:30.661,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.625] uart:$ [00:01:30.762,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.625] uart:$ [00:01:30.862,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.625] uart:$ [00:01:30.962,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.641] uart:$ [00:01:31.062,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.641] uart:$ [00:01:31.162,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.641] uart:$ uart:$ uart:$ --- 4 messages dropped ---
[2024-01-19 14:14:34.834] [00:01:31.232,000] net_tcp: tcp_send_process_no_lock: (rx_q[0]): ACK Seq=2753360514 Ack=1448469259 Len=0
[2024-01-19 14:14:34.834] uart:$ [00:01:31.232,000] net_tcp: tcp_send: (rx_q[0]): ACK Seq=2753360514 Ack=1448469259 Len=0
[2024-01-19 14:14:34.834] uart:$ [00:01:31.232,000] net_tcp: tcp_window_full: (rx_q[0]): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.834] uart:$ [00:01:31.263,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.834] uart:$ [00:01:31.356,000] net_tcp: tcp_in: (rx_q[0]): ACK,PSH Seq=1448469259 Ack=2753360514 Len=214 [ESTABLISHED Seq=2753360514 Ack=1448469259]
[2024-01-19 14:14:34.834] uart:$ [00:01:31.356,000] net_tcp: tcp_in: (rx_q[0]): Lowering send window from 65535 to 1536
[2024-01-19 14:14:34.834] uart:$ [00:01:31.356,000] net_tcp: tcp_window_full: (rx_q[0]): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.834] uart:$ [00:01:31.356,000] net_tcp: tcp_out_ext: (rx_q[0]): ACK Seq=2753360514 Ack=1448469473 Len=0
[2024-01-19 14:14:34.865] uart:$ [00:01:31.356,000] net_tcp: tcp_send_process_no_lock: (rx_q[0]): ACK Seq=2753360514 Ack=1448469473 Len=0
[2024-01-19 14:14:34.865] uart:$ [00:01:31.356,000] net_tcp: tcp_send: (rx_q[0]): ACK Seq=2753360514 Ack=1448469473 Len=0
[2024-01-19 14:14:34.865] uart:$ [00:01:31.356,000] net_tcp: tcp_window_full: (rx_q[0]): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.865] uart:$ [00:01:31.363,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:34.865] uart:$ uart:$ uart:$ [00:01:31.463,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.827] uart:$ [00:01:31.563,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.827] uart:$ [00:01:31.663,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.827] uart:$ [00:01:31.764,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.827] uart:$ [00:01:31.864,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.827] uart:$ [00:01:31.964,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.827] uart:$ [00:01:32.064,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.827] uart:$ [00:01:32.164,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.843] uart:$ [00:01:32.265,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.843] uart:$ [00:01:32.365,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:35.843] uart:$ uart:$ uart:$ [00:01:32.465,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:36.130] uart:$ [00:01:32.465,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=7
[2024-01-19 14:14:36.130] uart:$ [00:01:32.465,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1460
[2024-01-19 14:14:36.130] uart:$ [00:01:32.465,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469473 Len=1460
[2024-01-19 14:14:36.130] uart:$ [00:01:32.465,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469473 Len=1460
[2024-01-19 14:14:36.130] uart:$ [00:01:32.465,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469473 Len=1460
[2024-01-19 14:14:36.130] uart:$ [00:01:32.466,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1846, unacked_len=1460, send_win=1536, mss=1460
[2024-01-19 14:14:36.130] uart:$ [00:01:32.466,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=7
[2024-01-19 14:14:36.145] uart:$ [00:01:32.566,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:36.145] uart:$ [00:01:32.666,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:36.145] uart:$ uart:$ uart:$ [00:01:32.766,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.125] uart:$ [00:01:32.866,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.125] uart:$ [00:01:32.967,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.125] uart:$ [00:01:33.067,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.125] uart:$ [00:01:33.167,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.125] uart:$ [00:01:33.267,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.125] uart:$ [00:01:33.367,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.125] uart:$ [00:01:33.468,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.140] uart:$ [00:01:33.568,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.140] uart:$ [00:01:33.668,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:37.140] uart:$ uart:$ uart:$ [00:01:33.768,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:33.868,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:33.969,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:34.069,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:34.169,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:34.269,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:34.369,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:34.470,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:34.570,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ [00:01:34.670,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:38.144] uart:$ uart:$ uart:$ [00:01:34.770,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.143] uart:$ [00:01:34.870,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.143] uart:$ [00:01:34.971,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.143] uart:$ [00:01:35.071,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.143] uart:$ [00:01:35.171,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.143] uart:$ [00:01:35.271,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.143] uart:$ [00:01:35.371,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.143] uart:$ [00:01:35.472,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.159] uart:$ [00:01:35.572,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.159] uart:$ [00:01:35.672,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.159] uart:$ uart:$ uart:$ [00:01:35.772,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.462] uart:$ [00:01:35.872,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.462] uart:$ [00:01:35.973,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.462] uart:$ [00:01:35.991,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=8
[2024-01-19 14:14:39.462] uart:$ [00:01:35.991,000] net_tcp: tcp_unsent_len: (tcp_work): unsent_len=1460
[2024-01-19 14:14:39.462] uart:$ [00:01:35.991,000] net_tcp: tcp_out_ext: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469473 Len=1460
[2024-01-19 14:14:39.462] uart:$ [00:01:35.991,000] net_tcp: tcp_send_process_no_lock: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469473 Len=1460
[2024-01-19 14:14:39.462] uart:$ [00:01:35.991,000] net_tcp: tcp_send: (tcp_work): ACK,PSH Seq=2753360514 Ack=1448469473 Len=1460
[2024-01-19 14:14:39.477] uart:$ [00:01:35.991,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 total=1846, unacked_len=1460, send_win=1536, mss=1460
[2024-01-19 14:14:39.477] uart:$ [00:01:35.991,000] net_tcp: tcp_send_data: (tcp_work): conn: 0x200257c8 send_data_timer=0, send_data_retries=8
[2024-01-19 14:14:39.477] uart:$ uart:$ uart:$ [00:01:36.073,000] net_tcp: tcp_window_full: (mqtt_client_app_thread_id): conn: 0x200257c8 window_full=1
[2024-01-19 14:14:39.667] uart:$ [00:01:36.129,000] net_tcp: tcp_resend_data: (tcp_work): send_data_retries=9
[2024-01-19 14:14:39.667] uart:$ [00:01:36.129,000] net_tcp: tcp_resend_data: (tcp_work): conn: 0x200257c8 close, data retransmissions exceeded
[2024-01-19 14:14:39.667] uart:$ [00:01:36.129,000] net_tcp: tcp_conn_close_debug: (tcp_work): conn: 0x200257c8 closed by TCP stack (tcp_resend_data():1515)
[2024-01-19 14:14:39.667] uart:$ [00:01:36.129,000] net_tcp: tcp_conn_close_debug: (tcp_work): ESTABLISHED->CLOSED
[2024-01-19 14:14:39.667] uart:$ [00:01:36.129,000] net_tcp: tcp_conn_unref: (tcp_work): conn: 0x200257c8, ref_count=2
[2024-01-19 14:14:39.667] uart:$ [00:01:36.173,000] net_mqtt: Transport write failed, err_code = -128, closing connection
[2024-01-19 14:14:39.667] uart:$ [00:01:36.173,000] net_tcp: net_tcp_recv: (mqtt_client_app_thread_id): context: 0x200063c8, cb: 0, user_data: 0
[2024-01-19 14:14:39.682] uart:$ [00:01:36.173,000] net_tcp: net_tcp_put: (mqtt_client_app_thread_id): [CLOSED Seq=2753360514 Ack=1448469473]
[2024-01-19 14:14:39.682] uart:$ [00:01:36.173,000] net_tcp: net_tcp_put: (mqtt_client_app_thread_id): context 0x200063c8 CONNECTED
[2024-01-19 14:14:39.682] uart:$ [00:01:36.173,000] net_tcp: tcp_conn_unref: (mqtt_client_app_thread_id): conn: 0x200257c8, ref_count=1
[2024-01-19 14:14:39.682] uart:$ [00:01:36.173,000] net_mqtt_client: MQTT client disconnected -128
[2024-01-19 14:14:39.682] uart:$ [00:01:36.173,000] net_mqtt_client_app: publish err 1, 01, 1
[2024-01-19 14:14:39.682] uart:$ uart:$ uart:$

jukkar · 2024-01-25T19:39:46Z

jukkar
Jan 25, 2024
Collaborator

Is this the latest upstream zephyr version or some earlier one?

You could try to make TX window smaller and optionally perhaps increase the TX buffer count.
See this document about how to configure various network options
https://docs.zephyrproject.org/latest/connectivity/networking/net_config_guide.html#tcp-options

BTW, you could try to increase the CONFIG_LOG_BUFFER_SIZE to avoid the messages dropped prints.

2 replies

lmikl Jan 29, 2024
Author

it's zephyr 3.5.0

I try with CONFIG_NET_TCP_MAX_SEND_WINDOW_SIZE=0 and i have the same result.

jukkar Jan 29, 2024
Collaborator

No, do not set it to 0. You should select a value (it is in bytes), how much data you are able to queue locally. The number depends on your net_buf count. There was some fixes to the TCP queueing after 3.5 was released, could you try with the latest upstream?

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Uh oh!

TCP retransmission attempts doesn't work #68013

Uh oh!

{{title}}

Uh oh!

Replies: 1 comment 2 replies

Uh oh!

{{title}}

Uh oh!

Uh oh!

{{title}}

Uh oh!

Uh oh!

{{editor}}'s edit

{{editor}}'s edit

Uh oh!

Uh oh!

{{title}}

Uh oh!

Select a reply

Uh oh!

TCP retransmission attempts doesn't work #68013

Uh oh!

lmikl Jan 23, 2024

Replies: 1 comment · 2 replies

Uh oh!

jukkar Jan 25, 2024 Collaborator

Uh oh!

Uh oh!

lmikl Jan 29, 2024 Author

Uh oh!

jukkar Jan 29, 2024 Collaborator

lmikl
Jan 23, 2024

Replies: 1 comment 2 replies

jukkar
Jan 25, 2024
Collaborator

lmikl Jan 29, 2024
Author

jukkar Jan 29, 2024
Collaborator