[linuxtv-softmpeg] softmpeg : jerky video / sound

To: linuxtv-softmpeg@linuxtv.org
Subject: [linuxtv-softmpeg] softmpeg : jerky video / sound
From: Nicolas Huillard <nhuillard@ghs.fr>
Date: Tue, 22 Jun 2004 17:31:30 +0200
Content-transfer-encoding: 7bit
Content-type: text/plain; charset=us-ascii; format=flowed
List-help: <mailto:ecartis@linuxtv.org?subject=help>
List-id: linuxtv-softmpeg <linuxtv-softmpeg.elisten>
List-owner: <mailto:listmaster@convergence.de>
List-post: <mailto:linuxtv-softmpeg@linuxtv.org>
List-software: Ecartis version 1.0.0
List-subscribe: <mailto:ecartis@linuxtv.org?subject=subscribe%20linuxtv-softmpeg>
List-unsubscribe: <mailto:ecartis@linuxtv.org?subject=unsubscribe%20linuxtv-softmpeg>
Reply-to: linuxtv-softmpeg@linuxtv.org
Sender: linuxtv-softmpeg-bounce@linuxtv.org
User-agent: Mozilla/5.0 (Windows; U; Win98; en-US; rv:1.5) Gecko/20031007

Hello,

I basically have delay problems with softmpeg / libsoftmpeg. Here is all the info I have.
Setup :
* DirectFB 0.9.21 from CVS 20040616
* FusionSound 0.9.21 from CVS 20040616
* VDR 1.2.6-19 from Tobias Grimm
* SoftMPEG from CVS 20040616
* all self-compiled and Debian packaged
* Debian testing / kernel 2.4.26
* ffmpeg 0.4.8

I undef'd SOFTMPEG_ENABLE_DEBUG in "./src/internal.h" to eliminate all SOFTMPEG_DEBUG, SOFTMPEG_ERROR and SOFTMPEG_WARN messages and be sure the problem didn't simply came from too many messages.

I now have the following two VDR processes eating 10% CPU, with VDR tuned to the following FTA channel (35?*288 pixel, low bandwith channel):
Meteo Express :10911:v:S13E:27500:5820:5830:0:0:5800:0:0:0

21574 root 14 0 59140 9984 2252 R 5.0 4.5 3:22.65 vdr
21568 root 14 0 59140 9984 2252 S 3.6 4.5 4:07.71 vdr

Here is the VDR output at startup :

[softmpeg] initializing Plugin
[softmpeg] cSoftMPEG
(*) DirectFB/Config: Parsing config file '/etc/directfbrc'.

---------------------- DirectFB v0.9.21 ---------------------
(c) 2000-2002 convergence integrated media GmbH
(c) 2002-2004 convergence GmbH
-----------------------------------------------------------

(*) DirectFB/Core: Single Application Core. (with MMX support) (2004-06-16 21:46)
(*) Direct/Memcpy: Using SSE optimized memcpy()
(*) DirectFB/Core: doing sync()...
(*) Direct/Modules: suppress module 'keyboard'
(*) Direct/Modules: suppress module 'lirc'
(*) DirectFB/Genefx: MMX detected and enabled
(*) DirectFB/Graphics: VIA/S3G UniChrome 0.3 (-)
(*) DirectFB/WM: Default 0.1 (Convergence GmbH)
(!!!) *** WARNING [no video mode set yet] *** [../../../src/core/layers.c:536 in dfb_screen_rectangle()]
using videolayer
(*) Direct/Interface: Loaded 'default' implementation of 'IFusionSound'.
(*) Direct/Thread: Running 'Sound Mixer' (CRITICAL, 21570)...
[softmpeg] CanReplay()
[softmpeg] CanReplay()
[softmpeg] SetPlayMode()
setting Playmode to 1
[softmpeg] NewOsd()
[softmpeg] OSD @ 66 x 369
[softmpeg] OpenWindow(), handle:0, x:0, y:0, w:600, h:135
[softmpeg] ShowWindow(), 0
[softmpeg] OSD off
[softmpeg] releasing osd

And this is what regularly apprears on VDR's output. I think the added delays explain why the video is so jerky (and could explain the low CPU usage).

+00:01:20.062<=> +00:00:00.397
delaying 464 ms
delaying 348 ms
+00:01:20.062<=> +00:00:00.397
delaying 379 ms
delaying 348 ms
+00:01:20.062<=> +00:00:00.397
delaying 464 ms
delaying 348 ms
delaying 273 ms
delaying 241 ms
+00:01:20.062<=> +00:00:00.397
delaying 464 ms
delaying 348 ms
delaying 315 ms
delaying 284 ms
+00:01:20.062<=> +00:00:00.397
+00:01:20.062<=> +00:00:00.397
+00:01:20.062<=> +00:00:00.397
delaying 257 ms
ac-tex damaged at 14 6
ac-tex damaged at 1 7
concealing errors
Warning MVs not available
invalid mb type in P Frame at 2 12
concealing errors
+00:01:20.062<=> +00:00:00.397
delaying 379 ms
delaying 348 ms
delaying 273 ms
delaying 241 ms
+00:01:20.062<=> +00:00:00.397
delaying 348 ms
+00:01:20.062<=> +00:00:00.397
delaying 336 ms
delaying 348 ms
delaying 273 ms
delaying 241 ms
+00:01:20.062<=> +00:00:00.397
+00:01:20.062<=> +00:00:00.397
delaying 464 ms
delaying 348 ms
delaying 273 ms
delaying 284 ms
+00:01:20.062<=> +00:00:00.397
+00:01:20.062<=> +00:00:00.397

