Page 1 of 1

[SOLVED] Softphone Disconnects after 60 Sec

PostPosted: Sun Feb 27, 2022 2:52 am
by dinstar
Hey Everybody,

I have made an setup for outbound manual calling using softphone in my vicidial system. I can log in to web client and my softphone start ringing and I can connect to the system. But recently I am facing a issue in which, my softphone call gets disconnected after 60 seconds. I know I have messed somewhere something. Kindly assist. Also attaching the asterisk CLI below. Also,I am trying to pinpoint issue my self by searching forums from past 3 days, but in vain, hence creating a thread.

Code: Select all
[Feb 27 13:13:47]     -- Registered SIP '2001' at 49.14.164.67:61783
[Feb 27 13:13:47]        > Saved useragent "Linphone Desktop/ (Ubuntu 21.10, Qt 5.15.2) LinphoneCore/4.4.21" for peer 2001
[Feb 27 13:13:48] NOTICE[14160][C-0000038a]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:101@103.36.80.131>;tag=2034888053 for INVITE, code = -1
[Feb 27 13:14:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:14:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:14:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:14:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:14:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:14:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:14:10] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 868856334-928970731-1736643870 on non-critical invite transaction.
[Feb 27 13:14:20] WARNING[14160]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 1194237835-1972881809-1805647346 for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 27 13:14:48] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 245113848-437428966-1014706996 on non-critical invite transaction.
[Feb 27 13:14:52] NOTICE[14160][C-0000038c]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:101@103.36.80.131>;tag=276315201 for INVITE, code = -1
[Feb 27 13:15:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:15:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:15:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:15:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:15:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:15:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:15:24] WARNING[14160]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 85457192-2013006712-748267263 for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 27 13:15:28] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 435077475-1017093030-1559029413 on non-critical invite transaction.
[Feb 27 13:15:48] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 1114201980-1325332193-748437309 on non-critical invite transaction.
[Feb 27 13:15:54] NOTICE[14160]: chan_sip.c:30411 sip_poke_noanswer: Peer '2001' is now UNREACHABLE!  Last qualify: 199
[Feb 27 13:16:00] NOTICE[14160][C-00000390]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:101@103.36.80.131>;tag=1340773609 for INVITE, code = -1
[Feb 27 13:16:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:16:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:16:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:16:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:16:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:16:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:16:08]   == Using SIP RTP CoS mark 5
[Feb 27 13:16:08] NOTICE[14160][C-00000391]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:16:08]   == Using SIP RTP CoS mark 5
[Feb 27 13:16:08] NOTICE[14160][C-00000391]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:59226) to extension '0046812400356' rejected because extension not found in context 'default'.
[Feb 27 13:16:09] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 396603515-747690559-973758600 on non-critical invite transaction.
[Feb 27 13:16:18] NOTICE[14160]: chan_sip.c:24817 handle_response_peerpoke: Peer '2001' is now Reachable. (375ms / 2000ms)
[Feb 27 13:16:32] WARNING[14160]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 699332762-2079016139-1973840156 for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 31999ms with no response
[Feb 27 13:16:35]   == Using SIP RTP CoS mark 5
[Feb 27 13:16:35] NOTICE[14160][C-00000392]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:16:35]   == Using SIP RTP CoS mark 5
[Feb 27 13:16:35] NOTICE[14160][C-00000392]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:51541) to extension '046812400356' rejected because extension not found in context 'default'.
[Feb 27 13:17:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:17:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:17:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:17:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:17:02]   == Using SIP RTP CoS mark 5
[Feb 27 13:17:02] NOTICE[14160][C-00000395]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:17:03]   == Using SIP RTP CoS mark 5
[Feb 27 13:17:03] NOTICE[14160][C-00000395]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:48275) to extension '46812400356' rejected because extension not found in context 'default'.
[Feb 27 13:17:05] NOTICE[14160][C-00000396]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:101@103.36.80.131>;tag=1456018738 for INVITE, code = -1
[Feb 27 13:17:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:17:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:17:23] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 517524686-549131587-767650228 on non-critical invite transaction.
[Feb 27 13:17:27] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 111062199-2074109218-1531744841 on non-critical invite transaction.
[Feb 27 13:17:32]   == Using SIP RTP CoS mark 5
[Feb 27 13:17:32] NOTICE[14160][C-00000397]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:17:32]   == Using SIP RTP CoS mark 5
[Feb 27 13:17:32] NOTICE[14160][C-00000397]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:34414) to extension '90046812400356' rejected because extension not found in context 'default'.
[Feb 27 13:17:37] WARNING[14160]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 1014875458-1409169725-313840533 for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 27 13:17:59]   == Using SIP RTP CoS mark 5
[Feb 27 13:17:59] NOTICE[14160][C-00000399]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:18:00]   == Using SIP RTP CoS mark 5
[Feb 27 13:18:00] NOTICE[14160][C-00000399]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:39740) to extension '00046812400356' rejected because extension not found in context 'default'.
[Feb 27 13:18:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:18:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:18:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:18:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:18:06] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 1224846734-1786798277-452309860 on non-critical invite transaction.
[Feb 27 13:18:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:18:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:18:12] NOTICE[14160][C-0000039a]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:101@103.36.80.131>;tag=1937860743 for INVITE, code = -1
[Feb 27 13:18:24]   == Using SIP RTP CoS mark 5
[Feb 27 13:18:24] NOTICE[14160][C-0000039c]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:18:24]   == Using SIP RTP CoS mark 5
[Feb 27 13:18:24] NOTICE[14160][C-0000039c]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:47349) to extension '00146812400356' rejected because extension not found in context 'default'.
[Feb 27 13:18:44] WARNING[14160]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 577296846-392897240-1885580713 for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 27 13:18:49] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 163366309-1044935846-1234676832 on non-critical invite transaction.
[Feb 27 13:18:52]   == Using SIP RTP CoS mark 5
[Feb 27 13:18:52] NOTICE[14160][C-0000039e]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:18:52]   == Using SIP RTP CoS mark 5
[Feb 27 13:18:52] NOTICE[14160][C-0000039e]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:36638) to extension '00946812400356' rejected because extension not found in context 'default'.
[Feb 27 13:19:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:19:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:19:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:19:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:19:03] WARNING[14160]: chan_sip.c:4166 retrans_pkt: Timeout on 32423875-322934498-1106284177 on non-critical invite transaction.
[Feb 27 13:19:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 27 13:19:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 27 13:19:19] NOTICE[14160][C-0000039f]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:101@103.36.80.131>;tag=1992684176 for INVITE, code = -1
[Feb 27 13:19:21]   == Using SIP RTP CoS mark 5
[Feb 27 13:19:21] NOTICE[14160][C-000003a0]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 27 13:19:21]   == Using SIP RTP CoS mark 5
[Feb 27 13:19:21] NOTICE[14160][C-000003a0]: chan_sip.c:26674 handle_request_invite: Call from '2001' (193.46.255.7:46290) to extension '00746812400356' rejected because extension not found in context 'default'.
vicibox10*CLI>



