[SR-Users] No audio when calling from SIP to WEBRTC

[Daniel-Constantin Mierla]

Hello,

the private key for creating the tls connection is not found:

May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
[tls_domain.c:1159]: tls_lookup_private_key(): Private key lookup for
SSL_CTX-0x7fb8b0698018: (nil)
May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
[core/tcp_read.c:1527]: tcp_read_req(): EOF

Check the config options for tls module and the server profiles.

Cheers,
Daniel

On 15.05.20 16:23, Chirag Desai wrote:
> So it looks like I do indeed get logs. This is when trying to connect
> wss to 5061. I tried to connect just WS on 5060 and 5061 but still get
> an error.
>
> What's odd is the kamailio.cfg file is a copy of the file I was
> running on another machine. I changed the necessary bits, updated the
> certificates in tls.cfg, but it doesn't connect. On the older machine
> running kamailio the webrtc pages have no issue connecting. Any ideas?
>
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/ip_addr.c:229]: print_ip(): tcpconn_new: new tcp connection:
> [BROWSER_IP]
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:1236]: tcpconn_new(): on port 42610, type 3
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:1555]: tcpconn_add(): hashes: 3294:2497:2595, 5
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/io_wait.h:377]: io_watch_add(): DBG:
> io_watch_add(0x5599fd7a8ae0, 29, 2, 0x7fb8b073bb70), fd_no=19
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del
> (0x5599fd7a8ae0, 29, -1, 0x0) fd_no=20 called
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:4517]: handle_tcpconn_ev(): sending to child, events 1
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:4187]: send2child(): selected tcp worker idx:0
> proc:11 pid:2158 for activity on [tls:[KAMAILIO_PUBLIC_IP]:5061],
> 0x7fb8b073bb70
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:1759]: handle_io(): received n=8 con=0x7fb8b073bb70,
> fd=11
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
> [tls_server.c:199]: tls_complete_init(): completing tls connection
> initialization
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
> [tls_server.c:228]: tls_complete_init(): Using initial TLS domain
> TLSs<default> (dom 0x7fb8b0672518 ctx 0x7fb8b0698018 sn
> [sip.mydomain.com <http://sip.mydomain.com>])
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
> [tls_domain.c:1159]: tls_lookup_private_key(): Private key lookup for
> SSL_CTX-0x7fb8b0698018: (nil)
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
> [tls_domain.c:737]: sr_ssl_ctx_info_callback(): SSL handshake started
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
> [tls_domain.c:938]: tls_server_name_cb(): received server_name (TLS
> extension): 'sip.mydomain.com <http://sip.mydomain.com>'
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
> [tls_domain.c:957]: tls_server_name_cb(): TLS cfg domain selected for
> received server name [sip.mydomain.com <http://sip.mydomain.com>]:
> socket [:0] server name='sip.mydomain.com <http://sip.mydomain.com>' -
> switching SSL CTX to 0x7fb8b0698018 dom 0x7fb8b0672518 (default)
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_main.c:2767]: tcpconn_do_send(): sending...
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_main.c:2801]: tcpconn_do_send(): after real write: c=
> 0x7fb8b073bb70 n=3183 fd=11
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_main.c:2802]: tcpconn_do_send(): buf=#012#026#003#003
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/io_wait.h:377]: io_watch_add(): DBG:
> io_watch_add(0x5599fd8147c0, 11, 2, 0x7fb8b073bb70), fd_no=1
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:198]: tcp_emit_closed_event(): TCP closed event
> creation triggered (reason: 0)
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:206]: tcp_emit_closed_event(): no callback
> registering for handling TCP closed event
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:317]: tcp_read_data(): EOF on 0x7fb8b073bb70, FD 11
> ([[BROWSER_IP]]:42610 ->
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:319]: tcp_read_data(): -> [ IC_IP]]:5061)
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: tls
> [tls_domain.c:1159]: tls_lookup_private_key(): Private key lookup for
> SSL_CTX-0x7fb8b0698018: (nil)
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:1527]: tcp_read_req(): EOF
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del
> (0x5599fd8147c0, 11, -1, 0x10) fd_no=2 called
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:1680]: release_tcpconn(): releasing con
> 0x7fb8b073bb70, state -1, fd=11, id=5 ([[BROWSER_IP]]:42610 ->
> [[BROWSER_IP]]:5061)
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2158]: DEBUG: <core>
> [core/tcp_read.c:1684]: release_tcpconn(): extra_data 0x7fb8b075db18
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:3619]: handle_tcp_child(): reader response=
> 7fb8b073bb70, -1 from 0
> May 15 14:17:12 pluto /usr/local/sbin/kamailio[2162]: DEBUG: tls
> [tls_server.c:683]: tls_h_close(): Closing SSL connection 0x7fb8b075db18
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/ip_addr.c:229]: print_ip(): tcpconn_new: new tcp connection:
> [BROWSER_IP]
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:1236]: tcpconn_new(): on port 42636, type 3
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:1555]: tcpconn_add(): hashes: 3105:2366:2780, 6
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/io_wait.h:377]: io_watch_add(): DBG:
> io_watch_add(0x5599fd7a8ae0, 29, 2, 0x7fb8b073bb70), fd_no=19
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del
> (0x5599fd7a8ae0, 29, -1, 0x0) fd_no=20 called
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:4517]: handle_tcpconn_ev(): sending to child, events 1
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:4187]: send2child(): selected tcp worker idx:1
> proc:12 pid:2159 for activity on [tls:[KAMAILIO_PUBLIC_IP]:5061],
> 0x7fb8b073bb70
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:1759]: handle_io(): received n=8 con=0x7fb8b073bb70,
> fd=11
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: tls
> [tls_server.c:199]: tls_complete_init(): completing tls connection
> initialization
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: tls
> [tls_server.c:228]: tls_complete_init(): Using initial TLS domain
> TLSs<default> (dom 0x7fb8b0672518 ctx 0x7fb8b06997c8 sn
> [sip.mydomain.com <http://sip.mydomain.com>])
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: tls
> [tls_domain.c:1159]: tls_lookup_private_key(): Private key lookup for
> SSL_CTX-0x7fb8b06997c8: (nil)
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: tls
> [tls_domain.c:737]: sr_ssl_ctx_info_callback(): SSL handshake started
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: tls
> [tls_domain.c:938]: tls_server_name_cb(): received server_name (TLS
> extension): 'sip.mydomain.com <http://sip.mydomain.com>'
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: tls
> [tls_domain.c:957]: tls_server_name_cb(): TLS cfg domain selected for
> received server name [sip.mydomain.com <http://sip.mydomain.com>]:
> socket [:0] server name='sip.mydomain.com <http://sip.mydomain.com>' -
> switching SSL CTX to 0x7fb8b06997c8 dom 0x7fb8b0672518 (default)
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_main.c:2767]: tcpconn_do_send(): sending...
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_main.c:2801]: tcpconn_do_send(): after real write: c=
> 0x7fb8b073bb70 n=3183 fd=11
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_main.c:2802]: tcpconn_do_send(): buf=#012#026#003#003
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/io_wait.h:377]: io_watch_add(): DBG:
> io_watch_add(0x5599fd8147c0, 11, 2, 0x7fb8b073bb70), fd_no=1
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:198]: tcp_emit_closed_event(): TCP closed event
> creation triggered (reason: 0)
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:206]: tcp_emit_closed_event(): no callback
> registering for handling TCP closed event
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:317]: tcp_read_data(): EOF on 0x7fb8b073bb70, FD 11
> ([[BROWSER_IP]]:42636 ->
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:319]: tcp_read_data(): -> [[KAMAILIO_PUBLIC_IP]]:5061)
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: tls
> [tls_domain.c:1159]: tls_lookup_private_key(): Private key lookup for
> SSL_CTX-0x7fb8b06997c8: (nil)
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:1527]: tcp_read_req(): EOF
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del
> (0x5599fd8147c0, 11, -1, 0x10) fd_no=2 called
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:1680]: release_tcpconn(): releasing con
> 0x7fb8b073bb70, state -1, fd=11, id=6 ([[BROWSER_IP]]:42636 ->
> [[BROWSER_IP]]:5061)
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2159]: DEBUG: <core>
> [core/tcp_read.c:1684]: release_tcpconn(): extra_data 0x7fb8b075db18
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:3619]: handle_tcp_child(): reader response=
> 7fb8b073bb70, -1 from 1
> May 15 14:17:14 pluto /usr/local/sbin/kamailio[2162]: DEBUG: tls
> [tls_server.c:683]: tls_h_close(): Closing SSL connection 0x7fb8b075db18
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/ip_addr.c:229]: print_ip(): tcpconn_new: new tcp connection:
> [BROWSER_IP]
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:1236]: tcpconn_new(): on port 42662, type 3
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:1555]: tcpconn_add(): hashes: 3083:2324:2806, 7
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/io_wait.h:377]: io_watch_add(): DBG:
> io_watch_add(0x5599fd7a8ae0, 29, 2, 0x7fb8b073bb70), fd_no=19
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del
> (0x5599fd7a8ae0, 29, -1, 0x0) fd_no=20 called
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:4517]: handle_tcpconn_ev(): sending to child, events 1
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:4187]: send2child(): selected tcp worker idx:2
> proc:13 pid:2160 for activity on [tls:[KAMAILIO_PUBLIC_IP]:5061],
> 0x7fb8b073bb70
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:1759]: handle_io(): received n=8 con=0x7fb8b073bb70,
> fd=11
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: tls
> [tls_server.c:199]: tls_complete_init(): completing tls connection
> initialization
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: tls
> [tls_server.c:228]: tls_complete_init(): Using initial TLS domain
> TLSs<default> (dom 0x7fb8b0672518 ctx 0x7fb8b069af78 sn
> [sip.mydomain.com <http://sip.mydomain.com>])
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: tls
> [tls_domain.c:1159]: tls_lookup_private_key(): Private key lookup for
> SSL_CTX-0x7fb8b069af78: (nil)
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: tls
> [tls_domain.c:737]: sr_ssl_ctx_info_callback(): SSL handshake started
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: tls
> [tls_domain.c:938]: tls_server_name_cb(): received server_name (TLS
> extension): 'sip.mydomain.com <http://sip.mydomain.com>'
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: tls
> [tls_domain.c:957]: tls_server_name_cb(): TLS cfg domain selected for
> received server name [sip.mydomain.com <http://sip.mydomain.com>]:
> socket [:0] server name='sip.mydomain.com <http://sip.mydomain.com>' -
> switching SSL CTX to 0x7fb8b069af78 dom 0x7fb8b0672518 (default)
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_main.c:2767]: tcpconn_do_send(): sending...
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_main.c:2801]: tcpconn_do_send(): after real write: c=
> 0x7fb8b073bb70 n=3183 fd=11
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_main.c:2802]: tcpconn_do_send(): buf=#012#026#003#003
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/io_wait.h:377]: io_watch_add(): DBG:
> io_watch_add(0x5599fd8147c0, 11, 2, 0x7fb8b073bb70), fd_no=1
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:198]: tcp_emit_closed_event(): TCP closed event
> creation triggered (reason: 0)
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:206]: tcp_emit_closed_event(): no callback
> registering for handling TCP closed event
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:317]: tcp_read_data(): EOF on 0x7fb8b073bb70, FD 11
> ([[BROWSER_IP]]:42662 ->
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:319]: tcp_read_data(): -> [[KAMAILIO_PUBLIC_IP]]:5061)
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: tls
> [tls_domain.c:1159]: tls_lookup_private_key(): Private key lookup for
> SSL_CTX-0x7fb8b069af78: (nil)
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:1527]: tcp_read_req(): EOF
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del
> (0x5599fd8147c0, 11, -1, 0x10) fd_no=2 called
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:1680]: release_tcpconn(): releasing con
> 0x7fb8b073bb70, state -1, fd=11, id=7 ([[BROWSER_IP]]:42662 ->
> [[BROWSER_IP]]:5061)
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2160]: DEBUG: <core>
> [core/tcp_read.c:1684]: release_tcpconn(): extra_data 0x7fb8b075db18
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: <core>
> [core/tcp_main.c:3619]: handle_tcp_child(): reader response=
> 7fb8b073bb70, -1 from 2
> May 15 14:17:16 pluto /usr/local/sbin/kamailio[2162]: DEBUG: tls
> [tls_server.c:683]: tls_h_close(): Closing SSL connection 0x7fb8b075db18

-- 
Daniel-Constantin Mierla -- www.asipto.com
www.twitter.com/miconda -- www.linkedin.com/in/miconda
Funding: https://www.paypal.me/dcmierla

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.kamailio.org/pipermail/sr-users/attachments/20200519/948ad9d9/attachment.html>