WORKING: 

    -- Executing Macro("SIP/2006-0a0a1430", "user-callerid|") in new stack
    -- Executing NoOp("SIP/2006-0a0a1430", "user-callerid: device 2006") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?report") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?start") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "REALCALLERIDNUM=2006") in new stack
    -- Executing NoOp("SIP/2006-0a0a1430", "REALCALLERIDNUM is 2006") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "AMPUSER=2006") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "AMPUSERCIDNAME=2006") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?report") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "CALLERID(all)=2006 <2006>") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "REALCALLERIDNUM=2006") in new stack
    -- Executing NoOp("SIP/2006-0a0a1430", "TTL:  ARG1: ") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?continue") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "_TTL=64") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,21)
    -- Executing NoOp("SIP/2006-0a0a1430", "Using CallerID "2006" <2006>") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "1?skipdb") in new stack
    -- Goto (from-internal,1015,4)
    -- Executing Set("SIP/2006-0a0a1430", "__NODEST=") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "__BLKVM_OVERRIDE=BLKVM/1015/SIP/2006-0a0a1430") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "__BLKVM_BASE=1015") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "DB(BLKVM/1015/SIP/2006-0a0a1430)=TRUE") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "RRNODEST=") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "__NODEST=1015") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "1?REPCID") in new stack
    -- Goto (from-internal,1015,14)
    -- Executing NoOp("SIP/2006-0a0a1430", "CALLERID(name) is 2006") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0 ?skipsimple") in new stack
    -- Executing Macro("SIP/2006-0a0a1430", "simple-dial|1015|7") in new stack
    -- Executing Macro("SIP/2006-0a0a1430", "user-callerid|SKIPTTL") in new stack
    -- Executing NoOp("SIP/2006-0a0a1430", "user-callerid: 2006 2006") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?report") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "1?start") in new stack
    -- Goto (macro-user-callerid,s,5)
    -- Executing NoOp("SIP/2006-0a0a1430", "REALCALLERIDNUM is 2006") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "AMPUSER=2006") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "AMPUSERCIDNAME=2006") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?report") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "CALLERID(all)=2006 <2006>") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "REALCALLERIDNUM=2006") in new stack
    -- Executing NoOp("SIP/2006-0a0a1430", "TTL: 64 ARG1: SKIPTTL") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,21)
    -- Executing NoOp("SIP/2006-0a0a1430", "Using CallerID "2006" <2006>") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "EXTTOCALL=1015") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "RT=7") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "CFUEXT=") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "CFBEXT=") in new stack
    -- Executing Macro("SIP/2006-0a0a1430", "record-enable|1015|IN") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing DeadAGI("SIP/2006-0a0a1430", "recordingcheck|20070521-151109|1179724269.23") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20070521-151109|1179724269.23: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("SIP/2006-0a0a1430", "No recording needed") in new stack
    -- Executing Macro("SIP/2006-0a0a1430", "dial|7|tr|1015") in new stack
    -- Executing DeadAGI("SIP/2006-0a0a1430", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  dialparties.agi: priority is 1
  dialparties.agi: Caller ID name is '2006' number is '2006'
  dialparties.agi: Methodology of ring is  'none'
       >  dialparties.agi: USE_CONFIRMATION:  'FALSE'
       >  dialparties.agi: RINGGROUP_INDEX:   ''
    --  dialparties.agi: Added extension 1015 to extension map
    --  dialparties.agi: Extension 1015 cf is disabled
    --  dialparties.agi: Extension 1015 do not disturb is disabled
       >  dialparties.agi: extnum: 1015
       >  dialparties.agi: exthascw: 1
       >  dialparties.agi: exthascfb: 0
       >  dialparties.agi: extcfb:
       >  dialparties.agi: exthascfu: 0
       >  dialparties.agi: extcfu:
    --  dialparties.agi: dbset CALLTRACE/1015 to 2006
       >  dialparties.agi: NODEST: 1015 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing Dial("SIP/2006-0a0a1430", "SIP/1015|7|trM(auto-blkvm)") in new stack
    -- Called 1015
    -- SIP/1015-0a0ae7d0 is ringing
    -- Nobody picked up in 7000 ms
    -- Executing Set("SIP/2006-0a0a1430", "PR_DIALSTATUS=NOANSWER") in new stack
    -- Executing GosubIf("SIP/2006-0a0a1430", "0?docfu|1") in new stack
    -- Executing GosubIf("SIP/2006-0a0a1430", "0?docfb|1") in new stack
    -- Executing Goto("SIP/2006-0a0a1430", "s-NOANSWER|1") in new stack
    -- Goto (macro-simple-dial,s-NOANSWER,1)
    -- Executing NoOp("SIP/2006-0a0a1430", "Extension is reporting s-NOANSWER") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "RecordMethod=Group") in new stack
    -- Executing Macro("SIP/2006-0a0a1430", "record-enable|1018|Group") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing DeadAGI("SIP/2006-0a0a1430", "recordingcheck|20070521-151116|1179724269.23") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("SIP/2006-0a0a1430", "No recording needed") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "RingGroupMethod=ringall") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?doconfirm") in new stack
    -- Executing Macro("SIP/2006-0a0a1430", "dial|20|tr|1018") in new stack
    -- Executing DeadAGI("SIP/2006-0a0a1430", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  dialparties.agi: priority is 1
  dialparties.agi: Caller ID name is '2006' number is '2006'
  dialparties.agi: Methodology of ring is  'ringall'
       >  dialparties.agi: USE_CONFIRMATION:  'FALSE'
       >  dialparties.agi: RINGGROUP_INDEX:   ''
    --  dialparties.agi: Added extension 1018 to extension map
    --  dialparties.agi: Extension 1018 cf is disabled
    --  dialparties.agi: Extension 1018 do not disturb is disabled
       >  dialparties.agi: extnum: 1018
       >  dialparties.agi: exthascw: 1
       >  dialparties.agi: exthascfb: 0
       >  dialparties.agi: extcfb:
       >  dialparties.agi: exthascfu: 0
       >  dialparties.agi: extcfu:
    --  dialparties.agi: dbset CALLTRACE/1018 to 2006
       >  dialparties.agi: NODEST: 1015 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing Dial("SIP/2006-0a0a1430", "SIP/1018|20|trM(auto-blkvm)") in new stack
    -- Called 1018
    -- SIP/1018-0a0b3d10 is ringing
    -- Nobody picked up in 20000 ms
    -- Executing Goto("SIP/2006-0a0a1430", "nextstep") in new stack
    -- Goto (from-internal,1015,24)
    -- Executing Set("SIP/2006-0a0a1430", "RingGroupMethod=") in new stack
    -- Executing GotoIf("SIP/2006-0a0a1430", "0?nodest") in new stack
    -- Executing Set("SIP/2006-0a0a1430", "__NODEST=") in new stack
    -- Executing DBdel("SIP/2006-0a0a1430", "BLKVM/1015/SIP/2006-0a0a1430") in new stack
    -- DBdel: family=BLKVM, key=1015/SIP/2006-0a0a1430
    -- Executing Goto("SIP/2006-0a0a1430", "app-blackhole|zapateller|1") in new stack
    -- Goto (app-blackhole,zapateller,1)
    -- Executing NoOp("SIP/2006-0a0a1430", "Blackhole Dest: Play SIT Tone") in new stack
    -- Executing Answer("SIP/2006-0a0a1430", "") in new stack
    -- Executing Zapateller("SIP/2006-0a0a1430", "") in new stack



FAILED:

   -- Executing Macro("OOH323/Gateway CallId#60-794b", "exten-vm|1015|1015") in new stack
    -- Executing Macro("OOH323/Gateway CallId#60-794b", "user-callerid") in new stack
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "user-callerid: Gateway CallId#60 417416551") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "0?report") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "0?start") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "REALCALLERIDNUM=417416551") in new stack
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "REALCALLERIDNUM is 417416551") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "AMPUSER=") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "AMPUSERCIDNAME=") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "1?report") in new stack
    -- Goto (macro-user-callerid,s,11)
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "TTL:  ARG1: 1015") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "0?continue") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "_TTL=64") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,21)
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "Using CallerID "Gateway CallId#60" <417416551>") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "FROMCONTEXT=exten-vm") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "VMBOX=1015") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "EXTTOCALL=1015") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "CFUEXT=") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "CFBEXT=") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "RT=15") in new stack
    -- Executing Macro("OOH323/Gateway CallId#60-794b", "record-enable|1015|IN") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing DeadAGI("OOH323/Gateway CallId#60-794b", "recordingcheck|20070521-150934|1179724174.18") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20070521-150934|1179724174.18: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "No recording needed") in new stack
    -- Executing Macro("OOH323/Gateway CallId#60-794b", "dial|15|tr|1015") in new stack
    -- Executing DeadAGI("OOH323/Gateway CallId#60-794b", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  dialparties.agi: priority is 1
  dialparties.agi: Caller ID name is 'Gateway CallId#60' number is '417416551'
  dialparties.agi: Methodology of ring is  'none'
       >  dialparties.agi: USE_CONFIRMATION:  'FALSE'
       >  dialparties.agi: RINGGROUP_INDEX:   ''
    --  dialparties.agi: Added extension 1015 to extension map
    --  dialparties.agi: Extension 1015 cf is disabled
    --  dialparties.agi: Extension 1015 do not disturb is disabled
       >  dialparties.agi: extnum: 1015
       >  dialparties.agi: exthascw: 1
       >  dialparties.agi: exthascfb: 0
       >  dialparties.agi: extcfb:
       >  dialparties.agi: exthascfu: 0
       >  dialparties.agi: extcfu:
    --  dialparties.agi: dbset CALLTRACE/1015 to 417416551
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing Dial("OOH323/Gateway CallId#60-794b", "SIP/1015|15|tr") in new stack
    -- Called 1015
    -- SIP/1015-0a0a1430 is ringing
    -- Nobody picked up in 15000 ms
    -- Executing GosubIf("OOH323/Gateway CallId#60-794b", "0?docfu|1") in new stack
    -- Executing GosubIf("OOH323/Gateway CallId#60-794b", "0?docfb|1") in new stack
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "Voicemail is 1015") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "0?s-NOANSWER|1") in new stack
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "Sending to Voicemail box 1015") in new stack
    -- Executing Macro("OOH323/Gateway CallId#60-794b", "vm|1015|NOANSWER") in new stack
    -- Executing Macro("OOH323/Gateway CallId#60-794b", "user-callerid|SKIPTTL") in new stack
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "user-callerid: Gateway CallId#60 417416551") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "0?report") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "1?start") in new stack
    -- Goto (macro-user-callerid,s,5)
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "REALCALLERIDNUM is 417416551") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "AMPUSER=") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "AMPUSERCIDNAME=") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "1?report") in new stack
    -- Goto (macro-user-callerid,s,11)
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "TTL: 64 ARG1: SKIPTTL") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,21)
    -- Executing NoOp("OOH323/Gateway CallId#60-794b", "Using CallerID "Gateway CallId#60" <417416551>") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "VMGAIN=") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "1?s-NOANSWER|1") in new stack
    -- Goto (macro-vm,s-NOANSWER,1)
    -- Executing Macro("OOH323/Gateway CallId#60-794b", "get-vmcontext|1015") in new stack
    -- Executing Set("OOH323/Gateway CallId#60-794b", "VMCONTEXT=") in new stack
    -- Executing GotoIf("OOH323/Gateway CallId#60-794b", "1?200:300") in new stack
    -- Goto (macro-get-vmcontext,s,200)
    -- Executing Set("OOH323/Gateway CallId#60-794b", "VMCONTEXT=default") in new stack
    -- Executing VoiceMail("OOH323/Gateway CallId#60-794b", "1015@default|u") in new stack
    -- Executing Goto("OOH323/Gateway CallId#60-794b", "exit-FAILED|1") in new stack
    -- Goto (macro-vm,exit-FAILED,1)
    -- Executing Playback("OOH323/Gateway CallId#60-794b", "im-sorry&an-error-has-occured") in new stack
    -- Playing 'im-sorry' (language 'en')
    -- Playing 'an-error-has-occured' (language 'en')
    -- Executing Hangup("OOH323/Gateway CallId#60-794b", "") in new stack
  == Spawn extension (macro-vm, exit-FAILED, 2) exited non-zero on 'OOH323/Gateway CallId#60-794b' in macro 'vm'
  == Spawn extension (macro-vm, exit-FAILED, 2) exited non-zero on 'OOH323/Gateway CallId#60-794b' in macro 'exten-vm'
  == Spawn extension (macro-vm, exit-FAILED, 2) exited non-zero on 'OOH323/Gateway CallId#60-794b'