I have accepted the softphone call, but it still disconnects after 60 seconds
Thanks

Re: Softphone Disconnects after 60 Sec

PostPosted: Sun Feb 27, 2022 7:10 am
by dinstar
Hi! I tried and tested something. It seems like above problem is only occurring while on public ip. If i connect softphone over the lan, it works as expected and without any disconnections.
Possible issues:

1. My ISP GTPL have already NAT enabled in their system, which cannot be disabled. Vicidial too have NAT as "nat=force_rport,comedia ; Global NAT settings (Affects all peers and users)". Hence maybe due to Double NATting, I might be facing issue over Public ip. Possible remedy: will try changing vicidial NAT to "nat=no" in /etc/asterisk/sip.conf

2. ISP Blocking or Limited Port 5060, will try to change it to something else and check

Will get back soon.

Thanks

Re: Softphone Disconnects after 60 Sec

PostPosted: Sun Feb 27, 2022 7:34 am
by Kabis
Hi Team,

Did you add public IP in sip.conf? ANd check SIP and RTP ports are opened?

Re: Softphone Disconnects after 60 Sec

PostPosted: Sun Feb 27, 2022 8:21 am
by dinstar
Update:

1. After switching NAT off in sip.conf, softphone works perfectly in LAN environment, but facing same issue over public ip.
2. After switching to port 8089 in sip.conf, everything works perfect in LAN, but over public ip same disconnection occurs after 60 secs. I have forwarded 8089 port in my router too. Attaching screenshot below.

