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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set("PJSIP/100-0000007f", "AMPUSER=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:3] GotoIf("PJSIP/100-0000007f", "0?report") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:5] Set("PJSIP/100-0000007f", "AMPUSER=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:6] GotoIf("PJSIP/100-0000007f", "0?limit") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:7] Set("PJSIP/100-0000007f", "AMPUSERCIDNAME=Colin") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:8] GotoIf("PJSIP/100-0000007f", "0?report") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:9] Set("PJSIP/100-0000007f", "AMPUSERCID=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:10] Set("PJSIP/100-0000007f", "__DIAL_OPTIONS=Ttr") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:12] GotoIf("PJSIP/100-0000007f", "0?limit") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:29] Set("PJSIP/100-0000007f", "CALLERID(number)=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:30] Set("PJSIP/100-0000007f", "CALLERID(name)=Colin") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:31] GotoIf("PJSIP/100-0000007f", "0?cnum") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:32] Set("PJSIP/100-0000007f", "CDR(cnam)=Colin") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:33] Set("PJSIP/100-0000007f", "CDR(cnum)=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:34] Set("PJSIP/100-0000007f", "CHANNEL(language)=en") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:1] GotoIf("PJSIP/100-0000007f", "0?initialized") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:2] Set("PJSIP/100-0000007f", "__REC_STATUS=INITIALIZED") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:3] Set("PJSIP/100-0000007f", "NOW=1504520424") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:4] Set("PJSIP/100-0000007f", "__DAY=04") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:5] Set("PJSIP/100-0000007f", "__MONTH=09") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:6] Set("PJSIP/100-0000007f", "__YEAR=2017") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:7] Set("PJSIP/100-0000007f", "__TIMESTR=20170904-112024") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:8] Set("PJSIP/100-0000007f", "__FROMEXTEN=100") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:9] Set("PJSIP/100-0000007f", "__MON_FMT=wav") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:10] NoOp("PJSIP/100-0000007f", "Recordings initialized") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set("PJSIP/100-0000007f", "RECMODE=dontcare") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:8] Return("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set("PJSIP/100-0000007f", "MOHCLASS=default") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:5] Set("PJSIP/100-0000007f", "_NODEST=") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set("PJSIP/100-0000007f", "DIAL_TRUNK=3") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:3] GotoIf("PJSIP/100-0000007f", "0?disabletrunk,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:4] Set("PJSIP/100-0000007f", "DIAL_NUMBER=0872910755") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:9] GotoIf("PJSIP/100-0000007f", "0?skipoutcid") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:11] Macro("PJSIP/100-0000007f", "outbound-callerid,3") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:7] Set("PJSIP/100-0000007f", "USEROUTCID=") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:8] Set("PJSIP/100-0000007f", "EMERGENCYCID=") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:9] Set("PJSIP/100-0000007f", "TRUNKOUTCID=111807") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:13] Set("PJSIP/100-0000007f", "OUTNUM=0872910755") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:14] Set("PJSIP/100-0000007f", "custom=PJSIP") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] MacroExit("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:18] GotoIf("PJSIP/100-0000007f", "0?bypass,1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:22] GotoIf("PJSIP/100-0000007f", "0?customtrunk") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]:23] Dial("PJSIP/100-0000007f", "PJSIP/[email protected],300,T") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] app_dial.c: Called PJSIP/[email protected]
[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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Set("PJSIP/100-0000007f", "RC=1") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Progress("PJSIP/100-0000007f", "") in new stack
[2017-09-04 11:20:24] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]:1] Macro("PJSIP/100-0000007f", "hangupcall") in new stack
[2017-09-04 11:20:26] VERBOSE[4794][C-00000034] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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/[email protected]
[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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set("PJSIP/100-0000000a", "AMPUSER=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:3] GotoIf("PJSIP/100-0000000a", "0?report") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]:5] Set("PJSIP/100-0000000a", "AMPUSER=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:6] GotoIf("PJSIP/100-0000000a", "0?limit") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:7] Set("PJSIP/100-0000000a", "AMPUSERCIDNAME=Colin") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:8] GotoIf("PJSIP/100-0000000a", "0?report") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:9] Set("PJSIP/100-0000000a", "AMPUSERCID=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:10] Set("PJSIP/100-0000000a", "__DIAL_OPTIONS=Ttr") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]:12] GotoIf("PJSIP/100-0000000a", "0?limit") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:29] Set("PJSIP/100-0000000a", "CALLERID(number)=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:30] Set("PJSIP/100-0000000a", "CALLERID(name)=Colin") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:31] GotoIf("PJSIP/100-0000000a", "0?cnum") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:32] Set("PJSIP/100-0000000a", "CDR(cnam)=Colin") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:33] Set("PJSIP/100-0000000a", "CDR(cnum)=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:34] Set("PJSIP/100-0000000a", "CHANNEL(language)=en") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]:1] GotoIf("PJSIP/100-0000000a", "0?initialized") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:2] Set("PJSIP/100-0000000a", "__REC_STATUS=INITIALIZED") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:3] Set("PJSIP/100-0000000a", "NOW=1504534968") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:4] Set("PJSIP/100-0000000a", "__DAY=04") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:5] Set("PJSIP/100-0000000a", "__MONTH=09") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:6] Set("PJSIP/100-0000000a", "__YEAR=2017") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:7] Set("PJSIP/100-0000000a", "__TIMESTR=20170904-152248") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:8] Set("PJSIP/100-0000000a", "__FROMEXTEN=100") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:9] Set("PJSIP/100-0000000a", "__MON_FMT=wav") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:10] NoOp("PJSIP/100-0000000a", "Recordings initialized") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set("PJSIP/100-0000000a", "RECMODE=dontcare") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:8] Return("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set("PJSIP/100-0000000a", "MOHCLASS=default") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:5] Set("PJSIP/100-0000000a", "_NODEST=") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set("PJSIP/100-0000000a", "DIAL_TRUNK=3") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]:3] GotoIf("PJSIP/100-0000000a", "0?disabletrunk,1") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:4] Set("PJSIP/100-0000000a", "DIAL_NUMBER=0872910755") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]cro-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 [[email protected]: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 [[email protected]:11] Macro("PJSIP/100-0000000a", "outbound-callerid,3") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:7] Set("PJSIP/100-0000000a", "USEROUTCID=111807") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:8] Set("PJSIP/100-0000000a", "EMERGENCYCID=") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:9] Set("PJSIP/100-0000000a", "TRUNKOUTCID=111807") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:13] Set("PJSIP/100-0000000a", "OUTNUM=0872910755") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:14] Set("PJSIP/100-0000000a", "custom=PJSIP") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] MacroExit("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:18] GotoIf("PJSIP/100-0000000a", "0?bypass,1") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:22] GotoIf("PJSIP/100-0000000a", "0?customtrunk") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:23] Dial("PJSIP/100-0000000a", "PJSIP/[email protected],300,T") in new stack
[2017-09-04 15:22:48] VERBOSE[2983][C-00000005] app_dial.c: Called PJSIP/[email protected]
[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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Set("PJSIP/100-0000000a", "RC=21") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:7] Macro("PJSIP/100-0000000a", "outisbusy,") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:1] Progress("PJSIP/100-0000000a", "") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:2] GotoIf("PJSIP/100-0000000a", "0?emergency,1") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]:3] GotoIf("PJSIP/100-0000000a", "0?intracompany,1") in new stack
[2017-09-04 15:22:53] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]:1] Macro("PJSIP/100-0000000a", "hangupcall") in new stack
[2017-09-04 15:22:55] VERBOSE[2983][C-00000005] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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