Blind Transfer Won't Hangup

I’m using FreePBX 2.5.2.2 with Asterisk 1.6.1.4. If I make a call and
then decide to blind transfer them using ## my side of the call is not
hung up. Instead it sends me to voicemail. If somebody calls me and
then I blind transfer them with ## I am hung up on as expected.

I called from 8678 to 28688. I then transferred the call to 8532.
Asterisk acts like it wants to hang up, but then immediately runs
macro-dial. I’m not sure if this is a FreePBX or Asterisk issue.

== Spawn extension (macro-hangupcall, s, 11) exited non-zero on
’SIP/8678-c876bf18’ in macro ‘hangupcall’
== Spawn extension (macro-dial, h, 1) exited non-zero on ‘SIP/8678-c876bf18’

Here is the full log output.

– Transferring Local/[email protected];1 to ‘8532’
(context from-internal-xfer) priority 1
– Executing [[email protected]:1] Macro(“SIP/8678-c876bf18”,
“hangupcall”) in new stack
– Executing [[email protected]:1] ResetCDR(“SIP/8678-c876bf18”,
“w”) in new stack
– Executing [[email protected]:1]
GotoIf(“Local/[email protected];1”, “0?ext-local,8532,1”) in
new stack
– Executing [[email protected]:2]
Macro(“Local/[email protected];1”, “user-callerid,”) in new
stack
– Executing [[email protected]:1]
Set(“Local/[email protected];1”, “AMPUSER=8688”) in new stack
– Executing [[email protected]:2]
GotoIf(“Local/[email protected];1”, “1?report”) in new stack
– Goto (macro-user-callerid,s,11)
– Executing [[email protected]:11]
GotoIf(“Local/[email protected];1”, “0?continue”) in new stack
– Executing [[email protected]:12]
Set(“Local/[email protected];1”, “__TTL=63”) in new stack
– Executing [[email protected]:13]
GotoIf(“Local/[email protected];1”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,20)
– Executing [[email protected]:20]
NoOp(“Local/[email protected];1”, “Using CallerID “” <8688>”)
in new stack
– Executing [[email protected]:3]
GotoIf(“Local/[email protected];1”, “0?skipdb”) in new stack
– Executing [[email protected]:4]
GotoIf(“Local/[email protected];1”, “0?skipov”) in new stack
– Executing [[email protected]:5]
Set(“Local/[email protected];1”, “__NODEST=”) in new stack
– Executing [[email protected]:6]
Set(“Local/[email protected];1”,
"__BLKVM_OVERRIDE=BLKVM/8532/Local/[email protected];1") in new
stack
– Executing [[email protected]:7]
Set(“Local/[email protected];1”, “__BLKVM_BASE=8532”) in new
stack
– Executing [[email protected]:8]
Set(“Local/[email protected];1”,
“DB(BLKVM/8532/Local/[email protected];1)=TRUE”) in new stack
– Executing [[email protected]:9]
Set(“Local/[email protected];1”, “RRNODEST=”) in new stack
– Executing [[email protected]:10]
Set(“Local/[email protected];1”, “__NODEST=8532”) in new stack
– Executing [[email protected]:11]
Set(“Local/[email protected];1”, “RecordMethod=Group”) in new
stack
– Executing [[email protected]:12]
Macro(“Local/[email protected];1”,
“record-enable,8532-38532-48532,Group”) in new stack
– Executing [[email protected]:1]
GotoIf(“Local/[email protected];1”, “0?check”) in new stack
– Executing [[email protected]:2]
ResetCDR(“Local/[email protected];1”, “w”) in new stack
– Executing [[email protected]:2] NoCDR(“SIP/8678-c876bf18”, “”)
in new stack
– Executing [[email protected]:3] GotoIf(“SIP/8678-c876bf18”,
“1?skiprg”) in new stack
– Goto (macro-hangupcall,s,6)
– Executing [[email protected]:6] GotoIf(“SIP/8678-c876bf18”,
“0?skipblkvm”) in new stack
– Executing [[email protected]:7] NoOp(“SIP/8678-c876bf18”,
“Cleaning Up Block VM Flag: BLKVM/8688/SIP/8678-c876bf18”) in new
stack
– Executing [[email protected]:8] DBdel(“SIP/8678-c876bf18”,
“BLKVM/8688/SIP/8678-c876bf18”) in new stack
– DBdel: family=BLKVM, key=8688/SIP/8678-c876bf18
– DBdel: Error deleting key from database.
– Executing [[email protected]:9] GotoIf(“SIP/8678-c876bf18”,
“1?theend”) in new stack
– Goto (macro-hangupcall,s,11)
– Executing [[email protected]:11] Hangup(“SIP/8678-c876bf18”,
"") in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on
’SIP/8678-c876bf18’ in macro ‘hangupcall’
== Spawn extension (macro-dial, h, 1) exited non-zero on ‘SIP/8678-c876bf18’
– Executing [[email protected]:8] Set(“SIP/8678-c876bf18”,
“DIALSTATUS=ANSWER”) in new stack
– Executing [[email protected]:9] GosubIf(“SIP/8678-c876bf18”,
“0?ANSWER,1”) in new stack
– Executing [[email protected]:19] Goto(“SIP/8678-c876bf18”,
“nextstep”) in new stack
– Goto (from-internal,8688,21)
– Executing [[email protected]:21] Set(“SIP/8678-c876bf18”,
“RingGroupMethod=”) in new stack
– Executing [[email protected]:22] GotoIf(“SIP/8678-c876bf18”,
“0?nodest”) in new stack
– Executing [[email protected]:23] Set(“SIP/8678-c876bf18”,
"__NODEST=") in new stack
– Executing [[email protected]:24] DBdel(“SIP/8678-c876bf18”,
“BLKVM/8688/SIP/8678-c876bf18”) in new stack
– DBdel: family=BLKVM, key=8688/SIP/8678-c876bf18
– DBdel: Error deleting key from database.
– Executing [[email protected]:25] Goto(“SIP/8678-c876bf18”,
“ext-local,vmb8688,1”) in new stack
– Goto (ext-local,vmb8688,1)
– Executing [[email protected]:1] Macro(“SIP/8678-c876bf18”,
“vm,8688,BUSY,”) in new stack
– Executing [[email protected]:1] Macro(“SIP/8678-c876bf18”,
“user-callerid,SKIPTTL”) in new stack
– Executing [[email protected]:1] Set(“SIP/8678-c876bf18”,
“AMPUSER=8678”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/8678-c876bf18”,
“0?report”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/8678-c876bf18”,
“0?Set(REALCALLERIDNUM=8678)”) in new stack
– Executing [[email protected]:4] Set(“SIP/8678-c876bf18”,
“AMPUSER=8678”) in new stack
– Executing [[email protected]:5] Set(“SIP/8678-c876bf18”,
“AMPUSERCIDNAME=Ryan Wagoner”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/8678-c876bf18”,
“0?report”) in new stack
– Executing [[email protected]:7] Set(“SIP/8678-c876bf18”,
“AMPUSERCID=8678”) in new stack
– Executing [[email protected]:8] Set(“SIP/8678-c876bf18”,
“CALLERID(all)=“Ryan Wagoner” <8678>”) in new stack
– Executing [[email protected]:9] Set(“SIP/8678-c876bf18”,
“REALCALLERIDNUM=8678”) in new stack
– Executing [[email protected]:10]
ExecIf(“SIP/8678-c876bf18”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:11]
GotoIf(“SIP/8678-c876bf18”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,20)
– Executing [[email protected]:20] NoOp(“SIP/8678-c876bf18”,
“Using CallerID “Ryan Wagoner” <8678>”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/8678-c876bf18”, “0?4”) in new stack
– Executing [[email protected]:3] SIPAddHeader(“SIP/8678-c876bf18”,
“Diversion: tel:8688;reason=no-answer;screen=no;privacy=off”) in
new stack
– Executing [[email protected]:4] Dial(“SIP/8678-c876bf18”,
“SIP/exchange-vm”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called exchange-vm
– Got SIP response 302 “Moved Temporarily” back from 10.9.1.13
– Now forwarding SIP/8678-c876bf18 to
’SIP/::::[email protected]:5067’ (thanks to SIP/exchange-vm-ac968658)
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– SIP/10.9.1.13:5067-ac80fdc8 is ringing
– Executing [[email protected]:3]
StopMonitor(“Local/[email protected];1”, “”) in new stack
– Executing [[email protected]:4]
AGI(“Local/[email protected];1”,
“recordingcheck,20090918-101319,1253283198.1299”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
– SIP/10.9.1.13:5067-ac80fdc8 answered SIP/8678-c876bf18
– Packet2Packet bridging SIP/8678-c876bf18 and SIP/10.9.1.13:5067-ac80fdc8
– <Local/[email protected];1>AGI Script recordingcheck
completed, returning 0
– Executing [[email protected]:5]
MacroExit(“Local/[email protected];1”, “”) in new stack
– Executing [[email protected]:13]
GotoIf(“Local/[email protected];1”, “1 ?skipsimple”) in new
stack
– Goto (from-internal-xfer,8532,15)
– Executing [[email protected]:15]
Set(“Local/[email protected];1”, “RingGroupMethod=ringall”) in
new stack
– Executing [[email protected]:16]
Set(“Local/[email protected];1”, “_FMGRP=8532”) in new stack
– Executing [[email protected]:17]
GotoIf(“Local/[email protected];1”, “0?doconfirm”) in new stack
– Executing [[email protected]:18]
Macro(“Local/[email protected];1”,
“dial,20,trwTW,8532-38532-48532”) in new stack
– Executing [[email protected]:1]
GotoIf(“Local/[email protected];1”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [[email protected]:3]
AGI(“Local/[email protected];1”, “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 ‘unknown’ number is ‘8688’
> dialparties.agi: USE_CONFIRMATION: ‘FALSE’
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 8532 to extension map
– dialparties.agi: Added extension 38532 to extension map
– dialparties.agi: Added extension 48532 to extension map
– dialparties.agi: Extension 8532 cf is disabled
– dialparties.agi: Extension 38532 cf is disabled
– dialparties.agi: Extension 48532 cf is disabled
– dialparties.agi: Extension 8532 do not disturb is disabled
– dialparties.agi: Extension 38532 do not disturb is disabled
– dialparties.agi: Extension 48532 do not disturb is disabled
> dialparties.agi: extnum 8532 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: dbset CALLTRACE/8532 to 8688
> dialparties.agi: extnum 38532 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: dbset CALLTRACE/38532 to 8688
> dialparties.agi: extnum 48532 has: cw: 1; hascfb: 0 [] hascfu: 0 []
– dialparties.agi: dbset CALLTRACE/48532 to 8688
– dialparties.agi: Filtered ARG3: 8532-38532-48532
> dialparties.agi: NODEST: 8532 adding M(auto-blkvm) to
dialopts: trwTWM(auto-blkvm)
> dialparties.agi: NODEST: 8532 blkvm enabled macro already in
dialopts: trwTWM(auto-blkvm)
– <Local/[email protected];1>AGI Script dialparties.agi
completed, returning 0
– Executing [[email protected]:7]
Dial(“Local/[email protected];1”,
“SIP/8532&SIP/38532&SIP/48532,20,trwTWM(auto-blkvm)”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called 8532
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called 38532
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– SIP/38532-c83eeff8 is ringing
– SIP/8532-c84c6cf8 is ringing
– SIP/38532-c83eeff8 answered Local/[email protected];1