New FreePBX install "All circuits are busy now" message

mpforum · December 7, 2018, 9:27am

Hey all,

I’ve set up a new FreePBX install and am finding that whenever I dial an outbound number I get the “All circuits are busy now” message.

I set up a DID number to check that my connection is good and have no issues there with inbound working a treat.

When I try to make a call I get the following in the log file:

[2018-12-07 20:13:02] VERBOSE[3370] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.2.99’
[2018-12-07 20:13:02] VERBOSE[3370] netsock2.c: Using SIP RTP Audio TOS bits 184
[2018-12-07 20:13:02] VERBOSE[3370] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2018-12-07 20:13:02] VERBOSE[3370] netsock2.c: Using SIP RTP Audio CoS mark 5
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:1] Macro(“PJSIP/1000-0000000b”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/1000-0000000b”, “TOUCH_MONITOR=1544173982.11”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/1000-0000000b”, “AMPUSER=1000”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/1000-0000000b”, “0?report”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/1000-0000000b”, “1?Set(REALCALLERIDNUM=1000)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/1000-0000000b”, “AMPUSER=1000”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/1000-0000000b”, “0?limit”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/1000-0000000b”, “AMPUSERCIDNAME=1000”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/1000-0000000b”, "0?Set(_CIDMASQUERADING=TRUE)") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/1000-0000000b”, “0?report”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/1000-0000000b”, “AMPUSERCID=1000”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/1000-0000000b”, "_DIAL_OPTIONS=HhTtr") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/1000-0000000b”, “CALLERID(all)=“1000” <1000>”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/1000-0000000b”, “0?limit”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/1000-0000000b”, “1?Set(GROUP(concurrency_limit)=1000)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CHANNEL(language)=)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/1000-0000000b”, “Macro Depth is 1”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/1000-0000000b”, “1?report2:macroerror”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/1000-0000000b”, “1?continue”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/1000-0000000b”, “CALLERID(number)=1000”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/1000-0000000b”, “CALLERID(name)=1000”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/1000-0000000b”, “0?cnum”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/1000-0000000b”, “CDR(cnam)=1000”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/1000-0000000b”, “CDR(cnum)=1000”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/1000-0000000b”, “CHANNEL(language)=en”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:2] Gosub(“PJSIP/1000-0000000b”, “sub-record-check,s,1(out,011614594444,dontcare)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/1000-0000000b”, “0?initialized”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/1000-0000000b”, "_REC_STATUS=INITIALIZED") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/1000-0000000b”, “NOW=1544173982”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/1000-0000000b”, "_DAY=07") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/1000-0000000b”, "_MONTH=12") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/1000-0000000b”, "_YEAR=2018") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/1000-0000000b”, "_TIMESTR=20181207-201302") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/1000-0000000b”, "_FROMEXTEN=1000") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/1000-0000000b”, "_MON_FMT=wav") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/1000-0000000b”, “Recordings initialized”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/1000-0000000b”, “0?Set(ARG3=dontcare)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/1000-0000000b”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/1000-0000000b”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/1000-0000000b”, “3?checkaction”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/1000-0000000b”, “1?sub-record-check,out,1”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [out@sub-record-check:1] NoOp(“PJSIP/1000-0000000b”, “Outbound Recording Check from 1000 to 011614594444”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [out@sub-record-check:2] Set(“PJSIP/1000-0000000b”, “RECMODE=yes”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [out@sub-record-check:3] ExecIf(“PJSIP/1000-0000000b”, “0?Goto(routewins)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [out@sub-record-check:4] ExecIf(“PJSIP/1000-0000000b”, “0?Goto(routewins)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [out@sub-record-check:5] Gosub(“PJSIP/1000-0000000b”, “recordcheck,1(yes,out,011614594444)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/1000-0000000b”, “Starting recording check against yes”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/1000-0000000b”, “yes”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf(“PJSIP/1000-0000000b”, “0?Return()”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:10] Set(“PJSIP/1000-0000000b”, "_REC_POLICY_MODE=YES") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:11] Goto(“PJSIP/1000-0000000b”, “startrec”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp(“PJSIP/1000-0000000b”, “Starting recording: out, 011614594444”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:17] Set(“PJSIP/1000-0000000b”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2018-12-07 20:13:02] ERROR[22389][C-00000007] pbx_functions.c: Function AUDIOHOOK_INHERIT not registered
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:18] Set(“PJSIP/1000-0000000b”, "_CALLFILENAME=out-011614594444-1000-20181207-201302-1544173982.11") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor(“PJSIP/1000-0000000b”, “2018/12/07/out-011614594444-1000-20181207-201302-1544173982.11.wav,abi(LOCAL_MIXMON_ID),”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:20] Set(“PJSIP/1000-0000000b”, "_MIXMON_ID=0x7ff4b0029ab0") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:21] Set(“PJSIP/1000-0000000b”, "_RECORD_ID=PJSIP/1000-0000000b") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:22] Set(“PJSIP/1000-0000000b”, "_REC_STATUS=RECORDING") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:23] Set(“PJSIP/1000-0000000b”, “CDR(recordingfile)=out-011614594444-1000-20181207-201302-1544173982.11.wav”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [recordcheck@sub-record-check:24] Return(“PJSIP/1000-0000000b”, “”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [out@sub-record-check:6] Return(“PJSIP/1000-0000000b”, “”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:3] ExecIf(“PJSIP/1000-0000000b”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:4] Set(“PJSIP/1000-0000000b”, “MOHCLASS=default”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:5] ExecIf(“PJSIP/1000-0000000b”, “1?Set(TRUNKCIDOVERRIDE=<131010>)”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:6] Set(“PJSIP/1000-0000000b”, "NODEST=") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:7] Macro(“PJSIP/1000-0000000b”, “dialout-trunk,1,011614594444,off”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“PJSIP/1000-0000000b”, “DIAL_TRUNK=1”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(“PJSIP/1000-0000000b”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf(“PJSIP/1000-0000000b”, “0?sub-pincheck,s,1()”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CALLERID(num)=1000)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf(“PJSIP/1000-0000000b”, “0?disabletrunk,1”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“PJSIP/1000-0000000b”, “DIAL_NUMBER=011614594444”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“PJSIP/1000-0000000b”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“PJSIP/1000-0000000b”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:9] Set(“PJSIP/1000-0000000b”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf(“PJSIP/1000-0000000b”, “0?nomax”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“PJSIP/1000-0000000b”, “0?chanfull”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf(“PJSIP/1000-0000000b”, “0?skipoutcid”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:13] Macro(“PJSIP/1000-0000000b”, “outbound-callerid,1”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“PJSIP/1000-0000000b”, “1000”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“PJSIP/1000-0000000b”, “”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“PJSIP/1000-0000000b”, “off”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf(“PJSIP/1000-0000000b”, “0?Set(REALCALLERIDNUM=1000)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf(“PJSIP/1000-0000000b”, “0?Set(AMPUSER=1000)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf(“PJSIP/1000-0000000b”, “1?normcid”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:12] Set(“PJSIP/1000-0000000b”, “USEROUTCID=”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:13] Set(“PJSIP/1000-0000000b”, “EMERGENCYCID=”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:14] Set(“PJSIP/1000-0000000b”, “TRUNKOUTCID=131010”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf(“PJSIP/1000-0000000b”, “1?trunkcid”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf(“PJSIP/1000-0000000b”, “1?Set(CALLERID(all)=131010)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CALLERID(all)=)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“PJSIP/1000-0000000b”, “1?Set(CALLERID(all)=<131010>)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:25] Set(“PJSIP/1000-0000000b”, “CDR(outbound_cnum)=131010”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outbound-callerid:26] Set(“PJSIP/1000-0000000b”, “CDR(outbound_cnam)=”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf(“PJSIP/1000-0000000b”, “0?sub-flp-1,s,1()”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“PJSIP/1000-0000000b”, “OUTNUM=011614594444”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:16] Set(“PJSIP/1000-0000000b”, “custom=PJSIP”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“PJSIP/1000-0000000b”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf(“PJSIP/1000-0000000b”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:19] Macro(“PJSIP/1000-0000000b”, “dialout-trunk-predial-hook,”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/1000-0000000b”, “”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf(“PJSIP/1000-0000000b”, “0?skipcrm”) in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:21] Set(“PJSIP/1000-0000000b”, "_CRM_DIRECTION=OUTBOUND") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:22] Set(“PJSIP/1000-0000000b”, "_CRM_DESTINATION=011614594444") in new stack
_[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:23] Set(“PJSIP/1000-0000000b”, "_CRM_SOURCE=1000") in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:24] AGI(“PJSIP/1000-0000000b”, “sangomacrm.agi”) in new stack
[2018-12-07 20:13:02] VERBOSE[22389][C-00000007] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-12-07 20:13:02] VERBOSE[22396][C-00000007] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/1000-0000000b
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] res_agi.c: <PJSIP/1000-0000000b>AGI Script sangomacrm.agi completed, returning 0
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:25] Set(“PJSIP/1000-0000000b”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp(“PJSIP/1000-0000000b”, “CRM Finished”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf(“PJSIP/1000-0000000b”, “0?bypass,1”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf(“PJSIP/1000-0000000b”, “1?Set(CONNECTEDLINE(num,i)=011614594444)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf(“PJSIP/1000-0000000b”, “1?Set(CONNECTEDLINE(name,i)=CID:131010)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)131010)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf(“PJSIP/1000-0000000b”, “0?customtrunk”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:32] Dial(“PJSIP/1000-0000000b”, “PJSIP/011614594444@Flow_Route,300,Tb(func-apply-sipheaders^s^1)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] app_stack.c: PJSIP/Flow_Route-0000000c Internal Gosub(func-apply-sipheaders,s,1) start
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/Flow_Route-0000000c”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/Flow_Route-0000000c”, “Applying SIP Headers to channel”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/Flow_Route-0000000c”, “SIPHEADERKEYS=”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf(“PJSIP/Flow_Route-0000000c”, “0?Set(Rheader=1)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/Flow_Route-0000000c”, “0”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] app_while.c: Jumping to priority 9
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/Flow_Route-0000000c”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/Flow_Route-0000000c”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] pbx.c: Executing [s@func-apply-sipheaders:12] Return(“PJSIP/Flow_Route-0000000c”, “”) in new stack
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] app_stack.c: Spawn extension (from-pstn, 011614594444, 1) exited non-zero on ‘PJSIP/Flow_Route-0000000c’
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] app_stack.c: PJSIP/Flow_Route-0000000c Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-12-07 20:13:05] VERBOSE[22389][C-00000007] app_dial.c: Called PJSIP/011614594444@Flow_Route
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:33] NoOp(“PJSIP/1000-0000000b”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-dialout-trunk:34] GotoIf(“PJSIP/1000-0000000b”, “0?continue,1:s-CHANUNAVAIL,1”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“PJSIP/1000-0000000b”, “RC=21”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto(“PJSIP/1000-0000000b”, “21,1”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-dialout-trunk,21,1)
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [21@macro-dialout-trunk:1] Goto(“PJSIP/1000-0000000b”, “continue,1”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp(“PJSIP/1000-0000000b”, “TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf(“PJSIP/1000-0000000b”, “1?Set(CALLERID(number)=1000)”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [011614594444@from-internal:8] Macro(“PJSIP/1000-0000000b”, “outisbusy,”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outisbusy:1] Progress(“PJSIP/1000-0000000b”, “”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outisbusy:2] GotoIf(“PJSIP/1000-0000000b”, “0?emergency,1”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outisbusy:3] GotoIf(“PJSIP/1000-0000000b”, “0?intracompany,1”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-outisbusy:4] Playback(“PJSIP/1000-0000000b”, “all-circuits-busy-now&please-try-call-later, noanswer”) in new stack
[2018-12-07 20:13:06] VERBOSE[22389][C-00000007] file.c: <PJSIP/1000-0000000b> Playing ‘all-circuits-busy-now.g722’ (language ‘en’)
[2018-12-07 20:13:08] VERBOSE[22389][C-00000007] file.c: <PJSIP/1000-0000000b> Playing ‘please-try-call-later.ulaw’ (language ‘en’)
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/1000-0000000b”, “hangupcall”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/1000-0000000b”, “1?theend”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/1000-0000000b”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/1000-0000000b”, " monior file= /var/spool/asterisk/monitor/2018/12/07/out-011614594444-1000-20181207-201302-1544173982.11.wav") in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-hangupcall:5] AGI(“PJSIP/1000-0000000b”, “attendedtransfer-rec-restart.php,/var/spool/asterisk/monitor/2018/12/07/out-011614594444-1000-20181207-201302-1544173982.11.wav”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] res_agi.c: <PJSIP/1000-0000000b>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“PJSIP/1000-0000000b”, “”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/1000-0000000b’ in macro ‘hangupcall’
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/1000-0000000b’
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] app_stack.c: PJSIP/1000-0000000b Internal Gosub(crm-hangup,s,1) start
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/1000-0000000b”, “Sending Hangup to CRM”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/1000-0000000b”, “HANGUP CAUSE: 21”) in new stack
_[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/1000-0000000b”, "0?Set(_CRM_VOICEMAIL=)") in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/1000-0000000b”, “MASTER CHANNEL: 1544173982.11 = 1544173982.11”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/1000-0000000b”, “0?return”) in new stack
_[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:6] Set(“PJSIP/1000-0000000b”, "_CRM_HANGUP=1") in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:7] AGI(“PJSIP/1000-0000000b”, “sangomacrm.agi”) in new stack
[2018-12-07 20:13:09] VERBOSE[22389][C-00000007] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-12-07 20:13:11] VERBOSE[22389][C-00000007] res_agi.c: <PJSIP/1000-0000000b>AGI Script sangomacrm.agi completed, returning 0
[2018-12-07 20:13:11] VERBOSE[22389][C-00000007] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/1000-0000000b”, “”) in new stack
[2018-12-07 20:13:11] VERBOSE[22389][C-00000007] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/1000-0000000b’
[2018-12-07 20:13:11] VERBOSE[22389][C-00000007] app_stack.c: PJSIP/1000-0000000b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-12-07 20:13:11] VERBOSE[22396][C-00000007] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2018-12-07 20:13:11] VERBOSE[22396][C-00000007] app_mixmonitor.c: End MixMonitor Recording PJSIP/1000-0000000b

