Asterisk queue wrapup appears to be ignored

martinnystrom · February 23, 2024, 12:44pm

Hello,

Asterisk version 20.5.2

Description: It appears the queue setting wrapup time is ignored, or does not properly apply to our queues. It happend frequently that an agents gets pushed another queue call the very momeny the previous queue call ends i.e. before the wrapup, or within the wrapup timer.

queues.conf

[general]
persistentmembers=yes
autofill=yes
monitor-type=MixMonitor
shared_lastcall=yes
retry=2
wrapuptime=2
setinterfacevar=yes
setqueueentryvar=yes
timeoutrestart=yes
setqueuevar=yes

[queue-6]
 context=organization-3
 musicclass=default
 strategy=rrmemory
 joinempty=yes
 leavewhenempty=no
 ringinuse=no
 maxlen=100
 timeout=15
 weight=0
 autopause=no
 announce=
 announce-frequency=0
 min-announce-frequency=0
 periodic-announce-frequency=0
 random-periodic-announce=no
 relative-periodic-announce=no
 announce-holdtime=no
 announce-position=no
 announce-position-limit=5
 announce-position-only-up=yes
 reportholdtime=no
 periodic-announce=
member => PJSIP/10005-5,0,,PJSIP/10005-5
member => PJSIP/10006-5,0,,PJSIP/10006-5
member => PJSIP/10007-5,0,,PJSIP/10007-5
member => PJSIP/10008-5,0,,PJSIP/10008-5
member => PJSIP/10010-5,0,,PJSIP/10010-5
member => PJSIP/10009-5,0,,PJSIP/10009-5
member => PJSIP/10011-5,0,,PJSIP/10011-5
member => PJSIP/10013-5,0,,PJSIP/10013-5
member => PJSIP/10016-5,0,,PJSIP/10016-5
member => PJSIP/10014-5,0,,PJSIP/10014-5
member => PJSIP/10017-5,0,,PJSIP/10017-5
member => PJSIP/10015-5,0,,PJSIP/10015-5
member => PJSIP/10020-5,0,,PJSIP/10020-5
member => PJSIP/10023-5,0,,PJSIP/10023-5
member => PJSIP/10026-5,0,,PJSIP/10026-5
member => PJSIP/10027-5,0,,PJSIP/10027-5
member => PJSIP/10025-5,0,,PJSIP/10025-5
member => PJSIP/10028-5,0,,PJSIP/10028-5

[queue-16]
 context=organization-3
 musicclass=default
 strategy=rrmemory
 joinempty=yes
 leavewhenempty=no
 ringinuse=no
 maxlen=100
 timeout=15
 weight=0
 autopause=no
 announce=
 announce-frequency=0
 min-announce-frequency=0
 periodic-announce-frequency=0
 random-periodic-announce=no
 relative-periodic-announce=no
 announce-holdtime=no
 announce-position=no
 announce-position-limit=5
 announce-position-only-up=yes
 reportholdtime=no
 periodic-announce=
member => PJSIP/10005-5,0,,PJSIP/10005-5
member => PJSIP/10006-5,0,,PJSIP/10006-5
member => PJSIP/10007-5,0,,PJSIP/10007-5
member => PJSIP/10008-5,0,,PJSIP/10008-5
member => PJSIP/10010-5,0,,PJSIP/10010-5
member => PJSIP/10009-5,0,,PJSIP/10009-5
member => PJSIP/10011-5,0,,PJSIP/10011-5
member => PJSIP/10013-5,0,,PJSIP/10013-5
member => PJSIP/10016-5,0,,PJSIP/10016-5
member => PJSIP/10014-5,0,,PJSIP/10014-5
member => PJSIP/10017-5,0,,PJSIP/10017-5
member => PJSIP/10015-5,0,,PJSIP/10015-5
member => PJSIP/10020-5,0,,PJSIP/10020-5
member => PJSIP/10023-5,0,,PJSIP/10023-5
member => PJSIP/10026-5,0,,PJSIP/10026-5
member => PJSIP/10027-5,0,,PJSIP/10027-5
member => PJSIP/10025-5,0,,PJSIP/10025-5
member => PJSIP/10028-5,0,,PJSIP/10028-5

Here’s the Asterisk log for two calls that intertwine each other during the wrapup timer for the same agent. The impacted agent is PJSIP/10009-5

