Ring group behaviour change after 16.0/chan_pjsip upgrade

miken32 · January 3, 2023, 7:40pm

We’re in the process of upgrading our PBXs, and have found some behaviour changes in ring groups I’m hoping we can fix somehow.

The old PBX (FreePBX 15, Asterisk 13, chan_sip) had a ring group with 3 extensions and ringall strategy. If one of the extensions was on the phone, calls would still go to it, with the option to answer. Log entries:

[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Caller ID name is 'Main:7058796847' number is '7058796847'
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: CW Ignore is:
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: CF Ignore is:
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Ringgroup confirm is  :
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Methodology of ring is  'ringall'
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Added extension 7010 to extension map
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Added extension 7020 to extension map
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Added extension 7060 to extension map
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Extension 7010 cf is disabled
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Extension 7020 cf is disabled
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Extension 7060 cf is disabled
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Extension 7010 do not disturb is disabled
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Extension 7020 do not disturb is disabled
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Extension 7060 do not disturb is disabled
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: dbset CALLTRACE/7010 to 7058796847
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: dbset CALLTRACE/7020 to 7058796847
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: dbset CALLTRACE/7060 to 7058796847
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: Filtered ARG3: 7010-7020-7060
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: RVOL_MODE ''
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: RVOL is:
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: dialparties.agi: RVOLPARENT is:
[2022-12-27 09:52:13] VERBOSE[32630][C-00000219] res_agi.c: <SIP/primus1-0000077c>AGI Script dialparties.agi completed, returning 0

The new PBX (FreePBX 16, Asterisk 16, chan_pjsip) no longer calls the extension that’s in use, and has a different log output from dialparties, despite the scripts being exactly the same between versions.

[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Starting New Dialparties.agi
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Caller ID name is 'Main' number is '7058408114'
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: CW Ignore is:
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: CF Ignore is:
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: CW IN_USE/BUSY is: 1
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Ringgroup confirm is  :
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Methodology of ring is  'ringall'
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 7010 to extension map
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 7020 to extension map
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 7060 to extension map
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7010 cf is disabled
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7020 cf is disabled
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7060 cf is disabled
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7010 do not disturb is disabled
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7020 do not disturb is disabled
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7060 do not disturb is disabled
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: EXTENSION_STATE: 1 (INUSE)
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7010 has ExtensionState: 1
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Checking CW and CFB status for extension 7010
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7010 is not available to be called
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7010 has call waiting disabled
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7020 has ExtensionState: 0
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Checking CW and CFB status for extension 7020
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/7020
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with real PJSIP Dial string PJSIP/7020/sip:[email protected]:1030;x-ast-orig-host=192.168.0.101:0
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/7020 to 7058408114
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 7060 has ExtensionState: 0
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Checking CW and CFB status for extension 7060
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/7060
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with real PJSIP Dial string PJSIP/7060/sip:[email protected]:1032;x-ast-orig-host=192.168.0.113:0
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/7060 to 7058408114
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: Filtered ARG3: 7020-7060
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL_MODE ''
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL is:
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOLPARENT is:
[2023-01-03 08:44:31] VERBOSE[359294][C-00000041] res_agi.c: <PJSIP/Primus_1-000000f5>AGI Script agi://127.0.0.1/dialparties.agi completed, returning 0

We’d like to go back to the previous behaviour with all available extensions being called, regardless of whether they’re on the phone. Anybody have any thoughts?

lgaetz · January 3, 2023, 7:45pm

Is call waiting enabled for the extension?

miken32 · January 3, 2023, 7:55pm

It was not! Looks like that setting didn’t get migrated by the backup module. Hopefully that’s the easy fix for this.

miken32 · January 3, 2023, 8:26pm

That should probably be filed as a bug; the core module only backs up the AMPUSER tree of the Asterisk database, but the CW tree contains the call waiting state. This appears to be the only place this value is stored; nothing in MySQL.

lgaetz · January 3, 2023, 11:33pm

Please do so at https://issues.freepbx.org/

system · January 10, 2023, 11:33pm

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