Hey Guys,
We are using Asterisk 10.12.10 with FreePBX 2.10.1.6 (I know, I know, we are migrating everything off to a LTS release of Asterisk along with the new FreePBX)
Anyways, we are having a call forwarding issue where if an external caller calls into our company, an internal users has call forwarding set, the system never forwards the call out, it fails and I get the generic call cannot be completed as dialed message.
The weird thing is, if an internal employee calls that extension using the extension number, it will forward the call out correctly. This just doesn’t work when an external caller calls the extension and call forwarding activated to an external number.
The extension is 188, with FollowMe set to forward to the number 0157314…
The number calling into the system is: 01763155…
(Location is Germany)
I have tried:
“Block Foreign CID’s” inside the trunk.
Tried transferring manually - works
Tried disabling FollowMe and using a MiscDestination and set it to failover there if no answer.
Here is the full output:
-- Executing [exten@sub-record-check:12] Return("SIP/colt-000076d7",
"") in new stack
-- Executing [188@from-did-direct:18] Set("SIP/colt-000076d7",
"RingGroupMethod=ringallv2") in new stack
-- Executing [188@from-did-direct:19] Set("SIP/colt-000076d7",
"_FMGRP=188") in new stack
-- Executing [188@from-did-direct:20] GotoIf("SIP/colt-000076d7", "0?
doconfirm") in new stack
-- Executing [188@from-did-direct:21] Macro("SIP/colt-000076d7", "dial,
24,trTL(14400000)I,0015731440553#") in new stack
-- Executing [s@macro-dial:1] GotoIf("SIP/colt-000076d7", "1?dial") in
new stack
-- Goto (macro-dial,s,3)
-- Executing [s@macro-dial:3] AGI("SIP/colt-000076d7",
"dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is '017631553432' number is
'0017631553432'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'ringallv2'
-- dialparties.agi: Added extension 0015731440553# to extension map
> dialparties.agi: got fmgrp_prering: 4, fmgrp_grptime: 20
> dialparties.agi: fmgrp_totalprering: 24
> dialparties.agi: extension not in group list, ringing only during
prering time
> dialparties.agi: ringallv2 ring times: REALPRERING: 4, PRERING: 4
> dialparties.agi: Extension 0015731440553# has call screening off
-- dialparties.agi: Extension 188 cf is disabled
-- dialparties.agi: Extension 0015731440553# cf is disabled
-- dialparties.agi: Extension 188 do not disturb is disabled
> dialparties.agi: extnum 188 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 188 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 188
-- dialparties.agi: dbset CALLTRACE/188 to 0017631553432
> dialparties.agi: extnum 0015731440553# has: cw: 0; hascfb: 0 []
hascfu: 0 []
> dialparties.agi: Built External dialstring component for
0015731440553: Local/0015731440553@from-internal/n
-- dialparties.agi: Filtered ARG3: 188-0015731440553
> dialparties.agi: NODEST: 188 adding M(auto-blkvm) to dialopts:
trTL(14400000)IM(auto-blkvm)
> dialparties.agi: NODEST: 188 blkvm enabled macro already in
dialopts: trTL(14400000)IM(auto-blkvm)
-- <SIP/colt-000076d7>AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("SIP/colt-000076d7", "Local/
FMPR-188@from-internal&Local/FMGL-0015731440553#@from-internal,
24,trTL(14400000)IM(auto-blkvm)") in new stack
-- Setting call duration limit to 14400.000 seconds.
-- Called Local/FMPR-188@from-internal
-- Executing [FMPR-188@from-internal:1] NoCDR("Local/FMPR-188@from-
internal-00000651;2", "") in new stack
-- Called Local/FMGL-0015731440553#@from-internal
-- Executing [FMPR-188@from-internal:2] Set("Local/FMPR-188@from-
internal-00000651;2", "RingGroupMethod=") in new stack
-- Executing [FMGL-0015731440553#@from-internal:1] NoCDR("Local/
FMGL-0015731440553#@from-internal-00000652;2", "") in new stack
-- Executing [FMPR-188@from-internal:3] Set("Local/FMPR-188@from-
internal-00000651;2", "USE_CONFIRMATION=") in new stack
-- Executing [FMGL-0015731440553#@from-internal:2] Set("Local/
FMGL-0015731440553#@from-internal-00000652;2", "ENDLOOP=1413540849") in new
stack
-- Executing [FMPR-188@from-internal:4] Set("Local/FMPR-188@from-
internal-00000651;2", "RINGGROUP_INDEX=") in new stack
-- Executing [FMGL-0015731440553#@from-internal:3] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "0?dodnd") in new stack
-- Executing [FMPR-188@from-internal:5] Macro("Local/FMPR-188@from-
internal-00000651;2", "simple-dial,188,4") in new stack
-- Executing [FMGL-0015731440553#@from-internal:4] Wait("Local/
FMGL-0015731440553#@from-internal-00000652;2", "1") in new stack
-- Executing [s@macro-simple-dial:1] Set("Local/FMPR-188@from-
internal-00000651;2", "__EXTTOCALL=188") in new stack
-- Executing [s@macro-simple-dial:2] Set("Local/FMPR-188@from-
internal-00000651;2", "RT=4") in new stack
-- Executing [s@macro-simple-dial:3] Set("Local/FMPR-188@from-
internal-00000651;2", "CFUEXT=") in new stack
-- Executing [s@macro-simple-dial:4] Set("Local/FMPR-188@from-
internal-00000651;2", "CFBEXT=") in new stack
-- Executing [s@macro-simple-dial:5] Set("Local/FMPR-188@from-
internal-00000651;2", "CWI_TMP=") in new stack
-- Executing [s@macro-simple-dial:6] Macro("Local/FMPR-188@from-
internal-00000651;2", "dial-one,4,trTL(14400000),188") in new stack
-- Executing [s@macro-dial-one:1] Set("Local/FMPR-188@from-
internal-00000651;2", "DEXTEN=188") in new stack
-- Executing [s@macro-dial-one:2] Set("Local/FMPR-188@from-
internal-00000651;2", "DIALSTATUS_CW=") in new stack
-- Executing [s@macro-dial-one:3] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "0?screen,1()") in new stack
-- Executing [s@macro-dial-one:4] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "0?cf,1()") in new stack
-- Executing [s@macro-dial-one:5] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "1?skip1") in new stack
-- Goto (macro-dial-one,s,8)
-- Executing [s@macro-dial-one:8] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?nodial") in new stack
-- Executing [s@macro-dial-one:9] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?continue") in new stack
-- Executing [s@macro-dial-one:10] Set("Local/FMPR-188@from-

internal-00000651;2", "EXTHASCW=") in new stack
-- Executing [s@macro-dial-one:11] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "1?next1:cwinusebusy") in new stack
-- Goto (macro-dial-one,s,12)
-- Executing [s@macro-dial-one:12] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?docfu:skip3") in new stack
-- Goto (macro-dial-one,s,16)
-- Executing [s@macro-dial-one:16] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "1?next2:continue") in new stack
-- Goto (macro-dial-one,s,17)
-- Executing [s@macro-dial-one:17] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "1?continue") in new stack
-- Goto (macro-dial-one,s,25)
-- Executing [s@macro-dial-one:25] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?nodial") in new stack
-- Executing [s@macro-dial-one:26] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "1?dstring,1():dlocal,1()") in new stack
-- Executing [dstring@macro-dial-one:1] Set("Local/FMPR-188@from-
internal-00000651;2", "DSTRING=") in new stack
-- Executing [dstring@macro-dial-one:2] Set("Local/FMPR-188@from-
internal-00000651;2", "DEVICES=188") in new stack
-- Executing [dstring@macro-dial-one:3] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?Return()") in new stack
-- Executing [dstring@macro-dial-one:4] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?Set(DEVICES=88)") in new stack
-- Executing [dstring@macro-dial-one:5] Set("Local/FMPR-188@from-
internal-00000651;2", "LOOPCNT=1") in new stack
-- Executing [dstring@macro-dial-one:6] Set("Local/FMPR-188@from-
internal-00000651;2", "ITER=1") in new stack
-- Executing [dstring@macro-dial-one:7] Set("Local/FMPR-188@from-
internal-00000651;2", "THISDIAL=SIP/188") in new stack
-- Executing [dstring@macro-dial-one:8] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "1?zap2dahdi,1()") in new stack
-- Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?Return()") in new stack
-- Executing [zap2dahdi@macro-dial-one:2] Set("Local/FMPR-188@from-
internal-00000651;2", "NEWDIAL=") in new stack
-- Executing [zap2dahdi@macro-dial-one:3] Set("Local/FMPR-188@from-
internal-00000651;2", "LOOPCNT2=1") in new stack
-- Executing [zap2dahdi@macro-dial-one:4] Set("Local/FMPR-188@from-
internal-00000651;2", "ITER2=1") in new stack
-- Executing [zap2dahdi@macro-dial-one:5] Set("Local/FMPR-188@from-
internal-00000651;2", "THISPART2=SIP/188") in new stack
-- Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?Set(THISPART2=DAHDI/188)") in new stack
-- Executing [zap2dahdi@macro-dial-one:7] Set("Local/FMPR-188@from-
internal-00000651;2", "NEWDIAL=SIP/188&") in new stack
-- Executing [zap2dahdi@macro-dial-one:8] Set("Local/FMPR-188@from-
internal-00000651;2", "ITER2=2") in new stack
-- Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/FMPR-188@from-

internal-00000651;2", "0?begin2") in new stack
-- Executing [zap2dahdi@macro-dial-one:10] Set("Local/FMPR-188@from-
internal-00000651;2", "THISDIAL=SIP/188") in new stack
-- Executing [zap2dahdi@macro-dial-one:11] Return("Local/FMPR-188@from-
internal-00000651;2", "") in new stack
-- Executing [dstring@macro-dial-one:9] Set("Local/FMPR-188@from-
internal-00000651;2", "DSTRING=SIP/188&") in new stack
-- Executing [dstring@macro-dial-one:10] Set("Local/FMPR-188@from-
internal-00000651;2", "ITER=2") in new stack
-- Executing [dstring@macro-dial-one:11] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?begin") in new stack
-- Executing [dstring@macro-dial-one:12] Set("Local/FMPR-188@from-
internal-00000651;2", "DSTRING=SIP/188") in new stack
-- Executing [dstring@macro-dial-one:13] Return("Local/FMPR-188@from-
internal-00000651;2", "") in new stack
-- Executing [s@macro-dial-one:27] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?nodial") in new stack
-- Executing [s@macro-dial-one:28] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?skiptrace") in new stack
-- Executing [s@macro-dial-one:29] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "1?ctset,1():ctclear,1()") in new stack
-- Executing [ctset@macro-dial-one:1] Set("Local/FMPR-188@from-
internal-00000651;2", "DB(CALLTRACE/188)=0017631553432") in new stack
-- Executing [ctset@macro-dial-one:2] Return("Local/FMPR-188@from-
internal-00000651;2", "") in new stack
-- Executing [s@macro-dial-one:30] Set("Local/FMPR-188@from-
internal-00000651;2", "D_OPTIONS=trTL(14400000)M(auto-blkvm)") in new stack
-- Executing [s@macro-dial-one:31] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?SIPAddHeader(Alert-Info: )") in new stack
-- Executing [s@macro-dial-one:32] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?SIPAddHeader()") in new stack
-- Executing [s@macro-dial-one:33] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?Set(CHANNEL(musicclass)=)") in new stack
-- Executing [s@macro-dial-one:34] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "0?qwait,1()") in new stack
-- Executing [s@macro-dial-one:35] Set("Local/FMPR-188@from-
internal-00000651;2", "__CWIGNORE=") in new stack
-- Executing [s@macro-dial-one:36] Set("Local/FMPR-188@from-
internal-00000651;2", "__KEEPCID=TRUE") in new stack
-- Executing [s@macro-dial-one:37] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "0?usegoto,1") in new stack
-- Executing [s@macro-dial-one:38] GotoIf("Local/FMPR-188@from-
internal-00000651;2", "1?godial") in new stack
-- Goto (macro-dial-one,s,42)
-- Executing [s@macro-dial-one:42] Dial("Local/FMPR-188@from-
internal-00000651;2", "SIP/188,4,trTL(14400000)M(auto-blkvm)") in new stack
-- Setting call duration limit to 14400.000 seconds.
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called SIP/188

 -- Local/FMPR-188@from-internal-00000651;1 is ringing
-- Connected line update to SIP/colt-000076d7 prevented.
-- Connected line update to SIP/colt-000076d7 prevented.
-- SIP/188-000076d8 is ringing
-- Local/FMPR-188@from-internal-00000651;1 is ringing
-- Executing [FMGL-0015731440553#@from-internal:5] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "1?start") in new stack
-- Goto (from-internal,FMGL-0015731440553#,3)
-- Executing [FMGL-0015731440553#@from-internal:3] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "0?dodnd") in new stack
-- Executing [FMGL-0015731440553#@from-internal:4] Wait("Local/
FMGL-0015731440553#@from-internal-00000652;2", "1") in new stack
-- Executing [FMGL-0015731440553#@from-internal:5] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "1?start") in new stack
-- Goto (from-internal,FMGL-0015731440553#,3)
-- Executing [FMGL-0015731440553#@from-internal:3] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "0?dodnd") in new stack
-- Executing [FMGL-0015731440553#@from-internal:4] Wait("Local/
FMGL-0015731440553#@from-internal-00000652;2", "1") in new stack
-- Executing [FMGL-0015731440553#@from-internal:5] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "1?start") in new stack
-- Goto (from-internal,FMGL-0015731440553#,3)
-- Executing [FMGL-0015731440553#@from-internal:3] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "0?dodnd") in new stack
-- Executing [FMGL-0015731440553#@from-internal:4] Wait("Local/
FMGL-0015731440553#@from-internal-00000652;2", "1") in new stack
-- Executing [h@macro-dial-one:1] Macro("SIP/colt-000076bf",
"hangupcall,") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/colt-000076bf", "1?
theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s@macro-hangupcall:3] ExecIf("SIP/colt-000076bf", "0?
Set(CDR(recordingfile)=)") in new stack
-- Executing [s@macro-hangupcall:4] Hangup("SIP/colt-000076bf", "") in
new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/
colt-000076bf' in macro 'hangupcall'
== Spawn extension (macro-dial-one, h, 1) exited non-zero on 'SIP/
colt-000076bf'
== Spawn extension (macro-dial-one, s, 42) exited non-zero on 'SIP/
colt-000076bf' in macro 'dial-one'
== Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/
colt-000076bf' in macro 'exten-vm'
== Spawn extension (from-internal-xfer, 115, 2) exited non-zero on 'SIP/
colt-000076bf'
-- Executing [FMGL-0015731440553#@from-internal:5] GotoIf("Local/
FMGL-0015731440553#@from-internal-00000652;2", "0?start") in new stack
-- Executing [FMGL-0015731440553#@from-internal:6] Set("Local/
FMGL-0015731440553#@from-internal-00000652;2", "SHARED(FM_DND,SIP/
colt-000076d7)=") in new stack
-- Executing [FMGL-0015731440553#@from-internal:7] Macro("Local/
FMGL-0015731440553#@from-internal-00000652;2", "dial,20,trTL(14400000),
0015731440553#") in new stack
-- Executing [s@macro-dial:1] GotoIf("Local/FMGL-0015731440553#@from-
internal-00000652;2", "1?dial") in new stack
-- Goto (macro-dial,s,3)
-- Executing [s@macro-dial:3] AGI("Local/FMGL-0015731440553#@from-
internal-00000652;2", "dialparties.agi") in new stack
-- Nobody picked up in 4000 ms
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
-- Executing [s@macro-dial-one:43] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?Set(DIALSTATUS=)") in new stack
-- Executing [s@macro-dial-one:44] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "0?s-NOANSWER,1()") in new stack
-- Executing [s@macro-dial-one:45] MacroExit("Local/FMPR-188@from-
internal-00000651;2", "") in new stack
-- Executing [s@macro-simple-dial:7] Set("Local/FMPR-188@from-
internal-00000651;2", "__CWIGNORE=") in new stack
-- Executing [s@macro-simple-dial:8] Set("Local/FMPR-188@from-
internal-00000651;2", "PR_DIALSTATUS=NOANSWER") in new stack
-- Executing [s@macro-simple-dial:9] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "0?docfu,1()") in new stack
-- Executing [s@macro-simple-dial:10] GosubIf("Local/FMPR-188@from-
internal-00000651;2", "0?docfb,1()") in new stack
-- Executing [s@macro-simple-dial:11] Set("Local/FMPR-188@from-
internal-00000651;2", "DIALSTATUS=NOANSWER") in new stack
-- Executing [s@macro-simple-dial:12] Goto("Local/FMPR-188@from-
internal-00000651;2", "s-NOANSWER,1") in new stack
-- Goto (macro-simple-dial,s-NOANSWER,1)
-- Executing [s-NOANSWER@macro-simple-dial:1] NoOp("Local/
FMPR-188@from-internal-00000651;2", "Extension is reporting s-NOANSWER") in
new stack
-- Executing [FMPR-188@from-internal:6] ExecIf("Local/FMPR-188@from-
internal-00000651;2", "0?Set(SHARED(FM_DND,SIP/colt-000076d7)=DND)") in new
stack
-- Executing [FMPR-188@from-internal:7] Hangup("Local/FMPR-188@from-
internal-00000651;2", "") in new stack
== Spawn extension (from-internal, FMPR-188, 7) exited non-zero on
'Local/FMPR-188@from-internal-00000651;2'
-- Executing [h@from-internal:1] Hangup("Local/FMPR-188@from-
internal-00000651;2", "") in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'Local/
FMPR-188@from-internal-00000651;2'
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is '017631553432' number is
'0017631553432'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'ringall'
-- dialparties.agi: Added extension 0015731440553# to extension map

-- dialparties.agi: Extension 0015731440553# cf is disabled
> dialparties.agi: extnum 0015731440553# has: cw: 0; hascfb: 0 []
hascfu: 0 []
> dialparties.agi: Built External dialstring component for
0015731440553: Local/0015731440553@from-internal/n
-- dialparties.agi: Filtered ARG3: 0015731440553
> dialparties.agi: NODEST: 188 adding M(auto-blkvm) to dialopts:
trTL(14400000)M(auto-blkvm)
> dialparties.agi: NODEST: 188 blkvm enabled macro already in
dialopts: trTL(14400000)M(auto-blkvm)
-- <Local/FMGL-0015731440553#@from-internal-00000652;2>AGI Script
dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("Local/FMGL-0015731440553#@from-
internal-00000652;2", "Local/0015731440553@from-internal/n,
20,trTL(14400000)M(auto-blkvm)") in new stack
-- Setting call duration limit to 14400.000 seconds.
-- Called Local/0015731440553@from-internal/n
-- Local/FMGL-0015731440553#@from-internal-00000652;1 is ringing
-- Executing [0015731440553@from-internal:1] Macro("Local/
0015731440553@from-internal-00000653;2", "user-callerid,LIMIT,") in new
stack
-- Executing [s@macro-user-callerid:1] Set("Local/0015731440553@from-
internal-00000653;2", "AMPUSER=0017631553432") in new stack
-- Executing [s@macro-user-callerid:2] GotoIf("Local/
0015731440553@from-internal-00000653;2", "0?report") in new stack
-- Executing [s@macro-user-callerid:3] ExecIf("Local/
0015731440553@from-internal-00000653;2", "1?
Set(REALCALLERIDNUM=0017631553432)") in new stack
-- Executing [s@macro-user-callerid:4] Set("Local/0015731440553@from-
internal-00000653;2", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:5] Set("Local/0015731440553@from-
internal-00000653;2", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("Local/
0015731440553@from-internal-00000653;2", "1?report") in new stack
-- Goto (macro-user-callerid,s,13)
-- Executing [s@macro-user-callerid:13] GotoIf("Local/
0015731440553@from-internal-00000653;2", "1?continue") in new stack
-- Goto (macro-user-callerid,s,26)
-- Executing [s@macro-user-callerid:26] Set("Local/0015731440553@from-
internal-00000653;2", "CALLERID(number)=0017631553432") in new stack
-- Executing [s@macro-user-callerid:27] Set("Local/0015731440553@from-
internal-00000653;2", "CALLERID(name)=017631553432") in new stack
-- Executing [s@macro-user-callerid:28] Set("Local/0015731440553@from-
internal-00000653;2", "CHANNEL(language)=de") in new stack
-- Executing [0015731440553@from-internal:2] NoCDR("Local/
0015731440553@from-internal-00000653;2", "") in new stack
-- Executing [0015731440553@from-internal:3] Progress("Local/
0015731440553@from-internal-00000653;2", "") in new stack
-- Local/0015731440553@from-internal-00000653;1 is making progress
passing it to Local/FMGL-0015731440553#@from-internal-00000652;2

-- Executing [0015731440553@from-internal:4] Wait("Local/
0015731440553@from-internal-00000653;2", "1") in new stack
-- Executing [0015731440553@from-internal:5] Progress("Local/
0015731440553@from-internal-00000653;2", "") in new stack
-- Local/0015731440553@from-internal-00000653;1 is making progress
passing it to Local/FMGL-0015731440553#@from-internal-00000652;2
-- Executing [0015731440553@from-internal:6] Playback("Local/
0015731440553@from-internal-00000653;2", "silence/1&cannot-complete-as-
dialed&check-number-dial-again,noanswer") in new stack
-- <Local/0015731440553@from-internal-00000653;2> Playing 'silence/
1.gsm' (language 'de')
-- <Local/0015731440553@from-internal-00000653;2> Playing 'cannot-
complete-as-dialed.alaw' (language 'de')
-- <Local/0015731440553@from-internal-00000653;2> Playing 'check-
number-dial-again.alaw' (language 'de')
-- Executing [0015731440553@from-internal:7] Wait("Local/
0015731440553@from-internal-00000653;2", "1") in new stack
-- Executing [0015731440553@from-internal:8] Congestion("Local/
0015731440553@from-internal-00000653;2", "20") in new stack
-- Local/0015731440553@from-internal-00000653;1 is circuit-busy
== Everyone is busy/congested at this time (1:0/1/0)
-- Executing [s@macro-dial:8] Set("Local/FMGL-0015731440553#@from-
internal-00000652;2", "DIALSTATUS=CONGESTION") in new stack
-- Executing [s@macro-dial:9] GosubIf("Local/FMGL-0015731440553#@from-
internal-00000652;2", "0?CONGESTION,1") in new stack
[2014-10-17 12:14:17] WARNING[2545]: channel.c:4827 ast_prod: Prodding
channel 'Local/0015731440553@from-internal-00000653;2' failed
-- Executing [FMGL-0015731440553#@from-internal:8] Hangup("Local/
FMGL-0015731440553#@from-internal-00000652;2", "") in new stack
== Spawn extension (from-internal, FMGL-0015731440553#, 8) exited non-
zero on 'Local/FMGL-0015731440553#@from-internal-00000652;2'
== Spawn extension (from-internal, 0015731440553, 8) exited non-zero on
'Local/0015731440553@from-internal-00000653;2'
-- Executing [h@from-internal:1] Hangup("Local/
FMGL-0015731440553#@from-internal-00000652;2", "") in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'Local/
FMGL-0015731440553#@from-internal-00000652;2'
-- No one is available to answer at this time (2:0/0/0)
-- Executing [h@from-internal:1] Hangup("Local/0015731440553@from-
internal-00000653;2", "") in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'Local/
0015731440553@from-internal-00000653;2'
-- Executing [s@macro-dial:8] Set("SIP/colt-000076d7",
"DIALSTATUS=NOANSWER") in new stack
-- Executing [s@macro-dial:9] GosubIf("SIP/colt-000076d7", "0?NOANSWER,
1") in new stack
-- Executing [188@from-did-direct:22] Goto("SIP/colt-000076d7",
"nextstep") in new stack
-- Goto (from-did-direct,188,24)
-- Executing [188@from-did-direct:24] Set("SIP/colt-000076d7",

"RingGroupMethod=") in new stack
-- Executing [188@from-did-direct:25] GotoIf("SIP/colt-000076d7", "0?
nodest") in new stack
-- Executing [188@from-did-direct:26] Set("SIP/colt-000076d7",
"__NODEST=") in new stack
-- Executing [188@from-did-direct:27] Set("SIP/colt-000076d7",
"__PICKUPMARK=") in new stack
-- Executing [188@from-did-direct:28] Macro("SIP/colt-000076d7",
"blkvm-clr,") in new stack
-- Executing [s@macro-blkvm-clr:1] Set("SIP/colt-000076d7",
"SHARED(BLKVM,SIP/colt-000076d7)=") in new stack
-- Executing [s@macro-blkvm-clr:2] Set("SIP/colt-000076d7",
"GOSUB_RETVAL=") in new stack
-- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/colt-000076d7", "")
in new stack
-- Executing [188@from-did-direct:29] Set("SIP/colt-000076d7",
"DIALSTATUS=NOANSWER") in new stack
-- Executing [188@from-did-direct:30] Goto("SIP/colt-000076d7", "ext-
local,188,dest") in new stack
-- Goto (ext-local,188,3)
-- Executing [188@ext-local:3] Set("SIP/colt-000076d7",
"__PICKUPMARK=") in new stack
-- Executing [188@ext-local:4] Goto("SIP/colt-000076d7", ",return,1")
in new stack
-- Goto (ext-local,return,1)
[2014-10-17 12:14:17] WARNING[2540]: pbx.c:5803 __ast_pbx_run: Channel
'SIP/colt-000076d7' sent into invalid extension 'return' in context 'ext-
local', but no invalid handler
-- Executing [h@ext-local:1] Macro("SIP/colt-000076d7", "hangupcall,")
in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/colt-000076d7", "1?
theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s@macro-hangupcall:3] ExecIf("SIP/colt-000076d7", "0?
Set(CDR(recordingfile)=)") in new stack
-- Executing [s@macro-hangupcall:4] Hangup("SIP/colt-000076d7", "") in
new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/
colt-000076d7' in macro 'hangupcall'
== Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/
colt-000076d7'
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5

I can’t see anything in the SIP debug either.
I can post the entire output if needed.
Anyone have ideas?