All trunks busy and voice mail

My system has been up and running smoothly for over two years.
I decided to set up voice mail.
That was working to an extant. Extension Extension worked, but calls coming in on the VoIP line would just be terminated instead of going to voice mail.
I tried setting voice mail on the ring group.
Then something interesting happened.
If I call my VoIP number it goes directly to voice mail.
I looked and didn’t have any extensions in the DND mode.
A while later I tried making an outgoing VoIP call.
“All trunks are busy.”

Now what?

Jeff

Logs?

Alright.
I’m logged into the PBX using SSH from my Macbook.
[[email protected] asterisk]# cd /var/log/asterisk
[[email protected] asterisk]#
What’s the next step?

https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:24] NoOp(“SIP/301-00000106”, “CRM Finished”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:25] GotoIf(“SIP/301-00000106”, “0?bypass,1”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:26] ExecIf(“SIP/301-00000106”, “1?Set(CONNECTEDLINE(num,i)=12542101939)”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:27] ExecIf(“SIP/301-00000106”, “1?Set(CONNECTEDLINE(name,i)=CID:2543347001)”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:28] ExecIf(“SIP/301-00000106”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)2543347001)”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:29] GotoIf(“SIP/301-00000106”, “0?customtrunk”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:30] Dial(“SIP/301-00000106”, “SIP/fpbx-2-RrMUI0yoShJI/12542101939,300,T”) in new stack

[2019-11-05 16:40:53] WARNING[27277][C-00000043] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:31] NoOp(“SIP/301-00000106”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 20”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:32] GotoIf(“SIP/301-00000106”, “0?continue,1:s-CHANUNAVAIL,1”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:1] Set(“SIP/301-00000106”, “RC=20”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:2] Goto(“SIP/301-00000106”, “20,1”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx_builtins.c: Goto (macro-dialout-trunk,20,1)

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:1] Goto(“SIP/301-00000106”, “continue,1”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:1] NoOp(“SIP/301-00000106”, “TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 20 - failing through to other trunks”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:2] ExecIf(“SIP/301-00000106”, “1?Set(CALLERID(number)=301)”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:8] Macro(“SIP/301-00000106”, “outisbusy,”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:1] Progress(“SIP/301-00000106”, “”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:2] GotoIf(“SIP/301-00000106”, “0?emergency,1”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:3] GotoIf(“SIP/301-00000106”, “0?intracompany,1”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:4] Playback(“SIP/301-00000106”, “all-circuits-busy-now&please-try-call-later, noanswer”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] file.c: <SIP/301-00000106> Playing ‘all-circuits-busy-now.ulaw’ (language ‘en’)

[2019-11-05 16:40:55] VERBOSE[27277][C-00000043] file.c: <SIP/301-00000106> Playing ‘please-try-call-later.ulaw’ (language ‘en’)

[2019-11-05 16:40:56] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 101

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:5] Congestion(“SIP/301-00000106”, “20”) in new stack

[2019-11-05 16:40:57] WARNING[27277][C-00000043] channel.c: Prodding channel ‘SIP/301-00000106’ failed

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on ‘SIP/301-00000106’ in macro ‘outisbusy’

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Spawn extension (from-internal, 12542101939, 8) exited non-zero on ‘SIP/301-00000106’

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:1] Macro(“SIP/301-00000106”, “hangupcall”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:1] GotoIf(“SIP/301-00000106”, “1?theend”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx_builtins.c: Goto (macro-hangupcall,s,3)

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/301-00000106”, “0?Set(CDR(recordingfile)=)”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:4] Hangup(“SIP/301-00000106”, “”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/301-00000106’ in macro ‘hangupcall’

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/301-00000106’

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] app_stack.c: SIP/301-00000106 Internal Gosub(crm-hangup,s,1) start

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:1] NoOp(“SIP/301-00000106”, “Sending Hangup to CRM”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:2] NoOp(“SIP/301-00000106”, “HANGUP CAUSE: 34”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/301-00000106”, “0?Set(__CRM_VOICEMAIL=)”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:4] NoOp(“SIP/301-00000106”, “MASTER CHANNEL: 1572993653.511 = 1572993653.511”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:5] GotoIf(“SIP/301-00000106”, “0?return”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:6] Set(“SIP/301-00000106”, “__CRM_HANGUP=1”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:7] AGI(“SIP/301-00000106”, “sangomacrm.agi”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] res_agi.c: <SIP/301-00000106>AGI Script sangomacrm.agi completed, returning 0

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:8] Return(“SIP/301-00000106”, “”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/301-00000106’

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] app_stack.c: SIP/301-00000106 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

