Voicemail notification issue

Asterisk Version - 11.8
FreePBX Version - 2.11
Phone Type - Yealink T46G
Switches - CISCO SG500-52P

We are running into an issue where when a voicemail is left on one extension a new voicemail notification is pooping up on all the phones. None of the endpoints are set to monitor any other voicemail box than the extension programmed on them. You cannot access the voicemail from any other extension than the one it was left on originally and the notifications goes away when the message is deleted from the phone the voicemail was left on.

At first we thought it was Unicast Flooding issue but we have taken the appropriate steps on the CISCO switch to prevent this and it is still happening. I have also rebuilt extensions and reporvisioned phones both with Endpoint Manager and manually but the issue persists. I am not seeing any indication in the call log that notifications are being sent to any other extension when a voicemail is left (below is a recent log of a call). Any ideas?

[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "__FROM_DID=+13179698530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Gosub("SIP/1-0000008a", "app-blacklist-check,s,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] GotoIf("SIP/1-0000008a", "0?blacklisted") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "CALLED_BLACKLIST=1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] Return("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] Set("SIP/1-0000008a", "CDR(did)=+13179698530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] ExecIf("SIP/1-0000008a", "1 ?Set(CALLERID(name)=+18776455156)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] Set("SIP/1-0000008a", "CHANNEL(musicclass)=default") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:6] Set("SIP/1-0000008a", "__MOHCLASS=default") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] Set("SIP/1-0000008a", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] Set("SIP/1-0000008a", "CALLERPRES()=allowed_not_screened") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:9] Goto("SIP/1-0000008a", "from-did-direct,8530,1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (from-did-direct,8530,1)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] GotoIf("SIP/1-0000008a", "1?ext-local,8530,1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (ext-local,8530,1)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "__RINGTIMER=15") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Macro("SIP/1-0000008a", "exten-vm,8530,8530,0,0,0") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Macro("SIP/1-0000008a", "user-callerid,") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "TOUCH_MONITOR=1438101802.138") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "AMPUSER=+18776455156") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] GotoIf("SIP/1-0000008a", "0?report") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] ExecIf("SIP/1-0000008a", "1?Set(REALCALLERIDNUM=+18776455156)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] Set("SIP/1-0000008a", "AMPUSER=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:6] GotoIf("SIP/1-0000008a", "0?limit") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] Set("SIP/1-0000008a", "AMPUSERCIDNAME=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] GotoIf("SIP/1-0000008a", "1?report") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-user-callerid,s,16)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:16] GotoIf("SIP/1-0000008a", "0?continue") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:17] Set("SIP/1-0000008a", "__TTL=64") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:18] GotoIf("SIP/1-0000008a", "1?continue") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-user-callerid,s,29)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:29] Set("SIP/1-0000008a", "CALLERID(number)=+18776455156") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:30] Set("SIP/1-0000008a", "CALLERID(name)=+18776455156") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:31] Set("SIP/1-0000008a", "CDR(cnum)=+18776455156") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:32] Set("SIP/1-0000008a", "CDR(cnam)=+18776455156") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:33] Set("SIP/1-0000008a", "CHANNEL(language)=en") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "RingGroupMethod=none") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] Set("SIP/1-0000008a", "__EXTTOCALL=8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] Set("SIP/1-0000008a", "__PICKUPMARK=8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] Set("SIP/1-0000008a", "RT=15") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:6] Gosub("SIP/1-0000008a", "sub-record-check,s,1(exten,8530,)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "REC_POLICY_MODE_SAVE=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] GotoIf("SIP/1-0000008a", "1?check") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (sub-record-check,s,7)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] Set("SIP/1-0000008a", "__MON_FMT=wav") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] GotoIf("SIP/1-0000008a", "1?next") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (sub-record-check,s,11)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:11] ExecIf("SIP/1-0000008a", "0?Return()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:12] ExecIf("SIP/1-0000008a", "0?Set(__REC_POLICY_MODE=)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:13] GotoIf("SIP/1-0000008a", "0?exten,1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:14] Set("SIP/1-0000008a", "__REC_STATUS=INITIALIZED") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:15] Set("SIP/1-0000008a", "NOW=1438101802") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:16] Set("SIP/1-0000008a", "__DAY=28") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:17] Set("SIP/1-0000008a", "__MONTH=07") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:18] Set("SIP/1-0000008a", "__YEAR=2015") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:19] Set("SIP/1-0000008a", "__TIMESTR=20150728-124322") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:20] Set("SIP/1-0000008a", "__FROMEXTEN=+18776455156") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:21] Set("SIP/1-0000008a", "__CALLFILENAME=exten-8530-+18776455156-20150728-124322-1438101802.138") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:22] Goto("SIP/1-0000008a", "exten,1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (sub-record-check,exten,1)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] GotoIf("SIP/1-0000008a", "0?callee") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "__REC_POLICY_MODE=dontcare") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] GotoIf("SIP/1-0000008a", "1?caller") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (sub-record-check,exten,10)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:10] Set("SIP/1-0000008a", "__REC_POLICY_MODE=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:11] GosubIf("SIP/1-0000008a", "0?record,1(exten,8530,+18776455156)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:12] Return("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] GotoIf("SIP/1-0000008a", "1?macrodial") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-exten-vm,s,13)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:13] GosubIf("SIP/1-0000008a", "0?clrheader,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:14] Macro("SIP/1-0000008a", "dial-one,15,Ttr,8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "DEXTEN=8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "DIALSTATUS_CW=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] GosubIf("SIP/1-0000008a", "0?screen,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] GosubIf("SIP/1-0000008a", "0?cf,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] GotoIf("SIP/1-0000008a", "1?skip1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-dial-one,s,8)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] GotoIf("SIP/1-0000008a", "0?nodial") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:9] GotoIf("SIP/1-0000008a", "0?continue") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:10] Set("SIP/1-0000008a", "EXTHASCW=ENABLED") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:11] GotoIf("SIP/1-0000008a", "0?next1:cwinusebusy") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-dial-one,s,23)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:23] GotoIf("SIP/1-0000008a", "1?next3:continue") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-dial-one,s,24)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:24] ExecIf("SIP/1-0000008a", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:25] GotoIf("SIP/1-0000008a", "0?nodial") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:26] GosubIf("SIP/1-0000008a", "1?dstring,1():dlocal,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "DSTRING=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "DEVICES=8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] ExecIf("SIP/1-0000008a", "0?Return()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] ExecIf("SIP/1-0000008a", "0?Set(DEVICES=530)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] Set("SIP/1-0000008a", "LOOPCNT=1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:6] Set("SIP/1-0000008a", "ITER=1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] Set("SIP/1-0000008a", "THISDIAL=SIP/8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] GosubIf("SIP/1-0000008a", "1?zap2dahdi,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] ExecIf("SIP/1-0000008a", "0?Return()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "NEWDIAL=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] Set("SIP/1-0000008a", "LOOPCNT2=1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] Set("SIP/1-0000008a", "ITER2=1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] Set("SIP/1-0000008a", "THISPART2=SIP/8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:6] ExecIf("SIP/1-0000008a", "0?Set(THISPART2=DAHDI/8530)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] Set("SIP/1-0000008a", "NEWDIAL=SIP/8530&") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] Set("SIP/1-0000008a", "ITER2=2") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:9] GotoIf("SIP/1-0000008a", "0?begin2") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:10] Set("SIP/1-0000008a", "THISDIAL=SIP/8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:11] Return("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:9] Set("SIP/1-0000008a", "DSTRING=SIP/8530&") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:10] Set("SIP/1-0000008a", "ITER=2") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:11] GotoIf("SIP/1-0000008a", "0?begin") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:12] Set("SIP/1-0000008a", "DSTRING=SIP/8530") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:13] Return("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:27] GotoIf("SIP/1-0000008a", "0?nodial") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:28] GotoIf("SIP/1-0000008a", "0?skiptrace") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:29] GosubIf("SIP/1-0000008a", "1?ctset,1():ctclear,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "DB(CALLTRACE/8530)=+18776455156") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Return("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:30] Set("SIP/1-0000008a", "D_OPTIONS=Ttr") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:31] ExecIf("SIP/1-0000008a", "0?SIPAddHeader(Alert-Info: )") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:32] ExecIf("SIP/1-0000008a", "0?SIPAddHeader()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:33] ExecIf("SIP/1-0000008a", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:34] GosubIf("SIP/1-0000008a", "0?qwait,1()") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:35] Set("SIP/1-0000008a", "__CWIGNORE=") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:36] Set("SIP/1-0000008a", "__KEEPCID=TRUE") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:37] GotoIf("SIP/1-0000008a", "0?usegoto,1") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:38] GotoIf("SIP/1-0000008a", "1?godial") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-dial-one,s,43)
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:43] Dial("SIP/1-0000008a", "SIP/8530,15,Ttr") in new stack
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] netsock2.c:   == Using SIP RTP TOS bits 184
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] netsock2.c:   == Using SIP RTP CoS mark 5
[2015-07-28 12:43:22] VERBOSE[3287] chan_sip.c:   == Extension Changed 8530[ext-local] new state Ringing for Notify User 8531 
[2015-07-28 12:43:22] VERBOSE[3287] chan_sip.c:   == Extension Changed 8530[ext-local] new state Ringing for Notify User 102 
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] app_dial.c:     -- Called SIP/8530
[2015-07-28 12:43:22] VERBOSE[15615][C-00000026] app_dial.c:     -- SIP/8530-0000008b is ringing
[2015-07-28 12:43:22] VERBOSE[3287] chan_sip.c:   == Extension Changed 8530[ext-local] new state Ringing for Notify User 8531 
[2015-07-28 12:43:22] VERBOSE[3287] chan_sip.c:   == Extension Changed 8530[ext-local] new state Ringing for Notify User 102 
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] app_dial.c:     -- Nobody picked up in 15000 ms
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:44] ExecIf("SIP/1-0000008a", "0?MacroExit()") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:45] ExecIf("SIP/1-0000008a", "0?Set(DIALSTATUS=)") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:46] GosubIf("SIP/1-0000008a", "0?s-NOANSWER,1()") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:47] MacroExit("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:15] Set("SIP/1-0000008a", "SV_DIALSTATUS=NOANSWER") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:16] GosubIf("SIP/1-0000008a", "0?docfu,1()") in new stack
[2015-07-28 12:43:37] VERBOSE[3287] chan_sip.c:   == Extension Changed 8530[ext-local] new state Idle for Notify User 8531 
[2015-07-28 12:43:37] VERBOSE[3287] chan_sip.c:   == Extension Changed 8530[ext-local] new state Idle for Notify User 102 
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:17] GosubIf("SIP/1-0000008a", "0?docfb,1()") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:18] Set("SIP/1-0000008a", "DIALSTATUS=NOANSWER") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:19] ExecIf("SIP/1-0000008a", "0?MacroExit()") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:20] GotoIf("SIP/1-0000008a", "0?s-NOANSWER,1") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:21] Macro("SIP/1-0000008a", "vm,8530,NOANSWER,") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Macro("SIP/1-0000008a", "user-callerid,SKIPTTL") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "TOUCH_MONITOR=1438101802.138") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "AMPUSER=+18776455156") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] GotoIf("SIP/1-0000008a", "0?report") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] ExecIf("SIP/1-0000008a", "0?Set(REALCALLERIDNUM=+18776455156)") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] Set("SIP/1-0000008a", "AMPUSER=") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:6] GotoIf("SIP/1-0000008a", "0?limit") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] Set("SIP/1-0000008a", "AMPUSERCIDNAME=") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] GotoIf("SIP/1-0000008a", "1?report") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-user-callerid,s,16)
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:16] GotoIf("SIP/1-0000008a", "1?continue") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-user-callerid,s,29)
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:29] Set("SIP/1-0000008a", "CALLERID(number)=+18776455156") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:30] Set("SIP/1-0000008a", "CALLERID(name)=+18776455156") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:31] Set("SIP/1-0000008a", "CDR(cnum)=+18776455156") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:32] Set("SIP/1-0000008a", "CDR(cnam)=+18776455156") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:33] Set("SIP/1-0000008a", "CHANNEL(language)=en") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "VMGAIN=g(12)") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]macro-vm:3] Macro("SIP/1-0000008a", "blkvm-check,") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "GOSUB_RETVAL=") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] ExecIf("SIP/1-0000008a", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] MacroExit("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] GotoIf("SIP/1-0000008a", "1?vmx,1") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-vm,vmx,1)
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "MEXTEN=8530") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] Set("SIP/1-0000008a", "MMODE=NOANSWER") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] Set("SIP/1-0000008a", "RETVM=") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] Set("SIP/1-0000008a", "MODE=unavail") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:5] GotoIf("SIP/1-0000008a", "1?chknomsg") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-vm,vmx,7)
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:7] GotoIf("SIP/1-0000008a", "0?s-NOANSWER,1") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:8] GotoIf("SIP/1-0000008a", "1?notdirect") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-vm,vmx,10)
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:10] NoOp("SIP/1-0000008a", "Checking if ext 8530 is enabled: ") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:11] GotoIf("SIP/1-0000008a", "1?s-NOANSWER,1") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-vm,s-NOANSWER,1)
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Macro("SIP/1-0000008a", "get-vmcontext,8530") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Set("SIP/1-0000008a", "VMCONTEXT=default") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] GotoIf("SIP/1-0000008a", "0?200:300") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-get-vmcontext,s,300)
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:300] NoOp("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:2] VoiceMail("SIP/1-0000008a", "[email protected],sug(12)") in new stack
[2015-07-28 12:43:37] VERBOSE[15615][C-00000026] file.c:     -- <SIP/1-0000008a> Playing '/var/spool/asterisk/voicemail/default/8530/unavail.slin' (language 'en')
[2015-07-28 12:43:47] VERBOSE[15615][C-00000026] file.c:     -- <SIP/1-0000008a> Playing 'beep.ulaw' (language 'en')
[2015-07-28 12:43:48] VERBOSE[15615][C-00000026] app_voicemail.c:     -- Recording the message
[2015-07-28 12:43:48] VERBOSE[15615][C-00000026] app.c:     -- x=0, open writing:  /var/spool/asterisk/voicemail/default/8530/tmp/x3057h format: wav, 0x7f958811b238
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] app.c:     -- User hung up
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] config.c:   == Parsing '/var/spool/asterisk/voicemail/default/8530/INBOX/msg0000.txt': Found
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] app_macro.c:   == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'SIP/1-0000008a' in macro 'vm'
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] app_macro.c:   == Spawn extension (macro-exten-vm, s, 21) exited non-zero on 'SIP/1-0000008a' in macro 'exten-vm'
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] pbx.c:   == Spawn extension (ext-local, 8530, 2) exited non-zero on 'SIP/1-0000008a'
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] Macro("SIP/1-0000008a", "hangupcall,") in new stack
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:1] GotoIf("SIP/1-0000008a", "1?theend") in new stack
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:3] ExecIf("SIP/1-0000008a", "0?Set(CDR(recordingfile)=)") in new stack
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] pbx.c:     -- Executing [[email protected]:4] Hangup("SIP/1-0000008a", "") in new stack
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/1-0000008a' in macro 'hangupcall'
[2015-07-28 12:43:58] VERBOSE[15615][C-00000026] pbx.c:   == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/1-0000008a'
[2015-07-28 12:44:08] VERBOSE[3428][C-00000027] netsock2.c:   == Using SIP RTP TOS bits 184
[2015-07-28 12:44:08] VERBOSE[3428][C-00000027] netsock2.c:   == Using SIP RTP CoS mark 5

Extension can be configured to receive Message Waiting Indication for multiple mailboxes like this → I have this extension subscribed to receive MWI from x100, 101, and 102.

Is this setting is only subscribed to one mailbox?

Also, was this working earlier (each extension only MWI for its own mailbox) and then suddenly stopped working? What changed?

Each extension is only subscribed to its own voiceamil box. This is a recent install so I believe it has been since the beginning and they just now brought it to our attention.