Issue Tranferring Calls to Ring Group

scampbell_adnet · August 5, 2020, 5:32pm

FreePBX 15.0.16.72, Ring Groups 5.0.11.8, Sangoma S505 2.0.4.77

I have client having an intermittent issue transferring calls to another ring group.

Here is the scenario,

Caller reaches IVR, selects option 3 and reaches ring group 733. (ext 206, 209, 226)
Agent picks up, realizes caller needs to be transferred to another department ring group 712 (ext 211, 212, 213, 229).
Agent enters ‘transfer’ 712 ‘transfer’ for blind transfer. Logs show the transfer being made to ring group 712, but the extensions included are from ring group 733.

This issue was just reported last week. All available system and module updates have been applied, system as been rebooted since the issue was noticed.

Any advice would be appreciated.

comtech · August 5, 2020, 11:09pm

https://wiki.freepbx.org/display/SUP/Providing+Great+Debug

scampbell_adnet · August 14, 2020, 8:35pm

Thank you. As previously mentioned, this should drop into a queue after trying this ring group, but it seems to be failing and going back to the extensions in ring group 733. I think it’s happening in here, but I’m a novice at reading these logs:

[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [712@from-internal-xfer:21] Macro(“SIP/VI-In2-00000d38”, “dial,20,HhTtrQ(NO_ANSWER),211-212-213-229”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:1] NoOp(“SIP/VI-In2-00000d38”, "Blind Transfer: PJSIP/226-00001440, Attended Transfer: , User: , Alert Info: ") in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:2] Set(“SIP/VI-In2-00000d38”, “__CRM_SOURCE=1NXXXXXXXXX”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:3] ExecIf(“SIP/VI-In2-00000d38”, “0?Set(ALERT_INFO=)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:4] ExecIf(“SIP/VI-In2-00000d38”, “1?Set(ALERT_INFO=)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:5] ExecIf(“SIP/VI-In2-00000d38”, “0?Set(ALERT_INFO=)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:6] ExecIf(“SIP/VI-In2-00000d38”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:7] AGI(“SIP/VI-In2-00000d38”, “dialparties.agi”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Caller ID name is ‘ADNET TECH’ number is ‘NXXXXXXXXX’
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: CW Ignore is: TRUE
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: CF Ignore is: TRUE
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Added extension 211 to extension map
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Added extension 212 to extension map
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Added extension 213 to extension map
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Added extension 229 to extension map
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 211 cf is disabled
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 212 cf is disabled
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 213 cf is disabled
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 229 cf is disabled
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 211 has do not disturb enabled, or followme pre-ring returned busy
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 212 do not disturb is disabled
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 213 has do not disturb enabled, or followme pre-ring returned busy
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 229 has do not disturb enabled, or followme pre-ring returned busy
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: EXTENSION_STATE: 1 (INUSE)
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 212 has ExtensionState: 1
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 212
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 212 is not available to be called
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 212 has call waiting disabled
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Filtered ARG3:
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: RVOL_MODE ‘’
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: RVOL is:
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: RVOLPARENT is:
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: <SIP/VI-In2-00000d38>AGI Script dialparties.agi completed, returning 0
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:8] GotoIf(“SIP/VI-In2-00000d38”, “1?normdial”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx_builtins.c: Goto (macro-dial,s,11)
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:11] NoOp(“SIP/VI-In2-00000d38”, “Returned from dialparties with groups to dial”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:12] NoOp(“SIP/VI-In2-00000d38”, "ringall array ") in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:13] Set(“SIP/VI-In2-00000d38”, “__FMGL_DIAL=”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:14] Set(“SIP/VI-In2-00000d38”, “LOOPCNT=0”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:15] Set(“SIP/VI-In2-00000d38”, “ITER=1”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:16] Set(“SIP/VI-In2-00000d38”, “__EXTTOCALL=”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:17] NoOp(“SIP/VI-In2-00000d38”, "Working with ") in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:18] ExecIf(“SIP/VI-In2-00000d38”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:19] ExecIf(“SIP/VI-In2-00000d38”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:20] Set(“SIP/VI-In2-00000d38”, “ITER=2”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:21] GotoIf(“SIP/VI-In2-00000d38”, “0?ndloopbegin”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:22] Macro(“SIP/VI-In2-00000d38”, “dial-ringall-predial-hook,”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“SIP/VI-In2-00000d38”, “”) in new stack
[2020-08-04 11:15:40] WARNING[8683][C-00000ea2] func_extstate.c: EXTENSION_STATE requires an extension
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:23] ExecIf(“SIP/VI-In2-00000d38”, “0?Set(CWRING=r(callwaiting)):Set(CWRING=)”) in new stack
[2020-08-04 11:15:40] WARNING[8683][C-00000ea2] func_extstate.c: EXTENSION_STATE requires an extension
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:24] ExecIf(“SIP/VI-In2-00000d38”, “0?Set(ds=PJSIP/209/sip:[email protected]:5060&PJSIP/206/sip:[email protected]:5060&PJSIP/226/sip:[email protected]:5060,40,HhtrQ(NO_ANSWER)M(auto-blkvm)g)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@macro-dial:25] Dial(“SIP/VI-In2-00000d38”, “PJSIP/209/sip:[email protected]:5060&PJSIP/206/sip:[email protected]:5060&PJSIP/226/sip:[email protected]:5060,40,HhtrQ(NO_ANSWER)M(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: PJSIP/209-00001441 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/209-00001441”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/209-00001441”, “Applying SIP Headers to channel PJSIP/209-00001441”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/209-00001441”, “TECH=PJSIP”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/209-00001441”, “SIPHEADERKEYS=”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/209-00001441”, “0”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_while.c: Jumping to priority 13
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:14] Return(“PJSIP/209-00001441”, “”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: Spawn extension (from-internal, 712, 1) exited non-zero on ‘PJSIP/209-00001441’
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: PJSIP/209-00001441 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: PJSIP/206-00001442 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/206-00001442”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/206-00001442”, “Applying SIP Headers to channel PJSIP/206-00001442”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/206-00001442”, “TECH=PJSIP”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/206-00001442”, “SIPHEADERKEYS=”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/206-00001442”, “0”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_while.c: Jumping to priority 13
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:14] Return(“PJSIP/206-00001442”, “”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: Spawn extension (from-internal, 712, 1) exited non-zero on ‘PJSIP/206-00001442’
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: PJSIP/206-00001442 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: PJSIP/226-00001443 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/226-00001443”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/226-00001443”, “Applying SIP Headers to channel PJSIP/226-00001443”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/226-00001443”, “TECH=PJSIP”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/226-00001443”, “SIPHEADERKEYS=”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/226-00001443”, “0”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_while.c: Jumping to priority 13
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] pbx.c: Executing [s@func-apply-sipheaders:14] Return(“PJSIP/226-00001443”, “”) in new stack
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: Spawn extension (from-internal, 712, 1) exited non-zero on ‘PJSIP/226-00001443’
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_stack.c: PJSIP/226-00001443 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_dial.c: Called PJSIP/209/sip:[email protected]:5060
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_dial.c: Called PJSIP/206/sip:[email protected]:5060
[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] app_dial.c: Called PJSIP/226/sip:[email protected]:5060

comtech · August 14, 2020, 10:49pm

[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 211 has do not disturb enabled, or followme pre-ring returned busy
 [2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 212 do not disturb is disabled
 [2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 213 has do not disturb enabled, or followme pre-ring returned busy
 [2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 229 has do not disturb enabled, or followme pre-ring returned busy

Only 212 is eligible to take the call.

[2020-08-04 11:15:40] VERBOSE[8683][C-00000ea2] res_agi.c: dialparties.agi: Extension 212 has ExtensionState: 1

But 212 is already in use

So the call does not ring any in the ring group. What does RG 712 do if no one answers/is not available?

scampbell_adnet · August 17, 2020, 1:50pm

It is set to drop into a queue, but it seems to be bouncing back to the original ring group (733) extensions. (206, 209, 226).

Transferring directly to the queue extension number seems to be working fine.

comtech · August 17, 2020, 2:52pm

I think your question is answered in that the RG does not answer the call because no-one “can”. Please share the configuration of the RGs 733, 712 and the fail-over queue.

scampbell_adnet · August 18, 2020, 2:06pm

Thanks for you help, but shouldn’t it drop into the ‘destination if no answer’ if none of the extensions are available?

It seems to bounce back rather than just continue on to the queue. I’ve atached the 2 ring group settings and the queue settings I have in place currently.

comtech · August 18, 2020, 2:27pm

I would think your logic is correct, although I’ve never had a situation where a ring group didn’t at least ring (the output suggests it didn’t even try due to all members being unavailable.

What extensions staff queue 750, static and dynamic? Were the queue entry rules (time of day, # of staff, etc.) met?

I do see in the dialplan Returned from dialparties with groups to dial maybe there has to be at least one dial able extension, otherwise the call returns to the first party (the original RG). If that is the case,
I am not sure this is a feature or bug, but you could submit a feature request, but that will not solve the problem immediately: https://issues.freepbx.org/secure/Dashboard.jspa

What happens if you force ring (don’t skip busy)? Does it proceed as planned?

system · September 18, 2020, 2:27pm

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