[2019-11-05 16:40:57] VERBOSE[2415] chan_sip.c: Extension Changed auto_hint_301[from-internal] new state Idle for Notify User 301

[2019-11-05 16:40:57] VERBOSE[2415] chan_sip.c: Extension Changed auto_hint_301[from-internal] new state Idle for Notify User 300

[2019-11-05 16:41:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8591)

[2019-11-05 16:41:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8591)

[2019-11-05 16:41:14] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 102

[2019-11-05 16:41:17] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 300

[2019-11-05 16:41:26] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8592)

[2019-11-05 16:41:26] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8592)

[2019-11-05 16:41:46] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8593)

[2019-11-05 16:41:46] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8593)

[2019-11-05 16:41:53] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 311

[2019-11-05 16:41:53] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 310

[2019-11-05 16:42:00] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 104

[2019-11-05 16:42:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8594)

[2019-11-05 16:42:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8594)

[2019-11-05 16:42:26] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8595)

[2019-11-05 16:42:26] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8595)

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:1] Set(“Message/ast_msg_queue”, “MESSAGE(custom_data)=mark_all_outbound”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:2] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-URI)=sip:192.168.1.235:5060”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:3] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-FullContact)=sip:192.168.1.235:5060;ob”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:4] MessageSend(“Message/ast_msg_queue”, “digium_phone:blah”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:5] Hangup(“Message/ast_msg_queue”, “”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Spawn extension (dpma_message_context, proxy, 5) exited non-zero on ‘Message/ast_msg_queue’

[2019-11-05 16:42:37] WARNING[27530] app_voicemail.c: Failed to create mailbox snapshot for unknown voicemail user [email protected]

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:1] Set(“Message/ast_msg_queue”, “MESSAGE(custom_data)=mark_all_outbound”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:2] Set(“Message/ast_msg_queue”, “TMP_RESPONSE_URI=sip:192.168.1.235:5060”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:3] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(Request-URI)=sip:192.168.1.235:5060;ob”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:4] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-URI)=”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:5] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-FullContact)=”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:6] MessageSend(“Message/ast_msg_queue”, “sip:192.168.1.235:5060,proxy”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [[email protected]_message_context:7] Hangup(“Message/ast_msg_queue”, “”) in new stack

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Spawn extension (dpma_message_context, digium_phone_module, 7) exited non-zero on ‘Message/ast_msg_queue’

[2019-11-05 16:42:37] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 103

[2019-11-05 16:42:37] NOTICE[2460] chan_sip.c: Received SIP subscribe for peer without mailbox: 102

[2019-11-05 16:42:46] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8596)

[2019-11-05 16:42:46] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8596)

[2019-11-05 16:43:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8597)

[2019-11-05 16:43:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8597)

The call can’t complete

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:30] Dial(“SIP/301-00000106”, “SIP/fpbx-2-RrMUI0yoShJI/12542101939,300,T”) in new stack
[2019-11-05 16:40:53] WARNING[27277][C-00000043] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [[email protected]:31] NoOp(“SIP/301-00000106”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 20”) in new stack

You need to first fix

[2019-11-05 16:41:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8591)
[2019-11-05 16:41:26] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8592)
[2019-11-05 16:41:46] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8593)
[2019-11-05 16:42:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8594)
[2019-11-05 16:42:26] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8595)
[2019-11-05 16:42:46] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8596)
[2019-11-05 16:43:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8597)

Well, my first thought was that somehow Sangoma didn’t get paid for this month, but
OCT 302019
SANGOMA US I REF# 930100028768 SCHMOOZECOM.C,WI Card ending in 5606

  • $33.11

So the next question is how do I fix the registration?

Trunks

This page is used to manage various system trunks

Name Tech CallerID Status Actions
5355 dahdi 2546475355 Enabled
fpbx-1-RrMUI0yoShJI sip 2543347001 Enabled
fpbx-2-RrMUI0yoShJI sip 2543347001 Enabled

Showing 1 to 3 of 3 rows

