[vlc] How to Tune ATSC...

Byron Formwalt byron.formwalt at gmail.com
Sat Oct 10 19:16:09 CEST 2009


Below is the verbose output for launching VLC and displaying a single  
channel.  Items marked with the prefix, "dvb access debug", are of  
particular interest for this problem.

formwalt at videolan:~$ vlc -vvv channels.conf
VLC media player 1.0.2 Goldeneye
[0x238e888] main libvlc debug: VLC media player - version 1.0.2  
Goldeneye - (c) 1996-2009 the VideoLAN team
[0x238e888] main libvlc debug: libvlc was configured with ./configure   
'--build=x86_64-linux-gnu' '--disable-maintainer-mode' '--enable- 
release' '--prefix=/usr' '--config-cache' '--enable-fast-install' '-- 
with-binary-version=1ubuntu1' '--disable-update-check' '--disable-fb'  
'--enable-ggi' '--enable-sdl' '--enable-mad' '--enable-jack' '--enable- 
pulse' '--enable-lirc' '--enable-a52' '--enable-aa' '--enable-dvbpsi'  
'--enable-mozilla' '--with-mozilla-pkg=xulrunner-plugin' '--enable- 
dvb' '--enable-ogg' '--enable-vorbis' '--enable-shout' '--enable-qt4'  
'--enable-flac' '--enable-skins2' '--enable-freetype' '--enable-mkv'  
'--enable-speex' '--enable-caca' '--enable-live555' '--enable- 
libmpeg2' '--enable-fribidi' '--enable-mod' '--enable-theora' '-- 
enable-dvdnav' '--enable-gnutls' '--enable-ncurses' '--enable-smb' '-- 
disable-gnomevfs' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '-- 
enable-vcdx' '--enable-notify' '--enable-twolame' '--enable-faad' '-- 
disable-zvbi' '--enable-telx' '--enable-x264' '--disable-atmo' '-- 
enable-taglib' '--enable-libass' '--enable-dca' '--enable-realrtsp' '-- 
disable-dv' '--disable-fluidsynth' '--disable-kate' '--disable-mtp' '-- 
enable-alsa' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable- 
svgalib' 'build_alias=x86_64-linux-gnu' 'CFLAGS=-g -O2' 'LDFLAGS=-Wl,-- 
as-needed' 'CPPFLAGS=' 'CXXFLAGS=-g -O2'
[0x238e888] main libvlc debug: translation test: code is "C"
[0x238e888] main libvlc debug: checking plugin modules
[0x238e888] main libvlc debug: loading plugins cache file /home/ 
formwalt/.cache/vlc/plugins-04081e.dat
[0x238e888] main libvlc debug: recursively browsing `/usr/lib/vlc'
[0x238e888] main libvlc debug: module bank initialized (384 modules)
[0x238e888] main libvlc debug: opening config file (/home/ 
formwalt/.config/vlc/vlcrc)
[0x238e888] main libvlc debug: CPU has capabilities 486 586 MMX 3DNow!  
MMXEXT SSE SSE2 FPU
[0x238e888] main libvlc debug: looking for memcpy module: 4 candidates
[0x238e888] main libvlc debug: using memcpy module "memcpymmxext"
[0x2482268] main input debug: Creating an input for 'Media Library'
[0x2482268] main input debug: Input is a meta file: disabling unneeded  
options
[0x2482268] main input debug: using timeshift granularity of 50 MBytes
[0x2482268] main input debug: using timeshift path '/tmp'
[0x2482268] main input debug: `file/xspf-open:///home/formwalt/.local/ 
share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/ 
formwalt/.local/share/vlc/ml.xspf'
[0x2482268] main input debug: creating demux: access='file'  
demux='xspf-open' path='/home/formwalt/.local/share/vlc/ml.xspf'
[0x248e508] main demux debug: looking for access_demux module: 1  
candidate
[0x248e508] main demux warning: no access_demux module matching "file"  
could be loaded
[0x248e508] main demux debug: TIMER module_need() : 0.712 ms - Total  
0.712 ms / 1 intvls (Avg 0.712 ms)
[0x2482268] main input debug: creating access 'file' path='/home/ 
formwalt/.local/share/vlc/ml.xspf'
[0x249ecf8] main access debug: looking for access module: 3 candidates
[0x249ecf8] access_file access debug: opening file `/home/ 
formwalt/.local/share/vlc/ml.xspf'
[0x249ecf8] main access debug: using access module "access_file"
[0x249ecf8] main access debug: TIMER module_need() : 0.622 ms - Total  
0.622 ms / 1 intvls (Avg 0.622 ms)
[0x249da38] main stream debug: Using AStream*Stream
[0x249da38] main stream debug: pre buffering
[0x249da38] main stream debug: received first data after 0 ms
[0x249da38] main stream debug: pre-buffering done 296 bytes in 0s -  
12567 kbytes/s
[0x249de88] main stream debug: looking for stream_filter module: 4  
candidates
[0x249de88] main stream debug: TIMER module_need() : 0.576 ms - Total  
0.576 ms / 1 intvls (Avg 0.576 ms)
[0x249de88] main stream debug: looking for stream_filter module: 1  
candidate
[0x249de88] main stream debug: using stream_filter module  
"stream_filter_record"
[0x249de88] main stream debug: TIMER module_need() : 0.182 ms - Total  
0.182 ms / 1 intvls (Avg 0.182 ms)
[0x2482268] main input debug: creating demux: access='file'  
demux='xspf-open' path='/home/formwalt/.local/share/vlc/ml.xspf'
[0x24a1a08] main demux debug: looking for demux module: 1 candidate
[0x24a1a08] playlist demux debug: using XSPF playlist reader
[0x24a1a08] main demux debug: using demux module "playlist"
[0x24a1a08] main demux debug: TIMER module_need() : 0.270 ms - Total  
0.270 ms / 1 intvls (Avg 0.270 ms)
[0x2482268] main input debug: `file/xspf-open:///home/formwalt/.local/ 
share/vlc/ml.xspf' successfully opened
[0x24a2668] main xml debug: looking for xml module: 2 candidates
[0x24a2668] main xml debug: using xml module "xml"
[0x24a2668] main xml debug: TIMER module_need() : 0.744 ms - Total  
0.744 ms / 1 intvls (Avg 0.744 ms)
[0x24a1a08] playlist demux debug: parsed 0 tracks successfully
[0x24a2668] main xml debug: removing module "xml"
[0x2482268] main input debug: EOF reached
[0x24a1a08] main demux debug: removing module "playlist"
[0x249de88] main stream debug: removing module "stream_filter_record"
[0x249ecf8] main access debug: removing module "access_file"
[0x2482268] main input debug: TIMER input launching for 'Media  
Library' : 6.135 ms - Total 6.135 ms / 1 intvls (Avg 6.135 ms)
[0x2485dd8] main playlist debug: Activated
[0x2485dd8] main playlist debug: rebuilding array of current - root  
Playlist
[0x2485dd8] main playlist debug: rebuild done - 0 items, index -1
[0x249ea68] main interface debug: looking for interface module: 1  
candidate
[0x249ea68] main interface debug: using interface module "hotkeys"
[0x249ea68] main interface debug: TIMER module_need() : 0.462 ms -  
Total 0.462 ms / 1 intvls (Avg 0.462 ms)
[0x249ea68] main interface debug: thread (interface) created at  
priority 0 (interface/interface.c:151)
[0x249ea68] main interface debug: thread started
[0x2484968] main interface debug: looking for interface module: 1  
candidate
[0x2484968] main interface debug: using interface module "inhibit"
[0x2484968] main interface debug: TIMER module_need() : 2.875 ms -  
Total 2.875 ms / 1 intvls (Avg 2.875 ms)
[0x2484968] main interface debug: thread started
[0x2484968] main interface debug: thread (interface) created at  
priority 0 (interface/interface.c:151)
[0x249f568] main interface debug: looking for interface module: 1  
candidate
[0x249f568] main interface debug: using interface module "screensaver"
[0x249f568] main interface debug: TIMER module_need() : 0.296 ms -  
Total 0.296 ms / 1 intvls (Avg 0.296 ms)
[0x249f568] main interface debug: thread (interface) created at  
priority 0 (interface/interface.c:151)
[0x249f568] main interface debug: thread started
[0x2485dd8] main playlist debug: adding item  
`channels.conf' ( channels.conf )
[0x238f0d8] main interface debug: looking for interface module: 1  
candidate
[0x238f0d8] main interface debug: using interface module "signals"
[0x238f0d8] main interface debug: TIMER module_need() : 0.324 ms -  
Total 0.324 ms / 1 intvls (Avg 0.324 ms)
[0x238f0d8] main interface debug: thread (interface) created at  
priority 0 (interface/interface.c:151)
[0x238f0d8] main interface debug: thread started
[0x238f0d8] main interface debug: thread ended
[0x24a5508] main interface debug: looking for interface module: 0  
candidates
[0x24a5508] main interface error: no interface module matched  
"globalhotkeys,none"
[0x24a5508] main interface debug: TIMER module_need() : 0.104 ms -  
Total 0.104 ms / 1 intvls (Avg 0.104 ms)
[0x24a5508] main interface error: no suitable interface module
[0x238e888] main libvlc error: interface "globalhotkeys,none"  
initialization failed
[0x238e888] main libvlc: Running vlc with the default interface. Use  
'cvlc' to use vlc without interface.
[0x24a5578] main interface debug: looking for interface module: 4  
candidates
[0x24a5578] main interface debug: using interface module "qt4"
[0x24a5578] main interface debug: TIMER module_need() : 286.960 ms -  
Total 286.960 ms / 1 intvls (Avg 286.960 ms)
[0x24a5578] main interface debug: thread (interface) created at  
priority 0 (interface/interface.c:151)
[0x2485dd8] main playlist debug: rebuilding array of current - root  
Playlist
[0x2485dd8] main playlist debug: rebuild done - 1 items, index -1
[0x2485dd8] main playlist debug: processing request item null node  
Playlist skip 0
[0x2485dd8] main playlist debug: starting new item
[0x2485dd8] main playlist debug: creating new input thread
[0x24a5578] qt4 interface debug: Error while initializing qt-specific  
localization
[0x7f25e80015c8] main input debug: Creating an input for 'channels.conf'
[0x7f25e80015c8] main input debug: thread started
[0x7f25e80015c8] main input debug: using timeshift granularity of 50  
MBytes
[0x7f25e80015c8] main input debug: using timeshift path '/tmp'
[0x7f25e80015c8] main input debug: thread (input) created at priority  
10 (input/input.c:230)
[0x7f25e80015c8] main input debug: `channels.conf' gives access `'  
demux `' path `channels.conf'
[0x7f25e80015c8] main input debug: creating demux: access='' demux=''  
path='channels.conf'
[0x24a7fc8] main demux debug: looking for access_demux module: 7  
candidates
[0x24a7fc8] main demux debug: TIMER module_need() : 1.968 ms - Total  
1.968 ms / 1 intvls (Avg 1.968 ms)
[0x7f25e80015c8] main input debug: creating access ''  
path='channels.conf'
[0x27e9ac8] main access debug: looking for access module: 7 candidates
[0x27e9ac8] vcd access debug: trying .cue file: channels.cue
[0x27e9ac8] vcd access debug: could not find .cue file
[0x27e9ac8] access_file access debug: opening file `channels.conf'
[0x27e9ac8] main access debug: using access module "access_file"
[0x27e9ac8] main access debug: TIMER module_need() : 2.012 ms - Total  
2.012 ms / 1 intvls (Avg 2.012 ms)
[0x27cf968] main stream debug: Using AStream*Stream
[0x27cf968] main stream debug: pre buffering
[0x27cf968] main stream debug: received first data after 0 ms
[0x27cf968] main stream debug: pre-buffering done 563 bytes in 0s -  
21992 kbytes/s
[0x27d06f8] main stream debug: looking for stream_filter module: 4  
candidates
[0x27d06f8] main stream debug: TIMER module_need() : 0.105 ms - Total  
0.105 ms / 1 intvls (Avg 0.105 ms)
[0x27d06b8] main stream debug: looking for stream_filter module: 1  
candidate
[0x27d06b8] main stream debug: using stream_filter module  
"stream_filter_record"
[0x27d06b8] main stream debug: TIMER module_need() : 0.069 ms - Total  
0.069 ms / 1 intvls (Avg 0.069 ms)
[0x7f25e80015c8] main input debug: creating demux: access='' demux=''  
path='channels.conf'
[0x27d0a38] main demux debug: looking for demux module: 51 candidates
[0x24a5578] qt4 interface debug: IM: Setting an input
[0x24a5578] main interface debug: thread started
[0x24a5578] main interface debug: thread ended
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x27d0a38] playlist demux debug: found valid DVB conf playlist file
[0x27d0a38] main demux debug: using demux module "playlist"
[0x27d0a38] main demux debug: TIMER module_need() : 24.459 ms - Total  
24.459 ms / 1 intvls (Avg 24.459 ms)
[0x7f25e80015c8] main input debug: looking for a subtitle file in / 
home/formwalt/
[0x7f25e80015c8] main input debug: `channels.conf' successfully opened
[0x2485dd8] main playlist debug: deleting item `channels.conf'
[0x7f25e80015c8] main input debug: EOF reached
[0x27d0a38] main demux debug: removing module "playlist"
[0x27d06b8] main stream debug: removing module "stream_filter_record"
[0x27e9ac8] main access debug: removing module "access_file"
[0x7f25e80015c8] main input debug: thread ended
[0x2485dd8] main playlist debug: dead input
[0x2485dd8] main playlist debug: changing item without a request  
(current 0/1)
[0x2485dd8] main playlist debug: rebuilding array of current - root  
Playlist
[0x2485dd8] main playlist debug: rebuild done - 18 items, index -1
[0x2485dd8] main playlist debug: using item 0
[0x2485dd8] main playlist debug: starting new item
[0x2485dd8] main playlist debug: creating new input thread
[0x27d06b8] main input debug: Creating an input for 'KOAT-DT'
[0x27d06b8] main input debug: thread started
[0x27d06b8] main input debug: using timeshift granularity of 50 MBytes
[0x27d06b8] main input debug: using timeshift path '/tmp'
[0x27d06b8] main input debug: thread (input) created at priority 10  
(input/input.c:230)
[0x27d06b8] main input debug: `dvb://' gives access `dvb' demux `'  
path `'
[0x27d06b8] main input debug: creating demux: access='dvb' demux=''  
path=''
[0x27c8f78] main demux debug: looking for access_demux module: 0  
candidates
[0x27c8f78] main demux debug: no access_demux module matched "dvb"
[0x27c8f78] main demux debug: TIMER module_need() : 0.134 ms - Total  
0.134 ms / 1 intvls (Avg 0.134 ms)
[0x27d06b8] main input debug: creating access 'dvb' path=''
[0x27c8f78] main access debug: looking for access module: 1 candidate
[0x2485dd8] main playlist debug: no fetch required for (null) (art  
currently (null))
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/frontend0
[0x24a5578] qt4 interface debug: IM: Deleting the input
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x7f25e80015c8] main input debug: TIMER input launching for  
'channels.conf' : 34.121 ms - Total 34.121 ms / 1 intvls (Avg 34.121 ms)
[0x24a5578] qt4 interface debug: IM: Setting an input
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x27c8f78] dvb access debug: Frontend Info:
[0x27c8f78] dvb access debug:   name = Auvitek AU8522 QAM/8VSB Frontend
[0x27c8f78] dvb access debug:   type = ATSC (USA)
[0x27c8f78] dvb access debug:   frequency_min = 54000000 (kHz)
[0x27c8f78] dvb access debug:   frequency_max = 858000000 (kHz)
[0x27c8f78] dvb access debug:   frequency_stepsize = 62500
[0x27c8f78] dvb access debug:   frequency_tolerance = 0
[0x27c8f78] dvb access debug:   symbol_rate_min = 0 (kHz)
[0x27c8f78] dvb access debug:   symbol_rate_max = 0 (kHz)
[0x27c8f78] dvb access debug:   symbol_rate_tolerance (ppm) = 0
[0x27c8f78] dvb access debug:   notifier_delay (ms) = 0
[0x27c8f78] dvb access debug: Frontend Info capability list:
[0x27c8f78] dvb access debug:   inversion auto
[0x27c8f78] dvb access debug:   card can do QAM 64
[0x27c8f78] dvb access debug:   card can do QAM 256
[0x27c8f78] dvb access debug:   card can do 8vsb
[0x27c8f78] dvb access debug: End of capability list
[0x27c8f78] dvb access debug: trying to tune the frontend...
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/dvr0
[0x27c8f78] dvb access debug: setting filter on PAT
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/demux0
[0x27c8f78] dvb access debug: DMXSetFilter: DMX_PES_OTHER for PID 0
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/ca0
[0x27c8f78] dvb access warning: CAMInit: opening CAM device failed (No  
such file or directory)
[0x27c8f78] main access debug: using access module "dvb"
[0x27c8f78] main access debug: TIMER module_need() : 6993.299 ms -  
Total 6993.299 ms / 1 intvls (Avg 6993.299 ms)
[0x2917d98] main stream debug: Using AStream*Block
[0x2917d98] main stream debug: pre buffering
[0x2917d98] main stream debug: received first data after 364 ms
[0x2917d98] main stream debug: prebuffering done 376 bytes in 0s - 1  
kbytes/s
[0x2886378] main stream debug: looking for stream_filter module: 4  
candidates
[0x2886378] main stream debug: TIMER module_need() : 0.197 ms - Total  
0.197 ms / 1 intvls (Avg 0.197 ms)
[0x2887038] main stream debug: looking for stream_filter module: 1  
candidate
[0x2887038] main stream debug: using stream_filter module  
"stream_filter_record"
[0x2887038] main stream debug: TIMER module_need() : 0.123 ms - Total  
0.123 ms / 1 intvls (Avg 0.123 ms)
[0x27d06b8] main input debug: creating demux: access='dvb' demux=''  
path=''
[0x2886378] main demux debug: looking for demux module: 51 candidates
[0x27c8f78] dvb access debug: frontend has acquired signal
[0x27c8f78] dvb access debug: frontend has acquired carrier
[0x27c8f78] dvb access debug: frontend has acquired stable FEC
[0x27c8f78] dvb access debug: frontend has acquired sync
[0x27c8f78] dvb access debug: frontend has acquired lock
[0x27c8f78] dvb access debug: - Bit error rate: 0
[0x27c8f78] dvb access debug: - Signal strength: 250
[0x27c8f78] dvb access debug: - SNR: 250
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/demux0
[0x27c8f78] dvb access debug: DMXSetFilter: DMX_PES_OTHER for PID 17
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/demux0
[0x27c8f78] dvb access debug: DMXSetFilter: DMX_PES_OTHER for PID 18
[0x2886378] main demux debug: using demux module "ts"
[0x2886378] main demux debug: TIMER module_need() : 2117.270 ms -  
Total 2117.270 ms / 1 intvls (Avg 2117.270 ms)
[0x2886378] ts demux debug: DEMUX_SET_GROUP 3 (nil)
[0x27c8f78] dvb access warning: unimplemented query in control
[0x27d06b8] main input debug: `dvb://' successfully opened
libdvbpsi error (PSI decoder): TS discontinuity (received 2, expected  
1) for PID 0
libdvbpsi error (PSI decoder): TS discontinuity (received 1, expected  
3) for PID 0
libdvbpsi error (PSI decoder): PSI section too long
libdvbpsi error (PSI decoder): TS discontinuity (received 4, expected  
2) for PID 0
[0x2886378] ts demux debug: PATCallBack called
[0x2886378] ts demux debug: new PAT ts_id=2033 version=2 current_next=1
[0x2886378] ts demux debug:   * number=3 pid=48
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/demux0
[0x27c8f78] dvb access debug: DMXSetFilter: DMX_PES_OTHER for PID 48
[0x2886378] ts demux debug:   * number=4 pid=64
libdvbpsi error (PSI decoder): TS discontinuity (received 7, expected  
0) for PID 48
[0x2886378] ts demux debug: PMTCallBack called
[0x2886378] ts demux debug: new PMT program number=3 version=8  
pid_pcr=49
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/demux0
[0x27c8f78] dvb access debug: DMXSetFilter: DMX_PES_OTHER for PID 49
[0x2886378] ts demux debug:   * es pid=49 type=2 fcc=mpgv
[0x27d06b8] main input debug: selecting program id=3
[0x2893138] main decoder debug: looking for decoder module: 31  
candidates
[0x2893138] main decoder debug: using decoder module "libmpeg2"
[0x2893138] main decoder debug: TIMER module_need() : 4.134 ms - Total  
4.134 ms / 1 intvls (Avg 4.134 ms)
[0x2893138] main decoder debug: thread started
[0x2893138] main decoder debug: thread (decoder) created at priority 0  
(input/decoder.c:315)
[0x2886378] ts demux debug:   * es pid=52 type=129 fcc=a52
[0x28bfb78] main decoder debug: looking for decoder module: 31  
candidates
[0x28bfb78] main decoder debug: using decoder module "a52"
[0x28bfb78] main decoder debug: TIMER module_need() : 2.871 ms - Total  
2.871 ms / 1 intvls (Avg 2.871 ms)
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x28bfb78] main decoder debug: thread started
[0x28bfb78] main decoder debug: thread (decoder) created at priority 5  
(input/decoder.c:315)
[0x27c8f78] dvb access debug: Opening device /dev/dvb/adapter0/demux0
[0x27c8f78] dvb access debug: DMXSetFilter: DMX_PES_OTHER for PID 52
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x24a5578] qt4 interface debug: Updating the geometry
[0x2886378] ts demux warning: first packet for pid=52 cc=0x3
[0x27d06b8] main input debug: control type=1
[0x24a5578] qt4 interface debug: New caching: 0
[0x24a5578] qt4 interface debug: New caching: 0
[0x27d06b8] main input debug: Buffering 0%
[0x27d06b8] main input debug: Buffering 4%
[0x24a5578] qt4 interface debug: New caching: 4
[0x24a5578] qt4 interface debug: New caching: 4
[0x27d06b8] main input debug: Buffering 11%
[0x27d06b8] main input debug: Buffering 16%
[0x24a5578] qt4 interface debug: New caching: 16
[0x24a5578] qt4 interface debug: New caching: 16
[0x24a5578] qt4 interface debug: New caching: 20
[0x24a5578] qt4 interface debug: New caching: 20
[0x27d06b8] main input debug: Buffering 20%
[0x27d06b8] main input debug: Buffering 28%
[0x27d06b8] main input debug: Buffering 32%
[0x27d06b8] main input debug: Buffering 36%
[0x24a5578] qt4 interface debug: New caching: 36
[0x24a5578] qt4 interface debug: New caching: 36
[0x24a5578] qt4 interface debug: New caching: 48
[0x24a5578] qt4 interface debug: New caching: 48
[0x27d06b8] main input debug: Buffering 48%
[0x27d06b8] main input debug: Buffering 55%
[0x2893138] libmpeg2 decoder debug: 1280x720 (display 1280,720),  
aspect 768000, sar 1:1, 59.941 fps
[0x27d06b8] main input debug: no usable vout present, spawning one
[0x28d6c08] main spu text debug: looking for text renderer module: 2  
candidates
[0x28e5e18] main generic debug: thread started
[0x28e5e18] freetype generic debug: Building font database...
[0x28e5e18] freetype generic debug: Finished building font database.
[0x28e5e18] freetype generic debug: Took 574 microseconds
[0x28e5e18] main generic debug: thread (fontlist builder) created at  
priority 0 (freetype.c:475)
[0x28e5e18] main generic debug: thread ended
[0x28d6c08] freetype spu text debug: using fontsize: 2
[0x28d6c08] main spu text debug: using text renderer module "freetype"
[0x28d6c08] main spu text debug: TIMER module_need() : 7.767 ms -  
Total 7.767 ms / 1 intvls (Avg 7.767 ms)
[0x28f4e88] main scale debug: looking for video filter2 module: 20  
candidates
[0x28f4e88] swscale scale debug: 32x32 chroma: YUVA -> 16x16 chroma:  
YUVA with scaling using Bicubic (good quality)
[0x28f4e88] main scale debug: using video filter2 module "swscale"
[0x28f4e88] main scale debug: TIMER module_need() : 3.093 ms - Total  
3.093 ms / 1 intvls (Avg 3.093 ms)
[0x28ea018] main scale debug: looking for video filter2 module: 20  
candidates
[0x28ea018] yuvp scale debug: YUVP to YUVA converter
[0x28ea018] main scale debug: using video filter2 module "yuvp"
[0x28ea018] main scale debug: TIMER module_need() : 3.692 ms - Total  
3.692 ms / 1 intvls (Avg 3.692 ms)
[0x28d12a8] main video output debug: window size: 1280x720
[0x28d12a8] main video output debug: looking for video output module:  
8 candidates
[0x27d06b8] main input debug: Buffering 61%
[0x28bfb78] a52 decoder: A/52 channels:6 samplerate:48000 bitrate:384000
[0x27d06b8] main input debug: Buffering 66%
[0x27d06b8] main input debug: Buffering 73%
[0x27d06b8] main input debug: Buffering 77%
[0x27d06b8] main input debug: Buffering 81%
[0x27d06b8] main input debug: Buffering 88%
[0x27d06b8] main input debug: Buffering 93%
[0x27d06b8] main input debug: Buffering 98%
[0x27d06b8] main input debug: Stream buffering done (315 ms in 301 ms)
[0x24a5578] qt4 interface debug: New caching: 100
[0x24a5578] qt4 interface debug: New caching: 100
[0x28d12a8] xvideo video output debug: adaptor 0, port 355, format  
0x32315659 (YV12) planar
[0x2867f28] main window debug: looking for xwindow module: 3 candidates
[0x2867f28] qt4 window debug: requesting video...
[0x24a5578] qt4 interface debug: Video was requested -1, -1
[0x24a5578] qt4 interface debug: Video is resizing to: 1280 720
[0x24a5578] qt4 interface debug: Updating the geometry
[0x2867f28] main window debug: using xwindow module "qt4"
[0x2867f28] main window debug: TIMER module_need() : 8.680 ms - Total  
8.680 ms / 1 intvls (Avg 8.680 ms)
[0x28d12a8] xvideo video output debug: XShm video extension v1.1  
(without pixmaps, opcode: 143)
[0x28d12a8] xvideo video output debug: Window manager supports NetWM
[0x28d12a8] xvideo video output debug: Window manager supports  
_NET_WM_STATE_FULLSCREEN
[0x28d12a8] xvideo video output debug: Window manager supports  
_NET_WM_STATE_ABOVE
[0x28d12a8] xvideo video output debug: Window manager supports  
_NET_WM_STATE_BELOW
[0x28d12a8] main video output debug: using video output module "xvideo"
[0x28d12a8] main video output debug: TIMER module_need() : 311.971 ms  
- Total 311.971 ms / 1 intvls (Avg 311.971 ms)
[0x28d12a8] main video output debug: Deinterlacing available
QPainter::begin: Paint device returned engine == 0, type: 1
QPainter::begin: Paint device returned engine == 0, type: 1
[0x28d12a8] main video output debug: got 16 direct buffer(s)
[0x28d12a8] main video output debug: pic render sz 1280x720, of (0,0),  
vsz 1280x720, 4cc I420, ar 16:9, sar 1:1, msk r0x0 g0x0 b0x0
[0x28d12a8] main video output debug: pic in sz 1280x720, of (0,0), vsz  
1280x720, 4cc I420, ar 16:9, sar 1:1, msk r0x0 g0x0 b0x0
[0x28d12a8] main video output debug: pic out sz 1280x720, of (0,0),  
vsz 1280x720, 4cc I420, ar 16:9, sar 1:1, msk r0x0 g0x0 b0x0
[0x28d12a8] main video output debug: direct render, mapping render  
pictures 0-14 to system pictures 1-15
[0x27d06b8] main input debug: creating aout
[0x2893138] main decoder warning: dts != current_pts (-8871152010)
[0x2b82b18] main audio output debug: looking for audio output module:  
6 candidates
[0x28d12a8] qt4 video output debug: Qt: Entering Fullscreen
[0x2893138] main decoder warning: decoder synchro warning: pts !=  
current_date (-16689)
[0x2893138] main decoder debug: End of video preroll
[0x2893138] main decoder debug: Received first picture
ALSA lib confmisc.c:768:(parse_card) cannot find card '0'
ALSA lib conf.c:3513:(_snd_config_evaluate) function  
snd_func_card_driver returned error: No such file or directory
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:3513:(_snd_config_evaluate) function snd_func_concat  
returned error: No such file or directory
ALSA lib confmisc.c:1251:(snd_func_refer) error evaluating name
ALSA lib conf.c:3513:(_snd_config_evaluate) function snd_func_refer  
returned error: No such file or directory
ALSA lib conf.c:3985:(snd_config_expand) Evaluate error: No such file  
or directory
ALSA lib pcm.c:2211:(snd_pcm_open_noupdate) Unknown PCM  
iec958:AES0=0x2,AES1=0x82,AES2=0x0,AES3=0x2
[0x2b82b18] alsa audio output debug: opening ALSA device `default'
[0x2b82b18] main audio output debug: thread (aout) created at priority  
15 (alsa.c:687)
[0x2b82b18] main audio output debug: thread started
[0x2b82b18] main audio output debug: using audio output module "alsa"
[0x2b82b18] main audio output debug: TIMER module_need() : 99.006 ms -  
Total 99.006 ms / 1 intvls (Avg 99.006 ms)
[0x2b82b18] main audio output debug: output 'fl32' 48000 Hz Stereo  
frame=1 samples/8 bytes
[0x2b82b18] main audio output debug: mixer 'fl32' 48000 Hz Stereo  
frame=1 samples/8 bytes
[0x2b82b18] main audio output debug: no need for any filter
[0x2b82b18] main audio output debug: looking for audio mixer module: 3  
candidates
[0x2b82b18] main audio output debug: using audio mixer module  
"float32_mixer"
[0x2b82b18] main audio output debug: TIMER module_need() : 0.505 ms -  
Total 0.505 ms / 1 intvls (Avg 0.505 ms)
[0x2b82b18] main audio output debug: input 'a52 ' 48000 Hz 3F2R/LFE  
frame=1536 samples/1536 bytes
[0x2b87538] main audio filter debug: looking for audio filter module:  
1 candidate
[0x2b87538] scaletempo audio filter warning: bad input or output format
[0x2b87538] scaletempo audio filter warning: input and output formats  
are not similar
[0x2b87538] main audio filter warning: no audio filter module matching  
"scaletempo" could be loaded
[0x2b87538] main audio filter debug: TIMER module_need() : 0.545 ms -  
Total 0.545 ms / 1 intvls (Avg 0.545 ms)
[0x2b87538] main audio filter debug: looking for audio filter module:  
1 candidate
[0x2b87538] scaletempo audio filter debug: format: 48000 rate, 6 nch,  
4 bps, fl32
[0x2b87538] scaletempo audio filter debug: params: 30 stride, 0.200  
overlap, 14 search
[0x2b87538] scaletempo audio filter debug: 1.000 scale, 1440.000  
stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search,  
2400 queue, fl32 mode
[0x2b87538] main audio filter debug: using audio filter module  
"scaletempo"
[0x2b87538] main audio filter debug: TIMER module_need() : 0.386 ms -  
Total 0.386 ms / 1 intvls (Avg 0.386 ms)
[0x2b82b18] main audio output debug: filter(s) 'a52 '->'fl32' 48000 Hz- 
 >48000 Hz 3F2R/LFE->3F2R/LFE
[0x2bbfa68] main audio output debug: looking for audio filter module:  
24 candidates
[0x2bbfa68] main audio output debug: using audio filter module  
"a52tofloat32"
[0x2bbfa68] main audio output debug: TIMER module_need() : 7.159 ms -  
Total 7.159 ms / 1 intvls (Avg 7.159 ms)
[0x2b82b18] main audio output debug: found a filter for the whole  
conversion
[0x2b82b18] main audio output debug: filter(s) 'fl32'->'fl32' 48000 Hz- 
 >48000 Hz 3F2R/LFE->Stereo
[0x2bd6af8] main audio output debug: looking for audio filter module:  
24 candidates
[0x2bd6af8] main audio output debug: using audio filter module  
"simple_channel_mixer"
[0x2bd6af8] main audio output debug: TIMER module_need() : 1.469 ms -  
Total 1.469 ms / 1 intvls (Avg 1.469 ms)
[0x2b82b18] main audio output debug: found a filter for the whole  
conversion
[0x2b82b18] main audio output debug: filter(s) 'fl32'->'fl32' 52800 Hz- 
 >48000 Hz Stereo->Stereo
[0x2bdf988] main audio output debug: looking for audio filter module:  
24 candidates
[0x2bdf988] main audio output debug: using audio filter module  
"bandlimited_resampler"
[0x2bdf988] main audio output debug: TIMER module_need() : 0.332 ms -  
Total 0.332 ms / 1 intvls (Avg 0.332 ms)
[0x2b82b18] main audio output debug: found a filter for the whole  
conversion
[0x28bfb78] main decoder debug: End of audio preroll
[0x27d06b8] main input debug: Decoder buffering done in 354 ms
[0x28d6c08] freetype spu text debug: using fontsize: 45
[0x2beb2a8] main blend debug: looking for video blending module: 1  
candidate
[0x2beb2a8] blend blend debug: chroma: YUVA -> I420
[0x2beb2a8] main blend debug: using video blending module "blend"
[0x2beb2a8] main blend debug: TIMER module_need() : 0.607 ms - Total  
0.607 ms / 1 intvls (Avg 0.607 ms)
[0x2b82b18] main audio output warning: audio drift is too big  
(351295), dropping buffer
[0x2b82b18] main audio output warning: audio drift is too big  
(319295), dropping buffer
[0x2b82b18] main audio output warning: audio drift is too big  
(287295), dropping buffer
[0x2b82b18] main audio output warning: audio drift is too big  
(255295), dropping buffer
[0x2b82b18] main audio output warning: audio drift is too big  
(223295), dropping buffer
[0x2b82b18] main audio output warning: audio drift is too big  
(191295), dropping buffer
[0x2b82b18] main audio output warning: audio drift is too big  
(159295), dropping buffer
[0x2b82b18] main audio output warning: audio drift is too big  
(149518), dropping buffer
[0x2b82b18] main audio output warning: buffer is 117518 late,  
triggering upsampling
[0x2893138] main decoder debug: decoded 63/108 pictures
[0x2b82b18] main audio output warning: resampling stopped after  
16981224 usec (drift: -11368)
[0x28d12a8] main video output warning: late picture skipped (3944 > -16)




More information about the vlc mailing list