Verbosity is at least 4
-- Executing [7777@from-internal:1] Goto("SIP/326-b6913b20", "from-pstn|s|1") in new stack
-- Goto (from-pstn,s,1)
-- Executing [s@from-pstn:1] Set("SIP/326-b6913b20", "__FROM_DID=s") in new stack
-- Executing [s@from-pstn:2] Gosub("SIP/326-b6913b20", "app-blacklist-check|s|1") in new stack
-- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/326-b6913b20", "") in new stack
-- Executing [s@app-blacklist-check:2] GotoIf("SIP/326-b6913b20", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:3] Return("SIP/326-b6913b20", "") in new stack
-- Executing [s@from-pstn:3] GotoIf("SIP/326-b6913b20", "1 ?cidok") in new stack
-- Goto (from-pstn,s,5)
-- Executing [s@from-pstn:5] NoOp("SIP/326-b6913b20", "CallerID is "device" <326>") in new stack
-- Executing [s@from-pstn:6] Goto("SIP/326-b6913b20", "app-announcement-1|s|1") in new stack
-- Goto (app-announcement-1,s,1)
-- Executing [s@app-announcement-1:1] GotoIf("SIP/326-b6913b20", "0?begin") in new stack
-- Executing [s@app-announcement-1:2] Answer("SIP/326-b6913b20", "") in new stack
-- Executing [s@app-announcement-1:3] Wait("SIP/326-b6913b20", "1") in new stack
-- Executing [s@app-announcement-1:4] NoOp("SIP/326-b6913b20", "Playing announcement Welcome") in new stack
-- Executing [s@app-announcement-1:5] Playback("SIP/326-b6913b20", "custom/Derek|noanswer") in new stack
-- <SIP/326-b6913b20> Playing 'custom/Derek' (language 'en')
-- Executing [s@app-announcement-1:6] Goto("SIP/326-b6913b20", "ivr-2|s|1") in new stack
-- Goto (ivr-2,s,1)
-- Executing [s@ivr-2:1] Set("SIP/326-b6913b20", "LOOPCOUNT=0") in new stack
-- Executing [s@ivr-2:2] Set("SIP/326-b6913b20", "__DIR-CONTEXT=default") in new stack
-- Executing [s@ivr-2:3] Set("SIP/326-b6913b20", "_IVR_CONTEXT_ivr-2=") in new stack
-- Executing [s@ivr-2:4] Set("SIP/326-b6913b20", "_IVR_CONTEXT=ivr-2") in new stack
-- Executing [s@ivr-2:5] GotoIf("SIP/326-b6913b20", "1?begin") in new stack
-- Goto (ivr-2,s,8)
-- Executing [s@ivr-2:8] Set("SIP/326-b6913b20", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3
-- Executing [s@ivr-2:9] Set("SIP/326-b6913b20", "TIMEOUT(response)=20") in new stack
-- Response timeout set to 20
-- Executing [s@ivr-2:10] BackGround("SIP/326-b6913b20", "if-u-know-ext-dial&otherwise&for-sales&press-5&silence/1&for-tech-support&press-7&silence/1&to-dial-by-name-press&digits/pound&silence/1&to-reach-operator&press-0&to-hear-menu-again&press-star") in new stack
-- <SIP/326-b6913b20> Playing 'if-u-know-ext-dial' (language 'en')
-- <SIP/326-b6913b20> Playing 'otherwise' (language 'en')
-- Hungup 'Zap/6-1'
== Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/328-b6929d78' in macro 'dialout-trunk'
== Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/328-b6929d78'
-- Executing [h@macro-dialout-trunk:1] Macro("SIP/328-b6929d78", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] ResetCDR("SIP/328-b6929d78", "w") in new stack
-- Executing [s@macro-hangupcall:2] NoCDR("SIP/328-b6929d78", "") in new stack
-- Executing [s@macro-hangupcall:3] GotoIf("SIP/328-b6929d78", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing [s@macro-hangupcall:6] GotoIf("SIP/328-b6929d78", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing [s@macro-hangupcall:9] GotoIf("SIP/328-b6929d78", "1?theend") in new stack
-- Goto (macro-hangupcall,s,11)
-- Executing [s@macro-hangupcall:11] Hangup("SIP/328-b6929d78", "") in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/328-b6929d78' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/328-b6929d78'
-- <SIP/326-b6913b20> Playing 'for-sales' (language 'en')
-- <SIP/326-b6913b20> Playing 'press-5' (language 'en')
-- <SIP/326-b6913b20> Playing 'silence/1' (language 'en')
-- <SIP/326-b6913b20> Playing 'for-tech-support' (language 'en')
-- <SIP/326-b6913b20> Playing 'press-7' (language 'en')
-- <SIP/326-b6913b20> Playing 'silence/1' (language 'en')
-- <SIP/326-b6913b20> Playing 'to-dial-by-name-press' (language 'en')
-- <SIP/326-b6913b20> Playing 'digits/pound' (language 'en')
-- <SIP/326-b6913b20> Playing 'silence/1' (language 'en')
-- <SIP/326-b6913b20> Playing 'to-reach-operator' (language 'en')
== CDR updated on SIP/326-b6913b20
-- Executing [326@ivr-2:1] Macro("SIP/326-b6913b20", "exten-vm|326|326") in new stack
-- Executing [s@macro-exten-vm:1] Macro("SIP/326-b6913b20", "user-callerid") in new stack
-- Executing [s@macro-user-callerid:1] NoOp("SIP/326-b6913b20", "user-callerid: device 326") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/326-b6913b20", "AMPUSER=326") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/326-b6913b20", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] GotoIf("SIP/326-b6913b20", "0?start") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/326-b6913b20", "REALCALLERIDNUM=326") in new stack
-- Executing [s@macro-user-callerid:6] NoOp("SIP/326-b6913b20", "REALCALLERIDNUM is 326") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/326-b6913b20", "AMPUSER=326") in new stack
-- Executing [s@macro-user-callerid:8] Set("SIP/326-b6913b20", "AMPUSERCIDNAME=Will") in new stack
-- Executing [s@macro-user-callerid:9] GotoIf("SIP/326-b6913b20", "0?report") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/326-b6913b20", "AMPUSERCID=326") in new stack
-- Executing [s@macro-user-callerid:11] Set("SIP/326-b6913b20", "CALLERID(all)=Will <326>") in new stack
-- Executing [s@macro-user-callerid:12] Set("SIP/326-b6913b20", "REALCALLERIDNUM=326") in new stack
-- Executing [s@macro-user-callerid:13] NoOp("SIP/326-b6913b20", "TTL: ARG1: 326") in new stack
-- Executing [s@macro-user-callerid:14] GotoIf("SIP/326-b6913b20", "0?continue") in new stack
-- Executing [s@macro-user-callerid:15] Set("SIP/326-b6913b20", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:16] GotoIf("SIP/326-b6913b20", "1?continue") in new stack
-- Goto (macro-user-callerid,s,23)
-- Executing [s@macro-user-callerid:23] NoOp("SIP/326-b6913b20", "Using CallerID "Will" <326>") in new stack
-- Executing [s@macro-exten-vm:2] Set("SIP/326-b6913b20", "FROMCONTEXT=exten-vm") in new stack
-- Executing [s@macro-exten-vm:3] Set("SIP/326-b6913b20", "VMBOX=326") in new stack
-- Executing [s@macro-exten-vm:4] Set("SIP/326-b6913b20", "EXTTOCALL=326") in new stack
-- Executing [s@macro-exten-vm:5] Set("SIP/326-b6913b20", "CFUEXT=") in new stack
-- Executing [s@macro-exten-vm:6] Set("SIP/326-b6913b20", "CFBEXT=") in new stack
-- Executing [s@macro-exten-vm:7] Set("SIP/326-b6913b20", "RT=15") in new stack
-- Executing [s@macro-exten-vm:8] Macro("SIP/326-b6913b20", "record-enable|326|IN") in new stack
-- Executing [s@macro-record-enable:1] GotoIf("SIP/326-b6913b20", "0?2:4") in new stack
-- Goto (macro-record-enable,s,4)
-- Executing [s@macro-record-enable:4] DeadAGI("SIP/326-b6913b20", "recordingcheck|20070720-111112|1184944251.290") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20070720-111112|1184944251.290: Inbound recording not enabled
-- AGI Script recordingcheck completed, returning 0
-- Executing [s@macro-record-enable:5] NoOp("SIP/326-b6913b20", "No recording needed") in new stack
-- Executing [s@macro-exten-vm:9] Macro("SIP/326-b6913b20", "dial|15|tr|326") in new stack
-- Executing [s@macro-dial:1] AGI("SIP/326-b6913b20", "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
== Manager 'admin' logged on from 127.0.0.1
dialparties.agi: priority is 1
dialparties.agi: Caller ID name is 'Will' number is '326'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'none'
-- dialparties.agi: Added extension 326 to extension map
-- dialparties.agi: Extension 326 cf is disabled
-- dialparties.agi: Extension 326 do not disturb is disabled
> dialparties.agi: extnum: 326
> dialparties.agi: exthascw: 1
> dialparties.agi: exthascfb: 0
> dialparties.agi: extcfb:
> dialparties.agi: exthascfu: 0
> dialparties.agi: extcfu:
> dialparties.agi: ExtensionState: 0
-- dialparties.agi: dbset CALLTRACE/326 to 326
== Manager 'admin' logged off from 127.0.0.1
-- AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:10] Dial("SIP/326-b6913b20", "SIP/326|15|tr") in new stack
-- Called 326
-- SIP/326-082a1138 is ringing
-- Nobody picked up in 15000 ms
-- Executing [s@macro-dial:11] Set("SIP/326-b6913b20", "DIALSTATUS=NOANSWER") in new stack
-- Executing [s@macro-exten-vm:10] Set("SIP/326-b6913b20", "SV_DIALSTATUS=NOANSWER") in new stack
-- Executing [s@macro-exten-vm:11] GosubIf("SIP/326-b6913b20", "0?docfu|1") in new stack
-- Executing [s@macro-exten-vm:12] GosubIf("SIP/326-b6913b20", "0?docfb|1") in new stack
-- Executing [s@macro-exten-vm:13] Set("SIP/326-b6913b20", "DIALSTATUS=NOANSWER") in new stack
-- Executing [s@macro-exten-vm:14] NoOp("SIP/326-b6913b20", "Voicemail is 326") in new stack
-- Executing [s@macro-exten-vm:15] GotoIf("SIP/326-b6913b20", "0?s-NOANSWER|1") in new stack
-- Executing [s@macro-exten-vm:16] NoOp("SIP/326-b6913b20", "Sending to Voicemail box 326") in new stack
-- Executing [s@macro-exten-vm:17] Macro("SIP/326-b6913b20", "vm|326|NOANSWER") in new stack
-- Executing [s@macro-vm:1] Macro("SIP/326-b6913b20", "user-callerid|SKIPTTL") in new stack
-- Executing [s@macro-user-callerid:1] NoOp("SIP/326-b6913b20", "user-callerid: Will LaSala 326") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/326-b6913b20", "AMPUSER=326") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/326-b6913b20", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] GotoIf("SIP/326-b6913b20", "1?start") in new stack
-- Goto (macro-user-callerid,s,6)
-- Executing [s@macro-user-callerid:6] NoOp("SIP/326-b6913b20", "REALCALLERIDNUM is 326") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/326-b6913b20", "AMPUSER=326") in new stack
-- Executing [s@macro-user-callerid:8] Set("SIP/326-b6913b20", "AMPUSERCIDNAME=Will LaSala") in new stack
-- Executing [s@macro-user-callerid:9] GotoIf("SIP/326-b6913b20", "0?report") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/326-b6913b20", "AMPUSERCID=326") in new stack
-- Executing [s@macro-user-callerid:11] Set("SIP/326-b6913b20", "CALLERID(all)=Will LaSala <326>") in new stack
-- Executing [s@macro-user-callerid:12] Set("SIP/326-b6913b20", "REALCALLERIDNUM=326") in new stack
-- Executing [s@macro-user-callerid:13] NoOp("SIP/326-b6913b20", "TTL: 64 ARG1: SKIPTTL") in new stack
-- Executing [s@macro-user-callerid:14] GotoIf("SIP/326-b6913b20", "1?continue") in new stack
-- Goto (macro-user-callerid,s,23)
-- Executing [s@macro-user-callerid:23] NoOp("SIP/326-b6913b20", "Using CallerID "Will LaSala" <326>") in new stack
-- Executing [s@macro-vm:2] Set("SIP/326-b6913b20", "VMGAIN=") in new stack
-- Executing [s@macro-vm:3] GotoIf("SIP/326-b6913b20", "1?vmx|1") in new stack
-- Goto (macro-vm,vmx,1)
-- Executing [vmx@macro-vm:1] Set("SIP/326-b6913b20", "MODE=unavail") in new stack
-- Executing [vmx@macro-vm:2] GotoIf("SIP/326-b6913b20", "1?notdirect") in new stack
-- Goto (macro-vm,vmx,4)
-- Executing [vmx@macro-vm:4] NoOp("SIP/326-b6913b20", "Checking if ext 326 is enabled: enabled") in new stack
-- Executing [vmx@macro-vm:5] GotoIf("SIP/326-b6913b20", "0?s-NOANSWER|1") in new stack
-- Executing [vmx@macro-vm:6] Macro("SIP/326-b6913b20", "get-vmcontext|326") in new stack
-- Executing [s@macro-get-vmcontext:1] Set("SIP/326-b6913b20", "VMCONTEXT=default") in new stack
-- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/326-b6913b20", "0?200:300") in new stack
-- Goto (macro-get-vmcontext,s,300)
-- Executing [s@macro-get-vmcontext:300] NoOp("SIP/326-b6913b20", "") in new stack
-- Executing [vmx@macro-vm:7] System("SIP/326-b6913b20", "ls /var/spool/asterisk/voicemail/default/326/unavail.[wW][aA][vV]") in new stack
-- Executing [vmx@macro-vm:8] Set("SIP/326-b6913b20", "LOOPCOUNT=0") in new stack
-- Executing [vmx@macro-vm:9] GotoIf("SIP/326-b6913b20", "0?vmxtime") in new stack
-- Executing [vmx@macro-vm:10] Set("SIP/326-b6913b20", "VMX_REPEAT=1") in new stack
-- Executing [vmx@macro-vm:11] GotoIf("SIP/326-b6913b20", "0?vmxloops") in new stack
-- Executing [vmx@macro-vm:12] Set("SIP/326-b6913b20", "VMX_TIMEOUT=2") in new stack
-- Executing [vmx@macro-vm:13] GotoIf("SIP/326-b6913b20", "0?vmxanswer") in new stack
-- Executing [vmx@macro-vm:14] Set("SIP/326-b6913b20", "VMX_LOOPS=1") in new stack
-- Executing [vmx@macro-vm:15] Answer("SIP/326-b6913b20", "") in new stack
-- Executing [vmx@macro-vm:16] Read("SIP/326-b6913b20", "ACTION|/var/spool/asterisk/voicemail/default/326/unavail|1|skip|1|2") in new stack
-- Accepting a maximum of 1 digits.
-- User disconnected
== Spawn extension (macro-vm, vmx, 16) exited non-zero on 'SIP/326-b6913b20' in macro 'vm'
== Spawn extension (macro-vm, vmx, 16) exited non-zero on 'SIP/326-b6913b20' in macro 'exten-vm'
== Spawn extension (macro-vm, vmx, 16) exited non-zero on 'SIP/326-b6913b20'