If you arfe positive that your firewall is not blocking traffic and you are using the correct authority, you would have to ask Sangoma/Cyberlynk

you can

sip set debug ip 162.253.134.142

(or “pjsip set logger on” if using pjsip)

for more detail

Where do I execute that command from?

[[email protected] ~]# sip set debug ip 192.168.1.32
-bash: sip: command not found
[[email protected] ~]# pjsip set logger on
-bash: pjsip: command not found
[[email protected] ~]#

With respect, I think you should find some paid support, last time we went this way it took way too long and frayed way to many tempers. (The wiki is REALLY a good place to start)

It’s a simple question.
Wiki search “sip set debug ip”
Page 1 of 355. Showing 3,547 results (0.068 seconds)
Which one of the 3,547 results is the correct one?
Or is there a specific search term I should be using?

I’m trying not to be “pissy” as you put it the last time.

Take your time, I’m headed off to dinner, back in an hour or so.

Bon apetit, personally, I won’t do your homework or even be back :wink:

From elsewhere on the net:
Can you get to an Asterisk console? asterisk -r  from the command line should do it.

Once there, try sip show registry  and see what it says.

If you want to get debugging logs, sip set debug peer AussieBB  should show you the traffic. Remember to sip set debug off  afterwards. If you’re comfortable with tcpdump and WireShark, that’s a less messy way of doing it.

So the simple answer to my previous question would have been “At the Asterisk command prompt, type asterisk -r.”

jdangus-optiplex-360*CLI> sip show registry
Host dnsmgr Username Refresh State Reg.Time
trunk2.freepbx.com:5060 Y RrMUI0yoShJI 120 Request Sent
trunk1.freepbx.com:5060 Y RrMUI0yoShJI 120 Request Sent
2 SIP registrations.

sip debug ip 192.168.1.32: on an outgoing call attempt.

[2019-11-06 10:21:25] ERROR [2460][C-00000045]: netsock2.c : 305 ast_sockaddr_resolve : getaddrinfo(“jdangus-optiplex-360”, “(null)”, …): Name or service not known

[2019-11-06 10:21:25] WARNING [2460][C-00000045]: acl.c : 835 resolve_first : Unable to lookup ‘jdangus-optiplex-360’

[2019-11-06 10:21:25] NOTICE [2460]: chan_sip.c : 15869 sip_reg_timeout : – Registration for ‘[email protected]’ timed out, trying again (Attempt #3014)

[2019-11-06 10:21:25] NOTICE [2460]: chan_sip.c : 15869 sip_reg_timeout : – Registration for ‘[email protected]’ timed out, trying again (Attempt #3014)

[2019-11-06 10:21:25] WARNING [519][C-00000045]: app_dial.c : 2525 dial_exec_full : Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)

[2019-11-06 10:21:25] WARNING [519][C-00000045]: app_dial.c : 2525 dial_exec_full : Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)

[2019-11-06 10:21:28] NOTICE [2460]: chan_sip.c : 28418 handle_request_subscribe : Received SIP subscribe for peer without mailbox: 300

[2019-11-06 10:21:29] WARNING [519][C-00000045]: channel.c : 5005 ast_prod : Prodding channel ‘SIP/301-00000108’ failed

[2019-11-06 10:21:45] NOTICE [2460]: chan_sip.c : 15869 sip_reg_timeout : – Registration for ‘[email protected]’ timed out, trying again (Attempt #3015)

[2019-11-06 10:21:45] NOTICE [2460]: chan_sip.c : 15869 sip_reg_timeout : – Registration for ‘[email protected]’ timed out, trying again (Attempt #3015)

Open a SIPStation support ticket

Sigh…

Sorry, we encountered a problem (invalid department). Please start over.

Submit a ticket

Please pick the appropriate Support Department below for assistance.

If you are a SIPStation trunking customer and are seeking support related to your SIPStation service or Porting a Phone Number. Support on SIPStation service is free of charge but upon submission of your ticket you will need to pick the location the request is for so make sure you are logged in with the same username you use for managing your SIPStation store account.

After over three weeks with no service, my VoIP line is working again.
Thank you to those that helped.

You’re trunks now show as registered. Can you confirm if your service is working now. It should be.

Lucien Wilcox

Ticket Details
Ticket ID: 936380
Department: SIPStation General Support

This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.