Extensions in queue ringing for 1 second

FreePBX
,
1

(PBXact version Version 12.7.5-1807-1.sng7)

Hello,

We have a queue with several extensions (5026, 5023, 5020, 5025) where the agents are saying the call flashes up too quickly on their phones for them to pick up the call. Having a look at the full log extract from a sample call below does seem to correspond with what they are saying. (Agent timeout is set to unlimited and the ring strategy is ring all.)

[2019-06-13 12:54:34] VERBOSE[8186][C-00009324] app_stack.c: SIP/5020-000157a1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-06-13 12:54:34] VERBOSE[8188][C-00009324] app_dial.c: Called SIP/5026
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5026@from-queue-0000f4cc;1 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5026@from-queue-0000f4cc;1 connected line has changed. Saving it until answer for IAX2/tsnlshops-8734
[2019-06-13 12:54:34] VERBOSE[8187][C-00009324] app_dial.c: Called SIP/5023
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5023@from-queue-0000f4cb;1 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5023@from-queue-0000f4cb;1 connected line has changed. Saving it until answer for IAX2/tsnlshops-8734
[2019-06-13 12:54:34] VERBOSE[8186][C-00009324] app_dial.c: Called SIP/5020
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5020@from-queue-0000f4ca;1 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5020@from-queue-0000f4ca;1 connected line has changed. Saving it until answer for IAX2/tsnlshops-8734
[2019-06-13 12:54:34] VERBOSE[8189][C-00009324] app_dial.c: Called SIP/5025
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5025@from-queue-0000f4cd;1 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5025@from-queue-0000f4cd;1 connected line has changed. Saving it until answer for IAX2/tsnlshops-8734
[2019-06-13 12:54:34] VERBOSE[8188][C-00009324] app_dial.c: SIP/5026-000157a2 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5026@from-queue-0000f4cc;1 is ringing
[2019-06-13 12:54:34] VERBOSE[8187][C-00009324] app_dial.c: SIP/5023-000157a3 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5023@from-queue-0000f4cb;1 is ringing
[2019-06-13 12:54:34] VERBOSE[8186][C-00009324] app_dial.c: SIP/5020-000157a1 is ringing
[2019-06-13 12:54:34] VERBOSE[8189][C-00009324] app_dial.c: SIP/5025-000157a0 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5025@from-queue-0000f4cd;1 is ringing
[2019-06-13 12:54:34] VERBOSE[7350][C-00009324] app_queue.c: Local/5020@from-queue-0000f4ca;1 is ringing
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] app_dial.c: SIP/5025-000157a0 answered Local/5025@from-queue-0000f4cd;2
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:1] ExecIf(“SIP/5025-000157a0”, “1?Set(CDR(recordingfile)=q-6000-4131-20190613-125313-1560423193.271823.wav)”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:2] Set(“SIP/5025-000157a0”, “__MACRO_RESULT=”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:3] Set(“SIP/5025-000157a0”, “CFIGNORE=”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:4] Set(“SIP/5025-000157a0”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:5] Set(“SIP/5025-000157a0”, “FORWARD_CONTEXT=from-internal”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:6] Set(“SIP/5025-000157a0”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:7] Macro(“SIP/5025-000157a0”, “blkvm-clr,”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“SIP/5025-000157a0”, “SHARED(BLKVM,IAX2/tsnlshops-8734)=”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“SIP/5025-000157a0”, “GOSUB_RETVAL=”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/5025-000157a0”, “”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf(“SIP/5025-000157a0”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=5025)”) in new stack
[2019-06-13 12:54:37] VERBOSE[8189][C-00009324] pbx.c: Executing [s@macro-auto-blkvm:9] ExecIf(“SIP/5025-000157a0”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Winnie van Meeuwen - CC)”) in new stack
[2019-06-13 12:54:37] VERBOSE[7350][C-00009324] app_queue.c: Local/5025@from-queue-0000f4cd;1 answered IAX2/tsnlshops-8734

Any help appreciated.

Best Regards

Mihailo

2

I see the call being answered by Extension 5025 three seconds after the initial ring. That’s about one ring.

What’s special about 5025?

3

Thanks cynjut, much appreciated. I think this can be closed. I failed to understand something here which is now clear!

4

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