VLC 1.2.0 problem streaming RTSP

[lukealpha]

Dear all,

I'm running VLC media player 1.2.0-git-20111020-0004 on a Windows 7 x64 machine.

I'm trying to stream from an IP server via RTSP. This works flawlessly with VLC 1.1.11, but the same command on the same machine does not work with 1.2.0.
Instead of a live stream I get a freezed image.

I need to run 1.2.0 because VideoLan DotNet (http://vlcdotnet.codeplex.com) does not support the stable versions anymore.

Any help would be very appreciated. It would be great if you could guide me in the right direction how to debug this issue.

My command:

Code: Select all

vlc -vvv --sout-transcode-hurry-up --no-drop-late-frames --no-skip-frames --rtsp-caching=1000 --network-caching=1000 rtsp://10.0.0.3/input1/video2

Debug log says:

Code: Select all

main debug: processing request item: rtsp://10.0.0.3/input1/video2, node: Wiedergabeliste, skip: 0 main debug: resyncing on rtsp://10.0.0.3/input1/video2 main debug: rtsp://10.0.0.3/input1/video2 is at 0 main debug: starting playback of the new playlist item main debug: creating new input thread main debug: Creating an input for 'rtsp://10.0.0.3/input1/video2' main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\LUKAS~1.SIL\AppData\Local\Temp' main debug: `rtsp://10.0.0.3/input1/video2' gives access `rtsp' demux `' path `10.0.0.3/input1/video2' main debug: creating demux: access='rtsp' demux='' location='10.0.0.3/input1/video2' file='\\10.0.0.3\input1\video2' main debug: looking for access_demux module: 1 candidate live555 debug: version 2011.05.25 qt4 debug: IM: Setting an input live555 debug: RTP subsession 'video/H264' main debug: selecting program id=0 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: We have a timeout of 60 seconds live555 debug: spawned timeout thread live555 debug: play start: 0.000000 stop:0.000000 main debug: using access_demux module "live555" main debug: TIMER module_need() : 49.841 ms - Total 49.841 ms / 1 intvls (Avg 49.841 ms) main debug: looking for decoder module: 32 candidates avcodec debug: libavcodec already initialized avcodec debug: trying to use direct rendering avcodec debug: allowing 2 thread(s) for decoding avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started avcodec debug: using frame thread mode with 2 threads main debug: using decoder module "avcodec" main debug: TIMER module_need() : 2.225 ms - Total 2.225 ms / 1 intvls (Avg 2.225 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.335 ms - Total 0.335 ms / 1 intvls (Avg 0.335 ms) main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Users\lukas.silberbauer\AppData\Roaming\vlc\lua\meta\reader lua debug: Trying Lua scripts in c:\Program Files (x86)\VideoLAN_New\VLC\lua\meta\reader lua debug: Trying Lua playlist script c:\Program Files (x86)\VideoLAN_New\VLC\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 1.527 ms - Total 1.527 ms / 1 intvls (Avg 1.527 ms) main debug: `rtsp://10.0.0.3/input1/video2' successfully opened main debug: Buffering 0% main debug: Buffering 0% main debug: Buffering 0% main debug: Buffering 0% main debug: Stream buffering done (40 ms in 16 ms) main debug: Decoder buffering done in 0 ms main debug: looking for text renderer module: 2 candidates 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.233 ms - Total 1.233 ms / 1 intvls (Avg 1.233 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() : 0.814 ms - Total 0.814 ms / 1 intvls (Avg 0.814 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() : 0.239 ms - Total 0.239 ms / 1 intvls (Avg 0.239 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: 1 candidate 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() : 25.485 ms - Total 25.485 ms / 1 intvls (Avg 25.485 ms) direct3d debug: created video sub-window direct3d debug: Vout EventThread running direct3d debug: disabling screen saver main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 0 ms) main error: ES_OUT_RESET_PCR called main debug: VoutDisplayEvent 'resize' 368x326 window direct3d debug: DirectXUpdateRects image_src_clipped coords: 0,0,352,288 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() : 101.062 ms - Total 101.062 ms / 1 intvls (Avg 101.062 ms) main debug: original format sz 352x288, of (0,0), vsz 352x288, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 main debug: removing module "freetype" main debug: looking for text renderer module: 2 candidates 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.386 ms - Total 1.386 ms / 1 intvls (Avg 1.386 ms) avcodec debug: using direct rendering main debug: VoutDisplayEvent 'resize' 436x288 window direct3d debug: DirectXUpdateRects image_src_clipped coords: 0,0,352,288 main debug: Buffering 0% main debug: Stream buffering done (40 ms in 39 ms) main debug: Decoder buffering done in 0 ms main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 1 ms) main error: ES_OUT_RESET_PCR called main debug: Buffering 0% main debug: Stream buffering done (40 ms in 38 ms) main debug: Decoder buffering done in 0 ms main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 1 ms) main error: ES_OUT_RESET_PCR called main debug: Buffering 0% main debug: Stream buffering done (40 ms in 39 ms) main debug: Decoder buffering done in 0 ms main debug: auto hiding mouse live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP() main error: ES_OUT_RESET_PCR called main debug: Buffering 0% main debug: Stream buffering done (40 ms in 42 ms) main debug: Decoder buffering done in 0 ms main debug: End of video preroll main debug: Post-processing available main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 2 ms) main error: ES_OUT_RESET_PCR called

[lukealpha]

Update: I just tried it again with version vlc-1.2.0-git-20110319-1320-win32 from March (the oldest nightly build I could get my hands on) - there it works perfectly!

If somebody is interested, I could check which nightly build exactly causes the error by trial and error.
If not I'm gonna leave it, because I have found my solution (stick to the March build).

[VLC_help]

It is most likely a live555 issues. So it might help to know the version where it broke, since we could try to track the contrib version.

[lukealpha]

Hi,

this is what I have found out: the bug must lie between vlc-1.2.0-git-20110615-0200-win32 and vlc-1.2.0-git-20110917-0002-win32.
I took the nightly build installation files from the trunk folder. Currently I cannot narrow the issue down further, because I can't find the nightly builds between June and September, and a second bug "VLC could not open the file "(null)". (%m)" appears to mask this one from September 14 on.

Can you make sense of this?

Here are my test results:

vlc-1.2.0-git-20100719-2336-win32 - works
vlc-1.2.0-git-20110606-0454-win32 - works
vlc-1.2.0-git-20110615-0200-win32 - works
vlc-1.2.0-git-20110914-0004-win32 - VLC could not open the file "(null)". (%m)
vlc-1.2.0-git-20110915-0003-win32 - VLC could not open the file "(null)". (%m)
vlc-1.2.0-git-20110916-0003-win32 - VLC could not open the file "(null)". (%m)
vlc-1.2.0-git-20110917-0002-win32 - error as reported in previous post
vlc-1.2.0-git-20110918-0002-win32 - error as reported in previous post
vlc-1.2.0-git-20110923-0004-win32 - error as reported in previous post
vlc-1.2.0-git-20110926-0004-win32 - error as reported in previous post

[VLC_help]

I will check this out during weekend.

[VLC_help]

I tested with 1.2.0-git-20111023-0002 and URLs I have tried earlier work fine.
e.g. rtsp://videosci.cee.nau.edu/amoeba-lowX-streamingB.mov

[nbilber]

hi,

I have the same problem. I tried the last version (vlc-1.2.0-pre1-20111107-0009 ) and the version 1.2.0-git-20111023-0002.
And it don't work!

My URL is simple rtsp://admin:admin@192.168.0.125:554/media/video1

Can someboy send me a link of early versions, eg: vlc-1.2.0-git-20110615-0200 because they don´t appear in reopsitory

Cheers
nbilber

[Sébastien Escudier]

if you want us to investigate the issue, you should provide us :
a publicly accessible non working rtsp url
or, if not possible, full logs (vlc + rtsp)

[nbilber]

::log file ::

=====================================================================================================================================================================================
main debug: Creating an input for 'rtsp://192.168.0.125:554/media/video1'
main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\nbr\AppData\Local\Temp'
main debug: `rtsp://admin:admin@192.168.0.125:554/media/video1' gives access `rtsp' demux `' path `admin:admin@192.168.0.125:554/media/video1'
main debug: creating demux: access='rtsp' demux='' location='admin:admin@192.168.0.125:554/media/video1' file='\\admin:admin@192.168.0.125:554\media\video1'
main debug: looking for access_demux module: 1 candidate
live555 debug: version 2011.05.25
live555 debug: RTP subsession 'video/H264'
main debug: selecting program id=0
live555 debug: setup start: 0.000000 stop:0.000000
live555 debug: We have a timeout of 60 seconds
live555 debug: spawned timeout thread
live555 debug: play start: 0.000000 stop:0.000000
main debug: using access_demux module "live555"
main debug: TIMER module_need() : 1053.305 ms - Total 1053.305 ms / 1 intvls (Avg 1053.305 ms)
main debug: looking for decoder module: 32 candidates
avcodec debug: libavcodec initialized (interface 0x351401)
avcodec debug: trying to use direct rendering
avcodec debug: allowing 2 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
avcodec debug: using frame thread mode with 2 threads
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 121.470 ms - Total 121.470 ms / 1 intvls (Avg 121.470 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() : 56.552 ms - Total 56.552 ms / 1 intvls (Avg 56.552 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\nbr\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN_WPF\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN_WPF\VLC\lua\meta\reader\filename.luac
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 45.217 ms - Total 45.217 ms / 1 intvls (Avg 45.217 ms)
main debug: `rtsp://admin:admin@192.168.0.125:554/media/video1' successfully opened
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Stream buffering done (33 ms in 15 ms)
main debug: Decoder buffering done in 0 ms
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 5 ms)
main debmuagi:n leororkoirn:g EfSo_rO UteTx_tR rEeSEnTd_ePrCeRr cmaodlulleed
: 2 candidates
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() : 30.940 ms - Total 30.940 ms / 1 intvls (Avg 30.940 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() : 27.653 ms - Total 27.653 ms / 1 intvls (Avg 27.653 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() : 61.357 ms - Total 61.357 ms / 1 intvls (Avg 61.357 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: 1 candidate
main debug: VoutDisplayEvent 'fullscreen' 0
main debug: VoutDisplayEvent 'resize' 1280x720 window
main debug: using vout display module "vmem"
main debug: TIMER module_need() : 100.959 ms - Total 100.959 ms / 1 intvls (Avg 100.959 ms)
main debug: A filter to adapt decoder to display is needed
main debug: looking for video filter2 module: 18 candidates
swscale debug: 1280x720 chroma: I420 -> 1280x720 chroma: RV32 with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 14.123 ms - Total 14.123 ms / 1 intvls (Avg 14.123 ms)
main debug: Filter 'Swscale' (0ac3a5f8) appended to chain
main debug: original format sz 1280x720, of (0,0), vsz 1280x720, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main error: Failed to set on top
main debug: removing module "freetype"
main debug: looking for text renderer module: 2 candidates
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() : 32.387 ms - Total 32.387 ms / 1 intvls (Avg 32.387 ms)
avcodec debug: using direct rendering
main debug: Buffering 0%
main debug: Stream buffering done (200 ms in 13 ms)
main debug: Decoder buffering done in 0 ms
main debug: End of video preroll
main debug: Post-processing available
main warning: picture is too late to be displayed (missing 171 ms)
main warning: picture is too late to be displayed (missing 152 ms)
main warning: picture is too late to be displayed (missing 56 ms)
main warning: picture is too late to be displayed (missing 56 ms)
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 11 ms)
main error: ES_OUT_RESET_PCR called
main debug: Buffering 0%
main debug: Stream buffering done (133 ms in 112 ms)
main debug: Decoder buffering done in 0 ms
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 32 ms)
main error: ES_OUT_RESET_PCR called
main debug: Buffering 0%
live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP()
main error: ES_OUT_RESET_PCR called
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Stream buffering done (100 ms in 407 ms)
main debug: Decoder buffering done in 0 ms
main debug: End of video preroll
main warning: picture is too late to be displayed (missing 286 ms)
main warning: picture is too late to be displayed (missing 239 ms)
main warning: picture is too late to be displayed (missing 138 ms)
main warning: picture is too late to be displayed (missing 506 ms)
main warning: picture is too late to be displayed (missing 453 ms)
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 266 ms)
main error: ES_OUT_RESET_PCR called
main debug: Buffering 0%
main debug: Buffering 32%
main debug: Buffering 69%
main debug: Stream buffering done (318 ms in 365 ms)
main debug: Decoder buffering done in 0 ms
main debug: End of video preroll
main warning: picture is too late to be displayed (missing 863 ms)
main warning: picture is too late to be displayed (missing 841 ms)
main warning: picture is too late to be displayed (missing 722 ms)
main warning: picture is too late to be displayed (missing 764 ms)
main warning: picture is too late to be displayed (missing 684 ms)
avcodec error: more than 5 seconds of late video -> dropping frame (computer too slow ?)
main warning: picture is too late to be displayed (missing 2886 ms)
main warning: picture is too late to be displayed (missing 2791 ms)
main warning: picture is too late to be displayed (missing 2537 ms)
main warning: picture is too late to be displayed (missing 499 ms)
main warning: picture is too late to be displayed (missing 2622 ms)

