SOLVED voip.ms no incoming calls

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
I just re-installed PIAF after temporarily having to use my PBX box for another purpose at home (not used all that often so it wasn't a big deal for my wife and I). However, after re-installing PIAF & Incredible PBX 11 I cannot get my voip.ms trunk working for inbound calls. Outbound calls work fine but I need to be able to receive calls as well. I can provide whatever logs/configs you need so please let me know what you would like to help me out.
 

Max Power

Member
Joined
Oct 12, 2013
Messages
53
Reaction score
3
Do you have an inbound route set up?

If you post asterisk CLI log of an incoming call it should help us figure it out.
 

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
Yep, I have an inbound route setup. I've attached the log.
 

Attachments

  • call test.txt
    16.7 KB · Views: 22

Max Power

Member
Joined
Oct 12, 2013
Messages
53
Reaction score
3
Sorry I don't have time to sort through that right now it's very difficult to read as it is formatted.

How do you have your trunk set up from voip.ms? Did you follow their instructions?

Did you make sure voip.ms is routed to your pbx? On voip.ms website - Did Numbers>Manage Dids>Routing Sip/IAX - set to your pbx? Also make your DID Point of Presence is selected to the server you are registered to.
 

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
Sorry I don't have time to sort through that right now it's very difficult to read as it is formatted.

How do you have your trunk set up from voip.ms? Did you follow their instructions?


Did you make sure voip.ms is routed to your pbx? On voip.ms website - Did Numbers>Manage Dids>Routing Sip/IAX - set to your pbx? Also make your DID Point of Presence is selected to the server you are registered to.

Followed their directions.
Routed to my PBX, and the Point of Presence is set to the server I'm registered to.

As for the log, I did an "asterisk -rvvvvvvvvvv > out.txt" to get the log while I made a call then renamed it on my desktop. If there is another way you'd like the logs please give me the command.
http://wiki.voip.ms/article/PBXs#FreePBX_.2F_PBX_in_a_Flash_.28SIP.29


NOTE: The trunk name should be set to voipms in lowercase. Otherwise you may have issues with the incoming calls.

All lowercase.
 

Max Power

Member
Joined
Oct 12, 2013
Messages
53
Reaction score
3
Sorry it is formatted better if I open with a different program. It should be fine.
 

Max Power

Member
Joined
Oct 12, 2013
Messages
53
Reaction score
3
Sorry I am not experienced enough to help you with this. The output is not what I am used to seeing I think partially because Debug is on. Hopefully an expert will come along shortly and help you.
 

rossiv

Guru
Joined
Oct 26, 2008
Messages
2,624
Reaction score
139
Please run "asterisk -rx "sip set debug off""
 

rossiv

Guru
Joined
Oct 26, 2008
Messages
2,624
Reaction score
139
Yes but they have too much info. If you run my command it takes out the extra stuff.
So run mine then yours.
 

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
Here you go rossiv.
 

Attachments

  • call test2.txt
    3.9 KB · Views: 5

rossiv

Guru
Joined
Oct 26, 2008
Messages
2,624
Reaction score
139
What SSH client are you using? I've got a LOT of strange characters in the text file you attached. That and there's like no log content either other than a few debug messages.

Instead of running "asterisk -rvvvvvvv > out.txt" just run "asterisk -vvvr" and copy/paste the output here when you place an inbound call.
 

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
I use PuTTY from a windows system. Here is the output of asterisk -vvvr when I place a call. Probably some extra but I figure a little more is better than not enough.

PT1
I use PuTTY from a windows system. Here is the output of asterisk -vvvr when I place a call. Probably some extra but I figure a little more is better than not enough.

Code:
root@pbx:~ $ asterisk -vvvr
Asterisk 11.6.0, Copyright (C) 1999 - 2013 Digium, Inc. and others.
Created by Mark Spencer <[email protected]>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
=========================================================================
Connected to Asterisk 11.6.0 currently running on pbx (pid = 1848)
[2013-11-11 14:40:37] DEBUG[1964]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1960]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 15 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 14 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 13 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 12 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1961]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1958]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1957]: chan_iax2.c:1635 __send_lagrq: I was supposed to send a LAGRQ with callno 13128, but no such call exists.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 2
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:8764 sip_alloc: Allocating new SIP dialog for 6047e6842b085669449c485b057472e3@[::1] - REGISTER (No RTP)
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:37] DEBUG[1977]: acl.c:979 ast_ouraddrfor: For destination '208.100.39.52', our source address is '172.16.6.8'.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:4031 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 172.16.6.8:5060
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 4
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:15268 transmit_register: Scheduled a registration timeout for chicago.voip.ms id  #19975
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3517 initialize_initreq: Initializing initreq for method REGISTER - callid 6047e6842b085669449c485b057472e3@[::1]
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:15343 transmit_register: REGISTER attempt 1 to [email protected]
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 208.100.39.52:5060
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:4138 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #19976))
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 208.100.39.52:5060
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for  Call ID: 6047e6842b085669449c485b057472e3@[::1] (Checking To) --From tag as77ee7150 --To-tag as2139cb81
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:4566 __sip_ack: Stopping retransmission on '6047e6842b085669449c485b057472e3@[::1]' of Request 360: Match Found
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 4
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3515 initialize_initreq: Initializing already initialized SIP dialog 6047e6842b085669449c485b057472e3@[::1] (presumably reinvite)
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:15343 transmit_register: REGISTER attempt 2 to [email protected]
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 208.100.39.52:5060
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for  Call ID: 6047e6842b085669449c485b057472e3@[::1] (Checking To) --From tag as48157849 --To-tag as2139cb81
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:4566 __sip_ack: Stopping retransmission on '6047e6842b085669449c485b057472e3@[::1]' of Request 361: Match Found
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:23373 handle_response_register: Registration successful
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:23375 handle_response_register: Cancelling timeout 19975
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 2
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 1
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 2
[2013-11-11 14:40:47] DEBUG[1960]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 13 for 127.0.0.1
[2013-11-11 14:40:47] DEBUG[1964]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 14 for 127.0.0.1
 

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
PT2
Code:
[2013-11-11 14:40:47] DEBUG[1963]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 15 for 127.0.0.1
[2013-11-11 14:40:47] DEBUG[1961]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 16 for 127.0.0.1
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for  Call ID: NTk5ODRiYmJhYTNmZDM0NDgzZmQ2ZDlhNjZhZmM0Mjg. (Checking From) --From tag 4802383c --To-tag
[2013-11-11 14:40:48] DEBUG[1977]: acl.c:979 ast_ouraddrfor: For destination '172.16.6.11', our source address is '172.16.6.8'.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:4031 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 172.16.6.8:5060
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.11:64155' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.11' and port '64155'.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:8764 sip_alloc: Allocating new SIP dialog for NTk5ODRiYmJhYTNmZDM0NDgzZmQ2ZDlhNjZhZmM0Mjg. - REGISTER (No RTP)
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:28095 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.11:64155' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.11' and port '64155'.
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.8:5060' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.8' and port ''.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'SIP/2.0 401' onto UDP socket destined for 172.16.6.11:64155
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for  Call ID: NTk5ODRiYmJhYTNmZDM0NDgzZmQ2ZDlhNjZhZmM0Mjg. (Checking From) --From tag 4802383c --To-tag
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.8:5060' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.8' and port '5060'.
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.8:5060' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.8' and port '5060'.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:28095 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.11:64155' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.11' and port '64155'.
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.8:5060' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.8' and port ''.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:15995 parse_register_contact: Store REGISTER's Contact header for call routing.
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '172.16.6.11:64155' into...
[2013-11-11 14:40:48] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '172.16.6.11' and port '64155'.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:8764 sip_alloc: Allocating new SIP dialog for 4c27960c5d03a39f0f6c858b48b41201@[::1]:5060 - OPTIONS (No RTP)
[2013-11-11 14:40:48] DEBUG[1977]: acl.c:979 ast_ouraddrfor: For destination '172.16.6.11', our source address is '172.16.6.8'.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:4031 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 172.16.6.8:5060
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:8559 change_callid_pvt: SIP call-id changed from '4c27960c5d03a39f0f6c858b48b41201@[::1]:5060' to '[email protected]:5060'
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:3517 initialize_initreq: Initializing initreq for method OPTIONS - callid [email protected]:5060
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'OPTIONS sip' onto UDP socket destined for 172.16.6.11:64155
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 172.16.6.11:64155
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:8764 sip_alloc: Allocating new SIP dialog for 3e63960a3ff033092b5e5502295657a3@[::1]:5060 - NOTIFY (No RTP)
[2013-11-11 14:40:48] DEBUG[1977]: acl.c:979 ast_ouraddrfor: For destination '172.16.6.11', our source address is '172.16.6.8'.
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:4031 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 172.16.6.8:5060
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:8559 change_callid_pvt: SIP call-id changed from '3e63960a3ff033092b5e5502295657a3@[::1]:5060' to '[email protected]:5060'
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:3517 initialize_initreq: Initializing initreq for method NOTIFY - callid [email protected]:5060
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'NOTIFY sip:' onto UDP socket destined for 172.16.6.11:64155
[2013-11-11 14:40:48] DEBUG[1855]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - 702
[2013-11-11 14:40:48] DEBUG[1855]: chan_sip.c:29551 sip_devicestate: Checking device state for peer 702
[2013-11-11 14:40:48] DEBUG[1855]: devicestate.c:467 do_state_change: Changing state for SIP/702 - state 1 (Not in use)
[2013-11-11 14:40:48] DEBUG[1855]: devicestate.c:442 devstate_event: device 'SIP/702' state '1'
[2013-11-11 14:40:48] DEBUG[2070]: app_queue.c:1809 handle_statechange: Device 'SIP/702' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2013-11-11 14:40:48] DEBUG[1857]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "Custom" - number: DND702
[2013-11-11 14:40:48] DEBUG[1857]: devicestate.c:417 getproviderstate: Checking provider Custom with Custom
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:4138 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 210 ms (t1 105 ms (Retrans id #19985))
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'NOTIFY sip:' onto UDP socket destined for 172.16.6.11:64155
[2013-11-11 14:40:48] DEBUG[1857]: db.c:373 db_get_common: Unable to find key 'DND702' in family 'CustomDevstate'
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for  Call ID: [email protected]:5060 (Checking To) --From tag as30810a8f --To-tag 676b214d
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:4566 __sip_ack: Stopping retransmission on '[email protected]:5060' of Request 102: Match Found
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:6827 sip_destroy: Destroying SIP dialog [email protected]:5060
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for  Call ID: [email protected]:5060 (Checking To) --From tag as12442132 --To-tag 26183b31
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:4566 __sip_ack: Stopping retransmission on '[email protected]:5060' of Request 102: Match Found
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for  Call ID: [email protected]:5060 (Checking To) --From tag as12442132 --To-tag 26183b31
[2013-11-11 14:40:48] DEBUG[1977]: chan_sip.c:4566 __sip_ack: Stopping retransmission on '[email protected]:5060' of Request 102: Match Not Found
[2013-11-11 14:40:55] DEBUG[1977]: chan_sip.c:4422 __sip_autodestruct: Auto destroying SIP dialog '[email protected]:5060'
[2013-11-11 14:40:55] DEBUG[1977]: chan_sip.c:6827 sip_destroy: Destroying SIP dialog [email protected]:5060
[2013-11-11 14:40:57] DEBUG[1962]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:57] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 15 for 127.0.0.1
[2013-11-11 14:40:57] DEBUG[1964]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:57] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 14 for 127.0.0.1
[2013-11-11 14:40:57] DEBUG[1963]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:57] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 13 for 127.0.0.1
[2013-11-11 14:40:57] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 12 for 127.0.0.1
[2013-11-11 14:40:57] DEBUG[1958]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:41:07] DEBUG[1962]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 13 for 127.0.0.1
[2013-11-11 14:41:07] DEBUG[1964]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 14 for 127.0.0.1
[2013-11-11 14:41:07] DEBUG[1961]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 15 for 127.0.0.1
[2013-11-11 14:41:07] DEBUG[1957]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 16 for 127.0.0.1
[2013-11-11 14:41:10] DEBUG[1977]: chan_sip.c:4422 __sip_autodestruct: Auto destroying SIP dialog '6047e6842b085669449c485b057472e3@[::1]'
[2013-11-11 14:41:10] DEBUG[1977]: chan_sip.c:6827 sip_destroy: Destroying SIP dialog 6047e6842b085669449c485b057472e3@[::1]
pbx*CLI>
Disconnected from Asterisk server
Asterisk cleanly ending (0).
Executing last minute cleanups
 

