[vdr] XviD movie stuttering (Mplayer)

Sun Apr 10 18:01:28 CEST 2005

Juri Haberland wrote:
> Pasi Juppo <pasi.juppo at iki.fi> wrote:
> 
>>One movie fails to play properly when using TT full featured card, VDR 
>>(1.3.22), MPlayer plugin (0.9.11), mplayer (1.0 pre 6 with codes from 
>>Feb 2005) and mplayer.sh (0.8.6). Audio stutters constantly.
>>
>>However, using following command the audio does not stutter:
>>mplayer -vf expand=720:576 -vo mpegpes -ao mpegpes <avi-file>
>>
>>And this works even over ssh tunnel well (subtitles are missing but that 
>>should not be that big deal). So the file is good but there are some 
>>parameters that cause this but haven't figured out what causes this.
>>
>>Following configs are used (from mplayer.sh.conf):
> 
> 
> [snip]
> 
> 
>>Can anyone help with the problem?
> 
> 
> Please set DEBUG=true (see the end of mplayer.sh.conf) and provide the
> log entries when playing this file via mplayer.sh.

OK. Here's the log of first attempt which always fails to play any video 
clip:

Apr 10 18:47:11 backup logger: *** Starting mplayer.sh Version 0.8.6
Apr 10 18:47:11 backup logger: *** DEBUG: Variable CFGFIL has value 
"/usr/bin/mplayer.sh.conf"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable USEAC3 has value "true"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable AC3AOUT has value 
"-ac hwac3"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable TV_ASPECT has value 
"16/9"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable PAL has value "true"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable NTSC has value "false"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable USE_SPEED has value 
"true"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable DETC_FILTER has value 
"ivtc=1"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable MPLAYER has value 
"/usr/local/bin/mplayer"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable VOP has value "lavc=5000"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable VO has value "mpegpes"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable AO has value "mpegpes"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable CACHE has value "8192"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable CACHESTR has value 
"-cache 8192"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable FRAMEDROP has value 
"false"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable FDSTR has value ""
Apr 10 18:47:11 backup logger: *** DEBUG: Variable LIRCRC has value 
"/opt/lirc/lircrc"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable LIRCSTR has value 
"-lircconf /opt/lirc/lircrc"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable SUBTITLE has value " 
-subpos 80 -sub-bg-color 0 -sub-bg-alpha 255"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable REMOTE has value 
"-slave -nolirc"
Apr 10 18:47:11 backup logger: *** Use Option USERDEF at your own risk!
Apr 10 18:47:11 backup logger: *** DEBUG: Variable USERDEF has value 
"-quiet"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable XResPAL has value 
"352 480 528 544 688 704 720"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable XResNTSC has value 
"352 480 512 640 704 720"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable SLOW_CPU has value 
"false"
Apr 10 18:47:11 backup logger: *** DEBUG: *** Option DVDFiles not set 
correctly! You will not be able to play VCD/DVD ""
Apr 10 18:47:11 backup logger: *** DEBUG: Variable DVDFiles has value ""
Apr 10 18:47:11 backup logger: *** DEBUG: Variable DVD has value "/dev/hdc"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable DVDLANG has value "en"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable DVDOPTIONS has value 
"-aop list=volume:volume=170"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable VCDOPTIONS has value ""
Apr 10 18:47:11 backup logger: *** DEBUG: Variable MPEG_DIRECT has value 
"true"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable SUFFIX has value ".avi"
Apr 10 18:47:11 backup logger: *** DEBUG: Variable MPLAYER_V1 has value 
"true"
Apr 10 18:47:11 backup logger: *** DEBUG: Calling getvidxy function to 
analyze source video stream ...
Apr 10 18:47:12 backup logger: *** DEBUG: OutputFromMPLAYER: ""
Apr 10 18:47:12 backup logger: *** DEBUG: MPLAYER_RETURN:  "1"
Apr 10 18:47:12 backup logger: *** FATAL: something went wrong analyzing 
the video; mplayer reported an error!
Apr 10 18:47:12 backup logger: *** FATAL: check your mplayer 
installation. Exiting...

And the log of next attempt, which plays fine except for stuttering:

