Outbound calls fail with All circuits are Busy when going to OBI200 and Google Voice

patdickey52761 · December 30, 2023, 11:30pm

Hi, everyone,
I set up a Google Voice number on an Obihai OBI200 and connected it to my RasPBX. RasPBX is 16.0.40.7 and Asterisk is 19.4.1. If I call in, it works as expected. However, when I try to make an outbound call, I get the “All Circuits are Busy” message. I’m using a PJSIP Trunk. In the trunk, don’t have a dial pattern set, but in the outbound route, I have **11 for the prepend, 624 for the prefix, and NXXNXXXXXX for the number (I also have one set with **1, 624, and 1NXXNXXXXXX set as well). I don’t think it’s making it to the route though.

Here are the log files from when I attempt to call out:

192660	[2023-12-30 17:19:11] VERBOSE[1254] netsock2.c: Using SIP RTP Audio TOS bits 184	
192661	[2023-12-30 17:19:11] VERBOSE[1254] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.	
192662	[2023-12-30 17:19:11] VERBOSE[1254] netsock2.c: Using SIP RTP Audio CoS mark 5	
192663	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:1] Macro("PJSIP/9205-00000002", "user-callerid,LIMIT,EXTERNAL,") in new stack	
192664	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/9205-00000002", "TOUCH_MONITOR=1703978351.2") in new stack	
192665	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/9205-00000002", "CHANCONTEXT=") in new stack	
192666	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/9205-00000002", "CHANCONTEXT=") in new stack	
192667	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/9205-00000002", "CHANEXTENCONTEXT=9205-00000002") in new stack	
192668	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/9205-00000002", "CHANEXTEN=9205-00000002") in new stack	
192669	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/9205-00000002", "CALLERID(number)=9205") in new stack	
192670	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/9205-00000002", "AMPUSER=9205") in new stack	
192671	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/9205-00000002", "HOTDESCKCHAN=9205-00000002") in new stack	
192672	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/9205-00000002", "HOTDESKEXTEN=9205") in new stack	
192673	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/9205-00000002", "HOTDESKCALL=0") in new stack	
192674	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/9205-00000002", "0?Set(HOTDESKCALL=1)") in new stack	
192675	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/9205-00000002", "0?Set(CALLERID(name)=)") in new stack	
192676	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/9205-00000002", "0?report") in new stack	
192677	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/9205-00000002", "1?Set(REALCALLERIDNUM=9205)") in new stack	
192678	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/9205-00000002", "AMPUSER=9205") in new stack	
192679	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/9205-00000002", "0?limit") in new stack	
192680	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/9205-00000002", "AMPUSERCIDNAME=Patrick Dickey-W0PCD") in new stack	
192681	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/9205-00000002", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
192682	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/9205-00000002", "0?report") in new stack	
192683	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/9205-00000002", "AMPUSERCID=9205") in new stack	
192684	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/9205-00000002", "__DIAL_OPTIONS=") in new stack	
192685	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/9205-00000002", "CALLERID(all)="Patrick Dickey-W0PCD" <9205>") in new stack	
192686	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/9205-00000002", "0?Set(CUSDIAL=)") in new stack	
192687	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/9205-00000002", "0?Set(CALLERID(all)="Patrick Dickey-W0PCD" <9205>)") in new stack	
192688	[2023-12-30 17:19:11] WARNING[2782][C-00000003] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected '>', expecting '-' or '!' or '(' or '<token>'; Input:	
192689	"LIMIT"="LIMIT" & 4 & 0 & >0 & 0>=	
192690	^	
192691	[2023-12-30 17:19:11] WARNING[2782][C-00000003] ast_expr2.fl: If you have questions, please refer to https://wiki.asterisk.org/wiki/display/AST/Channel+Variables	
192692	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/9205-00000002", "0?limit") in new stack	
192693	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/9205-00000002", "1?Set(GROUP(concurrency_limit)=9205)") in new stack	
192694	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:27] NoOp("PJSIP/9205-00000002", "Macro Depth is 1") in new stack	
192695	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:28] GotoIf("PJSIP/9205-00000002", "1?report2:macroerror") in new stack	
192696	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,29)	
192697	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/9205-00000002", "1?continue") in new stack	
192698	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,47)	
192699	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/9205-00000002", "CALLERID(number)=9205") in new stack	
192700	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/9205-00000002", "CALLERID(name)=Patrick Dickey-W0PCD") in new stack	
192701	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:49] GotoIf("PJSIP/9205-00000002", "0?cnum") in new stack	
192702	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/9205-00000002", "CDR(cnam)=Patrick Dickey-W0PCD") in new stack	
192703	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/9205-00000002", "CDR(cnum)=9205") in new stack	
192704	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/9205-00000002", "CHANNEL(language)=en") in new stack	
192705	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:2] Gosub("PJSIP/9205-00000002", "sub-record-check,s,1(out,62415632995663,dontcare)") in new stack	
192706	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/9205-00000002", "0?initialized") in new stack	
192707	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/9205-00000002", "__REC_STATUS=INITIALIZED") in new stack	
192708	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/9205-00000002", "NOW=1703978351") in new stack	
192709	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/9205-00000002", "__DAY=30") in new stack	
192710	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/9205-00000002", "__MONTH=12") in new stack	
192711	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/9205-00000002", "__YEAR=2023") in new stack	
192712	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/9205-00000002", "__TIMESTR=20231230-171911") in new stack	
192713	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/9205-00000002", "__FROMEXTEN=9205") in new stack	
192714	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/9205-00000002", "__MON_FMT=wav") in new stack	
192715	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/9205-00000002", "Recordings initialized") in new stack	
192716	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/9205-00000002", "0?Set(ARG3=dontcare)") in new stack	
192717	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/9205-00000002", "REC_POLICY_MODE_SAVE=") in new stack	
192718	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/9205-00000002", "0?Set(REC_STATUS=NO)") in new stack	
192719	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/9205-00000002", "3?checkaction") in new stack	
192720	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx_builtins.c: Goto (sub-record-check,s,17)	
192721	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/9205-00000002", "1?sub-record-check,out,1") in new stack	
192722	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx_builtins.c: Goto (sub-record-check,out,1)	
192723	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/9205-00000002", "Outbound Recording Check from 9205 to 62415632995663") in new stack	
192724	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/9205-00000002", "RECMODE=dontcare") in new stack	
192725	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/9205-00000002", "1?Goto(routewins)") in new stack	
192726	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx_builtins.c: Goto (sub-record-check,out,7)	
192727	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/9205-00000002", "recordcheck,1(dontcare,out,62415632995663)") in new stack	
192728	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/9205-00000002", "Starting recording check against dontcare") in new stack	
192729	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/9205-00000002", "dontcare") in new stack	
192730	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)	
192731	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/9205-00000002", "") in new stack	
192732	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/9205-00000002", "") in new stack	
192733	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:3] Set("PJSIP/9205-00000002", "_ROUTEID=3") in new stack	
192734	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:4] Set("PJSIP/9205-00000002", "_ROUTENAME=OBIOut") in new stack	
192735	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:5] Set("PJSIP/9205-00000002", "MOHCLASS=default") in new stack	
192736	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:6] Set("PJSIP/9205-00000002", "_CALLERIDNAMEINTERNAL=Patrick Dickey-W0PCD") in new stack	
192737	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:7] Set("PJSIP/9205-00000002", "_CALLERIDNUMINTERNAL=9205") in new stack	
192738	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:8] Set("PJSIP/9205-00000002", "_EMAILNOTIFICATION=FALSE") in new stack	
192739	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:9] Set("PJSIP/9205-00000002", "_NODEST=") in new stack	
192740	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [62415632995663@from-internal:10] Macro("PJSIP/9205-00000002", "outisbusy,") in new stack	
192741	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/9205-00000002", "") in new stack	
192742	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/9205-00000002", "0?emergency,1") in new stack	
192743	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/9205-00000002", "0?intracompany,1") in new stack	
192744	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/9205-00000002", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack	
192745	[2023-12-30 17:19:11] VERBOSE[2782][C-00000003] file.c: <PJSIP/9205-00000002> Playing 'all-circuits-busy-now.ulaw' (language 'en')	
192746	[2023-12-30 17:19:13] VERBOSE[2782][C-00000003] file.c: <PJSIP/9205-00000002> Playing 'please-try-call-later.ulaw' (language 'en')	
192747	[2023-12-30 17:19:15] VERBOSE[2782][C-00000003] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/9205-00000002", "hangupcall") in new stack	
192748	[2023-12-30 17:19:15] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/9205-00000002", "1?theend") in new stack	
192749	[2023-12-30 17:19:15] VERBOSE[2782][C-00000003] pbx_builtins.c: Goto (macro-hangupcall,s,3)	
192750	[2023-12-30 17:19:15] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/9205-00000002", "0?Set(CDR(recordingfile)=)") in new stack	
192751	[2023-12-30 17:19:15] VERBOSE[2782][C-00000003] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/9205-00000002", "") in new stack	
192752	[2023-12-30 17:19:15] VERBOSE[2782][C-00000003] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/9205-00000002' in macro 'hangupcall'	
192753	[2023-12-30 17:19:15] VERBOSE[2782][C-00000003] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/9205-00000002'

