Alison announces incoming calls?

I have a question that driving me nuts. Since installing FreePBX 12.0.43 from scratch recently. I have in incoming route that routes to a ring group. That ring group has two extensions one of which is an Android handset (no problem there) the other is a Grandstream GXP2110. My issue is, whenever a call comes in via this route, I hear a quick bust of ringing in the GXP2110 then I hear Alison annonce “You have a call from” and proceeds to announce the CID of the caller. This isn’t what I want, I just want the GXP2110 to ring in the conventional way. I know this must be the result of some setting I have set, but for the life of me I can’t figure out what that was!

If someone know what setting I have mistakenly set that has caused that please put me out of my misery and let me know.

Thanks in advance.

Probably “call screening” on your extension.

I thought of that, but call screening is disabled ?

So, you know the script by now, post a log.

Here you go:

> Connected to Asterisk 11.16.0 currently running on FreePBX (pid = 1760)
>   == Using SIP RTP TOS bits 184
>   == Using SIP RTP CoS mark 5
>     -- Executing [9999999@from-trunk-sip-2mbs:1] Set("SIP/2mbs-000000ea", "GROUP()=OUT_2") in new stack
>     -- Executing [9999999@from-trunk-sip-2mbs:2] Goto("SIP/2mbs-000000ea", "from-trunk,9999999,1") in new stack
>     -- Goto (from-trunk,9999999,1)
>     -- Executing [9999999@from-trunk:1] Set("SIP/2mbs-000000ea", "__FROM_DID=9999999") in new stack
>     -- Executing [9999999@from-trunk:2] Set("SIP/2mbs-000000ea", "CIDSFSCHEME=YmFzZV9EZWZhdWx0") in new stack
>     -- Executing [9999999@from-trunk:3] AGI("SIP/2mbs-000000ea", "/var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
>     -- Launched AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta is Answering the Channel
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Scheme is Default
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The DID passed from Asterisk is: 9999999
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The number passed from Asterisk is: 01707123456
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The CID name passed from Asterisk is: 01707123456
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Executing Scheme..
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: CID Determined to be: 'Home'
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Attempting to set lookupcid
>  /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Success!
>     -- <SIP/2mbs-000000ea>AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
>     -- Executing [9999999@from-trunk:4] Set("SIP/2mbs-000000ea", "CALLERID(name)=Home") in new stack
>     -- Executing [9999999@from-trunk:5] Gosub("SIP/2mbs-000000ea", "sub-record-check,s,1(in,9999999,dontcare)") in new stack
>     -- Executing [s@sub-record-check:1] GotoIf("SIP/2mbs-000000ea", "0?initialized") in new stack
>     -- Executing [s@sub-record-check:2] Set("SIP/2mbs-000000ea", "__REC_STATUS=INITIALIZED") in new stack
>     -- Executing [s@sub-record-check:3] Set("SIP/2mbs-000000ea", "NOW=1426102931") in new stack
>     -- Executing [s@sub-record-check:4] Set("SIP/2mbs-000000ea", "__DAY=11") in new stack
>     -- Executing [s@sub-record-check:5] Set("SIP/2mbs-000000ea", "__MONTH=03") in new stack
>     -- Executing [s@sub-record-check:6] Set("SIP/2mbs-000000ea", "__YEAR=2015") in new stack
>     -- Executing [s@sub-record-check:7] Set("SIP/2mbs-000000ea", "__TIMESTR=20150311-194211") in new stack
>     -- Executing [s@sub-record-check:8] Set("SIP/2mbs-000000ea", "__FROMEXTEN=unknown") in new stack
>     -- Executing [s@sub-record-check:9] Set("SIP/2mbs-000000ea", "__MON_FMT=wav") in new stack
>     -- Executing [s@sub-record-check:10] NoOp("SIP/2mbs-000000ea", "Recordings initialized") in new stack
>     -- Executing [s@sub-record-check:11] ExecIf("SIP/2mbs-000000ea", "0?Set(ARG3=dontcare)") in new stack
>     -- Executing [s@sub-record-check:12] Set("SIP/2mbs-000000ea", "REC_POLICY_MODE_SAVE=") in new stack
>     -- Executing [s@sub-record-check:13] ExecIf("SIP/2mbs-000000ea", "0?Set(REC_STATUS=NO)") in new stack
>     -- Executing [s@sub-record-check:14] GotoIf("SIP/2mbs-000000ea", "2?checkaction") in new stack
>     -- Goto (sub-record-check,s,17)
>     -- Executing [s@sub-record-check:17] GotoIf("SIP/2mbs-000000ea", "1?sub-record-check,in,1") in new stack
>     -- Goto (sub-record-check,in,1)
>     -- Executing [in@sub-record-check:1] NoOp("SIP/2mbs-000000ea", "Inbound Recording Check to 9999999") in new stack
>     -- Executing [in@sub-record-check:2] Set("SIP/2mbs-000000ea", "FROMEXTEN=unknown") in new stack
>     -- Executing [in@sub-record-check:3] ExecIf("SIP/2mbs-000000ea", "11?Set(FROMEXTEN=01707123456)") in new stack
>     -- Executing [in@sub-record-check:4] Gosub("SIP/2mbs-000000ea", "recordcheck,1(dontcare,in,9999999)") in new stack
>     -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/2mbs-000000ea", "Starting recording check against dontcare") in new stack
>     -- Executing [recordcheck@sub-record-check:2] Goto("SIP/2mbs-000000ea", "dontcare") in new stack
>     -- Goto (sub-record-check,recordcheck,3)
>     -- Executing [recordcheck@sub-record-check:3] Return("SIP/2mbs-000000ea", "") in new stack
>     -- Executing [in@sub-record-check:5] Return("SIP/2mbs-000000ea", "") in new stack
>     -- Executing [9999999@from-trunk:6] Gosub("SIP/2mbs-000000ea", "app-blacklist-check,s,1()") in new stack
>     -- Executing [s@app-blacklist-check:1] GotoIf("SIP/2mbs-000000ea", "0?blacklisted") in new stack
>     -- Executing [s@app-blacklist-check:2] Set("SIP/2mbs-000000ea", "CALLED_BLACKLIST=1") in new stack
>     -- Executing [s@app-blacklist-check:3] Return("SIP/2mbs-000000ea", "") in new stack
>     -- Executing [9999999@from-trunk:7] Set("SIP/2mbs-000000ea", "CDR(did)=9999999") in new stack
>     -- Executing [9999999@from-trunk:8] ExecIf("SIP/2mbs-000000ea", "0 ?Set(CALLERID(name)=01707123456)") in new stack
>     -- Executing [9999999@from-trunk:9] Set("SIP/2mbs-000000ea", "CHANNEL(musicclass)=default") in new stack
>     -- Executing [9999999@from-trunk:10] Set("SIP/2mbs-000000ea", "__MOHCLASS=default") in new stack
> [2015-03-11 19:42:11] WARNING[1498][C-000000e0]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
>     -- Executing [9999999@from-trunk:11] GotoIf("SIP/2mbs-000000ea", "0?macro-hangupcall") in new stack
>     -- Executing [9999999@from-trunk:12] Set("SIP/2mbs-000000ea", "__CALLINGPRES_SV=allowed_not_screened") in new stack
>     -- Executing [9999999@from-trunk:13] Set("SIP/2mbs-000000ea", "CALLERPRES()=allowed_not_screened") in new stack
>     -- Executing [9999999@from-trunk:14] Set("SIP/2mbs-000000ea", "__ALERT_INFO=;info=ring3") in new stack
>     -- Executing [9999999@from-trunk:15] Goto("SIP/2mbs-000000ea", "ext-group,601,1") in new stack
>     -- Goto (ext-group,601,1)
>     -- Executing [601@ext-group:1] Macro("SIP/2mbs-000000ea", "user-callerid,") in new stack
>     -- Executing [s@macro-user-callerid:1] Set("SIP/2mbs-000000ea", "TOUCH_MONITOR=1426102921.236") in new stack
>     -- Executing [s@macro-user-callerid:2] Set("SIP/2mbs-000000ea", "AMPUSER=01707123456") in new stack
>     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/2mbs-000000ea", "0?report") in new stack
>     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/2mbs-000000ea", "1?Set(REALCALLERIDNUM=01707123456)") in new stack
>     -- Executing [s@macro-user-callerid:5] Set("SIP/2mbs-000000ea", "AMPUSER=") in new stack
>     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/2mbs-000000ea", "0?limit") in new stack
>     -- Executing [s@macro-user-callerid:7] Set("SIP/2mbs-000000ea", "AMPUSERCIDNAME=") in new stack
>     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/2mbs-000000ea", "1?report") in new stack
>     -- Goto (macro-user-callerid,s,16)
>     -- Executing [s@macro-user-callerid:16] GotoIf("SIP/2mbs-000000ea", "0?continue") in new stack
>     -- Executing [s@macro-user-callerid:17] ExecIf("SIP/2mbs-000000ea", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
>     -- Executing [s@macro-user-callerid:18] Set("SIP/2mbs-000000ea", "__TTL=64") in new stack
>     -- Executing [s@macro-user-callerid:19] GotoIf("SIP/2mbs-000000ea", "1?continue") in new stack
>     -- Goto (macro-user-callerid,s,30)
>     -- Executing [s@macro-user-callerid:30] Set("SIP/2mbs-000000ea", "CALLERID(number)=01707123456") in new stack
>     -- Executing [s@macro-user-callerid:31] Set("SIP/2mbs-000000ea", "CALLERID(name)=Home") in new stack
>     -- Executing [s@macro-user-callerid:32] Set("SIP/2mbs-000000ea", "CDR(cnum)=01707123456") in new stack
>     -- Executing [s@macro-user-callerid:33] Set("SIP/2mbs-000000ea", "CDR(cnam)=Home") in new stack
>     -- Executing [s@macro-user-callerid:34] Set("SIP/2mbs-000000ea", "CHANNEL(language)=en") in new stack
>     -- Executing [601@ext-group:2] Macro("SIP/2mbs-000000ea", "blkvm-setifempty,") in new stack
>     -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/2mbs-000000ea", "1?init") in new stack
>     -- Goto (macro-blkvm-setifempty,s,4)
>     -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/2mbs-000000ea", "__BLKVM_CHANNEL=SIP/2mbs-000000ea") in new stack
>     -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/2mbs-000000ea", "SHARED(BLKVM,SIP/2mbs-000000ea)=TRUE") in new stack
>     -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/2mbs-000000ea", "GOSUB_RETVAL=TRUE") in new stack
>     -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/2mbs-000000ea", "") in new stack
>     -- Executing [601@ext-group:3] GotoIf("SIP/2mbs-000000ea", "1?skipov") in new stack
>     -- Goto (ext-group,601,6)
>     -- Executing [601@ext-group:6] Set("SIP/2mbs-000000ea", "RRNODEST=") in new stack
>     -- Executing [601@ext-group:7] Set("SIP/2mbs-000000ea", "__NODEST=601") in new stack
>     -- Executing [601@ext-group:8] GosubIf("SIP/2mbs-000000ea", "0?sub-rgsetcid,s,1()") in new stack
>     -- Executing [601@ext-group:9] Set("SIP/2mbs-000000ea", "__ALERT_INFO=;info=ring1") in new stack
>     -- Executing [601@ext-group:10] Gosub("SIP/2mbs-000000ea", "sub-record-check,s,1(rg,601,dontcare)") in new stack
>     -- Executing [s@sub-record-check:1] GotoIf("SIP/2mbs-000000ea", "11?initialized") in new stack
>     -- Goto (sub-record-check,s,10)
>     -- Executing [s@sub-record-check:10] NoOp("SIP/2mbs-000000ea", "Recordings initialized") in new stack
>     -- Executing [s@sub-record-check:11] ExecIf("SIP/2mbs-000000ea", "0?Set(ARG3=dontcare)") in new stack
>     -- Executing [s@sub-record-check:12] Set("SIP/2mbs-000000ea", "REC_POLICY_MODE_SAVE=") in new stack
>     -- Executing [s@sub-record-check:13] ExecIf("SIP/2mbs-000000ea", "0?Set(REC_STATUS=NO)") in new stack
>     -- Executing [s@sub-record-check:14] GotoIf("SIP/2mbs-000000ea", "2?checkaction") in new stack
>     -- Goto (sub-record-check,s,17)
>     -- Executing [s@sub-record-check:17] GotoIf("SIP/2mbs-000000ea", "0?sub-record-check,rg,1") in new stack
>     -- Executing [s@sub-record-check:18] NoOp("SIP/2mbs-000000ea", "Generic rg Recording Check - 01707123456 601") in new stack
>     -- Executing [s@sub-record-check:19] Gosub("SIP/2mbs-000000ea", "recordcheck,1(dontcare,rg,601)") in new stack
>     -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/2mbs-000000ea", "Starting recording check against dontcare") in new stack
>     -- Executing [recordcheck@sub-record-check:2] Goto("SIP/2mbs-000000ea", "dontcare") in new stack
>     -- Goto (sub-record-check,recordcheck,3)
>     -- Executing [recordcheck@sub-record-check:3] Return("SIP/2mbs-000000ea", "") in new stack
>     -- Executing [s@sub-record-check:20] Return("SIP/2mbs-000000ea", "") in new stack
>     -- Executing [601@ext-group:11] Set("SIP/2mbs-000000ea", "RingGroupMethod=ringall") in new stack
>     -- Executing [601@ext-group:12] Macro("SIP/2mbs-000000ea", "dial,20,Ttr,100-101") in new stack
>     -- Executing [s@macro-dial:1] GotoIf("SIP/2mbs-000000ea", "0?dial") in new stack
>     -- Executing [s@macro-dial:2] Set("SIP/2mbs-000000ea", "CHANNEL(musicclass)=default") in new stack
>     -- Executing [s@macro-dial:3] AGI("SIP/2mbs-000000ea", "dialparties.agi") in new stack
>     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
>  dialparties.agi: Starting New Dialparties.agi
>  dialparties.agi: Caller ID name is 'Home' number is '01707123456'
>        > dialparties.agi: Setting Alert-Info:  ;info=ring1
>     -- AGI Script Executing Application: (SIPAddHeader) Options: (Alert-Info:;info=ring1)
>        > dialparties.agi: USE_CONFIRMATION:  'FALSE'
>        > dialparties.agi: RINGGROUP_INDEX:   ''
>  dialparties.agi: Methodology of ring is  'ringall'
>     -- dialparties.agi: Added extension 100 to extension map
>     -- dialparties.agi: Added extension 101 to extension map
>     -- dialparties.agi: Extension 100 cf is disabled
>     -- dialparties.agi: Extension 101 cf is disabled
>     -- dialparties.agi: Extension 100 do not disturb is disabled
>     -- dialparties.agi: Extension 101 do not disturb is disabled
>        > dialparties.agi: extnum 100 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
>     -- dialparties.agi: dbset CALLTRACE/100 to 01707123456
>        > dialparties.agi: extnum 101 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
>     -- dialparties.agi: dbset CALLTRACE/101 to 01707123456
>     -- dialparties.agi: Filtered ARG3: 100-101
>        > dialparties.agi: NODEST: 601 adding M(auto-blkvm) to dialopts: TtrM(auto-blkvm)
>        > dialparties.agi: NODEST: 601 blkvm enabled macro already in dialopts: TtrM(auto-blkvm)
>     -- <SIP/2mbs-000000ea>AGI Script dialparties.agi completed, returning 0
>     -- Executing [s@macro-dial:7] Dial("SIP/2mbs-000000ea", "SIP/100&SIP/101,20,TtrM(auto-blkvm)") in new stack
>   == Using SIP RTP TOS bits 184
>   == Using SIP RTP CoS mark 5
>   == Using SIP RTP TOS bits 184
>   == Using SIP RTP CoS mark 5
>   == Extension Changed 100[ext-local] new state Ringing for Notify User 102 
>     -- Called SIP/100
>   == Extension Changed 101[ext-local] new state Ringing for Notify User 100 
>     -- Called SIP/101
>     -- SIP/101-000000ec connected line has changed. Saving it until answer for SIP/2mbs-000000ea
>     -- SIP/100-000000eb connected line has changed. Saving it until answer for SIP/2mbs-000000ea
>   == Extension Changed 101[ext-local] new state Ringing for Notify User 100 (queued)
>     -- SIP/101-000000ec is ringing
>     -- SIP/101-000000ec is ringing
>     -- SIP/100-000000eb is ringing
>   == Extension Changed 100[ext-local] new state Ringing for Notify User 102 
>   == Extension Changed 100[ext-local] new state Idle for Notify User 102 
>   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/2mbs-000000ea' in macro 'dial'
>   == Spawn extension (ext-group, 601, 12) exited non-zero on 'SIP/2mbs-000000ea'
>     -- Executing [h@ext-group:1] Macro("SIP/2mbs-000000ea", "hangupcall,") in new stack
>   == Extension Changed 101[ext-local] new state Idle for Notify User 100 
>     -- Executing [s@macro-hangupcall:1] ExecIf("SIP/2mbs-000000ea", "0?Set(CDR(recordingfile)=.wav)") in new stack
>     -- Executing [s@macro-hangupcall:2] GotoIf("SIP/2mbs-000000ea", "1?theend") in new stack
>     -- Goto (macro-hangupcall,s,4)
>     -- Executing [s@macro-hangupcall:4] Hangup("SIP/2mbs-000000ea", "") in new stack
>   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/2mbs-000000ea' in macro 'hangupcall'
>   == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/2mbs-000000ea'

Nothing there to show that asterisk had anything to do with it.

Hence my question, does anyone have any idea what settings would cause this behaviour?

You misunderstand Dicko’s response. From your log we don’t see a playback or a background. Basically your log says nothing is being played back. So there are zero settings to adjust because according to your log everything is correct.

I’d agree with you except that the voice I hear is Alison’s and theres only one place that can come from and thats from Asterisk/FreePBX so I’m not really sure where to go from here?

Go into the ring group and remove the “#” after your GXP2110’s extension number. Hit submit. Apply changes.

Hi Andrew, thanks for this. I had just fixed this problem before seeing your response. I have Alert=;info=ring1 set in the Ring Group. This seems to have caused this behaviour in the Grandstream GXP2110. Removing the Alert setting or setting to ;info=ring2 or ;info=ring3 fixes this. Not sure why Alert=;info=ring1 makes the phone do this or what the phone sends back to the pbx to trigger this behaviour but at least now I know how to fix it. Sorry to have wasted your time here, and I hope this post may benefit anyone else who may come across this issue.

So apparently there is not “only one place” that Alison’s voice can come from :wink:

1 Like

Absolutely, I didnt realise that until now :blush: