Intercom beep not playing for caller on certain extensions

chrisduncansb · December 20, 2024, 9:37pm

This is a tricky one. When intercomming certain extensions, the extension initiating the intercom does not hear a beep when the call is established. A single ring is heard instead.

I examined the logs for each call and they diverge at this point:

Intercom with ring instead of beep:
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:20] GotoIf(“PJSIP/28-00031eef”, “0?pagemode”) in new stack

Intercom with beep:
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:20] GotoIf(“PJSIP/27-00031db9”, “1?pagemode”) in new stack

As you can see, the pagemode values are different for extension 29 and 27.

I exported the extension list for the PBX and cross referenced the values for the extension set that results in a ring and the set that results in a beep. The culprit setting appears to be “send_connected_line”. It looks like when intercomming extensions that have this set to yes a beep is heard. Several extension have no value for this setting. The setting appears to have been removed from the extension’s advanced tab so I can’t test this directly. I couldn’t find this setting in the configuration files or the db to try and manually change it.

This is where I’m stuck.

Here’s a larger log excerpt for each call:

correct behavior (beep plays for both parties):
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:20] GotoIf(“PJSIP/27-00031db9”, “1?pagemode”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx_builtins.c: Goto (ext-intercom,*8022,38)
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:38] Set(“PJSIP/27-00031db9”, “ITER=1”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:39] Set(“PJSIP/27-00031db9”, “DIALSTR=”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:40] ChanIsAvail(“PJSIP/27-00031db9”, “PJSIP/22,s”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:41] GotoIf(“PJSIP/27-00031db9”, “0?skip”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:42] Set(“PJSIP/27-00031db9”, “DIALSTR=-22”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:43] Set(“PJSIP/27-00031db9”, “ITER=2”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:44] GotoIf(“PJSIP/27-00031db9”, “1?begin”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx_builtins.c: Goto (ext-intercom,*8022,40)
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:40] ChanIsAvail(“PJSIP/27-00031db9”, “PJSIP/9822,s”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:41] GotoIf(“PJSIP/27-00031db9”, “1?skip”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx_builtins.c: Goto (ext-intercom,*8022,43)
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:43] Set(“PJSIP/27-00031db9”, “ITER=3”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:44] GotoIf(“PJSIP/27-00031db9”, “0?begin”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:45] Set(“PJSIP/27-00031db9”, “DIALSTR=22”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:46] GotoIf(“PJSIP/27-00031db9”, “0?checkpageoption”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:47] Set(“PJSIP/27-00031db9”, “_AMPUSER=27”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [*8022@ext-intercom:48] Gosub(“PJSIP/27-00031db9”, “page,1(22)”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:1] Set(“PJSIP/27-00031db9”, “PAGE_CONF=1734719432672”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:2] Set(“PJSIP/27-00031db9”, “PAGEMODE=PAGE”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:3] Set(“PJSIP/27-00031db9”, “PAGE_MEMBERS=22”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:4] Set(“PJSIP/27-00031db9”, “PAGE_CONF_OPTS=duplex”) in new stack
[2024-12-20 13:30:32] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:5] AGI(“PJSIP/27-00031db9”, “agi://127.0.0.1/page.agi”) in new stack
[2024-12-20 13:30:33] VERBOSE[11281] dial.c: Called s@app-page-stream
[2024-12-20 13:30:33] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:1] Wait(“Local/s@app-page-stream-0000c4f8;2”, “1”) in new stack
[2024-12-20 13:30:33] VERBOSE[11285] dial.c: Called PAGE22@app-paging/n
[2024-12-20 13:30:33] VERBOSE[11286] dial.c: Called s@app-page-stream
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [PAGE22@app-paging:1] Macro(“Local/PAGE22@app-paging-0000c4f9;2”, “autoanswer,22”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE22@app-paging-0000c4f9;2”, “1?knowndial”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE22@app-paging-0000c4f9;2”, “DIAL=PJSIP/22/sip:[email protected]:1028;x-ast-orig-host=192.168.1.36:5060”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:20] GotoIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?macro”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE22@app-paging-0000c4f9;2”, “1?pjsipua”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx_builtins.c: Goto (macro-autoanswer,s,24)
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:24] Set(“Local/PAGE22@app-paging-0000c4f9;2”, “AOR=22”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:25] Set(“Local/PAGE22@app-paging-0000c4f9;2”, “CONTACT=22;@4a47d457d2a307f43cd982ec3fa21136”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:26] Set(“Local/PAGE22@app-paging-0000c4f9;2”, “USERAGENT=Yealink SIP-T46S 66.84.0.125”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:27] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “1?Set(USERAGENT=Yealink SIP-T46S 66.84.0.125)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(CALLINFO=sip:broadworks.net;answer-after=0)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=http://example.com;info=alert-autoanswer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=Alert-Info: Auto Answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=Direct-Intercom)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:37] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:38] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “0?Set(ALERTINFO=Direct-Intercom)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@macro-autoanswer:39] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [PAGE22@app-paging:2] NoOp(“Local/PAGE22@app-paging-0000c4f9;2”, “”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [PAGE22@app-paging:3] GotoIf(“Local/PAGE22@app-paging-0000c4f9;2”, “1?doptions”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx_builtins.c: Goto (app-paging,PAGE22,6)
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [PAGE22@app-paging:6] ExecIf(“Local/PAGE22@app-paging-0000c4f9;2”, “1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,;answer-after=0)))”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [PAGE22@app-paging:7] Dial(“Local/PAGE22@app-paging-0000c4f9;2”, “PJSIP/22/sip:[email protected]:1028;x-ast-orig-host=192.168.1.36:5060,5,A()b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2024-12-20 13:30:33] VERBOSE[11272][C-00012271] res_agi.c: <PJSIP/27-00031db9>AGI Script agi://127.0.0.1/page.agi completed, returning 0
[2024-12-20 13:30:33] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:6] Set(“PJSIP/27-00031db9”, “CONFBRIDGE(user,template)=page_user_duplex”) in new stack
[2024-12-20 13:30:33] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:7] Set(“PJSIP/27-00031db9”, “CONFBRIDGE(user,admin)=yes”) in new stack
[2024-12-20 13:30:33] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:8] Set(“PJSIP/27-00031db9”, “CONFBRIDGE(user,marked)=yes”) in new stack
[2024-12-20 13:30:33] VERBOSE[11272][C-00012271] pbx.c: Executing [page@ext-intercom:9] ConfBridge(“PJSIP/27-00031db9”, “1734719432672,admin_menu”) in new stack
[2024-12-20 13:30:33] VERBOSE[11288][C-00012274] pbx.c: Executing [s@app-page-stream:1] Wait(“Local/s@app-page-stream-0000c4fa;2”, “1”) in new stack
[2024-12-20 13:30:33] VERBOSE[11291][C-00012271] bridge_channel.c: Channel CBAnn/1734719432672-0000c4fb;2 joined ‘softmix’ base-bridge <7b36a96b-b62b-492b-a430-983ed17f3a4a>
[2024-12-20 13:30:33] VERBOSE[11272][C-00012271] bridge_channel.c: Channel PJSIP/27-00031db9 joined ‘softmix’ base-bridge <7b36a96b-b62b-492b-a430-983ed17f3a4a>
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] app_stack.c: PJSIP/22-00031dbb Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) start
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@autoanswer:1] GosubIf(“PJSIP/22-00031dbb”, “1?func-set-sipheader,s,1(Alert-Info,Ring Answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/22-00031dbb”, “Sip Add Header function called. Adding Alert-Info = Ring Answer”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/22-00031dbb”, “HASH(__SIPHEADERS,Alert-Info)=Ring Answer”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/22-00031dbb”, “”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@autoanswer:2] GosubIf(“PJSIP/22-00031dbb”, “1?func-set-sipheader,s,1(Call-Info,;answer-after=0)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/22-00031dbb”, “Sip Add Header function called. Adding Call-Info = ;answer-after=0”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/22-00031dbb”, “HASH(__SIPHEADERS,Call-Info)=;answer-after=0”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/22-00031dbb”, “”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@autoanswer:3] Gosub(“PJSIP/22-00031dbb”, “func-apply-sipheaders,s,1()”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/22-00031dbb”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/22-00031dbb”, “Applying SIP Headers to channel PJSIP/22-00031dbb”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/22-00031dbb”, “TECH=PJSIP”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/22-00031dbb”, “SIPHEADERKEYS=Call-Info,Alert-Info”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/22-00031dbb”, “1”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/22-00031dbb”, “sipheader=;answer-after=0”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/22-00031dbb”, “0?SIPRemoveHeader(Call-Info:)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/22-00031dbb”, “0?Set(PJSIP_HEADER(remove,Call-Info)=)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/22-00031dbb”, “0?Set(sipheader=http://127.0.0.1;info=;answer-after=0)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/22-00031dbb”, “0?Set(sipheader=http://127.0.0.1;answer-after=0)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/22-00031dbb”, “0?SIPAddHeader(Call-Info:;answer-after=0)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“PJSIP/22-00031dbb”, “1?Set(PJSIP_HEADER(add,Call-Info)=;answer-after=0)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile(“PJSIP/22-00031dbb”, “”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/22-00031dbb”, “1”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/22-00031dbb”, “sipheader=Ring Answer”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/22-00031dbb”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/22-00031dbb”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/22-00031dbb”, “1?Set(sipheader=http://127.0.0.1;info=Ring Answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/22-00031dbb”, “0?Set(sipheader=http://127.0.0.1 http://127.0.0.1;info=Ring Answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/22-00031dbb”, “0?SIPAddHeader(Alert-Info:http://127.0.0.1;info=Ring Answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“PJSIP/22-00031dbb”, “1?Set(PJSIP_HEADER(add,Alert-Info)=http://127.0.0.1;info=Ring Answer)”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile(“PJSIP/22-00031dbb”, “”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/22-00031dbb”, “0”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@func-apply-sipheaders:14] Return(“PJSIP/22-00031dbb”, “”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] pbx.c: Executing [s@autoanswer:4] Return(“PJSIP/22-00031dbb”, “”) in new stack
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] app_stack.c: Spawn extension (from-internal, PAGE22, 1) exited non-zero on ‘PJSIP/22-00031dbb’
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] app_stack.c: PJSIP/22-00031dbb Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) complete GOSUB_RETVAL=
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] app_dial.c: Called PJSIP/22/sip:[email protected]:1028;x-ast-orig-host=192.168.1.36:5060
[2024-12-20 13:30:33] VERBOSE[3543] netsock2.c: Using SIP RTP Audio TOS bits 184
[2024-12-20 13:30:33] VERBOSE[3543] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2024-12-20 13:30:33] VERBOSE[3543] netsock2.c: Using SIP RTP Audio CoS mark 5
[2024-12-20 13:30:33] VERBOSE[11287][C-00012273] app_dial.c: PJSIP/22-00031dbb is ringing
[2024-12-20 13:30:33] VERBOSE[11285] dial.c: Local/PAGE22@app-paging-0000c4f9;1 is ringing
[2024-12-20 13:30:34] VERBOSE[11287][C-00012273] app_dial.c: PJSIP/22-00031dbb answered Local/PAGE22@app-paging-0000c4f9;2
[2024-12-20 13:30:34] VERBOSE[11285] dial.c: Local/PAGE22@app-paging-0000c4f9;1 answered
[2024-12-20 13:30:34] VERBOSE[11285] bridge_channel.c: Channel Local/PAGE22@app-paging-0000c4f9;1 joined ‘softmix’ base-bridge <7b36a96b-b62b-492b-a430-983ed17f3a4a>
[2024-12-20 13:30:34] VERBOSE[11292][C-00012273] bridge_channel.c: Channel PJSIP/22-00031dbb joined ‘simple_bridge’ basic-bridge <84f4b336-5bb8-4bd1-a7c5-937b04d621b5>
[2024-12-20 13:30:34] VERBOSE[11287][C-00012273] bridge_channel.c: Channel Local/PAGE22@app-paging-0000c4f9;2 joined ‘simple_bridge’ basic-bridge <84f4b336-5bb8-4bd1-a7c5-937b04d621b5>
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:2] Answer(“Local/s@app-page-stream-0000c4f8;2”, “”) in new stack
[2024-12-20 13:30:34] VERBOSE[11281] dial.c: Local/s@app-page-stream-0000c4f8;1 answered
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:3] Set(“Local/s@app-page-stream-0000c4f8;2”, “CHANNEL(language)=en”) in new stack
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:4] Set(“Local/s@app-page-stream-0000c4f8;2”, “CONFBRIDGE(user,template)=page_user_duplex”) in new stack
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:5] Set(“Local/s@app-page-stream-0000c4f8;2”, “CONFBRIDGE(user,marked)=yes”) in new stack
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:6] ExecIf(“Local/s@app-page-stream-0000c4f8;2”, “0?Set(CONFBRIDGE(user,startmuted)=yes)”) in new stack
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:7] ExecIf(“Local/s@app-page-stream-0000c4f8;2”, “0?Set(CONFBRIDGE(user,marked)=yes)”) in new stack
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] pbx.c: Executing [s@app-page-stream:8] ConfBridge(“Local/s@app-page-stream-0000c4f8;2”, “1734719432672,”) in new stack
[2024-12-20 13:30:34] VERBOSE[11282][C-00012272] bridge_channel.c: Channel Local/s@app-page-stream-0000c4f8;2 joined ‘softmix’ base-bridge <7b36a96b-b62b-492b-a430-983ed17f3a4a>
[2024-12-20 13:30:34] VERBOSE[11288][C-00012274] pbx.c: Executing [s@app-page-stream:2] Answer(“Local/s@app-page-stream-0000c4fa;2”, “”) in new stack
[2024-12-20 13:30:34] VERBOSE[11286] dial.c: Local/s@app-page-stream-0000c4fa;1 answered
[2024-12-20 13:30:34] VERBOSE[11286] file.c: <Local/s@app-page-stream-0000c4fa;1> Playing ‘beep.slin16’ (language ‘en’)

