VLC and ISpy

[mpucyk]

VLC will not display ISpy recorded video, either stand-alone or from within ISpy. I get a black screen then a single frame is displayed for the duration of the video. WMP has no issues.

Any ideas?

[Lotesdelere]

Please open Tools -> Messages (set Verbosity to 2) before you start the playback and then paste the full resulting log here or on Pastebin.com if it's too long.

Also upload a short sample of a problematic file to either http://streams.videolan.org/upload/ or to EmbedUpload.com, so it will upload the file for you to several other hosts, and then post the link to the file here.

[mpucyk]

File uploaded to videolan 0_2012-08-23_16-32-27.mp4

debug log below


main debug: adding item `0_2012-08-23_16-32-27.mp4' ( file:///C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4 )
qt4 debug: Adding a new MRL to recent ones: file:///C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 1 items, index -1
main debug: processing request item: 0_2012-08-23_16-32-27.mp4, node: null, skip: 0
main debug: resyncing on 0_2012-08-23_16-32-27.mp4
main debug: 0_2012-08-23_16-32-27.mp4 is at 0
main debug: starting playback of the new playlist item
main debug: resyncing on 0_2012-08-23_16-32-27.mp4
main debug: 0_2012-08-23_16-32-27.mp4 is at 0
main debug: creating new input thread
main debug: Creating an input for '0_2012-08-23_16-32-27.mp4'
main debug: Creating an input for '0_2012-08-23_16-32-27.mp4'
main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\Mark\AppData\Local\Temp'
main debug: `file:///C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4' gives access `file' demux `' path `/C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4'
main debug: creating demux: access='file' demux='' location='/C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4' file='C:\Users\Mark\AppData\Roaming\iSpy\WebServerRoot\Media\video\OREHS\0_2012-08-23_16-32-27.mp4'
main debug: looking for access_demux module: 3 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.249 ms - Total 0.249 ms / 1 intvls (Avg 0.249 ms)
main debug: creating access 'file' location='/C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4', path='C:\Users\Mark\AppData\Roaming\iSpy\WebServerRoot\Media\video\OREHS\0_2012-08-23_16-32-27.mp4'
main debug: looking for access module: 3 candidates
filesystem debug: opening file `C:\Users\Mark\AppData\Roaming\iSpy\WebServerRoot\Media\video\OREHS\0_2012-08-23_16-32-27.mp4'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.150 ms - Total 0.150 ms / 1 intvls (Avg 0.150 ms)
main debug: Using stream method for AStream*
main debug: starting pre-buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 66666 KiB/s
main debug: looking for stream_filter module: 4 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.060 ms - Total 0.060 ms / 1 intvls (Avg 0.060 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.085 ms - Total 0.085 ms / 1 intvls (Avg 0.085 ms)
main debug: creating demux: access='file' demux='' location='/C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4' file='C:\Users\Mark\AppData\Roaming\iSpy\WebServerRoot\Media\video\OREHS\0_2012-08-23_16-32-27.mp4'
main debug: looking for demux module: 55 candidates
mp4 debug: dumping root Box "root"
mp4 debug: | + ftyp size 32
mp4 debug: | + free size 8
mp4 debug: | + mdat size 1343984
mp4 debug: | + moov size 2069
mp4 debug: | | + mvhd size 108
mp4 debug: | | + trak size 949
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 849
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 45
mp4 debug: | | | | + minf size 764
mp4 debug: | | | | | + vmhd size 20
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 700
mp4 debug: | | | | | | + stsd size 148
mp4 debug: | | | | | | | + avc1 size 132
mp4 debug: | | | | | | | | + avcC size 46
mp4 debug: | | | | | | + stts size 248
mp4 debug: | | | | | | + stss size 32
mp4 debug: | | | | | | + stsc size 40
mp4 debug: | | | | | | + stsz size 136
mp4 debug: | | | | | | + stco size 88
mp4 debug: | | + trak size 908
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 808
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 45
mp4 debug: | | | | + minf size 723
mp4 debug: | | | | | + smhd size 16
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 663
mp4 debug: | | | | | | + stsd size 103
mp4 debug: | | | | | | | + mp4a size 87
mp4 debug: | | | | | | | | + esds size 51
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stsc size 172
mp4 debug: | | | | | | + stsz size 272
mp4 debug: | | | | | | + stco size 84
mp4 debug: | | + udta size 96
mp4 debug: | | | + meta size 88
mp4 debug: | | | | + hdlr size 33
mp4 debug: | | | | + ilst size 43
mp4 debug: | | | | | + ctoo size 35
mp4 debug: ISO Media file (isom) version 512.
mp4 debug: found 2 tracks
mp4 debug: track[Id 0x1] read 18 chunk
mp4 debug: track[Id 0x1] read 29 samples length:13s
main debug: selecting program id=0
mp4 debug: adding track[Id 0x1] video (enable) language undef
mp4 debug: track[Id 0x2] read 17 chunk
mp4 debug: track[Id 0x2] read 63 samples length:8s
mp4 debug: adding track[Id 0x2] audio (enable) language undef
main debug: using demux module "mp4"
main debug: TIMER module_need() : 1.644 ms - Total 1.644 ms / 1 intvls (Avg 1.644 ms)
main debug: looking for a subtitle file in C:\Users\Mark\AppData\Roaming\iSpy\WebServerRoot\Media\video\OREHS\
main debug: looking for decoder module: 31 candidates
main debug: meta ok for (null), need to fetch art
main debug: looking for meta fetcher module: 1 candidate
lua debug: Trying Lua scripts in C:\Users\Mark\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
qt4 debug: IM: Setting an input
main debug: using meta fetcher module "lua"
main debug: TIMER module_need() : 3.121 ms - Total 3.121 ms / 1 intvls (Avg 3.121 ms)
main debug: removing module "lua"
main debug: searching art for 0_2012-08-23_16-32-27.mp4
main debug: looking for art finder module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\Mark\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
avcodec debug: libavcodec initialized (interface 0x361100)
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
avcodec debug: trying to use direct rendering
avcodec debug: allowing 4 thread(s) for decoding
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
main debug: no art finder module matching "any" could be loaded
main debug: TIMER module_need() : 3.762 ms - Total 3.762 ms / 1 intvls (Avg 3.762 ms)
main debug: art not found for 0_2012-08-23_16-32-27.mp4
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
avcodec debug: using frame thread mode with 4 threads
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 10.289 ms - Total 10.289 ms / 1 intvls (Avg 10.289 ms)
main debug: looking for decoder module: 31 candidates
main debug: using decoder module "faad"
main debug: TIMER module_need() : 0.233 ms - Total 0.233 ms / 1 intvls (Avg 0.233 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\Mark\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader\filename.luac
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 1.265 ms - Total 1.265 ms / 1 intvls (Avg 1.265 ms)
main debug: `file:///C:/Users/Mark/AppData/Roaming/iSpy/WebServerRoot/Media/video/OREHS/0_2012-08-23_16-32-27.mp4' successfully opened
mp4 debug: track[Id 0x1] using Sync Sample Box (stss)
mp4 debug: stts gives 0 --> 0 (sample number)
mp4 debug: track[Id 0x2] does not provide Sync Sample Box (stss)
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 33%
main debug: Buffering 66%
main debug: Buffering 100%
main debug: Stream buffering done (400 ms in 0 ms)
main debug: looking for text renderer module: 2 candidates
faad warning: decoded zero sample
freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 1.077 ms - Total 1.077 ms / 1 intvls (Avg 1.077 ms)
main debug: looking for video filter2 module: 18 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 1.231 ms - Total 1.231 ms / 1 intvls (Avg 1.231 ms)
main debug: looking for video filter2 module: 18 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 3.879 ms - Total 3.879 ms / 1 intvls (Avg 3.879 ms)
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module: 6 candidates
direct3d debug: creating Vout EventThread
direct3d debug: DirectXCreateWindow
main debug: looking for vout window hwnd module: 2 candidates
qt4 debug: requesting video...
qt4 debug: Video was requested 0, 0
main debug: using vout window hwnd module "qt4"
main debug: TIMER module_need() : 121.432 ms - Total 121.432 ms / 1 intvls (Avg 121.432 ms)
direct3d debug: created video sub-window
direct3d debug: Vout EventThread running
direct3d debug: disabling screen saver
direct3d debug: Direct3d Device: NVIDIA GeForce GTX 550 Ti 4318 4676 161
main debug: VoutDisplayEvent 'resize' 656x518 window
direct3d warning: trying surface pixel format: YV12
direct3d debug: selected surface pixel format is YV12
direct3d debug: Using D3DTEXF_LINEAR for minification
direct3d debug: Using D3DTEXF_LINEAR for magnification
direct3d debug: Direct3D scene created successfully
direct3d debug: Direct3D device adapter successfully initialized
main debug: using vout display module "direct3d"
main debug: TIMER module_need() : 143.828 ms - Total 143.828 ms / 1 intvls (Avg 143.828 ms)
main debug: original format sz 640x480, of (0,0), vsz 640x480, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 2 candidates
main debug: VoutDisplayEvent 'resize' 640x480 window
freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 0.585 ms - Total 0.585 ms / 1 intvls (Avg 0.585 ms)
avcodec debug: using direct rendering
main debug: creating audio output
main debug: looking for audio output module: 2 candidates
aout_directx debug: Opening DirectSound Audio Output
aout_directx debug: found device: Primary Sound Driver
aout_directx debug: found device: Speakers (Realtek High Definition Audio)
aout_directx debug: found device: Realtek Digital Output(Optical) (Realtek High Definition Audio)
aout_directx debug: found device: Realtek Digital Output (Realtek High Definition Audio)
aout_directx debug: device supports 2 channels (DEFAULT!)
aout_directx debug: device supports 1 channel
aout_directx debug: Windows says your SpeakerConfig is stereo
aout_directx debug: creating DirectSoundThread
main debug: using audio output module "aout_directx"
main debug: TIMER module_need() : 30.002 ms - Total 30.002 ms / 1 intvls (Avg 30.002 ms)
aout_directx debug: DirectSoundThread ready
main debug: output 's16l' 16000 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'f32l' 16000 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'s16l' 16000 Hz->16000 Hz Stereo->Stereo
main debug: looking for audio filter module: 14 candidates
audio_format debug: f32l->s16l, bits per sample: 32->16
main debug: using audio filter module "audio_format"
main debug: TIMER module_need() : 3.327 ms - Total 3.327 ms / 1 intvls (Avg 3.327 ms)
main debug: conversion pipeline completed
main debug: looking for audio mixer module: 2 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 0.308 ms - Total 0.308 ms / 1 intvls (Avg 0.308 ms)
main debug: input 'f32l' 16000 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 16000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 480.000 stride_in, 480 stride_out, 384 standing, 96 overlap, 224 search, 800 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.331 ms - Total 0.331 ms / 1 intvls (Avg 0.331 ms)
main debug: filter(s) 'f32l'->'f32l' 16000 Hz->16000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 16000 Hz->16000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 17600 Hz->16000 Hz Stereo->Stereo
main debug: looking for audio filter module: 14 candidates
main debug: using audio filter module "samplerate"
main debug: TIMER module_need() : 0.102 ms - Total 0.102 ms / 1 intvls (Avg 0.102 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
main debug: Decoder buffering done in 187 ms
main warning: audio output out of sync, adjusting dates (63276 us)
main warning: not synchronized (63277 us), resampling
main warning: buffer too late (62027), up-sampling
main debug: End of video preroll
direct3d debug: Created 399x33 texture for OSD
main debug: Post-processing available
main warning: picture is too late to be displayed (missing 768 ms)
main warning: picture is too late to be displayed (missing 730 ms)
main debug: auto hiding mouse cursor
main warning: picture is too late to be displayed (missing 840 ms)
main warning: picture is too late to be displayed (missing 824 ms)
main debug: auto hiding mouse cursor
avcodec error: more than 5 seconds of late video -> dropping frame (computer too slow ?)
main warning: picture is too late to be displayed (missing 6790 ms)
main warning: picture is too late to be displayed (missing 6449 ms)
main warning: picture is too late to be displayed (missing 6429 ms)
main debug: EOF reached
main debug: waiting decoder fifos to empty
main debug: finished input
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 0 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "faad"
main debug: killing decoder fourcc `mp4a', 0 PES in FIFO
main debug: removing module "scaletempo"
main debug: removing module "samplerate"
main debug: removing module "aout_directx"
aout_directx debug: closing audio device
aout_directx debug: DirectSoundThread exiting
main debug: removing module "audio_format"
main debug: removing module "float32_mixer"
main debug: releasing audio output
main debug: removing module "mp4"
mp4 debug: freeing all memory
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: changing item without a request (current 0/1)
main debug: nothing to play
main debug: destroying useless vout
main debug: removing module "direct3d"
direct3d debug: Direct3D scene released successfully
direct3d debug: DirectXEventThread terminating
direct3d debug: DirectXCloseWindow
direct3d debug: WinProc WM_DESTROY
qt4 debug: releasing video...
qt4 debug: IM: Deleting the input
main debug: TIMER input launching for '0_2012-08-23_16-32-27.mp4' : 15.389 ms - Total 15.389 ms / 1 intvls (Avg 15.389 ms)
qt4 debug: Video is not needed anymore
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"

[Lotesdelere]

A sample file is needed.

[mpucyk]

File uploaded to videolan 0_2012-08-23_16-32-27.mp4

[Lotesdelere]

0_2012-08-23_16-32-27.mp4

2 FPS video. VLC has troubles for playing very low FPS videos, it's a known issue awaiting for a fix.

[mpucyk]

Thought that might be it. Thank you.

[Jean-Baptiste Kempf]

You can try increasing the caching by a lot to play very low fps videos.