followed this guide right through. everything is working only with incoming calls i get extension in use and goes to voice mail maybe i have a number of mistakes to fix hopefully this log can assist u in helping me. any help is appreciated thanks
Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: Set
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [vmx@macro-vm:3] GotoIf(“SIP/2001-08e0fb30”, “1?notdirect”) in new stack
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Goto (macro-vm,vmx,5)
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: GotoIf
[Oct 27 11:29:28] DEBUG[23455] func_db.c: DB: AMPUSER/1000/vmx/busy/state not found in database.
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [vmx@macro-vm:5] NoOp(“SIP/2001-08e0fb30”, “Checking if ext 1000 is enabled: “) in new stack
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: Noop
[Oct 27 11:29:28] DEBUG[23455] func_db.c: DB: AMPUSER/1000/vmx/busy/state not found in database.
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [vmx@macro-vm:6] GotoIf(“SIP/2001-08e0fb30”, “1?s-BUSY|1”) in new stack
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Goto (macro-vm,s-BUSY,1)
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: GotoIf
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [s-BUSY@macro-vm:1] NoOp(“SIP/2001-08e0fb30”, “BUSY voicemail”) in new stack
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: NoOp
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [s-BUSY@macro-vm:2] Macro(“SIP/2001-08e0fb30”, “get-vmcontext|1000”) in new stack
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [s@macro-get-vmcontext:1] Set(“SIP/2001-08e0fb30”, “VMCONTEXT=default”) in new stack
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: Set
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/2001-08e0fb30”, “0?200:300”) in new stack
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Goto (macro-get-vmcontext,s,300)
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: GotoIf
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [s@macro-get-vmcontext:300] NoOp(“SIP/2001-08e0fb30”, “”) in new stack
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: NoOp
[Oct 27 11:29:28] DEBUG[23455] app_macro.c: Executed application: Macro
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – Executing [s-BUSY@macro-vm:3] VoiceMail(“SIP/2001-08e0fb30”, “1000@default|b”) in new stack
[Oct 27 11:29:28] VERBOSE[23455] logger.c: – <SIP/2001-08e0fb30> Playing ‘vm-theperson’ (language ‘en’)
[Oct 27 11:29:29] VERBOSE[23455] logger.c: – <SIP/2001-08e0fb30> Playing ‘digits/1’ (language ‘en’)
[Oct 27 11:29:30] VERBOSE[23455] logger.c: – <SIP/2001-08e0fb30> Playing ‘digits/0’ (language ‘en’)
[Oct 27 11:29:31] VERBOSE[23455] logger.c: – <SIP/2001-08e0fb30> Playing ‘digits/0’ (language ‘en’)
[Oct 27 11:29:32] VERBOSE[23455] logger.c: – <SIP/2001-08e0fb30> Playing ‘digits/0’ (language ‘en’)
[Oct 27 11:29:32] VERBOSE[23455] logger.c: – <SIP/2001-08e0fb30> Playing ‘vm-isonphone’ (language ‘en’)
[Oct 27 11:29:34] VERBOSE[23455] logger.c: – <SIP/2001-08e0fb30> Playing ‘vm-intro’ (language ‘en’)
[Oct 27 11:29:37] VERBOSE[23455] logger.c: == Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on ‘SIP/2001-08e0fb30’ in macro ‘vm’
[Oct 27 11:29:37] VERBOSE[23455] logger.c: == Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on ‘SIP/2001-08e0fb30’ in macro ‘exten-vm’
[Oct 27 11:29:37] VERBOSE[23455] logger.c: == Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on ‘SIP/2001-08e0fb30’
[Oct 27 11:30:01] VERBOSE[23462] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Oct 27 11:30:01] VERBOSE[23462] logger.c: Found
[Oct 27 11:30:01] VERBOSE[23462] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [Oct 27 11:30:01] VERBOSE[23462] logger.c: Found
[Oct 27 11:30:01] VERBOSE[23462] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [Oct 27 11:30:01] VERBOSE[23462] logger.c: Found
[Oct 27 11:30:01] VERBOSE[23462] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Oct 27 11:30:03] VERBOSE[23462] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Oct 27 11:30:03] VERBOSE[23470] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Oct 27 11:30:03] VERBOSE[23470] logger.c: Found
[Oct 27 11:30:03] VERBOSE[23470] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [Oct 27 11:30:03] VERBOSE[23470] logger.c: Found
[Oct 27 11:30:03] VERBOSE[23470] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [Oct 27 11:30:03] VERBOSE[23470] logger.c: Found
[Oct 27 11:30:03] VERBOSE[23470] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Oct 27 11:30:03] VERBOSE[23470] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Oct 27 11:30:03] VERBOSE[23471] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Oct 27 11:30:03] VERBOSE[23471] logger.c: Found
[Oct 27 11:30:03] VERBOSE[23471] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [Oct 27 11:30:03] VERBOSE[23471] logger.c: Found
[Oct 27 11:30:03] VERBOSE[23471] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [Oct 27 11:30:03] VERBOSE[23471] logger.c: Found
[Oct 27 11:30:03] VERBOSE[23471] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Oct 27 11:30:06] VERBOSE[23471] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [1000@from-internal:1] Macro(“SIP/2001-08e47be0”, “exten-vm|1000|1000”) in new stack
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/2001-08e47be0”, “user-callerid”) in new stack
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:1] Set(“SIP/2001-08e47be0”, “AMPUSER=2001”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/2001-08e47be0”, “0?report”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/2001-08e47be0”, “1|Set|REALCALLERIDNUM=2001”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: ExecIf
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:4] Set(“SIP/2001-08e47be0”, “AMPUSER=2001”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:5] Set(“SIP/2001-08e47be0”, “AMPUSERCIDNAME=William”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/2001-08e47be0”, “0?report”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:7] Set(“SIP/2001-08e47be0”, “AMPUSERCID=2001”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:8] Set(“SIP/2001-08e47be0”, “CALLERID(all)=“William” <2001>”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:9] Set(“SIP/2001-08e47be0”, “REALCALLERIDNUM=2001”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:10] ExecIf(“SIP/2001-08e47be0”, “0|Set|CHANNEL(language)=”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: ExecIf
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:11] GotoIf(“SIP/2001-08e47be0”, “0?continue”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:12] Set(“SIP/2001-08e47be0”, “__TTL=64”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:13] GotoIf(“SIP/2001-08e47be0”, “1?continue”) in new stack
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Goto (macro-user-callerid,s,20)
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:20] NoOp(“SIP/2001-08e47be0”, “Using CallerID “William” <2001>”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Noop
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Macro
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:2] Set(“SIP/2001-08e47be0”, “RingGroupMethod=none”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:3] Set(“SIP/2001-08e47be0”, “VMBOX=1000”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:4] Set(“SIP/2001-08e47be0”, “EXTTOCALL=1000”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] DEBUG[23491] func_db.c: DB: CFU/1000 not found in database.
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:5] Set(“SIP/2001-08e47be0”, “CFUEXT=”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] DEBUG[23491] func_db.c: DB: CFB/1000 not found in database.
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:6] Set(“SIP/2001-08e47be0”, “CFBEXT=”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:7] Set(“SIP/2001-08e47be0”, “RT=15”) in new stack
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:8] Macro(“SIP/2001-08e47be0”, “record-enable|1000|IN”) in new stack
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/2001-08e47be0”, “1?check”) in new stack
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Goto (macro-record-enable,s,4)
[Oct 27 11:34:51] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Executing [s@macro-record-enable:4] AGI(“SIP/2001-08e47be0”, “recordingcheck|20081027-113451|1225121691.125”) in new stack
[Oct 27 11:34:51] VERBOSE[23491] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Oct 27 11:34:52] VERBOSE[23491] logger.c: recordingcheck|20081027-113451|1225121691.125: Inbound recording not enabled
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – AGI Script recordingcheck completed, returning 0
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: AGI
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-record-enable:5] MacroExit(“SIP/2001-08e47be0”, “”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Macro
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:9] Macro(“SIP/2001-08e47be0”, “dial|15|tr|1000”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-dial:1] GotoIf(“SIP/2001-08e47be0”, “1?dial”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Goto (macro-dial,s,3)
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-dial:3] AGI(“SIP/2001-08e47be0”, “dialparties.agi”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Oct 27 11:34:52] VERBOSE[23491] logger.c: dialparties.agi: Starting New Dialparties.agi
[Oct 27 11:34:52] VERBOSE[23494] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Oct 27 11:34:52] VERBOSE[23494] logger.c: Found
[Oct 27 11:34:52] VERBOSE[23494] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [Oct 27 11:34:52] VERBOSE[23494] logger.c: Found
[Oct 27 11:34:52] VERBOSE[23494] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [Oct 27 11:34:52] VERBOSE[23494] logger.c: Found
[Oct 27 11:34:52] VERBOSE[23494] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Oct 27 11:34:52] VERBOSE[23491] logger.c: dialparties.agi: Caller ID name is ‘William’ number is ‘2001’
[Oct 27 11:34:52] VERBOSE[23491] logger.c: dialparties.agi: Methodology of ring is ‘none’
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – dialparties.agi: Added extension 1000 to extension map
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – dialparties.agi: Extension 1000 cf is disabled
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – dialparties.agi: Extension 1000 do not disturb is disabled
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – dialparties.agi: dbset CALLTRACE/1000 to 2001
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – dialparties.agi: Filtered ARG3: 1000
[Oct 27 11:34:52] VERBOSE[23494] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – AGI Script dialparties.agi completed, returning 0
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: AGI
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-dial:7] Dial(“SIP/2001-08e47be0”, “SIP/1000|15|tr”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Called 1000
[Oct 27 11:34:52] VERBOSE[2807] logger.c: – Got SIP response 486 “Busy Here” back from 192.168.2.25
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – SIP/1000-08e14210 is busy
[Oct 27 11:34:52] VERBOSE[23491] logger.c: == Everyone is busy/congested at this time (1:1/0/0)
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Dial
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-dial:8] Set(“SIP/2001-08e47be0”, “DIALSTATUS=BUSY”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-dial:9] GosubIf(“SIP/2001-08e47be0”, “0?BUSY|1”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GosubIf
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Macro
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:10] GotoIf(“SIP/2001-08e47be0”, “0?exit|return”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:11] Set(“SIP/2001-08e47be0”, “SV_DIALSTATUS=BUSY”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:12] GosubIf(“SIP/2001-08e47be0”, “0?docfu|1”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GosubIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:13] GosubIf(“SIP/2001-08e47be0”, “0?docfb|1”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GosubIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:14] Set(“SIP/2001-08e47be0”, “DIALSTATUS=BUSY”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:15] NoOp(“SIP/2001-08e47be0”, “Voicemail is 1000”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: NoOp
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:16] GotoIf(“SIP/2001-08e47be0”, “0?s-BUSY|1”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:17] NoOp(“SIP/2001-08e47be0”, “Sending to Voicemail box 1000”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: NoOp
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-exten-vm:18] Macro(“SIP/2001-08e47be0”, “vm|1000|BUSY|”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-vm:1] Macro(“SIP/2001-08e47be0”, “user-callerid|SKIPTTL”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:1] Set(“SIP/2001-08e47be0”, “AMPUSER=2001”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/2001-08e47be0”, “0?report”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/2001-08e47be0”, “0|Set|REALCALLERIDNUM=2001”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: ExecIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:4] Set(“SIP/2001-08e47be0”, “AMPUSER=2001”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:5] Set(“SIP/2001-08e47be0”, “AMPUSERCIDNAME=William”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/2001-08e47be0”, “0?report”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:7] Set(“SIP/2001-08e47be0”, “AMPUSERCID=2001”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:8] Set(“SIP/2001-08e47be0”, “CALLERID(all)=“William” <2001>”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:9] Set(“SIP/2001-08e47be0”, “REALCALLERIDNUM=2001”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:10] ExecIf(“SIP/2001-08e47be0”, “0|Set|CHANNEL(language)=”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: ExecIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:11] GotoIf(“SIP/2001-08e47be0”, “1?continue”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Goto (macro-user-callerid,s,20)
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-user-callerid:20] NoOp(“SIP/2001-08e47be0”, “Using CallerID “William” <2001>”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Noop
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Macro
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-vm:2] Set(“SIP/2001-08e47be0”, “VMGAIN=”””) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] WARNING[23491] func_db.c: DB requires an argument, DB(/)
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-vm:3] GotoIf(“SIP/2001-08e47be0”, “1?vmx|1”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Goto (macro-vm,vmx,1)
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [vmx@macro-vm:1] GotoIf(“SIP/2001-08e47be0”, “0?s-BUSY|1”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [vmx@macro-vm:2] Set(“SIP/2001-08e47be0”, “MODE=busy”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [vmx@macro-vm:3] GotoIf(“SIP/2001-08e47be0”, “1?notdirect”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Goto (macro-vm,vmx,5)
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] DEBUG[23491] func_db.c: DB: AMPUSER/1000/vmx/busy/state not found in database.
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [vmx@macro-vm:5] NoOp(“SIP/2001-08e47be0”, "Checking if ext 1000 is enabled: ") in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Noop
[Oct 27 11:34:52] DEBUG[23491] func_db.c: DB: AMPUSER/1000/vmx/busy/state not found in database.
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [vmx@macro-vm:6] GotoIf(“SIP/2001-08e47be0”, “1?s-BUSY|1”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Goto (macro-vm,s-BUSY,1)
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s-BUSY@macro-vm:1] NoOp(“SIP/2001-08e47be0”, “BUSY voicemail”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: NoOp
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s-BUSY@macro-vm:2] Macro(“SIP/2001-08e47be0”, “get-vmcontext|1000”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-get-vmcontext:1] Set(“SIP/2001-08e47be0”, “VMCONTEXT=default”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Set
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/2001-08e47be0”, “0?200:300”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Goto (macro-get-vmcontext,s,300)
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: GotoIf
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s@macro-get-vmcontext:300] NoOp(“SIP/2001-08e47be0”, “”) in new stack
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: NoOp
[Oct 27 11:34:52] DEBUG[23491] app_macro.c: Executed application: Macro
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – Executing [s-BUSY@macro-vm:3] VoiceMail(“SIP/2001-08e47be0”, “1000@default|b”) in new stack
[Oct 27 11:34:52] VERBOSE[23491] logger.c: – <SIP/2001-08e47be0> Playing ‘vm-theperson’ (language ‘en’)
[Oct 27 11:34:54] VERBOSE[23491] logger.c: – <SIP/2001-08e47be0> Playing ‘digits/1’ (language ‘en’)
[Oct 27 11:34:54] VERBOSE[23491] logger.c: – <SIP/2001-08e47be0> Playing ‘digits/0’ (language ‘en’)
[Oct 27 11:34:55] VERBOSE[23491] logger.c: – <SIP/2001-08e47be0> Playing ‘digits/0’ (language ‘en’)
[Oct 27 11:34:56] VERBOSE[23491] logger.c: – <SIP/2001-08e47be0> Playing ‘digits/0’ (language ‘en’)
[Oct 27 11:34:57] VERBOSE[23491] logger.c: – <SIP/2001-08e47be0> Playing ‘vm-isonphone’ (language ‘en’)
[Oct 27 11:34:58] VERBOSE[23491] logger.c: – <SIP/2001-08e47be0> Playing ‘vm-intro’ (language ‘en’)
[Oct 27 11:35:00] VERBOSE[23491] logger.c: == Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on ‘SIP/2001-08e47be0’ in macro ‘vm’
[Oct 27 11:35:00] VERBOSE[23491] logger.c: == Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on ‘SIP/2001-08e47be0’ in macro ‘exten-vm’
[Oct 27 11:35:00] VERBOSE[23491] logger.c: == Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on ‘SIP/2001-08e47be0’
[Oct 27 11:35:28] VERBOSE[23496] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Oct 27 11:35:28] VERBOSE[23496] logger.c: Found
[Oct 27 11:35:28] VERBOSE[23496] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [Oct 27 11:35:28] VERBOSE[23496] logger.c: Found
2001/2001 192.168.2.17 D N 1198 OK (106 ms)
1001 (Unspecified) D N 0 UNKNOWN
1000/1000 192.168.2.25 D N 5060 OK (8 ms)
1-pstn/1-pstn 192.168.2.25 D 5061 OK (8 ms)
7 sip peers [Monitored: 3 online, 4 offline Unmonitored: 0 online, 0 offline]