Issue with VmX and FollowMe

Using the tutorial here: http://www.freepbx.org/news/2007-09-13/follow-me-or-vmx-locater-which-one-is-for-you and the current release of AsteriskNOW with all FreePBX updates, I have configured my extension to be forwarded to my cell.

Although the tutorial didn’t mention it, I found that I had to set up my temp voicemail message before forwarding would go through VM. Having got that far, I then found that pressing “1” had no effect - the call drops into VM.

I have gone through the tutorial again and am sure I have my configuration correct; however, when calling from another extension, it won’t follow. The log looks like this:

obelix*CLI> -- Executing [7001@from-internal:1] GotoIf("SIP/7002-091c2580", "1?ext-local|7001|1") in new stack -- Goto (ext-local,7001,1) -- Executing [7001@ext-local:1] Macro("SIP/7002-091c2580", "exten-vm|7001|7001") in new stack -- Executing [s@macro-exten-vm:1] Macro("SIP/7002-091c2580", "user-callerid") in new stack -- Executing [s@macro-user-callerid:1] Set("SIP/7002-091c2580", "AMPUSER=7002") in new stack -- Executing [s@macro-user-callerid:2] GotoIf("SIP/7002-091c2580", "0?report") in new stack -- Executing [s@macro-user-callerid:3] ExecIf("SIP/7002-091c2580", "1|Set|REALCALLERIDNUM=7002") in new stack -- Executing [s@macro-user-callerid:4] Set("SIP/7002-091c2580", "AMPUSER=7002") in new stack -- Executing [s@macro-user-callerid:5] Set("SIP/7002-091c2580", "AMPUSERCIDNAME=Test User2") in new stack -- Executing [s@macro-user-callerid:6] GotoIf("SIP/7002-091c2580", "0?report") in new stack -- Executing [s@macro-user-callerid:7] Set("SIP/7002-091c2580", "AMPUSERCID=7002") in new stack -- Executing [s@macro-user-callerid:8] Set("SIP/7002-091c2580", "CALLERID(all)="Test User2" <7002>") in new stack -- Executing [s@macro-user-callerid:9] Set("SIP/7002-091c2580", "REALCALLERIDNUM=7002") in new stack -- Executing [s@macro-user-callerid:10] ExecIf("SIP/7002-091c2580", "0|Set|CHANNEL(language)=") in new stack -- Executing [s@macro-user-callerid:11] GotoIf("SIP/7002-091c2580", "0?continue") in new stack -- Executing [s@macro-user-callerid:12] Set("SIP/7002-091c2580", "__TTL=64") in new stack -- Executing [s@macro-user-callerid:13] GotoIf("SIP/7002-091c2580", "1?continue") in new stack -- Goto (macro-user-callerid,s,20) -- Executing [s@macro-user-callerid:20] NoOp("SIP/7002-091c2580", "Using CallerID "Test User2" <7002>") in new stack -- Executing [s@macro-exten-vm:2] Set("SIP/7002-091c2580", "RingGroupMethod=none") in new stack -- Executing [s@macro-exten-vm:3] Set("SIP/7002-091c2580", "VMBOX=7001") in new stack -- Executing [s@macro-exten-vm:4] Set("SIP/7002-091c2580", "EXTTOCALL=7001") in new stack -- Executing [s@macro-exten-vm:5] Set("SIP/7002-091c2580", "CFUEXT=") in new stack -- Executing [s@macro-exten-vm:6] Set("SIP/7002-091c2580", "CFBEXT=") in new stack -- Executing [s@macro-exten-vm:7] Set("SIP/7002-091c2580", "RT=15") in new stack -- Executing [s@macro-exten-vm:8] Macro("SIP/7002-091c2580", "record-enable|7001|IN") in new stack -- Executing [s@macro-record-enable:1] GotoIf("SIP/7002-091c2580", "1?check") in new stack -- Goto (macro-record-enable,s,4) -- Executing [s@macro-record-enable:4] AGI("SIP/7002-091c2580", "recordingcheck|20090621-220615|1245647175.0") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck recordingcheck|20090621-220615|1245647175.0: Inbound recording not enabled -- AGI Script recordingcheck completed, returning 0 -- Executing [s@macro-record-enable:5] MacroExit("SIP/7002-091c2580", "") in new stack -- Executing [s@macro-exten-vm:9] Macro("SIP/7002-091c2580", "dial|15|tr|7001") in new stack -- Executing [s@macro-dial:1] GotoIf("SIP/7002-091c2580", "1?dial") in new stack -- Goto (macro-dial,s,3) -- Executing [s@macro-dial:3] AGI("SIP/7002-091c2580", "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 'Test User2' number is '7002' dialparties.agi: Methodology of ring is 'none' -- dialparties.agi: Added extension 7001 to extension map -- dialparties.agi: Extension 7001 cf is disabled -- dialparties.agi: Extension 7001 do not disturb is disabled -- dialparties.agi: dbset CALLTRACE/7001 to 7002 -- dialparties.agi: Filtered ARG3: 7001 == Manager 'admin' logged off from 127.0.0.1 -- AGI Script dialparties.agi completed, returning 0 -- Executing [s@macro-dial:7] Dial("SIP/7002-091c2580", "SIP/7001|15|tr") in new stack -- Called 7001 -- SIP/7001-091c9688 is ringing -- Nobody picked up in 15000 ms -- Executing [s@macro-dial:8] Set("SIP/7002-091c2580", "DIALSTATUS=NOANSWER") in new stack -- Executing [s@macro-dial:9] GosubIf("SIP/7002-091c2580", "0?NOANSWER|1") in new stack -- Executing [s@macro-exten-vm:10] GotoIf("SIP/7002-091c2580", "0?exit|return") in new stack -- Executing [s@macro-exten-vm:11] Set("SIP/7002-091c2580", "SV_DIALSTATUS=NOANSWER") in new stack -- Executing [s@macro-exten-vm:12] GosubIf("SIP/7002-091c2580", "0?docfu|1") in new stack -- Executing [s@macro-exten-vm:13] GosubIf("SIP/7002-091c2580", "0?docfb|1") in new stack -- Executing [s@macro-exten-vm:14] Set("SIP/7002-091c2580", "DIALSTATUS=NOANSWER") in new stack -- Executing [s@macro-exten-vm:15] NoOp("SIP/7002-091c2580", "Voicemail is 7001") in new stack -- Executing [s@macro-exten-vm:16] GotoIf("SIP/7002-091c2580", "0?s-NOANSWER|1") in new stack -- Executing [s@macro-exten-vm:17] NoOp("SIP/7002-091c2580", "Sending to Voicemail box 7001") in new stack -- Executing [s@macro-exten-vm:18] Macro("SIP/7002-091c2580", "vm|7001|NOANSWER|") in new stack -- Executing [s@macro-vm:1] Macro("SIP/7002-091c2580", "user-callerid|SKIPTTL") in new stack -- Executing [s@macro-user-callerid:1] Set("SIP/7002-091c2580", "AMPUSER=7002") in new stack -- Executing [s@macro-user-callerid:2] GotoIf("SIP/7002-091c2580", "0?report") in new stack -- Executing [s@macro-user-callerid:3] ExecIf("SIP/7002-091c2580", "0|Set|REALCALLERIDNUM=7002") in new stack -- Executing [s@macro-user-callerid:4] Set("SIP/7002-091c2580", "AMPUSER=7002") in new stack -- Executing [s@macro-user-callerid:5] Set("SIP/7002-091c2580", "AMPUSERCIDNAME=Test User2") in new stack -- Executing [s@macro-user-callerid:6] GotoIf("SIP/7002-091c2580", "0?report") in new stack -- Executing [s@macro-user-callerid:7] Set("SIP/7002-091c2580", "AMPUSERCID=7002") in new stack -- Executing [s@macro-user-callerid:8] Set("SIP/7002-091c2580", "CALLERID(all)="Test User2" <7002>") in new stack -- Executing [s@macro-user-callerid:9] Set("SIP/7002-091c2580", "REALCALLERIDNUM=7002") in new stack -- Executing [s@macro-user-callerid:10] ExecIf("SIP/7002-091c2580", "0|Set|CHANNEL(language)=") in new stack -- Executing [s@macro-user-callerid:11] GotoIf("SIP/7002-091c2580", "1?continue") in new stack -- Goto (macro-user-callerid,s,20) -- Executing [s@macro-user-callerid:20] NoOp("SIP/7002-091c2580", "Using CallerID "Test User2" <7002>") in new stack -- Executing [s@macro-vm:2] Set("SIP/7002-091c2580", "VMGAIN=""") in new stack -- Executing [s@macro-vm:3] GotoIf("SIP/7002-091c2580", "1?vmx|1") in new stack -- Goto (macro-vm,vmx,1) -- Executing [vmx@macro-vm:1] GotoIf("SIP/7002-091c2580", "0?s-NOANSWER|1") in new stack -- Executing [vmx@macro-vm:2] Set("SIP/7002-091c2580", "MODE=unavail") in new stack -- Executing [vmx@macro-vm:3] GotoIf("SIP/7002-091c2580", "1?notdirect") in new stack -- Goto (macro-vm,vmx,5) -- Executing [vmx@macro-vm:5] NoOp("SIP/7002-091c2580", "Checking if ext 7001 is enabled: enabled") in new stack -- Executing [vmx@macro-vm:6] GotoIf("SIP/7002-091c2580", "0?s-NOANSWER|1") in new stack -- Executing [vmx@macro-vm:7] Macro("SIP/7002-091c2580", "get-vmcontext|7001") in new stack -- Executing [s@macro-get-vmcontext:1] Set("SIP/7002-091c2580", "VMCONTEXT=default") in new stack -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/7002-091c2580", "0?200:300") in new stack -- Goto (macro-get-vmcontext,s,300) -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/7002-091c2580", "") in new stack -- Executing [vmx@macro-vm:8] AGI("SIP/7002-091c2580", "checksound.agi|/var/spool/asterisk/voicemail/default/7001/temp") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/checksound.agi -- AGI Script checksound.agi completed, returning 0 -- Executing [vmx@macro-vm:9] GotoIf("SIP/7002-091c2580", "1?tmpgreet") in new stack -- Goto (macro-vm,vmx,65) -- Executing [vmx@macro-vm:65] NoOp("SIP/7002-091c2580", "Temporary Greeting Detected| going to normal voicemail") in new stack -- Executing [vmx@macro-vm:66] Goto("SIP/7002-091c2580", "s-NOANSWER|1") in new stack -- Goto (macro-vm,s-NOANSWER,1) -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/7002-091c2580", "get-vmcontext|7001") in new stack -- Executing [s@macro-get-vmcontext:1] Set("SIP/7002-091c2580", "VMCONTEXT=default") in new stack -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/7002-091c2580", "0?200:300") in new stack -- Goto (macro-get-vmcontext,s,300) -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/7002-091c2580", "") in new stack -- Executing [s-NOANSWER@macro-vm:2] VoiceMail("SIP/7002-091c2580", "7001@default|u") in new stack -- <SIP/7002-091c2580> Playing '/var/spool/asterisk/voicemail/default/7001/temp' (language 'en') -- <SIP/7002-091c2580> Playing 'vm-intro' (language 'en') == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'SIP/7002-091c2580' in macro 'vm' == Spawn extension (macro-exten-vm, s, 18) exited non-zero on 'SIP/7002-091c2580' in macro 'exten-vm' == Spawn extension (ext-local, 7001, 1) exited non-zero on 'SIP/7002-091c2580'

I’d appreciate any assistance in resolving this.

Thanks,

Des