Paging without the BEEP

I am trying to get paging working without the BEEP. I’m pretty sure there is a bug somewhere forcing the beep.

Some background. Paging and Intercom version 13.0.26.2 installed. Phones are Grandstream GXP2140’s.

Under Paging and Intercom > Settings, I have Auto-answer defaults set to NONE. If I leave this as default, when I do an intercom call (*80EXT) there is a BEEP. But setting it to none eliminates the beep in an intercom call.

On the Page Group config, toggling Announcement to None and then calling the paging extension still causes a beep.

I have done a packet capture to see what was being sent to the phone to see if maybe whatever is being sent is causing the phone to beep, but the packet is the same whether it is a intercom call or a paging call:
INVITE sip:[email protected]:5060;intercom=true SIP/2.0

I searched around and found this post: (community . freepbx . org/t/hack-to-disable-intercom-beep/3475/11) and even tried it. I edited the functions.inc.php and Paging.class.php files and removed all references of BEEP and rebooted.

The beep remains. I can’t find anywhere else in settings that could be causing this blasted beep.

Get a call trace to see if the beep is Asterisk dialplan or the phone itself:
https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs

Hope I did this right. I’m a bit crosseyed trying to dissect this though.


Intercom Call (no beep)

root@PIAF:~ $ tail -f /var/log/asterisk/full
[2018-01-07 16:19:38] VERBOSE[1893][C-00000011] netsock2.c: Using SIP RTP TOS bits 184
[2018-01-07 16:19:38] VERBOSE[1893][C-00000011] netsock2.c: Using SIP RTP CoS mark 5
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@from-internal:1] Goto("SIP/702-00000011", "ext-intercom,*80733,1") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (ext-intercom,*80733,1)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:1] Macro("SIP/702-00000011", "user-callerid,") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/702-00000011", "TOUCH_MONITOR=1515359978.41") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/702-00000011", "AMPUSER=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/702-00000011", "0?report") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/702-00000011", "1?Set(REALCALLERIDNUM=702)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/702-00000011", "AMPUSER=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/702-00000011", "0?limit") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/702-00000011", "AMPUSERCIDNAME=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/702-00000011", "0?report") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/702-00000011", "AMPUSERCID=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/702-00000011", "__DIAL_OPTIONS=Ttr") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/702-00000011", "CALLERID(all)="702" <702>") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/702-00000011", "0?limit") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/702-00000011", "0?Set(GROUP(concurrency_limit)=702)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/702-00000011", "0?continue") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/702-00000011", "__TTL=6") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/702-00000011", "1?continue") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (macro-user-callerid,s,27)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:27] Set("SIP/702-00000011", "CALLERID(number)=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:28] Set("SIP/702-00000011", "CALLERID(name)=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/702-00000011", "0?cnum") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/702-00000011", "CDR(cnam)=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/702-00000011", "CDR(cnum)=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/702-00000011", "CHANNEL(language)=en") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:2] Set("SIP/702-00000011", "dialnumber=733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:3] Set("SIP/702-00000011", "INTERCOM_CALL=TRUE") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:4] Gosub("SIP/702-00000011", "sub-record-check,s,1(exten,733)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/702-00000011", "0?initialized") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:2] Set("SIP/702-00000011", "__REC_STATUS=INITIALIZED") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:3] Set("SIP/702-00000011", "NOW=1515359978") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:4] Set("SIP/702-00000011", "__DAY=07") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:5] Set("SIP/702-00000011", "__MONTH=01") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:6] Set("SIP/702-00000011", "__YEAR=2018") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:7] Set("SIP/702-00000011", "__TIMESTR=20180107-161938") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:8] Set("SIP/702-00000011", "__FROMEXTEN=702") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:9] Set("SIP/702-00000011", "__MON_FMT=wav") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/702-00000011", "Recordings initialized") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/702-00000011", "1?Set(ARG3=dontcare)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:12] Set("SIP/702-00000011", "REC_POLICY_MODE_SAVE=") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/702-00000011", "0?Set(REC_STATUS=NO)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/702-00000011", "5?checkaction") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/702-00000011", "1?sub-record-check,exten,1") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/702-00000011", "Exten Recording Check between 702 and 733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/702-00000011", "CALLTYPE=internal") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/702-00000011", "0?Set(CALLTYPE=)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/702-00000011", "CALLEE=dontcare") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/702-00000011", "0?Set(CALLEE=dontcare)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/702-00000011", "0?callee") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:7] GotoIf("SIP/702-00000011", "1?caller") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:13] Set("SIP/702-00000011", "RECMODE=dontcare") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:14] ExecIf("SIP/702-00000011", "0?Set(RECMODE=dontcare)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:15] ExecIf("SIP/702-00000011", "1?Set(RECMODE=dontcare)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:16] Gosub("SIP/702-00000011", "recordcheck,1(dontcare,internal,733)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/702-00000011", "Starting recording check against dontcare") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/702-00000011", "dontcare") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/702-00000011", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [exten@sub-record-check:17] Return("SIP/702-00000011", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:5] GotoIf("SIP/702-00000011", "0?end") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:6] GotoIf("SIP/702-00000011", "0?end") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:7] GotoIf("SIP/702-00000011", "0?allow") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:8] GotoIf("SIP/702-00000011", "0?nointercom") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:9] GotoIf("SIP/702-00000011", "0?nointercom") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:10] Set("SIP/702-00000011", "DEVICES=733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:11] GotoIf("SIP/702-00000011", "0?end") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:12] Set("SIP/702-00000011", "OVERRIDE=") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:13] Set("SIP/702-00000011", "LOOPCNT=1") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:14] Set("SIP/702-00000011", "_SIPURI=") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:15] Set("SIP/702-00000011", "_ALERTINFO=Ring Answer") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:16] Set("SIP/702-00000011", "_CALLINFO=<uri>;answer-after=0") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:17] Set("SIP/702-00000011", "_SIPURI=intercom=true") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:18] Set("SIP/702-00000011", "_DTIME=5") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:19] Set("SIP/702-00000011", "_ANSWERMACRO=") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:20] GotoIf("SIP/702-00000011", "0?pagemode") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:21] Macro("SIP/702-00000011", "autoanswer,733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("SIP/702-00000011", "0?knowndial") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:2] Set("SIP/702-00000011", "DEVICE=SIP/733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:3] GotoIf("SIP/702-00000011", "0?dopjsip") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:4] Set("SIP/702-00000011", "KNOWNDIAL=SIP/733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:5] Goto("SIP/702-00000011", "knowndial") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:19] Set("SIP/702-00000011", "DIAL=SIP/733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("SIP/702-00000011", "0?Set(DIAL=DAHDI/733)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("SIP/702-00000011", "0?macro") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("SIP/702-00000011", "0?pjsipua") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:23] Set("SIP/702-00000011", "USERAGENT=Grandstream GXP2140 1.0.9.26") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:24] Goto("SIP/702-00000011", "uafin") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (macro-autoanswer,s,28)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("SIP/702-00000011", "0?Set(USERAGENT=)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("SIP/702-00000011", "0?Set(PAGE_VOL=;volume=)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("SIP/702-00000011", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("SIP/702-00000011", "0?Set(ALERTINFO=Intercom)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("SIP/702-00000011", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("SIP/702-00000011", "0?Set(ALERTINFO=ring-answer)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:34] ExecIf("SIP/702-00000011", "0?Set(ALERTINFO=<http://www.sangoma.com>;info=external)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:35] ExecIf("SIP/702-00000011", "0?Set(ALERTINFO=<http://example.com>;info=alert-autoanswer)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@macro-autoanswer:36] ExecIf("SIP/702-00000011", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:22] Set("SIP/702-00000011", "_DOPTIONS=A()b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:23] ChanIsAvail("SIP/702-00000011", "SIP/733,s") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] netsock2.c: Using SIP RTP TOS bits 184
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] netsock2.c: Using SIP RTP CoS mark 5
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:24] ExecIf("SIP/702-00000011", "0?Set(AVAILORIGCHAN=SIP/733)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:25] GotoIf("SIP/702-00000011", "1?continue") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx_builtins.c: Goto (ext-intercom,*80733,30)
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:30] NoOp("SIP/702-00000011", "Continuing with page") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:31] GotoIf("SIP/702-00000011", "0?godial") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:32] Set("SIP/702-00000011", "CONNECTEDLINE(name,i)=733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:33] Set("SIP/702-00000011", "CONNECTEDLINE(num)=733") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [*80733@ext-intercom:34] Dial("SIP/702-00000011", "SIP/733,5,IA()b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] netsock2.c: Using SIP RTP TOS bits 184
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] netsock2.c: Using SIP RTP CoS mark 5
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] app_stack.c: SIP/733-00000013 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@autoanswer:1] GosubIf("SIP/733-00000013", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/733-00000013", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/733-00000013", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/733-00000013", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@autoanswer:2] GosubIf("SIP/733-00000013", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/733-00000013", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/733-00000013", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/733-00000013", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@autoanswer:3] Gosub("SIP/733-00000013", "func-apply-sipheaders,s,1()") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/733-00000013", "Applying SIP Headers to channel") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/733-00000013", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/733-00000013", "1") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/733-00000013", "sipheader=<uri>;answer-after=0") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/733-00000013", "Call-Info: <uri>;answer-after=0") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/733-00000013", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2018-01-07 16:19:38] ERROR[14802][C-00000011] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/733-00000013", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/733-00000013", "1") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/733-00000013", "sipheader=Ring Answer") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/733-00000013", "Alert-Info: Ring Answer") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/733-00000013", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2018-01-07 16:19:38] ERROR[14802][C-00000011] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/733-00000013", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/733-00000013", "0") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/733-00000013", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] pbx.c: Executing [s@autoanswer:4] Return("SIP/733-00000013", "") in new stack
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] app_stack.c: Spawn extension (from-internal, *80733, 1) exited non-zero on 'SIP/733-00000013'
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] app_stack.c: SIP/733-00000013 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] app_dial.c: Called SIP/733
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] app_dial.c: Connected line update to SIP/702-00000011 prevented.
[2018-01-07 16:19:38] VERBOSE[14802][C-00000011] app_dial.c: SIP/733-00000013 is ringing
[2018-01-07 16:19:39] VERBOSE[14802][C-00000011] app_dial.c: Connected line update to SIP/702-00000011 prevented.
[2018-01-07 16:19:39] VERBOSE[14802][C-00000011] app_dial.c: SIP/733-00000013 answered SIP/702-00000011
[2018-01-07 16:19:39] VERBOSE[14805][C-00000011] bridge_channel.c: Channel SIP/733-00000013 joined 'simple_bridge' basic-bridge <723f3211-990a-4be2-a52e-2f264c062ff2>
[2018-01-07 16:19:39] VERBOSE[14802][C-00000011] bridge_channel.c: Channel SIP/702-00000011 joined 'simple_bridge' basic-bridge <723f3211-990a-4be2-a52e-2f264c062ff2>
[2018-01-07 16:19:43] VERBOSE[14802][C-00000011] bridge_channel.c: Channel SIP/702-00000011 left 'simple_bridge' basic-bridge <723f3211-990a-4be2-a52e-2f264c062ff2>
[2018-01-07 16:19:43] VERBOSE[14802][C-00000011] pbx.c: Spawn extension (ext-intercom, *80733, 34) exited non-zero on 'SIP/702-00000011'
[2018-01-07 16:19:43] VERBOSE[14805][C-00000011] bridge_channel.c: Channel SIP/733-00000013 left 'simple_bridge' basic-bridge <723f3211-990a-4be2-a52e-2f264c062ff2>