Apr 10 18:47:30 backup logger: *** DEBUG: Variable CFGFIL has value 
"/usr/bin/mplayer.sh.conf"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable USEAC3 has value "true"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable AC3AOUT has value 
"-ac hwac3"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable TV_ASPECT has value 
"16/9"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable PAL has value "true"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable NTSC has value "false"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable USE_SPEED has value 
"true"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable DETC_FILTER has value 
"ivtc=1"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable MPLAYER has value 
"/usr/local/bin/mplayer"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable VOP has value "lavc=5000"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable VO has value "mpegpes"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable AO has value "mpegpes"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable CACHE has value "8192"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable CACHESTR has value 
"-cache 8192"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable FRAMEDROP has value 
"false"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable FDSTR has value ""
Apr 10 18:47:30 backup logger: *** DEBUG: Variable LIRCRC has value 
"/opt/lirc/lircrc"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable LIRCSTR has value 
"-lircconf /opt/lirc/lircrc"
Apr 10 18:47:30 backup logger: *** DEBUG: Variable SUBTITLE has value " 
-subpos 80 -sub-bg-color 0 -sub-bg-alpha 255"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable REMOTE has value 
"-slave -nolirc"
Apr 10 18:47:31 backup logger: *** Use Option USERDEF at your own risk!
Apr 10 18:47:31 backup logger: *** DEBUG: Variable USERDEF has value 
"-quiet"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable XResPAL has value 
"352 480 528 544 688 704 720"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable XResNTSC has value 
"352 480 512 640 704 720"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable SLOW_CPU has value 
"false"
Apr 10 18:47:31 backup logger: *** DEBUG: *** Option DVDFiles not set 
correctly! You will not be able to play VCD/DVD ""
Apr 10 18:47:31 backup logger: *** DEBUG: Variable DVDFiles has value ""
Apr 10 18:47:31 backup logger: *** DEBUG: Variable DVD has value "/dev/hdc"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable DVDLANG has value "en"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable DVDOPTIONS has value 
"-aop list=volume:volume=170"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable VCDOPTIONS has value ""
Apr 10 18:47:31 backup logger: *** DEBUG: Variable MPEG_DIRECT has value 
"true"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable SUFFIX has value ".avi"
Apr 10 18:47:31 backup logger: *** DEBUG: Variable MPLAYER_V1 has value 
"true"
Apr 10 18:47:31 backup logger: *** DEBUG: Calling getvidxy function to 
analyze source video stream ...
Apr 10 18:47:32 backup logger: *** DEBUG: OutputFromMPLAYER: 
"ID_VIDEO_ID=0 ID_AUDIO_ID=1 ID_FILE_SUB_ID=0 
ID_FILE_SUB_FILENAME=<filename.sub> ID_FILENAME=<filename.avi> 
ID_VIDEO_FORMAT=XVID ID_VIDEO_BITRATE=997000 ID_VIDEO_WIDTH=640 
ID_VIDEO_HEIGHT=352 ID_VIDEO_FPS=23.976 ID_VIDEO_ASPECT=0.0000 
ID_AUDIO_CODEC=mp3 ID_AUDIO_FORMAT=85 ID_AUDIO_BITRATE=96000 
ID_AUDIO_RATE=48000 ID_AUDIO_NCH=2 ID_LENGTH=5135"
Apr 10 18:47:32 backup logger: *** DEBUG: MPLAYER_RETURN:  "0"
Apr 10 18:47:32 backup logger: *** DEBUG: parsed output for ORIG_X: "640"
Apr 10 18:47:32 backup logger: *** DEBUG: parsed output for ORIG_Y: "352"
Apr 10 18:47:32 backup logger: *** DEBUG: parsed output for ORIG_FPS: 
"23.976"
Apr 10 18:47:32 backup logger: *** DEBUG: parsed output for ORIG_ASPECT: 
"0.0000"
Apr 10 18:47:32 backup logger: *** DEBUG: parsed output for 
VIDEO_FORMAT: "XVID"
Apr 10 18:47:32 backup logger: *** DEBUG: parsed output for AUDIO_CODEC: 
"mp3"
Apr 10 18:47:32 backup logger: *** INFO: Source Video has Resolution of 
640 x 352 ...
Apr 10 18:47:32 backup logger: *** DEBUG: Film ""
Apr 10 18:47:32 backup logger: *** DEBUG: Variable MAX_X has value "1024"
Apr 10 18:47:32 backup logger: *** DEBUG: Variable NEW_Y has value "563"
Apr 10 18:47:32 backup logger: *** INFO: For Sqare Pixels we would scale 
to 1024 x 563 ...
Apr 10 18:47:32 backup logger: *** DEBUG: Variable XResTEMP has value 
"352 480 528 544 688 704 720"
Apr 10 18:47:32 backup logger: *** DEBUG: Variable AnzahlVonXResTEMP has 
value "7"
Apr 10 18:47:32 backup logger: *** DEBUG: Variable NEW_X has value "688"
Apr 10 18:47:32 backup logger: *** DEBUG: setting REAL_Y = FULL_Y ""
Apr 10 18:47:32 backup logger: *** DEBUG: Variable CMDLINE has value 
"/usr/local/bin/mplayer -vo mpegpes -ao mpegpes -vop 
lavc=5000:25,expand=688:576:-1:-1:1,scale=688:563 -speed 1.04  -cache 
8192 -slave -nolirc  -subpos 80 -sub-bg-color 0 -sub-bg-alpha 255 -quiet "
Apr 10 18:49:45 backup ntpd[2759]: synchronized to 192.26.119.7, stratum=2

Maybe the reason for stuttering comes from the speed parameter?

Br, Pasi