Howto enable pre buffering?

[SiliconBear]

Dear experts,

I searched the forums but didn't see any relevant answers.

I'm sending two RTP streams to a multicast address over a uni-directional channel. I use vlc 0.9.3 to playout the video/audio. The RTP sessions are described by an SDP file.

I noticed that vlc does not do any pre buffering: i.e., the player starts playing right after receiving the first byte. Then, there are quite some late frames (as well as audio samples). I tried to increase the cache by --udp-caching 10000 or --rtp-caching 10000, but still there is still no pre-buffering. I wonder whether it's due to my mis-formated SDP file?

Thanks,
Cheng

My SDP file is:
c=IN IP4 224.0.0.1
m=video 11110 RTP/AVP 96
a=control:trackID=3
a=rtpmap:96 H264/90000
a=fmtp:96 profile-level-id=674d40; sprop-parameter-sets=Z01ADZJWCwzYCIACi7GAmJaAR4oVcA==,aO4yyA==; packetization-mode=1
c=IN IP4 224.0.0.1
m=audio 11112 RTP/AVP 97
a=control:trackID=4
a=rtpmap:97 mpeg4-generic/96000/2
a=mpeg4-esid:2
a=fmtp:97 streamtype=5; profile-level-id=15; mode=AAC-hbr; config=1010; SizeLength=13; IndexLength=3; IndexDeltaLength=3;


