[OpenSIPS-Users] What does happen when location table has records for peer that is offline?

Bogdan-Andrei Iancu bogdan at opensips.org
Wed Oct 5 15:27:48 CEST 2016 

Hi Rodrigo,

Yes, probably there were TCP contacts left over in location table - and 
when OpenSIPS was trying to use them, it was attempting to open a TCP 
connection to the IP indicated by the contact (where of course, nobody 
was listening).

That is SIP :). Maybe you should try to lower the registration duration 
for TCP contacts. OR , if using 2.2, you can do pinging with 
auto-removal of un-reachable contacts.

Regards,

Bogdan-Andrei Iancu
OpenSIPS Founder and Developer
http://www.opensips-solutions.com

On 04.10.2016 16:26, Rodrigo Pimenta Carvalho wrote:
>
> Hi.
>
> In my table location (in OpenSIPS) I found some records for a peer 
> that was not online. That is, some peer offline let some registers in 
> the location table.
>
> For example: the peer A registered itself using several different 
> devices, then turned off all of such devices.
>
>
> Such records would expires sometime in the future.
>
>
> Then, peer B called A, and in this moment I saw a log with several 
> erros, from OpenSIPs.
>
> Does such logs relates to these invalid records in table location?
>
>
> After removing these records (opensipsctl ul rm ....) those messages 
> stopped appearing in the log. See the log below.
>
>
> Any hint will be very helpful!
>
> Best regards.
>
>
> RODRIGO PIMENTA CARVALHO
> Inatel Competence Center
> Software
> Ph: +55 35 3471 9200 RAMAL 979
>
>
> ------------------------------------------------------------------------
>
>
> Jan 01 01:19:16 colibri-imx6 opensips[609]: new branch at 
> sip:g1r2u3p4o5 at 127.0.0.1:47340;transport=TCP;ob
> Jan 01 01:19:16 colibri-imx6 opensips[609]: new branch at 
> sip:g1r2u3p4o5 at 127.0.0.1:54112;transport=TCP;ob
> Jan 01 01:19:16 colibri-imx6 opensips[609]: new branch at 
> sip:g1r2u3p4o5 at 127.0.0.1:38220;transport=TCP;ob
> Jan 01 01:19:16 colibri-imx6 opensips[609]: new branch at 
> sip:g1r2u3p4o5 at 127.0.0.1:54112;transport=TCP;ob
> Jan 01 01:19:16 colibri-imx6 opensips[609]: new branch at 
> sip:g1r2u3p4o5 at 127.0.0.1:38220;transport=TCP;ob
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> INFO:core:probe_max_sock_buff: using snd buffer of 320 kb
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> INFO:core:init_sock_keepalive: TCP keepalive enabled on socket 21
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:tcpconn_async_connect: poll error: flags 1c
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:tcpconn_async_connect: failed to retrieve SO_ERROR 
> [server=127.0.0.1:47340] (111) Connection refused
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:proto_tcp_send: async TCP connect failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:tm:msg_send: send() for proto 2 failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:tm:t_forward_nonack: sending request failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> INFO:core:probe_max_sock_buff: using snd buffer of 320 kb
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> INFO:core:init_sock_keepalive: TCP keepalive enabled on socket 21
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:tcpconn_async_connect: poll error: flags 1c
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:tcpconn_async_connect: failed to retrieve SO_ERROR 
> [server=127.0.0.1:54112] (111) Connection refused
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:proto_tcp_send: async TCP connect failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:tm:msg_send: send() for proto 2 failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:tm:t_forward_nonack: sending request failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> INFO:core:probe_max_sock_buff: using snd buffer of 320 kb
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> INFO:core:init_sock_keepalive: TCP keepalive enabled on socket 21
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:tcpconn_async_connect: poll error: flags 1c
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:tcpconn_async_connect: failed to retrieve SO_ERROR 
> [server=127.0.0.1:54112] (111) Connection refused
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:core:proto_tcp_send: async TCP connect failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:tm:msg_send: send() for proto 2 failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [616] 
> ERROR:tm:t_forward_nonack: sending request failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> INFO:core:probe_max_sock_buff: using snd buffer of 320 kb
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> INFO:core:init_sock_keepalive: TCP keepalive enabled on socket 23
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:core:tcpconn_async_connect: poll error: flags 1c
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:core:tcpconn_async_connect: failed to retrieve SO_ERROR 
> [server=127.0.0.1:47340] (111) Connection refused
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:core:proto_tcp_send: async TCP connect failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:tm:msg_send: send() for proto 2 failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:tm:t_forward_nonack: sending request failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> INFO:core:probe_max_sock_buff: using snd buffer of 320 kb
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> INFO:core:init_sock_keepalive: TCP keepalive enabled on socket 23
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:core:tcpconn_async_connect: poll error: flags 1c
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:core:tcpconn_async_connect: failed to retrieve SO_ERROR 
> [server=127.0.0.1:54112] (111) Connection refused
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:core:proto_tcp_send: async TCP connect failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:tm:msg_send: send() for proto 2 failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:16 [617] 
> ERROR:tm:t_forward_nonack: sending request failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> INFO:core:probe_max_sock_buff: using snd buffer of 320 kb
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> INFO:core:init_sock_keepalive: TCP keepalive enabled on socket 23
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:core:tcpconn_async_connect: poll error: flags 1c
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:core:tcpconn_async_connect: failed to retrieve SO_ERROR 
> [server=127.0.0.1:47340] (111) Connection refused
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:core:proto_tcp_send: async TCP connect failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:tm:msg_send: send() for proto 2 failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:tm:t_forward_nonack: sending request failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> INFO:core:probe_max_sock_buff: using snd buffer of 320 kb
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> INFO:core:init_sock_keepalive: TCP keepalive enabled on socket 23
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:core:tcpconn_async_connect: poll error: flags 1c
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:core:tcpconn_async_connect: failed to retrieve SO_ERROR 
> [server=127.0.0.1:54112] (111) Connection refused
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:core:proto_tcp_send: async TCP connect failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:tm:msg_send: send() for proto 2 failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Jan  1 01:19:17 [617] 
> ERROR:tm:t_forward_nonack: sending request failed
> Jan 01 01:19:17 colibri-imx6 opensips[609]: od: invalid option -- 'A'
> Jan 01 01:19:17 colibri-imx6 opensips[609]: BusyBox v1.22.1 
> (2016-06-21 17:18:12 BRT) multi-call binary.
> Jan 01 01:19:17 colibri-imx6 opensips[609]: Usage: od 
> [-aBbcDdeFfHhIiLlOovXx] [FILE]
> Jan 01 01:19:18 colibri-imx6 opensips[609]: Ignoring callid 
>  "7842873d-5947-47ec-8b07-3e29e02550a3"
>
>
>
>
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20161005/d2f78cf0/attachment-0001.htm>

More information about the Users mailing list