All trunks busy and voice mail

foxsmercantile · November 5, 2019, 7:28pm

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

dicko · November 5, 2019, 7:40pm

Logs?

foxsmercantile · November 5, 2019, 10:00pm

Alright.
I’m logged into the PBX using SSH from my Macbook.
[root@jdangus-optiplex-360 asterisk]# cd /var/log/asterisk
[root@jdangus-optiplex-360 asterisk]#
What’s the next step?

dicko · November 5, 2019, 10:31pm

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

foxsmercantile · November 5, 2019, 10:43pm

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

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

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:29] GotoIf(“SIP/301-00000106”, “0?customtrunk”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“SIP/301-00000106”, “RC=20”) in new stack

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk: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 [20@macro-dialout-trunk: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 [continue@macro-dialout-trunk: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 [continue@macro-dialout-trunk: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 [12542101939@from-internal:8] Macro(“SIP/301-00000106”, “outisbusy,”) in new stack

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

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

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

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [s@macro-outisbusy: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 [s@macro-outisbusy: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 [h@from-internal:1] Macro(“SIP/301-00000106”, “hangupcall”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@crm-hangup: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 [s@crm-hangup:2] NoOp(“SIP/301-00000106”, “HANGUP CAUSE: 34”) in new stack

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [s@crm-hangup: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 [s@crm-hangup: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 [s@crm-hangup:5] GotoIf(“SIP/301-00000106”, “0?return”) in new stack

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

[2019-11-05 16:40:57] VERBOSE[27277][C-00000043] pbx.c: Executing [s@crm-hangup: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 [s@crm-hangup: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: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: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: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 [proxy@dpma_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 [proxy@dpma_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 [proxy@dpma_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 [proxy@dpma_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 [proxy@dpma_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 301@device

[2019-11-05 16:42:37] VERBOSE[2403][C-00000000] pbx.c: Executing [digium_phone_module@dpma_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 [digium_phone_module@dpma_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 [digium_phone_module@dpma_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 [digium_phone_module@dpma_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 [digium_phone_module@dpma_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 [digium_phone_module@dpma_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 [digium_phone_module@dpma_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:43:06] NOTICE[2460] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #8597)

dicko · November 5, 2019, 11:09pm

The call can’t complete

[2019-11-05 16:40:53] VERBOSE[27277][C-00000043] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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)

foxsmercantile · November 5, 2019, 11:14pm

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
5355	dahdi	2546475355	Enabled
fpbx-1-RrMUI0yoShJI	sip	2543347001	Enabled
fpbx-2-RrMUI0yoShJI	sip	2543347001	Enabled

Showing 1 to 3 of 3 rows

dicko · November 5, 2019, 11:22pm

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

foxsmercantile · November 5, 2019, 11:35pm

Where do I execute that command from?

[root@jdangus-optiplex-360 ~]# sip set debug ip 192.168.1.32
-bash: sip: command not found
[root@jdangus-optiplex-360 ~]# pjsip set logger on
-bash: pjsip: command not found
[root@jdangus-optiplex-360 ~]#

dicko · November 5, 2019, 11:42pm

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)

foxsmercantile · November 5, 2019, 11:55pm

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.

dicko · November 5, 2019, 11:58pm

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

foxsmercantile · November 6, 2019, 4:15pm

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.

foxsmercantile · November 6, 2019, 4:23pm

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] 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)

lgaetz · November 6, 2019, 4:45pm

Open a SIPStation support ticket

foxsmercantile · November 6, 2019, 5:09pm

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.

foxsmercantile · November 13, 2019, 3:28pm

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

system · December 14, 2019, 3:28pm

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