Wrap-up time is ignored sometimes


FreePBX 14.0.3.2 'VoIP Server’
Asterisk 13.19.1

I’ve got three queues and 20 operators who serve them(some of the operators are in three queues at the same time, others serve only one)

Can you elaborate?

Calls from all queues?
All users?

Please explain.

i have known about this trouble from four operators in one queue and 2 operators in another one
it happened randomly

Are the queue operators that have the problem in more than one queue?
Do all of your queues have the same wrap-up time?

My suspicion is that the way this is happening is that Caller A gets a call on Q_Z. They handle it and are wrapping up when a call comes in on Q_Y. Since they are not wrapping up a call from Q_Y, the module marks them as “available”, even though they are not available from Q_Z yet…

I thought about it, but some of the operators serve only one queue, and get this issue. 2-3 times per week.

Logs are really the only way we can do this without guessing.

Tell your team that you need to know precisely what time this happens and to tell you ASAP. Once you have that information, you can look back through the /var/log/asterisk/full log and see what the sequence was on the call that caused the problem.

[2018-09-20 08:42:02] VERBOSE[28689][C-000077b7] pbx.c: Executing [s@macro-auto-blkvm:10] NoOp("SIP/5016-00014f66", "Answer agent queues call linkedid 1537421673.1576459 Agent  CALLERID(NUM) 5016 CONNECTEDLINE(num) 0159443548 Exten s EXTTOCALL 5016") in new stack
[2018-09-20 08:42:57] VERBOSE[13331][C-000077b7] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/YLTE100-00014e26", "Local/5016@from-queue-000b610f;1 monior file= /var/spool/asterisk/monitor/2018/09/20/q-770-0159443548-20180920-083433-1537421673.1576459.wav") in new stack
[2018-09-20 08:42:57] VERBOSE[13331][C-000077b7] pbx.c: Executing [s@macro-hangupcall:5] AGI("SIP/YLTE100-00014e26", "attendedtransfer-rec-restart.php,Local/5016@from-queue-000b610f;1,/var/spool/asterisk/monitor/2018/09/20/q-770-0159443548-20180920-083433-1537421673.1576459.wav") in new stack
[2018-09-20 08:42:57] VERBOSE[28689][C-000077b7] pbx.c: Executing [s@bitrix24-closecard:1] NoOp("Local/5016@from-queue-000b610f;2", "closecard call linkedid 1537421673.1576459 Agent 0159443548 CALLERID(NUM) 0159443548 CONNECTEDLINE(num) 80173890770 Exten s") in new stack
[2018-09-20 08:42:58] VERBOSE[13331][C-000077b7] pbx.c: Executing [s@bitrix24-finish:1] NoOp("SIP/YLTE100-00014e26", "Finish call linkedid 1537421673.1576459 Agent  CALLERID(NUM) 0159443548 CONNECTEDLINE(num)  Exten s") in new stack
[2018-09-20 08:42:58] VERBOSE[13331][C-000077b7] pbx.c: Executing [s@bitrix24-finish:2] System("SIP/YLTE100-00014e26", "curl --data-urlencode "status=finish" --data-urlencode "id=1537421673.1576459" --data-urlencode "file=q-770-0159443548-20180920-083433-1537421673.1576459.wav"   --data-urlencode "datetime=2018-09-20 08:42:58" http://127.0.0.1/bitrix24/ >/dev/null 2>/dev/null &") in new stack


[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/5016@from-queue-000b6154;2", "AMPUSER=0171655135") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-user-callerid:37] Set("Local/5016@from-queue-000b6154;2", "CALLERID(number)=0171655135") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-user-callerid:38] Set("Local/5016@from-queue-000b6154;2", "CALLERID(name)=0171655135") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-user-callerid:40] Set("Local/5016@from-queue-000b6154;2", "CDR(cnam)=0171655135") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-user-callerid:41] Set("Local/5016@from-queue-000b6154;2", "CDR(cnum)=0171655135") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@sub-record-check:8] Set("Local/5016@from-queue-000b6154;2", "__FROMEXTEN=0171655135") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [exten@sub-record-check:1] NoOp("Local/5016@from-queue-000b6154;2", "Exten Recording Check between 0171655135 and 5016") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [ctset@macro-dial-one:1] Set("Local/5016@from-queue-000b6154;2", "DB(CALLTRACE/5016)=0171655135") in new stack
[2018-09-20 08:42:58] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-dial-one:33] NoOp("Local/5016@from-queue-000b6154;2", "Blind Transfer: , Attended Transfer: , User: 0171655135, Alert Info: ") in new stack
[2018-09-20 08:42:58] VERBOSE[30999][C-000077b9] pbx.c: Executing [s@bitrix24-closecard:1] NoOp("Local/5004@from-queue-000b6153;2", "closecard call linkedid 1537421709.1576541 Agent 0171655135 CALLERID(NUM) 0171655135 CONNECTEDLINE(num) 80173890770 Exten s") in new stack
[2018-09-20 08:43:10] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-auto-blkvm:1] ExecIf("SIP/5016-00014f91", "1?Set(CDR(recordingfile)=q-770-0171655135-20180920-083509-1537421709.1576541.wav)") in new stack
[2018-09-20 08:43:10] VERBOSE[31025][C-000077b9] pbx.c: Executing [s@macro-auto-blkvm:10] NoOp("SIP/5016-00014f91", "Answer agent queues call linkedid 1537421709.1576541 Agent  CALLERID(NUM) 5016 CONNECTEDLINE(num) 0171655135 Exten s EXTTOCALL 5016") in new stack

During the first call, the second call was at the queue and when the first call finished, the second call went to the same operator

Has anyone encountered this problem?

If I’m reading this log right, those calls came from different queues. I don’t think the pause time is global - I’m pretty sure it’s only bound to a single queue.

There is a parameter on the Advanced Settings page, but I don’t know if it actually works:

2 Likes

only the one queue in this log (q-770)

i have read about it, but in my version freepbx i can’t find this point

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