[asterisk-bugs] [Asterisk 0015609]: WARNING[23025]: channel.c:952 __ast_queue_frame: Exceptionally long voice queue length queuing to Local
Asterisk Bug Tracker
noreply at bugs.digium.com
Tue Sep 1 08:41:33 CDT 2009
A NOTE has been added to this issue.
======================================================================
https://issues.asterisk.org/view.php?id=15609
======================================================================
Reported By: aragon
Assigned To:
======================================================================
Project: Asterisk
Issue ID: 15609
Category: Core/Channels
Reproducibility: have not tried
Severity: minor
Priority: normal
Status: acknowledged
Asterisk Version: SVN
Regression: No
SVN Branch (only for SVN checkouts, not tarball releases): 1.4
SVN Revision (number only!): 206273
Request Review:
======================================================================
Date Submitted: 2009-07-29 09:26 CDT
Last Modified: 2009-09-01 08:41 CDT
======================================================================
Summary: WARNING[23025]: channel.c:952 __ast_queue_frame:
Exceptionally long voice queue length queuing to Local
Description:
Since upgrading to 1.4 SVN 206273 I see LOTS these errors when paging or
when calls are processed by app_queue. When I see the messages during a
page I don't hear any paging (my Polycom phones continue to ring but no
paging audio).
I have no idea where the message is coming from how to reproduce, or
collect debug information for this specific issue. I need help to find
root cause.
I think it could be caused by locking in autoservice since I see this lock
every time I see the warning message
=== Currently Held Locks ==============================================
=======================================================================
===
=== <file> <line num> <function> <lock name> <lock addr> (times locked)
===
=== Thread ID: 3057154960 (autoservice_run started at [ 238]
autoservice.c ast_autoservice_start())
=== ---> Waiting for Lock https://issues.asterisk.org/view.php?id=0
(autoservice.c): MUTEX 89 autoservice_run
&(&aslist)->lock 0x81798c8 (1)
=== --- ---> Locked Here: autoservice.c line 89 (autoservice_run)
=== -------------------------------------------------------------------
======================================================================
Relationships ID Summary
----------------------------------------------------------------------
related to 0015109 [patch] Abort by memory allocator, poss...
======================================================================
----------------------------------------------------------------------
(0109888) aragon (reporter) - 2009-09-01 08:41
https://issues.asterisk.org/view.php?id=15609#c109888
----------------------------------------------------------------------
The CLI displays lots of these SIP dialog messages before and after the
locks.
Really destroying SIP dialog
'388df4933543d0cd1f4088ca18d95533 at 192.168.30.165' Method: OPTIONS
Really destroying SIP dialog
'2ce9de5a6cce8cfc5f8e0c293d10d42f at 192.168.30.165' Method: OPTIONS
Really destroying SIP dialog
'62a213322864fee614dede9742872a96 at 192.168.30.165' Method: OPTIONS
Really destroying SIP dialog
'40c642ba4875f3f76e1d1fc568056d86 at 192.168.30.165' Method: OPTIONS
Really destroying SIP dialog
'05e75c10329c38e7147a6e372276ed4a at 192.168.30.165' Method: OPTIONS
Really destroying SIP dialog
'6048208a0c4f028b29468ee47f1bc093 at 192.168.30.165' Method: OPTIONS
Really destroying SIP dialog
'7a1eb3581d202a9b5f622aa83a7d7fe8 at 192.168.30.165' Method: OPTIONS
-- Executing [h at default-application-acd-new-english:1]
Goto("SIP/siptrunk-b5ba72b0", "all-hangup|s|1") in new stack
-- Goto (all-hangup,s,1)
-- Executing [s at all-hangup:1] GotoIf("SIP/siptrunk-b5ba72b0",
"0?all-faxnotify|s|1:2") in new stack
-- Goto (all-hangup,s,2)
-- Executing [s at all-hangup:2] ResetCDR("SIP/siptrunk-b5ba72b0", "w")
in new stack
-- Executing [s at all-hangup:3] NoCDR("SIP/siptrunk-b5ba72b0", "") in
new stack
-- Executing [s at all-hangup:4] System("SIP/siptrunk-b5ba72b0",
"/var/www/scopserv/telephony/scripts/billing/cdr.sh 1251811800.23097") in
new stack
Really destroying SIP dialog
'68dfbcb92cdc4c79038aa09463d2c11e at 192.168.30.165' Method: OPTIONS
Really destroying SIP dialog
'066e4cf4238e779002f0a2383d6e2221 at 192.168.30.165' Method: OPTIONS
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
[Sep 1 09:31:31] WARNING[31458]: channel.c:951 __ast_queue_frame:
Exceptionally long voice queue length queuing to
Local/1620 at default-agent-4558,1
fireworx*CLI> core show locks
fireworx*CLI>
=======================================================================
=== Currently Held Locks ==============================================
=======================================================================
===
=== <file> <line num> <function> <lock name> <lock addr> (times locked)
===
=== Thread ID: 3062037392 (autoservice_run started at [ 238]
autoservice.c ast_autoservice_start())
=== ---> Waiting for Lock https://issues.asterisk.org/view.php?id=0
(autoservice.c): MUTEX 89 autoservice_run
&(&aslist)->lock 0x8178448 (1)
=== --- ---> Locked Here: autoservice.c line 89 (autoservice_run)
=== -------------------------------------------------------------------
Issue History
Date Modified Username Field Change
======================================================================
2009-09-01 08:41 aragon Note Added: 0109888
======================================================================
More information about the asterisk-bugs
mailing list