[vlc-devel] main warning: late picture skipped

[jboileau]

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
>
>