incorrect behavior (beep only played to callee):
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:20] GotoIf(“PJSIP/28-00031eef”, “0?pagemode”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:21] Macro(“PJSIP/28-00031eef”, “autoanswer,29”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“PJSIP/28-00031eef”, “0?knowndial”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:2] Set(“PJSIP/28-00031eef”, “DEVICE=PJSIP/29”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:3] GotoIf(“PJSIP/28-00031eef”, “1?dopjsip”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx_builtins.c: Goto (macro-autoanswer,s,6)
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:6] Set(“PJSIP/28-00031eef”, “KNOWNDIAL=PJSIP/29/sip:[email protected]:5060;x-ast-orig-host=192.168.254.138:5060”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:7] GotoIf(“PJSIP/28-00031eef”, “1?knowndial”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:19] Set(“PJSIP/28-00031eef”, “DIAL=PJSIP/29/sip:[email protected]:5060;x-ast-orig-host=192.168.254.138:5060”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:20] GotoIf(“PJSIP/28-00031eef”, “0?macro”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“PJSIP/28-00031eef”, “1?pjsipua”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx_builtins.c: Goto (macro-autoanswer,s,24)
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:24] Set(“PJSIP/28-00031eef”, “AOR=29”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:25] Set(“PJSIP/28-00031eef”, “CONTACT=29;@07ee6ec9735d83cd5a812c94e7f40413”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:26] Set(“PJSIP/28-00031eef”, “USERAGENT=Yealink SIP-T46S 66.86.0.160”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:27] ExecIf(“PJSIP/28-00031eef”, “0?Set(USERAGENT=)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“PJSIP/28-00031eef”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“PJSIP/28-00031eef”, “0?Set(CALLINFO=sip:broadworks.net;answer-after=0)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=http://example.com;info=alert-autoanswer)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=Alert-Info: Auto Answer)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=Direct-Intercom)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:37] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:38] ExecIf(“PJSIP/28-00031eef”, “0?Set(ALERTINFO=Direct-Intercom)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@macro-autoanswer:39] ExecIf(“PJSIP/28-00031eef”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:22] Set(“PJSIP/28-00031eef”, “_DOPTIONS=A(custom/beep)b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:23] ChanIsAvail(“PJSIP/28-00031eef”, “PJSIP/29,s”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:24] ExecIf(“PJSIP/28-00031eef”, “0?Set(AVAILORIGCHAN=PJSIP/29)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:25] GotoIf(“PJSIP/28-00031eef”, “1?continue”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx_builtins.c: Goto (ext-intercom,*8029,30)
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:30] NoOp(“PJSIP/28-00031eef”, “Continuing with page”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:31] GotoIf(“PJSIP/28-00031eef”, “0?godial”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:32] Set(“PJSIP/28-00031eef”, “CONNECTEDLINE(name,i)=Norstone - Test”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:33] Set(“PJSIP/28-00031eef”, “CONNECTEDLINE(num)=29”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [*8029@ext-intercom:34] Dial(“PJSIP/28-00031eef”, “PJSIP/29/sip:[email protected]:5060;x-ast-orig-host=192.168.254.138:5060,5,IA(custom/beep)b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] app_stack.c: PJSIP/29-00031ef1 Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) start
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@autoanswer:1] GosubIf(“PJSIP/29-00031ef1”, “1?func-set-sipheader,s,1(Alert-Info,Ring Answer)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/29-00031ef1”, “Sip Add Header function called. Adding Alert-Info = Ring Answer”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/29-00031ef1”, “HASH(__SIPHEADERS,Alert-Info)=Ring Answer”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/29-00031ef1”, “”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@autoanswer:2] GosubIf(“PJSIP/29-00031ef1”, “1?func-set-sipheader,s,1(Call-Info,;answer-after=0)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/29-00031ef1”, “Sip Add Header function called. Adding Call-Info = ;answer-after=0”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/29-00031ef1”, “HASH(__SIPHEADERS,Call-Info)=;answer-after=0”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/29-00031ef1”, “”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@autoanswer:3] Gosub(“PJSIP/29-00031ef1”, “func-apply-sipheaders,s,1()”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/29-00031ef1”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/29-00031ef1”, “Applying SIP Headers to channel PJSIP/29-00031ef1”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/29-00031ef1”, “TECH=PJSIP”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/29-00031ef1”, “SIPHEADERKEYS=Call-Info,Alert-Info”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/29-00031ef1”, “1”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/29-00031ef1”, “sipheader=;answer-after=0”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/29-00031ef1”, “0?SIPRemoveHeader(Call-Info:)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/29-00031ef1”, “0?Set(PJSIP_HEADER(remove,Call-Info)=)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/29-00031ef1”, “0?Set(sipheader=http://127.0.0.1;info=;answer-after=0)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/29-00031ef1”, “0?Set(sipheader=http://127.0.0.1;answer-after=0)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/29-00031ef1”, “0?SIPAddHeader(Call-Info:;answer-after=0)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“PJSIP/29-00031ef1”, “1?Set(PJSIP_HEADER(add,Call-Info)=;answer-after=0)”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile(“PJSIP/29-00031ef1”, “”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/29-00031ef1”, “1”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/29-00031ef1”, “sipheader=Ring Answer”) in new stack
[2024-12-20 14:40:08] VERBOSE[28869][C-00012342] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/29-00031ef1”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack

franckdanard · December 21, 2024, 6:34am

There is a difference of models with one which doesn’t work and another one which works?

kenn10 · December 21, 2024, 6:34pm

There are two different ways to have an intercom beep. One is provided when the setting is turned on in FreePBX. The phones will auto-answer and play the system beep. The other is for the phone itself to make a beep when the intercom call comes in. Check to see which way you are expecting a beep to be delivered. If the phone is providing the beep then the ones that don’t beep need that feature activated. If the system is providing the beep and the phones don’t beep, they are auto-answering too slowly to get the beep.

Now if you’re saying that if you dial an extension using intercom, you don’t hear a beep, that is normal. The system does not provide to the initiator a beep under any circumstance. The initiator may have heard the beep from the dialed extension if it is in duplex mode.

Also, check the advanced settings in the FreePBX GUI for the extension in question:

BlazeStudios · December 22, 2024, 4:26am

We need to see this output but for extension 22. Also, does extension 29 have anything like WebRTC (UCP phone) or Sangoma Connect attached to it? The pagemode difference is due to one extension having a single endpoint associated with it but extension 29 seems to have multiple endpoints.

There are various reasons as to why the beep isn’t being set (or not heard) but we need to see what happens during the call.

chrisduncansb · December 22, 2024, 4:49pm

No difference. I confirmed the issue is independent of the phone make/model.

chrisduncansb · December 22, 2024, 4:59pm

After retesting I think you are correct, the initiator hears the beep from the dialed extension. According to the logs, the beep that is played when both parties hear it is:

[2024-12-20 13:30:34] VERBOSE[11286] file.c: <Local/s@app-page-stream-0000c4fa;1> Playing ‘beep.slin16’ (language ‘en’)

Again, you are correct - the phone is auto-answering too slowly to get the beep. I just confirmed this with a test.

I checked the those settings on each extension and they are both the same as what is in your screenshot. So the difference between these 2 extensions is somewhere else.

chrisduncansb · December 22, 2024, 5:11pm

I noticed that as well, one of the common factors with extensions that hear a beep on both phones were also extensions that had Sangoma Connect enabled. I did this for ext 29 to test but got the same no beep result. However, now that I’m reinspecting the logs it appears ext 29 did not have 2 endpoints associated with it even though Sangoma Connect is enabled. It appears I need to register Sangoma app at least once for the endpoint 9829 to be created. I did so, retested and I heard the beep on both sides.

Thank you for pointing me in the right direction!

BlazeStudios · December 22, 2024, 5:16pm

There should still be a beep even with a single endpoint associated. My request to see the logs between the two is because a single endpoint is handled a bit differently than multiple endpoints. There could have been something that was not triggered right.

system · December 29, 2024, 5:17pm

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