[asterisk-bugs] [Asterisk 0017521]: Brief lagginess on IAX2 channels is fatal

[Asterisk Bug Tracker]

A NOTE has been added to this issue. 
====================================================================== 
https://issues.asterisk.org/view.php?id=17521 
====================================================================== 
Reported By:                jcovert
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   17521
Category:                   Channels/chan_iax2
Reproducibility:            always
Severity:                   major
Priority:                   normal
Status:                     acknowledged
Asterisk Version:           1.6.2.8 
JIRA:                       SWP-1718 
Regression:                 No 
Reviewboard Link:            
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Request Review:              
====================================================================== 
Date Submitted:             2010-06-17 10:32 CDT
Last Modified:              2011-01-27 15:36 CST
====================================================================== 
Summary:                    Brief lagginess on IAX2 channels is fatal
Description: 
The problem reported in issue https://issues.asterisk.org/view.php?id=15609 and
https://issues.asterisk.org/view.php?id=15900 continues:

[Jun 17 09:55:14] WARNING[15579]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to IAX2/x38-9660
[Jun 17 09:57:54] WARNING[15579]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to IAX2/x38-9660
[Jun 17 09:57:54] WARNING[15579]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to IAX2/x38-9660
[Jun 17 09:58:07] NOTICE[15579]: chan_iax2.c:11447 __iax2_poke_noanswer:
Peer 'x29' is now UNREACHABLE! Time: 2
[Jun 17 09:58:07] NOTICE[15579]: chan_iax2.c:11447 __iax2_poke_noanswer:
Peer 'jrclaptop' is now UNREACHABLE! Time: 4
[Jun 17 09:58:07] NOTICE[15579]: chan_iax2.c:11447 __iax2_poke_noanswer:
Peer 'x38' is now UNREACHABLE! Time: 136
[Jun 17 09:58:10] WARNING[15579]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to IAX2/x38-9660
[Jun 17 09:58:10] WARNING[15579]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to IAX2/x38-9660
[Jun 17 09:58:10] WARNING[15579]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to IAX2/x38-9660
    -- Hungup 'IAX2/x38-9660'
  == Spawn extension (dialstation, 38, 1) exited non-zero on
'SIP/x28-0000048b'
[Jun 17 09:58:12] NOTICE[15579]: chan_iax2.c:10418 socket_process: Peer
'x29' is now REACHABLE! Time: 2
[Jun 17 09:58:12] NOTICE[15579]: chan_iax2.c:10418 socket_process: Peer
'jrclaptop' is now REACHABLE! Time: 4
[Jun 17 09:58:12] NOTICE[15579]: chan_iax2.c:10418 socket_process: Peer
'x38' is now REACHABLE! Time: 137

This tends to reproduce for me only on transatlantic calls.

/john

======================================================================
Relationships       ID      Summary
----------------------------------------------------------------------
related to          0015609 [patch] WARNING[23025]: channel.c:952 _...
related to          0015900 Console flood & CPU load 100% when ...
related to          0017625 "I should never be called!" m...
related to          0018511 [patch] Asterisk hangs with no calls be...
====================================================================== 

---------------------------------------------------------------------- 
 (0131139) seanbright (manager) - 2011-01-27 15:36
 https://issues.asterisk.org/view.php?id=17521#c131139 
---------------------------------------------------------------------- 
Not disputing your findings, but with the latest from the 1.8 branch I am
not able to replicate in my environment:

<pre>
    -- Launching dial(IAX2/guest at 10.27.0.29/ringnoanswer) on
SIP/P0004F22457B2-00000001
    -- Called guest at 10.27.0.29/ringnoanswer
    -- Accepting UNAUTHENTICATED call from 10.27.0.29:
       > requested format = gsm,
       > requested prefs = (),
       > actual format = gsm,
       > host prefs = (),
       > priority = mine
    -- Call accepted by 10.27.0.29 (format gsm)
    -- Format for call is gsm
    -- Executing [ringnoanswer at default:1]
Progress("IAX2/10.27.0.29:4569-1426", "") in new stack
    -- Executing [ringnoanswer at default:2]
NoOp("IAX2/10.27.0.29:4569-1426", ""Guest IAX User" <>") in new stack
    -- Executing [ringnoanswer at default:3]
PlayTones("IAX2/10.27.0.29:4569-1426", "440+495/2000,0/4000") in new stack
    -- IAX2/10.27.0.29:4569-925 is making progress passing it to
SIP/P0004F22457B2-00000001
    -- Executing [ringnoanswer at default:4]
Wait("IAX2/10.27.0.29:4569-1426", "120") in new stack
    -- Executing [ringnoanswer at default:5]
Hangup("IAX2/10.27.0.29:4569-1426", "") in new stack
  == Spawn extension (default, ringnoanswer, 5) exited non-zero on
'IAX2/10.27.0.29:4569-1426'
    -- Hungup 'IAX2/10.27.0.29:4569-1426'
    -- Hungup 'IAX2/10.27.0.29:4569-925'
    -- No one is available to answer at this time (1:0/0/0)</pre>
What timing source are you using?  Any other factors that may be affecting
this? 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2011-01-27 15:36 seanbright     Note Added: 0131139                          
======================================================================