[OpenSIPS-Users] Oopensips 2.2.1 wss Crash

[Răzvan Crainea]

Hi, Kirill!

My assumption is that this is something related to this bug[1], reported 
by Tito Cumpen. Could you please update your sources to the latest 2.2 
version and let us know if this is still happening?

[1] 
https://github.com/OpenSIPS/opensips/commit/39e40f93fe5bc76c58c5b64f8b32b327b975f62e

Best regards,

Răzvan Crainea
OpenSIPS Solutions
www.opensips-solutions.com

On 09/20/2016 07:35 AM, Kirill Galinurov wrote:
> Hi,All I testing wss in Opensips 2.2.1. Sometimes it's crashed
>
>   19 17:46:00 [32693] INFO:core:init_sock_keepalive: TCP keepalive
> enabled on socket 24
> Sep 19 17:46:00 [32693] DBG:core:print_ip: tcpconn_new: new tcp
> connection to: 194.186.5.238
> Sep 19 17:46:00 [32693] DBG:core:tcpconn_new: on port 35888, proto 6
> Sep 19 17:46:00 [32693] DBG:proto_wss:tls_conn_init: entered: Creating
> a whole new ssl connection
> Sep 19 17:46:00 [32693] DBG:proto_wss:tls_conn_init: looking up socket
> based TLS server domain [92.53.102.226:8443]
> Sep 19 17:46:00 [32693] DBG:tls_mgm:tls_find_server_domain: virtual
> TLS server domain not found, Using default TLS server domain settin
> gs
> Sep 19 17:46:00 [32693] DBG:proto_wss:tls_conn_init: found socket
> based TLS server domain [0.0.0.0:0]
> Sep 19 17:46:00 [32693] DBG:proto_wss:tls_conn_init: Setting in ACCEPT
> mode (server)
> Sep 19 17:46:00 [32693] DBG:core:tcpconn_add: hashes: 754, 98
> Sep 19 17:46:00 [32693] DBG:core:handle_new_connect: new connection:
> 0x7f4b8ab6cab0 24 flags: 0006
> Sep 19 17:46:00 [32693] DBG:core:send2child: no free tcp receiver,
> connection passed to the least busy one (proc #1, 1 con)
> Sep 19 17:46:00 [32693] DBG:core:send2child: to tcp child 1 0(32692),
> 0x7f4b8ab6cab0 rw 1
> Sep 19 17:46:00 [32692] DBG:core:handle_io: We have received conn
> 0x7f4b8ab6cab0 with rw 1 on fd 25
> Sep 19 17:46:00 [32692] DBG:core:io_watch_add: [TCP_worker]
> io_watch_add op (25 on 5) (0x7e2fc0, 25, 19, 0x7f4b8ab6cab0,1),
> fd_no=3/102
> 4
> Sep 19 17:46:00 [32692] DBG:proto_wss:tls_update_fd: New fd is 25
> Sep 19 17:46:00 [32693] DBG:core:io_wait_loop_epoll: [TCP_main]
> EPOLLHUP on IN ->connection closed by the remote peer!
> Sep 19 17:46:00 [32693] DBG:core:handle_tcp_worker: dead tcp worker 1
> (pid 32692, no 0) (shutting down?)
> Sep 19 17:46:00 [32693] DBG:core:io_watch_del: [TCP_main] io_watch_del
> op on index 10 18 (0x7e2fc0, 18, 10, 0x0,0x1) fd_no=11 called
> Sep 19 17:46:00 [32693] DBG:core:io_wait_loop_epoll: [TCP_main]
> EPOLLHUP on IN ->connection closed by the remote peer!
> Sep 19 17:46:00 [32693] CRITICAL:core:receive_fd: EOF on 21
> Sep 19 17:46:00 [32693] DBG:core:handle_worker: dead child 6, pid
> 32692 (shutting down?)
> Sep 19 17:46:00 [32693] DBG:core:io_watch_del: [TCP_main] io_watch_del
> op on index 8 21 (0x7e2fc0, 21, 8, 0x0,0x1) fd_no=10 called
> Sep 19 17:46:00 [32686] DBG:core:handle_sigs: status = 139
> Sep 19 17:46:00 [32686] INFO:core:handle_sigs: child process 32692
> exited by a signal 11
> Sep 19 17:46:00 [32686] INFO:core:handle_sigs: core was generated
> Sep 19 17:46:00 [32686] INFO:core:handle_sigs: terminating due to SIGCHLD
> Sep 19 17:46:00 [32688] INFO:core:sig_usr: signal 15 received
> Sep 19 17:46:00 [32687] INFO:core:sig_usr: signal 15 received
> Sep 19 17:46:00 [32690] INFO:core:sig_usr: signal 15 received
> Sep 19 17:46:00 [32691] INFO:core:sig_usr: signal 15 received
> Sep 19 17:46:00 [32689] INFO:core:sig_usr: signal 15 received
> Sep 19 17:46:00 [32693] INFO:core:sig_usr: signal 15 received
> Sep 19 17:46:00 [32686] INFO:core:cleanup: cleanup
> Sep 19 17:46:00 [32686] DBG:uac_auth:mod_destroy: done
> Sep 19 17:46:00 [32686] DBG:core:pool_remove: removing connection from the pool
> Sep 19 17:46:00 [32686] DBG:db_postgres:db_postgres_free_connection:
> PQfinish(0xb8df30)
> Sep 19 17:46:00 [32686] DBG:db_postgres:db_postgres_free_connection:
> pkg_free(0x7f4b8c805150)
> Sep 19 17:46:00 [32686] DBG:tls_mgm:mod_destroy: entered
> Sep 19 17:46:00 [32686] DBG:tm:tm_shutdown: tm_shutdown : start
> Sep 19 17:46:00 [32686] DBG:tm:unlink_timer_lists: emptying DELETE
> list for set 0
> Sep 19 17:46:00 [32686] DBG:tm:tm_shutdown: emptying hash table
> Sep 19 17:46:00 [32686] DBG:tm:tm_shutdown: releasing timers
> Sep 19 17:46:00 [32686] DBG:tm:tm_shutdown: removing semaphores
> Sep 19 17:46:00 [32686] DBG:tm:tm_shutdown: destroying callback lists
> Sep 19 17:46:00 [32686] DBG:tm:tm_shutdown: tm_shutdown : done
> Sep 19 17:46:00 [32686] DBG:proto_wss:tls_update_fd: New fd is 24
> Sep 19 17:46:00 [32686] ERROR:proto_wss:tls_write: TLS connection to
> 62.183.124.129:32224 write failed
> Sep 19 17:46:00 [32686] ERROR:proto_wss:tls_write: TLS write error:
> Sep 19 17:46:00 [32686] ERROR:proto_wss:tls_blocking_write: TLS failed
> to send data
> Sep 19 17:46:00 [32686] DBG:proto_wss:tls_conn_clean: entered
> Sep 19 17:46:00 [32686] DBG:proto_wss:tls_update_fd: New fd is 22
> Sep 19 17:47:00 [32686] CRITICAL:core:sig_alarm_abort: BUG - shutdown
> timeout triggered, dying...Aborted (core dumped)
>
>
> (gdb) bt full
> #0  0x00007f4b88c28669 in ?? ()
> No symbol table info available.
> #1  0x0000000000000001 in ?? ()
> No symbol table info available.
> #2  0x00007f4b8ab89c00 in ?? ()
> No symbol table info available.
> #3  0x0000000000008548 in ?? ()
> No symbol table info available.
> #4  0x0000000000000000 in ?? ()
> No symbol table info available.
>
> Core was generated by `opensips'.
> Program terminated with signal 6, Aborted.
> #0  0x00007f4b8ca0d5f7 in ?? ()
> "/root/./core.32686" is a core file
>
> (gdb) bt full
> #0  0x00007f4b8ca0d5f7 in ?? ()
> No symbol table info available.
> #1  0x00007f4b8ca0ece8 in ?? ()
> No symbol table info available.
> #2  0x0000000000000020 in ?? ()
> No symbol table info available.
> #3  0x0000000000000000 in ?? ()
> No symbol table info available.
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users