I’ve got a new Sangoma 50 Appliance, and its running FreePBX 12.0.76.2. General setup has gone fine, but the big issue is that I can’t get calls in or out. Incoming calls get “number is not in Service” and outgoing calls get “all circuits are busy”. The System Status page shows the 1 Trunk as offline, but the SIP provider (voip.ms) shows it as being online (gives my IP and registration on 5060).
I’ve matched the settings to my office FPBX box (distro on a white box) which uses the same provider- the trunk and incoming and outgoing settings appear to be matching, i.e. I think its all the way it should be. Any suggestions on what else to look at?
[2016-01-02 17:02:23] VERBOSE[1977][C-00000008] netsock2.c: == Using SIP RTP TOS bits 184
[2016-01-02 17:02:23] VERBOSE[1977][C-00000008] netsock2.c: == Using SIP RTP CoS mark 5
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@from-internal:1] Macro("SIP/9418-0000000b", "user-callerid,LIMIT") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/9418-0000000b", "TOUCH_MONITOR=1451772143.11") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/9418-0000000b", "AMPUSER=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/9418-0000000b", "0?report") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/9418-0000000b", "1?Set(REALCALLERIDNUM=9418)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/9418-0000000b", "AMPUSER=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/9418-0000000b", "0?limit") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/9418-0000000b", "AMPUSERCIDNAME=Audrey x9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/9418-0000000b", "0?report") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/9418-0000000b", "AMPUSERCID=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/9418-0000000b", "__DIAL_OPTIONS=Ttr") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/9418-0000000b", "CALLERID(all)="Audrey x9418" <9418>") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/9418-0000000b", "0?limit") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:13] ExecIf("SIP/9418-0000000b", "1?Set(GROUP(concurrency_limit)=9418)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:14] GosubIf("SIP/9418-0000000b", "7?sub-ccss,s,1(from-internal,5552221011)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/9418-0000000b", "0?Return()") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/9418-0000000b", "CCSS_SETUP=TRUE") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/9418-0000000b", "0?monitor_config,1(from-internal,5552221011):monitor_default,1(from-internal,5552221011)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/9418-0000000b", "0?is_exten") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/9418-0000000b", "") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("SIP/9418-0000000b", "FALSE") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:15] ExecIf("SIP/9418-0000000b", "0?Set(CHANNEL(language)=)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/9418-0000000b", "1?continue") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (macro-user-callerid,s,30)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/9418-0000000b", "CALLERID(number)=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/9418-0000000b", "CALLERID(name)=Audrey x9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/9418-0000000b", "CDR(cnum)=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/9418-0000000b", "CDR(cnam)=Audrey x9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/9418-0000000b", "CHANNEL(language)=en") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-user-callerid:35] GosubIf("SIP/9418-0000000b", "0?app-check-classofservce,s,1()") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@from-internal:2] Set("SIP/9418-0000000b", "ROUTEUSER=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@from-internal:3] GotoIf("SIP/9418-0000000b", "1?notblind") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (from-internal,5552221011,6)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@from-internal:6] GotoIf("SIP/9418-0000000b", "1?restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2,5552221011,2:outbound-allroutes,5552221011,2") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2,5552221011,2)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:2] Gosub("SIP/9418-0000000b", "sub-record-check,s,1(out,5552221011,dontcare)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/9418-0000000b", "0?initialized") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/9418-0000000b", "__REC_STATUS=INITIALIZED") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/9418-0000000b", "NOW=1451772144") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/9418-0000000b", "__DAY=02") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/9418-0000000b", "__MONTH=01") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/9418-0000000b", "__YEAR=2016") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/9418-0000000b", "__TIMESTR=20160102-170224") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/9418-0000000b", "__FROMEXTEN=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/9418-0000000b", "__MON_FMT=wav") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/9418-0000000b", "Recordings initialized") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/9418-0000000b", "0?Set(ARG3=dontcare)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/9418-0000000b", "REC_POLICY_MODE_SAVE=") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/9418-0000000b", "0?Set(REC_STATUS=NO)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/9418-0000000b", "3?checkaction") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (sub-record-check,s,17)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/9418-0000000b", "1?sub-record-check,out,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (sub-record-check,out,1)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [out@sub-record-check:1] NoOp("SIP/9418-0000000b", "Outbound Recording Check from 9418 to 5552221011") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [out@sub-record-check:2] Set("SIP/9418-0000000b", "RECMODE=dontcare") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [out@sub-record-check:3] ExecIf("SIP/9418-0000000b", "1?Goto(routewins)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (sub-record-check,out,7)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [out@sub-record-check:7] Gosub("SIP/9418-0000000b", "recordcheck,1(dontcare,out,5552221011)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/9418-0000000b", "Starting recording check against dontcare") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/9418-0000000b", "dontcare") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("SIP/9418-0000000b", "") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [out@sub-record-check:8] Return("SIP/9418-0000000b", "") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:3] ExecIf("SIP/9418-0000000b", "0 ?Set(CDR(accountcode)=)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:4] Set("SIP/9418-0000000b", "MOHCLASS=default") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:5] ExecIf("SIP/9418-0000000b", "0?Set(TRUNKCIDOVERRIDE=<9292869409>)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:6] Set("SIP/9418-0000000b", "_NODEST=") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:7] Macro("SIP/9418-0000000b", "dialout-trunk,2,5552221011,,off") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/9418-0000000b", "DIAL_TRUNK=2") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/9418-0000000b", "0?sub-pincheck,s,1()") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/9418-0000000b", "0?disabletrunk,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/9418-0000000b", "DIAL_NUMBER=5552221011") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/9418-0000000b", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/9418-0000000b", "OUTBOUND_GROUP=OUT_2") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/9418-0000000b", "0?nomax") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/9418-0000000b", "0?chanfull") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/9418-0000000b", "0?skipoutcid") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/9418-0000000b", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/9418-0000000b", "outbound-callerid,2") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/9418-0000000b", "0?Set(CALLERPRES()=)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/9418-0000000b", "0?Set(REALCALLERIDNUM=9418)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/9418-0000000b", "1?normcid") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/9418-0000000b", "USEROUTCID=<9292869418>") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/9418-0000000b", "EMERGENCYCID=") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/9418-0000000b", "TRUNKOUTCID=9292869409") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/9418-0000000b", "1?trunkcid") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (macro-outbound-callerid,s,14)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/9418-0000000b", "1?Set(CALLERID(all)=9292869409)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/9418-0000000b", "1?Set(CALLERID(all)=<9292869418>)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/9418-0000000b", "0?Set(CALLERID(all)=)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/9418-0000000b", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:18] Set("SIP/9418-0000000b", "CDR(outbound_cnum)=9292869418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outbound-callerid:19] Set("SIP/9418-0000000b", "CDR(outbound_cnam)=") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/9418-0000000b", "0?sub-flp-2,s,1()") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/9418-0000000b", "OUTNUM=5552221011") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/9418-0000000b", "custom=SIP/voip.ms_1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/9418-0000000b", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/9418-0000000b", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("SIP/9418-0000000b", "dialout-trunk-predial-hook,") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/9418-0000000b", "") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/9418-0000000b", "0?bypass,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/9418-0000000b", "1?Set(CONNECTEDLINE(num,i)=5552221011)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/9418-0000000b", "1?Set(CONNECTEDLINE(name,i)=CID:9292869418)") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/9418-0000000b", "0?customtrunk") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("SIP/9418-0000000b", "SIP/voip.ms_1/5552221011,300,Tt") in new stack
[2016-01-02 17:02:24] WARNING[12503][C-00000008] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/9418-0000000b", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 20") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/9418-0000000b", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/9418-0000000b", "RC=20") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/9418-0000000b", "20,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (macro-dialout-trunk,20,1)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [20@macro-dialout-trunk:1] Goto("SIP/9418-0000000b", "continue,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Goto (macro-dialout-trunk,continue,1)
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [continue@macro-dialout-trunk:1] NoOp("SIP/9418-0000000b", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 20 - failing through to other trunks") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [continue@macro-dialout-trunk:2] Set("SIP/9418-0000000b", "CALLERID(number)=9418") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [5552221011@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:8] Macro("SIP/9418-0000000b", "outisbusy,") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outisbusy:1] Progress("SIP/9418-0000000b", "") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outisbusy:2] GotoIf("SIP/9418-0000000b", "0?emergency,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outisbusy:3] GotoIf("SIP/9418-0000000b", "0?intracompany,1") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] pbx.c: -- Executing [s@macro-outisbusy:4] Playback("SIP/9418-0000000b", "all-circuits-busy-now&pls-try-call-later, noanswer") in new stack
[2016-01-02 17:02:24] VERBOSE[12503][C-00000008] file.c: -- <SIP/9418-0000000b> Playing 'all-circuits-busy-now.ulaw' (language 'en')
[2016-01-02 17:02:26] VERBOSE[12503][C-00000008] file.c: -- <SIP/9418-0000000b> Playing 'pls-try-call-later.ulaw' (language 'en')
[2016-01-02 17:02:26] VERBOSE[12503][C-00000008] app_macro.c: == Spawn extension (macro-outisbusy, s, 4) exited non-zero on 'SIP/9418-0000000b' in macro 'outisbusy'
[2016-01-02 17:02:26] VERBOSE[12503][C-00000008] pbx.c: == Spawn extension (restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2, 5552221011, 8) exited non-zero on 'SIP/9418-0000000b'
[2016-01-02 17:02:26] VERBOSE[12503][C-00000008] pbx.c: -- Executing [h@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:1] Hangup("SIP/9418-0000000b", "") in new stack
[2016-01-02 17:02:26] VERBOSE[12503][C-00000008] pbx.c: == Spawn extension (restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2, h, 1) exited non-zero on 'SIP/9418-0000000b'