net: socket: tls: net.socket.tls* fail

[katgiadla]

Describe the bug
Tests:

• tests/net/socket/tls/net.socket.tls
• tests/net/socket/tls/net.socket.tls.sendmsg_no_buf
• tests/net/socket/tls/net.socket.tls.preempt

fail.

Observed for:

• nrf52840dk/nrf52840
• nrf5340dk/nrf5340/cpuapp.

To Reproduce
Steps to reproduce the behavior:

1. have nrf52840dk/nrf52840 connected
2. go to your zephyr dir
3. call ./scripts/twister -T tests/net/socket/tls -p nrf52840dk/nrf52840 --device-testing --device-serial /dev/ttyACM0 -v --inline-logs
4. See console output with error

Expected behavior
Valid console output

Impact
Not clear

Logs and console output

*** Booting Zephyr OS build v3.6.0-3413-g49d6b6bc0e36 ***
Running TESTSUITE net_socket_tls
===================================================================
START - test_accept_invalid_handshake_data
PASS - test_accept_invalid_handshake_data in 0.014 seconds
===================================================================
START - test_accept_non_block
PASS - test_accept_non_block in 0.002 seconds
===================================================================
START - test_close_while_accept
PASS - test_close_while_accept in 0.012 seconds
===================================================================
START - test_close_while_recv
PASS - test_close_while_recv in 0.096 seconds
===================================================================
START - test_connect_closed_port
PASS - test_connect_closed_port in 0.013 seconds
===================================================================
START - test_connect_invalid_handshake_data
PASS - test_connect_invalid_handshake_data in 0.025 seconds
===================================================================
START - test_connect_no_handshake_data
PASS - test_connect_no_handshake_data in 0.030 seconds
===================================================================
START - test_connect_timeout
PASS - test_connect_timeout in 0.113 seconds
===================================================================
START - test_poll_dtls_pollerr
PASS - test_poll_dtls_pollerr in 0.083 seconds
===================================================================
START - test_poll_dtls_pollhup
PASS - test_poll_dtls_pollhup in 0.082 seconds
===================================================================
START - test_poll_dtls_pollin
PASS - test_poll_dtls_pollin in 0.084 seconds
===================================================================
START - test_poll_dtls_pollout
PASS - test_poll_dtls_pollout in 0.079 seconds
===================================================================
START - test_poll_tls_pollerr
PASS - test_poll_tls_pollerr in 0.088 seconds
===================================================================
START - test_poll_tls_pollhup
PASS - test_poll_tls_pollhup in 0.088 seconds
===================================================================
START - test_poll_tls_pollin
PASS - test_poll_tls_pollin in 0.089 seconds
===================================================================
START - test_poll_tls_pollout
Assertion failed at ../../../../../../../zephyr/tests/net/socket/tls/src/main.c:1642: net_socket_tls_test_poll_tls_pollout: (ret not equal to 0)
Unexpected poll() event
FAIL - test_poll_tls_pollout in 0.243 seconds
===================================================================
START - test_recv_block
PASS - test_recv_block in 0.099 seconds
===================================================================
START - test_recv_eof_on_close
PASS - test_recv_eof_on_close in 0.097 seconds
===================================================================
START - test_recv_non_block
PASS - test_recv_non_block in 0.098 seconds
===================================================================
START - test_send_block
PASS - test_send_block in 0.253 seconds
===================================================================
START - test_send_non_block
PASS - test_send_non_block in 0.255 seconds
===================================================================
START - test_send_on_close
PASS - test_send_on_close in 0.178 seconds
===================================================================
START - test_send_while_recv
PASS - test_send_while_recv in 0.112 seconds
===================================================================
START - test_shutdown_rd_synchronous
PASS - test_shutdown_rd_synchronous in 0.094 seconds
===================================================================
START - test_shutdown_rd_while_recv
PASS - test_shutdown_rd_while_recv in 0.104 seconds
===================================================================
START - test_so_protocol
PASS - test_so_protocol in 0.003 seconds
===================================================================
START - test_so_rcvtimeo
PASS - test_so_rcvtimeo in 0.599 seconds
===================================================================
START - test_so_sndtimeo
PASS - test_so_sndtimeo in 0.749 seconds
===================================================================
START - test_so_type
PASS - test_so_type in 0.003 seconds
===================================================================
START - test_v4_dtls_sendmsg
SKIP - test_v4_dtls_sendmsg in 0.001 seconds
===================================================================
START - test_v4_dtls_sendmsg_no_buf
PASS - test_v4_dtls_sendmsg_no_buf in 0.105 seconds
===================================================================
START - test_v4_msg_trunc
PASS - test_v4_msg_trunc in 0.090 seconds
===================================================================
START - test_v4_msg_waitall
PASS - test_v4_msg_waitall in 0.172 seconds
===================================================================
START - test_v6_dtls_sendmsg
SKIP - test_v6_dtls_sendmsg in 0.001 seconds
===================================================================
START - test_v6_dtls_sendmsg_no_buf
PASS - test_v6_dtls_sendmsg_no_buf in 0.109 seconds
===================================================================
START - test_v6_msg_trunc
PASS - test_v6_msg_trunc in 0.094 seconds
===================================================================
START - test_v6_msg_waitall
PASS - test_v6_msg_waitall in 0.173 seconds
===================================================================
TESTSUITE net_socket_tls failed.
------ TESTSUITE SUMMARY START ------
SUITE FAIL -  97.14% [net_socket_tls]: pass = 34, fail = 1, skip = 2, total = 37 duration = 4.530 seconds
- PASS - [net_socket_tls.test_accept_invalid_handshake_data] duration = 0.014 seconds
- PASS - [net_socket_tls.test_accept_non_block] duration = 0.002 seconds
- PASS - [net_socket_tls.test_close_while_accept] duration = 0.012 seconds
- PASS - [net_socket_tls.test_close_while_recv] duration = 0.096 seconds
- PASS - [net_socket_tls.test_connect_closed_port] duration = 0.013 seconds
- PASS - [net_socket_tls.test_connect_invalid_handshake_data] duration = 0.025 seconds
- PASS - [net_socket_tls.test_connect_no_handshake_data] duration = 0.030 seconds
- PASS - [net_socket_tls.test_connect_timeout] duration = 0.113 seconds
- PASS - [net_socket_tls.test_poll_dtls_pollerr] duration = 0.083 seconds
- PASS - [net_socket_tls.test_poll_dtls_pollhup] duration = 0.082 seconds
- PASS - [net_socket_tls.test_poll_dtls_pollin] duration = 0.084 seconds
- PASS - [net_socket_tls.test_poll_dtls_pollout] duration = 0.079 seconds
- PASS - [net_socket_tls.test_poll_tls_pollerr] duration = 0.088 seconds
- PASS - [net_socket_tls.test_poll_tls_pollhup] duration = 0.088 seconds
- PASS - [net_socket_tls.test_poll_tls_pollin] duration = 0.089 seconds
- FAIL - [net_socket_tls.test_poll_tls_pollout] duration = 0.243 seconds
- PASS - [net_socket_tls.test_recv_block] duration = 0.099 seconds
- PASS - [net_socket_tls.test_recv_eof_on_close] duration = 0.097 seconds
- PASS - [net_socket_tls.test_recv_non_block] duration = 0.098 seconds
- PASS - [net_socket_tls.test_send_block] duration = 0.253 seconds
- PASS - [net_socket_tls.test_send_non_block] duration = 0.255 seconds
- PASS - [net_socket_tls.test_send_on_close] duration = 0.178 seconds
- PASS - [net_socket_tls.test_send_while_recv] duration = 0.112 seconds
- PASS - [net_socket_tls.test_shutdown_rd_synchronous] duration = 0.094 seconds
- PASS - [net_socket_tls.test_shutdown_rd_while_recv] duration = 0.104 seconds
- PASS - [net_socket_tls.test_so_protocol] duration = 0.003 seconds
- PASS - [net_socket_tls.test_so_rcvtimeo] duration = 0.599 seconds
- PASS - [net_socket_tls.test_so_sndtimeo] duration = 0.749 seconds
- PASS - [net_socket_tls.test_so_type] duration = 0.003 seconds
- SKIP - [net_socket_tls.test_v4_dtls_sendmsg] duration = 0.001 seconds
- PASS - [net_socket_tls.test_v4_dtls_sendmsg_no_buf] duration = 0.105 seconds
- PASS - [net_socket_tls.test_v4_msg_trunc] duration = 0.090 seconds
- PASS - [net_socket_tls.test_v4_msg_waitall] duration = 0.172 seconds
- SKIP - [net_socket_tls.test_v6_dtls_sendmsg] duration = 0.001 seconds
- PASS - [net_socket_tls.test_v6_dtls_sendmsg_no_buf] duration = 0.109 seconds
- PASS - [net_socket_tls.test_v6_msg_trunc] duration = 0.094 seconds
- PASS - [net_socket_tls.test_v6_msg_waitall] duration = 0.173 seconds
------ TESTSUITE SUMMARY END ------
===================================================================
RunID: 3d54c12ada54711b2a8bde5c216f8e7d
PROJECT EXECUTION FAILED

Environment (please complete the following information):

• OS: Ubuntu 20.04.1 LTS
• Toolchain: Zephyr SDK 0.16.5-1
• Commit SHA or Version used: v3.6.0-3413-g49d6b6bc0e36

[rlubos]

This particular failure has been fixed already with #72334.

I've noticed however some instabilities when running TLS test suite in a repetitive manner (with CONFIG_ZTEST_SHUFFLE enabled). I've submitted a separate PR to help with those instabilities (#72520). Since it's not directly related to the original issue reported here, I think it's ok to close this bug report.