-- Executing [h@macro-dial:1] Macro("DAHDI/2-1", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("DAHDI/2-1", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [s@macro-hangupcall:4] GotoIf("DAHDI/2-1", "0?skipblkvm") in new stack
    -- Executing [s@macro-hangupcall:5] NoOp("DAHDI/2-1", "Cleaning Up Block VM Flag: BLKVM/7903/DAHDI/2-1") in new stack
    -- Executing [s@macro-hangupcall:6] NoOp("DAHDI/2-1", "Deleting: BLKVM/7903/DAHDI/2-1 ") in new stack
    -- Executing [s@macro-hangupcall:7] GotoIf("DAHDI/2-1", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] Hangup("DAHDI/2-1", "") in new stack
  == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'DAHDI/2-1' in macro 'hangupcall'
  == Spawn h extension (macro-dial, h, 1) exited non-zero on 'DAHDI/2-1'
  == Spawn extension (macro-dial, s, 7) exited non-zero on 'DAHDI/2-1' in macro 'dial'
  == Spawn extension (ext-group, 7903, 22) exited non-zero on 'DAHDI/2-1'
    -- Hungup 'DAHDI/2-1'
 Extension Changed 7026[ext-local] new state Idle for Notify User 7121
  == Parsing '/etc/asterisk/manager.conf': Found
  == Parsing '/etc/asterisk/manager_additional.conf': Found
  == Parsing '/etc/asterisk/manager_custom.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  == Parsing '/etc/asterisk/manager.conf': Found
  == Parsing '/etc/asterisk/manager_additional.conf': Found
  == Parsing '/etc/asterisk/manager_custom.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
    -- Executing [7021@from-internal:1] GotoIf("Local/7021@from-internal-f969,2", "0?ext-local|7021|1") in new stack
    -- Executing [7021@from-internal:2] Macro("Local/7021@from-internal-f969,2", "user-callerid|") in new stack
    -- Executing [s@macro-user-callerid:1] Set("Local/7021@from-internal-f969,2", "TOUCH_MONITOR=1303160327.14186") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/7021@from-internal-f969,2", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/7021@from-internal-f969,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,11)
    -- Executing [s@macro-user-callerid:11] GotoIf("Local/7021@from-internal-f969,2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:12] Set("Local/7021@from-internal-f969,2", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:13] GotoIf("Local/7021@from-internal-f969,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,20)
    -- Executing [s@macro-user-callerid:20] NoOp("Local/7021@from-internal-f969,2", "Using CallerID "VMAIL/7021" <>") in new stack
    -- Executing [7021@from-internal:3] Set("Local/7021@from-internal-f969,2", "__EXTTOCALL=7021") in new stack
    -- Executing [7021@from-internal:4] GotoIf("Local/7021@from-internal-f969,2", "1?skipdb") in new stack
    -- Goto (from-internal,7021,6)
    -- Executing [7021@from-internal:6] Set("Local/7021@from-internal-f969,2", "__NODEST=") in new stack
    -- Executing [7021@from-internal:7] Set("Local/7021@from-internal-f969,2", "__BLKVM_OVERRIDE=BLKVM/7021/Local/7021@from-internal-f969,2") in new stack
    -- Executing [7021@from-internal:8] Set("Local/7021@from-internal-f969,2", "__BLKVM_BASE=7021") in new stack
    -- Executing [7021@from-internal:9] Set("Local/7021@from-internal-f969,2", "DB(BLKVM/7021/Local/7021@from-internal-f969,2)=TRUE") in new stack
    -- Executing [7021@from-internal:10] Set("Local/7021@from-internal-f969,2", "RRNODEST=") in new stack
    -- Executing [7021@from-internal:11] Set("Local/7021@from-internal-f969,2", "__NODEST=7021") in new stack
    -- Executing [7021@from-internal:12] GosubIf("Local/7021@from-internal-f969,2", "0?sub-fmsetcid|s|1") in new stack
    -- Executing [7021@from-internal:13] GotoIf("Local/7021@from-internal-f969,2", "1?REPCID") in new stack
    -- Goto (from-internal,7021,18)
    -- Executing [7021@from-internal:18] NoOp("Local/7021@from-internal-f969,2", "CALLERID(name) is VMAIL/7021") in new stack
    -- Executing [7021@from-internal:19] Set("Local/7021@from-internal-f969,2", "_RGPREFIX=Jack-") in new stack
    -- Executing [7021@from-internal:20] Set("Local/7021@from-internal-f969,2", "CALLERID(name)=Jack-VMAIL/7021") in new stack
    -- Executing [7021@from-internal:21] Set("Local/7021@from-internal-f969,2", "RecordMethod=Group") in new stack
    -- Executing [7021@from-internal:22] Macro("Local/7021@from-internal-f969,2", "record-enable|7023-7021-7021|Group") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("Local/7021@from-internal-f969,2", "1?check") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] ExecIf("Local/7021@from-internal-f969,2", "0|MacroExit|") in new stack
    -- Executing [s@macro-record-enable:5] GotoIf("Local/7021@from-internal-f969,2", "1?Group:OUT") in new stack
    -- Goto (macro-record-enable,s,6)
    -- Executing [s@macro-record-enable:6] Set("Local/7021@from-internal-f969,2", "LOOPCNT=3") in new stack
    -- Executing [s@macro-record-enable:7] Set("Local/7021@from-internal-f969,2", "ITER=1") in new stack
    -- Executing [s@macro-record-enable:8] GotoIf("Local/7021@from-internal-f969,2", "1?continue") in new stack
    -- Goto (macro-record-enable,s,13)
    -- Executing [s@macro-record-enable:13] Set("Local/7021@from-internal-f969,2", "ITER=2") in new stack
    -- Executing [s@macro-record-enable:14] GotoIf("Local/7021@from-internal-f969,2", "1?begin") in new stack
    -- Goto (macro-record-enable,s,8)
    -- Executing [s@macro-record-enable:8] GotoIf("Local/7021@from-internal-f969,2", "1?continue") in new stack
    -- Goto (macro-record-enable,s,13)
    -- Executing [s@macro-record-enable:13] Set("Local/7021@from-internal-f969,2", "ITER=3") in new stack
    -- Executing [s@macro-record-enable:14] GotoIf("Local/7021@from-internal-f969,2", "1?begin") in new stack
    -- Goto (macro-record-enable,s,8)
    -- Executing [s@macro-record-enable:8] GotoIf("Local/7021@from-internal-f969,2", "1?continue") in new stack
    -- Goto (macro-record-enable,s,13)
    -- Executing [s@macro-record-enable:13] Set("Local/7021@from-internal-f969,2", "ITER=4") in new stack
    -- Executing [s@macro-record-enable:14] GotoIf("Local/7021@from-internal-f969,2", "0?begin") in new stack
    -- Executing [s@macro-record-enable:15] GotoIf("Local/7021@from-internal-f969,2", "0?IN") in new stack
    -- Executing [s@macro-record-enable:16] ExecIf("Local/7021@from-internal-f969,2", "1|MacroExit|") in new stack
    -- Executing [7021@from-internal:23] GotoIf("Local/7021@from-internal-f969,2", "0 ?skipsimple") in new stack
    -- Executing [7021@from-internal:24] Macro("Local/7021@from-internal-f969,2", "simple-dial|7021|8") in new stack
    -- Executing [s@macro-simple-dial:1] Set("Local/7021@from-internal-f969,2", "__EXTTOCALL=7021") in new stack
    -- Executing [s@macro-simple-dial:2] Set("Local/7021@from-internal-f969,2", "RT=8") in new stack
    -- Executing [s@macro-simple-dial:3] Set("Local/7021@from-internal-f969,2", "CFUEXT=") in new stack
    -- Executing [s@macro-simple-dial:4] Set("Local/7021@from-internal-f969,2", "CFBEXT=") in new stack
    -- Executing [s@macro-simple-dial:5] Set("Local/7021@from-internal-f969,2", "CWI_TMP=") in new stack
    -- Executing [s@macro-simple-dial:6] Macro("Local/7021@from-internal-f969,2", "dial|8|tTrwW|7021") in new stack
    -- Executing [s@macro-dial:1] GotoIf("Local/7021@from-internal-f969,2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("Local/7021@from-internal-f969,2", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  == Parsing '/etc/asterisk/manager.conf': Found
  == Parsing '/etc/asterisk/manager_additional.conf': Found
  == Parsing '/etc/asterisk/manager_custom.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  dialparties.agi: Caller ID name is 'Jack-VMAIL/7021' number is 'unknown'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 7021 to extension map
    --  dialparties.agi: Extension 7021 cf is disabled
    --  dialparties.agi: Extension 7021 do not disturb is disabled
       >  dialparties.agi: extnum 7021 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
  dialparties.agi: ExtensionState: 0
    --  dialparties.agi: DbDel CALLTRACE/7021 - Caller ID is not defined
    --  dialparties.agi: Filtered ARG3: 7021
       >  dialparties.agi: NODEST: 7021 adding M(auto-blkvm) to dialopts: tTrwWM(auto-blkvm)
       >  dialparties.agi: NODEST: 7021 blkvm enabled macro already in dialopts: tTrwWM(auto-blkvm)
  == Manager 'admin' logged off from 127.0.0.1
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:7] Dial("Local/7021@from-internal-f969,2", "SIP/7021|8|tTrwWM(auto-blkvm)") in new stack
    -- Called 7021
 Extension Changed 7021[ext-local] new state Ringing for Notify User 7026
    -- SIP/7021-00001d46 is ringing
 Extension Changed 7021[ext-local] new state InUse for Notify User 7026
    -- SIP/7021-00001d46 answered Local/7021@from-internal-f969,2
    -- Executing [s@macro-auto-blkvm:1] Set("SIP/7021-00001d46", "__MACRO_RESULT=") in new stack
    -- Executing [s@macro-auto-blkvm:2] NoOp("SIP/7021-00001d46", "Deleting: BLKVM/7021/Local/7021@from-internal-f969,2 TRUE") in new stack
       > Channel Local/7021@from-internal-f969,1 was answered.
  == Manager 'admin' logged off from 127.0.0.1
  == Manager 'admin' logged off from 127.0.0.1
    -- Executing [s@vm-callme:1] Answer("Local/7021@from-internal-f969,1", "") in new stack
    -- Executing [s@vm-callme:2] Wait("Local/7021@from-internal-f969,1", "1") in new stack
    -- Executing [s@vm-callme:3] BackGround("Local/7021@from-internal-f969,1", "/var/spool/asterisk/voicemail/default/7021/INBOX/msg0073,MBOX=7021&silence/2&vm-repeat&vm-starmain") in new stack
[2011-04-18 15:58:50] WARNING[11685]: file.c:664 ast_openstream_full: File /var/spool/asterisk/voicemail/default/7021/INBOX/msg0073,MBOX=7021 does not exist in any format
[2011-04-18 15:58:50] WARNING[11685]: file.c:991 ast_streamfile: Unable to open /var/spool/asterisk/voicemail/default/7021/INBOX/msg0073,MBOX=7021 (format 0x40 (slin)): No such file or directory
[2011-04-18 15:58:50] WARNING[11685]: pbx.c:5752 pbx_builtin_background: ast_streamfile failed on Local/7021@from-internal-f969,1 for /var/spool/asterisk/voicemail/default/7021/INBOX/msg0073,MBOX=7021&silence/2&vm-repeat&vm-starmain
    -- Executing [s@vm-callme:4] WaitExten("Local/7021@from-internal-f969,1", "15|") in new stack