[asterisk-bugs] [Asterisk 0010342]: On Mac OS X PowerPC, Asterisk 1.4.x stops bridging new calls shortly after start

noreply at bugs.digium.com noreply at bugs.digium.com
Fri Jun 13 21:24:28 CDT 2008


A NOTE has been added to this issue. 
====================================================================== 
http://bugs.digium.com/view.php?id=10342 
====================================================================== 
Reported By:                jcovert
Assigned To:                putnopvut
====================================================================== 
Project:                    Asterisk
Issue ID:                   10342
Category:                   Core/Portability
Reproducibility:            always
Severity:                   major
Priority:                   normal
Status:                     assigned
Asterisk Version:           1.6.0-beta8 
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Disclaimer on File?:        N/A 
Request Review:              
====================================================================== 
Date Submitted:             07-31-2007 09:10 CDT
Last Modified:              06-13-2008 21:24 CDT
====================================================================== 
Summary:                    On Mac OS X PowerPC, Asterisk 1.4.x stops bridging
new calls shortly after start
Description: 
I have unsuccessfully tried to get every 1.4.x version of Asterisk to work
on Mac OS X PowerPC, most recently Asterisk 1.4.9 on Mac OS X 10.4.10.

Asterisk starts up fine, and works for a short while.  But after about the
third or fourth bridged call (local sip soft client, SJphone, or locally
connected SIP ATAs Cisco ATA 186), RTP read too short errors start
ocurring, or other dead call problems.  It is still possible to connect to
IVRs within asterisk, and inbound IAX2 calls from a 1.2.22 version of
Asterisk to the 1.4.9 version still work, but originating SIP devices are
unable to reliably make outbound calls.
====================================================================== 

---------------------------------------------------------------------- 
 jcovert - 06-13-08 21:24  
---------------------------------------------------------------------- 
The time I had it up and running all night using Darwin's poll(), I might
have just been lucky (or unlucky, depending on how you look at it).  I can
now make it hang in almost every case with an incoming IAX2 call.

I decided to throw in some debug output into the emulated poll() after it
makes the select() call.

We start up idle, and occasionally some I/O happens; and I'm not sure
whether the occasional EINTRs are a problem or not.

[Jun 13 22:19:29] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1824400,
[Jun 13 22:19:36] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
[Jun 13 22:19:36] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:19:49] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1824400,
[Jun 13 22:19:56] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
[Jun 13 22:19:56] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:06] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
[Jun 13 22:20:06] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
[Jun 13 22:20:06] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:06] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
[Jun 13 22:20:06] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:09] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1824400,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,

Then I throw a call at it:

    -- Accepting AUTHENTICATED call from 192.168.0.100:
       > requested format = ulaw,
       > requested prefs = (ulaw|alaw|g726|gsm|ilbc),
       > actual format = ulaw,
       > host prefs = (),
       > priority = caller
[Jun 13 22:20:10] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
    -- Executing [12640001 at inbound-cnet-264:1] Answer("IAX2/mainpbx-8555",
"300") in new stack

[Jun 13 22:20:10] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,

Reasonably normal for a while, I think...

[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf760,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf760,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf760,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf760,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:10] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf760,

Things look normal here, so we'll skip a bit and continue:

[Jun 13 22:20:11] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf760,
    -- Executing [12640001 at inbound-cnet-264:2] NoOp("IAX2/mainpbx-8555",
"") in new stack
    -- Executing [12640001 at inbound-cnet-264:3]
Playback("IAX2/mainpbx-8555", "/Users/jcovert/asterisk/laptop-pbx") in new
stack
    -- <IAX2/mainpbx-8555> Playing '/Users/jcovert/asterisk/laptop-pbx'
(language 'en')
[Jun 13 22:20:11] WARNING[25154]: poll.c:308 poll: select errno=4,
pArray=1814c00,
[Jun 13 22:20:11] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:11] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf5f0,
[Jun 13 22:20:11] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:11] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf5f0,

This goes on for a while...

[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf5f0,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf5f0,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=f04bf5f0,

... and then suddenly the output stops, and we are in a solid loop of:

[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,
[Jun 13 22:20:13] WARNING[25154]: poll.c:310 poll: ready=1,
pArray=1814c00,

and this continues until I killall -9 asterisk. 

Issue History 
Date Modified   Username       Field                    Change               
====================================================================== 
06-13-08 21:24  jcovert        Note Added: 0088708                          
======================================================================




More information about the asterisk-bugs mailing list