Here is the full output (it is really huge) :

[softmpeg] initializing Plugin
[softmpeg] cSoftMPEG
(*) DirectFB/Config: Parsing config file '/etc/directfbrc'.

---------------------- DirectFB v0.9.21 ---------------------
(c) 2000-2002 convergence integrated media GmbH
(c) 2002-2004 convergence GmbH
-----------------------------------------------------------

(*) DirectFB/Core: Single Application Core. (with MMX support) (2004-06-16 21:46)
(*) Direct/Memcpy: Using SSE optimized memcpy()
(*) DirectFB/Core: doing sync()...
(*) Direct/Modules: suppress module 'keyboard'
(*) Direct/Modules: suppress module 'lirc'
(*) DirectFB/Genefx: MMX detected and enabled
(*) DirectFB/Graphics: VIA/S3G UniChrome 0.3 (-)
(*) DirectFB/WM: Default 0.1 (Convergence GmbH)
(!!!) *** WARNING [no video mode set yet] *** [../../../src/core/layers.c:536 in dfb_screen_rectangle()]
using videolayer
{-} [23924: 0.000] SoftMPEG/init/setting up DirectFB: layer_id:1 window_id:-1
{-} [23924: 0.002] SoftMPEG/init/using layer configuration for video output via DirectFB
(*) Direct/Interface: Loaded 'default' implementation of 'IFusionSound'.
(*) Direct/Thread: Running 'Sound Mixer' (CRITICAL, 23931)...
[softmpeg] CanReplay()
[softmpeg] CanReplay()
[softmpeg] SetPlayMode()
setting Playmode to 1
[softmpeg] NewOsd()
[softmpeg] OSD @ 66 x 369
[softmpeg] OpenWindow(), handle:0, x:0, y:0, w:600, h:135
[softmpeg] ShowWindow(), 0
{-} [23935: 1.970] SoftMPEG/reconfigure/changing audio configuration: buffer_size:64000, sample_rate:44100, channels:2
{!} [23935: 3.633] SoftMPEG - WARNING in audio_manage at audio.c:340: providing audio data too fast, at least delay 379 ms
delaying 379 ms
{!} [23935: 4.221] SoftMPEG - WARNING in audio_manage at audio.c:340: providing audio data too fast, at least delay 304 ms
delaying 304 ms
{-} [23929: 4.548] SoftMPEG/resize/changing layer configuration, w:352, h:288
{!} [23935: 4.794] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 4.823] SoftMPEG - WARNING in audio_manage at audio.c:340: providing audio data too fast, at least delay 230 ms
delaying 230 ms
[softmpeg] OSD off
[softmpeg] releasing osd
{!} [23935: 5.910] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 5.911] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 6.464] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 7.024] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 7.047] SoftMPEG - WARNING in audio_playout at audio.c:251: falling below low mark (3016/3333)
{!} [23935: 7.056] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 7.057] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 7.058] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 7.616] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 8.171] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 8.214] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 8.215] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 8.217] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 8.218] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 8.771] SoftMPEG - WARNING in audio_playout at audio.c:251: falling below low mark (2500/3333)
{!} [23935: 9.293] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 9.309] SoftMPEG - WARNING in audio_playout at audio.c:251: falling below low mark (3009/3333)
{!} [23935: 9.863] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11554/64000]
{!} [23935: 9.870] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 9.871] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 9.873] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 9.874] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 9.875] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 10.450] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 11.000] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 11.028] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 11.030] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 11.032] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 11.032] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 11.034] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 11.035] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed
{!} [23935: 11.592] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 11.607] SoftMPEG - WARNING in audio_playout at audio.c:251: falling below low mark (3009/3333)
{!} [23935: 12.136] SoftMPEG - ERROR in audio_playout at audio.c:243: stream playback unexpectedly ended! [11510/64000]
{!} [23935: 12.152] SoftMPEG - WARNING in audio_playout at audio.c:251: falling below low mark (3016/3333)
{!} [23935: 12.156] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:598: some error while mpeg audio frame decoding
{!} [23935: 12.157] SoftMPEG - ERROR in audio_handle_pes_data at audio.c:595: mpeg audio frame decoding failed

--
NH

Prev by Date: [linuxtv-softmpeg] VDR "softmpeg" plugin for vdr 1.3.11, new "unichrome" DirectFB driver
Next by Date: [linuxtv-softmpeg] Re: VDR "softmpeg" plugin for vdr 1.3.11, new "unichrome" DirectFB driver
Previous by thread: [linuxtv-softmpeg] Re: VDR "softmpeg" plugin for vdr 1.3.11, new "unichrome" DirectFB driver
Next by thread: [linuxtv-softmpeg] softmpeg on crtc2?
Index(es):
- Date
- Thread

Home | Main Index | Thread Index