Paging call (BEEP)

[2018-01-07 16:21:55] VERBOSE[1893][C-00000012] netsock2.c: Using SIP RTP TOS bits 184
[2018-01-07 16:21:55] VERBOSE[1893][C-00000012] netsock2.c: Using SIP RTP CoS mark 5
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@from-internal:1] Goto("SIP/702-00000014", "app-pagegroups,777,1") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx_builtins.c: Goto (app-pagegroups,777,1)
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:1] Macro("SIP/702-00000014", "user-callerid,") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/702-00000014", "TOUCH_MONITOR=1515360115.44") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/702-00000014", "AMPUSER=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/702-00000014", "0?report") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/702-00000014", "1?Set(REALCALLERIDNUM=702)") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/702-00000014", "AMPUSER=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/702-00000014", "0?limit") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/702-00000014", "AMPUSERCIDNAME=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/702-00000014", "0?report") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/702-00000014", "AMPUSERCID=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/702-00000014", "__DIAL_OPTIONS=Ttr") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/702-00000014", "CALLERID(all)="702" <702>") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/702-00000014", "0?limit") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/702-00000014", "0?Set(GROUP(concurrency_limit)=702)") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/702-00000014", "0?continue") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/702-00000014", "__TTL=6") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/702-00000014", "1?continue") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx_builtins.c: Goto (macro-user-callerid,s,27)
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:27] Set("SIP/702-00000014", "CALLERID(number)=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:28] Set("SIP/702-00000014", "CALLERID(name)=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/702-00000014", "0?cnum") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/702-00000014", "CDR(cnam)=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/702-00000014", "CDR(cnum)=702") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/702-00000014", "CHANNEL(language)=en") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:2] Set("SIP/702-00000014", "_PAGEGROUP=777") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:3] GotoIf("SIP/702-00000014", "1?:busy") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:4] Set("SIP/702-00000014", "DEVICE_STATE(Custom:PAGE777)=INUSE") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:5] Gosub("SIP/702-00000014", "app-paging,ssetup,1()") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:1] Set("SIP/702-00000014", "_SIPURI=") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:2] Set("SIP/702-00000014", "_ALERTINFO=Ring Answer") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:3] Set("SIP/702-00000014", "_CALLINFO=<uri>;answer-after=0") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:4] Set("SIP/702-00000014", "_SIPURI=intercom=true") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:5] Set("SIP/702-00000014", "_DTIME=5") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:6] Set("SIP/702-00000014", "_ANSWERMACRO=") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:7] Set("SIP/702-00000014", "PAGE_CONF=1515360115163") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [ssetup@app-paging:8] Return("SIP/702-00000014", "") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:6] Set("SIP/702-00000014", "PAGEMODE=PAGE") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:7] Set("SIP/702-00000014", "PAGE_MEMBERS=702-733") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:8] Set("SIP/702-00000014", "PAGE_CONF_OPTS=duplex") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:9] Set("SIP/702-00000014", "ANNOUNCEMENT=") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:10] AGI("SIP/702-00000014", "page.agi") in new stack
[2018-01-07 16:21:55] VERBOSE[15026][C-00000012] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/page.agi
[2018-01-07 16:21:56] VERBOSE[15036] dial.c: Called s@app-page-stream
[2018-01-07 16:21:56] VERBOSE[15037] dial.c: Called PAGE733@app-paging/n
[2018-01-07 16:21:56] VERBOSE[15038] dial.c: Called s@app-page-stream
[2018-01-07 16:21:56] VERBOSE[15039][C-00000013] pbx.c: Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-0000000c;2", "1") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [PAGE733@app-paging:1] Macro("Local/PAGE733@app-paging-0000000d;2", "autoanswer,733") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE733@app-paging-0000000d;2", "1?knowndial") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE733@app-paging-0000000d;2", "DIAL=SIP/733") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:20] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(DIAL=DAHDI/733)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE733@app-paging-0000000d;2", "0?macro") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:22] GotoIf("Local/PAGE733@app-paging-0000000d;2", "0?pjsipua") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:23] Set("Local/PAGE733@app-paging-0000000d;2", "USERAGENT=Grandstream GXP2140 1.0.9.26") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:24] Goto("Local/PAGE733@app-paging-0000000d;2", "uafin") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx_builtins.c: Goto (macro-autoanswer,s,28)
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(USERAGENT=)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(PAGE_VOL=;volume=)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(ALERTINFO=Intercom)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(ALERTINFO=ring-answer)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:34] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(ALERTINFO=<http://www.sangoma.com>;info=external)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:35] ExecIf("Local/PAGE733@app-paging-0000000d;2", "0?Set(ALERTINFO=<http://example.com>;info=alert-autoanswer)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@macro-autoanswer:36] ExecIf("Local/PAGE733@app-paging-0000000d;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [PAGE733@app-paging:2] NoOp("Local/PAGE733@app-paging-0000000d;2", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [PAGE733@app-paging:3] GotoIf("Local/PAGE733@app-paging-0000000d;2", "1?doptions") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx_builtins.c: Goto (app-paging,PAGE733,6)
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [PAGE733@app-paging:6] ExecIf("Local/PAGE733@app-paging-0000000d;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [PAGE733@app-paging:7] Dial("Local/PAGE733@app-paging-0000000d;2", "SIP/733,5,A()b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
[2018-01-07 16:21:56] VERBOSE[15041][C-00000015] pbx.c: Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-0000000e;2", "1") in new stack
[2018-01-07 16:21:56] VERBOSE[15026][C-00000012] res_agi.c: <SIP/702-00000014>AGI Script page.agi completed, returning 0
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] netsock2.c: Using SIP RTP TOS bits 184
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] netsock2.c: Using SIP RTP CoS mark 5
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] app_stack.c: SIP/733-00000015 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@autoanswer:1] GosubIf("SIP/733-00000015", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/733-00000015", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/733-00000015", "HASH(_SIPHEADERS,Alert-Info)=Ring Answer") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/733-00000015", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@autoanswer:2] GosubIf("SIP/733-00000015", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-set-sipheader:1] NoOp("SIP/733-00000015", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-set-sipheader:2] Set("SIP/733-00000015", "HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-set-sipheader:3] Return("SIP/733-00000015", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@autoanswer:3] Gosub("SIP/733-00000015", "func-apply-sipheaders,s,1()") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/733-00000015", "Applying SIP Headers to channel") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/733-00000015", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/733-00000015", "1") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/733-00000015", "sipheader=<uri>;answer-after=0") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/733-00000015", "Call-Info: <uri>;answer-after=0") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/733-00000015", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2018-01-07 16:21:56] ERROR[15040][C-00000014] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/733-00000015", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/733-00000015", "1") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/733-00000015", "sipheader=Ring Answer") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/733-00000015", "Alert-Info: Ring Answer") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/733-00000015", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2018-01-07 16:21:56] ERROR[15040][C-00000014] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:7] EndWhile("SIP/733-00000015", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/733-00000015", "0") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/733-00000015", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] pbx.c: Executing [s@autoanswer:4] Return("SIP/733-00000015", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] app_stack.c: Spawn extension (from-internal, PAGE733, 1) exited non-zero on 'SIP/733-00000015'
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] app_stack.c: SIP/733-00000015 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] app_dial.c: Called SIP/733
[2018-01-07 16:21:56] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:11] Set("SIP/702-00000014", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
[2018-01-07 16:21:56] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:12] Set("SIP/702-00000014", "CONFBRIDGE(user,admin)=yes") in new stack
[2018-01-07 16:21:56] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:13] Set("SIP/702-00000014", "CONFBRIDGE(user,marked)=yes") in new stack
[2018-01-07 16:21:56] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:14] Answer("SIP/702-00000014", "") in new stack
[2018-01-07 16:21:56] VERBOSE[15040][C-00000014] app_dial.c: SIP/733-00000015 is ringing
[2018-01-07 16:21:56] VERBOSE[15037] dial.c: Local/PAGE733@app-paging-0000000d;1 is ringing
[2018-01-07 16:21:56] VERBOSE[15026][C-00000012] pbx.c: Executing [777@app-pagegroups:15] ConfBridge("SIP/702-00000014", "1515360115163,,,admin_menu") in new stack
[2018-01-07 16:21:56] VERBOSE[15026][C-00000012] bridge_channel.c: Channel SIP/702-00000014 joined 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:21:56] VERBOSE[15045] bridge_channel.c: Channel CBAnn/1515360115163-0000000f;2 joined 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:21:57] VERBOSE[15040][C-00000014] app_dial.c: SIP/733-00000015 answered Local/PAGE733@app-paging-0000000d;2
[2018-01-07 16:21:57] VERBOSE[15037] dial.c: Local/PAGE733@app-paging-0000000d;1 answered
[2018-01-07 16:21:57] VERBOSE[15037] bridge_channel.c: Channel Local/PAGE733@app-paging-0000000d;1 joined 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:21:57] VERBOSE[15046][C-00000014] bridge_channel.c: Channel SIP/733-00000015 joined 'simple_bridge' basic-bridge <4d8a7acb-9b9f-4044-beb1-a2fef778d4c4>
[2018-01-07 16:21:57] VERBOSE[15040][C-00000014] bridge_channel.c: Channel Local/PAGE733@app-paging-0000000d;2 joined 'simple_bridge' basic-bridge <4d8a7acb-9b9f-4044-beb1-a2fef778d4c4>
[2018-01-07 16:21:57] VERBOSE[15039][C-00000013] pbx.c: Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-0000000c;2", "") in new stack
[2018-01-07 16:21:57] VERBOSE[15036] dial.c: Local/s@app-page-stream-0000000c;1 answered
[2018-01-07 16:21:57] VERBOSE[15039][C-00000013] pbx.c: Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-0000000c;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
[2018-01-07 16:21:57] VERBOSE[15039][C-00000013] pbx.c: Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-0000000c;2", "CONFBRIDGE(user,marked)=yes") in new stack
[2018-01-07 16:21:57] VERBOSE[15039][C-00000013] pbx.c: Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-0000000c;2", "1515360115163,,,") in new stack
[2018-01-07 16:21:57] VERBOSE[15039][C-00000013] bridge_channel.c: Channel Local/s@app-page-stream-0000000c;2 joined 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:21:57] VERBOSE[15041][C-00000015] pbx.c: Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-0000000e;2", "") in new stack
[2018-01-07 16:21:57] VERBOSE[15038] dial.c: Local/s@app-page-stream-0000000e;1 answered
[2018-01-07 16:21:57] VERBOSE[15038] file.c: <Local/s@app-page-stream-0000000e;1> Playing 'beep.ulaw' (language 'en')
[2018-01-07 16:21:57] VERBOSE[15041][C-00000015] pbx.c: Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-0000000e;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
[2018-01-07 16:21:57] VERBOSE[15041][C-00000015] pbx.c: Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-0000000e;2", "CONFBRIDGE(user,marked)=yes") in new stack
[2018-01-07 16:21:57] VERBOSE[15041][C-00000015] pbx.c: Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-0000000e;2", "1515360115163,,,") in new stack
[2018-01-07 16:21:57] VERBOSE[15041][C-00000015] bridge_channel.c: Channel Local/s@app-page-stream-0000000e;2 joined 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:21:57] VERBOSE[15041][C-00000015] bridge_channel.c: Channel Local/s@app-page-stream-0000000e;2 left 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:22:02] VERBOSE[15039][C-00000013] bridge_channel.c: Channel Local/s@app-page-stream-0000000c;2 left 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:22:06] VERBOSE[15026][C-00000012] bridge_channel.c: Channel SIP/702-00000014 left 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:22:06] VERBOSE[15026][C-00000012] pbx.c: Executing [h@app-pagegroups:1] ExecIf("SIP/702-00000014", "1?Set(DEVICE_STATE(Custom:PAGE777)=NOT_INUSE)") in new stack
[2018-01-07 16:22:06] VERBOSE[15037] bridge_channel.c: Channel Local/PAGE733@app-paging-0000000d;1 left 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:22:06] VERBOSE[15045] bridge_channel.c: Channel CBAnn/1515360115163-0000000f;2 left 'softmix' base-bridge <dbd6f11a-a5c5-4967-ac45-b1ff5f53e37e>
[2018-01-07 16:22:06] VERBOSE[15040][C-00000014] bridge_channel.c: Channel Local/PAGE733@app-paging-0000000d;2 left 'simple_bridge' basic-bridge <4d8a7acb-9b9f-4044-beb1-a2fef778d4c4>
[2018-01-07 16:22:06] VERBOSE[15040][C-00000014] pbx.c: Spawn extension (app-paging, PAGE733, 7) exited non-zero on 'Local/PAGE733@app-paging-0000000d;2'
[2018-01-07 16:22:06] VERBOSE[15046][C-00000014] bridge_channel.c: Channel SIP/733-00000015 left 'simple_bridge' basic-bridge <4d8a7acb-9b9f-4044-beb1-a2fef778d4c4>