[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:1] NoOp("PJSIP/trunk-7-000001f7", ""Incoming call from route 28 HIDDEN-CALLERID"") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:2] Set("PJSIP/trunk-7-000001f7", "__OrigOriginator=HIDDEN-CALLERID") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:3] Set("PJSIP/trunk-7-000001f7", "__OrigRecipient=HIDDEN-DNID") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:4] Set("PJSIP/trunk-7-000001f7", "CallID=6b83d1ab6d4704fd2ca8b5b17abe69f0@HIDDEN-IP-ADDR:5060") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:5] Set("PJSIP/trunk-7-000001f7", "UniqueID=1708685977.828") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:6] Set("PJSIP/trunk-7-000001f7", "LinkedID=1708685977.828") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:7] Set("PJSIP/trunk-7-000001f7", "VoiceRouteID=28") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:8] AGI("PJSIP/trunk-7-000001f7", "agi://127.0.0.1:8018") in new stack
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: AGI Tx >> agi_network: yes
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_request: agi://127.0.0.1:8018
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_channel: PJSIP/trunk-7-000001f7
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_language: en
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_type: PJSIP
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_uniqueid: 1708685977.828
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_version: 20.5.2
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_callerid: HIDDEN-CALLERID
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_calleridname: unknown
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_callingpres: 0
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_callingani2: 0
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_callington: 0
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_callingtns: 0
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_dnid: HIDDEN-DNID
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_rdnis: unknown
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_context: from-voip-provider
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_extension: HIDDEN-DNID
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_priority: 8
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_enhanced: 0.0
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_accountcode: 
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> agi_threadid: 139631651645184
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << GET VARIABLE UniqueID
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=1 (1708685977.828)
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << GET VARIABLE CallID
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=1 (6b83d1ab6d4704fd2ca8b5b17abe69f0@HIDDEN-IP-ADDR:5060)
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << GET VARIABLE VoiceRouteID
[Feb 23 10:59:37] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=1 (28)
[Feb 23 10:59:38] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << ANSWER
[Feb 23 10:59:38] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=0
[Feb 23 10:59:38] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << STREAM FILE "/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/4a52c025-f9b5-4aaa-b330-8c8cc583b1ce" "#"
[Feb 23 10:59:38] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7> Playing '/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/4a52c025-f9b5-4aaa-b330-8c8cc583b1ce.slin' (escape_digits=#) (sample_offset 0) (language 'en')
[Feb 23 10:59:48] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=0 endpos=72973
[Feb 23 10:59:48] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << GET DATA /opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/dd18af3f-2716-4d2c-aee1-5ccc3b8f49d4 30000 1
[Feb 23 10:59:48] VERBOSE[13952][C-000000f8] file.c: <PJSIP/trunk-7-000001f7> Playing '/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/dd18af3f-2716-4d2c-aee1-5ccc3b8f49d4.slin' (language 'en')
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:1] NoOp("PJSIP/trunk-7-000001f9", ""Incoming call from route 18 HIDDEN-CALLERID"") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:2] Set("PJSIP/trunk-7-000001f9", "__OrigOriginator=HIDDEN-CALLERID") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:3] Set("PJSIP/trunk-7-000001f9", "__OrigRecipient=HIDDEN-DNID") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:4] Set("PJSIP/trunk-7-000001f9", "CallID=3c51a5293ba4350d28a7aba87239cd01@HIDDEN-IP-ADDR:5060") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:5] Set("PJSIP/trunk-7-000001f9", "UniqueID=1708685993.831") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:6] Set("PJSIP/trunk-7-000001f9", "LinkedID=1708685993.831") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:7] Set("PJSIP/trunk-7-000001f9", "VoiceRouteID=18") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] pbx.c: Executing [HIDDEN-DNID@from-voip-provider:8] AGI("PJSIP/trunk-7-000001f9", "agi://127.0.0.1:8018") in new stack
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: AGI Tx >> agi_network: yes
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_request: agi://127.0.0.1:8018
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_channel: PJSIP/trunk-7-000001f9
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_language: en
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_type: PJSIP
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_uniqueid: 1708685993.831
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_version: 20.5.2
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_callerid: HIDDEN-CALLERID
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_calleridname: unknown
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_callingpres: 0
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_callingani2: 0
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_callington: 0
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_callingtns: 0
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_dnid: HIDDEN-DNID
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_rdnis: unknown
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_context: from-voip-provider
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_extension: HIDDEN-DNID
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_priority: 8
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_enhanced: 0.0
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_accountcode: 
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> agi_threadid: 139630450181888
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << GET VARIABLE UniqueID
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=1 (1708685993.831)
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << GET VARIABLE CallID
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=1 (3c51a5293ba4350d28a7aba87239cd01@HIDDEN-IP-ADDR:5060)
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << GET VARIABLE VoiceRouteID
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=1 (18)
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << ANSWER
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=0
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << STREAM FILE "/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/4a52c025-f9b5-4aaa-b330-8c8cc583b1ce" "#"
[Feb 23 10:59:53] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9> Playing '/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/4a52c025-f9b5-4aaa-b330-8c8cc583b1ce.slin' (escape_digits=#) (sample_offset 0) (language 'en')
[Feb 23 11:00:02] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=0 endpos=72973
[Feb 23 11:00:03] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << GET DATA /opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/75c14142-a711-4e4f-b5f3-62121d8e187c 30000 1
[Feb 23 11:00:03] VERBOSE[13962][C-000000fa] file.c: <PJSIP/trunk-7-000001f9> Playing '/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/75c14142-a711-4e4f-b5f3-62121d8e187c.slin' (language 'en')
[Feb 23 11:00:25] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=6
[Feb 23 11:00:25] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << SET VARIABLE language "6"
[Feb 23 11:00:25] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=1
[Feb 23 11:00:25] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << GET DATA /opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/2419eb3f-e3a9-4c5c-a793-8d1c601e2a51 30000 10
[Feb 23 11:00:25] VERBOSE[13952][C-000000f8] file.c: <PJSIP/trunk-7-000001f7> Playing '/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/2419eb3f-e3a9-4c5c-a793-8d1c601e2a51.slin' (language 'en')
[Feb 23 11:00:31] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=5656456
[Feb 23 11:00:31] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << SET VARIABLE personnummer "5656456"
[Feb 23 11:00:31] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=1
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Rx << EXEC QUEUE queue-16,b(ADD_HEADER^s^1),,,,,,,,
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] res_agi.c: AGI Script Executing Application: (QUEUE) Options: (queue-16,b(ADD_HEADER^s^1),,,,,,,,)
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] res_musiconhold.c: Started music on hold, class 'default', on channel 'PJSIP/trunk-7-000001f7'
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] app_stack.c: PJSIP/10009-5-000001fb Internal Gosub(ADD_HEADER,s,1) start
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] pbx.c: Executing [s@ADD_HEADER:1] Set("PJSIP/10009-5-000001fb", "PJSIP_HEADER(add,X-UNIQUEID)=1708685977.828") in new stack
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] pbx.c: Executing [s@ADD_HEADER:2] Set("PJSIP/10009-5-000001fb", "PJSIP_HEADER(add,X-CALL-ID)=62969d8b-f817-4ef0-adea-3e2d4ef12206") in new stack
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] pbx.c: Executing [s@ADD_HEADER:3] Return("PJSIP/10009-5-000001fb", "") in new stack
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] app_stack.c: Spawn extension (organization-3, HIDDEN-DNID, 1) exited non-zero on 'PJSIP/10009-5-000001fb'
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] app_stack.c: PJSIP/10009-5-000001fb Internal Gosub(ADD_HEADER,s,1) complete GOSUB_RETVAL=
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] app_queue.c: Called PJSIP/10009-5
[Feb 23 11:00:33] VERBOSE[13952][C-000000f8] app_queue.c: PJSIP/10009-5-000001fb is ringing
[Feb 23 11:00:40] VERBOSE[13952][C-000000f8] app_queue.c: PJSIP/10009-5-000001fb answered PJSIP/trunk-7-000001f7
[Feb 23 11:00:40] VERBOSE[13952][C-000000f8] res_musiconhold.c: Stopped music on hold on PJSIP/trunk-7-000001f7
[Feb 23 11:00:40] VERBOSE[13996][C-000000f8] bridge_channel.c: Channel PJSIP/10009-5-000001fb joined 'simple_bridge' basic-bridge <07bdb6c6-5409-4ed4-ba83-e8f513e3fa15>
[Feb 23 11:00:40] VERBOSE[13952][C-000000f8] bridge_channel.c: Channel PJSIP/trunk-7-000001f7 joined 'simple_bridge' basic-bridge <07bdb6c6-5409-4ed4-ba83-e8f513e3fa15>
[Feb 23 11:00:40] WARNING[13996][C-000000f8] res_rtp_asterisk.c: 1708686033.833: DTLS packet from HIDDEN-IP-ADDR:62033 dropped. ICE not completed yet.
[Feb 23 11:00:47] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=8
[Feb 23 11:00:47] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << SET VARIABLE language "8"
[Feb 23 11:00:47] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=1
[Feb 23 11:00:47] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << GET DATA /opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/2419eb3f-e3a9-4c5c-a793-8d1c601e2a51 30000 10
[Feb 23 11:00:47] VERBOSE[13962][C-000000fa] file.c: <PJSIP/trunk-7-000001f9> Playing '/opt/ASTERISKPBX-asterisk-gateway/uploads/sounds/transcoded/2419eb3f-e3a9-4c5c-a793-8d1c601e2a51.slin' (language 'en')
[Feb 23 11:01:06] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=1333313
[Feb 23 11:01:06] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << SET VARIABLE personnummer "1333313"
[Feb 23 11:01:06] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=1
[Feb 23 11:01:08] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << EXEC QUEUE queue-6,b(ADD_HEADER^s^1),,,,,,,,
[Feb 23 11:01:08] VERBOSE[13962][C-000000fa] res_agi.c: AGI Script Executing Application: (QUEUE) Options: (queue-6,b(ADD_HEADER^s^1),,,,,,,,)
[Feb 23 11:01:08] VERBOSE[13962][C-000000fa] res_musiconhold.c: Started music on hold, class 'default', on channel 'PJSIP/trunk-7-000001f9'
[Feb 23 11:02:31] VERBOSE[13952][C-000000f8] bridge_channel.c: Channel PJSIP/trunk-7-000001f7 left 'simple_bridge' basic-bridge <07bdb6c6-5409-4ed4-ba83-e8f513e3fa15>
[Feb 23 11:02:31] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> 200 result=-1
[Feb 23 11:02:31] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> HANGUP
[Feb 23 11:02:31] VERBOSE[13996][C-000000f8] bridge_channel.c: Channel PJSIP/10009-5-000001fb left 'simple_bridge' basic-bridge <07bdb6c6-5409-4ed4-ba83-e8f513e3fa15>
[Feb 23 11:02:31] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Script agi://127.0.0.1:8018 completed, returning 4
[Feb 23 11:02:31] VERBOSE[13952][C-000000f8] res_agi.c: <PJSIP/trunk-7-000001f7>AGI Tx >> HANGUP
[Feb 23 11:02:31] VERBOSE[13952][C-000000f8] pbx.c: Spawn extension (from-voip-provider, HIDDEN-DNID, 8) exited non-zero on 'PJSIP/trunk-7-000001f7'
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] app_stack.c: PJSIP/10009-5-00000201 Internal Gosub(ADD_HEADER,s,1) start
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:1] Set("PJSIP/10009-5-00000201", "PJSIP_HEADER(add,X-UNIQUEID)=1708685993.831") in new stack
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:2] Set("PJSIP/10009-5-00000201", "PJSIP_HEADER(add,X-CALL-ID)=8e11b07e-9367-4580-9eb1-544585af5470") in new stack
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:3] Return("PJSIP/10009-5-00000201", "") in new stack
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] app_stack.c: Spawn extension (organization-3, HIDDEN-DNID, 1) exited non-zero on 'PJSIP/10009-5-00000201'
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] app_stack.c: PJSIP/10009-5-00000201 Internal Gosub(ADD_HEADER,s,1) complete GOSUB_RETVAL=
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] app_queue.c: Called PJSIP/10009-5
[Feb 23 11:02:31] VERBOSE[13962][C-000000fa] app_queue.c: PJSIP/10009-5-00000201 is ringing
[Feb 23 11:02:42] VERBOSE[13962][C-000000fa] app_queue.c: Nobody picked up in 11000 ms
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] app_stack.c: PJSIP/10011-5-00000204 Internal Gosub(ADD_HEADER,s,1) start
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:1] Set("PJSIP/10011-5-00000204", "PJSIP_HEADER(add,X-UNIQUEID)=1708685993.831") in new stack
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:2] Set("PJSIP/10011-5-00000204", "PJSIP_HEADER(add,X-CALL-ID)=16b09454-2cb4-4bcc-b7d2-5e9c504901e0") in new stack
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:3] Return("PJSIP/10011-5-00000204", "") in new stack
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] app_stack.c: Spawn extension (organization-3, HIDDEN-DNID, 1) exited non-zero on 'PJSIP/10011-5-00000204'
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] app_stack.c: PJSIP/10011-5-00000204 Internal Gosub(ADD_HEADER,s,1) complete GOSUB_RETVAL=
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] app_queue.c: Called PJSIP/10011-5
[Feb 23 11:02:56] VERBOSE[13962][C-000000fa] app_queue.c: PJSIP/10011-5-00000204 is ringing
[Feb 23 11:03:01] VERBOSE[13962][C-000000fa] app_queue.c: Nobody picked up in 5000 ms
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] app_stack.c: PJSIP/10014-5-00000206 Internal Gosub(ADD_HEADER,s,1) start
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:1] Set("PJSIP/10014-5-00000206", "PJSIP_HEADER(add,X-UNIQUEID)=1708685993.831") in new stack
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:2] Set("PJSIP/10014-5-00000206", "PJSIP_HEADER(add,X-CALL-ID)=e5fbd420-f28c-408b-8259-145ff2217b5f") in new stack
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] pbx.c: Executing [s@ADD_HEADER:3] Return("PJSIP/10014-5-00000206", "") in new stack
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] app_stack.c: Spawn extension (organization-3, HIDDEN-DNID, 1) exited non-zero on 'PJSIP/10014-5-00000206'
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] app_stack.c: PJSIP/10014-5-00000206 Internal Gosub(ADD_HEADER,s,1) complete GOSUB_RETVAL=
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] app_queue.c: Called PJSIP/10014-5
[Feb 23 11:03:31] VERBOSE[13962][C-000000fa] app_queue.c: PJSIP/10014-5-00000206 is ringing
[Feb 23 11:03:35] VERBOSE[13962][C-000000fa] app_queue.c: PJSIP/10014-5-00000206 answered PJSIP/trunk-7-000001f9
[Feb 23 11:03:35] VERBOSE[13962][C-000000fa] res_musiconhold.c: Stopped music on hold on PJSIP/trunk-7-000001f9
[Feb 23 11:03:35] VERBOSE[14137][C-000000fa] bridge_channel.c: Channel PJSIP/10014-5-00000206 joined 'simple_bridge' basic-bridge <ac45a84e-0e0e-408f-8125-c5068c86d727>
[Feb 23 11:03:35] VERBOSE[13962][C-000000fa] bridge_channel.c: Channel PJSIP/trunk-7-000001f9 joined 'simple_bridge' basic-bridge <ac45a84e-0e0e-408f-8125-c5068c86d727>
[Feb 23 11:03:35] WARNING[14137][C-000000fa] res_rtp_asterisk.c: 1708686211.850: DTLS packet from HIDDEN-IP-ADDR:55835 dropped. ICE not completed yet.
[Feb 23 11:07:31] VERBOSE[14137][C-000000fa] bridge_channel.c: Channel PJSIP/10014-5-00000206 left 'simple_bridge' basic-bridge <ac45a84e-0e0e-408f-8125-c5068c86d727>
[Feb 23 11:07:31] VERBOSE[13962][C-000000fa] bridge_channel.c: Channel PJSIP/trunk-7-000001f9 left 'simple_bridge' basic-bridge <ac45a84e-0e0e-408f-8125-c5068c86d727>
[Feb 23 11:07:31] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=-1
[Feb 23 11:07:31] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Rx << GET VARIABLE QUEUESTATUS
[Feb 23 11:07:31] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> 200 result=0
[Feb 23 11:07:32] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> HANGUP
[Feb 23 11:07:32] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Script agi://127.0.0.1:8018 completed, returning 4
[Feb 23 11:07:32] VERBOSE[13962][C-000000fa] res_agi.c: <PJSIP/trunk-7-000001f9>AGI Tx >> HANGUP
[Feb 23 11:07:32] VERBOSE[13962][C-000000fa] pbx.c: Spawn extension (from-voip-provider, HIDDEN-DNID, 8) exited non-zero on 'PJSIP/trunk-7-000001f9'

In the above example the call ends at 11:02:31 and instantly another calls rings the agent at 11:02:31.

martinnystrom · February 23, 2024, 1:59pm

Update:

It appears the wrapuptime properly sets when we set it under each queue. Is this parameter not supported under the general section? I’ve now come to think that much of my [general] parameters has not been applied if it is not supported under this section, only the bolded.

[general]
**persistentmembers=yes**
**autofill=yes**
**monitor-type=MixMonitor**
**shared_lastcall=yes**
retry=2
wrapuptime=2
setinterfacevar=yes
setqueueentryvar=yes
timeoutrestart=yes
setqueuevar=yes

; Load Custom queues
#include queues_custom.conf

system · March 24, 2024, 2:00pm

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