[OpenSIPS-Devel] Crash in trunk

[Dan Pascu]

I found the trace below in the logs today. Any idea what all the "Resource temporary unavailable" mean? Are they related to trying to read from /dev/random or /dev/urandom? From what I see multiple processes had some issue, and while some only reported this as "resource temporary unavailable", one process crashed by accessing illegal memory.

Aug 07 03:07:48 node15 ./opensips[12664]: ERROR:proto_tls:tls_accept: TLS error: (ret=-1, err=1, errno=11/Resource temporarily unavailable):
Aug 07 03:07:48 node15 ./opensips[12664]: ERROR:proto_tls:tls_print_errstack: TLS errstack: error:24067044:random number generator:rand_pool_add:internal error
Aug 07 03:07:48 node15 ./opensips[12664]: ERROR:proto_tls:tls_read_req: failed to do pre-tls reading
Aug 07 03:07:48 node15 ./opensips[12665]: CRITICAL:core:sig_usr: segfault in process pid: 12665, id: 13
Aug 07 03:07:48 node15 ./opensips[12665]: =================================================================
Aug 07 03:07:48 node15 ./opensips[12665]:
Aug 07 03:07:48 node15 ./opensips[12665]: ==12665==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000004 (pc 0x7f792d1fb5e2 bp 0x000000000010 sp 0x7ffc2e45f210 T0)
Aug 07 03:07:48 node15 ./opensips[12665]:
Aug 07 03:07:48 node15 ./opensips[12665]: ==12665==The signal is caused by a READ memory access.
Aug 07 03:07:48 node15 ./opensips[12665]:
Aug 07 03:07:48 node15 ./opensips[12665]: ==12665==Hint: address points to the zero page.
Aug 07 03:07:48 node15 ./opensips[12665]:
Aug 07 03:07:48 node15 ./opensips[12665]:     #0 0x7f792d1fb5e1 in EVP_DecryptUpdate (/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1+0x1835e1)
Aug 07 03:07:48 node15 ./opensips[12665]:     #1 0x7f792d22dbb3  (/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1+0x1b5bb3)
Aug 07 03:07:48 node15 ./opensips[12665]:     #2 0x7f792d22e222  (/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1+0x1b6222)
Aug 07 03:07:48 node15 ./opensips[12665]:     #3 0x7f792d22ebd0 in RAND_DRBG_reseed (/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1+0x1b6bd0)
Aug 07 03:07:48 node15 ./opensips[12665]:     #4 0x7f792d22f069 in RAND_DRBG_generate (/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1+0x1b7069)
Aug 07 03:07:48 node15 ./opensips[12665]:     #5 0x7f792d22f280 in RAND_DRBG_bytes (/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1+0x1b7280)
Aug 07 03:07:48 node15 ./opensips[12665]:     #6 0x7f792d39569c  (/usr/lib/x86_64-linux-gnu/libssl.so.1.1+0x2d69c)
Aug 07 03:07:48 node15 ./opensips[12665]:     #7 0x7f792d3c6a48  (/usr/lib/x86_64-linux-gnu/libssl.so.1.1+0x5ea48)
Aug 07 03:07:48 node15 ./opensips[12665]:     #8 0x7f792d3b64da  (/usr/lib/x86_64-linux-gnu/libssl.so.1.1+0x4e4da)
Aug 07 03:07:48 node15 ./opensips[12665]:     #9 0x7f792d3a2203 in SSL_do_handshake (/usr/lib/x86_64-linux-gnu/libssl.so.1.1+0x3a203)
Aug 07 03:07:48 node15 ./opensips[12665]:     #10 0x7f794d53c007 in tls_accept ../tls_mgm/tls_conn_server.h:270
Aug 07 03:07:48 node15 ./opensips[12665]:     #11 0x7f794d53f8d7 in tls_fix_read_conn ../tls_mgm/tls_conn_server.h:529
Aug 07 03:07:48 node15 ./opensips[12665]:     #12 0x7f794d54ae42 in tls_read_req modules/proto_tls/proto_tls.c:546
Aug 07 03:07:48 node15 ./opensips[12665]:     #13 0x5578e595a622 in handle_io net/net_tcp_proc.c:301
Aug 07 03:07:48 node15 ./opensips[12665]:     #14 0x5578e595593a in io_wait_loop_epoll net/../io_wait_loop.h:280
Aug 07 03:07:48 node15 ./opensips[12665]:     #15 0x5578e595c11c in tcp_worker_proc_loop net/net_tcp_proc.c:411
Aug 07 03:07:48 node15 ./opensips[12665]:     #16 0x5578e598e926 in tcp_start_processes net/net_tcp.c:2076
Aug 07 03:07:48 node15 ./opensips[12665]:     #17 0x5578e573ffb4 in main_loop main.c:801
Aug 07 03:07:48 node15 ./opensips[12665]:     #18 0x5578e57464c6 in main main.c:1480
Aug 07 03:07:48 node15 ./opensips[12665]:     #19 0x7f795039c09a in __libc_start_main ../csu/libc-start.c:308
Aug 07 03:07:48 node15 ./opensips[12665]:     #20 0x5578e559acc9 in _start (/home/dan/work/opensips/build/test/opensips-xs/opensips+0xe0cc9)
Aug 07 03:07:48 node15 ./opensips[12665]:
Aug 07 03:07:48 node15 ./opensips[12665]: AddressSanitizer can not provide additional info.
Aug 07 03:07:48 node15 ./opensips[12665]:
Aug 07 03:07:48 node15 ./opensips[12665]: SUMMARY: AddressSanitizer: SEGV (/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1+0x1835e1) in EVP_DecryptUpdate
Aug 07 03:07:48 node15 ./opensips[12665]:
Aug 07 03:07:48 node15 ./opensips[12665]: ==12665==ABORTING
Aug 07 03:07:48 node15 ./opensips[12665]:


Aug 07 03:07:49 node15 ./opensips[12649]: ERROR:proto_tls:tls_conn_shutdown: something wrong in SSL: 1, 11, Resource temporarily unavailable
Aug 07 03:07:49 node15 ./opensips[12649]: ERROR:proto_tls:tls_print_errstack: TLS errstack: error:140E0197:SSL routines:SSL_shutdown:shutdown while in init


--
Dan