[streaming] VLC+IVTV Errors: late buffer for mux input

[Justin I. Nevill]

Hi folks,

First, thanks to all the devs and testers, VLC is an awesome piece of
software.

I'm running VLC on a 266 MHz AMD Geode SC1100 CPU, with a Yuan MPC622
(http://www.yuan.com.tw/en/products/vdo_mpc622.html) hardware MPEG2
encoder, and IVTV 0.2.0-rc3k (www.ivtv.tv) drivers.

The main issue is that I'm getting "received buffer in the future"
errors on my VLC 0.8.2 client. I think this stems from the "late buffer
for mux input" errors I'm receiving from my VLC 0.8.2 server.

I suspected CPU load, but utilization is under 50%. I only found one
reference to "late buffer for mux input" on Google. What does this mean?
Is this pointing to IVTV or something else? I'm also concerned about
"trying to send non-dated packet to stream output!". Is VLC recieving
bad packets from IVTV?

Any help would be grately appreciated. The VLC -vvv output is below:

--- begin ---
# /usr/bin/vlc -vvv --plugin-path /lib/vlc --intf http --http-src
/var/www/vlc --http-host 192.168.0.101:8089
pvr:///dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480 --sout
udp/ts:239.9.9.1:1234 --cr-average 1000
VLC media player 0.8.2 Janus
[00000001] main vlc debug: checking builtin modules
[00000001] main vlc debug: checking plugin modules
[00000001] main vlc debug: loading plugins cache file
/root/.vlc/cache/plugins-04041e.dat
[00000001] main vlc debug: recursively browsing `modules'
[00000001] main vlc debug: recursively browsing
`/mnt/target/src/bering-uclibc/buildtool/build/vlc/lib/vlc'
[00000001] main vlc debug: recursively browsing `plugins'
[00000001] main vlc debug: recursively browsing `/lib/vlc'
[00000001] main vlc debug: module bank initialized, found 18 modules
[00000001] main vlc debug: opening config file /root/.vlc/vlcrc
[00000001] main vlc warning: config file /root/.vlc/vlcrc does not exist
yet
[00000000] main root debug: VLC media player - version 0.8.2 Janus - (c)
1996-2005 VideoLAN
[00000000] main root debug: libvlc was configured with ./configure
--prefix=/mnt/target/src/bering-uclibc/buildtool/build/vlc
CC=/mnt/target/src/bering-uclibc/buildtool/staging/usr/bin/gcc
LD=/mnt/target/src/bering-uclibc/buildtool/staging/usr/bin/ld
CFLAGS=-I/mnt/target/src/bering-uclibc/buildtool/staging/include
CPPFLAGS=-I/mnt/target/src/bering-uclibc/buildtool/staging/include
--with-tuning=i386 --build=i386-linux --disable-rpath --disable-nls
--disable-utf8 --disable-pth --disable-st --disable-gprof
--disable-cprof --disable-mostly-builtin --disable-optimize-memory
--enable-optimizations --disable-altivec --disable-debug
--disable-release --enable-sout --enable-httpd --enable-vlm
--disable-gnutls --disable-livedotcom --disable-dvdread --disable-dvdnav
--disable-dshow --enable-dvbpsi --disable-v4l --enable-pvr
--disable-libcdio --disable-libcddb --disable-cdda --disable-cddax
--disable-vcd --disable-satellite --disable-dvb --disable-screen
--disable-ogg --disable-mkv --disable-mod --disable-mad --disable-ffmpeg
--disable-ffmpegaltivec --disable-faad --disable-toolame
--disable-quicktime --disable-a52 --disable-dts --disable-flac
--disable-libmpeg2 --disable-vorbis --disable-tremor --disable-speex
--disable-tarkin --disable-theora --disable-x264 --disable-cmml
--disable-x11 --disable-xvideo --disable-glx --disable-opengl
--disable-sdl --disable-freetype --disable-fribidi --disable-svg
--disable-qte --disable-hd1000v --disable-directx --disable-fb
--disable-mga --disable-svgalib --disable-ggi --disable-glide
--disable-aa --disable-caca --disable-wingdi --disable-oss --disable-esd
--disable-portaudio --disable-arts --disable-alsa --disable-waveout
--disable-coreaudio --disable-hd1000a --disable-skins2 --disable-pda
--disable-wxwindows --disable-opie --disable-macosx --disable-qnx
--disable-ncurses --disable-xosd --disable-visual --disable-galaktos
--disable-goom --disable-slp --disable-lirc --disable-joystick
--disable-corba --disable-mozilla --disable-testsuite --enable-plugins
--without-livedotcom --without-dvdread --without-dvdnav --with-dvbpsi
--without-v4l --without-dvb --without-mad --without-ffmpeg-mp3lame
--without-ffmpeg-faac --without-ffmpeg-dts --without-ffmpeg-zlib
--without-a52 --without-qte --without-directx --without-ggi
--without-glide --without-slp --without-libintl-prefix
[00000001] main vlc debug: translation test: code is "C"
[00000001] main vlc debug: checking builtin modules
[00000001] main vlc debug: checking plugin modules
[00000001] main vlc debug: loading plugins cache file
/root/.vlc/cache/plugins-04041e.dat
[00000001] main vlc debug: recursively browsing `modules'
[00000001] main vlc debug: recursively browsing
`/mnt/target/src/bering-uclibc/buildtool/build/vlc/lib/vlc'
[00000001] main vlc debug: recursively browsing `plugins'
[00000001] main vlc debug: recursively browsing `/lib/vlc'
[00000001] main vlc debug: module bank initialized, found 18 modules
[00000001] main vlc debug: opening config file /root/.vlc/vlcrc
[00000001] main vlc warning: config file /root/.vlc/vlcrc does not exist
yet
[00000001] main vlc debug: CPU has capabilities 486 586 MMX FPU
[00000001] main vlc debug: looking for memcpy module: 1 candidate
[00000005] main module debug: using memcpy module "memcpymmx"
[00000024] main playlist debug: waiting for thread completion
[00000024] main playlist debug: thread 1026 (playlist) created at
priority 0 (src/playlist/playlist.c:152)
[00000025] main private debug: waiting for thread completion
[00000025] main private debug: thread 2051 (preparser) created at
priority 0 (src/playlist/playlist.c:174)
[00000026] main interface debug: looking for interface module: 0
candidates
[00000026] main interface error: no interface module matched
"hotkeys,none"
[00000026] main interface error: no suitable intf module
[00000001] main vlc error: interface "hotkeys,none" initialization
failed
[00000024] main playlist debug: adding playlist item
`pvr:///dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480' (
pvr:///dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480 )
[00000027] main interface debug: looking for interface module: 1
candidate
[00000027] http interface debug: base 192.168.0.101:8089
[00000027] main interface: creating httpd
[00000030] main private debug: thread 3076 (httpd host thread) created
at priority 0 (src/misc/httpd.c:1163)
[00000027] http interface debug: dir=/var/www/vlc
[00000027] http interface debug: file=/var/www/vlc/test.html
(url=/test.html)
[00000013] main module debug: using interface module "http"
[00000027] main interface debug: interface initialized
[00000027] main interface debug: thread 4101 (manager) created at
priority 0 (src/interface/interface.c:196)
[00000024] main playlist debug: creating new input thread
[00000031] main input debug: waiting for thread completion
[00000031] main input debug: thread 5126 (input) created at priority 0
(src/input/input.c:230)
[00000032] main stream output debug: using sout
chain=`std{mux="ts",access="udp",url="239.9.9.1:1234"}'
[00000032] main stream output debug: stream=`std'
[00000033] main private debug: looking for sout stream module: 1
candidate
[00000033] main private debug: set sout option: sout-standard-mux to ts
[00000033] main private debug: set sout option: sout-standard-access to
udp
[00000033] main private debug: set sout option: sout-standard-url to
239.9.9.1:1234
[00000033] stream_out_standard private debug: creating
`udp/ts://239.9.9.1:1234'
[00000033] stream_out_standard private debug: extention is 1:1234
[00000033] stream_out_standard private debug: extention -> mux=(null)
[00000033] stream_out_standard private debug: using
`udp/ts://239.9.9.1:1234'
[00000035] main private debug: looking for sout access module: 1
candidate
[00000037] main private debug: looking for network module: 1 candidate
[00000037] ipv4 private debug: socket buffer size is 0x35000 instead of
0x80000
[00000018] main module debug: using network module "ipv4"
[00000018] main module debug: unlocking module "ipv4"
[00000037] main private debug: thread 6151 (sout write thread) created
at priority 0 (udp.c:277)
[00000035] access_output_udp private debug: udp access output
opened(239.9.9.1:1234)
[00000023] main module debug: using sout access module
"access_output_udp"
[00000033] stream_out_standard private debug: access opened
[00000039] main private debug: looking for sout mux module: 2 candidates
[00000039] mux_ts private debug: Open
[00000039] mux_ts private debug: shaping=200000 pcr=70000
dts_delay=400000
[00000012] main module debug: using sout mux module "mux_ts"
[00000032] main stream output debug: muxer support adding stream at any
time
[00000033] stream_out_standard private debug: mux opened
[00000019] main module debug: using sout stream module
"stream_out_standard"
[00000031] main input debug:
`pvr:///dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480' gives
access `pvr' demux `' path
`/dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480'
[00000031] main input debug: creating demux: access='pvr' demux=''
path='/dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480'
[00000040] main demuxer debug: looking for access_demux module: 0
candidates
[00000040] main demuxer warning: no access_demux module matched "pvr"
[00000031] main input debug: creating access 'pvr'
path='/dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480'
[00000041] main access debug: looking for access2 module: 1 candidate
[00000041] pvr access debug: using video device: /dev/video0
[00000041] pvr access debug: video standard set to:3000
[00000041] pvr access debug: picture size set to:720x480
[00000041] pvr access debug: Setting codec parameters to:  framerate: 0,
bitrate: 2000000/9600000/0
[00000017] main module debug: using access2 module "pvr"
[00000043] main private debug: pre buffering
[00000043] main private debug: received first data for our buffer
[00000043] main private debug: prebuffering done 36800 bytes in 0s - 39
kbytes/s
[00000031] main input debug: creating demux: access='pvr' demux=''
path='/dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480'
[00000044] main demuxer debug: looking for demux2 module: 2 candidates
[00000021] main module debug: using demux2 module "ps"
[00000000] main root error: option sub-delay does not exist
[00000031] main input debug: starting in synch mode
[00000041] pvr access warning: unimplemented query in control
[00000031] main input debug:
`pvr:///dev/video0:norm=ntsc:bitrate=2000000:width=720:height=480'
successfully opened
[00000031] main input debug: control type=19
[00000031] main input debug: selecting program id=0
[00000047] main packetizer debug: looking for packetizer module: 2
candidates
[00000016] main module debug: using packetizer module
"packetizer_mpegvideo"
[00000047] main packetizer debug: thread 7176 (decoder) created at
priority 0 (src/input/decoder.c:159)
[00000047] packetizer_mpegvideo packetizer debug: Size 720x480
fps=29.970
[00000049] main packetizer debug: looking for packetizer module: 2
candidates
[00000015] main module debug: using packetizer module "mpeg_audio"
[00000049] main packetizer debug: thread 8201 (decoder) created at
priority 0 (src/input/decoder.c:159)
[00000032] main stream output debug: adding a new input
[00000049] mpeg_audio packetizer debug: MPGA channels:2 samplerate:48000
bitrate:384
[00000039] main private debug: adding a new input
[00000032] main stream output debug: adding a new input
[00000039] mux_ts private debug: adding input codec=mpgv pid=68
[00000039] mux_ts private debug: new PCR PID is 68
[00000032] main stream output warning: trying to send non-dated packet
to stream output!
[00000039] main private debug: adding a new input
[00000039] mux_ts private debug: adding input codec=mpga pid=69
[00000039] main private warning: late buffer for mux input (154931)
[00000039] main private warning: late buffer for mux input (111408)
[00000039] main private warning: late buffer for mux input (89866)
[00000039] main private warning: late buffer for mux input (64908)
[00000039] main private warning: late buffer for mux input (76619)
[00000039] main private warning: late buffer for mux input (52970)
[00000039] main private warning: late buffer for mux input (29290)
[00000039] main private warning: late buffer for mux input (7366)
[00000039] main private warning: late buffer for mux input (7373)
[00000039] main private warning: late buffer for mux input (42193)
[00000039] main private warning: late buffer for mux input (18308)
[00000039] main private warning: late buffer for mux input (34337)
[00000039] main private warning: late buffer for mux input (23843)
[00000039] main private warning: late buffer for mux input (9572)
[00000039] main private warning: late buffer for mux input (9548)
[00000037] access_output_udp private debug: packet has been sent too
late (26261)
--- end ---

Thanks,
Justin

-- 
This is the streaming mailing-list, see http://www.videolan.org/streaming/
To unsubscribe, please read http://www.videolan.org/support/lists.html