Also, I have installed SSL certificate on vicidial, it doesn't work on LAN properly, but over public ip it is as expected.

Screenshot of router port forwarding: https://ibb.co/NLmxt9R

Re: Softphone Disconnects after 60 Sec

PostPosted: Sun Feb 27, 2022 2:17 pm
by dinstar
Hi, Sorry for bumping my post again and again.
I have enabled 10000-65355 Port with TCP & UDP in router, along with 5060-5062 for TCP & UDP.
I have enabled above same ports in vicibox firewall in external, public and docker interface.
Tried again over the WAN but it auto disconnects in 60 seconds

Although it does have similar error over asterisk cli, but what I can see that softphone doesn't disconnects over LAN. Attaching asterisk CLI below.
Code: Select all
Feb 28 00:45:20] WARNING[14360]: chan_sip.c:4166 retrans_pkt: Timeout on 388518753-1386123866-1204366028 on non-critical invite transaction.
[Feb 28 00:45:23]   == Using SIP RTP CoS mark 5
[Feb 28 00:45:23] NOTICE[14360][C-00000069]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 00:45:23]   == Using SIP RTP CoS mark 5
[Feb 28 00:45:23] NOTICE[14360][C-00000069]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:50521) to extension '+00546812118598' rejected because extension not found in context 'default'.
[Feb 28 00:45:27]   == Using SIP RTP CoS mark 5
[Feb 28 00:45:27] NOTICE[14360][C-0000006a]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 00:45:29]   == Using SIP RTP CoS mark 5
[Feb 28 00:45:29] NOTICE[14360][C-0000006b]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 00:45:29]   == Using SIP RTP CoS mark 5
[Feb 28 00:45:29] NOTICE[14360][C-0000006a]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:42146) to extension '+00746812118598' rejected because extension not found in context 'default'.
[Feb 28 00:45:29]   == Using SIP RTP CoS mark 5
[Feb 28 00:45:29] NOTICE[14360][C-0000006b]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:43176) to extension '8~10..46812400356' rejected because extension not found in context 'default'.
[Feb 28 00:45:31] WARNING[14360]: chan_sip.c:4166 retrans_pkt: Timeout on 1510186136-1067312663-1575297786 on non-critical invite transaction.
[Feb 28 00:46:01] WARNING[14360]: chan_sip.c:4166 retrans_pkt: Timeout on 207736586-1924753921-1875002386 on non-critical invite transaction.
[Feb 28 00:46:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 00:46:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 00:46:01]   == Manager 'sendcron' logged off from 127.0.0.1

Re: Softphone Disconnects after 60 Sec

PostPosted: Sun Feb 27, 2022 11:47 pm
by dinstar
Kabis wrote:Hi Team,

Did you add public IP in sip.conf? ANd check SIP and RTP ports are opened?



Hey Kabis, Yes, It seems like public ip has been added in sip.conf....attaching code below.
Code: Select all
;registertimeout=20             ; retry registration calls every 20 seconds (default)
;registerattempts=10            ; Number of registration attempts before we give up
externip = 123.12.1.123        ; Address that we're going to put in outbound SIP
;externhost=test.test.com     ; Alternatively you can specify a domain
;externrefresh=10               ; How often to refresh externhost if
localnet=192.168.0.0/255.255.0.0; All RFC 1918 addresses are local network

Made my static ip as 123 for privacy concerns

Pls guide me how and where to check for SIP & RTP port?

Thanks

Re: Softphone Disconnects after 60 Sec

PostPosted: Mon Feb 28, 2022 2:40 am
by dinstar
Hi! I think I have figured out the issues. It seems like RTP & SIP ports are not open. I have tried the following but failed:
1. Opening ports using iptables, but it isn't persistent and I don't know how to save it in OpenSuse.
2. Opening ports using yast firewall, settings seems to be persistent in settings. I have mentioned port range from 10000-65535 in udp section of yast firewall of public, default, docker and external. But after using nmap over lan, it shows only 10000 and 65535 as open, all other are closed and over wan not even 10000, 65535 are open.