The number I have dialed is a random Australian number, but is a valid format.

SIP provider is Flowroute. Using PJSip with the default port (5060). Authentication is Outbound and registration is send.

Dial patters are:
011.
1NXXNXXXXXX
NXXNXXXXXX
NXXXXXX

I’m still fairly new to this so any help would be amazing.

Stewart1 · December 7, 2018, 10:02am

Flowroute rejected the call because the number was improperly formatted.

Flowroute expects all numbers to start with the country code. If you want to set up your system for ‘US style’ dialing, then in your Outbound Route, put 011 in the prefix field and e.g. XX. in the match pattern.

Your 1NXXNXXXXXX pattern is ok, try dialing 18004377950 (free on Flowroute) which should announce your calling number.

If you want to allow 10-digit dialing in US, put 1 in the prepend and NXXNXXXXXX in the match pattern.

For 7-digit dialing, put e.g. 1212 in the prepend (if you are in area code 212) and NXXXXXX in the match pattern.

614594444 would not be a valid number in Australia – the code 4 indicates a mobile and there should be 8 digits following that.

It appears that you are trying to send 131010 as your outbound caller ID, which is not valid. A US number should be 11 digits, starting with 1.

mpforum · December 7, 2018, 11:37am

Thanks for your reply Stewart.

I dialed 18004377950 and the call was able to connect without any issues so it looks like it’s my ‘Dial Pattern’.

