[SOLVED] Paging Groups Stopped Working After Update

kgmoney · May 10, 2016, 2:50pm

Hi,

I did an update a couple days ago and now paging has stopped working. If I wireshark the pbx and try to send a page, I see no sip traffic being sent to the extension in the paging group. Here are the updates that were applied:

arimanager 13.0.4 (current: 13.0.3)
backup 13.0.21.9 (current: 13.0.21.8)
blacklist 13.0.10 (current: 13.0.9)
bria 13.0.13 (current: 13.0.12)
cdr 13.0.28 (current: 13.0.27)
certman 13.0.17 (current: 13.0.12)
contactmanager 13.0.28 (current: 13.0.23)
core 13.0.75 (current: 13.0.67)
dahdiconfig 13.0.14 (current: 13.0.13)
dashboard 13.0.22.1 (current: 13.0.21)
daynight 13.0.10 (current: 13.0.9)
endpoint 13.0.50.1 (current: 13.0.39)
extensionroutes 13.0.9.1 (current: 13.0.8)
findmefollow 13.0.29 (current: 13.0.28)
firewall 13.0.27.1 (current: 13.0.21)
framework 13.0.113 (current: 13.0.101)
freepbx_ha 13.0.8.5 (current: 13.0.7.2)
paging 13.0.14.1 (current: 13.0.14)
recording_report 13.0.21 (current: 13.0.20)
restapi 13.0.18.6 (current: 13.0.18.3)
restapps 13.0.27 (current: 13.0.13)
ringgroups 13.0.18 (current: 13.0.17)
sipsettings 13.0.19 (current: 13.0.18)
soundlang 13.0.11 (current: 13.0.10)
superfecta 13.0.3.12 (current: 13.0.3.10)
sysadmin 13.0.56.1 (current: 13.0.49)
timeconditions 13.0.29 (current: 13.0.26)
ucp 13.0.30 (current: 13.0.27)
ucpnode 13.0.27 (current: 13.0.22)
userman 13.0.61 (current: 13.0.55)
voicemail 13.0.43 (current: 13.0.37)
vqplus 13.0.20 (current: 13.0.19)
webrtc 13.0.20 (current: 13.0.16)
xmpp 13.0.9 (current: 13.0.6)`

And here is console output during a page:

  -- Executing [2002@from-internal:1] Goto("PJSIP/150-000000c2", "app-pagegroups,2002,1") in new stack
    -- Goto (app-pagegroups,2002,1)
    -- Executing [2002@app-pagegroups:1] Macro("PJSIP/150-000000c2", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("PJSIP/150-000000c2", "TOUCH_MONITOR=1462891750.280") in new stack
    -- Executing [s@macro-user-callerid:2] Set("PJSIP/150-000000c2", "AMPUSER=150") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("PJSIP/150-000000c2", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("PJSIP/150-000000c2", "1?Set(REALCALLERIDNUM=150)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("PJSIP/150-000000c2", "AMPUSER=150") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("PJSIP/150-000000c2", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("PJSIP/150-000000c2", "AMPUSERCIDNAME=Kevin Garman") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("PJSIP/150-000000c2", "0?report") in new stack
    -- Executing [s@macro-user-callerid:9] Set("PJSIP/150-000000c2", "AMPUSERCID=150") in new stack
    -- Executing [s@macro-user-callerid:10] Set("PJSIP/150-000000c2", "__DIAL_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-user-callerid:11] Set("PJSIP/150-000000c2", "CALLERID(all)="Kevin Garman" <150>") in new stack
    -- Executing [s@macro-user-callerid:12] GotoIf("PJSIP/150-000000c2", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:13] ExecIf("PJSIP/150-000000c2", "0?Set(GROUP(concurrency_limit)=150)") in new stack
    -- Executing [s@macro-user-callerid:14] ExecIf("PJSIP/150-000000c2", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [s@macro-user-callerid:15] GotoIf("PJSIP/150-000000c2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:16] ExecIf("PJSIP/150-000000c2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [s@macro-user-callerid:17] Set("PJSIP/150-000000c2", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:18] GotoIf("PJSIP/150-000000c2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [s@macro-user-callerid:29] Set("PJSIP/150-000000c2", "CALLERID(number)=150") in new stack
    -- Executing [s@macro-user-callerid:30] Set("PJSIP/150-000000c2", "CALLERID(name)=Kevin Garman") in new stack
    -- Executing [s@macro-user-callerid:31] Set("PJSIP/150-000000c2", "CDR(cnum)=150") in new stack
    -- Executing [s@macro-user-callerid:32] Set("PJSIP/150-000000c2", "CDR(cnam)=Kevin Garman") in new stack
    -- Executing [s@macro-user-callerid:33] Set("PJSIP/150-000000c2", "CHANNEL(language)=en") in new stack
    -- Executing [2002@app-pagegroups:2] Set("PJSIP/150-000000c2", "_PAGEGROUP=2002") in new stack
    -- Executing [2002@app-pagegroups:3] GotoIf("PJSIP/150-000000c2", "1?:busy") in new stack
    -- Executing [2002@app-pagegroups:4] Set("PJSIP/150-000000c2", "DEVICE_STATE(Custom:PAGE2002)=INUSE") in new stack
    -- Executing [2002@app-pagegroups:5] Gosub("PJSIP/150-000000c2", "app-paging,ssetup,1()") in new stack
    -- Executing [ssetup@app-paging:1] Set("PJSIP/150-000000c2", "_SIPURI=") in new stack
    -- Executing [ssetup@app-paging:2] Set("PJSIP/150-000000c2", "_ALERTINFO=Ring Answer") in new stack
    -- Executing [ssetup@app-paging:3] Set("PJSIP/150-000000c2", "_CALLINFO=<uri>;answer-after=0") in new stack
    -- Executing [ssetup@app-paging:4] Set("PJSIP/150-000000c2", "_SIPURI=intercom=true") in new stack
    -- Executing [ssetup@app-paging:5] Set("PJSIP/150-000000c2", "_DTIME=5") in new stack
    -- Executing [ssetup@app-paging:6] Set("PJSIP/150-000000c2", "_ANSWERMACRO=") in new stack
    -- Executing [ssetup@app-paging:7] Set("PJSIP/150-000000c2", "PAGE_CONF=1462891751510") in new stack
    -- Executing [ssetup@app-paging:8] Return("PJSIP/150-000000c2", "") in new stack
    -- Executing [2002@app-pagegroups:6] Set("PJSIP/150-000000c2", "PAGEMODE=PAGE") in new stack
    -- Executing [2002@app-pagegroups:7] Set("PJSIP/150-000000c2", "PAGE_MEMBERS=502") in new stack
    -- Executing [2002@app-pagegroups:8] Set("PJSIP/150-000000c2", "PAGE_CONF_OPTS=duplex") in new stack
    -- Executing [2002@app-pagegroups:9] Set("PJSIP/150-000000c2", "ANNOUNCEMENT=beep") in new stack
    -- Executing [2002@app-pagegroups:10] AGI("PJSIP/150-000000c2", "page.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/page.agi
    -- Called s@app-page-stream
    -- Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-00000015;2", "1") in new stack
    -- Called s@app-page-stream
    -- Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-00000016;2", "1") in new stack
    -- <PJSIP/150-000000c2>AGI Script page.agi completed, returning 0
    -- Executing [2002@app-pagegroups:11] Set("PJSIP/150-000000c2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
    -- Executing [2002@app-pagegroups:12] Set("PJSIP/150-000000c2", "CONFBRIDGE(user,admin)=yes") in new stack
    -- Executing [2002@app-pagegroups:13] Set("PJSIP/150-000000c2", "CONFBRIDGE(user,marked)=yes") in new stack
    -- Executing [2002@app-pagegroups:14] Answer("PJSIP/150-000000c2", "") in new stack
    -- Executing [2002@app-pagegroups:15] ConfBridge("PJSIP/150-000000c2", "1462891751510,,,admin_menu") in new stack
    -- Channel PJSIP/150-000000c2 joined 'softmix' base-bridge <720c5011-5ac5-481f-9eab-3a5e47017f2e>
    -- Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-00000015;2", "") in new stack
    -- Local/s@app-page-stream-00000015;1 answered
    -- Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-00000015;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
    -- Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-00000015;2", "CONFBRIDGE(user,marked)=yes") in new stack
    -- Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-00000015;2", "1462891751510,,,") in new stack
    -- Channel Local/s@app-page-stream-00000015;2 joined 'softmix' base-bridge <720c5011-5ac5-481f-9eab-3a5e47017f2e>
    -- Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-00000016;2", "") in new stack
    -- Local/s@app-page-stream-00000016;1 answered
    -- <Local/s@app-page-stream-00000016;1> Playing 'beep.gsm' (language 'en')
    -- Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-00000016;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
    -- Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-00000016;2", "CONFBRIDGE(user,marked)=yes") in new stack
    -- Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-00000016;2", "1462891751510,,,") in new stack
    -- Channel Local/s@app-page-stream-00000016;2 joined 'softmix' base-bridge <720c5011-5ac5-481f-9eab-3a5e47017f2e>
    -- Channel Local/s@app-page-stream-00000016;2 left 'softmix' base-bridge <720c5011-5ac5-481f-9eab-3a5e47017f2e>
[2016-05-10 09:49:16] NOTICE[31412]: res_pjsip_exten_state.c:356 new_subscribe: Extension state subscription failed: Extension 170 does not exist in context 'from-internal' or has no associated hint
    -- Channel PJSIP/150-000000c2 left 'softmix' base-bridge <720c5011-5ac5-481f-9eab-3a5e47017f2e>
    -- Executing [h@app-pagegroups:1] ExecIf("PJSIP/150-000000c2", "1?Set(DEVICE_STATE(Custom:PAGE2002)=NOT_INUSE)") in new stack
    -- Channel Local/s@app-page-stream-00000015;2 left 'softmix' base-bridge <720c5011-5ac5-481f-9eab-3a5e47017f2e>

Anyone else seeing this?

Thanks,
Kevin

lewishipkins · May 12, 2016, 1:08pm

I too am having the same issue with Paging all of a sudden. Version 13.0.115 running on a customers machine. Of course they use the paging feature quit a bit and I cannot find the reason it just stopped. I made sure to switch the paging module to the confbridge. For some reason, the other one was the default. But after restarting, the paging feature still does not work for them.

In the CDR , I see this. I used to see all of the extensions answering the page. I continue to look for answers.

lewishipkins · May 12, 2016, 1:12pm

Result of the page in the CLI.

  -- Executing [500@from-internal:1] Goto("PJSIP/505-00000023", "app-pagegroups,500,1") in new stack
    -- Goto (app-pagegroups,500,1)
    -- Executing [500@app-pagegroups:1] Macro("PJSIP/505-00000023", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("PJSIP/505-00000023", "TOUCH_MONITOR=1463058665.105") in new stack
    -- Executing [s@macro-user-callerid:2] Set("PJSIP/505-00000023", "AMPUSER=505") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("PJSIP/505-00000023", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("PJSIP/505-00000023", "1?Set(REALCALLERIDNUM=505)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("PJSIP/505-00000023", "AMPUSER=505") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("PJSIP/505-00000023", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("PJSIP/505-00000023", "AMPUSERCIDNAME=Lewis Test Softphone") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("PJSIP/505-00000023", "0?report") in new stack
    -- Executing [s@macro-user-callerid:9] Set("PJSIP/505-00000023", "AMPUSERCID=505") in new stack
    -- Executing [s@macro-user-callerid:10] Set("PJSIP/505-00000023", "__DIAL_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-user-callerid:11] Set("PJSIP/505-00000023", "CALLERID(all)="Lewis Test Softphone" <505>") in new stack
    -- Executing [s@macro-user-callerid:12] GotoIf("PJSIP/505-00000023", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:13] ExecIf("PJSIP/505-00000023", "0?Set(GROUP(concurrency_limit)=505)") in new stack
    -- Executing [s@macro-user-callerid:14] ExecIf("PJSIP/505-00000023", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [s@macro-user-callerid:15] GotoIf("PJSIP/505-00000023", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:16] ExecIf("PJSIP/505-00000023", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [s@macro-user-callerid:17] Set("PJSIP/505-00000023", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:18] GotoIf("PJSIP/505-00000023", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [s@macro-user-callerid:29] Set("PJSIP/505-00000023", "CALLERID(number)=505") in new stack
    -- Executing [s@macro-user-callerid:30] Set("PJSIP/505-00000023", "CALLERID(name)=Lewis Test Softphone") in new stack
    -- Executing [s@macro-user-callerid:31] Set("PJSIP/505-00000023", "CDR(cnum)=505") in new stack
    -- Executing [s@macro-user-callerid:32] Set("PJSIP/505-00000023", "CDR(cnam)=Lewis Test Softphone") in new stack
    -- Executing [s@macro-user-callerid:33] Set("PJSIP/505-00000023", "CHANNEL(language)=en") in new stack
    -- Executing [500@app-pagegroups:2] Set("PJSIP/505-00000023", "_PAGEGROUP=500") in new stack
    -- Executing [500@app-pagegroups:3] GotoIf("PJSIP/505-00000023", "1?:busy") in new stack
    -- Executing [500@app-pagegroups:4] Set("PJSIP/505-00000023", "DEVICE_STATE(Custom:PAGE500)=INUSE") in new stack
    -- Executing [500@app-pagegroups:5] Gosub("PJSIP/505-00000023", "app-paging,ssetup,1()") in new stack
    -- Executing [ssetup@app-paging:1] Set("PJSIP/505-00000023", "_SIPURI=") in new stack
    -- Executing [ssetup@app-paging:2] Set("PJSIP/505-00000023", "_ALERTINFO=Ring Answer") in new stack
    -- Executing [ssetup@app-paging:3] Set("PJSIP/505-00000023", "_CALLINFO=<uri>;answer-after=0") in new stack
    -- Executing [ssetup@app-paging:4] Set("PJSIP/505-00000023", "_SIPURI=intercom=true") in new stack
    -- Executing [ssetup@app-paging:5] Set("PJSIP/505-00000023", "_DTIME=5") in new stack
    -- Executing [ssetup@app-paging:6] Set("PJSIP/505-00000023", "_ANSWERMACRO=") in new stack
    -- Executing [ssetup@app-paging:7] Set("PJSIP/505-00000023", "PAGE_CONF=1463058665610") in new stack
    -- Executing [ssetup@app-paging:8] Return("PJSIP/505-00000023", "") in new stack
    -- Executing [500@app-pagegroups:6] Set("PJSIP/505-00000023", "PAGEMODE=PAGE") in new stack
    -- Executing [500@app-pagegroups:7] Set("PJSIP/505-00000023", "PAGE_MEMBERS=110-111-112-113-114-115-116-117-118-119-121-122-124-125-127-128-129-130-131-132") in new stack
    -- Executing [500@app-pagegroups:8] Set("PJSIP/505-00000023", "PAGE_CONF_OPTS=") in new stack
    -- Executing [500@app-pagegroups:9] Set("PJSIP/505-00000023", "ANNOUNCEMENT=beep") in new stack
    -- Executing [500@app-pagegroups:10] AGI("PJSIP/505-00000023", "page.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/page.agi
    -- Called s@app-page-stream
    -- Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-00000019;2", "1") in new stack
    -- Called s@app-page-stream
    -- Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-0000001a;2", "1") in new stack
    -- <PJSIP/505-00000023>AGI Script page.agi completed, returning 0
    -- Executing [500@app-pagegroups:11] Set("PJSIP/505-00000023", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
    -- Executing [500@app-pagegroups:12] Set("PJSIP/505-00000023", "CONFBRIDGE(user,admin)=yes") in new stack
    -- Executing [500@app-pagegroups:13] Set("PJSIP/505-00000023", "CONFBRIDGE(user,marked)=yes") in new stack
    -- Executing [500@app-pagegroups:14] Answer("PJSIP/505-00000023", "") in new stack
    -- Executing [500@app-pagegroups:15] ConfBridge("PJSIP/505-00000023", "1463058665610,,,admin_menu") in new stack
    -- Channel PJSIP/505-00000023 joined 'softmix' base-bridge <ab62f0a8-7790-4078-92c2-981260dbb643>
    -- Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-00000019;2", "") in new stack
    -- Local/s@app-page-stream-00000019;1 answered
       > Launching Wait(5) on Local/s@app-page-stream-00000019;1
    -- Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-0000001a;2", "") in new stack
    -- Local/s@app-page-stream-0000001a;1 answered
       > Launching Playback(beep) on Local/s@app-page-stream-0000001a;1
    -- <Local/s@app-page-stream-0000001a;1> Playing 'beep.gsm' (language 'en')
    -- Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-0000001a;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
    -- Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-0000001a;2", "CONFBRIDGE(user,marked)=yes") in new stack
    -- Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-0000001a;2", "1463058665610,,,") in new stack
    -- Channel Local/s@app-page-stream-0000001a;2 joined 'softmix' base-bridge <ab62f0a8-7790-4078-92c2-981260dbb643>
    -- Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-00000019;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack
    -- Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-00000019;2", "CONFBRIDGE(user,marked)=yes") in new stack
    -- Executing [s@app-page-stream:5] ConfBridge("Local/s@app-page-stream-00000019;2", "1463058665610,,,") in new stack
    -- Channel Local/s@app-page-stream-00000019;2 joined 'softmix' base-bridge <ab62f0a8-7790-4078-92c2-981260dbb643>
    -- Channel Local/s@app-page-stream-0000001a;2 left 'softmix' base-bridge <ab62f0a8-7790-4078-92c2-981260dbb643>
    -- Channel PJSIP/505-00000023 left 'softmix' base-bridge <ab62f0a8-7790-4078-92c2-981260dbb643>
    -- Executing [h@app-pagegroups:1] ExecIf("PJSIP/505-00000023", "1?Set(DEVICE_STATE(Custom:PAGE500)=NOT_INUSE)") in new stack

Checking the paging module, it’s currently at 13.0.14.1 and up to date.

lewishipkins · May 12, 2016, 1:43pm

Is it possible to roll back the module? If so, where to find an older copy of the module to install?

lgaetz · May 12, 2016, 2:07pm

Yes. Module Admin, Check Online, select the module you want to rollback and choose the version you want.

lewishipkins · May 12, 2016, 2:38pm

Thanks. It was staring me in the face. the current version of paging module seemed to have broken it.

kgmoney · May 12, 2016, 2:51pm

Yes, thanks…I concur that rolling back to Paging and Intercom 13.0.14 is working.

tm1000 · May 12, 2016, 3:35pm

Edit: Nevermind. The bug was fixed 10 days ago in Paging 13.0.15

kgmoney · May 12, 2016, 3:47pm

Awesome! Thanks for the info and thanks for your work…I quite enjoy using FreePBX.