Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing Set("SIP/missnebun-08641ee8", "VMGAIN=") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Set
Nov 3 10:11:07 DEBUG[5429] db.c: Unable to find key '100/SIP/missnebun-08641ee8' in family 'BLKVM'
Nov 3 10:11:07 DEBUG[5429] func_db.c: DB: BLKVM/100/SIP/missnebun-08641ee8 not found in database.
Nov 3 10:11:07 DEBUG[5429] pbx.c: Function result is ''
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '1'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing GotoIf("SIP/missnebun-08641ee8", "1?vmx|1") in new stack
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Goto (macro-vm,vmx,1)
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: GotoIf
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '0'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing GotoIf("SIP/missnebun-08641ee8", "0?s-BUSY|1") in new stack
Nov 3 10:11:07 DEBUG[5429] pbx.c: Not taking any branch
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: GotoIf
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '1'
Nov 3 10:11:07 DEBUG[5429] pbx.c: Function result is 'busy'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing Set("SIP/missnebun-08641ee8", "MODE=busy") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Set
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '1'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing GotoIf("SIP/missnebun-08641ee8", "1?notdirect") in new stack
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Goto (macro-vm,vmx,5)
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: GotoIf
Nov 3 10:11:07 DEBUG[5429] pbx.c: Function result is 'enabled'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing NoOp("SIP/missnebun-08641ee8", "Checking if ext 100 is enabled: enabled") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Noop
Nov 3 10:11:07 DEBUG[5429] pbx.c: Function result is 'enabled'
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '0'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing GotoIf("SIP/missnebun-08641ee8", "0?s-BUSY|1") in new stack
Nov 3 10:11:07 DEBUG[5429] pbx.c: Not taking any branch
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: GotoIf
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing Macro("SIP/missnebun-08641ee8", "get-vmcontext|100") in new stack
Nov 3 10:11:07 DEBUG[5429] pbx.c: Function result is 'default'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing Set("SIP/missnebun-08641ee8", "VMCONTEXT=default") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Set
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '0'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing GotoIf("SIP/missnebun-08641ee8", "0?200:300") in new stack
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Goto (macro-get-vmcontext,s,300)
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: GotoIf
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing NoOp("SIP/missnebun-08641ee8", "") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: NoOp
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Macro
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing AGI("SIP/missnebun-08641ee8", "checksound.agi|/var/spool/asterisk/voicemail/default/100/busy") in new stack
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/checksound.agi
Nov 3 10:11:07 VERBOSE[5429] logger.c: checksound.agi|/var/spool/asterisk/voicemail/default/100/busy: VmX requires: /var/spool/asterisk/voicemail/default/100/busy.wav or .WAV exist in order to function
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- AGI Script checksound.agi completed, returning 0
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: AGI
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '1'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing GotoIf("SIP/missnebun-08641ee8", "1?nofile") in new stack
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Goto (macro-vm,vmx,61)
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: GotoIf
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing NoOp("SIP/missnebun-08641ee8", "File for mode: busy does not exist| SYSTEMSTATUS: APPERROR| going to normal voicemail") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Noop
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing Goto("SIP/missnebun-08641ee8", "s-BUSY|1") in new stack
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Goto (macro-vm,s-BUSY,1)
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Goto
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing NoOp("SIP/missnebun-08641ee8", "BUSY voicemail") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: NoOp
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing Macro("SIP/missnebun-08641ee8", "get-vmcontext|100") in new stack
Nov 3 10:11:07 DEBUG[5429] pbx.c: Function result is 'default'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing Set("SIP/missnebun-08641ee8", "VMCONTEXT=default") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Set
Nov 3 10:11:07 DEBUG[5429] pbx.c: Expression result is '0'
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing GotoIf("SIP/missnebun-08641ee8", "0?200:300") in new stack
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Goto (macro-get-vmcontext,s,300)
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: GotoIf
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing NoOp("SIP/missnebun-08641ee8", "") in new stack
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: NoOp
Nov 3 10:11:07 DEBUG[5429] app_macro.c: Executed application: Macro
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Executing VoiceMail("SIP/missnebun-08641ee8", "100@default|b") in new stack
Nov 3 10:11:07 DEBUG[5429] app_voicemail.c: /var/spool/asterisk/voicemail/default/100/busy doesn't exist, doing what we can
Nov 3 10:11:07 DEBUG[5429] channel.c: Scheduling timer at 160 sample intervals
Nov 3 10:11:07 VERBOSE[5429] logger.c: -- Playing 'vm-theperson' (language 'en')