FreePBX 12.0.76.4 / Asterisk 13.2.0
Ring Group 6600 -> goes to 6605, then 6729, then 6601. If no one answers, it should end up in 6605’s voicemail.
Problem is, the voicemail only sometimes plays. Its inconsistent, regardless of time at each phone and regardless of number of additional stops (extensions) on the way. I’ve rolled back to Ring Group 12.0.3.4 from 12.0.3.5 but it still is happening. Any suggestions are appreciated! Here is the log:
[2016-08-17 16:32:42] VERBOSE[11843][C-000006ca] app_dial.c: PJSIP/6605-00000e4a is ringing [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] app_dial.c: Nobody picked up in 20000 ms [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:39] GotoIf("PJSIP/Charter New-00000e49", "0?ANSWER,1") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:40] Set("PJSIP/Charter New-00000e49", "HuntLoop=1") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:41] GotoIf("PJSIP/Charter New-00000e49", "1?a46") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,43) [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:43] Set("PJSIP/Charter New-00000e49", "HuntMembers=2") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:44] Goto("PJSIP/Charter New-00000e49", "s,a22") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,22) [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:22] GotoIf("PJSIP/Charter New-00000e49", "1?a30") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,26) [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:26] Set("PJSIP/Charter New-00000e49", "HuntMember=HuntMember1") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:27] GotoIf("PJSIP/Charter New-00000e49", "1?a32:a35") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,28) [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:28] Set("PJSIP/Charter New-00000e49", "CT_EXTEN=6729") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:29] Set("PJSIP/Charter New-00000e49", "DB(CALLTRACE/6729)=5552899764") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:30] Goto("PJSIP/Charter New-00000e49", "s,a42") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,38) [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:38] Dial("PJSIP/Charter New-00000e49", "PJSIP/6729,20,trM(auto-blkvm)") in new stack [2016-08-17 16:33:01] VERBOSE[11843][C-000006ca] app_dial.c: Called PJSIP/6729 [2016-08-17 16:33:02] VERBOSE[11843][C-000006ca] app_dial.c: PJSIP/6729-00000e4b is ringing [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] app_dial.c: Nobody picked up in 20000 ms [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:39] GotoIf("PJSIP/Charter New-00000e49", "0?ANSWER,1") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:40] Set("PJSIP/Charter New-00000e49", "HuntLoop=2") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:41] GotoIf("PJSIP/Charter New-00000e49", "1?a46") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,43) [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:43] Set("PJSIP/Charter New-00000e49", "HuntMembers=1") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:44] Goto("PJSIP/Charter New-00000e49", "s,a22") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,22) [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:22] GotoIf("PJSIP/Charter New-00000e49", "1?a30") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,26) [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:26] Set("PJSIP/Charter New-00000e49", "HuntMember=HuntMember2") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:27] GotoIf("PJSIP/Charter New-00000e49", "1?a32:a35") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,28) [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:28] Set("PJSIP/Charter New-00000e49", "CT_EXTEN=6601") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:29] Set("PJSIP/Charter New-00000e49", "DB(CALLTRACE/6601)=5552899764") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:30] Goto("PJSIP/Charter New-00000e49", "s,a42") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,38) [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:38] Dial("PJSIP/Charter New-00000e49", "PJSIP/6601,20,trM(auto-blkvm)") in new stack [2016-08-17 16:33:21] VERBOSE[11843][C-000006ca] app_dial.c: Called PJSIP/6601 [2016-08-17 16:33:21] NOTICE[18125] res_pjsip_exten_state.c: Extension 4230 does not exist or has no associated hint [2016-08-17 16:33:22] VERBOSE[11843][C-000006ca] app_dial.c: PJSIP/6601-00000e4c is ringing [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] app_dial.c: Nobody picked up in 20000 ms [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:39] GotoIf("PJSIP/Charter New-00000e49", "0?ANSWER,1") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:40] Set("PJSIP/Charter New-00000e49", "HuntLoop=3") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:41] GotoIf("PJSIP/Charter New-00000e49", "1?a46") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,43) [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:43] Set("PJSIP/Charter New-00000e49", "HuntMembers=0") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:44] Goto("PJSIP/Charter New-00000e49", "s,a22") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-dial,s,22) [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:22] GotoIf("PJSIP/Charter New-00000e49", "0?a30") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-dial:23] NoOp("PJSIP/Charter New-00000e49", "Returning there are no members left in the hunt group to ring") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [6600@ext-group:12] Gosub("PJSIP/Charter New-00000e49", "sub-record-cancel,s,1()") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@sub-record-cancel:1] Return("PJSIP/Charter New-00000e49", "") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [6600@ext-group:13] Set("PJSIP/Charter New-00000e49", "RingGroupMethod=") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [6600@ext-group:14] GotoIf("PJSIP/Charter New-00000e49", "0?nodest") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [6600@ext-group:15] Set("PJSIP/Charter New-00000e49", "__NODEST=") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [6600@ext-group:16] Macro("PJSIP/Charter New-00000e49", "blkvm-clr,") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-blkvm-clr:1] Set("PJSIP/Charter New-00000e49", "SHARED(BLKVM,PJSIP/Charter New-00000e49)=") in new stack [2016-08-17 16:33:41] WARNING[11843][C-000006ca] pbx.c: Please avoid unnecessary spaces on variables as it may lead to unexpected results ('SHARED(BLKVM,PJSIP/Charter New-00000e49)' set to ''). [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-blkvm-clr:2] Set("PJSIP/Charter New-00000e49", "GOSUB_RETVAL=") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("PJSIP/Charter New-00000e49", "") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [6600@ext-group:17] Goto("PJSIP/Charter New-00000e49", "ext-local,vmu6605,1") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Goto (ext-local,vmu6605,1) [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmu6605@ext-local:1] Macro("PJSIP/Charter New-00000e49", "vm,6605,NOANSWER,") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-vm:1] Macro("PJSIP/Charter New-00000e49", "user-callerid,SKIPTTL") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/Charter New-00000e49", "TOUCH_MONITOR=1471469561.24749") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/Charter New-00000e49", "AMPUSER=5552899764") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/Charter New-00000e49", "0?report") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/Charter New-00000e49", "0?Set(REALCALLERIDNUM=5552899764)") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/Charter New-00000e49", "AMPUSER=") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/Charter New-00000e49", "0?limit") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/Charter New-00000e49", "AMPUSERCIDNAME=") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/Charter New-00000e49", "1?report") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-user-callerid,s,16) [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/Charter New-00000e49", "1?continue") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-user-callerid,s,30) [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:30] Set("PJSIP/Charter New-00000e49", "CALLERID(number)=5552899764") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:31] Set("PJSIP/Charter New-00000e49", "CALLERID(name)=CURLESS JESSICA") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/Charter New-00000e49", "CDR(cnum)=5552899764") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/Charter New-00000e49", "CDR(cnam)=CURLESS JESSICA") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-user-callerid:34] Set("PJSIP/Charter New-00000e49", "CHANNEL(language)=en") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-vm:2] Set("PJSIP/Charter New-00000e49", "VMGAIN=g(12)") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-vm:3] Macro("PJSIP/Charter New-00000e49", "blkvm-check,") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-blkvm-check:1] Set("PJSIP/Charter New-00000e49", "GOSUB_RETVAL=") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf("PJSIP/Charter New-00000e49", "0?Set(GOSUB_RETVAL=TRUE)") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit("PJSIP/Charter New-00000e49", "") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-vm:4] GotoIf("PJSIP/Charter New-00000e49", "1?vmx,1") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-vm,vmx,1) [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:1] Set("PJSIP/Charter New-00000e49", "MEXTEN=6605") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:2] Set("PJSIP/Charter New-00000e49", "MMODE=NOANSWER") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:3] Set("PJSIP/Charter New-00000e49", "RETVM=") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:4] Set("PJSIP/Charter New-00000e49", "MODE=unavail") in new stack [2016-08-17 16:33:41] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:5] Macro("PJSIP/Charter New-00000e49", "get-vmcontext,6605") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/Charter New-00000e49", "VMCONTEXT=default") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/Charter New-00000e49", "0?200:300") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-get-vmcontext,s,300) [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/Charter New-00000e49", "") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:6] Set("PJSIP/Charter New-00000e49", "MODE=temp") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:7] NoOp("PJSIP/Charter New-00000e49", "MODE IS: temp") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:8] GotoIf("PJSIP/Charter New-00000e49", "1?chknomsg") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-vm,vmx,10) [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:10] GotoIf("PJSIP/Charter New-00000e49", "0?s-NOANSWER,1") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:11] GotoIf("PJSIP/Charter New-00000e49", "1?notdirect") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-vm,vmx,13) [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:13] NoOp("PJSIP/Charter New-00000e49", "Checking if ext 6605 is enabled: ") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [vmx@macro-vm:14] GotoIf("PJSIP/Charter New-00000e49", "1?s-NOANSWER,1") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-vm,s-NOANSWER,1) [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro("PJSIP/Charter New-00000e49", "get-vmcontext,6605") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/Charter New-00000e49", "VMCONTEXT=default") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/Charter New-00000e49", "0?200:300") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Goto (macro-get-vmcontext,s,300) [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/Charter New-00000e49", "") in new stack [2016-08-17 16:33:42] VERBOSE[11843][C-000006ca] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail("PJSIP/Charter New-00000e49", "6605@default,sug(12)") in new stack