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

Tue Jul 13 15:23:11 CDT 2010

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:              2010-07-13 15:23 CDT
====================================================================== 
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 &amp; CPU load 100% when ...
related to          0017625 &quot;I should never be called!&quot; m...
====================================================================== 

---------------------------------------------------------------------- 
 (0124534) jcovert (reporter) - 2010-07-13 15:23
 https://issues.asterisk.org/view.php?id=17521#c124534 
---------------------------------------------------------------------- 
With a fairly heavy additional (non-asterisk) upload, I can still get:

    -- Executing [13777777 at cnetlookup:13] Dial("SIP/x28-00000207",
"IAX2/cnetguest at weco355a.dyndns.org/13777777,120") in new stack
    -- Called cnetguest at weco355a.dyndns.org/13777777
    -- Call accepted by 64.25.152.29 (format ulaw)
    -- Format for call is ulaw
    -- IAX2/64.25.152.29:4569-8938 answered SIP/x28-00000207
[Jul 13 16:19:54] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:19:54] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:19:54] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:19:54] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:20:00] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:20:00] NOTICE[21633]: chan_iax2.c:10424 socket_process: Peer
'x38' is now TOO LAGGED (2256 ms)!
[Jul 13 16:20:00] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:20:12] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:20:12] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:20:12] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938
[Jul 13 16:20:12] WARNING[21633]: channel.c:1044 __ast_queue_frame:
Exceptionally long voice queue length queuing to
IAX2/64.25.152.29:4569-8938

and G.722 transcoding does not seem to be required, but it does make it
worse.

Two calls still seems to make it better. 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2010-07-13 15:23 jcovert        Note Added: 0124534                          
======================================================================