[asterisk-bugs] [Asterisk 0018028]: Exceptionally long queue length queuing to XXXXX
Asterisk Bug Tracker
noreply at bugs.digium.com
Wed Feb 16 17:16:15 CST 2011
A NOTE has been added to this issue.
======================================================================
https://issues.asterisk.org/view.php?id=18028
======================================================================
Reported By: alecdavis
Assigned To:
======================================================================
Project: Asterisk
Issue ID: 18028
Category: Core/PBX
Reproducibility: always
Severity: minor
Priority: normal
Status: acknowledged
Asterisk Version: SVN
JIRA: SWP-2292
Regression: No
Reviewboard Link:
SVN Branch (only for SVN checkouts, not tarball releases): N/A
SVN Revision (number only!): 288195
Request Review:
======================================================================
Date Submitted: 2010-09-22 06:18 CDT
Last Modified: 2011-02-16 17:16 CST
======================================================================
Summary: Exceptionally long queue length queuing to XXXXX
Description:
Although this is under test conditions, this can go on for ever - seems
like 100's per second.
But the state of code at the moment, allows you to hangup, and it clears.
======================================================================
Relationships ID Summary
----------------------------------------------------------------------
related to 0018008 deadlock in local_write trying to bridg...
related to 0018637 [patch] No MOH on Call Park and Excepti...
======================================================================
----------------------------------------------------------------------
(0132056) jcovert (reporter) - 2011-02-16 17:16
https://issues.asterisk.org/view.php?id=18028#c132056
----------------------------------------------------------------------
It doesn't take 30 local channels, it can happen with just one active.
The following is on 1.8.1.1
I just had an occurrence of this (message spewed 58,057 times over a three
minute period) at a client who is getting more and more frustrated with the
state of things:
[root at asterisk asterisk]# grep queue /tmp/x.tmp |wc
58057 638627 7024897
[root at asterisk asterisk]# grep queue /tmp/x.tmp
[14-Feb-2011 13:16:36.75] WARNING[9558] channel.c: Exceptionally long
queue length queuing to Local/sleep at monitor-be7c;2
[14-Feb-2011 13:16:36.75] WARNING[9558] channel.c: Exceptionally long
queue length queuing to Local/sleep at monitor-be7c;2
...
[14-Feb-2011 13:19:30.78] WARNING[9559] channel.c: Exceptionally long
queue length queuing to Local/sleep at monitor-be7c;2
[14-Feb-2011 13:19:31.37] WARNING[9559] channel.c: Exceptionally long
queue length queuing to Local/sleep at monitor-be7c;2
While this was going on a dozen or so unrelated SIP calls got fouled up,
and we just noticed they were still hanging around two days later, because
a client called us and complained about missing recordings (which would
have been queued when the Mixmonitor command completed)
Channel Context Extension Prio State
Application Data CallerID Duration Accountcode
PeerAccount BridgedTo
SIP/onstate-sip-serv macro-anyoldcall s 10 Up
Dial SIP/+16239357327 at onstate- +15132409191 51:33:49
SIP/onstate-sip-serv
SIP/onstate-sip-serv macro-anyoldcall s 10 Up
Dial SIP/+16232437242 at onstate- +16102524416 51:44:38
SIP/onstate-sip-serv
and these others:
SIP/asterisk4-00017e callskype gmagent1 1 Up
AppDial (Outgoing Line) gmagent1 51:49:01
SIP/onstate-sip-serv
SIP/asterisk4-00017e callskype klauss-ia 1 Up
AppDial (Outgoing Line) klauss-ia 51:48:41
SIP/onstate-sip-serv
SIP/onstate-sip-serv callskype klauss-ia 31 Up
Dial SIP/8759klauss-ia at asteris +12145457068 51:48:41
SIP/asterisk4-00017e
SIP/brendar.moving.s onstate 8717brendar.movi 1 Up
AppDial (Outgoing Line) 8717brendar.mov 51:53:47
SIP/onstate-sip-serv
SIP/asterisk4-00017e callskype aahcs.ladyclaire 1 Up
AppDial (Outgoing Line) aahcs.ladyclair 51:50:19
SIP/onstate-sip-serv
SIP/onstate-sip-serv callskype gmagent1 31 Up
Dial SIP/8759gmagent1 at asterisk +14104300932 51:49:01
SIP/asterisk4-00017e
SIP/onstate-sip-serv onstate 8717brendar.movi 13 Up
Dial SIP/brendar.moving.specia +12487988748 51:53:47
SIP/brendar.moving.s
SIP/jenilynn-00017d8 onstate 8717jenilynn 1 Up
AppDial (Outgoing Line) 8717jenilynn 51:54:38
SIP/onstate-sip-serv
SIP/johns.moving.spe onstate 8717johns.moving 1 Up
AppDial (Outgoing Line) 8717johns.movin 51:47:54
SIP/onstate-sip-serv
SIP/onstate-sip-serv macro-anyoldcall s 1 Up
AppDial (Outgoing Line) +16128861133 51:54:23
SIP/onstate-sip-serv
SIP/onstate-sip-serv onstate 8717topdog.james 13 Up
Dial SIP/topdog.james.etchegar +12392623643 51:54:27
SIP/topdog.james.etc
SIP/onstate-sip-serv onstate 8717jenilynn 13 Up
Dial SIP/jenilynn,120,M(onansw +14058485000 51:54:38
SIP/jenilynn-00017d8
SIP/onstate-sip-serv onstate 8717bcrann.movin 13 Up
Dial SIP/bcrann.moving.special +14044318017 51:48:13
SIP/bcrann.moving.sp
SIP/onstate-sip-serv callskype aahcs.rowena.raq 27 Up
Dial SIP/8759aahcs.rowena.raqu +13096696012 51:49:57
SIP/asterisk2-00017e
SIP/onstate-sip-serv callskype osa.ivyjoy.villa 27 Up
Dial SIP/8759osa.ivyjoy.villan +14434800725 51:50:42
SIP/asterisk2-00017e
SIP/asterisk2-00017e callskype aahcs.christine. 1 Up
AppDial (Outgoing Line) aahcs.christine 51:51:24
SIP/onstate-sip-serv
SIP/onstate-sip-serv callskype np.agent3 27 Up
Dial SIP/8759np.agent3 at asteris +14085957148 51:50:51
SIP/asterisk2-00017e
SIP/onstate-sip-serv callskype aahcs.ladyclaire 31 Up
Dial SIP/8759aahcs.ladyclaire. +18707465111 51:50:20
SIP/asterisk4-00017e
SIP/asterisk2-00017e callskype np.agent3 1 Up
AppDial (Outgoing Line) np.agent3 51:50:51
SIP/onstate-sip-serv
SIP/topdog.james.etc onstate 8717topdog.james 1 Up
AppDial (Outgoing Line) 8717topdog.jame 51:54:27
SIP/onstate-sip-serv
SIP/asterisk2-00017e callskype osa.ivyjoy.villa 1 Up
AppDial (Outgoing Line) osa.ivyjoy.vill 51:50:42
SIP/onstate-sip-serv
SIP/onstate-sip-serv callskype aahcs.christine. 27 Up
Dial SIP/8759aahcs.christine.c +15802239447 51:51:24
SIP/asterisk2-00017e
SIP/asterisk2-00017e callskype aahcs.rowena.raq 1 Up
AppDial (Outgoing Line) aahcs.rowena.ra 51:49:56
SIP/onstate-sip-serv
SIP/bcrann.moving.sp onstate 8717bcrann.movin 1 Up
AppDial (Outgoing Line) 8717bcrann.movi 51:48:13
SIP/onstate-sip-serv
SIP/hollye.moving.sp onstate 8717hollye.movin 1 Up
AppDial (Outgoing Line) 8717hollye.movi 51:48:11
SIP/onstate-sip-serv
SIP/onstate-sip-serv macro-anyoldcall s 10 Up
Dial SIP/+16128861133 at onstate- +17154565448 51:54:23
SIP/onstate-sip-serv
SIP/onstate-sip-serv onstate 8717johns.moving 13 Up
Dial SIP/johns.moving.speciali +14044318017 51:47:54
SIP/johns.moving.spe
SIP/onstate-sip-serv onstate 8717hollye.movin 13 Up
Dial SIP/hollye.moving.special +18054534687 51:48:11
SIP/hollye.moving.sp
Here's one of the two day old calls:
[root at asterisk asterisk]# asterisk -rx 'core show channel
SIP/onstate-sip-server-00017e79'
-- General --
Name: SIP/onstate-sip-server-00017e79
Type: SIP
UniqueID: asterisk.onstate.net-1297707348.133129
LinkedID: asterisk.onstate.net-1297707348.133129
Caller ID: +12145457068
Caller ID Name: (N/A)
DNID Digits: 8759klauss-ia
Language: en
State: Up (6)
Rings: 0
NativeFormats: 0x80004 (ulaw|h263)
WriteFormat: 0x4 (ulaw)
ReadFormat: 0x4 (ulaw)
WriteTranscode: No
ReadTranscode: No
1st File Descriptor: 583
Frames in: 6107
Frames out: 4
Time to Hangup: 0
Elapsed Time: 52h58m40s
Direct Bridge: SIP/asterisk4-00017e7a
Indirect Bridge: SIP/asterisk4-00017e7a
-- PBX --
Context: callskype
Extension: klauss-ia
Priority: 31
Call Group: 0
Pickup Group: 0
Application: Dial
Data: SIP/8759klauss-ia at asterisk4,120,M(onanswer)
Blocking in: ast_waitfor_nandfds
Variables:
BRIDGEPVTCALLID=4cc2bf9b63318baf0f1ba4874ed94005 at 63.210.44.146:5060
BRIDGEPEER=SIP/asterisk4-00017e7a
DIALEDPEERNUMBER=8759klauss-ia at asterisk4
DIALEDPEERNAME=SIP/asterisk4-00017e7a
DIALSTATUS=ANSWER
~HASH~SIP_CAUSE~SIP/asterisk4-00017e7a~=SIP 200 OK
DIALEDTIME=
ANSWEREDTIME=
retry=0
MACRO_DEPTH=0
which=server2
server2now=44
server1now=66
server2=asterisk4
server2max=40
server1=asterisk2
server1max=65
DB_RESULT=241
SkypeCount=111
GOSUB_RETVAL=
numcalls=91
prefix=
db_tracker=recording/test/test
SIPCALLID=2691d25b at sipserver1.On-State.com
SIPDOMAIN=gw.onstate.net
SIPURI=sip:SipServerClient at 63.210.44.142:5061
CDR Variables:
level 1: dnid=8759klauss-ia
level 1: clid=+12145457068
level 1: src=+12145457068
level 1: dst=klauss-ia
level 1: dcontext=callskype
level 1: channel=SIP/onstate-sip-server-00017e79
level 1: dstchannel=SIP/asterisk4-00017e7a
level 1: lastapp=Dial
level 1: lastdata=SIP/8759klauss-ia at asterisk4,120,M(onanswer)
level 1: start=2011-02-14 13:15:48
level 1: answer=2011-02-14 13:15:54
level 1: duration=190720
level 1: billsec=190714
level 1: disposition=ANSWERED
level 1: amaflags=DOCUMENTATION
level 1: uniqueid=asterisk.onstate.net-1297707348.133129
level 1: linkedid=asterisk.onstate.net-1297707348
level 1: sequence=183520
[root at asterisk asterisk]# asterisk -rx 'core show channel
SIP/asterisk4-00017e7a'
-- General --
Name: SIP/asterisk4-00017e7a
Type: SIP
UniqueID: asterisk.onstate.net-1297707348.133130
LinkedID: asterisk.onstate.net-1297707348.133129
Caller ID: klauss-ia
Caller ID Name: (N/A)
DNID Digits: (N/A)
Language: en
State: Up (6)
Rings: 0
NativeFormats: 0x80004 (ulaw|h263)
WriteFormat: 0x4 (ulaw)
ReadFormat: 0x4 (ulaw)
WriteTranscode: No
ReadTranscode: No
1st File Descriptor: 598
Frames in: 6170
Frames out: 0
Time to Hangup: 0
Elapsed Time: 53h0m6s
Direct Bridge: SIP/onstate-sip-server-00017e79
Indirect Bridge: SIP/onstate-sip-server-00017e79
-- PBX --
Context: callskype
Extension: klauss-ia
Priority: 1
Call Group: 0
Pickup Group: 0
Application: AppDial
Data: (Outgoing Line)
Blocking in: ast_waitfor_nandfds
Variables:
BRIDGEPVTCALLID=2691d25b at sipserver1.On-State.com
BRIDGEPEER=SIP/onstate-sip-server-00017e79
MACRO_DEPTH=0
DIALEDPEERNUMBER=8759klauss-ia at asterisk4
SIPCALLID=4cc2bf9b63318baf0f1ba4874ed94005 at 63.210.44.146:5060
CDR Variables:
level 1: dnid=
level 1: clid=klauss-ia
level 1: src=klauss-ia
level 1: dst=s
level 1: dcontext=onstate
level 1: channel=SIP/asterisk4-00017e7a
level 1: lastapp=MacroExit
level 1: start=2011-02-14 13:15:48
level 1: answer=2011-02-14 13:15:54
level 1: duration=190805
level 1: billsec=190799
level 1: disposition=ANSWERED
level 1: amaflags=DOCUMENTATION
level 1: uniqueid=asterisk.onstate.net-1297707348.133130
level 1: linkedid=asterisk.onstate.net-1297707348
level 1: sequence=183514
Issue History
Date Modified Username Field Change
======================================================================
2011-02-16 17:16 jcovert Note Added: 0132056
======================================================================
More information about the asterisk-bugs
mailing list