Thanks

Re: Softphone Disconnects after 60 Sec

PostPosted: Mon Feb 28, 2022 1:36 pm
by dinstar
Hey Once Again!


Asterisk Logs on Softphone over LAN

Code: Select all
[Feb 28 23:43:11]     -- SIP/2001-00000000 is ringing
[Feb 28 23:43:13] NOTICE[14270][C-0000006f]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:4001@xxx.xx.xxx.xx>;tag=2038689285 f
or INVITE, code = -1
[Feb 28 23:43:16] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 2113353093-2076028656-1139705898 on non-critical invite transaction.
[Feb 28 23:43:20]        > 0x7fa49c063090 -- Strict RTP learning after remote address set to: 192.168.1.11:60924
[Feb 28 23:43:20]     -- SIP/2001-00000000 answered
[Feb 28 23:43:20]     -- Executing [8600051@default:1] MeetMe("SIP/2001-00000000", "8600051,F") in new stack
[Feb 28 23:43:20]     -- Created MeetMe conference 1023 for conference '8600051'
[Feb 28 23:43:20]     -- <SIP/2001-00000000> Playing 'conf-onlyperson.gsm' (language 'en')
[Feb 28 23:43:20]        > 0x7fa49c063090 -- Strict RTP switching to RTP target address 192.168.1.11:60924 as source
[Feb 28 23:43:20] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1520463857-1544983365-1348431193 on non-critical invite transaction.
[Feb 28 23:43:20] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 759461917-1363379538-631545708 on non-critical invite transaction.
[Feb 28 23:43:21]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:43:24] WARNING[14270]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 205246370-1501220430-1785454554 for seqno 2 (Critical Res
ponse) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 28 23:43:25]        > 0x7fa49c063090 -- Strict RTP learning complete - Locking on source address 192.168.1.11:60924
[Feb 28 23:43:26] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 297336571-1553380155-1072267713 on non-critical invite transaction.
[Feb 28 23:43:27] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 140704436-1671331215-1396151065 on non-critical invite transaction.
[Feb 28 23:43:41] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1233668719-503618189-2062703487 on non-critical invite transaction.
[Feb 28 23:43:45] WARNING[14270]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 56397294-890917943-61536095 for seqno 2 (Critical Respons
e) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 31999ms with no response
[Feb 28 23:43:45] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1852264426-767891372-258657281 on non-critical invite transaction.
[Feb 28 23:43:51] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1404820819-1153729987-1391222622 on non-critical invite transaction.
[Feb 28 23:43:52] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 299483010-596509097-405200683 on non-critical invite transaction.
[Feb 28 23:43:54]   == Using SIP RTP CoS mark 5
[Feb 28 23:43:54] NOTICE[14270][C-0000007a]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:43:54]   == Using SIP RTP CoS mark 5
[Feb 28 23:43:54] NOTICE[14270][C-0000007a]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:41340) to extension '0090046812118598' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:43:55]   == Using SIP RTP CoS mark 5
[Feb 28 23:43:55] NOTICE[14270][C-0000007b]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:43:55]   == Using SIP RTP CoS mark 5
[Feb 28 23:43:55] NOTICE[14270][C-0000007b]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:38435) to extension '0010046812118598' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:43:55] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1165088947-989960644-1334210107 on non-critical invite transaction.
[Feb 28 23:43:58] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1748869728-1586086867-1885104357 on non-critical invite transaction.
[Feb 28 23:43:59] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 49575098-2023619311-1413663104 on non-critical invite transaction.
[Feb 28 23:44:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:44:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:44:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:44:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:44:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:44:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:44:09] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1049679948-1675388428-542430534 on non-critical invite transaction.
[Feb 28 23:44:21] NOTICE[14270][C-00000081]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:4001@xxx.xx.xxx.xx>;tag=1783045527 f
or INVITE, code = -1
[Feb 28 23:44:23] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 916338131-1599562326-199889746 on non-critical invite transaction.
[Feb 28 23:44:24] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 424467887-1198124697-1832269394 on non-critical invite transaction.
[Feb 28 23:44:29] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1725813223-1774321801-228507357 on non-critical invite transaction.
[Feb 28 23:44:30] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1772768063-172134416-1955272669 on non-critical invite transaction.
[Feb 28 23:44:30] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1128640755-216488969-2062813106 on non-critical invite transaction.
[Feb 28 23:44:37] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 833413112-1600366640-1093827539 on non-critical invite transaction.
[Feb 28 23:44:37] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1904206282-905041192-863045707 on non-critical invite transaction.
[Feb 28 23:44:38]   == Using SIP RTP CoS mark 5
[Feb 28 23:44:38] NOTICE[14270][C-00000089]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:44:39]   == Using SIP RTP CoS mark 5
[Feb 28 23:44:39] NOTICE[14270][C-00000089]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:53303) to extension '0010046812118598' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:44:52] NOTICE[14270][C-0000008c]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:3000@xxx.xx.xxx.xx>;tag=805578244 fo
r INVITE, code = -1
[Feb 28 23:44:53] WARNING[14270]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 59021530-1063413549-1509468220 for seqno 2 (Critical Resp
onse) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 28 23:44:54] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 563948035-1888967657-783507091 on non-critical invite transaction.
[Feb 28 23:44:54] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1088987671-2012447017-49452369 on non-critical invite transaction.
[Feb 28 23:44:56] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1967140817-356741628-1471764926 on non-critical invite transaction.
[Feb 28 23:44:57] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1720409372-185062592-277989578 on non-critical invite transaction.
[Feb 28 23:45:00] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1477879838-1853436482-1043731002 on non-critical invite transaction.
[Feb 28 23:45:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:45:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:45:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:45:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:45:02] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 770527432-30941247-1932467592 on non-critical invite transaction.
[Feb 28 23:45:02] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 502425934-1006192844-471534475 on non-critical invite transaction.
[Feb 28 23:45:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:45:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:45:13] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1974693504-703059377-834993550 on non-critical invite transaction.
[Feb 28 23:45:19] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 774648503-213241736-1249724241 on non-critical invite transaction.
[Feb 28 23:45:24] WARNING[14270]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 1987456218-331627002-1229250652 for seqno 2 (Critical Res
ponse) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 31999ms with no response
[Feb 28 23:45:24]     -- Hungup 'DAHDI/pseudo-556093189'
[Feb 28 23:45:24]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/2001-00000000'
[Feb 28 23:45:24]     -- Executing [h@default:1] AGI("SIP/2001-00000000", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 O
K)") in new stack                                                                                                                                                       
[Feb 28 23:45:24]     -- Unregistered SIP '2001'
[Feb 28 23:45:24]     -- <SIP/2001-00000000>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK) completed, return
ing 0
[Feb 28 23:45:26] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 945635202-831490292-572799144 on non-critical invite transaction.
[Feb 28 23:45:27] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 29759371-901061071-1699707958 on non-critical invite transaction.
[Feb 28 23:45:30]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:30] NOTICE[14270][C-00000095]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:45:30] NOTICE[14270][C-00000096]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:4001@xxx.xxx.xxx.xxx>;tag=1945104749 f
or INVITE, code = -1
[Feb 28 23:45:31]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:31] NOTICE[14270][C-00000097]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:45:31]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:31] NOTICE[14270][C-00000095]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:46066) to extension '9000046812118598' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:45:32]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:32] NOTICE[14270][C-00000097]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:48622) to extension '0010046812118598' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:45:34] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1107392776-1205112341-709139836 on non-critical invite transaction.
[Feb 28 23:45:34] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 2115215228-950865739-1723960288 on non-critical invite transaction.
[Feb 28 23:45:40]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:45:40]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:45:40]     -- Called 55558600051@default
[Feb 28 23:45:40]     -- Executing [55558600051@default:1] MeetMeAdmin("Local/55558600051@default-00000000;2", "8600051,K") in new stack
[Feb 28 23:45:40] WARNING[15408][C-0000009a]: app_meetme.c:5261 admin_exec: Conference number '8600051' not found!
[Feb 28 23:45:40]     -- Executing [55558600051@default:2] Hangup("Local/55558600051@default-00000000;2", "") in new stack
[Feb 28 23:45:40]   == Spawn extension (default, 55558600051, 2) exited non-zero on 'Local/55558600051@default-00000000;2'
[Feb 28 23:45:40] WARNING[15408][C-0000009a]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel  
[Feb 28 23:45:40]     -- Executing [h@default:1] AGI("Local/55558600051@default-00000000;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16----------
----------)") in new stack                                                                                                                                              
[Feb 28 23:45:40]     -- <Local/55558600051@default-00000000;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) complete
d, returning 0
[Feb 28 23:45:41]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:45:41]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:45:46]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:46] NOTICE[14270][C-0000009d]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:45:47]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:47] NOTICE[14270][C-0000009d]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:37684) to extension '0981046812400356' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:45:47]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:47] NOTICE[14270][C-0000009e]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:45:47]   == Using SIP RTP CoS mark 5
[Feb 28 23:45:47] NOTICE[14270][C-0000009e]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:59921) to extension '0981046812400356' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:45:54] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1794011119-11708048-1669273897 on non-critical invite transaction.
[Feb 28 23:45:57] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 111583261-708903679-2125296584 on non-critical invite transaction.
[Feb 28 23:45:58] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 2143990994-1679362613-1489902016 on non-critical invite transaction.
[Feb 28 23:46:00] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1504512235-1510323702-1434471064 on non-critical invite transaction.
[Feb 28 23:46:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:46:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:46:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:46:02]   == Manager 'sendcron' logged off from 127.0.0.1






