[asterisk-bugs] [Asterisk 0015609]: [patch] WARNING[23025]: channel.c:952 __ast_queue_frame: Exceptionally long voice queue length queuing to Local
Asterisk Bug Tracker
noreply at bugs.digium.com
Thu Oct 8 08:15:06 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: block
Priority: normal
Status: confirmed
Target Version: 1.4.28
Asterisk Version: SVN
JIRA: SWP-205
Regression: No
Reviewboard Link:
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-10-08 08:15 CDT
======================================================================
Summary: [patch] 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
----------------------------------------------------------------------
duplicate of 0015976 abort in filestream_destructor / ast_fi...
related to 0015109 [patch] Abort by memory allocator, poss...
has duplicate 0015900 Console flood & CPU load 100% when ...
related to 0015817 crash in local_attended_transfer, likel...
related to 0015845 Crash during attended transfer occurs
related to 0015616 random crashes
======================================================================
----------------------------------------------------------------------
(0112052) aragon (reporter) - 2009-10-08 08:15
https://issues.asterisk.org/view.php?id=15609#c112052
----------------------------------------------------------------------
Results after 3 days of testing SVN-branch-1.4-r222152M patched with
filestream_frisolate-1.4.diff2.txt
-No more memory leak during reload
-No more CPU increase over time caused by reload
-No more crash in filestream
Bad news
-ast_queue_frame: Exceptionally long voice queue length queuing to Local
is still very frequent (every 30-60 seconds)
-This lock is still very frequent and appears related to hangup event
(possibly related to destroying SIP dialogs based on CLI events surrounding
this lock)
-- Executing [s at all-hangup:1] GotoIf("SIP/siptrunk-09e1c438",
"0?all-faxnotify|s|1:2") in new stack
-- Goto (all-hangup,s,2)
-- Executing [s at all-hangup:2] ResetCDR("SIP/siptrunk-09e1c438", "w")
in new stack
-- Executing [s at all-hangup:3] NoCDR("SIP/siptrunk-09e1c438", "") in
new stack
-- Executing [s at all-hangup:4] System("SIP/siptrunk-09e1c438",
"/var/www/scopserv/telephony/scripts/billing/cdr.sh 1255006969.57632") in
new stack
scrubbed*CLI> core show locks
scrubbed*CLI>
=======================================================================
=== Currently Held Locks ==============================================
=======================================================================
===
=== <file> <line num> <function> <lock name> <lock addr> (times locked)
===
=== Thread ID: -1235027056 (autoservice_run started at [ 238]
autoservice.c ast_autoservice_start())
=== ---> Lock https://issues.asterisk.org/view.php?id=0 (autoservice.c): MUTEX
89 autoservice_run
&(&aslist)->lock 0x81797a8 (1)
=== -------------------------------------------------------------------
===
=======================================================================
Really destroying SIP dialog '265d10a72082ace36d707d1d5dcc519f at 127.0.0.1'
Method: REGISTER
Really destroying SIP dialog '6fda56727fcb6f7328cd0af159fde10d at 127.0.0.1'
Method: REGISTER
Really destroying SIP dialog '1aff780a6c1956ce575991ec202f712b at 127.0.0.1'
Method: REGISTER
Really destroying SIP dialog '19e2afab3e262eed65ac06eb682baa1d at 127.0.0.1'
Method: REGISTER
Really destroying SIP dialog '492ce9c433393bfc733c61cf7fee4a34 at 127.0.0.1'
Method: REGISTER
Really destroying SIP dialog '56a0b20e6d3a07dc6a02f44367faef4d at 127.0.0.1'
Method: REGISTER
There are relevant debug infos for those warnings in my previous notes on
this bug report.
They are:
15609sipdebug_cli14082009.txt
15609threadapplyall_locked in autoservice_line89.txt
15609threadapplyallbt_coreshowlocks10082009.txt
15609_13082009_cli_output with_lock_debug.txt
Issue History
Date Modified Username Field Change
======================================================================
2009-10-08 08:15 aragon Note Added: 0112052
======================================================================
More information about the asterisk-bugs
mailing list