Alert not playing proper ringtone on Digium Phones

FreePBX 14

Wanted to have a certain ringtone play when a specific caller ID called in.

Created an alert in the Digium Phones module

Created a custom ringtone and assigned it to the alert and also phone in the Digium Phones module

Created an inbound route for the DID with CID specified, set the alert and directed calls to a specific extension. Phone on the extension is a Digium D60 firmware 2.7.2

As a test I set the custom ringtone as the default ringtone for the phone then initiated a test call. The ringtone worked.

Removed the default ringtone and reconfigured the phone.

Dialed in to see if the alert would play the specified ringtone. It did not. The alert information is in the asterisk logs but no matter what I try I can’t get the ringtone to play from the alert.

Is this a known bug, is this functionality not possible with FreePBX and Digium phones or have a mis-configured something?

Test call logs showing the alert (note I redacted the last for digits of all phone numbers):

[2019-02-26 13:57:21] VERBOSE[10077][C-000001ef] netsock2.c: Using SIP RTP TOS bits 184
[2019-02-26 13:57:21] VERBOSE[10077][C-000001ef] netsock2.c: Using SIP RTP CoS mark 5
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk-sip-VI_70:1] Set("SIP/VI_70-00000297", "GROUP()=OUT_4") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk-sip-VI_70:2] Goto("SIP/VI_70-00000297", "from-trunk,973453XXXX,1") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx_builtins.c: Goto (from-trunk,973453XXXX,1)
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:1] Set("SIP/VI_70-00000297", "__DIRECTION=INBOUND") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:2] Gosub("SIP/VI_70-00000297", "sub-record-check,s,1(in,973453XXXX,dontcare)") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/VI_70-00000297", "0?initialized") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:2] Set("SIP/VI_70-00000297", "__REC_STATUS=INITIALIZED") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:3] Set("SIP/VI_70-00000297", "NOW=1551207441") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:4] Set("SIP/VI_70-00000297", "__DAY=26") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:5] Set("SIP/VI_70-00000297", "__MONTH=02") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:6] Set("SIP/VI_70-00000297", "__YEAR=2019") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:7] Set("SIP/VI_70-00000297", "__TIMESTR=20190226-135721") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:8] Set("SIP/VI_70-00000297", "__FROMEXTEN=unknown") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:9] Set("SIP/VI_70-00000297", "__MON_FMT=wav") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/VI_70-00000297", "Recordings initialized") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/VI_70-00000297", "0?Set(ARG3=dontcare)") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:12] Set("SIP/VI_70-00000297", "REC_POLICY_MODE_SAVE=") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/VI_70-00000297", "0?Set(REC_STATUS=NO)") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/VI_70-00000297", "2?checkaction") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/VI_70-00000297", "1?sub-record-check,in,1") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx_builtins.c: Goto (sub-record-check,in,1)
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/VI_70-00000297", "Inbound Recording Check to 973453XXXX") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [in@sub-record-check:2] Set("SIP/VI_70-00000297", "FROMEXTEN=unknown") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/VI_70-00000297", "11?Set(FROMEXTEN=1973886XXXX)") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/VI_70-00000297", "recordcheck,1(dontcare,in,973453XXXX)") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/VI_70-00000297", "Starting recording check against dontcare") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/VI_70-00000297", "dontcare") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/VI_70-00000297", "") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [in@sub-record-check:5] Return("SIP/VI_70-00000297", "") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:3] Set("SIP/VI_70-00000297", "CHANNEL(tonezone)=us") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:4] Set("SIP/VI_70-00000297", "__FROM_DID=973453XXXX") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:5] Set("SIP/VI_70-00000297", "returnhere=1") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:6] Gosub("SIP/VI_70-00000297", "app-blacklist-check,s,1()") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/VI_70-00000297", "0?blacklisted") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/VI_70-00000297", "CALLED_BLACKLIST=1") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/VI_70-00000297", "") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:7] Set("SIP/VI_70-00000297", "CDR(did)=973453XXXX") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:8] GotoIf("SIP/VI_70-00000297", "0?") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:9] ExecIf("SIP/VI_70-00000297", "1 ?Set(CALLERID(name)=1973886XXXX)") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:10] Set("SIP/VI_70-00000297", "__MOHCLASS=") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:11] Set("SIP/VI_70-00000297", "__REVERSAL_REJECT=FALSE") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:12] GotoIf("SIP/VI_70-00000297", "1?post-reverse-charge") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx_builtins.c: Goto (from-trunk,973453XXXX,14)
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:14] NoOp("SIP/VI_70-00000297", "") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:15] Set("SIP/VI_70-00000297", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:16] Set("SIP/VI_70-00000297", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:17] Set("SIP/VI_70-00000297", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:18] Set("SIP/VI_70-00000297", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:19] Set("SIP/VI_70-00000297", "__ALERT_INFO=Batman") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:20] Set("SIP/VI_70-00000297", "__RVOL=14") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:21] NoOp("SIP/VI_70-00000297", "CallerID Entry Point") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [973453XXXX@from-trunk:22] Goto("SIP/VI_70-00000297", "from-did-direct,207,1") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx_builtins.c: Goto (from-did-direct,207,1)
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx.c: Executing [207@from-did-direct:1] GotoIf("SIP/VI_70-00000297", "1?ext-local,207,1:followme-check,207,1") in new stack
[2019-02-26 13:57:21] VERBOSE[25715][C-000001ef] pbx_builtins.c: Goto (ext-local,207,1)

2 posts were merged into an existing topic: Paging and intercomm Broken since module updates