Call Forwarding Issue

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 [[email protected]:12] Return("SIP/colt-000076d7",
"") in new stack
    -- Executing [[email protected]:18] Set("SIP/colt-000076d7",
"RingGroupMethod=ringallv2") in new stack
    -- Executing [[email protected]:19] Set("SIP/colt-000076d7",
"_FMGRP=188") in new stack
    -- Executing [[email protected]:20] GotoIf("SIP/colt-000076d7", "0?
doconfirm") in new stack
    -- Executing [[email protected]:21] Macro("SIP/colt-000076d7", "dial,
24,trTL(14400000)I,0015731440553#") in new stack
    -- Executing [[email protected]:1] GotoIf("SIP/colt-000076d7", "1?dial") in
new stack
    -- Goto (macro-dial,s,3)
    -- Executing [[email protected]: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/[email protected]/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 [[email protected]:7] Dial("SIP/colt-000076d7", "Local/
[email protected]&Local/FMGL-0015731440553#@from-internal,
24,trTL(14400000)IM(auto-blkvm)") in new stack
    -- Setting call duration limit to 14400.000 seconds.
    -- Called Local/[email protected]
    -- Executing [[email protected]:1] NoCDR("Local/[email protected]
internal-00000651;2", "") in new stack
    -- Called Local/FMGL-0015731440553#@from-internal
    -- Executing [[email protected]:2] Set("Local/[email protected]
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 [[email protected]:3] Set("Local/[email protected]
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 [[email protected]:4] Set("Local/[email protected]
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 [[email protected]:5] Macro("Local/[email protected]
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 [[email protected]:1] Set("Local/[email protected]
internal-00000651;2", "__EXTTOCALL=188") in new stack
    -- Executing [[email protected]:2] Set("Local/[email protected]
internal-00000651;2", "RT=4") in new stack
    -- Executing [[email protected]:3] Set("Local/[email protected]
internal-00000651;2", "CFUEXT=") in new stack
    -- Executing [[email protected]:4] Set("Local/[email protected]
internal-00000651;2", "CFBEXT=") in new stack
    -- Executing [[email protected]:5] Set("Local/[email protected]
internal-00000651;2", "CWI_TMP=") in new stack
    -- Executing [[email protected]:6] Macro("Local/[email protected]
internal-00000651;2", "dial-one,4,trTL(14400000),188") in new stack
    -- Executing [[email protected]:1] Set("Local/[email protected]
internal-00000651;2", "DEXTEN=188") in new stack
    -- Executing [[email protected]:2] Set("Local/[email protected]
internal-00000651;2", "DIALSTATUS_CW=") in new stack
    -- Executing [[email protected]:3] GosubIf("Local/[email protected]
internal-00000651;2", "0?screen,1()") in new stack
    -- Executing [[email protected]:4] GosubIf("Local/[email protected]
internal-00000651;2", "0?cf,1()") in new stack
    -- Executing [[email protected]:5] GotoIf("Local/[email protected]
internal-00000651;2", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,8)
    -- Executing [[email protected]:8] GotoIf("Local/[email protected]
internal-00000651;2", "0?nodial") in new stack
    -- Executing [[email protected]:9] GotoIf("Local/[email protected]
internal-00000651;2", "0?continue") in new stack
-- Executing [[email protected]:10] Set("Local/[email protected]

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

internal-00000651;2", "0?begin2") in new stack
    -- Executing [[email protected]:10] Set("Local/[email protected]
internal-00000651;2", "THISDIAL=SIP/188") in new stack
    -- Executing [[email protected]:11] Return("Local/[email protected]
internal-00000651;2", "") in new stack
    -- Executing [[email protected]:9] Set("Local/[email protected]
internal-00000651;2", "DSTRING=SIP/188&") in new stack
    -- Executing [[email protected]:10] Set("Local/[email protected]
internal-00000651;2", "ITER=2") in new stack
    -- Executing [[email protected]:11] GotoIf("Local/[email protected]
internal-00000651;2", "0?begin") in new stack
    -- Executing [[email protected]:12] Set("Local/[email protected]
internal-00000651;2", "DSTRING=SIP/188") in new stack
    -- Executing [[email protected]:13] Return("Local/[email protected]
internal-00000651;2", "") in new stack
    -- Executing [[email protected]:27] GotoIf("Local/[email protected]
internal-00000651;2", "0?nodial") in new stack
    -- Executing [[email protected]:28] GotoIf("Local/[email protected]
internal-00000651;2", "0?skiptrace") in new stack
    -- Executing [[email protected]:29] GosubIf("Local/[email protected]
internal-00000651;2", "1?ctset,1():ctclear,1()") in new stack
    -- Executing [[email protected]:1] Set("Local/[email protected]
internal-00000651;2", "DB(CALLTRACE/188)=0017631553432") in new stack
    -- Executing [[email protected]:2] Return("Local/[email protected]
internal-00000651;2", "") in new stack
    -- Executing [[email protected]:30] Set("Local/[email protected]
internal-00000651;2", "D_OPTIONS=trTL(14400000)M(auto-blkvm)") in new stack
    -- Executing [[email protected]:31] ExecIf("Local/[email protected]
internal-00000651;2", "0?SIPAddHeader(Alert-Info: )") in new stack
    -- Executing [[email protected]:32] ExecIf("Local/[email protected]
internal-00000651;2", "0?SIPAddHeader()") in new stack
    -- Executing [[email protected]:33] ExecIf("Local/[email protected]
internal-00000651;2", "0?Set(CHANNEL(musicclass)=)") in new stack
    -- Executing [[email protected]:34] GosubIf("Local/[email protected]
internal-00000651;2", "0?qwait,1()") in new stack
    -- Executing [[email protected]:35] Set("Local/[email protected]
internal-00000651;2", "__CWIGNORE=") in new stack
    -- Executing [[email protected]:36] Set("Local/[email protected]
internal-00000651;2", "__KEEPCID=TRUE") in new stack
    -- Executing [[email protected]:37] GotoIf("Local/[email protected]
internal-00000651;2", "0?usegoto,1") in new stack
    -- Executing [[email protected]:38] GotoIf("Local/[email protected]
internal-00000651;2", "1?godial") in new stack
    -- Goto (macro-dial-one,s,42)
    -- Executing [[email protected]:42] Dial("Local/[email protected]
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/[email protected];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/[email protected];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 [[email protected]:1] Macro("SIP/colt-000076bf",
"hangupcall,") in new stack
    -- Executing [[email protected]:1] GotoIf("SIP/colt-000076bf", "1?
theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Executing [[email protected]:3] ExecIf("SIP/colt-000076bf", "0?
Set(CDR(recordingfile)=)") in new stack
    -- Executing [[email protected]: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 [[email protected]:1] GotoIf("Local/FMGL-0015731440553#@from-
internal-00000652;2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [[email protected]: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 [[email protected]:43] ExecIf("Local/[email protected]
internal-00000651;2", "0?Set(DIALSTATUS=)") in new stack
    -- Executing [[email protected]:44] GosubIf("Local/[email protected]
internal-00000651;2", "0?s-NOANSWER,1()") in new stack
    -- Executing [[email protected]:45] MacroExit("Local/[email protected]
internal-00000651;2", "") in new stack
    -- Executing [[email protected]:7] Set("Local/[email protected]
internal-00000651;2", "__CWIGNORE=") in new stack
    -- Executing [[email protected]:8] Set("Local/[email protected]
internal-00000651;2", "PR_DIALSTATUS=NOANSWER") in new stack
    -- Executing [[email protected]:9] GosubIf("Local/[email protected]
internal-00000651;2", "0?docfu,1()") in new stack
    -- Executing [[email protected]:10] GosubIf("Local/[email protected]
internal-00000651;2", "0?docfb,1()") in new stack
    -- Executing [[email protected]:11] Set("Local/[email protected]
internal-00000651;2", "DIALSTATUS=NOANSWER") in new stack
    -- Executing [[email protected]:12] Goto("Local/[email protected]
internal-00000651;2", "s-NOANSWER,1") in new stack
    -- Goto (macro-simple-dial,s-NOANSWER,1)
    -- Executing [[email protected]ro-simple-dial:1] NoOp("Local/
[email protected];2", "Extension is reporting s-NOANSWER") in
new stack
    -- Executing [[email protected]:6] ExecIf("Local/[email protected]
internal-00000651;2", "0?Set(SHARED(FM_DND,SIP/colt-000076d7)=DND)") in new
stack
    -- Executing [[email protected]:7] Hangup("Local/[email protected]
internal-00000651;2", "") in new stack
  == Spawn extension (from-internal, FMPR-188, 7) exited non-zero on
'Local/[email protected];2'
    -- Executing [[email protected]:1] Hangup("Local/[email protected]
internal-00000651;2", "") in new stack
  == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/
[email protected];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/[email protected]/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 [[email protected]:7] Dial("Local/FMGL-0015731440553#@from-
internal-00000652;2", "Local/[email protected]/n,
20,trTL(14400000)M(auto-blkvm)") in new stack
    -- Setting call duration limit to 14400.000 seconds.
    -- Called Local/[email protected]/n
    -- Local/FMGL-0015731440553#@from-internal-00000652;1 is ringing
    -- Executing [[email protected]:1] Macro("Local/
[email protected];2", "user-callerid,LIMIT,") in new
stack
    -- Executing [[email protected]:1] Set("Local/[email protected]
internal-00000653;2", "AMPUSER=0017631553432") in new stack
    -- Executing [[email protected]:2] GotoIf("Local/
[email protected];2", "0?report") in new stack
    -- Executing [[email protected]:3] ExecIf("Local/
[email protected];2", "1?
Set(REALCALLERIDNUM=0017631553432)") in new stack
    -- Executing [[email protected]:4] Set("Local/[email protected]
internal-00000653;2", "AMPUSER=") in new stack
    -- Executing [[email protected]:5] Set("Local/[email protected]
internal-00000653;2", "AMPUSERCIDNAME=") in new stack
    -- Executing [[email protected]:6] GotoIf("Local/
[email protected];2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [[email protected]:13] GotoIf("Local/
[email protected];2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,26)
    -- Executing [[email protected]:26] Set("Local/[email protected]
internal-00000653;2", "CALLERID(number)=0017631553432") in new stack
    -- Executing [[email protected]:27] Set("Local/[email protected]
internal-00000653;2", "CALLERID(name)=017631553432") in new stack
    -- Executing [[email protected]:28] Set("Local/[email protected]
internal-00000653;2", "CHANNEL(language)=de") in new stack
    -- Executing [[email protected]:2] NoCDR("Local/
[email protected];2", "") in new stack
    -- Executing [[email protected]:3] Progress("Local/
[email protected];2", "") in new stack
    -- Local/[email protected];1 is making progress
passing it to Local/FMGL-0015731440553#@from-internal-00000652;2

    -- Executing [[email protected]:4] Wait("Local/
[email protected]-00000653;2", "1") in new stack
    -- Executing [[email protected]:5] Progress("Local/
[email protected];2", "") in new stack
    -- Local/[email protected];1 is making progress
passing it to Local/FMGL-0015731440553#@from-internal-00000652;2
    -- Executing [[email protected]:6] Playback("Local/
[email protected];2", "silence/1&cannot-complete-as-
dialed&check-number-dial-again,noanswer") in new stack
    -- <Local/[email protected];2> Playing 'silence/
1.gsm' (language 'de')
    -- <Local/[email protected];2> Playing 'cannot-
complete-as-dialed.alaw' (language 'de')
    -- <Local/[email protected];2> Playing 'check-
number-dial-again.alaw' (language 'de')
    -- Executing [[email protected]:7] Wait("Local/
[email protected];2", "1") in new stack
    -- Executing [[email protected]:8] Congestion("Local/
[email protected];2", "20") in new stack
    -- Local/[email protected];1 is circuit-busy
  == Everyone is busy/congested at this time (1:0/1/0)
    -- Executing [[email protected]:8] Set("Local/FMGL-0015731440553#@from-
internal-00000652;2", "DIALSTATUS=CONGESTION") in new stack
    -- Executing [[email protected]: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/[email protected];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/[email protected];2'
    -- Executing [[email protected]: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 [[email protected]:1] Hangup("Local/[email protected]
internal-00000653;2", "") in new stack
  == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/
[email protected];2'
    -- Executing [[email protected]:8] Set("SIP/colt-000076d7",
"DIALSTATUS=NOANSWER") in new stack
    -- Executing [[email protected]:9] GosubIf("SIP/colt-000076d7", "0?NOANSWER,
1") in new stack
    -- Executing [[email protected]:22] Goto("SIP/colt-000076d7",
"nextstep") in new stack
    -- Goto (from-did-direct,188,24)
-- Executing [[email protected]:24] Set("SIP/colt-000076d7",

"RingGroupMethod=") in new stack
    -- Executing [[email protected]:25] GotoIf("SIP/colt-000076d7", "0?
nodest") in new stack
    -- Executing [[email protected]:26] Set("SIP/colt-000076d7",
"__NODEST=") in new stack
    -- Executing [[email protected]:27] Set("SIP/colt-000076d7",
"__PICKUPMARK=") in new stack
    -- Executing [[email protected]:28] Macro("SIP/colt-000076d7",
"blkvm-clr,") in new stack
    -- Executing [[email protected]:1] Set("SIP/colt-000076d7",
"SHARED(BLKVM,SIP/colt-000076d7)=") in new stack
    -- Executing [[email protected]:2] Set("SIP/colt-000076d7",
"GOSUB_RETVAL=") in new stack
    -- Executing [[email protected]:3] MacroExit("SIP/colt-000076d7", "")
in new stack
    -- Executing [[email protected]:29] Set("SIP/colt-000076d7",
"DIALSTATUS=NOANSWER") in new stack
    -- Executing [[email protected]:30] Goto("SIP/colt-000076d7", "ext-
local,188,dest") in new stack
    -- Goto (ext-local,188,3)
    -- Executing [[email protected]:3] Set("SIP/colt-000076d7",
"__PICKUPMARK=") in new stack
    -- Executing [[email protected]: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 [[email protected]:1] Macro("SIP/colt-000076d7", "hangupcall,")
in new stack
    -- Executing [[email protected]:1] GotoIf("SIP/colt-000076d7", "1?
theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Executing [[email protected]:3] ExecIf("SIP/colt-000076d7", "0?
Set(CDR(recordingfile)=)") in new stack
    -- Executing [[email protected]: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?

Exact same problem here, did you ever find the answer?