Can't make outgoing calls

I’ve set up a new installation of FreePBX and set up a trunk and 5 extensions.
Incoming calls work perfectly but calls out to any number fail with message saying “The number you have dialed is unavailable”.

See Askterisk log below.

Any help would be greatly appreciated.
If you need any more info please let me know.

Colin

[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [0872910755@from-internal:1] Macro("PJSIP/100-0000007f", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/100-0000007f", "TOUCH_MONITOR=1504520424.129") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/100-0000007f", "AMPUSER=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/100-0000007f", "0?report") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/100-0000007f", "1?Set(REALCALLERIDNUM=100)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/100-0000007f", "AMPUSER=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/100-0000007f", "0?limit") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/100-0000007f", "AMPUSERCIDNAME=Colin") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/100-0000007f", "0?report") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/100-0000007f", "AMPUSERCID=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/100-0000007f", "__DIAL_OPTIONS=Ttr") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/100-0000007f", "CALLERID(all)="Colin" <100>") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("PJSIP/100-0000007f", "0?limit") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/100-0000007f", "1?Set(GROUP(concurrency_limit)=100)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/100-0000007f", "0?Set(CHANNEL(language)=)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("PJSIP/100-0000007f", "1?continue") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:29] Set("PJSIP/100-0000007f", "CALLERID(number)=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:30] Set("PJSIP/100-0000007f", "CALLERID(name)=Colin") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("PJSIP/100-0000007f", "0?cnum") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/100-0000007f", "CDR(cnam)=Colin") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/100-0000007f", "CDR(cnum)=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-user-callerid:34] Set("PJSIP/100-0000007f", "CHANNEL(language)=en") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [0872910755@from-internal:2] Gosub("PJSIP/100-0000007f", "sub-record-check,s,1(out,0872910755,dontcare)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/100-0000007f", "0?initialized") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/100-0000007f", "__REC_STATUS=INITIALIZED") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/100-0000007f", "NOW=1504520424") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/100-0000007f", "__DAY=04") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/100-0000007f", "__MONTH=09") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/100-0000007f", "__YEAR=2017") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/100-0000007f", "__TIMESTR=20170904-112024") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/100-0000007f", "__FROMEXTEN=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/100-0000007f", "__MON_FMT=wav") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/100-0000007f", "Recordings initialized") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/100-0000007f", "0?Set(ARG3=dontcare)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/100-0000007f", "REC_POLICY_MODE_SAVE=") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/100-0000007f", "0?Set(REC_STATUS=NO)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/100-0000007f", "3?checkaction") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/100-0000007f", "1?sub-record-check,out,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (sub-record-check,out,1)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/100-0000007f", "Outbound Recording Check from 100 to 0872910755") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/100-0000007f", "RECMODE=dontcare") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/100-0000007f", "1?Goto(routewins)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (sub-record-check,out,7)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/100-0000007f", "recordcheck,1(dontcare,out,0872910755)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/100-0000007f", "Starting recording check against dontcare") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/100-0000007f", "dontcare") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [0872910755@from-internal:3] ExecIf("PJSIP/100-0000007f", "0 ?Set(CDR(accountcode)=)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [0872910755@from-internal:4] Set("PJSIP/100-0000007f", "MOHCLASS=default") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [0872910755@from-internal:5] Set("PJSIP/100-0000007f", "_NODEST=") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [0872910755@from-internal:6] Macro("PJSIP/100-0000007f", "dialout-trunk,3,0872910755,,off") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/100-0000007f", "DIAL_TRUNK=3") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf("PJSIP/100-0000007f", "0?sub-pincheck,s,1()") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf("PJSIP/100-0000007f", "0?disabletrunk,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:4] Set("PJSIP/100-0000007f", "DIAL_NUMBER=0872910755") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:5] Set("PJSIP/100-0000007f", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/100-0000007f", "OUTBOUND_GROUP=OUT_3") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("PJSIP/100-0000007f", "1?nomax") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-dialout-trunk,s,9)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("PJSIP/100-0000007f", "0?skipoutcid") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:10] Set("PJSIP/100-0000007f", "DIAL_TRUNK_OPTIONS=T") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:11] Macro("PJSIP/100-0000007f", "outbound-callerid,3") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf("PJSIP/100-0000007f", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf("PJSIP/100-0000007f", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf("PJSIP/100-0000007f", "0?Set(REALCALLERIDNUM=100)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf("PJSIP/100-0000007f", "1?normcid") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/100-0000007f", "USEROUTCID=") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/100-0000007f", "EMERGENCYCID=") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:9] Set("PJSIP/100-0000007f", "TRUNKOUTCID=111807") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf("PJSIP/100-0000007f", "1?trunkcid") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/100-0000007f", "1?Set(CALLERID(all)=111807)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf("PJSIP/100-0000007f", "0?Set(CALLERID(all)=)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf("PJSIP/100-0000007f", "0?Set(CALLERID(all)=)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf("PJSIP/100-0000007f", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf("PJSIP/100-0000007f", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/100-0000007f", "CDR(outbound_cnum)=111807") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/100-0000007f", "CDR(outbound_cnam)=") in new stack
[2017-09-04 11:20:24] WARNING[2188] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf("PJSIP/100-0000007f", "0?sub-flp-3,s,1()") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:13] Set("PJSIP/100-0000007f", "OUTNUM=0872910755") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:14] Set("PJSIP/100-0000007f", "custom=PJSIP") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf("PJSIP/100-0000007f", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("PJSIP/100-0000007f", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:17] Macro("PJSIP/100-0000007f", "dialout-trunk-predial-hook,") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf("PJSIP/100-0000007f", "0?bypass,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf("PJSIP/100-0000007f", "1?Set(CONNECTEDLINE(num,i)=0872910755)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/100-0000007f", "1?Set(CONNECTEDLINE(name,i)=CID:111807)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/100-0000007f", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)111807)") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf("PJSIP/100-0000007f", "0?customtrunk") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:23] Dial("PJSIP/100-0000007f", "PJSIP/0872910755@111807,300,T") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] app_dial.c: Called PJSIP/0872910755@111807
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:24] NoOp("PJSIP/100-0000007f", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-dialout-trunk:25] GotoIf("PJSIP/100-0000007f", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("PJSIP/100-0000007f", "RC=1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("PJSIP/100-0000007f", "1,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-dialout-trunk,1,1)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [1@macro-dialout-trunk:1] Goto("PJSIP/100-0000007f", "s-INVALIDNMBR,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-dialout-trunk,s-INVALIDNMBR,1)
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:1] NoOp("PJSIP/100-0000007f", "Dial failed due to trunk reporting Address Incomplete - giving up") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:2] Progress("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:3] Playback("PJSIP/100-0000007f", "ss-noservice,noanswer") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] file.c: <PJSIP/100-0000007f> Playing 'ss-noservice.ulaw' (language 'en')
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/100-0000007f", "hangupcall") in new stack
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/100-0000007f", "1?theend") in new stack
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/100-0000007f", "0?Set(CDR(recordingfile)=)") in new stack
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/100-0000007f' in macro 'hangupcall'
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/100-0000007f'

Start here.

Let us know what you got.

Thanks for replying!

Trunk is registered and incoming calls are working.
It is just on outgoing calls.

Whats about your outgoing settings?

[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] app_dial.c: Called PJSIP/0872910755@111807
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)