Calling other extensions works fine. I’m at a loss here. I followed the instructions from Creating an OBi200 Google Voice Trunk to Use with Asterisk – Nerd Vittles but set it up as a PJSIP trunk because everything else is PJSIP in my setup and I couldn’t authenticate with a SIP trunk. I can provide pictures of the trunk settings, as well as the outbound call route settings if necessary.

Thank you and have a great new year.
Patrick.

david55 · December 31, 2023, 12:41am

1 is not between 2 and 9.

https://docs.asterisk.org/Configuration/Dialplan/Pattern-Matching/#n

What follows 624 is not a valid NANP number. Are you in the NANP area?

patdickey52761 · December 31, 2023, 1:22am

So, the pattern was supposed to require them to either type 1-area code-prefix-number or if they didn’t type the 1, add it in.

I got the whole thing working though. Issue #1… Somewhere along the lines, it disconnected the outbound route from the trunk. Once I fixed that, it worked. I also changed the dial pattern (using the legacy old-style textarea) to this:
**1+76|1NXXNXXXXXX
**11+76|NXXNXXXXXX
So, it will add **1 or **11 (depending on whether the user adds the 1 to the number they’re calling), and requires 76 to get out. It worked when I called 761563xxx5663 (I’m leaving off part of the number so spam bots can’t get me). It also works if they just dial 76563xxx5663. The **1 is because the OBI-200 requires that in order to connect to the Google Voice number. At least that’s how I understand it, and it works.

To be clear about it, the 624 (and 76 now) was the prefix. Kind of like having to dial 9 to get an outside line. The way our PBX is set up, 7x, and 8 are never going to be allocated out, as they are required to dial out of the PBX.

Hopefully, that explains it well enough for someone else, if they are having the same issue.

Have a great night and Happy New Year.
Patrick.

system · January 31, 2024, 1:23am

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