H.264 frame skips when fading to black

Microsoft Windows specific usage questions
Forum rules
Please post only Windows specific questions in this forum category. If you don't know where to post, please read the different forums' rules. Thanks.
Alexsource
Cone that earned his stripes
Cone that earned his stripes
Posts: 102
Joined: 22 Nov 2006 15:45

H.264 frame skips when fading to black

Postby Alexsource » 27 May 2009 16:46

Hey, since I last posted in the froums, my old duron@892 Mhz went to my sister and my main box is faster, so i went and downloaded a few mHD files.

With one (an mkv of about 832*something res), i got a strange frame skipping issue during the intro, black background, red letters. When the text dades to black, a few frames are skipped. Also whn a character goes under a bridge and the scene fades to black because of the light changes, it skipps some frames.

It seems not related to viewtopic.php?f=14&t=59354&p=199190 .
Vlc 1.0 rc1 DOES use more cpu on the 2 h.264 files i tested, but when the frame skipping occurs on this one the cpu usage drops quite a bit (eg: it's close to 33% and on the frameskipping it drops to about 11%).

An old mplayer svn trough mplayerui did not have this issue, but the quality of the video was by far superior on vlc.

This happens with 0.9.8a, 0.9.9 and 1.0 rc1 as far as i know. Disabling the loop filters did not seem to fix the problem. Since that often allows people with older procesors watch full hd video, i really don't understand what may be going on. Any pointers will be apreciated.

I'll post more details about the file when I get home.

My specs:
pentium 4 2.40 GHZ
512 MB ram
Windows XP sp3
Zogis Nvidia Geforce 5500 fx based video card

Alexsource
Cone that earned his stripes
Cone that earned his stripes
Posts: 102
Joined: 22 Nov 2006 15:45

Re: H.264 frame skips when fading to black

Postby Alexsource » 28 May 2009 01:46

Hey, I came back with some info on the file (all taken with vlc 1.0 rc1).

Codec Info:
Image

Message log

Code: Select all

debugmkv debug: | + Seek head mkv debug: | | + Seek mkv debug: | - info at 4135 mkv debug: | + Information mkv debug: | | + TimecodeScale=1000000 mkv debug: | | + Muxing Application=libebml v0.7.7 + libmatroska v0.8.1 mkv debug: | | + Writing Application=mkvmerge v2.2.0 ('Turn It On Again') built on Mar 4 2008 12:58:26 mkv debug: | | + Duration=5230891 mkv debug: | | + Date=Fri Jul 18 10:05:24 2008 mkv debug: | | + Title=XXXXXXXXXXxxx mkv debug: | | + UID=-2067031932 mkv debug: | | + Seek| mkv debug: | - tracks at 4326 mkv debug: | + Tracks mkv debug: | | + Track Entry mkv debug: | | | + Track Number=1 mkv debug: | | | + Track UID=1 mkv debug: | | | + Track Type=video mkv debug: | | | + Unknown (N11libmatroska19KaxTrackFlagEnabledE) mkv debug: | | | + Track Default=1 mkv debug: | | | + Unknown (N11libmatroska18KaxTrackFlagForcedE) mkv debug: | | | + Track Lacing=0 mkv debug: | | | + Track MinCache=1 mkv debug: | | | + Track TimeCodeScale=1.000000 mkv debug: | | | + Unknown (N11libmatroska21KaxMaxBlockAdditionIDE) mkv debug: | | | + Track CodecId=V_MPEG4/ISO/AVC mkv debug: | | | + Unknown (N11libmatroska17KaxCodecDecodeAllE) mkv debug: | | | + Track CodecPrivate size=39 mkv debug: | | | + Track Default Duration=41708398 mkv debug: | | | + Track Language=`eng' mkv debug: | | | + Track Video mkv debug: | | | | + width=832 mkv debug: | | | | + height=416 mkv debug: | | | | + Unknown (N11libmatroska22KaxVideoFlagInterlacedE) mkv debug: | | | | + display width=61 mkv debug: | | | | + display height=26 mkv debug: | | + Track Entry mkv debug: | | | + Track Number=2 mkv debug: | | | + Track UID=2534248837 mkv debug: | | | + Track Type=audio mkv debug: | | | + Unknown (N11libmatroska19KaxTrackFlagEnabledE) mkv debug: | | | + Track Default=1 mkv debug: | | | + Unknown (N11libmatroska18KaxTrackFlagForcedE) mkv debug: | | | + Track Lacing=1 mkv debug: | | | + Track MinCache=0 mkv debug: | | | + Track TimeCodeScale=1.000000 mkv debug: | | | + Unknown (N11libmatroska21KaxMaxBlockAdditionIDE) mkv debug: | | | + Track CodecId=A_AAC mkv debug: | | | + Unknown (N11libmatroska17KaxCodecDecodeAllE) mkv debug: | | | + Track CodecPrivate size=7 mkv debug: | | | + Track Default Duration=42666666 mkv debug: | | | + Track Language=`chi' mkv debug: | | | + Track Audio mkv debug: | | | | + afreq=24000 mkv debug: | | | | + achan=6 mkv debug: | | | | + aoutfreq=48000 mkv debug: | | + Track Entry mkv debug: | | | + Track Number=3 mkv debug: | | | + Track UID=536055465 mkv debug: | | | + Track Type=subtitle mkv debug: | | | + Unknown (N11libmatroska19KaxTrackFlagEnabledE) mkv debug: | | | + Track Default=1 mkv debug: | | | + Unknown (N11libmatroska18KaxTrackFlagForcedE) mkv debug: | | | + Track Lacing=0 mkv debug: | | | + Track MinCache=0 mkv debug: | | | + Track TimeCodeScale=1.000000 mkv debug: | | | + Unknown (N11libmatroska21KaxMaxBlockAdditionIDE) mkv debug: | | | + Track CodecId=S_TEXT/UTF8 mkv debug: | | | + Unknown (N11libmatroska17KaxCodecDecodeAllE) mkv debug: | | | + Track Language=`eng' mkv debug: | | + Seek mkv debug: | - chained seekhead at 1150629071 mkv debug: | + Seek head mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 5663 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 38226 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 375508 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 878174 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 1428901 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 2011099 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 2395681 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 2614210 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 2713102 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 2802690 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 2880493 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 2959593 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 3197925 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 3621809 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 3994504 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 4327197 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 4709452 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 5087147 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 5449348 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 5895394 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 6326731 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 6790035 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 7193348 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 7895189 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 8191129 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 8337799 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 8500964 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 8698484 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 8901239 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 9090578 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 9267175 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 9332786 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 9412712 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 9470972 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 9523141 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 9699668 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 10199820 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 10606306 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 11337900 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 11716764 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 11992371 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 12388746 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 12764216 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 13133244 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 13470010 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 13717391 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 14375673 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 15276220 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 16149162 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 16874306 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 17571089 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 18454965 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 19155995 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 19452220 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 19971072 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 20025579 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 20075558 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 20127463 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 20918728 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 21298144 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 22779443 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 23816456 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 24674146 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 25535835 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 26067434 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 26335726 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 26741176 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 27130923 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 27552611 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 27880026 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 28253985 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 28445253 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 28633395 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 28861606 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 29047962 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 29350428 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 29539195 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 29748554 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 30018764 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 30601357 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 31068915 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 31646199 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 32016347 mkv debug: | | + Seek mkv debug: | - unknown seekhead reference at 32196031 main debug: looking for decoder module: 35 candidates avcodec debug: libavcodec already initialized avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started main debug: using decoder module "avcodec" main debug: TIMER module_need() : 6.097 ms - Total 6.097 ms / 1 intvls (Avg 6.097 ms) main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315) qt4 debug: Updating the geometry qt4 debug: Updating the geometry main debug: thread started main debug: thread started main debug: looking for decoder module: 35 candidates main debug: using decoder module "faad" main debug: TIMER module_need() : 1.243 ms - Total 1.243 ms / 1 intvls (Avg 1.243 ms) main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:315) main debug: thread started main debug: looking for decoder module: 35 candidates avcodec debug: libavcodec already initialized avcodec debug: codec not found (Plain text subtitles) subsdec debug: trying demuxer-specified character encoding: UTF-8 main debug: using decoder module "subsdec" main debug: TIMER module_need() : 1.277 ms - Total 1.277 ms / 1 intvls (Avg 1.277 ms) main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315) main debug: thread started main debug: `xxxxxxxxxxxxx.mkv' successfully opened main error: Invalid PCR value in ES_OUT_SET_(GROUP_)PCR ! main error: Invalid PCR value in ES_OUT_SET_(GROUP_)PCR ! main debug: Buffering 0% faad warning: decoded zero sample faad debug: AAC SBR (channels: 6, samplerate: 48000) main debug: reusing aout main debug: looking for audio output module: 3 candidates aout_directx debug: OpenAudio aout_directx debug: found device: Controlador primario de sonido aout_directx debug: found device: C-Media Wave Device main debug: Stream buffering done (342 ms in 15 ms) aout_directx debug: device supports 5.1 channels aout_directx debug: device supports 4 channels aout_directx debug: device supports 2 channels aout_directx debug: device supports 1 channel aout_directx debug: Windows says your SpeakerConfig is Quad aout_directx debug: creating DirectSoundThread main debug: thread started aout_directx debug: DirectSoundThread ready main debug: thread (DirectSound Notification Thread) created at priority 15 (../../.././modules/audio_output/directx.c:427) main debug: using audio output module "aout_directx" main debug: TIMER module_need() : 214.596 ms - Total 214.596 ms / 1 intvls (Avg 214.596 ms) main debug: output 's16l' 48000 Hz 2F2R frame=1 samples/8 bytes main debug: mixer 'fl32' 48000 Hz 2F2R frame=1 samples/16 bytes main debug: filter(s) 'fl32'->'s16l' 48000 Hz->48000 Hz 2F2R->2F2R main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "converter_float" main debug: TIMER module_need() : 0.432 ms - Total 0.432 ms / 1 intvls (Avg 0.432 ms) main debug: found a filter for the whole conversion main debug: looking for audio mixer module: 3 candidates main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 0.231 ms - Total 0.231 ms / 1 intvls (Avg 0.231 ms) main debug: input 'fl32' 48000 Hz 3F2R/LFE frame=1 samples/24 bytes main debug: looking for audio filter module: 1 candidate scaletempo warning: input and output formats are not similar main warning: no audio filter module matching "scaletempo" could be loaded main debug: TIMER module_need() : 0.267 ms - Total 0.267 ms / 1 intvls (Avg 0.267 ms) main debug: looking for audio filter module: 1 candidate scaletempo debug: format: 48000 rate, 6 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode main debug: using audio filter module "scaletempo" main debug: TIMER module_need() : 1.480 ms - Total 1.480 ms / 1 intvls (Avg 1.480 ms) main debug: filter(s) 'fl32'->'fl32' 48000 Hz->48000 Hz 3F2R/LFE->2F2R main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "simple_channel_mixer" main debug: TIMER module_need() : 0.334 ms - Total 0.334 ms / 1 intvls (Avg 0.334 ms) main debug: found a filter for the whole conversion main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz 2F2R->2F2R main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: TIMER module_need() : 0.635 ms - Total 0.635 ms / 1 intvls (Avg 0.635 ms) main debug: found a filter for the whole conversion main debug: End of audio preroll main debug: Decoder buffering done in 220 ms main debug: no usable vout present, spawning one main debug: looking for text renderer module: 2 candidates main debug: thread (fontlist builder) created at priority 0 (../../.././modules/misc/freetype.c:473) freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 3.701 ms - Total 3.701 ms / 1 intvls (Avg 3.701 ms) main debug: looking for video filter2 module: 20 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 4.319 ms - Total 4.319 ms / 1 intvls (Avg 4.319 ms) main debug: looking for video filter2 module: 20 candidates yuvp debug: YUVP to YUVA converter main debug: using video filter2 module "yuvp" main debug: TIMER module_need() : 0.800 ms - Total 0.800 ms / 1 intvls (Avg 0.800 ms) main debug: window size: 975x416 main debug: looking for video output module: 7 candidates vout_directx debug: creating DirectXEventThread main debug: thread (Vout Events Thread) created at priority 0 (../../../.././modules/video_output/msw/directx.c:282) main warning: PTS is out of range (26882), dropping buffer main warning: PTS is out of range (-15753), dropping buffer main warning: buffer is 43335 in advance, triggering downsampling qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry main debug: thread started freetype debug: Building font database... freetype debug: Finished building font database. freetype debug: Took 82 microseconds main debug: thread ended main debug: thread started vout_directx debug: DirectXCreateWindow main debug: looking for hwnd module: 3 candidates qt4 debug: requesting video... qt4 debug: Video was requested -1, -1 qt4 debug: Video is resizing to: 975 416 main debug: using hwnd module "qt4" main debug: TIMER module_need() : 86.725 ms - Total 86.725 ms / 1 intvls (Avg 86.725 ms) vout_directx debug: created video sub-window vout_directx debug: Vout EventThread running vout_directx debug: DirectXInitDDraw vout_directx debug: directx-device: vout_directx debug: DirectXEnumCallback: Controlador de pantalla primaria, display vout_directx debug: screen dimensions (0x0,1680x1050) vout_directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 vout_directx debug: align_boundary_src=0,0 align_boundary_dest=1,1 align_size_src=0,0 align_size_dest=0,0 vout_directx debug: End DirectXInitDDraw vout_directx debug: DirectXCreateDisplay qt4 debug: Updating the geometry vout_directx debug: DirectXCreateClipper vout_directx debug: disabling screen saver main debug: using video output module "vout_directx" main debug: TIMER module_need() : 405.311 ms - Total 405.311 ms / 1 intvls (Avg 405.311 ms) main debug: Deinterlacing available vout_directx debug: NewPictureVec overlay:yes chroma:YV12 vout_directx debug: YUV overlay created successfully vout_directx debug: End NewPictureVec (succeeded) main debug: got 1 direct buffer(s) main debug: pic render sz 832x416, of (0,0), vsz 832x416, 4cc I420, ar 168923:72000, sar 42246:36013, msk r0x0 g0x0 b0x0 main debug: pic in sz 832x416, of (0,0), vsz 832x416, 4cc I420, ar 168923:72000, sar 42246:36013, msk r0x0 g0x0 b0x0 main debug: pic out sz 832x416, of (0,0), vsz 832x416, 4cc I420, ar 168923:72000, sar 42246:36013, msk r0x0 g0x0 b0x0 main debug: direct render, mapping render pictures 0-14 to system pictures 1-15 main debug: End of video preroll qt4 debug: Qt: Entering Fullscreen main warning: late picture skipped (812934 > -10) main warning: late picture skipped (770934 > -10) main warning: late picture skipped (896934 > -10) main warning: late picture skipped (854934 > -10) main debug: Post-processing available freetype debug: using fontsize: 26 main debug: looking for video blending module: 1 candidate blend debug: chroma: YUVA -> I420 main debug: using video blending module "blend" main debug: TIMER module_need() : 0.359 ms - Total 0.359 ms / 1 intvls (Avg 0.359 ms) main warning: late picture skipped (234977 > -7333) main warning: late picture skipped (318977 > -7333) main warning: late picture skipped (276977 > -7333) main warning: late picture skipped (151977 > -7333) main warning: late picture skipped (193977 > -7333) main warning: late picture skipped (-207 > -8730) main warning: late picture skipped (-4893 > -10581) main warning: resampling stopped after 13076093 usec (drift: 43) main warning: late picture skipped (197232 > -3251) main warning: late picture skipped (156232 > -3251) main warning: late picture skipped (114232 > -3251) main warning: late picture skipped (72232 > -3251) main warning: late picture skipped (31232 > -3251) main warning: late picture skipped (162382 > -2961) main warning: late picture skipped (120382 > -2961) main warning: late picture skipped (78382 > -2961) main warning: late picture skipped (49729 > -2884) main warning: late picture skipped (15775 > -2884) main warning: late picture skipped (53906 > -3132) main warning: late picture skipped (18631 > -3132) main warning: late picture skipped (446177 > -3079) main warning: late picture skipped (405177 > -3079) main warning: late picture skipped (363177 > -3079) main warning: late picture skipped (321177 > -3079) main warning: late picture skipped (279177 > -3079) main warning: late picture skipped (381236 > -2873) main warning: late picture skipped (339236 > -2873) main warning: late picture skipped (297236 > -2873) main warning: late picture skipped (255236 > -2873) main warning: late picture skipped (422236 > -2873) main warning: late picture skipped (10504 > -3764) main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_need() : 1.158 ms - Total 1.158 ms / 1 intvls (Avg 1.158 ms) main debug: removing module "xml" main warning: late picture skipped (-2410 > -7482) main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_need() : 1.240 ms - Total 1.240 ms / 1 intvls (Avg 1.240 ms) main debug: removing module "xml" main warning: late picture skipped (7138 > -3050) main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_need() : 0.587 ms - Total 0.587 ms / 1 intvls (Avg 0.587 ms) main debug: removing module "xml" main warning: late picture skipped (-2352 > -7683) main warning: late picture skipped (2827 > -8217) main warning: late picture skipped (46686 > -8942) main warning: late picture skipped (20629 > -8942) main warning: late picture skipped (-654 > -8761) main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_need() : 0.528 ms - Total 0.528 ms / 1 intvls (Avg 0.528 ms) main debug: removing module "xml" main warning: late picture skipped (56838 > -7914) main warning: late picture skipped (14838 > -7914) main warning: late picture skipped (22213 > -9090) main warning: late picture skipped (-5011 > -9090) main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_need() : 0.873 ms - Total 0.873 ms / 1 intvls (Avg 0.873 ms) main debug: removing module "xml" main warning: late picture skipped (64588 > -5706) main warning: late picture skipped (22588 > -5706) main warning: late picture skipped (34226 > -6233) main warning: late picture skipped (5980 > -6233) main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_need() : 4.660 ms - Total 4.660 ms / 1 intvls (Avg 4.660 ms) main debug: removing module "xml" main warning: late picture skipped (45061 > -5928) main warning: late picture skipped (3061 > -5928) main warning: late picture skipped (65982 > -5627) main warning: late picture skipped (23982 > -5627) main warning: late picture skipped (-5339 > -5582) main warning: late picture skipped (63530 > -5731) main warning: late picture skipped (21530 > -5731) main warning: late picture skipped (415375 > -2961) main warning: late picture skipped (384012 > -2961) main warning: late picture skipped (365328 > -2912) main warning: late picture skipped (333377 > -2912) main warning: late picture skipped (302714 > -2967) main warning: late picture skipped (150318 > -3049) main warning: late picture skipped (109318 > -3049) main warning: late picture skipped (67318 > -3049) main warning: late picture skipped (25318 > -3049) main warning: late picture skipped (438381 > -2794) main warning: late picture skipped (397381 > -2794) main warning: late picture skipped (355381 > -2794) main warning: late picture skipped (351169 > -2918) main warning: late picture skipped (319272 > -2918) main warning: late picture skipped (55759 > -2833) main warning: late picture skipped (222759 > -2833) main warning: late picture skipped (181759 > -2833) main warning: late picture skipped (139759 > -2833) main warning: late picture skipped (97759 > -2833) main warning: late picture skipped (3304 > -3216) main warning: late picture skipped (3155 > -3137) main warning: late picture skipped (-1652 > -3885) main warning: late picture skipped (-3131 > -3199) main warning: late picture skipped (1285 > -3083) main warning: late picture skipped (-2563 > -3023) main warning: late picture skipped (5132 > -3411) main warning: late picture skipped (3033 > -3173) main warning: late picture skipped (12718 > -3703) main warning: late picture skipped (290091 > -2851) main warning: late picture skipped (258831 > -2937) main warning: late picture skipped (224361 > -2937) main warning: late picture skipped (199571 > -2987) main warning: late picture skipped (164437 > -2987) main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_need() : 0.546 ms - Total 0.546 ms / 1 intvls (Avg 0.546 ms) main debug: removing module "xml" main warning: late picture skipped (103323 > -9570) main warning: late picture skipped (62323 > -9570) main warning: late picture skipped (20323 > -9570) main warning: late picture skipped (405204 > -9860) main warning: late picture skipped (364204 > -9860) main warning: late picture skipped (322204 > -9860) main warning: late picture skipped (280204 > -9860) main warning: late picture skipped (447204 > -9860) main debug: control type=1
plus:
Image
Hope this helps...

VLC_help
Mega Cone Master
Mega Cone Master
Posts: 25661
Joined: 13 Sep 2006 14:16

Re: H.264 frame skips when fading to black

Postby VLC_help » 28 May 2009 20:02

Could you share us the problematic file or small sample from it?

Alexsource
Cone that earned his stripes
Cone that earned his stripes
Posts: 102
Joined: 22 Nov 2006 15:45

Re: H.264 frame skips when fading to black

Postby Alexsource » 28 May 2009 21:54

Sure, i'll try to cut a sample when I get home. Hopefully avidemux can deal with the files and i won't have to hunt for another app :P

Alexsource
Cone that earned his stripes
Cone that earned his stripes
Posts: 102
Joined: 22 Nov 2006 15:45

Re: H.264 frame skips when fading to black

Postby Alexsource » 29 May 2009 02:22

Here it is, uploaded to rapidshare (no password):

Code: Select all

http://rapidshare.com/files/238357808/fade_to_black_sample.rar.html
MKV Merge gui was able to do the job ;).

VLC_help
Mega Cone Master
Mega Cone Master
Posts: 25661
Joined: 13 Sep 2006 14:16

Re: H.264 frame skips when fading to black

Postby VLC_help » 29 May 2009 19:07

main warning: late picture skipped (4000 > -4047)
main warning: late picture skipped (39000 > -4001)
main warning: late picture skipped (2000 > -4250)
main warning: late picture skipped (39000 > -3750)
main warning: late picture skipped (2000 > -3812)
main warning: late picture skipped (419000 > -2521)
main warning: late picture skipped (378000 > -2521)
main warning: late picture skipped (340000 > -2890)
main warning: late picture skipped (304000 > -2890)
main warning: late picture skipped (268000 > -2890)
I get lots of messages like that when problem shows up. I assume it is some sort of decoder issue that hopefully gets solved during next libavcodec update.

Alexsource
Cone that earned his stripes
Cone that earned his stripes
Posts: 102
Joined: 22 Nov 2006 15:45

Re: H.264 frame skips when fading to black

Postby Alexsource » 29 May 2009 21:13

Ok.
When do you think it'll happen? 1.0 final?

Just to be sure i installed the latest "mplayer for windows" package from mulder's page (dated 5/18/2009), and it also plays those parts fine.

Lotesdelere
Cone Master
Cone Master
Posts: 9967
Joined: 08 Sep 2006 04:39
Location: Europe

Re: H.264 frame skips when fading to black

Postby Lotesdelere » 29 May 2009 22:23

Yep, there are still problems with some H264 videos.

These troubles are known and reported:
https://trac.videolan.org/vlc/ticket/2671
https://trac.videolan.org/vlc/ticket/2670

It seems that there might be many things involved (CPU usage, filters, splitters, slices, files with many ref frames and/or B frames) so let's give the devs some time to cure them and hopefully it will be fixed for v1.0.0 release :)

Alexsource
Cone that earned his stripes
Cone that earned his stripes
Posts: 102
Joined: 22 Nov 2006 15:45

Re: H.264 frame skips when fading to black

Postby Alexsource » 30 May 2009 14:44

Damn.... Well, I'll just wait then. I don't have that many h.264 videos yet.

Alexsource
Cone that earned his stripes
Cone that earned his stripes
Posts: 102
Joined: 22 Nov 2006 15:45

Re: H.264 frame skips when fading to black

Postby Alexsource » 05 Feb 2010 16:11

Just to keep you guys posted, It seems to still happen with 1.0.5, but it's noticebly not as much as before. So, we have an improvement. Yes, still happens when disabling loop filters.
If anyone wants it, I still have the sample I'd cut lying around and can re-upload it.


Return to “VLC media player for Windows Troubleshooting”

Who is online

Users browsing this forum: No registered users and 96 guests