SOLVED Issue with voicemail not working on Extensions starting with 3

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
I am running Incredible PBX 12.0.74 on a Raspberry Pi and through much pain, discovered that voicemail does not answer on unavailable on my 3 digit extensions that start with '3' extensions that start with '1' work fine. I searched for feature codes and extensions.conf & associated extenstions_.conf files but I have been unable to find the issue.

Any help would be greatly appreciated.
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
We have tried 301, 398, 399 and others. Those are the three I have running right now. In trying to debug I edited the extensions.conf file on the extensions with issue and did a dialplan reload (not applying settings, because that would undo my temporary edits) to try to force directly to voice mail, but it would still ring first.
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
Right now I have it as a total "dev" system, so we can try anything. I have it happening at 3 different sites, with two different downloads of the Raspberry Pi Incredible Pi image.
 

wardmundy

Nerd Uno
Joined
Oct 12, 2007
Messages
15,152
Reaction score
2,632
Try the following to see what's going on at the Asterisk level with each extension: grep 301 /etc/asterisk/*
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
Code:
/etc/asterisk/extensions_additional.conf:exten => *96301,1,Goto(app-cf-toggle,*96,1)
/etc/asterisk/extensions_additional.conf:exten => *96301,hint,Custom:DEVCF301
/etc/asterisk/extensions_additional.conf:exten => _*96301.,1,Set(toext=${EXTEN:6})
/etc/asterisk/extensions_additional.conf:exten => _*96301.,n,Goto(app-cf-toggle,*96,setdirect)
/etc/asterisk/extensions_additional.conf:exten => *84301,1,Goto(app-campon-toggle,*84,1)
/etc/asterisk/extensions_additional.conf:exten => *84301,hint,ccss:SIP/301
/etc/asterisk/extensions_additional.conf:exten => *301,1,Macro(vm,301,DIRECTDIAL,${IVR_RETVM})
/etc/asterisk/extensions_additional.conf:exten => *301,n,Goto(vmret,1)
/etc/asterisk/extensions_additional.conf:exten => 301,1,Set(__RINGTIMER=${IF($["${DB(AMPUSER/301/ringtimer)}" > "0"]?${DB(AMPUSER/301/ringtimer)}:${RINGTIMER_DEFAULT})})
/etc/asterisk/extensions_additional.conf:exten => 301,n,Macro(exten-vm,301,301,0,0,0)
/etc/asterisk/extensions_additional.conf:exten => 301,n(dest),Set(__PICKUPMARK=)
/etc/asterisk/extensions_additional.conf:exten => 301,n,Macro(vm,301,${DIALSTATUS},${IVR_RETVM})
/etc/asterisk/extensions_additional.conf:exten => 301,n,Goto(vmret,1)
/etc/asterisk/extensions_additional.conf:exten => 301,hint,SIP/301&Custom:DND301,CustomPresence:301
/etc/asterisk/extensions_additional.conf:exten => vmb301,1,Macro(vm,301,BUSY,${IVR_RETVM})
/etc/asterisk/extensions_additional.conf:exten => vmb301,n,Goto(vmret,1)
/etc/asterisk/extensions_additional.conf:exten => vmu301,1,Macro(vm,301,NOANSWER,${IVR_RETVM})
/etc/asterisk/extensions_additional.conf:exten => vmu301,n,Goto(vmret,1)
/etc/asterisk/extensions_additional.conf:exten => vms301,1,Macro(vm,301,NOMESSAGE,${IVR_RETVM})
/etc/asterisk/extensions_additional.conf:exten => vms301,n,Goto(vmret,1)
/etc/asterisk/extensions_additional.conf:exten => vmi301,1,Macro(vm,301,INSTRUCT,${IVR_RETVM})
/etc/asterisk/extensions_additional.conf:exten => vmi301,n,Goto(vmret,1)
/etc/asterisk/extensions_additional.conf:exten => *76301,1,Goto(app-dnd-toggle,*76,1)
/etc/asterisk/extensions_additional.conf:exten => *76301,hint,Custom:DEVDND301
/etc/asterisk/extensions_additional.conf:exten => *21301,1,Goto(app-fmf-toggle,*21,1)
/etc/asterisk/extensions_additional.conf:exten => *21301,hint,Custom:FOLLOWME301
/etc/asterisk/extensions_additional.conf:exten => *80301,1,Goto(ext-intercom,${EXTEN},1)
/etc/asterisk/extensions_additional.conf:exten => *85301,1,Macro(parked-call,,default)
/etc/asterisk/extensions_additional.conf:exten => *85301,hint,Custom:pARK301
/etc/asterisk/extensions_additional.conf:exten => *301,1,Macro(blkvm-clr,)
/etc/asterisk/extensions_additional.conf:exten => *301,n,Set(__NODEST=)
/etc/asterisk/extensions_additional.conf:exten => *301,n,Macro(vm,301,DIRECTDIAL,${IVR_RETVM})
/etc/asterisk/extensions_additional.conf:exten => *301,n,GotoIf($["${IVR_RETVM}" = "RETURN" & "${IVR_CONTEXT}" != ""]?ext-local,vmret,playret)
/etc/asterisk/extensions_additional.conf:exten => 301,1,Macro(blkvm-clr,)
/etc/asterisk/extensions_additional.conf:exten => 301,n,Set(__NODEST=)
/etc/asterisk/extensions_additional.conf:exten => 301,n,Goto(from-did-direct,301,1)
/etc/asterisk/ooh323.conf:;accountcode=h3230101
/etc/asterisk/ooh323.conf.orig:;accountcode=h3230101
/etc/asterisk/sip_additional.conf:[301]
/etc/asterisk/sip_additional.conf:dial=SIP/301
/etc/asterisk/sip_additional.conf:[email protected]
/etc/asterisk/sip_additional.conf:callerid=Milo Test <301>
grep: /etc/asterisk/tm4: Is a directory
/etc/asterisk/voicemail.conf:301 => 1111,Milo Test,,,attach=no|saycid=no|envelope=no|delete=no
 
