[Asterisk-video] app_rtsp: asterisk hangup

Tue Sep 11 11:21:56 CDT 2007

OK I understand this... But don't you think we should check the buffer
periodically and maybe clear it or even hangup before it is too late?

I just reproduced the problem two times by looping four videos (each ~10
seconds) all the time with app_rtsp. After about five or six minutes the
problem occured again (the errors and 100% CPU load). Since the videos
were played correctly for about ten times before the crash, maybe our
guess about the too high framerate is incorrect.

How can I find out which queue it is exactly which causes the problem
and maybe see when it is filled up?

BR,
Thomas

PS: here are some logs:

[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -Received rtcp [164]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -Received rtcp length [84]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -rtcp type [200,28]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -rtcp type [202,56]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -rtcp type [204,84]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -Started playback [10000]
[Sep 11 17:34:22] ERROR[30677] app_rtsp.c: -Received rtcp [164]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -Received rtcp length [84]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -rtcp type [200,28]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -rtcp type [202,56]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -rtcp type [204,84]
[Sep 11 17:34:15] ERROR[30677] app_rtsp.c: -Started playback [10000]
[Sep 11 17:34:22] ERROR[30677] app_rtsp.c: -Received rtcp [164]
[Sep 11 17:34:22] ERROR[30677] app_rtsp.c: -Received rtcp length [84]
[Sep 11 17:34:22] ERROR[30677] app_rtsp.c: -rtcp type [200,28]
[Sep 11 17:34:22] ERROR[30677] app_rtsp.c: -rtcp type [202,56]
[Sep 11 17:34:22] ERROR[30677] app_rtsp.c: -rtcp type [204,84]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: Playback finished
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -rtsp_play end loop[Sep 11
17:34:25] WARNING[30677] app_rtsp.c: -TEARDOWN
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: <rtsp_play[Sep 11 17:34:25]
WARNING[30677] app_rtsp.c: >rtsp play
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: >DESCRIBE
[/static/intro-b.mp4]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: <DESCRIBE
[/static/intro-b.mp4]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -rtsp play loop
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -Receiving describe
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [v=0]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [o=StreamingServer
3398513665 1189517873000 IN IP4 127.0.0.1]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line
[s=/static/intro-b.mp4]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [u=http:///]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [e=admin@]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [c=IN IP4 0.0.0.0]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [b=AS:29]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [t=0 0]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=control:*]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=x-copyright:
MP4/3GP File hinted with GPAC 0.4.5-DEV (C)2000-2005 -
http://gpac.sourceforge.net]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=range:npt=0-
10.00000]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [m=video 0 RTP/AVP
96]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -creating media [1,m=video
0 RTP/AVP 96]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=control:trackID=2]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=rtpmap:96
H263-2000/15]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line
[a=cliprect:0,0,144,176]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [Content-Base:
rtsp://127.0.0.1/static/intro-b.mp4/]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [v=0]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [o=StreamingServer
3398513665 1189517873000 IN IP4 127.0.0.1]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line
[s=/static/intro-b.mp4]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [u=http:///]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [e=admin@]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [c=IN IP4 0.0.0.0]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [b=AS:29]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [t=0 0]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=control:*]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=x-copyright:
MP4/3GP File hinted with GPAC 0.4.5-DEV (C)2000-2005 -
http://gpac.sourceforge.net]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=range:npt=0-
10.00000]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [m=video 0 RTP/AVP
96]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -creating media [1,m=video
0 RTP/AVP 96]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=control:trackID=2]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line [a=rtpmap:96
H263-2000/15]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -line
[a=cliprect:0,0,144,176]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -video
[1048576,96,trackID=2]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -SETUP VIDEO [trackID=2]
[Sep 11 17:34:25] WARNING[30677] app_rtsp.c: -PLAY [/static/intro-b.mp4]
[Sep 11 17:34:25] ERROR[30677] app_rtsp.c: -Started playback [10000]
[Sep 11 17:34:25] ERROR[30677] app_rtsp.c: -Received rtcp [166]
[Sep 11 17:34:25] ERROR[30677] app_rtsp.c: -Received rtcp length [84]
[Sep 11 17:34:25] ERROR[30677] app_rtsp.c: -rtcp type [200,28]
[Sep 11 17:34:25] ERROR[30677] app_rtsp.c: -rtcp type [202,56]
[Sep 11 17:34:25] ERROR[30677] app_rtsp.c: -rtcp type [204,84]
[Sep 11 17:34:32] ERROR[30677] app_rtsp.c: -Received rtcp [166]
[Sep 11 17:34:32] ERROR[30677] app_rtsp.c: -Received rtcp length [84]
[Sep 11 17:34:32] ERROR[30677] app_rtsp.c: -rtcp type [200,28]
[Sep 11 17:34:32] ERROR[30677] app_rtsp.c: -rtcp type [202,56]
[Sep 11 17:34:32] ERROR[30677] app_rtsp.c: -rtcp type [204,84]
[Sep 11 17:34:35] WARNING[30677] app_rtsp.c: Playback finished
[Sep 11 17:34:35] WARNING[30677] app_rtsp.c: -rtsp_play end loop[Sep 11
17:34:35] WARNING[30677] app_rtsp.c: -TEARDOWN
[Sep 11 17:34:35] WARNING[30677] app_rtsp.c: <rtsp_play[Sep 11 17:34:35]
WARNING[30677] app_rtsp.c: >rtsp play
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[Sep 11 17:34:36] WARNING[30676] channel.c: Exceptionally long queue
length queuing to Local/stream at video-9710,2
[...]

