After Module update, Paging now rings phones instead of beep/auto answer

ntg · October 9, 2018, 3:50am

Ran ‘fwconsole ma upgradeall’ on 10/6/18.

The morning of 10/8/18, staff initiated a page, the recipients phones rang instead of the beep and auto answer

Paging worked fine prior to the upgrade, no config changes were made to the phones (Cisco SPA 504G and 508G)

When comparing logs with a different, working PBX, I noticed this discrepancy.

PBX with good paging: "0?Set(ALERTINFO=Alert-Info: Auto Answer)") in new stack

PBX with broke paging: "0?Set(ALERTINFO=info=Auto Answer)") in new stack

I’ve tried:

rolling back the Paging and Intercom module, using all 5 previous versions
to rule a phone misconfig I reset one to factory, only configured proxy, extension, and password
FW console restart
PBX reboot

All else on this PBX seems fine.

PBX is in Device/user mode (client required queue agent phone login)

Current PBX Version:14.0.3.20

Current System Version:12.7.5-1807-1.sng7

Any help is greatly appreciated.

[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@from-internal:1] Goto(“PJSIP/2299-0000000d”, “app-pagegroups,2464,1”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx_builtins.c: Goto (app-pagegroups,2464,1)
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:1] Macro(“PJSIP/2299-0000000d”, “user-callerid,”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/2299-0000000d”, “TOUCH_MONITOR=1539059196.116”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/2299-0000000d”, “AMPUSER=2299”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/2299-0000000d”, “0?report”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/2299-0000000d”, “1?Set(REALCALLERIDNUM=2299)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/2299-0000000d”, “AMPUSER=2299”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/2299-0000000d”, “0?limit”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/2299-0000000d”, “AMPUSERCIDNAME=NTG TEST”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/2299-0000000d”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/2299-0000000d”, “0?report”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/2299-0000000d”, “AMPUSERCID=2299”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/2299-0000000d”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/2299-0000000d”, “CALLERID(all)=“NTG TEST” <2299>”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/2299-0000000d”, “0?limit”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/2299-0000000d”, “0?Set(GROUP(concurrency_limit)=2299)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/2299-0000000d”, “0?Set(CHANNEL(language)=)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/2299-0000000d”, “Macro Depth is 1”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/2299-0000000d”, “1?report2:macroerror”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/2299-0000000d”, “0?continue”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“PJSIP/2299-0000000d”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:20] Set(“PJSIP/2299-0000000d”, “__TTL=64”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“PJSIP/2299-0000000d”, “1?continue”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/2299-0000000d”, “CALLERID(number)=2299”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/2299-0000000d”, “CALLERID(name)=NTG TEST”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/2299-0000000d”, “0?cnum”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/2299-0000000d”, “CDR(cnam)=NTG TEST”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/2299-0000000d”, “CDR(cnum)=2299”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/2299-0000000d”, “CHANNEL(language)=en”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:2] Set(“PJSIP/2299-0000000d”, “_PAGEGROUP=2464”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:3] GotoIf(“PJSIP/2299-0000000d”, “1?:busy”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:4] Set(“PJSIP/2299-0000000d”, “DEVICE_STATE(Custom:PAGE2464)=INUSE”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:5] Gosub(“PJSIP/2299-0000000d”, “app-paging,ssetup,1()”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:1] Set(“PJSIP/2299-0000000d”, “_SIPURI=”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:2] Set(“PJSIP/2299-0000000d”, “_ALERTINFO=Ring Answer”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:3] Set(“PJSIP/2299-0000000d”, “_CALLINFO=<uri>;answer-after=0”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:4] Set(“PJSIP/2299-0000000d”, “_SIPURI=intercom=true”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:5] Set(“PJSIP/2299-0000000d”, “_DTIME=5”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:6] Set(“PJSIP/2299-0000000d”, “_ANSWERMACRO=”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:7] Set(“PJSIP/2299-0000000d”, “PAGE_CONF=1539059196544”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [ssetup@app-paging:8] Return(“PJSIP/2299-0000000d”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:6] Set(“PJSIP/2299-0000000d”, “PAGEMODE=PAGE”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:7] Set(“PJSIP/2299-0000000d”, “PAGE_MEMBERS=2299-4000-4001”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:8] Set(“PJSIP/2299-0000000d”, “PAGE_CONF_OPTS=”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:9] Set(“PJSIP/2299-0000000d”, “ANNOUNCEMENT=beep”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:10] AGI(“PJSIP/2299-0000000d”, “page.agi”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/page.agi
[2018-10-08 23:26:36] VERBOSE[7470] dial.c: Called s@app-page-stream
[2018-10-08 23:26:36] VERBOSE[7471][C-0000002f] pbx.c: Executing [s@app-page-stream:1] Wait(“Local/s@app-page-stream-0000002d;2”, “1”) in new stack
[2018-10-08 23:26:36] VERBOSE[7475] dial.c: Called s@app-page-stream
[2018-10-08 23:26:36] VERBOSE[7476][C-00000030] pbx.c: Executing [s@app-page-stream:1] Wait(“Local/s@app-page-stream-0000002f;2”, “1”) in new stack
[2018-10-08 23:26:36] VERBOSE[7473] dial.c: Called PAGE4000@app-paging/n
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [PAGE4000@app-paging:1] Macro(“Local/PAGE4000@app-paging-0000002e;2”, “autoanswer,4000”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE4000@app-paging-0000002e;2”, “1?knowndial”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE4000@app-paging-0000002e;2”, “DIAL=SIP/4000”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:20] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(DIAL=DAHDI/4000)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?macro”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:22] GotoIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?pjsipua”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:23] Set(“Local/PAGE4000@app-paging-0000002e;2”, “USERAGENT=Cisco/SPA525G2-7.5.6”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:24] Goto(“Local/PAGE4000@app-paging-0000002e;2”, “uafin”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx_builtins.c: Goto (macro-autoanswer,s,28)
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(USERAGENT=)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(ALERTINFO=<http://example.com>;info=alert-autoanswer)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(ALERTINFO=info=Auto Answer)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “0?Set(ALERTINFO=<http://www.sangoma.com>;info=external)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [PAGE4000@app-paging:2] NoOp(“Local/PAGE4000@app-paging-0000002e;2”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [PAGE4000@app-paging:3] GotoIf(“Local/PAGE4000@app-paging-0000002e;2”, “1?doptions”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx_builtins.c: Goto (app-paging,PAGE4000,6)
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [PAGE4000@app-paging:6] ExecIf(“Local/PAGE4000@app-paging-0000002e;2”, “1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [PAGE4000@app-paging:7] Dial(“Local/PAGE4000@app-paging-0000002e;2”, “SIP/4000,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] netsock2.c: Using SIP RTP TOS bits 184
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] netsock2.c: Using SIP RTP CoS mark 5
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] app_stack.c: SIP/4000-0000000c Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@autoanswer:1] GosubIf(“SIP/4000-0000000c”, “1?func-set-sipheader,s,1(Alert-Info,Ring Answer)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“SIP/4000-0000000c”, “Sip Add Header function called. Adding Alert-Info = Ring Answer”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-set-sipheader:2] Set(“SIP/4000-0000000c”, “HASH(_SIPHEADERS,Alert-Info)=Ring Answer”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-set-sipheader:3] Return(“SIP/4000-0000000c”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@autoanswer:2] GosubIf(“SIP/4000-0000000c”, “1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] res_agi.c: <PJSIP/2299-0000000d>AGI Script page.agi completed, returning 0
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:11] Set(“PJSIP/2299-0000000d”, “CONFBRIDGE(user,template)=page_user_duplex”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:12] Set(“PJSIP/2299-0000000d”, “CONFBRIDGE(user,admin)=yes”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:13] Set(“PJSIP/2299-0000000d”, “CONFBRIDGE(user,marked)=yes”) in new stack
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:14] Answer(“PJSIP/2299-0000000d”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“SIP/4000-0000000c”, “Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-set-sipheader:2] Set(“SIP/4000-0000000c”, “HASH(_SIPHEADERS,Call-Info)=<uri>;answer-after=0”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-set-sipheader:3] Return(“SIP/4000-0000000c”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@autoanswer:3] Gosub(“SIP/4000-0000000c”, “func-apply-sipheaders,s,1()”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/4000-0000000c”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/4000-0000000c”, “Applying SIP Headers to channel”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/4000-0000000c”, “SIPHEADERKEYS=Call-Info,Alert-Info”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf(“SIP/4000-0000000c”, “0?Set(Rheader=1)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:5] While(“SIP/4000-0000000c”, “1”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“SIP/4000-0000000c”, “sipheader=<uri>;answer-after=0”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“SIP/4000-0000000c”, “0?Set(Addheader=1)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“SIP/4000-0000000c”, “0?SIPAddHeader(Call-Info:<uri>;answer-after=0)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“SIP/4000-0000000c”, “0?Set(PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:10] EndWhile(“SIP/4000-0000000c”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:5] While(“SIP/4000-0000000c”, “1”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“SIP/4000-0000000c”, “sipheader=Ring Answer”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“SIP/4000-0000000c”, “0?Set(Addheader=1)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“SIP/4000-0000000c”, “0?SIPAddHeader(Alert-Info:Ring Answer)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“SIP/4000-0000000c”, “0?Set(PJSIP_HEADER(add,Alert-Info)=Ring Answer)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:10] EndWhile(“SIP/4000-0000000c”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:5] While(“SIP/4000-0000000c”, “0”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“SIP/4000-0000000c”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“SIP/4000-0000000c”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“SIP/4000-0000000c”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] pbx.c: Executing [s@autoanswer:4] Return(“SIP/4000-0000000c”, “”) in new stack
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] app_stack.c: Spawn extension (from-internal, PAGE4000, 1) exited non-zero on ‘SIP/4000-0000000c’
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] app_stack.c: SIP/4000-0000000c Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] app_dial.c: Called SIP/4000
[2018-10-08 23:26:36] VERBOSE[7477][C-00000031] app_dial.c: SIP/4000-0000000c is ringing
[2018-10-08 23:26:36] VERBOSE[7473] dial.c: Local/PAGE4000@app-paging-0000002e;1 is ringing
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] pbx.c: Executing [2464@app-pagegroups:15] ConfBridge(“PJSIP/2299-0000000d”, “1539059196544,admin_menu”) in new stack
[2018-10-08 23:26:36] VERBOSE[7480][C-0000002e] bridge_channel.c: Channel CBAnn/1539059196544-00000030;2 joined ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>
[2018-10-08 23:26:36] VERBOSE[7461][C-0000002e] bridge_channel.c: Channel PJSIP/2299-0000000d joined ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>
[2018-10-08 23:26:37] VERBOSE[7471][C-0000002f] pbx.c: Executing [s@app-page-stream:2] Answer(“Local/s@app-page-stream-0000002d;2”, “”) in new stack
[2018-10-08 23:26:37] VERBOSE[7470] dial.c: Local/s@app-page-stream-0000002d;1 answered
[2018-10-08 23:26:37] VERBOSE[7476][C-00000030] pbx.c: Executing [s@app-page-stream:2] Answer(“Local/s@app-page-stream-0000002f;2”, “”) in new stack
[2018-10-08 23:26:37] VERBOSE[7475] dial.c: Local/s@app-page-stream-0000002f;1 answered
[2018-10-08 23:26:37] VERBOSE[7475] file.c: <Local/s@app-page-stream-0000002f;1> Playing ‘beep.slin16’ (language ‘en’)
[2018-10-08 23:26:37] VERBOSE[7476][C-00000030] pbx.c: Executing [s@app-page-stream:3] Set(“Local/s@app-page-stream-0000002f;2”, “CONFBRIDGE(user,template)=page_user_duplex”) in new stack
[2018-10-08 23:26:37] VERBOSE[7476][C-00000030] pbx.c: Executing [s@app-page-stream:4] Set(“Local/s@app-page-stream-0000002f;2”, “CONFBRIDGE(user,marked)=yes”) in new stack
[2018-10-08 23:26:37] VERBOSE[7476][C-00000030] pbx.c: Executing [s@app-page-stream:5] ConfBridge(“Local/s@app-page-stream-0000002f;2”, “1539059196544,”) in new stack
[2018-10-08 23:26:37] VERBOSE[7476][C-00000030] bridge_channel.c: Channel Local/s@app-page-stream-0000002f;2 joined ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>
[2018-10-08 23:26:37] VERBOSE[7471][C-0000002f] pbx.c: Executing [s@app-page-stream:3] Set(“Local/s@app-page-stream-0000002d;2”, “CONFBRIDGE(user,template)=page_user_duplex”) in new stack
[2018-10-08 23:26:37] VERBOSE[7471][C-0000002f] pbx.c: Executing [s@app-page-stream:4] Set(“Local/s@app-page-stream-0000002d;2”, “CONFBRIDGE(user,marked)=yes”) in new stack
[2018-10-08 23:26:37] VERBOSE[7471][C-0000002f] pbx.c: Executing [s@app-page-stream:5] ConfBridge(“Local/s@app-page-stream-0000002d;2”, “1539059196544,”) in new stack
[2018-10-08 23:26:37] VERBOSE[7471][C-0000002f] bridge_channel.c: Channel Local/s@app-page-stream-0000002d;2 joined ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>
[2018-10-08 23:26:37] VERBOSE[7461][C-0000002e] bridge_channel.c: Channel PJSIP/2299-0000000d left ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>
[2018-10-08 23:26:37] VERBOSE[7461][C-0000002e] pbx.c: Executing [h@app-pagegroups:1] ExecIf(“PJSIP/2299-0000000d”, “1?Set(DEVICE_STATE(Custom:PAGE2464)=NOT_INUSE)”) in new stack
[2018-10-08 23:26:37] VERBOSE[8742] asterisk.c: Remote UNIX connection
[2018-10-08 23:26:37] VERBOSE[7484] asterisk.c: Remote UNIX connection disconnected
[2018-10-08 23:26:37] VERBOSE[7476][C-00000030] bridge_channel.c: Channel Local/s@app-page-stream-0000002f;2 left ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>
[2018-10-08 23:26:41] VERBOSE[7477][C-00000031] app_dial.c: Nobody picked up in 5000 ms
[2018-10-08 23:26:41] VERBOSE[7477][C-00000031] pbx.c: Executing [PAGE4000@app-paging:8] Hangup(“Local/PAGE4000@app-paging-0000002e;2”, “”) in new stack
[2018-10-08 23:26:41] VERBOSE[7477][C-00000031] pbx.c: Spawn extension (app-paging, PAGE4000, 8) exited non-zero on ‘Local/PAGE4000@app-paging-0000002e;2’
[2018-10-08 23:26:42] VERBOSE[7471][C-0000002f] bridge_channel.c: Channel Local/s@app-page-stream-0000002d;2 left ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>
[2018-10-08 23:26:42] VERBOSE[7480][C-0000002e] bridge_channel.c: Channel CBAnn/1539059196544-00000030;2 left ‘softmix’ base-bridge <a62482d0-6965-41fa-9e31-d6a953fb9aab>

cynjut · October 10, 2018, 5:25pm

IIRC, there was a problem with the latest paging module. Downgrade back to your previous version until they get it fixed.

Check back through the forums from a couple of weeks ago and see if those issues look familiar.

system · October 17, 2018, 5:31pm

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