[vlc-devel] main warning: late picture skipped

Sat Oct 10 08:19:10 CEST 2009

"avcodec error: more than 5 seconds of late video -> dropping frame (computer too slow ?)"

is known issue for VLC under Win32 (this happen even if you have enough CPU power to decode the video).
trac-test.videolan.org/vlc/ticket/2093

jboileau kirjoitti 10.10.2009 kello 00:25:
> What is the frame rate of your video? At low framerate I had similar
> problems. At 6fps VLC seems to mistakenly think that all frames are
> late and drops all of them. Someone from the VLC team may offer a
> better explanation. You might want to try setting the options for
> dropping frames to off.
> 
> Jacques Boileau
> 
> 
> 
> 
> On Fri, Oct 9, 2009 at 9:03 AM, Youssef AZZOUZI
> <sidimoulay2000 at hotmail.fr> wrote:
> > I want to read  a webcam streaming from an other PC using Gstreamer to a
> > mine using VLC, I can get the first secondes of a stream and for the rest
> I
> > get the following warning:
> >
> > main warning: late picture skipped (39512 > -221)
> >
> > avcodec error: more than 5 seconds of late video -> dropping frame
> (computer
> > too slow ?)
> >
> >
> > Someone can help me plz?
> >
> > I still receiving the packets but VLC cannot display them, the full debug
> is
> > the following:
> >
> > main debug: adding item `test.sdp' ( C:\Documents and
> > Settings\Ucef\Bureau\test.sdp )
> >
> > main debug: rebuilding array of current - root Liste de lecture
> >
> > main debug: rebuild done - 3 items, index 1
> >
> > main debug: processing request item test.sdp node Liste de lecture skip 0
> >
> > main debug: resyncing on test.sdp
> >
> > main debug: test.sdp is at 2
> >
> > main debug: starting new item
> >
> > main debug: creating new input thread
> >
> > main debug: Creating an input for 'test.sdp'
> >
> > qt4 debug: Adding a new MRL to recent ones: C:\Documents and
> > Settings\Ucef\Bureau\test.sdp
> >
> > main debug: thread (input) created at priority 1
> > (../.././src/input/input.c:230)
> >
> > main debug: thread started
> >
> > main debug: using timeshift granularity of 50 MBytes
> >
> > main debug: using timeshift path 'C:\DOCUME~1\Ucef\LOCALS~1\Temp'
> >
> > main debug: `C:\Documents and Settings\Ucef\Bureau\test.sdp' gives access
> `'
> > demux `' path `C:\Documents and Settings\Ucef\Bureau\test.sdp'
> >
> > main debug: creating demux: access='' demux='' path='C:\Documents and
> > Settings\Ucef\Bureau\test.sdp'
> >
> > main debug: looking for access_demux module: 3 candidates
> >
> > main debug: TIMER module_need() : 14.086 ms - Total 14.086 ms / 1 intvls
> > (Avg 14.086 ms)
> >
> > main debug: creating access '' path='C:\Documents and
> > Settings\Ucef\Bureau\test.sdp'
> >
> > main debug: looking for access module: 5 candidates
> >
> > vcd debug: trying .cue file: C:\Documents and
> Settings\Ucef\Bureau\test.cue
> >
> > vcd debug: could not find .cue file
> >
> > access_file debug: opening file `C:\Documents and
> > Settings\Ucef\Bureau\test.sdp'
> >
> > main debug: using access module "access_file"
> >
> > main debug: TIMER module_need() : 1.034 ms - Total 1.034 ms / 1 intvls
> (Avg
> > 1.034 ms)
> >
> > main debug: Using AStream*Stream
> >
> > main debug: pre buffering
> >
> > main debug: received first data after 0 ms
> >
> > main debug: pre-buffering done 185 bytes in 0s - 2125 kbytes/s
> >
> > main debug: looking for stream_filter module: 2 candidates
> >
> > main debug: TIMER module_need() : 0.196 ms - Total 0.196 ms / 1 intvls
> (Avg
> > 0.196 ms)
> >
> > main debug: looking for stream_filter module: 1 candidate
> >
> > main debug: using stream_filter module "stream_filter_record"
> >
> > main debug: TIMER module_need() : 0.140 ms - Total 0.140 ms / 1 intvls
> (Avg
> > 0.140 ms)
> >
> > main debug: creating demux: access='' demux='' path='C:\Documents and
> > Settings\Ucef\Bureau\test.sdp'
> >
> > main debug: looking for demux module: 50 candidates
> >
> > live555 debug: RTP subsession 'video/H264'
> >
> > main debug: selecting program id=0
> >
> > live555 debug: setup start: 0.000000 stop:0.000000
> >
> > live555 debug: play start: 0.000000 stop:0.000000
> >
> > main debug: using demux module "live555"
> >
> > main debug: TIMER module_need() : 73.807 ms - Total 73.807 ms / 1 intvls
> > (Avg 73.807 ms)
> >
> > main debug: looking for a subtitle file in C:\Documents and
> > Settings\Ucef\Bureau\
> >
> > main debug: looking for decoder module: 36 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() : 3.052 ms - Total 3.052 ms / 1 intvls
> (Avg
> > 3.052 ms)
> >
> > main debug: looking for packetizer module: 21 candidates
> >
> > packetizer_h264 debug: found NAL_SPS (sps_id=0)
> >
> > packetizer_h264 debug: found NAL_PPS (pps_id=0 sps_id=0)
> >
> > main debug: using packetizer module "packetizer_h264"
> >
> > main debug: TIMER module_need() : 0.430 ms - Total 0.430 ms / 1 intvls
> (Avg
> > 0.430 ms)
> >
> > main debug: thread (decoder) created at priority 0
> > (../.././src/input/decoder.c:315)
> >
> > main debug: thread started
> >
> > main debug: `C:\Documents and Settings\Ucef\Bureau\test.sdp' successfully
> > opened
> >
> > qt4 debug: IM: Setting an input
> >
> > qt4 debug: Updating the geometry
> >
> > qt4 debug: Updating the geometry
> >
> > main debug: Buffering 0%
> >
> > main debug: Buffering 0%
> >
> > main debug: Buffering 0%
> >
> > qt4 debug: New caching: 0
> >
> > qt4 debug: New caching: 0
> >
> > main debug: Buffering 11%
> >
> > main debug: Buffering 22%
> >
> > qt4 debug: New caching: 22
> >
> > qt4 debug: New caching: 22
> >
> > main debug: Buffering 33%
> >
> > main debug: Buffering 44%
> >
> > qt4 debug: New caching: 44
> >
> > qt4 debug: New caching: 44
> >
> > main debug: Buffering 55%
> >
> > qt4 debug: New caching: 55
> >
> > qt4 debug: New caching: 55
> >
> > main debug: Buffering 66%
> >
> > qt4 debug: New caching: 66
> >
> > qt4 debug: New caching: 66
> >
> > main debug: Buffering 77%
> >
> > qt4 debug: New caching: 77
> >
> > qt4 debug: New caching: 77
> >
> > main debug: Buffering 88%
> >
> > qt4 debug: New caching: 88
> >
> > qt4 debug: New caching: 88
> >
> > main debug: Buffering 99%
> >
> > qt4 debug: New caching: 99
> >
> > qt4 debug: New caching: 99
> >
> > main debug: Stream buffering done (333 ms in 279 ms)
> >
> > qt4 debug: New caching: 100
> >
> > qt4 debug: New caching: 100
> >
> > 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:475)
> >
> > main debug: thread started
> >
> > freetype debug: using fontsize: 2
> >
> > main debug: using text renderer module "freetype"
> >
> > main debug: TIMER module_need() : 253.429 ms - Total 253.429 ms / 1 intvls
> > (Avg 253.429 ms)
> >
> > main debug: looking for video filter2 module: 20 candidates
> >
> > freetype debug: Building font database...
> >
> > freetype debug: Finished building font database.
> >
> > freetype debug: Took 20533 microseconds
> >
> > main debug: thread ended
> >
> > 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() : 128.884 ms - Total 128.884 ms / 1 intvls
> > (Avg 128.884 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() : 37.466 ms - Total 37.466 ms / 1 intvls
> > (Avg 37.466 ms)
> >
> > main debug: window size: 320x240
> >
> > 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 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: 320 240
> >
> > qt4 debug: Updating the geometry
> >
> > main debug: using hwnd module "qt4"
> >
> > main debug: TIMER module_need() : 127.043 ms - Total 127.043 ms / 1 intvls
> > (Avg 127.043 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: Pilote d'affichage principal,
> > display
> >
> > vout_directx debug: DirectXEnumCallback: Mobile Intel(R) 965 Express
> Chipset
> > Family, \\.\DISPLAY1
> >
> > vout_directx debug: selecting Mobile Intel(R) 965 Express Chipset Family,
> > \\.\DISPLAY1
> >
> > vout_directx debug: screen dimensions (0x0,1280x800)
> >
> > vout_directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1
> > can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1
> >
> > vout_directx debug: End DirectXInitDDraw
> >
> > vout_directx debug: DirectXCreateDisplay
> >
> > vout_directx debug: DirectXCreateClipper
> >
> > vout_directx debug: disabling screen saver
> >
> > main debug: using video output module "vout_directx"
> >
> > main debug: TIMER module_need() : 604.587 ms - Total 604.587 ms / 1 intvls
> > (Avg 604.587 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 320x240, of (0,0), vsz 320x240, 4cc I420, ar
> 4:3,
> > sar 1:1, msk r0x0 g0x0 b0x0
> >
> > main debug: pic in sz 320x240, of (0,0), vsz 320x240, 4cc I420, ar 4:3,
> sar
> > 1:1, msk r0x0 g0x0 b0x0
> >
> > main debug: pic out sz 320x240, of (0,0), vsz 320x240, 4cc I420, ar 4:3,
> sar
> > 1:1, msk r0x0 g0x0 b0x0
> >
> > main debug: direct render, mapping render pictures 0-14 to system pictures
> > 1-15
> >
> > main debug: End of video preroll
> >
> > main debug: Received first picture
> >
> > freetype debug: using fontsize: 15
> >
> > qt4 debug: Qt: Entering Fullscreen
> >
> > main debug: looking for video blending module: 1 candidate
> >
> > main debug: Decoder buffering done in 1909 ms
> >
> > blend debug: chroma: YUVA -> I420
> >
> > main debug: using video blending module "blend"
> >
> > main debug: TIMER module_need() : 94.700 ms - Total 94.700 ms / 1 intvls
> > (Avg 94.700 ms)
> >
> > main debug: skipped big render time 440895 > 200010
> >
> > main debug: Post-processing available
> >
> > main warning: late picture skipped (40003 > -213)
> >
> > main warning: late picture skipped (38220 > -213)
> >
> > main warning: late picture skipped (37975 > -213)
> >
> > main warning: late picture skipped (40289 > -224)
> >
> > main warning: late picture skipped (39512 > -221)
> >
> > avcodec error: more than 5 seconds of late video -> dropping frame
> (computer
> > too slow ?)
> >
> > main warning: late picture skipped (1402782 > -211)
> >
> > main warning: late picture skipped (1400907 > -211)
> >
> > main warning: late picture skipped (1403653 > -211)
> >
> > main warning: late picture skipped (1435931 > -211)
> >
> > main warning: late picture skipped (1434485 > -215)
> >
> > avcodec error: more than 5 seconds of late video -> dropping frame
> (computer
> > too slow ?)
> >
> > main warning: late picture skipped (2467945 > -210)
> >
> > main warning: late picture skipped (2472650 > -210)
> >
> > main warning: late picture skipped (2498699 > -211)
> >
> > main warning: late picture skipped (2501696 > -211)
> >
> > main warning: late picture skipped (2499256 > -217)
> >
> > main debug: incoming request - stopping current input
> >
> > main debug: dying input
> >
> > main debug: control type=0
> >
> > main debug: control: stopping input
> >
> > main debug: dying input
> >
> > main debug: thread times: real 0m39.968750s, kernel 0m0.015625s, user
> > 0m0.375000s
> >
> > avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
> >
> > main debug: removing module "avcodec"
> >
> > main debug: killing decoder fourcc `h264', 0 PES in FIFO
> >
> > main debug: [0] 0 0
> >
> > main debug: [1] 0 0
> >
> > main debug: [2] 0 0
> >
> > main debug: [3] 0 0
> >
> > main debug: [4] 0 0
> >
> > main debug: [5] 4 0
> >
> > main debug: [6] 4 0
> >
> > main debug: [7] 4 0
> >
> > main debug: [8] 4 0
> >
> > main debug: [9] 4 0
> >
> > main debug: [10] 2 0
> >
> > main debug: [11] 4 0
> >
> > main debug: [12] 4 0
> >
> > main debug: [13] 4 0
> >
> > main debug: [14] 4 0
> >
> > main debug: saving a free vout
> >
> > main debug: removing module "packetizer_h264"
> >
> > main debug: removing module "live555"
> >
> > main debug: removing module "stream_filter_record"
> >
> > main debug: removing module "access_file"
> >
> > main debug: Program doesn't contain anymore ES
> >
> > main debug: thread ended
> >
> > main debug: dead input
> >
> > main debug: thread times: real 0m40.062500s, kernel 0m0.046875s, user
> > 0m0.078125s
> >
> > main debug: destroying useless vout
> >
> > vout_directx debug: DirectXCloseSurface
> >
> > vout_directx debug: DirectXCloseDisplay
> >
> > vout_directx debug: DirectXCloseDisplay clipper
> >
> > vout_directx debug: DirectXCloseDisplay display
> >
> > vout_directx debug: DirectXCloseDDraw
> >
> > qt4 debug: IM: Deleting the input
> >
> > qt4 debug: Updating the geometry
> >
> > qt4 debug: Updating the geometry
> >
> > qt4 debug: Qt: Entering Fullscreen
> >
> > main debug: TIMER input launching for 'test.sdp' : 115.281 ms - Total
> > 115.281 ms / 1 intvls (Avg 115.281 ms)
> >
> > vout_directx debug: DirectXEventThread terminating
> >
> > vout_directx debug: DirectXCloseWindow
> >
> > vout_directx debug: WinProc WM_DESTROY
> >
> > qt4 debug: releasing video...
> >
> > qt4 debug: Video is not needed anymore
> >
> > qt4 debug: Updating the geometry
> >
> > main debug: removing module "qt4"
> >
> > main debug: thread ended
> >
> > main debug: thread times: real 0m29.000000s, kernel 0m0.000000s, user
> > 0m0.015625s
> >
> > main debug: removing module "vout_directx"
> >
> > main debug: removing module "blend"
> >
> > main debug: thread times: real 0m0.140625s, kernel 0m0.000000s, user
> > 0m0.000000s
> >
> > main debug: removing module "freetype"
> >
> > main debug: removing module "yuvp"
> >
> > main debug: removing module "swscale"
> >
> > _______________________________________________
> > vlc-devel mailing list
> > To unsubscribe or modify your subscription options:
> > http://mailman.videolan.org/listinfo/vlc-devel
> >
> >
> _______________________________________________
> vlc-devel mailing list
> To unsubscribe or modify your subscription options:
> http://mailman.videolan.org/listinfo/vlc-devel

....................................................................
Luukku Plus -paketilla pääset eroon tila- ja turvallisuusongelmista.
Hanki Luukku Plus ja helpotat elämääsi. http://www.mtv3.fi/luukku