thresh@snowwhite ~ $ vlc -A pulse -vvv --reset-config --reset-plugins-cache ~/shiva-razrushitel.mp3
VLC media player 1.0.0-rc2 Goldeneye
[0x87df678] main libvlc debug: VLC media player - version 1.0.0-rc2 Goldeneye - (c) 1996-2009 the VideoLAN team
[0x87df678] main libvlc debug: libvlc was configured with ./configure '--build=i586-alt-linux' '--host=i586-alt-linux' '--prefix=/usr' '--exec-prefix=/usr' '--bindir=/usr/bin' '--sbindir=/usr/sbin' '--sysconfdir=/etc' '--datadir=/usr/share' '--includedir=/usr/include' '--libdir=/usr/lib' '--libexecdir=/usr/lib' '--localstatedir=/var/lib' '--sharedstatedir=/usr/com' '--mandir=/usr/share/man' '--infodir=/usr/share/info' '--disable-dependency-tracking' '--without-included-gettext' '--disable-debug' '--disable-rpath' '--disable-static' '--enable-a52' '--enable-aa' '--enable-alsa' '--enable-audioscrobbler' '--enable-asademux' '--enable-avcodec' '--enable-avformat' '--enable-swscale' '--enable-postproc' '--enable-caca' '--enable-cdda' '--disable-cddax' '--enable-cmml' '--enable-dc1394' '--disable-dirac' '--enable-dv' '--enable-dvb' '--enable-dvbpsi' '--enable-dvdnav' '--enable-dvdread' '--enable-dca' '--enable-faad' '--enable-fb' '--enable-flac' '--enable-freetype' '--enable-fribidi' '--enable-galaktos' '--disable-ggi' '--enable-glx' '--disable-gnomevfs' '--enable-gnutls' '--enable-goom' '--enable-hal' '--enable-httpd' '--enable-jack' '--enable-kate' '--enable-libass' '--enable-libcddb' '--enable-libcdio' '--enable-libmpeg2' '--enable-libv4l2' '--enable-libxml2' '--enable-lirc' '--enable-live555' '--enable-loader' '--enable-real' '--enable-mad' '--enable-mga' '--enable-mkv' '--enable-mod' '--enable-mozilla' '--enable-mpc' '--enable-ncurses' '--enable-notify' '--enable-ogg' '--enable-opengl' '--enable-oss' '--enable-png' '--enable-pulse' '--disable-quicktime' '--enable-realrtsp' '--enable-release' '--enable-schroedinger' '--enable-screen' '--enable-sdl' '--enable-shout' '--enable-skins2' '--enable-smb' '--enable-snapshot' '--enable-speex' '--disable-svg' '--enable-tarkin' '--enable-theora' '--enable-telepathy' '--enable-tremor' '--enable-twolame' '--disable-upnp' '--enable-v4l' '--enable-v4l2' '--enable-vcd' '--enable-vcdx' '--enable-visual' '--enable-vlm' '--enable-vorbis' '--enable-x11' '--enable-x264' '--enable-xinerama' '--enable-xosd' '--enable-xvideo' 'build_alias=i586-alt-linux' 'host_alias=i586-alt-linux' 'CFLAGS=-pipe -Wall -O2 -march=i586 -mtune=i686' 'CXXFLAGS=-pipe -Wall -O2 -march=i586 -mtune=i686'
[0x87df678] main libvlc debug: translation test: code is "C"
[0x87df678] main libvlc debug: checking plugin modules
[0x87df678] main libvlc debug: removing plugins cache file /home/thresh/.cache/vlc/plugins-04041e.dat
[0x87df678] main libvlc debug: recursively browsing `/usr/lib/vlc'
[0x87df678] main libvlc debug: module bank initialized (399 modules)
[0x87df678] main libvlc debug: opening config file (/home/thresh/.config/vlc/vlcrc)
[0x87df678] main libvlc debug: opening config file (/home/thresh/.config/vlc/vlcrc)
[0x87df678] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT FPU
[0x87df678] main libvlc debug: looking for memcpy module: 3 candidates
[0x87df678] main libvlc debug: using memcpy module "memcpymmxext"
[0x89a9b78] main input debug: Creating an input for 'Media Library'
[0x89a9b78] main input debug: Input is a meta file: disabling unneeded options
[0x89a9b78] main input debug: using timeshift granularity of 50 MBytes
[0x89a9b78] main input debug: using timeshift path '/tmp'
[0x89a9b78] main input debug: `file/xspf-open:///home/thresh/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/thresh/.local/share/vlc/ml.xspf'
[0x89a9b78] main input debug: creating demux: access='file' demux='xspf-open' path='/home/thresh/.local/share/vlc/ml.xspf'
[0x89a49a0] main demux debug: looking for access_demux module: 1 candidate
[0x89a49a0] main demux warning: no access_demux module matching "file" could be loaded
[0x89a49a0] main demux debug: TIMER module_need() : 0,108 ms - Total 0,108 ms / 1 intvls (Avg 0,108 ms)
[0x89a9b78] main input debug: creating access 'file' path='/home/thresh/.local/share/vlc/ml.xspf'
[0x89a49a0] main access debug: looking for access module: 3 candidates
[0x89a49a0] access_file access debug: opening file `/home/thresh/.local/share/vlc/ml.xspf'
[0x89a49a0] main access debug: using access module "access_file"
[0x89a49a0] main access debug: TIMER module_need() : 0,199 ms - Total 0,199 ms / 1 intvls (Avg 0,199 ms)
[0x89a51f0] main stream debug: Using AStream*Stream
[0x89a51f0] main stream debug: pre buffering
[0x89a51f0] main stream debug: received first data after 0 ms
[0x89a51f0] main stream debug: pre-buffering done 296 bytes in 0s - 24088 kbytes/s
[0x89a5aa8] main stream debug: looking for stream_filter module: 4 candidates
[0x89a5aa8] main stream debug: TIMER module_need() : 0,068 ms - Total 0,068 ms / 1 intvls (Avg 0,068 ms)
[0x89a5a90] main stream debug: looking for stream_filter module: 1 candidate
[0x89a5a90] main stream debug: using stream_filter module "stream_filter_record"
[0x89a5a90] main stream debug: TIMER module_need() : 0,067 ms - Total 0,067 ms / 1 intvls (Avg 0,067 ms)
[0x89a9b78] main input debug: creating demux: access='file' demux='xspf-open' path='/home/thresh/.local/share/vlc/ml.xspf'
[0x89a5c78] main demux debug: looking for demux module: 1 candidate
[0x89a5c78] playlist demux debug: using XSPF playlist reader
[0x89a5c78] main demux debug: using demux module "playlist"
[0x89a5c78] main demux debug: TIMER module_need() : 0,084 ms - Total 0,084 ms / 1 intvls (Avg 0,084 ms)
[0x89a9b78] main input debug: `file/xspf-open:///home/thresh/.local/share/vlc/ml.xspf' successfully opened
[0x89a5d50] main xml debug: looking for xml module: 2 candidates
[0x89a5d50] main xml debug: using xml module "xml"
[0x89a5d50] main xml debug: TIMER module_need() : 0,066 ms - Total 0,066 ms / 1 intvls (Avg 0,066 ms)
[0x89a5c78] playlist demux debug: parsed 0 tracks successfully
[0x89a5d50] main xml debug: removing module "xml"
[0x89a9b78] main input debug: EOF reached
[0x89a5c78] main demux debug: removing module "playlist"
[0x89a5a90] main stream debug: removing module "stream_filter_record"
[0x89a49a0] main access debug: removing module "access_file"
[0x89a9b78] main input debug: TIMER input launching for 'Media Library' : 4,027 ms - Total 4,027 ms / 1 intvls (Avg 4,027 ms)
[0x89a3518] main playlist debug: rebuilding array of current - root Playlist
[0x89a3518] main playlist debug: rebuild done - 0 items, index -1
[0x89a3518] main playlist debug: Activated
[0x89a7e58] main interface debug: looking for interface module: 1 candidate
[0x89a7e58] main interface debug: using interface module "hotkeys"
[0x89a7e58] main interface debug: TIMER module_need() : 0,141 ms - Total 0,141 ms / 1 intvls (Avg 0,141 ms)
[0x89a7e58] main interface debug: thread started
[0x89a7e58] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156)
[0x89a88d0] main interface debug: looking for interface module: 1 candidate
[0x89a88d0] main interface debug: using interface module "inhibit"
[0x89a88d0] main interface debug: TIMER module_need() : 1,089 ms - Total 1,089 ms / 1 intvls (Avg 1,089 ms)
[0x89a88d0] main interface debug: thread started
[0x89a88d0] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156)
[0x87f9e00] main interface debug: looking for interface module: 1 candidate
[0x87f9e00] main interface debug: using interface module "screensaver"
[0x87f9e00] main interface debug: TIMER module_need() : 0,090 ms - Total 0,090 ms / 1 intvls (Avg 0,090 ms)
[0x87f9e00] main interface debug: thread started
[0x87f9e00] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156)
[0x89a3518] main playlist debug: adding item `shiva-razrushitel.mp3' ( /home/thresh/shiva-razrushitel.mp3 )
[0x87fa978] main interface debug: looking for interface module: 1 candidate
[0x87fa978] main interface debug: using interface module "signals"
[0x87fa978] main interface debug: TIMER module_need() : 0,106 ms - Total 0,106 ms / 1 intvls (Avg 0,106 ms)
[0x87fa978] main interface debug: thread started
[0x87fa978] main interface debug: thread ended
[0x87fa978] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156)
[0x87fc300] main interface debug: looking for interface module: 1 candidate
[0x87fc300] main interface debug: using interface module "globalhotkeys"
[0x87fc300] main interface debug: TIMER module_need() : 8,182 ms - Total 8,182 ms / 1 intvls (Avg 8,182 ms)
[0x87fc300] main interface debug: thread started
[0x87fc300] main interface debug: thread ended
[0x87fc300] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156)
[0x87df678] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[0x87fcca8] main interface debug: looking for interface module: 5 candidates
[0x87fcca8] main interface debug: opening config file (/home/thresh/.config/vlc/vlcrc)
[0x87fcca8] main interface debug: using interface module "qt4"
[0x87fcca8] qt4 interface debug: Error while initializing qt-specific localization
[0x87fcca8] main interface debug: TIMER module_need() : 1440,342 ms - Total 1440,342 ms / 1 intvls (Avg 1440,342 ms)
[0x87fcca8] main interface debug: thread started
[0x87fcca8] main interface debug: thread ended
[0x87fcca8] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156)
[0x89a3518] main playlist debug: rebuilding array of current - root Playlist
[0x89a3518] main playlist debug: rebuild done - 1 items, index -1
[0x89a3518] main playlist debug: processing request item null node Playlist skip 0
[0x89a3518] main playlist debug: starting new item
[0x89a3518] main playlist debug: creating new input thread
[0x8b45da0] main input debug: Creating an input for 'shiva-razrushitel.mp3'
[0x8b45da0] main input debug: thread (input) created at priority 10 (input/input.c:230)
[0x8b45da0] main input debug: thread started
[0x8b45da0] main input debug: using timeshift granularity of 50 MBytes
[0x8b45da0] main input debug: using timeshift path '/tmp'
[0x8b45da0] main input debug: `/home/thresh/shiva-razrushitel.mp3' gives access `' demux `' path `/home/thresh/shiva-razrushitel.mp3'
[0x8b45da0] main input debug: creating demux: access='' demux='' path='/home/thresh/shiva-razrushitel.mp3'
[0x87fe9b8] main demux debug: looking for access_demux module: 8 candidates
[0x87fe9b8] main demux debug: TIMER module_need() : 0,346 ms - Total 0,346 ms / 1 intvls (Avg 0,346 ms)
[0x8b45da0] main input debug: creating access '' path='/home/thresh/shiva-razrushitel.mp3'
[0x87fe9b8] main access debug: looking for access module: 7 candidates
[0x87fe9b8] vcd access debug: trying .cue file: /home/thresh/shiva-razrushitel.cue
[0x87fe9b8] vcd access debug: could not find .cue file
[0x87fe9b8] access_file access debug: opening file `/home/thresh/shiva-razrushitel.mp3'
[0x87fe9b8] main access debug: using access module "access_file"
[0x87fe9b8] main access debug: TIMER module_need() : 1,812 ms - Total 1,812 ms / 1 intvls (Avg 1,812 ms)
[0x8b2c1a0] main stream debug: Using AStream*Stream
[0x8b2c1a0] main stream debug: pre buffering
[0x8b2c1a0] main stream debug: received first data after 0 ms
[0x8b2c1a0] main stream debug: pre-buffering done 1024 bytes in 0s - 4830 kbytes/s
[0x87ff268] main stream debug: looking for stream_filter module: 4 candidates
[0x87ff268] main stream debug: TIMER module_need() : 0,287 ms - Total 0,287 ms / 1 intvls (Avg 0,287 ms)
[0x87ff198] main stream debug: looking for stream_filter module: 1 candidate
[0x87ff198] main stream debug: using stream_filter module "stream_filter_record"
[0x87ff198] main stream debug: TIMER module_need() : 0,489 ms - Total 0,489 ms / 1 intvls (Avg 0,489 ms)
[0x8b45da0] main input debug: creating demux: access='' demux='' path='/home/thresh/shiva-razrushitel.mp3'
[0x87ff260] main demux debug: looking for demux module: 50 candidates
[0x87ff260] es demux debug: detected format mpga
[0x8b4abf0] main packetizer debug: looking for packetizer module: 21 candidates
[0x8b4abf0] main packetizer debug: using packetizer module "mpeg_audio"
[0x8b4abf0] main packetizer debug: TIMER module_need() : 0,509 ms - Total 0,509 ms / 1 intvls (Avg 0,509 ms)
[0x87ff260] main demux debug: using demux module "es"
[0x87ff260] main demux debug: TIMER module_need() : 1,478 ms - Total 1,478 ms / 1 intvls (Avg 1,478 ms)
[0x8b45da0] main input debug: looking for a subtitle file in /home/thresh/
[0x87ff260] main demux debug: looking for meta reader module: 1 candidate
[0x87ff260] main demux debug: using meta reader module "taglib"
[0x87ff260] main demux debug: TIMER module_need() : 1,005 ms - Total 1,005 ms / 1 intvls (Avg 1,005 ms)
[0x87ff260] main demux debug: removing module "taglib"
[0x8b45da0] main input debug: `/home/thresh/shiva-razrushitel.mp3' successfully opened
[0x8b4abf0] mpeg_audio packetizer debug: MPGA channels:2 samplerate:44100 bitrate:128
[0x8b45da0] main input debug: selecting program id=0
[0x8b4b9b8] main decoder debug: looking for decoder module: 38 candidates
[0x8b4b9b8] main decoder debug: using decoder module "mpeg_audio"
[0x8b4b9b8] main decoder debug: TIMER module_need() : 2,742 ms - Total 2,742 ms / 1 intvls (Avg 2,742 ms)
[0x8b4b9b8] main decoder debug: thread started
[0x8b4b9b8] main decoder debug: thread (decoder) created at priority 5 (input/decoder.c:315)
[0x8b45da0] main input debug: Buffering 0%
[0x8b45da0] main input debug: Buffering 8%
[0x8b4b9b8] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:128
[0x8b45da0] main input debug: creating aout
[0x8b48a08] main audio output debug: looking for audio output module: 6 candidates
[0x8b48a08] pulse audio output: No. of Audio Channels: 2
[0x8b45da0] main input debug: Buffering 17%
[0x8b45da0] main input debug: Buffering 26%
[0x8b45da0] main input debug: Buffering 34%
[0x8b45da0] main input debug: Buffering 43%
[0x8b45da0] main input debug: Buffering 52%
[0x8b45da0] main input debug: Buffering 60%
[0x8b45da0] main input debug: Buffering 69%
[0x8b45da0] main input debug: Buffering 78%
[0x8b45da0] main input debug: Buffering 87%
[0x8b45da0] main input debug: Buffering 95%
[0x8b45da0] main input debug: Stream buffering done (313 ms in 2 ms)
[0x87fcca8] qt4 interface debug: IM: Setting an input
[0x87fcca8] qt4 interface debug: Updating the geometry
[0x87fcca8] qt4 interface debug: Updating the geometry
[0x8b48a08] pulse audio output debug: Pulse mainloop started
[0x8b48a08] pulse audio output debug: Pulse stream connected
[0x8b48a08] pulse audio output debug: Pulse initialized successfully
[0x8b48a08] pulse audio output debug: Buffer metrics: maxlength=141120, tlength=55512, prebuf=48464, minreq=7056
[0x8b48a08] pulse audio output debug: Using sample spec 'float32le 2ch 44100Hz', channel map 'front-left,front-right'.
[0x8b48a08] pulse audio output debug: Connected to device alsa_output.default (0, not suspended).
[0x8b48a08] main audio output debug: using audio output module "pulse"
[0x8b48a08] main audio output debug: TIMER module_need() : 116,197 ms - Total 116,197 ms / 1 intvls (Avg 116,197 ms)
[0x8b48a08] main audio output debug: output 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes
[0x8b48a08] main audio output debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes
[0x8b48a08] main audio output debug: no need for any filter
[0x8b48a08] main audio output debug: looking for audio mixer module: 3 candidates
[0x8b48a08] main audio output debug: using audio mixer module "float32_mixer"
[0x8b48a08] main audio output debug: TIMER module_need() : 0,503 ms - Total 0,503 ms / 1 intvls (Avg 0,503 ms)
[0x8b48a08] main audio output debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes
[0x8b4ec38] main audio filter debug: looking for audio filter module: 1 candidate
[0x8b4ec38] scaletempo audio filter warning: bad input or output format
[0x8b4ec38] main audio filter warning: no audio filter module matching "scaletempo" could be loaded
[0x8b4ec38] main audio filter debug: TIMER module_need() : 2,947 ms - Total 2,947 ms / 1 intvls (Avg 2,947 ms)
[0x8b4ec38] main audio filter debug: looking for audio filter module: 1 candidate
[0x8b4ec38] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32
[0x8b4ec38] scaletempo audio filter debug: params: 30 stride, 0,200 overlap, 14 search
[0x8b4ec38] scaletempo audio filter debug: 1,000 scale, 1323,000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode
[0x8b4ec38] main audio filter debug: using audio filter module "scaletempo"
[0x8b4ec38] main audio filter debug: TIMER module_need() : 2,340 ms - Total 2,340 ms / 1 intvls (Avg 2,340 ms)
[0x8b48a08] main audio output debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo
[0x8b4f350] main audio output debug: looking for audio filter module: 24 candidates
[0x8b4f350] main audio output debug: using audio filter module "mpgatofixed32"
[0x8b4f350] main audio output debug: TIMER module_need() : 0,484 ms - Total 0,484 ms / 1 intvls (Avg 0,484 ms)
[0x8b48a08] main audio output debug: found a filter for the whole conversion
[0x8b48a08] main audio output debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo
[0x8b4fa68] main audio output debug: looking for audio filter module: 24 candidates
[0x8b4fa68] main audio output debug: using audio filter module "bandlimited_resampler"
[0x8b4fa68] main audio output debug: TIMER module_need() : 0,560 ms - Total 0,560 ms / 1 intvls (Avg 0,560 ms)
[0x8b48a08] main audio output debug: found a filter for the whole conversion
[0x8b4b9b8] main decoder debug: End of audio preroll
[0x8b45da0] main input debug: Decoder buffering done in 126 ms
[0x8b48a08] main audio output warning: PTS is out of range (-9867), dropping buffer
[0x8b48a08] main audio output warning: PTS is out of range (-35771), dropping buffer
[0x8b48a08] pulse audio output debug: Pulse stream started
[0x8b48a08] main audio output warning: output date isn't PTS date, requesting resampling (80508)
[0x8b48a08] main audio output warning: buffer is 80507 late, triggering upsampling
[0x8b48a08] main audio output warning: resampling stopped after 13661836 usec (drift: 355)