Last edited by a moderator:

wardmundy

Nerd Uno
Joined
Oct 12, 2007
Messages
15,152
Reaction score
2,632
Now try this one:
Code:
asterisk -rx "database show" | grep 301
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
/AMPUSER/301/answermode : disabled
/AMPUSER/301/ccss/cc_agent_policy : generic
/AMPUSER/301/ccss/cc_offer_timer : 30
/AMPUSER/301/cfringtimer : 0
/AMPUSER/301/cidname : Milo Test
/AMPUSER/301/cidnum : 301
/AMPUSER/301/concurrency_limit : 0
/AMPUSER/301/device : 301
/AMPUSER/301/noanswer :
/AMPUSER/301/novmpw : yes
/AMPUSER/301/outboundcid :
/AMPUSER/301/password :
/AMPUSER/301/queues/qnostate : usestate
/AMPUSER/301/recording :
/AMPUSER/301/recording/in/external : dontcare
/AMPUSER/301/recording/in/internal : dontcare
/AMPUSER/301/recording/ondemand : disabled
/AMPUSER/301/recording/out/external : dontcare
/AMPUSER/301/recording/out/internal : dontcare
/AMPUSER/301/recording/priority : 10
/AMPUSER/301/ringtimer : 0
/AMPUSER/301/voicemail : default
/CW/301 : ENABLED
/DEVICE/301/default_user : 301
/DEVICE/301/dial : SIP/301
/DEVICE/301/type : fixed
/DEVICE/301/user : 301
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
Extensions are identical. Results in log file from 399 (no voice mail)
[email protected]:/var/log/asterisk# grep 399 *
grep: bkp: Is a directory
full:[2016-05-24 21:38:43] VERBOSE[27288][C-00000000] pbx.c: Executing [[email protected]:1] Dial("SIP/398-00000000", "SIP/399") in new stack
full:[2016-05-24 21:38:43] VERBOSE[27288][C-00000000] app_dial.c: Called SIP/399
full:[2016-05-24 21:38:43] VERBOSE[27288][C-00000000] app_dial.c: SIP/399-00000001 is ringing
full:[2016-05-24 21:39:29] VERBOSE[27288][C-00000000] app_dial.c: SIP/399-00000001 answered SIP/398-00000000
full:[2016-05-24 21:39:29] VERBOSE[27370][C-00000000] bridge_channel.c: Channel SIP/399-00000001 joined 'simple_bridge' basic-bridge <7c7c2cf2-625b-4ad3-8d6a-f2961dec315b>
full:[2016-05-24 21:39:33] VERBOSE[27370][C-00000000] bridge_channel.c: Channel SIP/399-00000001 left 'simple_bridge' basic-bridge <7c7c2cf2-625b-4ad3-8d6a-f2961dec315b>
full:[2016-05-24 21:39:33] VERBOSE[27288][C-00000000] pbx.c: Spawn extension (from-internal, 399, 1) exited non-zero on 'SIP/398-00000000'
queue_log:1464139908|NONE|NONE|NONE|QUEUESTART|
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
Same call to 199
[email protected]:/var/log/asterisk# grep 199 *
grep: bkp: Is a directory
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:1] Set("SIP/398-00000002", "__RINGTIMER=15") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:2] Macro("SIP/398-00000002", "exten-vm,199,199,0,0,0") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:14] GosubIf("SIP/398-00000002", "7?sub-ccss,s,1(macro-exten-vm,199)") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:3] GosubIf("SIP/398-00000002", "0?monitor_config,1(macro-exten-vm,199):monitor_default,1(macro-exten-vm,199)") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:8] ExecIf("SIP/398-00000002", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:5] Set("SIP/398-00000002", "DB(AMPUSER/398/ccss/last_number)=199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:3] Set("SIP/398-00000002", "__EXTTOCALL=199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:4] Set("SIP/398-00000002", "__PICKUPMARK=199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:6] Gosub("SIP/398-00000002", "sub-record-check,s,1(exten,199,dontcare)") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:1] NoOp("SIP/398-00000002", "Exten Recording Check between 398 and 199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:16] Gosub("SIP/398-00000002", "recordcheck,1(dontcare,internal,199)") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:14] Macro("SIP/398-00000002", "dial-one,15,tr,199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:1] Set("SIP/398-00000002", "DEXTEN=199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:2] Set("SIP/398-00000002", "DEVICES=199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:7] Set("SIP/398-00000002", "THISDIAL=SIP/199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:5] Set("SIP/398-00000002", "THISPART2=SIP/199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:6] ExecIf("SIP/398-00000002", "0?Set(THISPART2=DAHDI/199)") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:7] Set("SIP/398-00000002", "NEWDIAL=SIP/199&") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:10] Set("SIP/398-00000002", "THISDIAL=SIP/199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:13] Set("SIP/398-00000002", "DSTRING=SIP/199&") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:17] Set("SIP/398-00000002", "DSTRING=SIP/199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:1] Set("SIP/398-00000002", "DB(CALLTRACE/199)=398") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:39] Gosub("SIP/398-00000002", "sub-presencestate-display,s,1(199)") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:40] Set("SIP/398-00000002", "CONNECTEDLINE(name,i)=199-Lowell") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:41] Set("SIP/398-00000002", "CONNECTEDLINE(num)=199") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:44] Dial("SIP/398-00000002", "SIP/199,15,trI") in new stack
full:[2016-05-24 21:41:45] VERBOSE[27584][C-00000001] app_dial.c: Called SIP/199
full:[2016-05-24 21:41:46] VERBOSE[27584][C-00000001] app_dial.c: SIP/199-00000003 is ringing
full:[2016-05-24 21:42:00] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:21] Macro("SIP/398-00000002", "vm,199,NOANSWER,") in new stack
full:[2016-05-24 21:42:00] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:1] Set("SIP/398-00000002", "MEXTEN=199") in new stack
full:[2016-05-24 21:42:00] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:5] Macro("SIP/398-00000002", "get-vmcontext,199") in new stack
full:[2016-05-24 21:42:00] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:13] NoOp("SIP/398-00000002", "Checking if ext 199 is enabled: ") in new stack
full:[2016-05-24 21:42:00] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:1] Macro("SIP/398-00000002", "get-vmcontext,199") in new stack
full:[2016-05-24 21:42:00] VERBOSE[27584][C-00000001] pbx.c: Executing [[email protected]:2] VoiceMail("SIP/398-00000002", "[email protected],u") in new stack
full:[2016-05-24 21:42:07] VERBOSE[27584][C-00000001] pbx.c: Spawn extension (from-internal, 199, 2) exited non-zero on 'SIP/398-00000002'
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
[email protected]:/etc/asterisk# asterisk -rx "database show" | grep 399
/AMPUSER/399/answermode : disabled
/AMPUSER/399/ccss/cc_agent_policy : generic
/AMPUSER/399/ccss/cc_offer_timer : 30
/AMPUSER/399/cfringtimer : 0
/AMPUSER/399/cidname : 399-Test
/AMPUSER/399/cidnum : 399
/AMPUSER/399/concurrency_limit : 0
/AMPUSER/399/device : 399
/AMPUSER/399/noanswer :
/AMPUSER/399/novmpw : yes
/AMPUSER/399/outboundcid :
/AMPUSER/399/password :
/AMPUSER/399/queues/qnostate : usestate
/AMPUSER/399/recording :
/AMPUSER/399/recording/in/external : dontcare
/AMPUSER/399/recording/in/internal : dontcare
/AMPUSER/399/recording/ondemand : disabled
/AMPUSER/399/recording/out/external : dontcare
/AMPUSER/399/recording/out/internal : dontcare
/AMPUSER/399/recording/priority : 10
/AMPUSER/399/ringtimer : 0
/AMPUSER/399/voicemail : default
/CALLTRACE/398 : 399
/CW/399 : ENABLED
/DEVICE/399/default_user : 399
/DEVICE/399/dial : SIP/399
/DEVICE/399/type : fixed
/DEVICE/399/user : 399
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
[email protected]:/etc/asterisk# asterisk -rx "database show" | grep 199
/AMPUSER/199/answermode : disabled
/AMPUSER/199/ccss/cc_agent_policy : generic
/AMPUSER/199/ccss/cc_offer_timer : 30
/AMPUSER/199/ccss/last_number : 101
/AMPUSER/199/cfringtimer : 0
/AMPUSER/199/cidname : 199-Lowell
/AMPUSER/199/cidnum : 199
/AMPUSER/199/concurrency_limit : 0
/AMPUSER/199/device : 199
/AMPUSER/199/noanswer :
/AMPUSER/199/novmpw : yes
/AMPUSER/199/outboundcid :
/AMPUSER/199/password :
/AMPUSER/199/queues/qnostate : usestate
/AMPUSER/199/recording :
/AMPUSER/199/recording/in/external : dontcare
/AMPUSER/199/recording/in/internal : dontcare
/AMPUSER/199/recording/ondemand : disabled
/AMPUSER/199/recording/out/external : dontcare
/AMPUSER/199/recording/out/internal : dontcare
/AMPUSER/199/recording/priority : 10
/AMPUSER/199/ringtimer : 0
/AMPUSER/199/voicemail : default
/AMPUSER/398/ccss/last_number : 199
/CALLTRACE/199 : 398
/CW/199 : ENABLED
/DEVICE/199/default_user : 199
/DEVICE/199/dial : SIP/199
/DEVICE/199/type : fixed
/DEVICE/199/user : 199
 