Asterisk Log over Public IP

Code: Select all
[Feb 28 23:47:10] WARNING[14270]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 1808300761-622903126-253791647 for seqno 2 (Critical Resp
onse) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 28 23:47:10]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:47:10]   == Using SIP RTP CoS mark 5
[Feb 28 23:47:10]     -- Called 2001
[Feb 28 23:47:11]     -- SIP/2001-00000001 is ringing
[Feb 28 23:47:16]        > 0x7fa45800e470 -- Strict RTP learning after remote address set to: xx.xxx.xxx.xxx:7078
[Feb 28 23:47:16]     -- SIP/2001-00000001 answered
[Feb 28 23:47:16]     -- Executing [8600051@default:1] MeetMe("SIP/2001-00000001", "8600051,F") in new stack
[Feb 28 23:47:16]     -- Created MeetMe conference 1023 for conference '8600051'
[Feb 28 23:47:16]     -- <SIP/2001-00000001> Playing 'conf-onlyperson.gsm' (language 'en')
[Feb 28 23:47:17]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:47:22] WARNING[14270]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 1293217731-1306054165-1448483163 for seqno 2 (Critical Re
sponse) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 31999ms with no response
[Feb 28 23:47:22] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 2063510586-697010837-1461039738 on non-critical invite transaction.
[Feb 28 23:47:32] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1203158795-103447209-1336473463 on non-critical invite transaction.
[Feb 28 23:47:33] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 2059303949-23303009-1861367685 on non-critical invite transaction.
[Feb 28 23:47:36] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1282896975-34443884-1771281764 on non-critical invite transaction.
[Feb 28 23:47:40] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 967114252-2056096334-1544680342 on non-critical invite transaction.
[Feb 28 23:47:41] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1576595652-1963778536-1722080730 on non-critical invite transaction.
[Feb 28 23:47:43] NOTICE[14270][C-000000be]: chan_sip.c:19559 send_check_user_failure_response: Failed to authenticate device <sip:4001@xx.xxx.xxx.xxx>;tag=1186100180 f
or INVITE, code = -1
[Feb 28 23:47:45]   == Using SIP RTP CoS mark 5
[Feb 28 23:47:45] NOTICE[14270][C-000000c0]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:47:47]   == Using SIP RTP CoS mark 5
[Feb 28 23:47:47] NOTICE[14270][C-000000c0]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:34639) to extension '9010046812118598' rejected b
ecause extension not found in context 'default'.
[Feb 28 23:47:59] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1946540224-1356963432-1903208826 on non-critical invite transaction.
[Feb 28 23:48:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:48:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:48:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:48:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:48:02]   == Using SIP RTP CoS mark 5
[Feb 28 23:48:02] NOTICE[14270][C-000000c2]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:48:02]   == Using SIP RTP CoS mark 5
[Feb 28 23:48:02] NOTICE[14270][C-000000c3]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:48:04]   == Using SIP RTP CoS mark 5
[Feb 28 23:48:04] NOTICE[14270][C-000000c3]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:55462) to extension '90100442038073675' rejected
because extension not found in context 'default'.
[Feb 28 23:48:04]   == Using SIP RTP CoS mark 5
[Feb 28 23:48:04] NOTICE[14270][C-000000c2]: chan_sip.c:26674 handle_request_invite: Call from '2001' (20.108.254.252:44030) to extension '90100442038073675' rejected
because extension not found in context 'default'.
[Feb 28 23:48:04] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 950760708-1674807580-1791314189 on non-critical invite transaction.
[Feb 28 23:48:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 28 23:48:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 28 23:48:09] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 1853230586-453079332-111001454 on non-critical invite transaction.
[Feb 28 23:48:09] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 771943073-922989877-1297896762 on non-critical invite transaction.
[Feb 28 23:48:12] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 145488328-1872346977-989243827 on non-critical invite transaction.
[Feb 28 23:48:12] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 91857313-982426842-16873987 on non-critical invite transaction.
[Feb 28 23:48:13] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 845751453-768001830-189442221 on non-critical invite transaction.
[Feb 28 23:48:15] WARNING[14270]: chan_sip.c:4092 retrans_pkt: Retransmission timeout reached on transmission 1415474278-1749762815-773086247 for seqno 2 (Critical Res
ponse) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32000ms with no response
[Feb 28 23:48:15] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 889308136-1637573291-628497527 on non-critical invite transaction.
[Feb 28 23:48:17] NOTICE[14270]: chan_sip.c:29836 check_rtp_timeout: Disconnecting call 'SIP/2001-00000001' for lack of RTP activity in 61 seconds
[Feb 28 23:48:17]     -- Hungup 'DAHDI/pseudo-2056410068'
[Feb 28 23:48:17]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/2001-00000001'
[Feb 28 23:48:17]     -- Executing [h@default:1] AGI("SIP/2001-00000001", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----44--------------------SIP 200 O
k)") in new stack                                                                                                                                                       
[Feb 28 23:48:17]     -- <SIP/2001-00000001>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----44--------------------SIP 200 Ok) completed, return
ing 0
[Feb 28 23:48:24] WARNING[14270]: chan_sip.c:4166 retrans_pkt: Timeout on 518856883-227393980-1253930253 on non-critical invite transaction.
[Feb 28 23:48:25]   == Using SIP RTP CoS mark 5
[Feb 28 23:48:25] NOTICE[14270][C-000000ca]: chan_sip.c:10950 process_sdp: No compatible codecs, not accepting this offer!
[Feb 28 23:48:27]   == Using SIP RTP CoS mark 5
[Feb 28 23:48:27] NOT



Comparing both logs shows that, only lack of RTP activity is the shown in the public ip logs. It seems to be the culprit causing the problem.
user name is 2001
Pls expert guide me what to do?

Thanks

Re: Softphone Disconnects after 60 Sec

PostPosted: Tue Mar 01, 2022 11:05 am
by carpenox
edit sip.conf and set rtptimeout=600 and rtpkeepalive=30

Re: Softphone Disconnects after 60 Sec

PostPosted: Thu Mar 03, 2022 6:00 am
by dinstar
Hey Carpenox. Thanks for revert. DMZ host & SIP ALG was enabled in my router causing port forwarding to fail. I disabled both of them, above settings worked as expected.

Also, I have one more query: Can we make vicidial ignore timezone which is list? As sometime after restart, timezones in the list of leads, gets chaned to something else, I have to clear the whole list and re upload? Can you help me out with this?

Re: Softphone Disconnects after 60 Sec

PostPosted: Thu Mar 03, 2022 8:13 am
by dinstar
Well it seems like I have solved the issue I just mentioned plus 91 in phone code of in the list.