=====================================================================================================================================================================================



The problem could be with codecs hx264 or MPEG4 because when I change the server to stream with JPEG, everythings is ok !

[VLC_help]

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

That usually means what it says (it can be a bug in demuxing but I would guess it isn't).

[lukealpha]

Can someboy send me a link of early versions, eg: vlc-1.2.0-git-20110615-0200 because they don´t appear in reopsitory

it used to be here, but it seems it has been deleted last week: http://nightlies.videolan.org/build/win ... -win32.exe

You could try this one: http://nightlies.videolan.org/build/win ... 0613-0003/
I haven't tested it, but date-wise it should work.

I'll try to hook up our video server to my dsl line at home next weekend - not sure if it works, but in theory it should allow us to reproduce the bug.

[hatboyzero]

FYI, this issue is still occurring with VLC 1.2.0 in Win 7 x64 while running the 32-bit binaries using the following build:

http://nightlies.videolan.org/build/win32/last/

Also, I'm attempting to access the RTSP stream from a standard AXIS video server appliance with the following URL:

rtsp://root:password@192.168.0.90/mpeg4/media.amp

Any suggestions on what might be going on here?

--
Matt

[hatboyzero]

This problem is still occurring in the latest build as of 2011-11-25. Additionally, the build suggested by lukealpha for use (20110613-003) *does* work, although it has what I'm assuming is a race condition of sorts on plugin loading during initialization that occasionally results in a memory access violation.

In any case, this is a real issue that I'm able to reproduce when attempting to view RTSP streams from a few different devices (i.e. Axis video server appliances, IQ Cameras, etc.). As I asked before, any idea on what's causing this?

[VLC_help]

It is either live555 or libavcodec bug most likely. But it can also be something else.

[hatboyzero]

According to the log file, you are correct, sir... The better question is how to fix it

Code: Select all

-- logger module started -- main debug: using interface module "logger" main debug: TIMER module_need() : 31.955 ms - Total 31.955 ms / 1 intvls (Avg 31.955 ms) main debug: looking for interface module: 1 candidate main debug: using interface module "hotkeys" main debug: TIMER module_need() : 3.986 ms - Total 3.986 ms / 1 intvls (Avg 3.986 ms) main error: option syslog does not exist main debug: looking for interface module: 1 candidate main debug: using interface module "globalhotkeys" main debug: TIMER module_need() : 8.795 ms - Total 8.795 ms / 1 intvls (Avg 8.795 ms) main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main debug: looking for interface module: 4 candidates main debug: playlist threads correctly activated main debug: rebuilding array of current - root Playlist main debug: rebuild done - 0 items, index -1 main debug: looking for extension module: 1 candidate lua debug: Opening Lua Extension module lua debug: Trying Lua scripts in C:\Users\mgray.MILTECRESEARCH\AppData\Roaming\vlc\lua\extensions lua debug: Trying Lua scripts in Z:\vendor_drops\vlc-1.2.0\lua\extensions lua debug: Trying Lua playlist script Z:\vendor_drops\vlc-1.2.0\lua\extensions\allocine-fr.luac lua debug: Scanning Lua script Z:\vendor_drops\vlc-1.2.0\lua\extensions\allocine-fr.luac lua debug: Script Z:\vendor_drops\vlc-1.2.0\lua\extensions\allocine-fr.luac has the following capability flags: 0xc main debug: using extension module "lua" main debug: TIMER module_need() : 19.538 ms - Total 19.538 ms / 1 intvls (Avg 19.538 ms) main debug: using interface module "qt4" main debug: TIMER module_need() : 133.947 ms - Total 133.947 ms / 1 intvls (Avg 133.947 ms) qt4 warning: Input option: network-caching=1000 main debug: adding item `rtsp://localhost/mpeg4/media.amp' ( rtsp://root:password@localhost/mpeg4/media.amp ) main qdte4b udge:b umge:t aA dodki nfgo ra (nneuwl lM)R,L to recent onneese:d rttso p://root:password@localhost/mpeg4/media.amp fmeaticnh daret bug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: processing request item: mratisnp :deb/u/gl:o claolohkoisntg/ mfpore gm4e/tmae dfieat.cahmepr, mnoodduel:e :n u1l lc,a nsdkiidpa:t e0 main debug: resyncing on rtsp://localhost/mpeg4/media.amp lua debug: Trying Lua scripmatisn dienb uCg::\ Urstesrps:\/m/glroacy.alhMoIsLt/TEmCpReESgE4A/RmCeHd\iAap.pamp Diast a\aRota m0 ing\vlc\lua\meta\fetcher main debug: starting playback of the new playlist item lua debug: Trymaiinng dLeubau gs:c rcirpetatsi ning Zn:e\wv einndpuotr _tdhrroepad s\vlc-1.2.0\lua\meta\fetcher main debug: Creating an input for 'rtsp://localhost/mpeg4/media.amp' lua debug: Trying Lua playlist script Z:\vendor_drops\vlc-1.2.0\lua\meta\fetcher\tvrage.luac qt4 debug: IM: Setting an input main debug: using meta fetcher module "lua" main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\MGRAY~1.MIL\AppData\Local\Temp' main debug: TIMER module_need() : 28.483 ms - Total 28.483 ms / 1 intvls (Avg 28.483 ms) main debug: `rtsp://root:password@localhost/mpeg4/media.amp' gives access `rtsp' demux `' path `root:password@localhost/mpeg4/media.amp' main debug: creating demux: access='rtsp' demux='' location='root:password@localhmoasitn/ mdpeebgu4g/:m erdeimao.vaimnpg' mfoidluel=e' \"\lruoao"t :password@localhost\mpeg4\media.amp' main debug: looking for access_demux module: 1 candidate main debug: searching art for rtsp://localhost/mpeg4/media.amp live555 debug: version 2011.05.25 main debug: looking for art finder module: 2 candidates lua debug: Trying Lua scripts in C:\Users\mgray.MILTECRESEARCH\AppData\Roaming\vlc\lua\meta\art lua debug: Trying Lua scripts in Z:\vendor_drops\vlc-1.2.0\lua\meta\art lua debug: Trying Lua playlist script Z:\vendor_drops\vlc-1.2.0\lua\meta\art\01_googleimage.luac lua debug: Trying Lua playlist script Z:\vendor_drops\vlc-1.2.0\lua\meta\art\02_frenchtv.luac lua debug: Trying Lua playlist script Z:\vendor_drops\vlc-1.2.0\lua\meta\art\03_lastfm.luac lua debug: Trying Lua playlist script Z:\vendor_drops\vlc-1.2.0\lua\meta\art\04_musicbrainz.luac main debug: no art finder module matching "any" could be loaded main debug: TIMER module_need() : 28.822 ms - Total 28.822 ms / 1 intvls (Avg 28.822 ms) main debug: art not found for rtsp://localhost/mpeg4/media.amp live555 debug: RTP subsession 'video/MP4V-ES' main debug: selecting program id=0 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: We have a timeout of 60 seconds live555 debug: spawned timeout thread live555 debug: play start: 0.000000 stop:0.000000 main debug: using access_demux module "live555" main debug: TIMER module_need() : 123.845 ms - Total 123.845 ms / 1 intvls (Avg 123.845 ms) main debug: looking for decoder module: 31 candidates avcodec debug: libavcodec initialized (interface 0x351401) avcodec debug: trying to use direct rendering avcodec debug: allowing 4 thread(s) for decoding avcodec debug: ffmpeg codec (MPEG-4 Video) started avcodec debug: using frame thread mode with 4 threads main debug: using decoder module "avcodec" main debug: TIMER module_need() : 22.706 ms - Total 22.706 ms / 1 intvls (Avg 22.706 ms) main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Users\mgray.MILTECRESEARCH\AppData\Roaming\vlc\lua\meta\reader lua debug: Trying Lua scripts in Z:\vendor_drops\vlc-1.2.0\lua\meta\reader lua debug: Trying Lua playlist script Z:\vendor_drops\vlc-1.2.0\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 10.900 ms - Total 10.900 ms / 1 intvls (Avg 10.900 ms) main debug: `rtsp://root:password@localhost/mpeg4/media.amp' successfully opened live555 warning: no data received in 10s. Switching to TCP main debug: removing module "avcodec" avcodec debug: ffmpeg codec (MPEG-4 Video) stopped main debug: killing decoder fourcc `mp4v', 0 PES in FIFO main debug: Program doesn't contain anymore ES live555 debug: RTP subsession 'video/MP4V-ES' main debug: looking for decoder module: 31 candidates avcodec debug: libavcodec already initialized avcodec debug: trying to use direct rendering avcodec debug: allowing 4 thread(s) for decoding avcodec debug: ffmpeg codec (MPEG-4 Video) started avcodec debug: using frame thread mode with 4 threads main debug: using decoder module "avcodec" main debug: TIMER module_need() : 10.427 ms - Total 10.427 ms / 1 intvls (Avg 10.427 ms) live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: play start: 0.000000 stop:0.000000 main debug: looking fmaoirn tdeexbtu gr:e nBduefrfeerr imnogd u0l% e: 2 candidates 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() : 9.398 ms - Total 9.398 ms / 1 intvls (Avg 9.398 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() : 6.794 ms - Total 6.794 ms / 1 intvls (Avg 6.794 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() : 7.289 ms - Total 7.289 ms / 1 intvls (Avg 7.289 ms) main debug: Deinterlacing available main debug: deinterlace 0, mode blend, is_needed 0 main debug: Opening vout display wrapper main debug: looking for vomauitn ddiesbpluagy: mSotdruelaem: b6u fcfaenrdiindga tdeos ne (66 ms in 37 ms) 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() : 20.740 ms - Total 20.740 ms / 1 intvls (Avg 20.740 ms) direct3d debug: created video sub-window direct3d debug: Vout EventThread running direct3d debug: disabling screen saver main debug: VoutDisplayEvent 'resize' 720x566 window direct3d debug: DirectXUpdateRects image_src_clipped coords: 0,0,704,480 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() : 77.976 ms - Total 77.976 ms / 1 intvls (Avg 77.976 ms) main debug: original format sz 704x480, of (0,0), vsz 704x480, 4cc I420, sar 10:11, msk r0x0 g0x0 b0x0 main debug: VoutDismpaliany Edveebnutg :' rreesmiozvei'n g7 0m4oxd5u2l8e w"ifnrdeoewt ype" direct3d debug: DirectmaiXnU pddeabtuegR:e cltosoking ifmoarg et_esxrtc _rcelnidpepreedr cmooodrudlse:: 02, 0c,a7n0d4i,d4a8t0 es 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() : 9.502 ms - Total 9.502 ms / 1 intvls (Avg 9.502 ms) avcodec debug: using direct rendering main debug: Decoder buffering done in 94 ms main debug: End of video preroll direct3d debug: Created 483x36 texture for OSD main debug: Post-processing available direct3d debug: Reusing 483x36 texture for OSD main warning: picture is too late to be displayed (missing 202 ms) direct3d debug: Reusing 483x36 texture for OSD main warning: picture is too late to be displayed (missing 196 ms) direct3d debug: Reusing 483x36 texture for OSD main warning: picture is too late to be displayed (missing 203 ms) direct3d debug: Reusing 483x36 texture for OSD main warning: picture is too late to be displayed (missing 265 ms) direct3d debug: Reusing 483x36 texture for OSD direct3d debug: Reusing 483x36 texture for OSD main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 0 ms) main error: ES_OUT_RESET_PCR called main debug: Buffering 0% main debug: Stream buffering done (66 ms in 89 ms) main debug: Decoder buffering done in 0 ms main error: ES_OUT_SET_ma(iGnR OdUePb_u)gP:C RE n di so fc avliledd etoo op rleartoel l (pts_delay increased to 22 ms) main warning: picture imsa itno oe rlraotre: tEoS _bOeU Td_iRsEpSlEaTy_ePdC R( mciaslsliendg 228 ms) main debug: Buffering 0% main debug: Stream buffering done (66 ms in 89 ms) main debug: Decoder buffering done in 0 ms main error: mEaSi_nO UdTe_bSuEgT:_ (EGnRdO UoPf_ )vPiCdRe o ipsr ecraolllle d too late (pts_delay increased to 24 ms) main error: ES_OUT_RmEaSiEnT _wPaCrRn icnagl:l epdi cture is too late to be displayed (missing 211 ms) main debug: Buffering 0% main debug: Stream buffering done (66 ms in 89 ms) main debug: Decoder buffering done in 0 ms main debug: auto hiding mouse cursor main debug: End of video preroll main warning: picture is too late to be displayed (missing 201 ms) main warning: picture is too late to be displayed (missing 241 ms) main warning: picture is too late to be displayed (missing 244 ms) main warning: picture is too late to be displayed (missing 223 ms) main warning: picture is too late to be displayed (missing 253 ms) main debug: auto hiding mouse cursor live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP() main error: ES_OUT_RESET_PCR called main debug: Buffering 0% main debug: Stream buffering done (66 ms in 97 ms) main debug: Decoder buffering done in 0 ms main debug: End of video preroll main warning: picture is too late to be displayed (missing 199 ms) main warning: picture is too late to be displayed (missing 243 ms) main warning: picture is too late to be displayed (missing 245 ms) main warning: picture is too late to be displayed (missing 220 ms) main warning: picture is too late to be displayed (missing 243 ms) avcodec error: more than 5 seconds of late video -> dropping frame (computer too slow ?) main warning: picture is too late to be displayed (missing 1240 ms) main warning: picture is too late to be displayed (missing 1130 ms) main warning: picture is too late to be displayed (missing 1140 ms) main warning: picture is too late to be displayed (missing 1027 ms) main warning: picture is too late to be displayed (missing 236 ms) avcodec error: more than 5 seconds of late video -> dropping frame (computer too slow ?) main warning: picture is too late to be displayed (missing 1273 ms) main warning: picture is too late to be displayed (missing 1129 ms) main warning: picture is too late to be displayed (missing 1140 ms) main warning: picture is too late to be displayed (missing 1026 ms) main warning: picture is too late to be displayed (missing 238 ms) main debug: auto hiding mouse cursor avcodec error: more than 5 seconds of late video -> dropping frame (computer too slow ?) main debug: auto hiding mouse cursor main debug: exiting main debug: deactivating the playlist main debug: deactivating the playlist main debug: incoming request - stopping current input main debug: dying input main debug: control type=ma0 in warning: picture is too late to be displayed (missing 1274 ms) main debug: control: stopping input main debug: dying input main debug: removing module "avcodec" avcodec debug: ffmpeg codec (MPEG-4 Video) stopped main debug: killing decoder fourcc `mp4v', 0 PES in FIFO main debug: saving a free vout main debug: reusing provided vout main debug: removing module "live555" main debug: Program doesn't contain anymore ES main debug: dead input 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: WinProqct 4W Md_eDbEuSgT:R OIYM : Deleting the input qt4 debug: releasing video... main debug: Destroying the input for 'rtsp://localhost/mpeg4/media.amp' main debug: TIMER input launching for 'rtsp://localhost/mpeg4/media.amp' : 183.577 ms - Total 183.577 ms / 1 intvls (Avg 183.577 ms) qt4 debug: Video is not needed anymore main debug: removing module "freetype" main debug: removing module "yuvp" main debug: removing module "swscale" main debug: saving Media Library to file C:\Users\mgray.MILTECRESEARCH\AppData\Roaming\vlc\ml.xspf main debug: looking for playlist export module: 1 candidate main debug: using playlist export module "export" main debug: TIMER module_need() : 4.769 ms - Total 4.769 ms / 1 intvls (Avg 4.769 ms) main debug: removing module "export" main debug: playlist correctly deactivated main debug: removing all services discovery tasks main debug: removing all interfaces main debug: removing module "qt4" qt4 debug: requesting exit... qt4 debug: waiting for UI thread... qt4 debug: QApp exec() finished qt4 debug: Video is not needed anymore qt4 debug: Killing extension dialog provider qt4 debug: ExtensionsDialogProvider is quitting... main debug: removing module "lua" lua debug: Deactivating all loaded extensions lua debug: All extensions are now deactivated main debug: removing module "globalhotkeys" main debug: removing module "hotkeys" main debug: removing module "logger" -- logger module stopped --

[VLC_help]

Something is completely broken in there. Messages like

Code: Select all

direct3d debug: WinProqct 4W Md_eDbEuSgT:R OIYM

should NEVER happen.

[nbilber]

hi

Where can I get the version vlc-1.2.0-git-20110615-0200-win32 ?

[hatboyzero]

@VLC_help : well, I'm running the binaries from the nightly build, and I'm pretty confident that all accesses to the exposed libVLC API are occurring in the same thread. In short, I don't *think* it's my code (I know, typical end-user developer response, but I promise it's not your typical arrogant know-it-all BS -- I just know that it's working with builds as far back as 20110829, and it's not working with this the latest I had tried).

Also, to further "exonerate" my code (and I say this while making air-quotes with my hands, of course), this log was generated while running the vlc.exe from the nightly build and not from a custom binary that is linking the libvlc.lib and loading the libvlc.dll dynamically.

Anyways, I guess the better question at this stage -- what is it that is "completely broken" in the latest nightly build of VLC, and how do we go about fixing it?

[VLC_help]

I can test Windows builds during weekend, so hopefully I say if it is a generic VLC issue or something to do with your computer/setup.

[hatboyzero]

Thanks -- let me know if there is anything I can provide in regards to information and/or assistance

[VLC_help]

I don't get the borked messages with my VLC, but I finally found a stream that causes the issue
rtsp://infogijon4.axiscam.net:554/mpeg4/media.amp

[hatboyzero]

*Bump* Any new status on this?

[hatboyzero]

Incidentally, it looks like build 1.3.0-git-20120104-0004 works.

[SimonReye]

I have found that with MJPEG streams RTSP works fine for live video feeds but if the video feed is MPEG4 then I need to set network-caching to at least 200ms or else it freezes. In our case this is not 'live' enough.

With the current release version of VLC we can set the caching down as low as 50ms with MPEG4 encoding with no freezing.

[circuitb]

hello all,
i've an ip camera gadspot n1250 (supports jpg, mjpeg, mpeg4)
i can see the video stream in vlc using
rtsp://ip_address:554/video.mp4
the camera has a standard config : 640x480 @ 25fps / bitrate = 1024
but i've 1 sec of delay so i try to change the network-cache in vlc
under 500ms image is freezing, but i really need a live feed so i try
with a directshow rtsp source filter and i've the minimum camera latency ..something like 150ms
so is there any workaround ? additional parameters to enter in vlc?
thanks
tested with vlc 2.0