This line is the only “beep” I see, but I don’t understand how/why and what next to check.

`[2018-01-07 16:21:57] VERBOSE[15038] file.c: <Local/s@app-page-stream-0000000e;1> Playing '**beep**.ulaw' (language 'en')`

Giving this a bump please.

If I’m understanding the output correctly, the BEEP is being generated by asterisk and not the phone as indicated in the line above. It’s being generated even though I have the announcement sent to none in both places in the P&I module.

Is this a bug? Are there further traces I can do to determine exactly what is causing this beep (other than the P&I module)?

Thanks!

Hi, Did you ever find a solution to this. I’ve having the same exact issue. My call trace is the same as yours.

Are you using the beep for anything else? In the short term you could upload a new beep.ulaw file to replace the current one. The new file would just be silent.

The beep is added on purpose.

There appear to be two ways to solve the problem:

  1. Remove the ‘Play a beep’ step from the context app-page-stream.
  2. Remove the “beep.ulaw” file from the sound library.
1 Like

As a simple test, you could just RENAME beep.ulaw to something else (beep.ulaw.bak) and see if that is where the beep is coming from.

1 Like

I did get this resolved, sorry I never reposted. I opened a ticket because this thread didn’t have any responses. That ticket is here:
https://issues.freepbx.org/browse/FREEPBX-16790

In short, it was fixed in paging modules
paging v13.0.26.5
paging v14.0.2

It should be fixed in the subsequent releases, I don’t see where it reverted, but I’m still on v13.0.26.5 so can’t verify 100%. Hope this helps.

Thank you for responding. It does seem like the bug was re-introduced in paging v14.0.4. I downgraded to paging v13.0.26.5 and the beeping issue went away.

Do you still have your call trace when you were on v14.0.4? If so please open a BUG ticket here https://issues.freepbx.org/ so that they can fix the 14 branch. You can reference my ticket so they can hopefully and easily put the fix back in. Otherwise the bug will persist.

Thanks!

Hi Bigjess007,

I reopened the bug report that pronounced the bug fixed in 14.0.4.

https://issues.freepbx.org/browse/FREEPBX-17320

Other than the 4 responses that you got? O_o

This topic was automatically closed 365 days after the last reply. New replies are no longer allowed.