[voip ~]# asterisk -vvvvvvvvvvvvr
Asterisk 1.4.18-3 RPM by vc-rpms@voipconsulting.nl, Copyright (C) 1999 - 2008 Digium, Inc. and others.
Created by Mark Spencer <markster@digium.com>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
=========================================================================
  == Parsing '/etc/asterisk/asterisk.conf': Found
Connected to Asterisk 1.4.18-3 RPM by vc-rpms@voipconsulting.nl currently running on voip (pid = 2367)
Verbosity is at least 12
    -- Executing [7777@from-internal:1] Goto("SIP/1001-098d7418", "from-pstn|s|1") in new stack
    -- Goto (from-pstn,s,1)
    -- Executing [s@from-pstn:1] Set("SIP/1001-098d7418", "__FROM_DID=s") in new stack
    -- Executing [s@from-pstn:2] Gosub("SIP/1001-098d7418", "app-blacklist-check|s|1") in new stack
    -- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/1001-098d7418", "") in new stack
    -- Executing [s@app-blacklist-check:2] GotoIf("SIP/1001-098d7418", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:3] Return("SIP/1001-098d7418", "") in new stack
    -- Executing [s@from-pstn:3] GotoIf("SIP/1001-098d7418", "1 ?cidok") in new stack
    -- Goto (from-pstn,s,5)
    -- Executing [s@from-pstn:5] NoOp("SIP/1001-098d7418", "CallerID is "device" <1001>") in new stack
    -- Executing [s@from-pstn:6] Goto("SIP/1001-098d7418", "ivr-3|s|1") in new stack
    -- Goto (ivr-3,s,1)
    -- Executing [s@ivr-3:1] Set("SIP/1001-098d7418", "LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-3:2] Set("SIP/1001-098d7418", "__DIR-CONTEXT=default") in new stack
    -- Executing [s@ivr-3:3] Set("SIP/1001-098d7418", "_IVR_CONTEXT_ivr-3=") in new stack
    -- Executing [s@ivr-3:4] Set("SIP/1001-098d7418", "_IVR_CONTEXT=ivr-3") in new stack
    -- Executing [s@ivr-3:5] GotoIf("SIP/1001-098d7418", "0?begin") in new stack
    -- Executing [s@ivr-3:6] Answer("SIP/1001-098d7418", "") in new stack
    -- Executing [s@ivr-3:7] Wait("SIP/1001-098d7418", "1") in new stack
    -- Executing [s@ivr-3:8] Set("SIP/1001-098d7418", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3
    -- Executing [s@ivr-3:9] Set("SIP/1001-098d7418", "TIMEOUT(response)=1") in new stack
    -- Response timeout set to 1
    -- Executing [s@ivr-3:10] BackGround("SIP/1001-098d7418", "custom/BlockedInvalidCallerID") in new stack
    -- <SIP/1001-098d7418> Playing 'custom/BlockedInvalidCallerID' (language 'en')
  == CDR updated on SIP/1001-098d7418
    -- Executing [0@ivr-3:1] DBdel("SIP/1001-098d7418", "") in new stack
    -- Executing [0@ivr-3:2] Set("SIP/1001-098d7418", "__NODEST=") in new stack
    -- Executing [0@ivr-3:3] Goto("SIP/1001-098d7418", "ext-group|600|1") in new stack
    -- Goto (ext-group,600,1)
    -- Executing [600@ext-group:1] Macro("SIP/1001-098d7418", "user-callerid|") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("SIP/1001-098d7418", "user-callerid: device 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/1001-098d7418", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/1001-098d7418", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/1001-098d7418", "1|Set|REALCALLERIDNUM=1001") in new stack
    -- Executing [s@macro-user-callerid:5] NoOp("SIP/1001-098d7418", "REALCALLERIDNUM is 1001") in new stack
    -- Executing [s@macro-user-callerid:6] Set("SIP/1001-098d7418", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/1001-098d7418", "AMPUSERCIDNAME=Office - Cordless") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/1001-098d7418", "0?report") in new stack
    -- Executing [s@macro-user-callerid:9] Set("SIP/1001-098d7418", "AMPUSERCID=1001") in new stack
    -- Executing [s@macro-user-callerid:10] Set("SIP/1001-098d7418", "CALLERID(all)="Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-user-callerid:11] Set("SIP/1001-098d7418", "REALCALLERIDNUM=1001") in new stack
    -- Executing [s@macro-user-callerid:12] ExecIf("SIP/1001-098d7418", "0|Set|CHANNEL(language)=") in new stack
    -- Executing [s@macro-user-callerid:13] NoOp("SIP/1001-098d7418", "TTL:  ARG1: ") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("SIP/1001-098d7418", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("SIP/1001-098d7418", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/1001-098d7418", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("SIP/1001-098d7418", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [600@ext-group:2] GotoIf("SIP/1001-098d7418", "1?skipdb") in new stack
    -- Goto (ext-group,600,4)
    -- Executing [600@ext-group:4] Set("SIP/1001-098d7418", "__NODEST=") in new stack
    -- Executing [600@ext-group:5] Set("SIP/1001-098d7418", "__BLKVM_OVERRIDE=BLKVM/600/SIP/1001-098d7418") in new stack
    -- Executing [600@ext-group:6] Set("SIP/1001-098d7418", "__BLKVM_BASE=600") in new stack
    -- Executing [600@ext-group:7] Set("SIP/1001-098d7418", "DB(BLKVM/600/SIP/1001-098d7418)=TRUE") in new stack
    -- Executing [600@ext-group:8] Set("SIP/1001-098d7418", "RRNODEST=") in new stack
    -- Executing [600@ext-group:9] Set("SIP/1001-098d7418", "__NODEST=600") in new stack
    -- Executing [600@ext-group:10] Set("SIP/1001-098d7418", "RecordMethod=Group") in new stack
    -- Executing [600@ext-group:11] Macro("SIP/1001-098d7418", "record-enable|1000#-1001#-1005#-2004#-2007#-2008#|Group") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("SIP/1001-098d7418", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("SIP/1001-098d7418", "recordingcheck|20080420-165832|1208725108.130") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20080420-165832|1208725108.130: No DB Entry AMPUSER/1000#/recording - Not recording
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("SIP/1001-098d7418", "No recording needed") in new stack
    -- Executing [600@ext-group:12] Set("SIP/1001-098d7418", "RingGroupMethod=ringall") in new stack
    -- Executing [600@ext-group:13] Macro("SIP/1001-098d7418", "dial|60|tr|1000#-1001#-1005#-2004#-2007#-2008#") in new stack
    -- Executing [s@macro-dial:1] GotoIf("SIP/1001-098d7418", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("SIP/1001-098d7418", "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 'Office - Cordless' number is '1001'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'ringall'
    --  dialparties.agi: Added extension 1000# to extension map
    --  dialparties.agi: Added extension 1001# to extension map
    --  dialparties.agi: Added extension 1005# to extension map
    --  dialparties.agi: Added extension 2004# to extension map
    --  dialparties.agi: Added extension 2007# to extension map
    --  dialparties.agi: Added extension 2008# to extension map
    --  dialparties.agi: Extension 1000# cf is disabled
    --  dialparties.agi: Extension 1001# cf is disabled
    --  dialparties.agi: Extension 1005# cf is disabled
    --  dialparties.agi: Extension 2004# cf is disabled
    --  dialparties.agi: Extension 2007# cf is disabled
    --  dialparties.agi: Extension 2008# cf is disabled
       >  dialparties.agi: extnum 1000# has:  cw: 0; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: Built External dialstring component for 1000: Local/1000@from-internal/n
       >  dialparties.agi: extnum 1001# has:  cw: 0; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: Built External dialstring component for 1001: Local/1001@from-internal/n
       >  dialparties.agi: extnum 1005# has:  cw: 0; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: Built External dialstring component for 1005: Local/1005@from-internal/n
       >  dialparties.agi: extnum 2004# has:  cw: 0; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: Built External dialstring component for 2004: Local/2004@from-internal/n
       >  dialparties.agi: extnum 2007# has:  cw: 0; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: Built External dialstring component for 2007: Local/2007@from-internal/n
       >  dialparties.agi: extnum 2008# has:  cw: 0; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: Built External dialstring component for 2008: Local/2008@from-internal/n
    --  dialparties.agi: Filtered ARG3: 1000-1001-1005-2004-2007-2008
       >  dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
       >  dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(auto-blkvm)
  == Manager 'admin' logged off from 127.0.0.1
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:7] Dial("SIP/1001-098d7418", "Local/1000@from-internal/n&Local/1001@from-internal/n&Local/1005@from-internal/n&Local/2004@from-internal/n&Local/2007@from-internal/n&Local/2008@from-internal/n|60|trM(auto-blkvm)") in new stack
    -- Called 1000@from-internal/n
    -- Called 1001@from-internal/n
    -- Called 1005@from-internal/n
    -- Called 2004@from-internal/n
    -- Called 2007@from-internal/n
    -- Called 2008@from-internal/n
    -- Executing [1000@from-internal:1] Macro("Local/1000@from-internal-90af,2", "exten-vm|1000|1000") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("Local/1000@from-internal-90af,2", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("Local/1000@from-internal-90af,2", "user-callerid: Office - Cordless 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/1000@from-internal-90af,2", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/1000@from-internal-90af,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] NoOp("Local/1000@from-internal-90af,2", "TTL: 64 ARG1: 1000") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("Local/1000@from-internal-90af,2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("Local/1000@from-internal-90af,2", "__TTL=63") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("Local/1000@from-internal-90af,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("Local/1000@from-internal-90af,2", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("Local/1000@from-internal-90af,2", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [s@macro-exten-vm:3] Set("Local/1000@from-internal-90af,2", "VMBOX=1000") in new stack
    -- Executing [s@macro-exten-vm:4] Set("Local/1000@from-internal-90af,2", "EXTTOCALL=1000") in new stack
    -- Executing [s@macro-exten-vm:5] Set("Local/1000@from-internal-90af,2", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("Local/1000@from-internal-90af,2", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("Local/1000@from-internal-90af,2", "RT=30") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("Local/1000@from-internal-90af,2", "record-enable|1000|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("Local/1000@from-internal-90af,2", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("Local/1000@from-internal-90af,2", "recordingcheck|20080420-165832|1208725112.132") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    -- Executing [1001@from-internal:1] Macro("Local/1001@from-internal-9e69,2", "exten-vm|novm|1001") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("Local/1001@from-internal-9e69,2", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("Local/1001@from-internal-9e69,2", "user-callerid: Office - Cordless 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/1001@from-internal-9e69,2", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/1001@from-internal-9e69,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] NoOp("Local/1001@from-internal-9e69,2", "TTL: 64 ARG1: novm") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("Local/1001@from-internal-9e69,2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("Local/1001@from-internal-9e69,2", "__TTL=63") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("Local/1001@from-internal-9e69,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("Local/1001@from-internal-9e69,2", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("Local/1001@from-internal-9e69,2", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [s@macro-exten-vm:3] Set("Local/1001@from-internal-9e69,2", "VMBOX=novm") in new stack
    -- Executing [s@macro-exten-vm:4] Set("Local/1001@from-internal-9e69,2", "EXTTOCALL=1001") in new stack
    -- Executing [s@macro-exten-vm:5] Set("Local/1001@from-internal-9e69,2", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("Local/1001@from-internal-9e69,2", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("Local/1001@from-internal-9e69,2", "RT=""") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("Local/1001@from-internal-9e69,2", "record-enable|1001|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("Local/1001@from-internal-9e69,2", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("Local/1001@from-internal-9e69,2", "recordingcheck|20080420-165832|1208725112.134") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    -- Executing [1005@from-internal:1] Macro("Local/1005@from-internal-b34e,2", "exten-vm|novm|1005") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("Local/1005@from-internal-b34e,2", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("Local/1005@from-internal-b34e,2", "user-callerid: Office - Cordless 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/1005@from-internal-b34e,2", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/1005@from-internal-b34e,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] NoOp("Local/1005@from-internal-b34e,2", "TTL: 64 ARG1: novm") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("Local/1005@from-internal-b34e,2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("Local/1005@from-internal-b34e,2", "__TTL=63") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("Local/1005@from-internal-b34e,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("Local/1005@from-internal-b34e,2", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("Local/1005@from-internal-b34e,2", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [s@macro-exten-vm:3] Set("Local/1005@from-internal-b34e,2", "VMBOX=novm") in new stack
    -- Executing [s@macro-exten-vm:4] Set("Local/1005@from-internal-b34e,2", "EXTTOCALL=1005") in new stack
    -- Executing [s@macro-exten-vm:5] Set("Local/1005@from-internal-b34e,2", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("Local/1005@from-internal-b34e,2", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("Local/1005@from-internal-b34e,2", "RT=""") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("Local/1005@from-internal-b34e,2", "record-enable|1005|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("Local/1005@from-internal-b34e,2", "0?2:4") in new stack
    -- Executing [2004@from-internal:1] Macro("Local/2004@from-internal-eeb2,2", "exten-vm|novm|2004") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("Local/2004@from-internal-eeb2,2", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("Local/2004@from-internal-eeb2,2", "user-callerid: Office - Cordless 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/2004@from-internal-eeb2,2", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/2004@from-internal-eeb2,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] NoOp("Local/2004@from-internal-eeb2,2", "TTL: 64 ARG1: novm") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("Local/2004@from-internal-eeb2,2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("Local/2004@from-internal-eeb2,2", "__TTL=63") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("Local/2004@from-internal-eeb2,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("Local/2004@from-internal-eeb2,2", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("Local/2004@from-internal-eeb2,2", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [2007@from-internal:1] Macro("Local/2007@from-internal-9b52,2", "exten-vm|novm|2007") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("Local/2007@from-internal-9b52,2", "user-callerid") in new stack
    -- Executing [2008@from-internal:1] Macro("Local/2008@from-internal-3f0b,2", "exten-vm|novm|2008") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("Local/2007@from-internal-9b52,2", "user-callerid: Office - Cordless 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/2007@from-internal-9b52,2", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/2007@from-internal-9b52,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] NoOp("Local/2007@from-internal-9b52,2", "TTL: 64 ARG1: novm") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("Local/2007@from-internal-9b52,2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("Local/2007@from-internal-9b52,2", "__TTL=63") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("Local/2007@from-internal-9b52,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("Local/2007@from-internal-9b52,2", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("Local/2007@from-internal-9b52,2", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [s@macro-exten-vm:3] Set("Local/2007@from-internal-9b52,2", "VMBOX=novm") in new stack
    -- Executing [s@macro-exten-vm:4] Set("Local/2007@from-internal-9b52,2", "EXTTOCALL=2007") in new stack
    -- Executing [s@macro-exten-vm:5] Set("Local/2007@from-internal-9b52,2", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("Local/2007@from-internal-9b52,2", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("Local/2007@from-internal-9b52,2", "RT=""") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("Local/2007@from-internal-9b52,2", "record-enable|2007|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("Local/2007@from-internal-9b52,2", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("Local/2007@from-internal-9b52,2", "recordingcheck|20080420-165833|1208725112.140") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("Local/1005@from-internal-b34e,2", "recordingcheck|20080420-165833|1208725112.136") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    -- Executing [s@macro-exten-vm:1] Macro("Local/2008@from-internal-3f0b,2", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("Local/2008@from-internal-3f0b,2", "user-callerid: Office - Cordless 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/2008@from-internal-3f0b,2", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/2008@from-internal-3f0b,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] NoOp("Local/2008@from-internal-3f0b,2", "TTL: 64 ARG1: novm") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("Local/2008@from-internal-3f0b,2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("Local/2008@from-internal-3f0b,2", "__TTL=63") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("Local/2008@from-internal-3f0b,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("Local/2008@from-internal-3f0b,2", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("Local/2008@from-internal-3f0b,2", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [s@macro-exten-vm:3] Set("Local/2008@from-internal-3f0b,2", "VMBOX=novm") in new stack
    -- Executing [s@macro-exten-vm:4] Set("Local/2008@from-internal-3f0b,2", "EXTTOCALL=2008") in new stack
    -- Executing [s@macro-exten-vm:5] Set("Local/2008@from-internal-3f0b,2", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("Local/2008@from-internal-3f0b,2", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("Local/2008@from-internal-3f0b,2", "RT=""") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("Local/2008@from-internal-3f0b,2", "record-enable|2008|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("Local/2008@from-internal-3f0b,2", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("Local/2008@from-internal-3f0b,2", "recordingcheck|20080420-165833|1208725112.142") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    -- Executing [s@macro-exten-vm:3] Set("Local/2004@from-internal-eeb2,2", "VMBOX=novm") in new stack
    -- Executing [s@macro-exten-vm:4] Set("Local/2004@from-internal-eeb2,2", "EXTTOCALL=2004") in new stack
    -- Executing [s@macro-exten-vm:5] Set("Local/2004@from-internal-eeb2,2", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("Local/2004@from-internal-eeb2,2", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("Local/2004@from-internal-eeb2,2", "RT=""") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("Local/2004@from-internal-eeb2,2", "record-enable|2004|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("Local/2004@from-internal-eeb2,2", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("Local/2004@from-internal-eeb2,2", "recordingcheck|20080420-165833|1208725112.138") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20080420-165833|1208725112.140: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("Local/2007@from-internal-9b52,2", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("Local/2007@from-internal-9b52,2", "dial||tr|2007") in new stack
    -- Executing [s@macro-dial:1] GotoIf("Local/2007@from-internal-9b52,2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("Local/2007@from-internal-9b52,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 'Office - Cordless' number is '1001'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 2007 to extension map
    --  dialparties.agi: Extension 2007 cf is disabled
    --  dialparties.agi: Extension 2007 do not disturb is disabled
       >  dialparties.agi: extnum 2007 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: ExtensionState: 0
    --  dialparties.agi: dbset CALLTRACE/2007 to 1001
    --  dialparties.agi: Filtered ARG3: 2007
       >  dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
       >  dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(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/2007@from-internal-9b52,2", "SIP/2007||trM(auto-blkvm)") in new stack
    -- Called 2007
    -- Local/2007@from-internal-9b52,1 is ringing
  recordingcheck|20080420-165833|1208725112.136: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("Local/1005@from-internal-b34e,2", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("Local/1005@from-internal-b34e,2", "dial||tr|1005") in new stack
    -- Executing [s@macro-dial:1] GotoIf("Local/1005@from-internal-b34e,2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("Local/1005@from-internal-b34e,2", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
    -- SIP/2007-0986cc08 is ringing
  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 'Office - Cordless' number is '1001'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 1005 to extension map
    --  dialparties.agi: Extension 1005 cf is disabled
    --  dialparties.agi: Extension 1005 do not disturb is disabled
       >  dialparties.agi: extnum 1005 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: ExtensionState: 0
    --  dialparties.agi: dbset CALLTRACE/1005 to 1001
    --  dialparties.agi: Filtered ARG3: 1005
       >  dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
       >  dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(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/1005@from-internal-b34e,2", "SIP/1005||trM(auto-blkvm)") in new stack
    -- Called 1005
    -- Local/1005@from-internal-b34e,1 is ringing
    -- SIP/1005-09870b80 is ringing
  recordingcheck|20080420-165833|1208725112.142: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("Local/2008@from-internal-3f0b,2", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("Local/2008@from-internal-3f0b,2", "dial||tr|2008") in new stack
    -- Executing [s@macro-dial:1] GotoIf("Local/2008@from-internal-3f0b,2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("Local/2008@from-internal-3f0b,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 'Office - Cordless' number is '1001'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 2008 to extension map
    --  dialparties.agi: Extension 2008 cf is disabled
    --  dialparties.agi: Extension 2008 do not disturb is disabled
       >  dialparties.agi: extnum 2008 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: ExtensionState: 0
    --  dialparties.agi: dbset CALLTRACE/2008 to 1001
    --  dialparties.agi: Filtered ARG3: 2008
       >  dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
       >  dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(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/2008@from-internal-3f0b,2", "SIP/2008||trM(auto-blkvm)") in new stack
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-dial:8] Set("Local/2008@from-internal-3f0b,2", "DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:10] Set("Local/2008@from-internal-3f0b,2", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:11] GosubIf("Local/2008@from-internal-3f0b,2", "0?docfu|1") in new stack
    -- Executing [s@macro-exten-vm:12] GosubIf("Local/2008@from-internal-3f0b,2", "0?docfb|1") in new stack
    -- Executing [s@macro-exten-vm:13] Set("Local/2008@from-internal-3f0b,2", "DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:14] NoOp("Local/2008@from-internal-3f0b,2", "Voicemail is novm") in new stack
    -- Executing [s@macro-exten-vm:15] GotoIf("Local/2008@from-internal-3f0b,2", "1?s-CHANUNAVAIL|1") in new stack
    -- Goto (macro-exten-vm,s-CHANUNAVAIL,1)
    -- Executing [s-CHANUNAVAIL@macro-exten-vm:1] PlayTones("Local/2008@from-internal-3f0b,2", "congestion") in new stack
    -- Executing [s-CHANUNAVAIL@macro-exten-vm:2] Congestion("Local/2008@from-internal-3f0b,2", "10") in new stack
    -- Local/2008@from-internal-3f0b,1 is circuit-busy
  == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 2) exited non-zero on 'Local/2008@from-internal-3f0b,2' in macro 'exten-vm'
  == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 2) exited non-zero on 'Local/2008@from-internal-3f0b,2'
  recordingcheck|20080420-165833|1208725112.138: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("Local/2004@from-internal-eeb2,2", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("Local/2004@from-internal-eeb2,2", "dial||tr|2004") in new stack
    -- Executing [s@macro-dial:1] GotoIf("Local/2004@from-internal-eeb2,2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("Local/2004@from-internal-eeb2,2", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  recordingcheck|20080420-165832|1208725112.132: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("Local/1000@from-internal-90af,2", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("Local/1000@from-internal-90af,2", "dial|30|tr|1000") in new stack
    -- Executing [s@macro-dial:1] GotoIf("Local/1000@from-internal-90af,2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("Local/1000@from-internal-90af,2", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  recordingcheck|20080420-165832|1208725112.134: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("Local/1001@from-internal-9e69,2", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("Local/1001@from-internal-9e69,2", "dial||tr|1001") in new stack
    -- Executing [s@macro-dial:1] GotoIf("Local/1001@from-internal-9e69,2", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("Local/1001@from-internal-9e69,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 'Office - Cordless' number is '1001'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 2004 to extension map
    --  dialparties.agi: Extension 2004 cf is disabled
    --  dialparties.agi: Extension 2004 do not disturb is disabled
       >  dialparties.agi: extnum 2004 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: ExtensionState: 0
  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 'Office - Cordless' number is '1001'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 1000 to extension map
    --  dialparties.agi: Extension 1000 cf is disabled
    --  dialparties.agi: Extension 1000 do not disturb is disabled
       >  dialparties.agi: extnum 1000 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: ExtensionState: 0
  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 'Office - Cordless' number is '1001'
  dialparties.agi: USE_CONFIRMATION:  'FALSE'
  dialparties.agi: RINGGROUP_INDEX:   ''
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 1001 to extension map
    --  dialparties.agi: Extension 1001 cf is disabled
    --  dialparties.agi: Extension 1001 do not disturb is disabled
       >  dialparties.agi: extnum 1001 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
       >  dialparties.agi: ExtensionState: 1
  dialparties.agi: Extension 1001 has call waiting enabled with state: 1
    --  dialparties.agi: dbset CALLTRACE/2004 to 1001
    --  dialparties.agi: Filtered ARG3: 2004
       >  dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
       >  dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(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/2004@from-internal-eeb2,2", "SIP/2004||trM(auto-blkvm)") in new stack
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-dial:8] Set("Local/2004@from-internal-eeb2,2", "DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:10] Set("Local/2004@from-internal-eeb2,2", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:11] GosubIf("Local/2004@from-internal-eeb2,2", "0?docfu|1") in new stack
    -- Executing [s@macro-exten-vm:12] GosubIf("Local/2004@from-internal-eeb2,2", "0?docfb|1") in new stack
    -- Executing [s@macro-exten-vm:13] Set("Local/2004@from-internal-eeb2,2", "DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:14] NoOp("Local/2004@from-internal-eeb2,2", "Voicemail is novm") in new stack
    -- Executing [s@macro-exten-vm:15] GotoIf("Local/2004@from-internal-eeb2,2", "1?s-CHANUNAVAIL|1") in new stack
    -- Goto (macro-exten-vm,s-CHANUNAVAIL,1)
    -- Executing [s-CHANUNAVAIL@macro-exten-vm:1] PlayTones("Local/2004@from-internal-eeb2,2", "congestion") in new stack
    -- Executing [s-CHANUNAVAIL@macro-exten-vm:2] Congestion("Local/2004@from-internal-eeb2,2", "10") in new stack
    -- Local/2004@from-internal-eeb2,1 is circuit-busy
  == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 2) exited non-zero on 'Local/2004@from-internal-eeb2,2' in macro 'exten-vm'
  == Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 2) exited non-zero on 'Local/2004@from-internal-eeb2,2'
    --  dialparties.agi: dbset CALLTRACE/1000 to 1001
    --  dialparties.agi: Filtered ARG3: 1000
       >  dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
       >  dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(auto-blkvm)
  == Manager 'admin' logged off from 127.0.0.1
    --  dialparties.agi: dbset CALLTRACE/1001 to 1001
    --  dialparties.agi: Filtered ARG3: 1001
       >  dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
       >  dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(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/1000@from-internal-90af,2", "SIP/1000|30|trM(auto-blkvm)") in new stack
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-dial:8] Set("Local/1000@from-internal-90af,2", "DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:10] Set("Local/1000@from-internal-90af,2", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:11] GosubIf("Local/1000@from-internal-90af,2", "0?docfu|1") in new stack
    -- Executing [s@macro-exten-vm:12] GosubIf("Local/1000@from-internal-90af,2", "0?docfb|1") in new stack
    -- Executing [s@macro-exten-vm:13] Set("Local/1000@from-internal-90af,2", "DIALSTATUS=CHANUNAVAIL") in new stack
    -- Executing [s@macro-exten-vm:14] NoOp("Local/1000@from-internal-90af,2", "Voicemail is 1000") in new stack
    -- Executing [s@macro-exten-vm:15] GotoIf("Local/1000@from-internal-90af,2", "0?s-CHANUNAVAIL|1") in new stack
    -- Executing [s@macro-exten-vm:16] NoOp("Local/1000@from-internal-90af,2", "Sending to Voicemail box 1000") in new stack
    -- Executing [s@macro-exten-vm:17] Macro("Local/1000@from-internal-90af,2", "vm|1000|CHANUNAVAIL") in new stack
    -- Executing [s@macro-vm:1] Macro("Local/1000@from-internal-90af,2", "user-callerid|SKIPTTL") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("Local/1000@from-internal-90af,2", "user-callerid: Office - Cordless 1001") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/1000@from-internal-90af,2", "AMPUSER=1001") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/1000@from-internal-90af,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] NoOp("Local/1000@from-internal-90af,2", "TTL: 63 ARG1: SKIPTTL") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("Local/1000@from-internal-90af,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("Local/1000@from-internal-90af,2", "Using CallerID "Office - Cordless" <1001>") in new stack
    -- Executing [s@macro-vm:2] Set("Local/1000@from-internal-90af,2", "VMGAIN=""") in new stack
    -- Executing [s@macro-vm:3] GotoIf("Local/1000@from-internal-90af,2", "0?vmx|1") in new stack
    -- Executing [s@macro-vm:4] NoOp("Local/1000@from-internal-90af,2", "CAME FROM: 600 - Blocking VM cause of key: ") in new stack
    -- Executing [1000@from-internal:2] Hangup("Local/1000@from-internal-90af,2", "") in new stack
  == Spawn extension (from-internal, 1000, 2) exited non-zero on 'Local/1000@from-internal-90af,2'
    -- Executing [h@from-internal:1] Macro("Local/1000@from-internal-90af,2", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] ResetCDR("Local/1000@from-internal-90af,2", "w") in new stack
    -- Executing [s@macro-hangupcall:2] NoCDR("Local/1000@from-internal-90af,2", "") in new stack
    -- Executing [s@macro-hangupcall:3] GotoIf("Local/1000@from-internal-90af,2", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing [s@macro-hangupcall:6] GotoIf("Local/1000@from-internal-90af,2", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] GotoIf("Local/1000@from-internal-90af,2", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,11)
    -- Executing [s@macro-hangupcall:11] Hangup("Local/1000@from-internal-90af,2", "") in new stack
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/1000@from-internal-90af,2' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/1000@from-internal-90af,2'
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:7] Dial("Local/1001@from-internal-9e69,2", "SIP/1001||trM(auto-blkvm)") in new stack
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-dial:8] Set("Local/1001@from-internal-9e69,2", "DIALSTATUS=BUSY") in new stack
    -- Executing [s@macro-exten-vm:10] Set("Local/1001@from-internal-9e69,2", "SV_DIALSTATUS=BUSY") in new stack
    -- Executing [s@macro-exten-vm:11] GosubIf("Local/1001@from-internal-9e69,2", "0?docfu|1") in new stack
    -- Executing [s@macro-exten-vm:12] GosubIf("Local/1001@from-internal-9e69,2", "0?docfb|1") in new stack
    -- Executing [s@macro-exten-vm:13] Set("Local/1001@from-internal-9e69,2", "DIALSTATUS=BUSY") in new stack
    -- Executing [s@macro-exten-vm:14] NoOp("Local/1001@from-internal-9e69,2", "Voicemail is novm") in new stack
    -- Executing [s@macro-exten-vm:15] GotoIf("Local/1001@from-internal-9e69,2", "1?s-BUSY|1") in new stack
    -- Goto (macro-exten-vm,s-BUSY,1)
    -- Executing [s-BUSY@macro-exten-vm:1] NoOp("Local/1001@from-internal-9e69,2", "Extension is reporting BUSY and not passing to Voicemail") in new stack
    -- Executing [s-BUSY@macro-exten-vm:2] PlayTones("Local/1001@from-internal-9e69,2", "busy") in new stack
    -- Executing [s-BUSY@macro-exten-vm:3] Busy("Local/1001@from-internal-9e69,2", "20") in new stack
    -- Local/1001@from-internal-9e69,1 is busy
  == Spawn extension (macro-exten-vm, s-BUSY, 3) exited non-zero on 'Local/1001@from-internal-9e69,2' in macro 'exten-vm'
  == Spawn extension (macro-exten-vm, s-BUSY, 3) exited non-zero on 'Local/1001@from-internal-9e69,2'
    -- ast_get_srv: SRV lookup for '_sip._udp.fwd.pulver.com' mapped to host fwd.pulver.com, port 5060
voip*CLI>