Your provider appears to be rejecting the call. Lots of possibilities, but common reasons would be invalid dial string, invalid CallerID, insufficient credit.

Ok, I’ve made some changes to outbound settings including adding outbound proxy.
From user was also incorrect.

Now, when a number is dialled, it rings once and then we get “All circuits are busy” message.
New log below:

[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [0872910755@from-internal:1] Macro("PJSIP/100-0000000a", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/100-0000000a", "TOUCH_MONITOR=1504534968.10") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/100-0000000a", "AMPUSER=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/100-0000000a", "0?report") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/100-0000000a", "1?Set(REALCALLERIDNUM=100)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/100-0000000a", "AMPUSER=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/100-0000000a", "0?limit") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/100-0000000a", "AMPUSERCIDNAME=Colin") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/100-0000000a", "0?report") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/100-0000000a", "AMPUSERCID=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/100-0000000a", "__DIAL_OPTIONS=Ttr") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/100-0000000a", "CALLERID(all)="Colin" <100>") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("PJSIP/100-0000000a", "0?limit") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/100-0000000a", "1?Set(GROUP(concurrency_limit)=100)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/100-0000000a", "0?Set(CHANNEL(language)=)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("PJSIP/100-0000000a", "1?continue") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:29] Set("PJSIP/100-0000000a", "CALLERID(number)=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:30] Set("PJSIP/100-0000000a", "CALLERID(name)=Colin") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("PJSIP/100-0000000a", "0?cnum") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/100-0000000a", "CDR(cnam)=Colin") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/100-0000000a", "CDR(cnum)=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-user-callerid:34] Set("PJSIP/100-0000000a", "CHANNEL(language)=en") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [0872910755@from-internal:2] Gosub("PJSIP/100-0000000a", "sub-record-check,s,1(out,0872910755,dontcare)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/100-0000000a", "0?initialized") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/100-0000000a", "__REC_STATUS=INITIALIZED") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/100-0000000a", "NOW=1504534968") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/100-0000000a", "__DAY=04") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/100-0000000a", "__MONTH=09") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/100-0000000a", "__YEAR=2017") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/100-0000000a", "__TIMESTR=20170904-152248") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/100-0000000a", "__FROMEXTEN=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/100-0000000a", "__MON_FMT=wav") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/100-0000000a", "Recordings initialized") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/100-0000000a", "0?Set(ARG3=dontcare)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/100-0000000a", "REC_POLICY_MODE_SAVE=") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/100-0000000a", "0?Set(REC_STATUS=NO)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/100-0000000a", "3?checkaction") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/100-0000000a", "1?sub-record-check,out,1") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (sub-record-check,out,1)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/100-0000000a", "Outbound Recording Check from 100 to 0872910755") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/100-0000000a", "RECMODE=dontcare") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/100-0000000a", "1?Goto(routewins)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (sub-record-check,out,7)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/100-0000000a", "recordcheck,1(dontcare,out,0872910755)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/100-0000000a", "Starting recording check against dontcare") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/100-0000000a", "dontcare") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [0872910755@from-internal:3] ExecIf("PJSIP/100-0000000a", "0 ?Set(CDR(accountcode)=)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [0872910755@from-internal:4] Set("PJSIP/100-0000000a", "MOHCLASS=default") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [0872910755@from-internal:5] Set("PJSIP/100-0000000a", "_NODEST=") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [0872910755@from-internal:6] Macro("PJSIP/100-0000000a", "dialout-trunk,3,0872910755,,off") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/100-0000000a", "DIAL_TRUNK=3") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf("PJSIP/100-0000000a", "0?sub-pincheck,s,1()") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf("PJSIP/100-0000000a", "0?disabletrunk,1") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:4] Set("PJSIP/100-0000000a", "DIAL_NUMBER=0872910755") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:5] Set("PJSIP/100-0000000a", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/100-0000000a", "OUTBOUND_GROUP=OUT_3") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("PJSIP/100-0000000a", "1?nomax") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-dialout-trunk,s,9)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("PJSIP/100-0000000a", "0?skipoutcid") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:10] Set("PJSIP/100-0000000a", "DIAL_TRUNK_OPTIONS=T") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:11] Macro("PJSIP/100-0000000a", "outbound-callerid,3") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf("PJSIP/100-0000000a", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf("PJSIP/100-0000000a", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf("PJSIP/100-0000000a", "0?Set(REALCALLERIDNUM=100)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf("PJSIP/100-0000000a", "1?normcid") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/100-0000000a", "USEROUTCID=111807") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/100-0000000a", "EMERGENCYCID=") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:9] Set("PJSIP/100-0000000a", "TRUNKOUTCID=111807") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf("PJSIP/100-0000000a", "1?trunkcid") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/100-0000000a", "1?Set(CALLERID(all)=111807)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf("PJSIP/100-0000000a", "1?Set(CALLERID(all)=111807)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf("PJSIP/100-0000000a", "1?Set(CALLERID(all)=111807)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf("PJSIP/100-0000000a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf("PJSIP/100-0000000a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/100-0000000a", "CDR(outbound_cnum)=111807") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/100-0000000a", "CDR(outbound_cnam)=") in new stack
[2017-09-04 15:22:48] WARNING[31189] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf("PJSIP/100-0000000a", "0?sub-flp-3,s,1()") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:13] Set("PJSIP/100-0000000a", "OUTNUM=0872910755") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:14] Set("PJSIP/100-0000000a", "custom=PJSIP") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf("PJSIP/100-0000000a", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("PJSIP/100-0000000a", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:17] Macro("PJSIP/100-0000000a", "dialout-trunk-predial-hook,") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf("PJSIP/100-0000000a", "0?bypass,1") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf("PJSIP/100-0000000a", "1?Set(CONNECTEDLINE(num,i)=0872910755)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/100-0000000a", "1?Set(CONNECTEDLINE(name,i)=CID:111807)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/100-0000000a", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)111807)") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf("PJSIP/100-0000000a", "0?customtrunk") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:23] Dial("PJSIP/100-0000000a", "PJSIP/0872910755@111807,300,T") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] app_dial.c: Called PJSIP/0872910755@111807
[2017-09-04 15:22:49] VERBOSE[2983][C-00000005] app_dial.c: PJSIP/111807-0000000b is ringing
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:24] NoOp("PJSIP/100-0000000a", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-dialout-trunk:25] GotoIf("PJSIP/100-0000000a", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("PJSIP/100-0000000a", "RC=21") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("PJSIP/100-0000000a", "21,1") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-dialout-trunk,21,1)
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("PJSIP/100-0000000a", "continue,1") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/100-0000000a", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/100-0000000a", "1?Set(CALLERID(number)=100)") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [0872910755@from-internal:7] Macro("PJSIP/100-0000000a", "outisbusy,") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/100-0000000a", "0?emergency,1") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/100-0000000a", "0?intracompany,1") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/100-0000000a", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] file.c: <PJSIP/100-0000000a> Playing 'all-circuits-busy-now.ulaw' (language 'en')
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] file.c: <PJSIP/100-0000000a> Playing 'please-try-call-later.ulaw' (language 'en')
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/100-0000000a", "hangupcall") in new stack
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/100-0000000a", "1?theend") in new stack
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/100-0000000a", "0?Set(CDR(recordingfile)=)") in new stack
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/100-0000000a' in macro 'hangupcall'
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/100-0000000a'

Got it working.

My mistake, outbound proxy not needed.
Coupled with the corrected From User field we now have outgoing calls.

Thanks
colin