I’m based in Australia and need to set up to dial Australian numbers which are generally for example 02 9876 5432 (or +61 2 9876 5432 from outside Australia). Problem is I’m not entirely sure how to set these dial patterns considering the calls are originating from a server in the US.

Stewart1 · December 7, 2018, 12:12pm

The US server is not an issue; you just need to set up the correct dial patterns. They would be the same for e.g. Voxbeam (UK based) or any provider that expects all destination numbers to start with the country code.

Oversimplified, if you want to dial e.g. 02 9876 5432 for Sydney and 0011 33 1 9876 5432 for Paris, you could use:
prepend 61 prefix 0 match pattern ZXX. and
prepend (blank) prefix 0011 match pattern XX.

However, it will be more complex because of special number formats, etc.

For better advice, please tell us a little about your application. Are you with GIO? Will you have other trunking providers on your system?

Why did you choose Flowroute? Although they are IMO a first class provider, you may have latency issues when calling Australia (though they don’t proxy the audio, they do use US and EU based carriers). Also, they wouldn’t support dialing 000 (though you could map that to a geographic number for police, etc.) You may have trouble reaching short codes. You may have trouble displaying a short code such as 131010 as your outbound caller ID (Flowroute won’t block it but their upstream carrier might).

BlazeStudios · December 7, 2018, 1:37pm

Call is hitting Flowroute. They are denying it. You have your trunk settings right?

cynjut · December 10, 2018, 2:44pm

The number dialed is invalid (one too few digits).

BlazeStudios · December 10, 2018, 2:53pm

Oh it’s invalid for sure, hence my question. Not only is it a digit short for being a NANP destination but Flowroute doesn’t accept calls with 011 prefix they want the country code+number. So even if that last digit was there for the phone number, the prefix is invalid.

mpforum · December 14, 2018, 9:09pm

Thanks for all your help everyone. I’ve now been able to, with your help, get my dial plan sorted.

You’re all a bunch of legends.

system · December 21, 2018, 9:09pm

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