Am Dienstag, den 11.09.2007, 16:53 +0200 schrieb Sergio Garcia:
> 
> It could be possible to implement it, but you'll have to drop all the frames
> until the next I frame. And if the bandwith is so high you'll end up droping
> almost instantaneously the next frames. And even that way the I frame is going
> to arrive late and not be synchronized with audio.. 
> 
> So if you want to offer any kind of quality service the only option is using 
> videos encoded at the best bitrate according it's use. You may even find that
> you have to encode the same video at different bitrates for different kind of
> access.
> 
> BR
> Sergio
> 
> ---------- Original Message ----------------------------------
> From: Thomas Frieling <thomas.frieling at viif.de>
> Reply-To: Development discussion of video media support in Asterisk<asterisk-video at lists.digium.com>
> Date:  Tue, 11 Sep 2007 16:44:52 +0200
> 
> >Hi Klaus!
> >
> >Sounds pretty convincing to me... But if this is really the case, then
> >we should definitely handle this problem before it occurs: Maybe we can
> >check the queue's size before sending a package and decide to skip some
> >packages to synchronize the outgoing stream.
> >This would help keeping sound and video synchronous and it would prevent
> >the "exceptionally long queue" error.
> >
> >What do you think about this? Would it be hard to implement?
> >
> >Greetings,
> >Thomas
> >
> >Am Montag, den 10.09.2007, 09:35 +0200 schrieb Klaus Darilion:
> >> Thomas Frieling schrieb:
> >> > Hi all!
> >> > 
> >> > When using app_rtsp with a 3G handset extensively asterisk starts
> >> > consuming 100% of the CPU and the following error appears very often
> >> > (~80 times a second) on the CLI:
> >> > 
> >> > [Sep  7 12:52:55] WARNING[11822]: channel.c:924 ast_queue_frame:
> >> > Exceptionally long queue length queuing to Local/stream at video-bad8,2
> >> > 
> >> > Does anyone know what this warning means or where it comes from? Which
> >> > logfile should I (or anyone who'd like to) take a look at?
> >> 
> >> 
> >> I guess the framerate received from app_rtsp is bigger then the 
> >> framerate received from the h324m connection. Every time h324m_gw reads 
> >> a frame from the incoming 64kbit data call, it writes an frame to the 
> >> data call too.
> >> 
> >> Thus if app_rtsp feeds to many frames the queue buffer might grow.
> >> 
> >> btw: shouldn't the pseudo channel (Local/..) be gone when app_rtsp 
> >> answers the call?
> >> 
> >> regards
> >> klaus
> >> 
> >> _______________________________________________
> >> --Bandwidth and Colocation Provided by http://www.api-digital.com--
> >> 
> >> asterisk-video mailing list
> >> To UNSUBSCRIBE or update options visit:
> >>    http://lists.digium.com/mailman/listinfo/asterisk-video
> >-- 
> >www.ViiF.de - your Mobile Video Community
> >
> >~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> >
> >Thomas Frieling - IT Development
> >ViiF Mobile Video GmbH, Poststr. 21-22, 10178 Berlin 
> >Cell: +49 (0) 173 63 62 62 3
> >
> >mailto:thomas at ViiF.de
> >
> >Sitz: Berlin, Amtgericht Berlin-Charlottenburg, HRB: 108350B
> >
> >GeschÃ¤ftsfÃ¼hrer: Daniel HÃ¶pfner, Steffen BrÃ¼nn
> >
> >~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> >
> >_______________________________________________
> >--Bandwidth and Colocation Provided by http://www.api-digital.com--
> >
> >asterisk-video mailing list
> >To UNSUBSCRIBE or update options visit:
> >   http://lists.digium.com/mailman/listinfo/asterisk-video
>  
> 
> 
> _______________________________________________
> --Bandwidth and Colocation Provided by http://www.api-digital.com--
> 
> asterisk-video mailing list
> To UNSUBSCRIBE or update options visit:
>    http://lists.digium.com/mailman/listinfo/asterisk-video
-- 
www.ViiF.de - your Mobile Video Community

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~

Thomas Frieling - IT Development
ViiF Mobile Video GmbH, Poststr. 21-22, 10178 Berlin 
Cell: +49 (0) 173 63 62 62 3

mailto:thomas at ViiF.de

Sitz: Berlin, Amtgericht Berlin-Charlottenburg, HRB: 108350B

Geschäftsführer: Daniel Höpfner, Steffen Brünn

~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~