rossiv

Guru
Joined
Oct 26, 2008
Messages
2,624
Reaction score
139
Alright, this is beyond me. Either your debug is still on or something's up.

From what I do see, your system is attempting to register to chicago.voip.ms but is not getting a response. My guess is a NAT issue.
 

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
Alright, this is beyond me. Either your debug is still on or something's up.

From what I do see, your system is attempting to register to chicago.voip.ms but is not getting a response. My guess is a NAT issue.

Managed to get debug off however nothing shows up in the asterisk CLI. Both my PBX & VoIP.ms show me as registered. Could still be NAT I guess but I find it odd that I can call out fine but not call in. I will try a new DID (not a big deal, used for on call for work and forwarded from Google Voice).
 

smccloud

Member
Joined
Jun 17, 2013
Messages
131
Reaction score
14
And after changing the DID Point of Presence to Atlanta & then back to Chicago it works. Now to call a printing place in Little Mountain PA to find out why they are calling me....
 
Get 3CX - Absolutely Free!

Link up your team and customers Phone System Live Chat Video Conferencing

Hosted or Self-managed. Up to 10 users free forever. No credit card. Try risk free.

3CX
A 3CX Account with that email already exists. You will be redirected to the Customer Portal to sign in or reset your password if you've forgotten it.
Top