The vlc -vvv outputs:
VLC media player 0.9.3 Grishenko
[00000001] main libvlc debug: VLC media player - version 0.9.3 Grishenko - (c) 1996-2008 the VideoLAN team
[00000001] main libvlc debug: libvlc was configured with ./configure '--mandir=/share/man' '--infodir=/share/info' '--host=i486-linux-gnu' '--build=i486-linux-gnu' '--enable-maintaner-mode' '--enable-release' '--prefix=/usr' '--enable-libtool' '--enable-fast-install' '--disable-update-check' '--disable-gnome' '--disable-gtk' '--disable-familiar' '--disable-fb' '--enable-ggi' '--enable-sdl' '--enable-esd' '--enable-mad' '--enable-arts' '--enable-jack' '--enable-pulse' '--enable-lirc' '--enable-a52' '--enable-aa' '--enable-dvbpsi' '--enable-mozilla' '--with-mozilla-pkg=libxul-plugin' '--disable-kde' '--enable-mp4' '--enable-dvb' '--disable-satellite' '--enable-ogg' '--enable-vorbis' '--enable-shout' '--enable-qt4' '--disable-slp' '--enable-flac' '--disable-skins' '--disable-basic-skins' '--enable-skins2' '--enable-freetype' '--enable-mkv' '--enable-speex' '--enable-caca' '--enable-live555' '--enable-libmpeg2' '--enable-fribidi' '--enable-cdio' '--enable-mod' '--enable-theora' '--enable-modplug' '--enable-dvdnav' '--enable-gnutls' '--enable-ffmpeg' '--enable-ncurses' '--enable-smb' '--disable-gnomevfs' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--disable-zvbi' '--enable-telx' '--enable-mediacontrol-bindings' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-libdca' '--enable-real' '--enable-realrtsp' '--enable-alsa' '--enable-dv' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-faad' '--with-faad-tree=extras/faad2' '--enable-x264' '--with-x264-tree=extras/x264' '--enable-glide' '--enable-svgalib' '--enable-dvd' '--without-dvdcss' 'build_alias=i486-linux-gnu' 'host_alias=i486-linux-gnu' 'CFLAGS=-g -O2' 'LDFLAGS=-Wl,-Bsymbolic-functions' 'CPPFLAGS=' 'CXXFLAGS=-g -O2' 'PKG_CONFIG_PATH=:extras/x264:extras/faad2'
[00000001] main libvlc debug: translation test: code is "C"
[00000001] main libvlc debug: checking builtin modules
[00000001] main libvlc debug: checking plugin modules
[00000001] main libvlc debug: loading plugins cache file /home/cha16/.cache/vlc/plugins-04041e.dat
[00000001] main libvlc debug: recursively browsing `/usr/lib/vlc'
[00000001] main libvlc debug: module bank initialized, found 281 modules
[00000001] main libvlc debug: opening config file (/home/cha16/.config/vlc/vlcrc)
[00000001] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU
[00000001] main libvlc debug: looking for memcpy module: 3 candidates
[00000001] main libvlc debug: using memcpy module "memcpymmxext"
[00000378] main interaction debug: thread started
[00000378] main interaction debug: thread 3080903568 (Interaction control) created at priority 0 (interface/interaction.c:382)
[00000380] main input debug: Creating an input for 'Media Library'
[00000380] main input debug: Input is a meta file: disabling unneeded options
[00000380] main input debug: `file/xspf-open:///home/cha16/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/cha16/.local/share/vlc/ml.xspf'
[00000380] main input debug: creating access 'file' path='/home/cha16/.local/share/vlc/ml.xspf'
[00000381] main access debug: looking for access module: 3 candidates
[00000381] access_mmap access debug: opening file /home/cha16/.local/share/vlc/ml.xspf
[00000381] main access debug: using access module "access_mmap"
[00000381] main access debug: TIMER module_Need() : 0.305 ms - Total 0.305 ms / 1 intvls (Avg 0.305 ms)
[00000381] main access debug: creating access filter 'timeshift'
[00000385] main access debug: looking for access_filter module: 1 candidate
[00000381] access_filter_timeshift access debug: ACCESS_CAN_CONTROL_PACE: timeshift useless
[00000385] main access warning: no access_filter module matching "timeshift" could be loaded
[00000385] main access debug: TIMER module_Need() : 8.066 ms - Total 8.066 ms / 1 intvls (Avg 8.066 ms)
[00000380] main input warning: failed to insert access filter timeshift
[00000387] main stream debug: Using AStream*Block
[00000387] main stream debug: pre buffering
[00000387] main stream debug: received first data for our buffer
[00000381] access_mmap access debug: at end of memory mapped file
[00000380] main input debug: creating demux: access='file' demux='xspf-open' path='/home/cha16/.local/share/vlc/ml.xspf'
[00000388] main demux debug: looking for demux module: 1 candidate
[00000388] playlist demux debug: using XSPF playlist reader
[00000388] main demux debug: using demux module "playlist"
[00000388] main demux debug: TIMER module_Need() : 0.251 ms - Total 0.251 ms / 1 intvls (Avg 0.251 ms)
[00000380] main input debug: `file/xspf-open:///home/cha16/.local/share/vlc/ml.xspf' successfully opened
[00000403] main xml debug: looking for xml module: 2 candidates
[00000403] main xml debug: using xml module "xml"
[00000403] main xml debug: TIMER module_Need() : 0.423 ms - Total 0.423 ms / 1 intvls (Avg 0.423 ms)
[00000381] access_mmap access debug: at end of memory mapped file
[00000388] playlist demux warning: invalid <playlist> attribute:"xmlns:vlc"
[00000388] playlist demux debug: parsed 0 tracks successfully
[00000403] main xml debug: removing module "xml"
[00000380] main input debug: EOF reached
[00000380] main input debug: control type=1
[00000388] main demux debug: removing module "playlist"
[00000381] main access debug: removing module "access_mmap"
[00000380] main input debug: TIMER input launching for 'Media Library' : 10.845 ms - Total 10.845 ms / 1 intvls (Avg 10.845 ms)
[00000405] main preparser debug: thread started
[00000405] main preparser debug: waiting for thread initialization
[00000405] main preparser debug: thread 3071085456 (preparser) created at priority 0 (playlist/thread.c:79)
[00000406] main fetcher debug: thread started
[00000406] main fetcher debug: waiting for thread initialization
[00000406] main fetcher debug: thread 3062692752 (fetcher) created at priority 0 (playlist/thread.c:108)
[00000379] main playlist debug: thread started
[00000379] main playlist debug: waiting for thread initialization
[00000379] main playlist debug: rebuilding array of current - root Playlist
[00000379] main playlist debug: rebuild done - 0 items, index -1
[00000379] main playlist debug: thread 3054300048 (playlist) created at priority 0 (playlist/thread.c:117)
[00000407] main interface debug: looking for interface module: 1 candidate
[00000407] main interface debug: using interface module "hotkeys"
[00000407] main interface debug: TIMER module_Need() : 0.144 ms - Total 0.144 ms / 1 intvls (Avg 0.144 ms)
[00000407] main interface debug: thread started
[00000407] main interface debug: thread 3045907344 (interface) created at priority 0 (interface/interface.c:168)
[00000409] main interface debug: looking for interface module: 1 candidate
[00000409] main interface debug: using interface module "inhibit"
[00000409] main interface debug: TIMER module_Need() : 1.463 ms - Total 1.463 ms / 1 intvls (Avg 1.463 ms)
[00000409] main interface debug: thread started
[00000409] main interface debug: thread 3037514640 (interface) created at priority 0 (interface/interface.c:168)
[00000411] main interface debug: looking for interface module: 1 candidate
[00000411] main interface debug: using interface module "screensaver"
[00000411] main interface debug: TIMER module_Need() : 0.159 ms - Total 0.159 ms / 1 intvls (Avg 0.159 ms)
[00000411] main interface debug: thread started
[00000411] main interface debug: thread 3029121936 (interface) created at priority 0 (interface/interface.c:168)
[00000379] main playlist debug: adding item `bear2.sdp' ( bear2.sdp )
[00000413] main interface debug: looking for interface module: 22 candidates
[00000413] main interface debug: using interface module "signals"
[00000413] main interface debug: TIMER module_Need() : 0.158 ms - Total 0.158 ms / 1 intvls (Avg 0.158 ms)
[00000413] main interface debug: thread started
[00000413] main interface debug: thread 3012336528 (interface) created at priority 0 (interface/interface.c:168)
[00000001] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[00000415] main interface debug: looking for interface module: 4 candidates
[00000415] main interface debug: using interface module "qt4"
[00000415] main interface debug: TIMER module_Need() : 13.563 ms - Total 13.563 ms / 1 intvls (Avg 13.563 ms)
[00000415] main interface debug: thread started
[00000415] main interface debug: thread 2985401232 (interface) created at priority 0 (interface/interface.c:168)
[00000379] main playlist debug: rebuilding array of current - root Playlist
[00000379] main playlist debug: rebuild done - 1 items, index -1
[00000379] main playlist debug: starting new item
[00000379] main playlist debug: processing request item null node Playlist skip 0
[00000379] main playlist debug: creating new input thread
[00000419] main input debug: Creating an input for 'bear2.sdp'
[00000419] main input debug: thread started
[00000419] main input debug: waiting for thread initialization
[00000419] main input debug: `bear2.sdp' gives access `' demux `' path `bear2.sdp'
[00000419] main input debug: thread 2974808976 (input) created at priority 10 (input/input.c:370)
[00000419] main input debug: creating demux: access='' demux='' path='bear2.sdp'
[00000420] main demux debug: looking for access_demux module: 3 candidates
[00000420] main demux debug: TIMER module_Need() : 0.853 ms - Total 0.853 ms / 1 intvls (Avg 0.853 ms)
[00000419] main input debug: creating access '' path='bear2.sdp'
[00000425] main access debug: looking for access module: 7 candidates
[00000425] vcd access debug: trying .cue file: bear2.cue
[00000425] vcd access debug: could not find .cue file
[00000425] access_mmap access debug: opening file bear2.sdp
[00000425] main access debug: using access module "access_mmap"
[00000425] main access debug: TIMER module_Need() : 1.463 ms - Total 1.463 ms / 1 intvls (Avg 1.463 ms)
[00000425] main access debug: creating access filter 'timeshift'
[00000428] main access debug: looking for access_filter module: 1 candidate
[00000425] access_filter_timeshift access debug: ACCESS_CAN_CONTROL_PACE: timeshift useless
[00000428] main access warning: no access_filter module matching "timeshift" could be loaded
[00000428] main access debug: TIMER module_Need() : 0.143 ms - Total 0.143 ms / 1 intvls (Avg 0.143 ms)
[00000419] main input warning: failed to insert access filter timeshift
[00000429] main stream debug: Using AStream*Block
[00000429] main stream debug: pre buffering
[00000429] main stream debug: received first data for our buffer
[00000425] access_mmap access debug: at end of memory mapped file
[00000419] main input debug: creating demux: access='' demux='' path='bear2.sdp'
[00000430] main demux debug: looking for demux module: 52 candidates
[00000425] access_mmap access debug: at end of memory mapped file
[00000430] live555 demux debug: RTP subsession 'video/H264'
[00000419] main input debug: selecting program id=0
[00000430] live555 demux debug: RTP subsession 'audio/MPEG4-GENERIC'
[00000430] live555 demux debug: setup start: 0 stop:0
[00000430] live555 demux debug: play start: 0 stop:0
[00000430] main demux debug: using demux module "live555"
[00000430] main demux debug: TIMER module_Need() : 2.890 ms - Total 2.890 ms / 1 intvls (Avg 2.890 ms)
[00000419] main input debug: looking for a subtitle file in /home/cha16/DVB-H/scripts/receiver/
[00000448] main decoder debug: looking for decoder module: 31 candidates
[00000448] avcodec decoder debug: libavcodec initialized (interface 3352064 )
[00000448] avcodec decoder debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
[00000448] main decoder debug: using decoder module "avcodec"
[00000448] main decoder debug: TIMER module_Need() : 5.107 ms - Total 5.107 ms / 1 intvls (Avg 5.107 ms)
[00000480] main packetizer debug: looking for packetizer module: 18 candidates
[00000480] packetizer_h264 packetizer debug: found NAL_SPS (sps_id=0)
[00000480] main packetizer debug: using packetizer module "packetizer_h264"
[00000480] main packetizer debug: TIMER module_Need() : 2.206 ms - Total 2.206 ms / 1 intvls (Avg 2.206 ms)
[00000448] main decoder debug: thread started
[00000448] main decoder debug: thread 2957523856 (decoder) created at priority 0 (input/decoder.c:217)
[00000491] main decoder debug: looking for decoder module: 31 candidates
[00000491] main decoder debug: using decoder module "faad"
[00000491] main decoder debug: TIMER module_Need() : 0.238 ms - Total 0.238 ms / 1 intvls (Avg 0.238 ms)
[00000491] main decoder debug: thread started
[00000491] main decoder debug: thread 2949131152 (decoder) created at priority 5 (input/decoder.c:217)
[00000419] main input debug: `bear2.sdp' successfully opened
[00000415] qt4 interface debug: Error while initializing qt-specific localization
[00000415] qt4 interface debug: Updating the stream status: 3
[00000419] main input debug: control type=1
[00000480] packetizer_h264 packetizer debug: found NAL_PPS (pps_id=0 sps_id=0)
[00000491] faad decoder warning: decoded zero sample
[00000491] main decoder debug: no aout present, spawning one
[00000492] main audio output debug: looking for audio output module: 7 candidates
[00000492] alsa audio output debug: opening ALSA device `default'
[00000492] alsa audio output warning: unable to commit hardware configuration with fl32 samples. Retrying with s16l (Invalid argument)
[00000492] main audio output debug: thread 2937912208 (aout) created at priority 15 (alsa.c:687)
[00000492] main audio output debug: thread started
[00000492] main audio output debug: using audio output module "alsa"
[00000492] main audio output debug: TIMER module_Need() : 36.843 ms - Total 36.843 ms / 1 intvls (Avg 36.843 ms)
[00000492] main audio output debug: output 's16l' 96000 Hz Stereo frame=1 samples/4 bytes
[00000492] main audio output debug: mixer 'fl32' 96000 Hz Stereo frame=1 samples/8 bytes
[00000492] main audio output debug: filter(s) 'fl32'->'s16l' 96000 Hz->96000 Hz Stereo->Stereo
[00000493] main audio output debug: looking for audio filter module: 23 candidates
[00000493] main audio output debug: using audio filter module "converter_float"
[00000493] main audio output debug: TIMER module_Need() : 1.331 ms - Total 1.331 ms / 1 intvls (Avg 1.331 ms)
[00000492] main audio output debug: found a filter for the whole conversion
[00000492] main audio output debug: looking for audio mixer module: 3 candidates
[00000492] main audio output debug: using audio mixer module "float32_mixer"
[00000492] main audio output debug: TIMER module_Need() : 0.144 ms - Total 0.144 ms / 1 intvls (Avg 0.144 ms)
[00000492] main audio output debug: input 'fl32' 96000 Hz Stereo frame=1 samples/8 bytes
[00000492] main audio output debug: filter(s) 'fl32'->'fl32' 105600 Hz->96000 Hz Stereo->Stereo
[00000525] main audio output debug: looking for audio filter module: 23 candidates
[00000525] main audio output debug: using audio filter module "bandlimited_resampler"
[00000525] main audio output debug: TIMER module_Need() : 0.101 ms - Total 0.101 ms / 1 intvls (Avg 0.101 ms)
[00000492] main audio output debug: found a filter for the whole conversion
[00000448] main decoder debug: no usable vout present, spawning one
[00000526] main video output debug: window size: 352x192
[00000526] main video output debug: looking for video output module: 8 candidates
[00000526] xvideo video output debug: adaptor 0, port 88, format 0x32315659 (YV12) planar
[00000529] main window debug: looking for vout window module: 2 candidates
[00000529] qt4 window debug: waiting for interface...
[00000529] qt4 window debug: requesting window...
[00000415] qt4 interface debug: Video was requested -1, -1
[00000415] qt4 interface debug: Video is resizing to: 352 192
[00000526] qt4 video output debug: Qt FS: Attaching Vout
[00000526] qt4 video output debug: Qt: Changing Fullscreen Mode
[00000529] main window debug: using vout window module "qt4"
[00000529] main window debug: TIMER module_Need() : 1.232 ms - Total 1.232 ms / 1 intvls (Avg 1.232 ms)
[00000415] qt4 interface debug: Updating the geometry
[00000526] xvideo video output debug: XShm video extension v1.1 (without pixmaps, opcode: 146)
[00000526] xvideo video output debug: Window manager supports NetWM
[00000526] xvideo video output debug: Window manager supports _NET_WM_STATE_FULLSCREEN
[00000526] xvideo video output debug: Window manager supports _NET_WM_STATE_ABOVE
[00000526] xvideo video output debug: Window manager supports _NET_WM_STATE_BELOW
[00000526] main video output debug: using video output module "xvideo"
[00000526] main video output debug: TIMER module_Need() : 7.953 ms - Total 7.953 ms / 1 intvls (Avg 7.953 ms)
[00000526] main video output debug: thread started
[00000526] main video output debug: got 8 direct buffer(s)
[00000526] main video output debug: picture in 352x192 (0,0,352x192), chroma I420, ar 11:6, sar 1:1
[00000526] main video output debug: picture user 352x192 (0,0,352x192), chroma I420, ar 11:6, sar 1:1
[00000526] main video output debug: picture out 352x192 (0,0,352x192), chroma I420, ar 11:6, sar 1:1
[00000526] main video output debug: direct render, mapping render pictures 0-6 to system pictures 1-7
QPainter::begin: Paint device returned engine == 0, type: 1
QPainter::begin: Paint device returned engine == 0, type: 1
[00000526] main video output debug: waiting for thread initialization
[00000526] main video output debug: thread 2927885200 (video output) created at priority 15 (video_output/video_output.c:504)
[00000415] qt4 interface debug: New Event: type 1109
[00000534] main spu text debug: looking for text renderer module: 2 candidates
[00000536] main generic debug: thread 2918120336 (fontlist builder) created at priority 0 (freetype.c:477)
[00000536] main generic debug: thread started
[00000534] freetype spu text debug: using fontsize: 12
[00000534] main spu text debug: using text renderer module "freetype"
[00000534] main spu text debug: TIMER module_Need() : 1.110 ms - Total 1.110 ms / 1 intvls (Avg 1.110 ms)
[00000534] freetype spu text debug: using fontsize: 12
[00000533] main blend debug: looking for video blending module: 1 candidate
[00000533] blend blend debug: chroma: YUVA -> I420
[00000533] main blend debug: using video blending module "blend"
[00000533] main blend debug: TIMER module_Need() : 0.189 ms - Total 0.189 ms / 1 intvls (Avg 0.189 ms)
[00000536] freetype generic debug: Building font database...
[00000536] freetype generic debug: Finished building font database.
[00000536] freetype generic debug: Took 0 seconds
[00000536] main generic debug: thread ended
[00000492] alsa audio output debug: recovered from buffer underrun
[00000413] signals interface error: Caught Interrupt signal, exiting...
[00000413] main interface debug: thread ended
[00000001] main libvlc debug: removing all interfaces
[00000379] main playlist debug: incoming request - stopping current input
[00000379] main playlist debug: dying input
[00000415] qt4 interface debug: Quitting the Qt4 Interface
[00000526] qt4 video output debug: Qt FS: Detaching Vout
[00000526] qt4 video output debug: Qt: Changing Fullscreen Mode
[00000415] qt4 interface debug: Destroying the main interface
[00000379] main playlist debug: dying input
[00000529] main window debug: removing module "qt4"
[00000415] qt4 interface debug: Destroying the Dialog Provider
[00000415] main interface debug: thread ended
[00000415] main interface debug: thread 2985401232 joined (interface/interface.c:188)
[00000415] main interface debug: removing module "qt4"
[00000413] main interface debug: thread 3012336528 joined (interface/interface.c:188)
[00000413] main interface debug: removing module "signals"
[00000411] main interface debug: thread ended
[00000411] main interface debug: thread 3029121936 joined (interface/interface.c:188)
[00000411] main interface debug: removing module "screensaver"
[00000409] main interface debug: thread ended
[00000409] main interface debug: thread 3037514640 joined (interface/interface.c:188)
[00000409] main interface debug: removing module "inhibit"
[00000407] main interface debug: thread ended
[00000407] main interface debug: thread 3045907344 joined (interface/interface.c:188)
[00000407] main interface debug: removing module "hotkeys"
[00000001] main libvlc debug: removing all services discovery tasks
[00000001] main libvlc debug: removing playlist
[00000379] main playlist debug: dying input
[00000419] main input debug: control type=0
[00000419] main input debug: control: stopping input
[00000430] main demux debug: removing module "live555"
[00000425] main access debug: removing module "access_mmap"
[00000448] avcodec decoder debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
[00000448] main decoder debug: removing module "avcodec"
[00000448] main decoder debug: thread ended
[00000448] main decoder debug: thread 2957523856 joined (input/decoder.c:248)
[00000448] main decoder debug: killing decoder fourcc `h264', 0 PES in FIFO
[00000533] main blend debug: removing module "blend"
[00000536] main generic debug: thread 2918120336 joined (freetype.c:511)
[00000534] main spu text debug: removing module "freetype"
[00000526] main video output debug: thread ended
[00000526] main video output debug: thread 2927885200 joined (video_output/video_output.c:536)
[00000379] main playlist debug: dying input
[00000526] main video output debug: removing module "xvideo"
[00000480] main packetizer debug: removing module "packetizer_h264"
[00000491] main decoder debug: removing module "faad"
[00000491] main decoder debug: thread ended
[00000491] main decoder debug: thread 2949131152 joined (input/decoder.c:248)
[00000491] main decoder debug: killing decoder fourcc `mp4a', 0 PES in FIFO
[00000525] main audio output debug: removing module "bandlimited_resampler"
[00000492] main audio output debug: thread ended
[00000492] main audio output debug: thread 2937912208 joined (alsa.c:742)
[00000492] main audio output debug: removing module "alsa"
[00000493] main audio output debug: removing module "converter_float"
[00000492] main audio output debug: removing module "float32_mixer"
[00000419] main input debug: thread ended
[00000379] main playlist debug: dead input
[00000419] main input debug: thread 2974808976 joined (playlist/engine.c:244)
[00000419] main input debug: TIMER input launching for 'bear2.sdp' : 15.577 ms - Total 15.577 ms / 1 intvls (Avg 15.577 ms)
[00000379] main playlist debug: saving Media Library to file /home/cha16/.local/share/vlc/ml.xspf
[00000379] main playlist debug: looking for playlist export module: 1 candidate
[00000379] main playlist debug: using playlist export module "export"
[00000379] main playlist debug: TIMER module_Need() : 0.282 ms - Total 0.282 ms / 1 intvls (Avg 0.282 ms)
[00000379] main playlist debug: removing module "export"
[00000405] main preparser debug: thread ended
[00000405] main preparser debug: thread 3071085456 joined (playlist/engine.c:521)
[00000406] main fetcher debug: thread ended
[00000406] main fetcher debug: thread 3062692752 joined (playlist/engine.c:523)
[00000379] main playlist debug: thread ended
[00000379] main playlist debug: thread 3054300048 joined (libvlc.c:992)
[00000405] main preparser debug: Destroyed
[00000406] main fetcher debug: Destroyed
[00000379] main playlist debug: Destroyed
[00000001] main libvlc debug: removing interaction
[00000378] main interaction debug: thread ended
[00000378] main interaction debug: thread 3080903568 joined (interface/interaction.c:400)
[00000001] main libvlc debug: removing all video outputs
[00000001] main libvlc debug: TIMER ML Load : Total 11.554 ms / 1 intvls (Avg 11.554 ms)
[00000001] main libvlc debug: TIMER Items array build : Total 0.033 ms / 2 intvls (Avg 0.017 ms)
[00000001] main libvlc debug: TIMER ML Dump : Total 0.359 ms / 1 intvls (Avg 0.359 ms)
[00000001] main libvlc debug: removing stats
[00000001] main libvlc debug: removing module "memcpymmxext"
[00000001] main libvlc debug: opening config file (/home/cha16/.config/vlc/vlcrc)
[00000001] main libvlc debug: opening config file (/home/cha16/.config/vlc/vlcrc)
[00000001] main libvlc debug: writing plugins cache /home/cha16/.cache/vlc/plugins-0

The -vvv log looks like:
VLC media player 0.9.3 Grishenko
[00000001] main libvlc debug: VLC media player - version 0.9.3 Grishenko - (c) 1996-2008 the VideoLAN team
[00000001] main libvlc debug: libvlc was configured with ./configure '--mandir=/share/man' '--infodir=/share/info' '--host=i486-linux-gnu' '--build=i486-linux-gnu' '--enable-maintaner-mode' '--enable-release' '--prefix=/usr' '--enable-libtool' '--enable-fast-install' '--disable-update-check' '--disable-gnome' '--disable-gtk' '--disable-familiar' '--disable-fb' '--enable-ggi' '--enable-sdl' '--enable-esd' '--enable-mad' '--enable-arts' '--enable-jack' '--enable-pulse' '--enable-lirc' '--enable-a52' '--enable-aa' '--enable-dvbpsi' '--enable-mozilla' '--with-mozilla-pkg=libxul-plugin' '--disable-kde' '--enable-mp4' '--enable-dvb' '--disable-satellite' '--enable-ogg' '--enable-vorbis' '--enable-shout' '--enable-qt4' '--disable-slp' '--enable-flac' '--disable-skins' '--disable-basic-skins' '--enable-skins2' '--enable-freetype' '--enable-mkv' '--enable-speex' '--enable-caca' '--enable-live555' '--enable-libmpeg2' '--enable-fribidi' '--enable-cdio' '--enable-mod' '--enable-theora' '--enable-modplug' '--enable-dvdnav' '--enable-gnutls' '--enable-ffmpeg' '--enable-ncurses' '--enable-smb' '--disable-gnomevfs' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--disable-zvbi' '--enable-telx' '--enable-mediacontrol-bindings' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-libdca' '--enable-real' '--enable-realrtsp' '--enable-alsa' '--enable-dv' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-faad' '--with-faad-tree=extras/faad2' '--enable-x264' '--with-x264-tree=extras/x264' '--enable-glide' '--enable-svgalib' '--enable-dvd' '--without-dvdcss' 'build_alias=i486-linux-gnu' 'host_alias=i486-linux-gnu' 'CFLAGS=-g -O2' 'LDFLAGS=-Wl,-Bsymbolic-functions' 'CPPFLAGS=' 'CXXFLAGS=-g -O2' 'PKG_CONFIG_PATH=:extras/x264:extras/faad2'
[00000001] main libvlc debug: translation test: code is "C"
[00000001] main libvlc debug: checking builtin modules
[00000001] main libvlc debug: checking plugin modules
[00000001] main libvlc debug: loading plugins cache file /home/cha16/.cache/vlc/plugins-04041e.dat
[00000001] main libvlc debug: recursively browsing `/usr/lib/vlc'
[00000001] main libvlc debug: module bank initialized, found 281 modules
[00000001] main libvlc debug: opening config file (/home/cha16/.config/vlc/vlcrc)
[00000001] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU
[00000001] main libvlc debug: looking for memcpy module: 3 candidates
[00000001] main libvlc debug: using memcpy module "memcpymmxext"
[00000378] main interaction debug: thread started
[00000378] main interaction debug: thread 3080903568 (Interaction control) created at priority 0 (interface/interaction.c:382)
[00000380] main input debug: Creating an input for 'Media Library'
[00000380] main input debug: Input is a meta file: disabling unneeded options
[00000380] main input debug: `file/xspf-open:///home/cha16/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/cha16/.local/share/vlc/ml.xspf'
[00000380] main input debug: creating access 'file' path='/home/cha16/.local/share/vlc/ml.xspf'
[00000381] main access debug: looking for access module: 3 candidates
[00000381] access_mmap access debug: opening file /home/cha16/.local/share/vlc/ml.xspf
[00000381] main access debug: using access module "access_mmap"
[00000381] main access debug: TIMER module_Need() : 0.305 ms - Total 0.305 ms / 1 intvls (Avg 0.305 ms)
[00000381] main access debug: creating access filter 'timeshift'
[00000385] main access debug: looking for access_filter module: 1 candidate
[00000381] access_filter_timeshift access debug: ACCESS_CAN_CONTROL_PACE: timeshift useless
[00000385] main access warning: no access_filter module matching "timeshift" could be loaded
[00000385] main access debug: TIMER module_Need() : 8.066 ms - Total 8.066 ms / 1 intvls (Avg 8.066 ms)
[00000380] main input warning: failed to insert access filter timeshift
[00000387] main stream debug: Using AStream*Block
[00000387] main stream debug: pre buffering
[00000387] main stream debug: received first data for our buffer
[00000381] access_mmap access debug: at end of memory mapped file
[00000380] main input debug: creating demux: access='file' demux='xspf-open' path='/home/cha16/.local/share/vlc/ml.xspf'
[00000388] main demux debug: looking for demux module: 1 candidate
[00000388] playlist demux debug: using XSPF playlist reader
[00000388] main demux debug: using demux module "playlist"
[00000388] main demux debug: TIMER module_Need() : 0.251 ms - Total 0.251 ms / 1 intvls (Avg 0.251 ms)
[00000380] main input debug: `file/xspf-open:///home/cha16/.local/share/vlc/ml.xspf' successfully opened
[00000403] main xml debug: looking for xml module: 2 candidates
[00000403] main xml debug: using xml module "xml"
[00000403] main xml debug: TIMER module_Need() : 0.423 ms - Total 0.423 ms / 1 intvls (Avg 0.423 ms)
[00000381] access_mmap access debug: at end of memory mapped file
[00000388] playlist demux warning: invalid <playlist> attribute:"xmlns:vlc"
[00000388] playlist demux debug: parsed 0 tracks successfully
[00000403] main xml debug: removing module "xml"
[00000380] main input debug: EOF reached
[00000380] main input debug: control type=1
[00000388] main demux debug: removing module "playlist"
[00000381] main access debug: removing module "access_mmap"
[00000380] main input debug: TIMER input launching for 'Media Library' : 10.845 ms - Total 10.845 ms / 1 intvls (Avg 10.845 ms)
[00000405] main preparser debug: thread started
[00000405] main preparser debug: waiting for thread initialization
[00000405] main preparser debug: thread 3071085456 (preparser) created at priority 0 (playlist/thread.c:79)
[00000406] main fetcher debug: thread started
[00000406] main fetcher debug: waiting for thread initialization
[00000406] main fetcher debug: thread 3062692752 (fetcher) created at priority 0 (playlist/thread.c:108)
[00000379] main playlist debug: thread started
[00000379] main playlist debug: waiting for thread initialization
[00000379] main playlist debug: rebuilding array of current - root Playlist
[00000379] main playlist debug: rebuild done - 0 items, index -1
[00000379] main playlist debug: thread 3054300048 (playlist) created at priority 0 (playlist/thread.c:117)
[00000407] main interface debug: looking for interface module: 1 candidate
[00000407] main interface debug: using interface module "hotkeys"
[00000407] main interface debug: TIMER module_Need() : 0.144 ms - Total 0.144 ms / 1 intvls (Avg 0.144 ms)
[00000407] main interface debug: thread started
[00000407] main interface debug: thread 3045907344 (interface) created at priority 0 (interface/interface.c:168)
[00000409] main interface debug: looking for interface module: 1 candidate
[00000409] main interface debug: using interface module "inhibit"
[00000409] main interface debug: TIMER module_Need() : 1.463 ms - Total 1.463 ms / 1 intvls (Avg 1.463 ms)
[00000409] main interface debug: thread started
[00000409] main interface debug: thread 3037514640 (interface) created at priority 0 (interface/interface.c:168)
[00000411] main interface debug: looking for interface module: 1 candidate
[00000411] main interface debug: using interface module "screensaver"
[00000411] main interface debug: TIMER module_Need() : 0.159 ms - Total 0.159 ms / 1 intvls (Avg 0.159 ms)
[00000411] main interface debug: thread started
[00000411] main interface debug: thread 3029121936 (interface) created at priority 0 (interface/interface.c:168)
[00000379] main playlist debug: adding item `bear2.sdp' ( bear2.sdp )
[00000413] main interface debug: looking for interface module: 22 candidates
[00000413] main interface debug: using interface module "signals"
[00000413] main interface debug: TIMER module_Need() : 0.158 ms - Total 0.158 ms / 1 intvls (Avg 0.158 ms)
[00000413] main interface debug: thread started
[00000413] main interface debug: thread 3012336528 (interface) created at priority 0 (interface/interface.c:168)
[00000001] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[00000415] main interface debug: looking for interface module: 4 candidates
[00000415] main interface debug: using interface module "qt4"
[00000415] main interface debug: TIMER module_Need() : 13.563 ms - Total 13.563 ms / 1 intvls (Avg 13.563 ms)
[00000415] main interface debug: thread started
[00000415] main interface debug: thread 2985401232 (interface) created at priority 0 (interface/interface.c:168)
[00000379] main playlist debug: rebuilding array of current - root Playlist
[00000379] main playlist debug: rebuild done - 1 items, index -1
[00000379] main playlist debug: starting new item
[00000379] main playlist debug: processing request item null node Playlist skip 0
[00000379] main playlist debug: creating new input thread
[00000419] main input debug: Creating an input for 'bear2.sdp'
[00000419] main input debug: thread started
[00000419] main input debug: waiting for thread initialization
[00000419] main input debug: `bear2.sdp' gives access `' demux `' path `bear2.sdp'
[00000419] main input debug: thread 2974808976 (input) created at priority 10 (input/input.c:370)
[00000419] main input debug: creating demux: access='' demux='' path='bear2.sdp'
[00000420] main demux debug: looking for access_demux module: 3 candidates
[00000420] main demux debug: TIMER module_Need() : 0.853 ms - Total 0.853 ms / 1 intvls (Avg 0.853 ms)
[00000419] main input debug: creating access '' path='bear2.sdp'
[00000425] main access debug: looking for access module: 7 candidates
[00000425] vcd access debug: trying .cue file: bear2.cue
[00000425] vcd access debug: could not find .cue file
[00000425] access_mmap access debug: opening file bear2.sdp
[00000425] main access debug: using access module "access_mmap"
[00000425] main access debug: TIMER module_Need() : 1.463 ms - Total 1.463 ms / 1 intvls (Avg 1.463 ms)
[00000425] main access debug: creating access filter 'timeshift'
[00000428] main access debug: looking for access_filter module: 1 candidate
[00000425] access_filter_timeshift access debug: ACCESS_CAN_CONTROL_PACE: timeshift useless
[00000428] main access warning: no access_filter module matching "timeshift" could be loaded
[00000428] main access debug: TIMER module_Need() : 0.143 ms - Total 0.143 ms / 1 intvls (Avg 0.143 ms)
[00000419] main input warning: failed to insert access filter timeshift
[00000429] main stream debug: Using AStream*Block
[00000429] main stream debug: pre buffering
[00000429] main stream debug: received first data for our buffer
[00000425] access_mmap access debug: at end of memory mapped file
[00000419] main input debug: creating demux: access='' demux='' path='bear2.sdp'
[00000430] main demux debug: looking for demux module: 52 candidates
[00000425] access_mmap access debug: at end of memory mapped file
[00000430] live555 demux debug: RTP subsession 'video/H264'
[00000419] main input debug: selecting program id=0
[00000430] live555 demux debug: RTP subsession 'audio/MPEG4-GENERIC'
[00000430] live555 demux debug: setup start: 0 stop:0
[00000430] live555 demux debug: play start: 0 stop:0
[00000430] main demux debug: using demux module "live555"
[00000430] main demux debug: TIMER module_Need() : 2.890 ms - Total 2.890 ms / 1 intvls (Avg 2.890 ms)
[00000419] main input debug: looking for a subtitle file in /home/cha16/DVB-H/scripts/receiver/
[00000448] main decoder debug: looking for decoder module: 31 candidates
[00000448] avcodec decoder debug: libavcodec initialized (interface 3352064 )
[00000448] avcodec decoder debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
[00000448] main decoder debug: using decoder module "avcodec"
[00000448] main decoder debug: TIMER module_Need() : 5.107 ms - Total 5.107 ms / 1 intvls (Avg 5.107 ms)
[00000480] main packetizer debug: looking for packetizer module: 18 candidates
[00000480] packetizer_h264 packetizer debug: found NAL_SPS (sps_id=0)
[00000480] main packetizer debug: using packetizer module "packetizer_h264"
[00000480] main packetizer debug: TIMER module_Need() : 2.206 ms - Total 2.206 ms / 1 intvls (Avg 2.206 ms)
[00000448] main decoder debug: thread started
[00000448] main decoder debug: thread 2957523856 (decoder) created at priority 0 (input/decoder.c:217)
[00000491] main decoder debug: looking for decoder module: 31 candidates
[00000491] main decoder debug: using decoder module "faad"
[00000491] main decoder debug: TIMER module_Need() : 0.238 ms - Total 0.238 ms / 1 intvls (Avg 0.238 ms)
[00000491] main decoder debug: thread started
[00000491] main decoder debug: thread 2949131152 (decoder) created at priority 5 (input/decoder.c:217)
[00000419] main input debug: `bear2.sdp' successfully opened
[00000415] qt4 interface debug: Error while initializing qt-specific localization
[00000415] qt4 interface debug: Updating the stream status: 3
[00000419] main input debug: control type=1
[00000480] packetizer_h264 packetizer debug: found NAL_PPS (pps_id=0 sps_id=0)
[00000491] faad decoder warning: decoded zero sample
[00000491] main decoder debug: no aout present, spawning one
[00000492] main audio output debug: looking for audio output module: 7 candidates
[00000492] alsa audio output debug: opening ALSA device `default'
[00000492] alsa audio output warning: unable to commit hardware configuration with fl32 samples. Retrying with s16l (Invalid argument)
[00000492] main audio output debug: thread 2937912208 (aout) created at priority 15 (alsa.c:687)
[00000492] main audio output debug: thread started
[00000492] main audio output debug: using audio output module "alsa"
[00000492] main audio output debug: TIMER module_Need() : 36.843 ms - Total 36.843 ms / 1 intvls (Avg 36.843 ms)
[00000492] main audio output debug: output 's16l' 96000 Hz Stereo frame=1 samples/4 bytes
[00000492] main audio output debug: mixer 'fl32' 96000 Hz Stereo frame=1 samples/8 bytes
[00000492] main audio output debug: filter(s) 'fl32'->'s16l' 96000 Hz->96000 Hz Stereo->Stereo
[00000493] main audio output debug: looking for audio filter module: 23 candidates
[00000493] main audio output debug: using audio filter module "converter_float"
[00000493] main audio output debug: TIMER module_Need() : 1.331 ms - Total 1.331 ms / 1 intvls (Avg 1.331 ms)
[00000492] main audio output debug: found a filter for the whole conversion
[00000492] main audio output debug: looking for audio mixer module: 3 candidates
[00000492] main audio output debug: using audio mixer module "float32_mixer"
[00000492] main audio output debug: TIMER module_Need() : 0.144 ms - Total 0.144 ms / 1 intvls (Avg 0.144 ms)
[00000492] main audio output debug: input 'fl32' 96000 Hz Stereo frame=1 samples/8 bytes
[00000492] main audio output debug: filter(s) 'fl32'->'fl32' 105600 Hz->96000 Hz Stereo->Stereo
[00000525] main audio output debug: looking for audio filter module: 23 candidates
[00000525] main audio output debug: using audio filter module "bandlimited_resampler"
[00000525] main audio output debug: TIMER module_Need() : 0.101 ms - Total 0.101 ms / 1 intvls (Avg 0.101 ms)
[00000492] main audio output debug: found a filter for the whole conversion
[00000448] main decoder debug: no usable vout present, spawning one
[00000526] main video output debug: window size: 352x192
[00000526] main video output debug: looking for video output module: 8 candidates
[00000526] xvideo video output debug: adaptor 0, port 88, format 0x32315659 (YV12) planar
[00000529] main window debug: looking for vout window module: 2 candidates
[00000529] qt4 window debug: waiting for interface...
[00000529] qt4 window debug: requesting window...
[00000415] qt4 interface debug: Video was requested -1, -1
[00000415] qt4 interface debug: Video is resizing to: 352 192
[00000526] qt4 video output debug: Qt FS: Attaching Vout
[00000526] qt4 video output debug: Qt: Changing Fullscreen Mode
[00000529] main window debug: using vout window module "qt4"
[00000529] main window debug: TIMER module_Need() : 1.232 ms - Total 1.232 ms / 1 intvls (Avg 1.232 ms)
[00000415] qt4 interface debug: Updating the geometry
[00000526] xvideo video output debug: XShm video extension v1.1 (without pixmaps, opcode: 146)
[00000526] xvideo video output debug: Window manager supports NetWM
[00000526] xvideo video output debug: Window manager supports _NET_WM_STATE_FULLSCREEN
[00000526] xvideo video output debug: Window manager supports _NET_WM_STATE_ABOVE
[00000526] xvideo video output debug: Window manager supports _NET_WM_STATE_BELOW
[00000526] main video output debug: using video output module "xvideo"
[00000526] main video output debug: TIMER module_Need() : 7.953 ms - Total 7.953 ms / 1 intvls (Avg 7.953 ms)
[00000526] main video output debug: thread started
[00000526] main video output debug: got 8 direct buffer(s)
[00000526] main video output debug: picture in 352x192 (0,0,352x192), chroma I420, ar 11:6, sar 1:1
[00000526] main video output debug: picture user 352x192 (0,0,352x192), chroma I420, ar 11:6, sar 1:1
[00000526] main video output debug: picture out 352x192 (0,0,352x192), chroma I420, ar 11:6, sar 1:1
[00000526] main video output debug: direct render, mapping render pictures 0-6 to system pictures 1-7
QPainter::begin: Paint device returned engine == 0, type: 1
QPainter::begin: Paint device returned engine == 0, type: 1
[00000526] main video output debug: waiting for thread initialization
[00000526] main video output debug: thread 2927885200 (video output) created at priority 15 (video_output/video_output.c:504)
[00000415] qt4 interface debug: New Event: type 1109
[00000534] main spu text debug: looking for text renderer module: 2 candidates
[00000536] main generic debug: thread 2918120336 (fontlist builder) created at priority 0 (freetype.c:477)
[00000536] main generic debug: thread started
[00000534] freetype spu text debug: using fontsize: 12
[00000534] main spu text debug: using text renderer module "freetype"
[00000534] main spu text debug: TIMER module_Need() : 1.110 ms - Total 1.110 ms / 1 intvls (Avg 1.110 ms)
[00000534] freetype spu text debug: using fontsize: 12
[00000533] main blend debug: looking for video blending module: 1 candidate
[00000533] blend blend debug: chroma: YUVA -> I420
[00000533] main blend debug: using video blending module "blend"
[00000533] main blend debug: TIMER module_Need() : 0.189 ms - Total 0.189 ms / 1 intvls (Avg 0.189 ms)
[00000536] freetype generic debug: Building font database...
[00000536] freetype generic debug: Finished building font database.
[00000536] freetype generic debug: Took 0 seconds
[00000536] main generic debug: thread ended
[00000492] alsa audio output debug: recovered from buffer underrun
[00000413] signals interface error: Caught Interrupt signal, exiting...
[00000413] main interface debug: thread ended

[Rémi Denis-Courmont]

If you're using live, it's --rtsp-caching

[SiliconBear]

Thanks, but I'm using a pre-recorded sequence, and I don't have an RTSP server (only an RTP server is used).

If you're using live, it's --rtsp-caching

[Rémi Denis-Courmont]

If you're using "live" as in liblive555...

[SiliconBear]

I see. No, I wrote my RTP server, which does not support RTSP...

If you're using "live" as in liblive555...

[Rémi Denis-Courmont]

If you are using liblive555 on the RECEIVER, then it's --rtsp-caching no matter whether it's RTSP or a plain SDP file.