[Home]

Summary:ASTERISK-18679: Wrong autopause behavior
Reporter:Aleksander Mischenko (mealstrom)Labels:
Date Opened:2011-10-06 03:34:34Date Closed:
Priority:MinorRegression?
Status:Open/NewComponents:Applications/app_queue
Versions:1.8.5.0 13.18.4 Frequency of
Occurrence		Frequent
Related
Issues:
Environment:Linux asterisk 2.6.32-33-generic-pae Distributor ID: Ubuntu Description: Ubuntu 10.04.3 LTS Release: 10.04 Codename: lucidAttachments:( 0) asterisk_autopause
Description:[support]
maxlen=10
timeout=35
retry=35
;remove "You are next"
queue-youarenext=
reportholdtime=no
strategy=rrmemory
memberdelay=0; set 3
;minimal delay before new call after successful call -- for comments
wrapuptime=25 ;set > 15
musicclass=native-random
announce-holdtime=no
announce-position=no
announce-frequency=0 ;set 60 after test
min-announce-frequency=25
periodic-announce=thank-you-message
periodic-announce-frequency=60 ; every 60 seconds
announce-position-limit=10
setinterfacevar=yes
eventmemberstatus=yes
eventwhencalled=yes
setqueuevar=no
servicelevel=30
timeoutrestart=yes
autopause=yes;
ringinuse=no;
joinempty=no
leavewhenempty=yes
monitor-format=wav

So by this logic autopause shoud be used only after 35 seconds of ringing.
Some calls are ringing for 45 seconds. (see below)
And ive got autopause used after 7 seconds:
[2011-10-06 11:03:29] VERBOSE[18132] pbx.c:     -- Executing [s@queue-support:4] Queue("SIP/1310-0001b94d", "support,t") in new stack
[2011-10-06 11:03:29] VERBOSE[18132] res_musiconhold.c:     -- Started music on hold, class 'native-random', on SIP/1310-0001b94d
[2011-10-06 11:03:29] VERBOSE[18132] netsock2.c:   == Using SIP RTP CoS mark 5
[2011-10-06 11:03:29] VERBOSE[18132] app_queue.c:     -- Called SIP/1104
[2011-10-06 11:03:36] VERBOSE[18132] app_queue.c:     -- Nobody picked up in 7000 ms
[2011-10-06 11:03:36] VERBOSE[18132] app_queue.c:     -- Auto-Pausing Queue Member SIP/1104 in queue support since they failed to answer.
[2011-10-06 11:03:36] VERBOSE[18132] netsock2.c:   == Using SIP RTP CoS mark 5
[2011-10-06 11:03:36] VERBOSE[18132] app_queue.c:     -- Called SIP/1101
[2011-10-06 11:03:36] VERBOSE[18132] app_queue.c:     -- SIP/1101-0001b94f is ringing
[2011-10-06 11:03:41] VERBOSE[18132] res_musiconhold.c:     -- Stopped music on hold on SIP/1310-0001b94d
[2011-10-06 11:03:41] VERBOSE[18132] pbx.c:   == Spawn extension (queue-support, s, 4) exited non-zero on 'SIP/1310-0001b94d'

