[asterisk-bugs] [Asterisk 0014723]: ERROR[5003]: channel.c:2043 __ast_read: ast_read() called with no recorded file descriptor.

[Asterisk Bug Tracker]

A NOTE has been added to this issue. 
====================================================================== 
http://bugs.digium.com/view.php?id=14723 
====================================================================== 
Reported By:                seadweller
Assigned To:                mmichelson
====================================================================== 
Project:                    Asterisk
Issue ID:                   14723
Category:                   Core/Channels
Reproducibility:            always
Severity:                   block
Priority:                   normal
Status:                     ready for testing
Target Version:             1.4.25
Asterisk Version:           1.4.24 
Regression:                 No 
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Request Review:              
====================================================================== 
Date Submitted:             2009-03-23 08:27 CDT
Last Modified:              2009-04-09 19:26 CDT
====================================================================== 
Summary:                    ERROR[5003]: channel.c:2043 __ast_read: ast_read()
called with no recorded file descriptor.
Description: 
Performed an upgrade from 1.4.22 to 1.4.24 running on CentOs 4.  Used the
same "slim" configuration that I used in 1.4.22, which worked fine.

Calls are processing, but connecting to the console gets the following:

[Mar 23 13:08:05] ERROR[5003]: channel.c:2043 __ast_read: ast_read()
called with no recorded file descriptor.
[Mar 23 13:08:05] ERROR[5003]: channel.c:2043 __ast_read: ast_read()
called with no recorded file descriptor.

This repeats continuously.  If I issue a show channels, I get some output
and it seems to stop the ERROR messages, though it seems the "show
channels" does not complete.  You can get the CLI command and * will still
be trying to spit out a few channels below it.

The system load is also very high (2.38 with 50 or so concurrent calls),
though CPU usage is low... it must be coming from somewhere else. 
Typically this load under 1.4.22 would show perhaps a 0.2 or 0.3 load.


======================================================================
Relationships       ID      Summary
----------------------------------------------------------------------
has duplicate       0014866 100% cpu problem with channel hangup
has duplicate       0014868 I have 100% CPU usage with a few calls
====================================================================== 

---------------------------------------------------------------------- 
 (0103059) falves11 (reporter) - 2009-04-09 19:26
 http://bugs.digium.com/view.php?id=14723#c103059 
---------------------------------------------------------------------- 
I run ps axum -C asterisk and get the thread id or process id that shows
the highest value in CPU. Then I grab that number and run gdb
/usr/sbin/asterisk pid, and I gte this:

(gdb) bt full
http://bugs.digium.com/view.php?id=0  0x00000039c94c92e6 in poll () from
/lib64/libc.so.6
No symbol table info available.
http://bugs.digium.com/view.php?id=1  0x000000000043df17 in canary_thread
(unused=0x71ba0000) at
asterisk.c:3051
        canary_stat = {st_dev = 0, st_ino = 46912519077056, st_nlink = 0,
st_mode = 1908015104, st_uid = 0, st_gid = 1582507856, pad0 = 32767,
st_rdev = 4720793, st_size = 0, st_blksize = 248190374611, st_blocks =
1908015104,
  st_atim = {tv_sec = 140734775895936, tv_nsec = 0}, st_mtim = {tv_sec =
4448023, tv_nsec = 140734775895936}, st_ctim = {tv_sec = 0, tv_nsec =
73014444032}, __unused = {8320864, 4294967307, 17}}
        now = {tv_sec = 140734775895824, tv_usec = 4699371}
        __PRETTY_FUNCTION__ = "Invalid Entity"
http://bugs.digium.com/view.php?id=2  0x000000000044032a in ao2_bt () at
astobj2.c:94
        c = 1582509696
        i = 32767
        addresses = {0x698fe90, 0x7fff5e532c91, 0x11, 0x57bf10, 0x14,
0x7fff5e532c90, 0x7fff5e532d30, 0x41ad8b, 0x7fff5e532d50, 0x57bec6,
0x2ada4d457fd0, 0x39c921abc0, 0x39c9750720, 0x1004197c3, 0x39c9750820,
0x69926c4,
  0x7fff5e532dc0, 0x82ff20, 0x82ef20, 0x0}
        strings = (char **) 0x39c921abc0
        __PRETTY_FUNCTION__ = "\000\000\216ãC\000"
http://bugs.digium.com/view.php?id=3  0x00000039c941d8b4 in __libc_start_main ()
from /lib64/libc.so.6
No symbol table info available.
http://bugs.digium.com/view.php?id=4  0x000000000041acc9 in jb_choose_impl
(chan=Cannot access memory at
address 0xffffffffffffffc8
) at abstract_jb.c:181
        jb = (struct ast_jb *) Cannot access memory at address
0xffffffffffffffd8
(gdb) bt
http://bugs.digium.com/view.php?id=0  0x00000039c94c92e6 in poll () from
/lib64/libc.so.6
http://bugs.digium.com/view.php?id=1  0x000000000043df17 in canary_thread
(unused=0x71ba0000) at
asterisk.c:3051
http://bugs.digium.com/view.php?id=2  0x000000000044032a in ao2_bt () at
astobj2.c:94
http://bugs.digium.com/view.php?id=3  0x00000039c941d8b4 in __libc_start_main ()
from /lib64/libc.so.6
http://bugs.digium.com/view.php?id=4  0x000000000041acc9 in jb_choose_impl
(chan=Cannot access memory at
address 0xffffffffffffffc8
) at abstract_jb.c:181
Previous frame inner to this frame (corrupt stack?) 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2009-04-09 19:26 falves11       Note Added: 0103059                          
======================================================================