billsimon

Experienced in Asterisk, FreePBX, and SIP
Joined
Jan 2, 2011
Messages
966
Reaction score
303
I'm going to take a swing at this one and say you have a match in your /etc/asterisk/extensions_custom.conf file, in the [from-internal-custom] context. Something like _3XX maybe.
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
I had just figured it out and wanted to post the fix. The last section of extensions_custom has the following

[from-internal]
;exten => _NXX,1,Dial(SIP/${EXTEN})

So I commented it out and it worked, my question is why didn't that also catch 199? And the bigger question is why is that in the Incredible Pi distribution?
 

billsimon

Experienced in Asterisk, FreePBX, and SIP
Joined
Jan 2, 2011
Messages
966
Reaction score
303
[from-internal]
;exten => _NXX,1,Dial(SIP/${EXTEN})

So I commented it out and it worked, my question is why didn't that also catch 199?
"N" doesn't match 1. It only matches digits 2-9.
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
That DISTRO needs to be fixed. VM is not going to work on any extensions besides 1 series.
 

lbergey

Member
Joined
Nov 25, 2011
Messages
86
Reaction score
17
Not Good!! How I found it was by writing a script that altered all of the extension*.conf files and put a noop with the file and a counter at any extn => ,1, and put that line as extn => ,n, Then did a dialplan reload (not amportal -a -r). I then found it in the logs. I think I want to edit and post my script, it really helped to debug.
 
  • Like
Reactions: wardmundy

wardmundy

Nerd Uno
Joined
Oct 12, 2007
Messages
15,152
Reaction score
2,632
That DISTRO needs to be fixed. VM is not going to work on any extensions besides 1 series.
Please post results of running status or pbx-status so we can track down the problem. That is not a context we include.
 

Members online

PIAF 5 - Powered by 3CX

Forum statistics

Threads
22,371
Messages
137,417
Members
14,576
Latest member
emmonks