#grep 'Nobody picked up in' verbose
[2011-10-04 10:43:57] VERBOSE[7782] app_queue.c:     -- Nobody picked up in 6000 ms
[2011-10-04 11:54:32] VERBOSE[8225] app_dial.c:     -- Nobody picked up in 45000 ms
[2011-10-04 12:50:16] VERBOSE[8620] app_queue.c:     -- Nobody picked up in 2000 ms
[2011-10-04 16:52:38] VERBOSE[9524] app_queue.c:     -- Nobody picked up in 7000 ms
[2011-10-04 17:26:22] VERBOSE[9671] app_queue.c:     -- Nobody picked up in 2000 ms
[2011-10-04 17:40:35] VERBOSE[9709] app_queue.c:     -- Nobody picked up in 6000 ms
[2011-10-04 21:58:38] VERBOSE[10570] app_queue.c:     -- Nobody picked up in 35000 ms
[2011-10-05 09:44:26] VERBOSE[11745] app_dial.c:     -- Nobody picked up in 45000 ms
[2011-10-05 09:53:15] VERBOSE[11840] app_dial.c:     -- Nobody picked up in 45000 ms
[2011-10-05 14:07:59] VERBOSE[12373] app_dial.c:     -- Nobody picked up in 45000 ms
[2011-10-05 17:52:38] VERBOSE[13711] app_queue.c:     -- Nobody picked up in 25000 ms
[2011-10-06 08:39:08] VERBOSE[17433] app_queue.c:     -- Nobody picked up in 35000 ms
[2011-10-06 10:47:32] VERBOSE[18114] app_dial.c:     -- Nobody picked up in 45000 ms
[2011-10-06 11:03:36] VERBOSE[18132] app_queue.c:     -- Nobody picked up in 7000 ms
Comments:By: Aleksander Mischenko (mealstrom) 2011-10-24 09:10:57.191-0500

wireshark dump file (SIP Session)
And this is what happens there during session of attached file
[2011-10-24 16:38:34] VERBOSE[2473] pbx.c:     -- Executing [s@queue-support:4] Queue("SIP/0382724777-0000b128", "support,t") in new stack
[2011-10-24 16:38:34] VERBOSE[2473] res_musiconhold.c:     -- Started music on hold, class 'native-random', on SIP/0382724777-0000b128
[2011-10-24 16:38:34] VERBOSE[2473] netsock2.c:   == Using SIP RTP CoS mark 5
[2011-10-24 16:38:34] VERBOSE[2473] app_queue.c:     -- Called SIP/1104
[2011-10-24 16:38:40] VERBOSE[2473] app_queue.c:     -- Nobody picked up in 6000 ms
[2011-10-24 16:38:40] VERBOSE[2473] app_queue.c:     -- Auto-Pausing Queue Member SIP/1104 in queue support since they failed to answer.
[2011-10-24 16:38:40] VERBOSE[2473] netsock2.c:   == Using SIP RTP CoS mark 5
[2011-10-24 16:38:40] VERBOSE[2473] app_queue.c:     -- Called SIP/1101
[2011-10-24 16:38:40] VERBOSE[2473] app_queue.c:     -- SIP/1101-0000b12e is ringing
[2011-10-24 16:38:44] VERBOSE[2473] app_queue.c:     -- SIP/1101-0000b12e answered SIP/0382724777-0000b128
[2011-10-24 16:38:44] VERBOSE[2473] res_musiconhold.c:     -- Stopped music on hold on SIP/0382724777-0000b128
[2011-10-24 16:41:38] VERBOSE[2473] pbx.c:   == Spawn extension (queue-support, s, 4) exited non-zero on 'SIP/0382724777-0000b128'
By: Matt Jordan (mjordan) 2011-11-16 14:56:54.201-0600

Can you attach the portion of your dialplan that contains the s extension?  Are you using the Progress application to send in-band progress information to the dialed in channels?
By: Aleksander Mischenko (mealstrom) 2011-11-16 16:46:11.669-0600

[queue-support]
exten => s,1,NoOp(Qprio: ${QUEUE_PRIO})
exten => s,n,Answer()
exten => s,n,Set(MONITOR_FILENAME="${FILTER(0-9,${CDR(start)})}-${UNIQUEID}")
exten => s,n,NoOp(${MONITOR_FILENAME}.wav")
exten => s,n,Queue(support,t)
exten => s,n,Congestion()
exten => s,n,Hangup()
By: Matt Jordan (mjordan) 2012-02-09 16:49:51.445-0600

By the way, when you enter feedback, you need to hit the Send Back button - otherwise we don't always get notified :-)
By: Aleksander Mischenko (mealstrom) 2012-02-16 02:57:03.956-0600

My bad, haven't read how to work with jira.
Do you need any additional information?