Pulse audio: no sound at all

[Maththias]

Hi to everyone!

I'm a debian sid user and yesterday I've changed my server audio into pulse audio (in gnome 2.26). Everything is ok, but vlc 1.0.0-rc2 doesn't work with pulse plugin directly (no sound at all)
With alsa one the sound comes out, but with some noises.

The debug output (with pulseaudio output enabled):

Code: Select all

~$ vlc -vvv VLC media player 1.0.0-rc2 Goldeneye [0x9a168a8] main libvlc debug: VLC media player - version 1.0.0-rc2 Goldeneye - (c) 1996-2009 the VideoLAN team [0x9a168a8] main libvlc debug: libvlc was configured with ./configure '--mandir=${prefix}/share/man' '--infodir=${prefix}/share/info' '--enable-maintainer-mode' '--enable-release' '--prefix=/usr' '--enable-libtool' '--enable-fast-install' '--disable-update-check' '--disable-gtk' '--disable-familiar' '--disable-fb' '--enable-ggi' '--enable-sdl' '--enable-esd' '--enable-mad' '--enable-jack' '--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' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--enable-faad' '--disable-zvbi' '--enable-telx' '--enable-mediacontrol-bindings' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-libdca' '--enable-realrtsp' '--disable-dv' '--enable-x264' '--enable-alsa' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-svgalib' '--enable-dvd' '--enable-pulse' 'CFLAGS=-Wall -g -O2' [0x9a168a8] main libvlc debug: translation test: code is "C" [0x9a168a8] main libvlc debug: checking plugin modules [0x9a168a8] main libvlc debug: loading plugins cache file /home/xxx/.cache/vlc/plugins-04041e.dat [0x9a168a8] main libvlc debug: recursively browsing `/usr/lib/vlc' [0x9a168a8] main libvlc debug: module bank initialized (388 modules) [0x9a168a8] main libvlc debug: opening config file (/home/xxx/.config/vlc/vlcrc) [0x9a168a8] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU [0x9a168a8] main libvlc debug: looking for memcpy module: 3 candidates [0x9a168a8] main libvlc debug: using memcpy module "memcpymmxext" [0x9acb248] main input debug: Creating an input for 'Raccolta multimediale' [0x9acb248] main input debug: Input is a meta file: disabling unneeded options [0x9acb248] main input debug: using timeshift granularity of 50 MBytes [0x9acb248] main input debug: using timeshift path '/tmp' [0x9acb248] main input debug: `file/xspf-open:///home/xxx/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/xxx/.local/share/vlc/ml.xspf' [0x9acb248] main input debug: creating demux: access='file' demux='xspf-open' path='/home/xxx/.local/share/vlc/ml.xspf' [0x9ad0550] main demux debug: looking for access_demux module: 1 candidate [0x9ad0550] main demux warning: no access_demux module matching "file" could be loaded [0x9ad0550] main demux debug: TIMER module_need() : 0,656 ms - Total 0,656 ms / 1 intvls (Avg 0,656 ms) [0x9acb248] main input debug: creating access 'file' path='/home/xxx/.local/share/vlc/ml.xspf' [0x9ad2648] main access debug: looking for access module: 3 candidates [0x9ad2648] access_file access debug: opening file `/home/xxx/.local/share/vlc/ml.xspf' [0x9ad2648] main access debug: using access module "access_file" [0x9ad2648] main access debug: TIMER module_need() : 0,641 ms - Total 0,641 ms / 1 intvls (Avg 0,641 ms) [0x9ad1ca8] main stream debug: Using AStream*Stream [0x9ad1ca8] main stream debug: pre buffering [0x9ad1ca8] main stream debug: received first data after 0 ms [0x9ad1ca8] main stream debug: pre-buffering done 304 bytes in 0s - 6453 kbytes/s [0x9ad35d0] main stream debug: looking for stream_filter module: 4 candidates [0x9ad35d0] main stream debug: TIMER module_need() : 0,486 ms - Total 0,486 ms / 1 intvls (Avg 0,486 ms) [0x9ad35d0] main stream debug: looking for stream_filter module: 1 candidate [0x9ad35d0] main stream debug: using stream_filter module "stream_filter_record" [0x9ad35d0] main stream debug: TIMER module_need() : 0,229 ms - Total 0,229 ms / 1 intvls (Avg 0,229 ms) [0x9acb248] main input debug: creating demux: access='file' demux='xspf-open' path='/home/xxx/.local/share/vlc/ml.xspf' [0x9ad4660] main demux debug: looking for demux module: 1 candidate [0x9ad4660] playlist demux debug: using XSPF playlist reader [0x9ad4660] main demux debug: using demux module "playlist" [0x9ad4660] main demux debug: TIMER module_need() : 0,426 ms - Total 0,426 ms / 1 intvls (Avg 0,426 ms) [0x9acb248] main input debug: `file/xspf-open:///home/xxx/.local/share/vlc/ml.xspf' successfully opened [0x9ad5b78] main xml debug: looking for xml module: 2 candidates [0x9ad5b78] main xml debug: using xml module "xml" [0x9ad5b78] main xml debug: TIMER module_need() : 0,568 ms - Total 0,568 ms / 1 intvls (Avg 0,568 ms) [0x9ad4660] playlist demux debug: parsed 0 tracks successfully [0x9ad5b78] main xml debug: removing module "xml" [0x9acb248] main input debug: EOF reached [0x9ad4660] main demux debug: removing module "playlist" [0x9ad35d0] main stream debug: removing module "stream_filter_record" [0x9ad2648] main access debug: removing module "access_file" [0x9acb248] main input debug: TIMER input launching for 'Raccolta multimediale' : 7,102 ms - Total 7,102 ms / 1 intvls (Avg 7,102 ms) [0x9abf468] main playlist debug: rebuilding array of current - root Scaletta [0x9abf468] main playlist debug: rebuild done - 0 items, index -1 [0x9abf468] main playlist debug: Activated [0x9ad5b78] main interface debug: looking for interface module: 1 candidate [0x9ad5b78] main interface debug: using interface module "hotkeys" [0x9ad5b78] main interface debug: TIMER module_need() : 0,451 ms - Total 0,451 ms / 1 intvls (Avg 0,451 ms) [0x9ad5b78] main interface debug: thread started [0x9ad5b78] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x9ad73a0] main interface debug: looking for interface module: 1 candidate [0x9ad73a0] main interface debug: using interface module "inhibit" [0x9ad73a0] main interface debug: TIMER module_need() : 4,535 ms - Total 4,535 ms / 1 intvls (Avg 4,535 ms) [0x9ad73a0] main interface debug: thread started [0x9ad73a0] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x9ad6f88] main interface debug: looking for interface module: 1 candidate [0x9ad6f88] main interface debug: using interface module "screensaver" [0x9ad6f88] main interface debug: TIMER module_need() : 0,398 ms - Total 0,398 ms / 1 intvls (Avg 0,398 ms) [0x9ad6f88] main interface debug: thread started [0x9ad6f88] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x9ad3708] main interface debug: looking for interface module: 1 candidate [0x9ad3708] main interface debug: using interface module "signals" [0x9ad3708] main interface debug: TIMER module_need() : 0,288 ms - Total 0,288 ms / 1 intvls (Avg 0,288 ms) [0x9ad3708] main interface debug: thread started [0x9ad3708] main interface debug: thread ended [0x9ad3708] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x9acfaa8] main interface debug: looking for interface module: 1 candidate [0x9acfaa8] main interface debug: using interface module "globalhotkeys" [0x9acfaa8] main interface debug: TIMER module_need() : 9,585 ms - Total 9,585 ms / 1 intvls (Avg 9,585 ms) [0x9acfaa8] main interface debug: thread started [0x9acfaa8] main interface debug: thread ended [0x9acfaa8] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x9a168a8] main libvlc: Esecuzione di vlc con l'interfaccia predefinita. Usa 'cvlc' per utilizzare vlc senza interfaccia. [0x9ad9158] main interface debug: looking for interface module: 4 candidates [0x9ad9158] main interface debug: using interface module "qt4" [0x9ad9158] main interface debug: TIMER module_need() : 291,434 ms - Total 291,434 ms / 1 intvls (Avg 291,434 ms) [0x9ad9158] main interface debug: thread started [0x9ad9158] main interface debug: thread ended [0x9ad9158] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x9ad9158] qt4 interface debug: Error while initializing qt-specific localization [0x9abf468] main playlist debug: adding item `xxx.mp3' ( /home/xxx/xxx/xxx/xxx.mp3 ) [0x9abf468] main playlist debug: rebuilding array of current - root Scaletta [0x9abf468] main playlist debug: rebuild done - 1 items, index -1 [0x9abf468] main playlist debug: processing request item xxx.mp3 node null skip 0 [0x9abf468] main playlist debug: resyncing on xxx.mp3 [0x9abf468] main playlist debug: xxx.mp3 is at 0 [0x9abf468] main playlist debug: starting new item [0x9abf468] main playlist debug: creating new input thread [0x9e230e8] main input debug: Creating an input for 'xxx.mp3' [0x9e230e8] main input debug: thread started [0x9e230e8] main input debug: using timeshift granularity of 50 MBytes [0x9e230e8] main input debug: using timeshift path '/tmp' [0x9ad9158] qt4 interface debug: Adding a new MRL to recent ones: /home/xxx/xxx/xxx/xxx.mp3 [0x9e230e8] main input debug: thread (input) created at priority 10 (input/input.c:230) [0x9abf468] main playlist debug: requesting art for xxx.mp3 [0x9e230e8] main input debug: `/home/xxx/xxx/xxx/xxx.mp3' gives access `' demux `' path `/home/xxx/xxx/xxx/xxx.mp3' [0x9e230e8] main input debug: creating demux: access='' demux='' path='/home/xxx/xxx/xxx/xxx.mp3' [0x9dfc830] main demux debug: looking for access_demux module: 7 candidates [0x9ad9158] qt4 interface debug: IM: Setting an input [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry [0x9dfc830] main demux debug: TIMER module_need() : 121,442 ms - Total 121,442 ms / 1 intvls (Avg 121,442 ms) [0x9e230e8] main input debug: creating access '' path='/home/xxx/xxx/xxx/xxx.mp3' [0x9e37240] main access debug: looking for access module: 7 candidates [0x9e37240] vcd access debug: trying .cue file: /home/xxx/xxx/xxx/xxx.cue [0x9e37240] vcd access debug: could not find .cue file [0x9e37240] access_file access debug: opening file `/home/xxx/xxx/xxx/xxx.mp3' [0x9e37240] main access debug: using access module "access_file" [0x9e37240] main access debug: TIMER module_need() : 97,730 ms - Total 97,730 ms / 1 intvls (Avg 97,730 ms) [0x9e34128] main stream debug: Using AStream*Stream [0x9e34128] main stream debug: pre buffering [0x9e34128] main stream debug: received first data after 11 ms [0x9e34128] main stream debug: pre-buffering done 1024 bytes in 0s - 85 kbytes/s [0x9e34650] main stream debug: looking for stream_filter module: 4 candidates [0x9e34650] main stream debug: TIMER module_need() : 0,333 ms - Total 0,333 ms / 1 intvls (Avg 0,333 ms) [0x9e34650] main stream debug: looking for stream_filter module: 1 candidate [0x9e34650] main stream debug: using stream_filter module "stream_filter_record" [0x9e34650] main stream debug: TIMER module_need() : 0,227 ms - Total 0,227 ms / 1 intvls (Avg 0,227 ms) [0x9e230e8] main input debug: creating demux: access='' demux='' path='/home/xxx/xxx/xxx/xxx.mp3' [0x9e37d70] main demux debug: ID3v2.3 revision 0 tag found, skipping 20786 bytes [0x9e37d70] main demux debug: looking for demux module: 50 candidates [0x9e37d70] es demux debug: detected format mpga [0x9e2de08] main packetizer debug: looking for packetizer module: 21 candidates [0x9e2de08] main packetizer debug: using packetizer module "mpeg_audio" [0x9e2de08] main packetizer debug: TIMER module_need() : 67,478 ms - Total 67,478 ms / 1 intvls (Avg 67,478 ms) [0x9e37d70] main demux debug: using demux module "es" [0x9e37d70] main demux debug: TIMER module_need() : 142,307 ms - Total 142,307 ms / 1 intvls (Avg 142,307 ms) [0x9e230e8] main input debug: looking for a subtitle file in /home/xxx/xxx/xxx/ [0x9e37d70] main demux debug: looking for meta reader module: 1 candidate [0x9e37d70] taglib demux debug: Found embedded art: 3 (image/jpeg) is 19578 bytes [0x9e37d70] main demux debug: using meta reader module "taglib" [0x9e37d70] main demux debug: TIMER module_need() : 101,422 ms - Total 101,422 ms / 1 intvls (Avg 101,422 ms) [0x9e37d70] main demux debug: removing module "taglib" [0x9abf468] main playlist debug: found art for xxx in cache [0x9e230e8] main input debug: `/home/xxx/xxx/xxx/xxx.mp3' successfully opened [0x9e2de08] mpeg_audio packetizer debug: MPGA channels:2 samplerate:44100 bitrate:256 [0x9e230e8] main input debug: selecting program id=0 [0x9e2f5e0] main decoder debug: looking for decoder module: 30 candidates [0x9e2f5e0] main decoder debug: using decoder module "mpeg_audio" [0x9e2f5e0] main decoder debug: TIMER module_need() : 94,200 ms - Total 94,200 ms / 1 intvls (Avg 94,200 ms) [0x9e2f5e0] main decoder debug: thread started [0x9e2f5e0] main decoder debug: thread (decoder) created at priority 5 (input/decoder.c:315) [0x9e230e8] main input debug: Buffering 0% [0x9e230e8] main input debug: Buffering 8% [0x9e2f5e0] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:256 [0x9e230e8] main input debug: creating aout [0x9e451a0] main audio output debug: looking for audio output module: 6 candidates [0x9e451a0] pulse audio output: No. of Audio Channels: 2 [0x9e451a0] pulse audio output debug: Pulse mainloop started [0x9e230e8] main input debug: Buffering 17% [0x9e230e8] main input debug: Buffering 26% [0x9e230e8] main input debug: Buffering 34% [0x9e230e8] main input debug: Buffering 43% [0x9e230e8] main input debug: Buffering 52% [0x9e230e8] main input debug: Buffering 60% [0x9e230e8] main input debug: Buffering 69% [0x9e230e8] main input debug: Buffering 78% [0x9e230e8] main input debug: Buffering 87% [0x9e230e8] main input debug: Buffering 95% [0x9e230e8] main input debug: Stream buffering done (313 ms in 39 ms) [0x9e451a0] pulse audio output debug: Pulse stream connected [0x9e451a0] pulse audio output debug: Pulse initialized successfully [0x9e451a0] pulse audio output debug: Buffer metrics: maxlength=141120, tlength=42336, prebuf=35288, minreq=7056 [0x9e451a0] pulse audio output debug: Using sample spec 'float32le ch 2 44100 Hz', channel map 'front-left,front-right'. [0x9e451a0] pulse audio output debug: Connected to device alsa_output.pci_8086_27d8_sound_card_0 (0, not suspended). [0x9e451a0] main audio output debug: using audio output module "pulse" [0x9e451a0] main audio output debug: TIMER module_need() : 82,187 ms - Total 82,187 ms / 1 intvls (Avg 82,187 ms) [0x9e451a0] main audio output debug: output 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9e451a0] main audio output debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9e451a0] main audio output debug: no need for any filter [0x9e451a0] main audio output debug: looking for audio mixer module: 3 candidates [0x9e451a0] main audio output debug: using audio mixer module "float32_mixer" [0x9e451a0] main audio output debug: TIMER module_need() : 0,645 ms - Total 0,645 ms / 1 intvls (Avg 0,645 ms) [0x9e451a0] main audio output debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes [0x9e50e80] main audio filter debug: looking for audio filter module: 1 candidate [0x9e50e80] scaletempo audio filter warning: bad input or output format [0x9e50e80] main audio filter warning: no audio filter module matching "scaletempo" could be loaded [0x9e50e80] main audio filter debug: TIMER module_need() : 0,638 ms - Total 0,638 ms / 1 intvls (Avg 0,638 ms) [0x9e50e80] main audio filter debug: looking for audio filter module: 1 candidate [0x9e50e80] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32 [0x9e50e80] scaletempo audio filter debug: params: 30 stride, 0,200 overlap, 14 search [0x9e50e80] scaletempo audio filter debug: 1,000 scale, 1323,000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode [0x9e50e80] main audio filter debug: using audio filter module "scaletempo" [0x9e50e80] main audio filter debug: TIMER module_need() : 0,410 ms - Total 0,410 ms / 1 intvls (Avg 0,410 ms) [0x9e451a0] main audio output debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo [0x9e58a38] main audio output debug: looking for audio filter module: 24 candidates [0x9e58a38] main audio output debug: using audio filter module "mpgatofixed32" [0x9e58a38] main audio output debug: TIMER module_need() : 0,704 ms - Total 0,704 ms / 1 intvls (Avg 0,704 ms) [0x9e451a0] main audio output debug: found a filter for the whole conversion [0x9e451a0] main audio output debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo [0x9e61610] main audio output debug: looking for audio filter module: 24 candidates [0x9e61610] main audio output debug: using audio filter module "bandlimited_resampler" [0x9e61610] main audio output debug: TIMER module_need() : 0,659 ms - Total 0,659 ms / 1 intvls (Avg 0,659 ms) [0x9e451a0] main audio output debug: found a filter for the whole conversion [0x9e2f5e0] main decoder debug: End of audio preroll [0x9e230e8] main input debug: Decoder buffering done in 48 ms [0x9e451a0] main audio output warning: PTS is out of range (-9830), dropping buffer [0x9e451a0] main audio output warning: PTS is out of range (-35931), dropping buffer [0x9e451a0] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x9e451a0] pulse audio output debug: Pulse stream started [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: New caching: 100 [0x9ad9158] qt4 interface debug: New caching: 100 [0x9e230e8] main input debug: EOF reached [0x9e2f5e0] main decoder debug: removing module "mpeg_audio" [0x9e2f5e0] main decoder debug: killing decoder fourcc `mpga', 0 PES in FIFO [0x9e58a38] main audio output debug: removing module "mpgatofixed32" [0x9e50e80] main audio filter debug: removing module "scaletempo" [0x9e61610] main audio output debug: removing module "bandlimited_resampler" [0x9e451a0] pulse audio output debug: Pulse Close [0x9abf468] main playlist debug: finished input [0x9e451a0] main audio output debug: removing module "pulse" [0x9e451a0] main audio output debug: removing module "float32_mixer" [0x9e230e8] main input debug: releasing aout [0x9e2de08] main packetizer debug: removing module "mpeg_audio" [0x9e37d70] main demux debug: removing module "es" [0x9e34650] main stream debug: removing module "stream_filter_record" [0x9e37240] main access debug: removing module "access_file" [0x9e230e8] main input debug: Program doesn't contain anymore ES [0x9abf468] main playlist debug: dead input [0x9e230e8] main input debug: thread ended [0x9abf468] main playlist debug: changing item without a request (current 0/1) [0x9abf468] main playlist debug: nothing to play [0x9ad9158] qt4 interface debug: IM: Deleting the input [0x9ad9158] qt4 interface debug: Updating the geometry [0x9ad9158] qt4 interface debug: Updating the geometry

What's happened?

Tell me if you want other info.

Thanks.

[Maththias]

I've upgraded to this git version > http://repo.or.cz/w/vlc.git?a=commit;h= ... f41805793c and now sound comes out, but initially it has strange noises, then after 5-6 seconds it becomes normal.

vlc -vvv of that:

Code: Select all

VLC media player 1.1.0-git Goldeneye [0x99b98a8] main libvlc debug: VLC media player - version 1.1.0-git Goldeneye - (c) 1996-2009 the VideoLAN team [0x99b98a8] main libvlc debug: libvlc was configured with ./configure '--mandir=${prefix}/share/man' '--infodir=${prefix}/share/info' '--build=i486-linux-gnu' '--enable-maintainer-mode' '--enable-release' '--prefix=/usr' '--enable-fast-install' '--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=libxul-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' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--enable-faad' '--disable-zvbi' '--enable-telx' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-realrtsp' '--disable-dv' '--enable-x264' '--enable-alsa' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-svgalib' '--enable-gnomevfs' 'build_alias=i486-linux-gnu' 'CFLAGS=-Wall -g -O2' [0x99b98a8] main libvlc debug: translation test: code is "C" [0x99b98a8] main libvlc debug: checking plugin modules [0x99b98a8] main libvlc debug: loading plugins cache file /home/maththias/.cache/vlc/plugins-04041e.dat [0x99b98a8] main libvlc debug: recursively browsing `/usr/lib/vlc' [0x99b98a8] main libvlc debug: module bank initialized (392 modules) [0x99b98a8] main libvlc debug: opening config file (/home/maththias/.config/vlc/vlcrc) [0x99b98a8] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT FPU [0x99b98a8] main libvlc debug: looking for memcpy module: 3 candidates [0x99b98a8] main libvlc debug: using memcpy module "memcpymmxext" [0x9a6eb30] main input debug: Creating an input for 'Raccolta multimediale' [0x9a6eb30] main input debug: Input is a meta file: disabling unneeded options [0x9a6eb30] main input debug: using timeshift granularity of 50 MBytes [0x9a6eb30] main input debug: using timeshift path '/tmp' [0x9a6eb30] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/maththias/.local/share/vlc/ml.xspf' [0x9a6eb30] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9a74c70] main demux debug: looking for access_demux module: 1 candidate [0x9a74c70] main demux warning: no access_demux module matching "file" could be loaded [0x9a74c70] main demux debug: TIMER module_need() : 0,641 ms - Total 0,641 ms / 1 intvls (Avg 0,641 ms) [0x9a6eb30] main input debug: creating access 'file' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9a76d70] main access debug: looking for access module: 3 candidates [0x9a76d70] access_file access debug: opening file `/home/maththias/.local/share/vlc/ml.xspf' [0x9a76d70] main access debug: using access module "access_file" [0x9a76d70] main access debug: TIMER module_need() : 0,653 ms - Total 0,653 ms / 1 intvls (Avg 0,653 ms) [0x9a763d8] main stream debug: Using AStream*Stream [0x9a763d8] main stream debug: pre buffering [0x9a763d8] main stream debug: received first data after 0 ms [0x9a763d8] main stream debug: pre-buffering done 304 bytes in 0s - 5709 kbytes/s [0x9a77cf8] main stream debug: looking for stream_filter module: 4 candidates [0x9a77cf8] main stream debug: TIMER module_need() : 0,493 ms - Total 0,493 ms / 1 intvls (Avg 0,493 ms) [0x9a77cf8] main stream debug: looking for stream_filter module: 1 candidate [0x9a77cf8] main stream debug: using stream_filter module "stream_filter_record" [0x9a77cf8] main stream debug: TIMER module_need() : 0,235 ms - Total 0,235 ms / 1 intvls (Avg 0,235 ms) [0x9a6eb30] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9a78e30] main demux debug: looking for demux module: 1 candidate [0x9a78e30] playlist demux debug: using XSPF playlist reader [0x9a78e30] main demux debug: using demux module "playlist" [0x9a78e30] main demux debug: TIMER module_need() : 0,448 ms - Total 0,448 ms / 1 intvls (Avg 0,448 ms) [0x9a6eb30] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' successfully opened [0x9a7a238] main xml debug: looking for xml module: 2 candidates [0x9a7a238] main xml debug: using xml module "xml" [0x9a7a238] main xml debug: TIMER module_need() : 0,578 ms - Total 0,578 ms / 1 intvls (Avg 0,578 ms) [0x9a78e30] playlist demux debug: parsed 0 tracks successfully [0x9a7a238] main xml debug: removing module "xml" [0x9a6eb30] main input debug: EOF reached [0x9a78e30] main demux debug: removing module "playlist" [0x9a77cf8] main stream debug: removing module "stream_filter_record" [0x9a76d70] main access debug: removing module "access_file" [0x9a6eb30] main input debug: TIMER input launching for 'Raccolta multimediale' : 7,084 ms - Total 7,084 ms / 1 intvls (Avg 7,084 ms) [0x9a6db10] main playlist debug: rebuilding array of current - root Scaletta [0x9a6db10] main playlist debug: rebuild done - 0 items, index -1 [0x9a6db10] main playlist debug: Activated [0x9a763d8] main interface debug: looking for interface module: 1 candidate [0x9a763d8] main interface debug: using interface module "hotkeys" [0x9a763d8] main interface debug: TIMER module_need() : 0,454 ms - Total 0,454 ms / 1 intvls (Avg 0,454 ms) [0x9a763d8] main interface debug: thread started [0x9a763d8] main interface debug: thread (interface) created at priority 0 (interface/interface.c:160) [0x9a7c5b8] main interface debug: looking for interface module: 1 candidate [0x9a7c5b8] main interface debug: using interface module "inhibit" [0x9a7c5b8] main interface debug: TIMER module_need() : 3,581 ms - Total 3,581 ms / 1 intvls (Avg 3,581 ms) [0x9a7b648] main interface debug: looking for interface module: 1 candidate [0x9a7b648] main interface debug: using interface module "screensaver" [0x9a7b648] main interface debug: TIMER module_need() : 1,400 ms - Total 1,400 ms / 1 intvls (Avg 1,400 ms) [0x99b9a40] main interface debug: looking for interface module: 1 candidate [0x99b9a40] main interface debug: using interface module "signals" [0x99b9a40] main interface debug: TIMER module_need() : 0,348 ms - Total 0,348 ms / 1 intvls (Avg 0,348 ms) [0x9a73f18] main interface debug: looking for interface module: 1 candidate [0x9a73f18] main interface debug: using interface module "globalhotkeys" [0x9a73f18] main interface debug: TIMER module_need() : 15,700 ms - Total 15,700 ms / 1 intvls (Avg 15,700 ms) [0x99b98a8] main libvlc: Esecuzione di vlc con l'interfaccia predefinita. Usa 'cvlc' per utilizzare vlc senza interfaccia. [0x9a7ed08] main interface debug: looking for interface module: 4 candidates [0x99b98a8] main libvlc warning: Could not inherit value for var dialog-fatal from config. Invalid Type [0x99b98a8] main libvlc warning: Could not inherit value for var dialog-login from config. Invalid Type [0x99b98a8] main libvlc warning: Could not inherit value for var dialog-question from config. Invalid Type [0x99b98a8] main libvlc warning: Could not inherit value for var dialog-progress-bar from config. Invalid Type [0x9a7ed08] main interface debug: using interface module "qt4" [0x9a7ed08] main interface debug: TIMER module_need() : 305,589 ms - Total 305,589 ms / 1 intvls (Avg 305,589 ms) [0x9a7ed08] qt4 interface debug: Error while initializing qt-specific localization [0x9a6db10] main playlist debug: adding item `Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' ( /home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3 ) [0x9a6db10] main playlist debug: rebuilding array of current - root Scaletta [0x9a6db10] main playlist debug: rebuild done - 1 items, index -1 [0x9a6db10] main playlist debug: processing request item Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3 node null skip 0 [0x9a6db10] main playlist debug: resyncing on Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3 [0x9a6db10] main playlist debug: Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3 is at 0 [0x9a6db10] main playlist debug: starting new item [0x9a6db10] main playlist debug: creating new input thread [0x9da3d28] main input debug: Creating an input for 'Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' [0x9da3d28] main input debug: thread started [0x9da3d28] main input debug: using timeshift granularity of 50 MBytes [0x9da3d28] main input debug: using timeshift path '/tmp' [0x9a7ed08] qt4 interface debug: Adding a new MRL to recent ones: /home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3 [0x9da3d28] main input debug: thread (input) created at priority 10 (input/input.c:230) [0x9da3d28] main input debug: `/home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' gives access `' demux `' path `/home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' [0x9da3d28] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' [0x9dafee0] main demux debug: looking for access_demux module: 7 candidates [0x9dafee0] main demux debug: TIMER module_need() : 10,103 ms - Total 10,103 ms / 1 intvls (Avg 10,103 ms) [0x9da3d28] main input debug: creating access '' path='/home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' [0x9dd0b40] main access debug: looking for access module: 8 candidates [0x9dd0b40] vcd access debug: trying .cue file: /home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.cue [0x9dd0b40] vcd access debug: could not find .cue file [0x9dd0b40] access_file access debug: opening file `/home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' [0x9dd0b40] main access debug: using access module "access_file" [0x9dd0b40] main access debug: TIMER module_need() : 4,529 ms - Total 4,529 ms / 1 intvls (Avg 4,529 ms) [0x9db04e8] main stream debug: Using AStream*Stream [0x9db04e8] main stream debug: pre buffering [0x9db04e8] main stream debug: received first data after 0 ms [0x9db04e8] main stream debug: pre-buffering done 1024 bytes in 0s - 9900 kbytes/s [0x9dd1d98] main stream debug: looking for stream_filter module: 4 candidates [0x9dd1d98] main stream debug: TIMER module_need() : 0,294 ms - Total 0,294 ms / 1 intvls (Avg 0,294 ms) [0x9dd1d98] main stream debug: looking for stream_filter module: 1 candidate [0x9dd1d98] main stream debug: using stream_filter module "stream_filter_record" [0x9dd1d98] main stream debug: TIMER module_need() : 0,347 ms - Total 0,347 ms / 1 intvls (Avg 0,347 ms) [0x9da3d28] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' [0x9dceb70] main demux debug: ID3v2.3 revision 0 tag found, skipping 256 bytes [0x9dceb70] main demux debug: looking for demux module: 53 candidates [0x9a7ed08] qt4 interface debug: IM: Setting an input [0x9dceb70] es demux debug: detected format mpga [0x9d9e328] main decoder debug: looking for packetizer module: 21 candidates [0x9a7ed08] qt4 interface debug: Updating the geometry [0x9d9e328] main decoder debug: using packetizer module "mpeg_audio" [0x9d9e328] main decoder debug: TIMER module_need() : 15,898 ms - Total 15,898 ms / 1 intvls (Avg 15,898 ms) [0x9dceb70] main demux debug: using demux module "es" [0x9dceb70] main demux debug: TIMER module_need() : 28,069 ms - Total 28,069 ms / 1 intvls (Avg 28,069 ms) [0x9da3d28] main input debug: looking for a subtitle file in /home/maththias/Mussik/Presa/ [0x9dceb70] main demux debug: looking for meta reader module: 1 candidate [0x9dceb70] main demux debug: using meta reader module "taglib" [0x9dceb70] main demux debug: TIMER module_need() : 1,720 ms - Total 1,720 ms / 1 intvls (Avg 1,720 ms) [0x9dceb70] main demux debug: removing module "taglib" [0x9da3d28] main input debug: `/home/maththias/Mussik/Presa/Anonimo X Secolo - Musica Sacra Della Chiesa Bizantina.mp3' successfully opened [0x9d9e328] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:256 [0x9da3d28] main input debug: selecting program id=0 [0x9ddb930] main decoder debug: looking for decoder module: 31 candidates [0x9ddb930] main decoder debug: using decoder module "mpeg_audio" [0x9ddb930] main decoder debug: TIMER module_need() : 3,845 ms - Total 3,845 ms / 1 intvls (Avg 3,845 ms) [0x9ddb930] main decoder debug: thread started [0x9ddb930] main decoder debug: thread (decoder) created at priority 5 (input/decoder.c:302) [0x9da3d28] main input debug: Buffering 0% [0x9da3d28] main input debug: Buffering 8% [0x9ddb930] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:256 [0x9da3d28] main input debug: creating aout [0x9de7940] main audio output debug: looking for audio output module: 6 candidates [0x9de7940] pulse audio output: No. of Audio Channels: 2 [0x9da3d28] main input debug: Buffering 17% [0x9da3d28] main input debug: Buffering 26% [0x9da3d28] main input debug: Buffering 34% [0x9da3d28] main input debug: Buffering 43% [0x9da3d28] main input debug: Buffering 52% [0x9da3d28] main input debug: Buffering 60% [0x9da3d28] main input debug: Buffering 69% [0x9da3d28] main input debug: Buffering 78% [0x9da3d28] main input debug: Buffering 87% [0x9da3d28] main input debug: Buffering 95% [0x9da3d28] main input debug: Stream buffering done (313 ms in 5 ms) [0x9a7ed08] qt4 interface debug: Updating the geometry [0x9de7940] pulse audio output debug: Pulse mainloop started [0x9de7940] pulse audio output debug: Pulse stream connected [0x9de7940] pulse audio output debug: Pulse initialized successfully [0x9de7940] pulse audio output debug: Buffer metrics: maxlength=141120, tlength=44456, prebuf=37408, minreq=7056 [0x9de7940] pulse audio output debug: Using sample spec 'float32le ch 2 44100 Hz', channel map 'front-left,front-right'. [0x9de7940] pulse audio output debug: Connected to device alsa_output.pci_8086_27d8_sound_card_0 (0, not suspended). [0x9de7940] main audio output debug: using audio output module "pulse" [0x9de7940] main audio output debug: TIMER module_need() : 99,703 ms - Total 99,703 ms / 1 intvls (Avg 99,703 ms) [0x9de7940] main audio output debug: output 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9de7940] main audio output debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9de7940] main audio output debug: no need for any filter [0x9de7940] main audio output debug: looking for audio mixer module: 3 candidates [0x9de7940] main audio output debug: using audio mixer module "float32_mixer" [0x9de7940] main audio output debug: TIMER module_need() : 0,498 ms - Total 0,498 ms / 1 intvls (Avg 0,498 ms) [0x9de7940] main audio output debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes [0x9dec3e0] main audio filter debug: looking for audio filter module: 1 candidate [0x9dec3e0] scaletempo audio filter warning: bad input or output format [0x9dec3e0] main audio filter warning: no audio filter module matching "scaletempo" could be loaded [0x9dec3e0] main audio filter debug: TIMER module_need() : 0,407 ms - Total 0,407 ms / 1 intvls (Avg 0,407 ms) [0x9dec3e0] main audio filter debug: looking for audio filter module: 1 candidate [0x9dec3e0] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32 [0x9dec3e0] scaletempo audio filter debug: params: 30 stride, 0,200 overlap, 14 search [0x9dec3e0] scaletempo audio filter debug: 1,000 scale, 1323,000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode [0x9dec3e0] main audio filter debug: using audio filter module "scaletempo" [0x9dec3e0] main audio filter debug: TIMER module_need() : 0,528 ms - Total 0,528 ms / 1 intvls (Avg 0,528 ms) [0x9de7940] main audio output debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo [0x9df2f38] main audio output debug: looking for audio filter module: 24 candidates [0x9df2f38] main audio output debug: using audio filter module "mpgatofixed32" [0x9df2f38] main audio output debug: TIMER module_need() : 1,945 ms - Total 1,945 ms / 1 intvls (Avg 1,945 ms) [0x9de7940] main audio output debug: found a filter for the whole conversion [0x9de7940] main audio output debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo [0x9dfd9d0] main audio output debug: looking for audio filter module: 24 candidates [0x9dfd9d0] main audio output debug: using audio filter module "bandlimited_resampler" [0x9dfd9d0] main audio output debug: TIMER module_need() : 0,864 ms - Total 0,864 ms / 1 intvls (Avg 0,864 ms) [0x9de7940] main audio output debug: found a filter for the whole conversion [0x9ddb930] main decoder debug: End of audio preroll [0x9da3d28] main input debug: Decoder buffering done in 100 ms [0x9de7940] main audio output warning: PTS is out of range (-6465), dropping buffer [0x9de7940] main audio output warning: PTS is out of range (-32521), dropping buffer [0x9de7940] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x9de7940] pulse audio output debug: Pulse stream started [0x9de7940] main audio output debug: audio output is too slow (195298), trashing 20000us [0x9de7940] main audio output debug: audio output is too slow (175365), trashing 20000us [0x9de7940] main audio output debug: audio output is too slow (155395), trashing 20000us [0x9de7940] main audio output debug: audio output is too slow (135423), trashing 20000us [0x9de7940] main audio output debug: audio output is too slow (115450), trashing 20000us [0x9de7940] main audio output debug: audio output is too slow (95477), trashing 20000us [0x9de7940] main audio output debug: audio output is too slow (75504), trashing 20000us [0x9de7940] main audio output debug: audio output is too slow (55530), trashing 20000us [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (119496) [0x9de7940] main audio output warning: buffer is 119495 late, triggering upsampling [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (54839) [0x9de7940] main audio output warning: audio drift is too big (174129), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (148007), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (121885), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (47158) [0x9de7940] main audio output warning: audio drift is too big (142920), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (54117) [0x9de7940] main audio output warning: audio drift is too big (170892), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (144770), dropping buffer [0x9de7940] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (49054) [0x9de7940] main audio output warning: audio drift is too big (167701), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (60192) [0x9de7940] main audio output warning: audio drift is too big (201770), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (175648), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (42770) [0x9de7940] main audio output warning: audio drift is too big (192296), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (49257) [0x9de7940] main audio output warning: audio drift is too big (215430), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (189308), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (42731) [0x9de7940] main audio output warning: audio drift is too big (205916), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (179794), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (153671), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (127549), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (177038) [0x9de7940] main audio output warning: audio drift is too big (278442), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (252319), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (226197), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (200075), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (173952), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (147830), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (121707), dropping buffer [0x9de7940] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (449603) [0x9de7940] main audio output warning: audio drift is too big (545165), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (519043), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (492920), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (466798), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (440676), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (414553), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (388431), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (362308), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (336186), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (310063), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (283941), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (257818), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (231696), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (205574), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (179451), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (153329), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (127206), dropping buffer [0x9de7940] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (895921) [0x9de7940] main audio output warning: audio drift is too big (996982), dropping buffer [0x9de7940] main audio output warning: output date isn't PTS date, requesting resampling (60289) [0x9de7940] main audio output warning: audio drift is too big (1031148), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (1005026), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (978903), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (952781), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (926659), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (900536), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (874414), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (848291), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (822169), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (796046), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (769924), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (743801), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (717679), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (691557), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (665434), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (639312), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (613189), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (587067), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (560944), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (534822), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (508699), dropping buffer [0x9de7940] main audio output warning: audio drift is too big (482577), dropping buffer [0x99c63f8] main audio output warning: audio drift is too big (126705), dropping buffer [0x99c63f8] mpgatofixed32 audio output debug: libmad error: Huffman data overrun [0x99c63f8] main audio output debug: audio output is starving (25482), playing silence [0x99c63f8] main audio output debug: audio output is starving (20330), playing silence [0x99c63f8] main audio output debug: audio output is starving (20529), playing silence [0x99c63f8] main audio output debug: audio output is starving (23142), playing silence [0x99c63f8] main audio output debug: audio output is starving (22448), playing silence [0x99c63f8] main audio output debug: audio output is starving (23682), playing silence [0x99c63f8] main audio output debug: audio output is starving (20885), playing silence [0x99c63f8] main audio output debug: audio output is starving (26755), playing silence [0x99c63f8] main audio output debug: audio output is starving (20062), playing silence [0x99c63f8] main audio output debug: audio output is starving (24687), playing silence [0x99c63f8] main audio output debug: audio output is starving (22244), playing silence [0x99c63f8] main audio output debug: audio output is starving (20382), playing silence [0x99c63f8] main audio output debug: audio output is starving (20644), playing silence [0x9a6db10] main playlist debug: incoming request - stopping current input [0x9a6db10] main playlist debug: dying input [0x9da3d28] main input debug: control type=0 [0x9da3d28] main input debug: control: stopping input [0x9ddb930] main decoder debug: removing module "mpeg_audio" [0x9ddb930] main decoder debug: killing decoder fourcc `mpga', 0 PES in FIFO [0x9df2f38] main audio output debug: removing module "mpgatofixed32" [0x9dec3e0] main audio filter debug: removing module "scaletempo" [0x9dfd9d0] main audio output debug: removing module "bandlimited_resampler" [0x9de7940] pulse audio output debug: Pulse Close [0x9a6db10] main playlist debug: dying input [0x9de7940] main audio output debug: removing module "pulse" [0x9de7940] main audio output debug: removing module "float32_mixer" [0x9da3d28] main input debug: releasing aout [0x9d9e328] main decoder debug: removing module "mpeg_audio" [0x9dceb70] main demux debug: removing module "es" [0x9dd1d98] main stream debug: removing module "stream_filter_record" [0x9dd0b40] main access debug: removing module "access_file" [0x9da3d28] main input debug: Program doesn't contain anymore ES [0x9da3d28] main input debug: thread ended [0x9a6db10] main playlist debug: dead input [0x9a7ed08] qt4 interface debug: IM: Deleting the input [0x9a7ed08] qt4 interface debug: Updating the geometry [0x9a7ed08] qt4 interface debug: Updating the geometry

[Snjezana]

I have the same problem but without a solution.
I upgraded vlc from 0.9.9a to 1.0.0-rc2 and the sound over PulseAudio is gone. With 0.9.9a it worked.
I also tried vlc-beta-1.1.0-2009060501.1 but with no success.

Addendum:
vlc 1.0.0-rc2 connects to server, it is shown as a client in the volume control application of PulseAudio, but there is no sound, the volume meter don't show any amplitude as it did with vlc 0.9.9a.

[Maththias]

i'm glad to know that I'm not the only one:

Now with 1.0.0-rc3 still no sound at all.

vlc -vvv

Code: Select all

vlc -vvv VLC media player 1.1.0-git Goldeneye [0x998d8a8] main libvlc debug: VLC media player - version 1.1.0-git Goldeneye - (c) 1996-2009 the VideoLAN team [0x998d8a8] main libvlc debug: libvlc was configured with ./configure '--mandir=${prefix}/share/man' '--infodir=${prefix}/share/info' '--build=i486-linux-gnu' '--enable-maintainer-mode' '--enable-release' '--prefix=/usr' '--enable-fast-install' '--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=libxul-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' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--enable-faad' '--disable-zvbi' '--enable-telx' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-realrtsp' '--disable-dv' '--enable-x264' '--enable-alsa' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-svgalib' '--enable-gnomevfs' 'build_alias=i486-linux-gnu' 'CFLAGS=-Wall -g -O2' [0x998d8a8] main libvlc debug: translation test: code is "C" [0x998d8a8] main libvlc debug: checking plugin modules [0x998d8a8] main libvlc debug: loading plugins cache file /home/maththias/.cache/vlc/plugins-04041e.dat [0x998d8a8] main libvlc debug: recursively browsing `/usr/lib/vlc' [0x998d8a8] main libvlc debug: module bank initialized (397 modules) [0x998d8a8] main libvlc debug: opening config file (/home/maththias/.config/vlc/vlcrc) [0x998d8a8] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT FPU [0x998d8a8] main libvlc debug: looking for memcpy module: 3 candidates [0x998d8a8] main libvlc debug: using memcpy module "memcpymmxext" [0x9a43a78] main input debug: Creating an input for 'Raccolta multimediale' [0x9a43a78] main input debug: Input is a meta file: disabling unneeded options [0x9a43a78] main input debug: using timeshift granularity of 50 MBytes [0x9a43a78] main input debug: using timeshift path '/tmp' [0x9a43a78] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/maththias/.local/share/vlc/ml.xspf' [0x9a43a78] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9a49c30] main demux debug: looking for access_demux module: 1 candidate [0x9a49c30] main demux warning: no access_demux module matching "file" could be loaded [0x9a49c30] main demux debug: TIMER module_need() : 0,642 ms - Total 0,642 ms / 1 intvls (Avg 0,642 ms) [0x9a43a78] main input debug: creating access 'file' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9a4bd58] main access debug: looking for access module: 3 candidates [0x9a4bd58] access_file access debug: opening file `/home/maththias/.local/share/vlc/ml.xspf' [0x9a4bd58] main access debug: using access module "access_file" [0x9a4bd58] main access debug: TIMER module_need() : 0,647 ms - Total 0,647 ms / 1 intvls (Avg 0,647 ms) [0x9a4ae28] main stream debug: Using AStream*Stream [0x9a4ae28] main stream debug: pre buffering [0x9a4ae28] main stream debug: received first data after 0 ms [0x9a4ae28] main stream debug: pre-buffering done 304 bytes in 0s - 5937 kbytes/s [0x9a4cce0] main stream debug: looking for stream_filter module: 4 candidates [0x9a4cce0] main stream debug: TIMER module_need() : 0,489 ms - Total 0,489 ms / 1 intvls (Avg 0,489 ms) [0x9a4cce0] main stream debug: looking for stream_filter module: 1 candidate [0x9a4cce0] main stream debug: using stream_filter module "stream_filter_record" [0x9a4cce0] main stream debug: TIMER module_need() : 0,231 ms - Total 0,231 ms / 1 intvls (Avg 0,231 ms) [0x9a43a78] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9a4dde8] main demux debug: looking for demux module: 1 candidate [0x9a4dde8] playlist demux debug: using XSPF playlist reader [0x9a4dde8] main demux debug: using demux module "playlist" [0x9a4dde8] main demux debug: TIMER module_need() : 0,457 ms - Total 0,457 ms / 1 intvls (Avg 0,457 ms) [0x9a43a78] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' successfully opened [0x9a4f218] main xml debug: looking for xml module: 2 candidates [0x9a4f218] main xml debug: using xml module "xml" [0x9a4f218] main xml debug: TIMER module_need() : 4,558 ms - Total 4,558 ms / 1 intvls (Avg 4,558 ms) [0x9a4dde8] playlist demux debug: parsed 0 tracks successfully [0x9a4f218] main xml debug: removing module "xml" [0x9a43a78] main input debug: EOF reached [0x9a4dde8] main demux debug: removing module "playlist" [0x9a4cce0] main stream debug: removing module "stream_filter_record" [0x9a4bd58] main access debug: removing module "access_file" [0x9a43a78] main input debug: TIMER input launching for 'Raccolta multimediale' : 7,100 ms - Total 7,100 ms / 1 intvls (Avg 7,100 ms) [0x9a38680] main playlist debug: rebuilding array of current - root Scaletta [0x9a38680] main playlist debug: rebuild done - 0 items, index -1 [0x9a38680] main playlist debug: Activated [0x9a4f218] main interface debug: looking for interface module: 1 candidate [0x9a4f218] main interface debug: using interface module "hotkeys" [0x9a4f218] main interface debug: TIMER module_need() : 0,446 ms - Total 0,446 ms / 1 intvls (Avg 0,446 ms) [0x9a4f218] main interface debug: thread started [0x9a4f218] main interface debug: thread (interface) created at priority 0 (interface/interface.c:160) [0x9a51b50] main interface debug: looking for interface module: 1 candidate [0x9a51b50] main interface debug: using interface module "inhibit" [0x9a51b50] main interface debug: TIMER module_need() : 4,435 ms - Total 4,435 ms / 1 intvls (Avg 4,435 ms) [0x9a49ae8] main interface debug: looking for interface module: 1 candidate [0x9a49ae8] main interface debug: using interface module "screensaver" [0x9a49ae8] main interface debug: TIMER module_need() : 0,462 ms - Total 0,462 ms / 1 intvls (Avg 0,462 ms) [0x9a4cf68] main interface debug: looking for interface module: 1 candidate [0x9a4cf68] main interface debug: using interface module "signals" [0x9a4cf68] main interface debug: TIMER module_need() : 0,295 ms - Total 0,295 ms / 1 intvls (Avg 0,295 ms) [0x9a48718] main interface debug: looking for interface module: 1 candidate [0x9a48718] main interface debug: using interface module "globalhotkeys" [0x9a48718] main interface debug: TIMER module_need() : 13,398 ms - Total 13,398 ms / 1 intvls (Avg 13,398 ms) [0x998d8a8] main libvlc: Esecuzione di vlc con l'interfaccia predefinita. Usa 'cvlc' per utilizzare vlc senza interfaccia. [0x9a53c50] main interface debug: looking for interface module: 4 candidates [0x998d8a8] main libvlc warning: Could not inherit value for var dialog-fatal from config. Invalid Type [0x998d8a8] main libvlc warning: Could not inherit value for var dialog-login from config. Invalid Type [0x998d8a8] main libvlc warning: Could not inherit value for var dialog-question from config. Invalid Type [0x998d8a8] main libvlc warning: Could not inherit value for var dialog-progress-bar from config. Invalid Type [0x9a53c50] main interface debug: using interface module "qt4" [0x9a53c50] main interface debug: TIMER module_need() : 317,370 ms - Total 317,370 ms / 1 intvls (Avg 317,370 ms) [0x9a53c50] qt4 interface debug: Error while initializing qt-specific localization [0x9a38680] main playlist debug: adding item `Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' ( /home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3 ) [0x9a53c50] qt4 interface debug: Adding a new MRL to recent ones: /home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3 [0x9a38680] main playlist debug: rebuilding array of current - root Scaletta [0x9a38680] main playlist debug: rebuild done - 1 items, index -1 [0x9a38680] main playlist debug: processing request item Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3 node null skip 0 [0x9a38680] main playlist debug: resyncing on Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3 [0x9a38680] main playlist debug: Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3 is at 0 [0x9a38680] main playlist debug: starting new item [0x9a38680] main playlist debug: creating new input thread [0x9d7a5f8] main input debug: Creating an input for 'Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' [0x9d7a5f8] main input debug: thread started [0x9d7a5f8] main input debug: using timeshift granularity of 50 MBytes [0x9d7a5f8] main input debug: using timeshift path '/tmp' [0x9d7a5f8] main input debug: thread (input) created at priority 10 (input/input.c:230) [0x9d7a5f8] main input debug: `/home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' gives access `' demux `' path `/home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' [0x9d7a5f8] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' [0x9d7f418] main demux debug: looking for access_demux module: 7 candidates [0x9a53c50] qt4 interface debug: IM: Setting an input [0x9a53c50] qt4 interface debug: Updating the geometry [0x9d7f418] main demux debug: TIMER module_need() : 24,928 ms - Total 24,928 ms / 1 intvls (Avg 24,928 ms) [0x9d7a5f8] main input debug: creating access '' path='/home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' [0x9da1ea8] main access debug: looking for access module: 8 candidates [0x9da1ea8] vcd access debug: trying .cue file: /home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.cue [0x9da1ea8] vcd access debug: could not find .cue file [0x9da1ea8] access_file access debug: opening file `/home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' [0x9da1ea8] main access debug: using access module "access_file" [0x9da1ea8] main access debug: TIMER module_need() : 12,783 ms - Total 12,783 ms / 1 intvls (Avg 12,783 ms) [0x9da4870] main stream debug: Using AStream*Stream [0x9da4870] main stream debug: pre buffering [0x9da4870] main stream debug: received first data after 11 ms [0x9da4870] main stream debug: pre-buffering done 1024 bytes in 0s - 88 kbytes/s [0x9da46a0] main stream debug: looking for stream_filter module: 4 candidates [0x9da46a0] main stream debug: TIMER module_need() : 0,204 ms - Total 0,204 ms / 1 intvls (Avg 0,204 ms) [0x9da46a0] main stream debug: looking for stream_filter module: 1 candidate [0x9da46a0] main stream debug: using stream_filter module "stream_filter_record" [0x9da46a0] main stream debug: TIMER module_need() : 0,157 ms - Total 0,157 ms / 1 intvls (Avg 0,157 ms) [0x9d7a5f8] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' [0x9da4fb8] main demux debug: ID3v2.3 revision 0 tag found, skipping 543 bytes [0x9da4fb8] main demux debug: looking for demux module: 53 candidates [0x9da4fb8] es demux debug: detected format mpga [0x9da76f8] main decoder debug: looking for packetizer module: 21 candidates [0x9a53c50] qt4 interface debug: Updating the geometry [0x9da76f8] main decoder debug: using packetizer module "mpeg_audio" [0x9da76f8] main decoder debug: TIMER module_need() : 9,998 ms - Total 9,998 ms / 1 intvls (Avg 9,998 ms) [0x9da4fb8] main demux debug: using demux module "es" [0x9da4fb8] main demux debug: TIMER module_need() : 11,381 ms - Total 11,381 ms / 1 intvls (Avg 11,381 ms) [0x9d7a5f8] main input debug: looking for a subtitle file in /home/maththias/Mussik/Nuova/ [0x9da4fb8] main demux debug: looking for meta reader module: 1 candidate [0x9da4fb8] main demux debug: using meta reader module "taglib" [0x9da4fb8] main demux debug: TIMER module_need() : 2,088 ms - Total 2,088 ms / 1 intvls (Avg 2,088 ms) [0x9da4fb8] main demux debug: removing module "taglib" [0x9d7a5f8] main input debug: `/home/maththias/Mussik/Nuova/Canti estatici Hildegard von Bingen - 06 Canto della Sibilla.mp3' successfully opened [0x9da76f8] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:192 [0x9d7a5f8] main input debug: selecting program id=0 [0x9da92e8] main decoder debug: looking for decoder module: 31 candidates [0x9da92e8] main decoder debug: using decoder module "mpeg_audio" [0x9da92e8] main decoder debug: TIMER module_need() : 26,566 ms - Total 26,566 ms / 1 intvls (Avg 26,566 ms) [0x9da92e8] main decoder debug: thread (decoder) created at priority 5 (input/decoder.c:302) [0x9da92e8] main decoder debug: thread started [0x9d7a5f8] main input debug: Buffering 0% [0x9d7a5f8] main input debug: Buffering 8% [0x9d7a5f8] main input debug: Buffering 17% [0x9d7a5f8] main input debug: Buffering 26% [0x9d7a5f8] main input debug: Buffering 34% [0x9d7a5f8] main input debug: Buffering 43% [0x9d7a5f8] main input debug: Buffering 52% [0x9d7a5f8] main input debug: Buffering 60% [0x9d7a5f8] main input debug: Buffering 69% [0x9d7a5f8] main input debug: Buffering 78% [0x9d7a5f8] main input debug: Buffering 87% [0x9d7a5f8] main input debug: Buffering 95% [0x9d7a5f8] main input debug: Stream buffering done (313 ms in 13 ms) [0x9da92e8] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:192 [0x9d7a5f8] main input debug: creating aout [0x9daeef0] main audio output debug: looking for audio output module: 6 candidates [0x9daeef0] pulse audio output: No. of Audio Channels: 2 [0x9a53c50] qt4 interface debug: Updating the geometry [0x9a53c50] qt4 interface debug: Updating the geometry [0x9a53c50] qt4 interface debug: Updating the geometry [0x9a53c50] qt4 interface debug: Updating the geometry [0x9a53c50] qt4 interface debug: Updating the geometry [0x9a53c50] qt4 interface debug: Updating the geometry [0x9a53c50] qt4 interface debug: Updating the geometry [0x9a53c50] qt4 interface debug: New caching: 100 [0x9a53c50] qt4 interface debug: New caching: 100 [0x9daeef0] pulse audio output debug: Pulse mainloop started [0x9daeef0] pulse audio output debug: Pulse stream connected [0x9daeef0] pulse audio output debug: Pulse initialized successfully [0x9daeef0] pulse audio output debug: Buffer metrics: maxlength=141120, tlength=42336, prebuf=35288, minreq=7056 [0x9daeef0] pulse audio output debug: Using sample spec 'float32le ch 2 44100 Hz', channel map 'front-left,front-right'. [0x9daeef0] pulse audio output debug: Connected to device alsa_output.pci_8086_27d8_sound_card_0 (0, not suspended). [0x9daeef0] main audio output debug: using audio output module "pulse" [0x9daeef0] main audio output debug: TIMER module_need() : 110,651 ms - Total 110,651 ms / 1 intvls (Avg 110,651 ms) [0x9daeef0] main audio output debug: output 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9daeef0] main audio output debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9daeef0] main audio output debug: no need for any filter [0x9daeef0] main audio output debug: looking for audio mixer module: 3 candidates [0x9daeef0] main audio output debug: using audio mixer module "float32_mixer" [0x9daeef0] main audio output debug: TIMER module_need() : 0,452 ms - Total 0,452 ms / 1 intvls (Avg 0,452 ms) [0x9daeef0] main audio output debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes [0x9dbd0f8] main audio filter debug: looking for audio filter module: 1 candidate [0x9dbd0f8] scaletempo audio filter warning: bad input or output format [0x9dbd0f8] main audio filter warning: no audio filter module matching "scaletempo" could be loaded [0x9dbd0f8] main audio filter debug: TIMER module_need() : 0,642 ms - Total 0,642 ms / 1 intvls (Avg 0,642 ms) [0x9dbd0f8] main audio filter debug: looking for audio filter module: 1 candidate [0x9dbd0f8] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32 [0x9dbd0f8] scaletempo audio filter debug: params: 30 stride, 0,200 overlap, 14 search [0x9dbd0f8] scaletempo audio filter debug: 1,000 scale, 1323,000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode [0x9dbd0f8] main audio filter debug: using audio filter module "scaletempo" [0x9dbd0f8] main audio filter debug: TIMER module_need() : 0,585 ms - Total 0,585 ms / 1 intvls (Avg 0,585 ms) [0x9daeef0] main audio output debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo [0x9dbd4a8] main audio output debug: looking for audio filter module: 24 candidates [0x9dbd4a8] main audio output debug: using audio filter module "mpgatofixed32" [0x9dbd4a8] main audio output debug: TIMER module_need() : 0,771 ms - Total 0,771 ms / 1 intvls (Avg 0,771 ms) [0x9daeef0] main audio output debug: found a filter for the whole conversion [0x9daeef0] main audio output debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo [0x9dc5518] main audio output debug: looking for audio filter module: 24 candidates [0x9dc5518] main audio output debug: using audio filter module "bandlimited_resampler" [0x9dc5518] main audio output debug: TIMER module_need() : 0,724 ms - Total 0,724 ms / 1 intvls (Avg 0,724 ms) [0x9daeef0] main audio output debug: found a filter for the whole conversion [0x9da92e8] main decoder debug: End of audio preroll [0x9d7a5f8] main input debug: Decoder buffering done in 122 ms [0x9daeef0] main audio output warning: PTS is out of range (-7233), dropping buffer [0x9daeef0] main audio output warning: PTS is out of range (-33283), dropping buffer [0x9daeef0] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x9daeef0] pulse audio output debug: Pulse stream started

And if I downgrade to git version of my last post... pulseaudio is broken also there.

Hope someone help us...

[kwizart]

For each try, please:
1- remove the cache plugins:
$ rm ~/.vlc/cache/*
2- Explicitely select the vlc pulseaudio output, then close vlc
3- kill the pulseaudio server and restart it will verbose log:
$ pulseaudio -k ; pulseaudio -vvvv
4- then run vlc with vlc -vvvv

Explantations:
vlc use the alsa output by default. This output allows vlc to hear sound from the PA server (using the alsa-plugin-pulseaudio is a little better), but the sound still crackles.
Instead, vlc have it owns native PA audio output. That's this output that currently doesn't work because of a "yet to be discovered" regression.

My POV is that reverting git commit b8f23ea716693d8d07dd8bd0cb4c9ba8ed05f568 fix the behaviour, but that doesn't explain what the problem is...

[Maththias]

Thanks kwizart!

pulseaudio -vvvv

Code: Select all

D: main.c: Started as real root: no, suid root: yes I: main.c: Attualmente nel gruppo "pulse-rt", che consente scheduling high-priority. I: main.c: setrlimit(RLIMIT_NICE, (31, 31)) non riuscita: Operation not permitted I: main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) non riuscita: Operation not permitted I: core-util.c: Successfully gained nice level -11. D: main.c: Can realtime: yes, can high-priority: yes I: main.c: Abbandono del CAP_NICE D: main.c: Can realtime: no, can high-priority: no I: main.c: Questo è PulseAudio 0.9.15 D: main.c: Host di compilazione: i486-pc-linux-gnu D: main.c: CFLAGS di compilazione: -g -O2 -g -Wall -O2 -Wall -W -Wextra -pipe -Wno-long-long -Winline -Wvla -Wno-overlength-strings -Wunsafe-loop-optimizations -Wundef -Wformat=2 -Wlogical-op -Wsign-compare -Wformat-security -Wmissing-include-dirs -Wformat-nonliteral -Wold-style-definition -Wpointer-arith -Winit-self -Wdeclaration-after-statement -Wfloat-equal -Wmissing-prototypes -Wstrict-prototypes -Wredundant-decls -Wmissing-declarations -Wmissing-noreturn -Wshadow -Wendif-labels -Wcast-align -Wstrict-aliasing=2 -Wwrite-strings -Wno-unused-parameter -ffast-math -Wp,-D_FORTIFY_SOURCE=2 -fno-common -fdiagnostics-show-option D: main.c: In esecuzione sull'host: Linux i686 2.6.29-2-686 #1 SMP Sun May 17 17:56:29 UTC 2009 D: main.c: Trovate 1 CPU. I: main.c: La dimensione di pagina è 4096 byte D: main.c: Compilato con supporto a Valgrind: no D: main.c: In esecuzione in modalità valgrind: no D: main.c: Build ottimizzata: sì D: main.c: All asserts enabled. I: main.c: L'ID della macchina è 8f238b7da18f7de326baca00484fc8eb I: main.c: Session ID is 8f238b7da18f7de326baca00484fc8eb-1244468837.983980-1980261696. I: main.c: In uso directory di runtime /home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:runtime. I: main.c: In uso directory di stato /home/maththias/.pulse. I: main.c: In esecuzione in modalità sistema: no I: main.c: Disponibili timer high-resolution freschi freschi! Buon appetito! D: rtclock.c: Timer slack is set to 50 us. D: memblock.c: Using shared memory pool with 1024 slots of size 64,0 KiB each, total size is 64,0 MiB, maximum usable slot size is 65496 I: module-device-restore.c: Sucessfully opened database file '/home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:device-volumes.i486-pc-linux-gnu.gdbm'. I: module.c: Loaded "module-device-restore" (index: #0; argument: ""). I: module-stream-restore.c: Sucessfully opened database file '/home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:stream-volumes.i486-pc-linux-gnu.gdbm'. I: module.c: Loaded "module-stream-restore" (index: #1; argument: ""). I: module-card-restore.c: Sucessfully opened database file '/home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:card-database.i486-pc-linux-gnu.gdbm'. I: module.c: Loaded "module-card-restore" (index: #2; argument: ""). I: module.c: Loaded "module-augment-properties" (index: #3; argument: ""). D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-hal-detect.so': success D: dbus-util.c: Successfully connected to D-Bus system bus a31abbc75cccfbd84a5f66664a2d1651 as :1.41 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_timer D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_sequencer D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_playback_0 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_capture_0 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_hw_specific_1 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_hw_specific_0 D: module-hal-detect.c: Loading module-alsa-card with arguments 'device_id=0 name=pci_8086_27d8_sound_card_0 card_name=alsa_card.pci_8086_27d8_sound_card_0 tsched=1' D: dbus-util.c: Successfully connected to D-Bus session bus 9c9e16dba80d97aa7398eb2d4a2d1667 as :1.99 D: reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0' D: alsa-util.c: Checking for playback on analog-mono (hw) D: alsa-util.c: Trying hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying hw:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:hw:0: Invalid argument D: alsa-util.c: Checking for playback on analog-stereo (front) D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. D: alsa-util.c: Checking for capture on analog-mono (hw) D: alsa-util.c: Trying hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying hw:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:hw:0: Invalid argument D: alsa-util.c: Checking for capture on analog-stereo (front) D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. I: module-alsa-card.c: Found output profile 'Output Analog Stereo + Input Stereo analogico' D: alsa-util.c: Checking for capture on iec958-stereo (iec958) D: alsa-util.c: Trying iec958:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D1c failed I: alsa-util.c: Error opening PCM device iec958:0: No such file or directory D: alsa-util.c: Checking for capture on hdmi-stereo (hdmi) D: alsa-util.c: Trying hdmi:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D3c failed I: alsa-util.c: Error opening PCM device hdmi:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-40 (surround40) D: alsa-util.c: Trying surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround40:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround40:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-40 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-41 (surround41) D: alsa-util.c: Trying surround41:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround41:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround41:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround41:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround41:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-50 (surround50) D: alsa-util.c: Trying surround50:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround50:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround50:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround50:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround50:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-51 (surround51) D: alsa-util.c: Trying surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround51:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround51:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-51 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-71 (surround71) D: alsa-util.c: Trying surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround71:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround71:0: Invalid argument I: module-alsa-card.c: Found output profile 'Output Stereo analogico' D: alsa-util.c: Checking for playback on iec958-stereo (iec958) D: alsa-util.c: Trying iec958:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D1p failed I: alsa-util.c: Error opening PCM device iec958:0: No such file or directory D: alsa-util.c: Checking for playback on hdmi-stereo (hdmi) D: alsa-util.c: Trying hdmi:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D3p failed I: alsa-util.c: Error opening PCM device hdmi:0: No such file or directory D: alsa-util.c: Checking for playback on analog-surround-40 (surround40) D: alsa-util.c: Trying surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround40:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround40:0: Invalid argument D: alsa-util.c: Checking for playback on iec958-ac3-surround-40 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for playback on analog-surround-41 (surround41) D: alsa-util.c: Trying surround41:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround41:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround41:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround41:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround41:0: Invalid argument D: alsa-util.c: Checking for playback on analog-surround-50 (surround50) D: alsa-util.c: Trying surround50:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround50:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround50:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround50:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround50:0: Invalid argument D: alsa-util.c: Checking for playback on analog-surround-51 (surround51) D: alsa-util.c: Trying surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround51:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround51:0: Invalid argument D: alsa-util.c: Checking for playback on iec958-ac3-surround-51 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for playback on analog-surround-71 (surround71) D: alsa-util.c: Trying surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround71:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround71:0: Invalid argument D: alsa-util.c: Checking for capture on analog-mono (hw) D: alsa-util.c: Trying hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying hw:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:hw:0: Invalid argument D: alsa-util.c: Checking for capture on analog-stereo (front) D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. I: module-alsa-card.c: Found output profile 'Input Stereo analogico' D: alsa-util.c: Checking for capture on iec958-stereo (iec958) D: alsa-util.c: Trying iec958:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D1c failed I: alsa-util.c: Error opening PCM device iec958:0: No such file or directory D: alsa-util.c: Checking for capture on hdmi-stereo (hdmi) D: alsa-util.c: Trying hdmi:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D3c failed I: alsa-util.c: Error opening PCM device hdmi:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-40 (surround40) D: alsa-util.c: Trying surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround40:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround40:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-40 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-41 (surround41) D: alsa-util.c: Trying surround41:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround41:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround41:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround41:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround41:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-50 (surround50) D: alsa-util.c: Trying surround50:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround50:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround50:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround50:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround50:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-51 (surround51) D: alsa-util.c: Trying surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround51:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround51:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-51 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-71 (surround71) D: alsa-util.c: Trying surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround71:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround71:0: Invalid argument I: module-card-restore.c: Restoring profile for card alsa_card.pci_8086_27d8_sound_card_0. I: card.c: Created 0 "alsa_card.pci_8086_27d8_sound_card_0" D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Maximum hw buffer size is 341 ms I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. I: alsa-sink.c: Successfully opened device front:0. I: alsa-sink.c: Selected configuration 'Analog Stereo' (analog-stereo). I: alsa-sink.c: Successfully enabled mmap() mode. I: alsa-sink.c: Successfully enabled timer-based scheduling mode. I: (alsa-lib)control.c: Invalid CTL front:0 I: alsa-util.c: Unable to attach to mixer front:0: No such file or directory I: alsa-util.c: Successfully attached to mixer 'hw:0' I: alsa-util.c: Cannot find mixer control "Master" or mixer control is no combination of switch/volume. I: alsa-util.c: Cannot find fallback mixer control "PCM" or mixer control is no combination of switch/volume. I: alsa-util.c: Using mixer control "PCM". I: module-device-restore.c: Restoring volume for sink alsa_output.pci_8086_27d8_sound_card_0. I: module-device-restore.c: Restoring mute state for sink alsa_output.pci_8086_27d8_sound_card_0. I: sink.c: Created sink 0 "alsa_output.pci_8086_27d8_sound_card_0" with sample spec s16le ch 2 48000 Hz and channel map front-left,front-right I: sink.c: alsa.resolution_bits = "16" I: sink.c: alsa.mixer_element = "PCM" I: sink.c: device.api = "alsa" I: sink.c: device.class = "sound" I: sink.c: alsa.class = "generic" I: sink.c: alsa.subclass = "generic-mix" I: sink.c: alsa.name = "ALC861 Analog" I: sink.c: alsa.id = "ALC861 Analog" I: sink.c: alsa.subdevice = "0" I: sink.c: alsa.subdevice_name = "subdevice #0" I: sink.c: alsa.device = "0" I: sink.c: alsa.card = "0" I: sink.c: alsa.card_name = "HDA Intel" I: sink.c: alsa.long_card_name = "HDA Intel at 0xdc440000 irq 22" I: sink.c: alsa.driver_name = "snd_hda_intel" I: sink.c: hal.udi = "/org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0" I: sink.c: hal.product = "HDA Intel Sound Card" I: sink.c: hal.card_id = "HDA Intel" I: sink.c: device.string = "front:0" I: sink.c: device.buffering.buffer_size = "65536" I: sink.c: device.buffering.fragment_size = "32768" I: sink.c: device.access_mode = "mmap+timer" I: sink.c: device.profile.name = "analog-stereo" I: sink.c: device.profile.description = "Analog Stereo" I: sink.c: device.description = "HDA Intel" I: sink.c: device.icon_name = "audio-card" I: module-device-restore.c: Restoring volume for source alsa_output.pci_8086_27d8_sound_card_0.monitor. I: module-device-restore.c: Restoring mute state for source alsa_output.pci_8086_27d8_sound_card_0.monitor. I: source.c: Created source 0 "alsa_output.pci_8086_27d8_sound_card_0.monitor" with sample spec s16le ch 2 48000 Hz and channel map front-left,front-right I: source.c: device.description = "Monitor of HDA Intel" I: source.c: device.class = "monitor" I: source.c: alsa.card = "0" I: source.c: alsa.card_name = "HDA Intel" I: source.c: alsa.long_card_name = "HDA Intel at 0xdc440000 irq 22" I: source.c: alsa.driver_name = "snd_hda_intel" I: source.c: hal.udi = "/org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0" I: source.c: hal.product = "HDA Intel Sound Card" I: source.c: hal.card_id = "HDA Intel" I: source.c: device.string = "0" I: source.c: device.icon_name = "audio-card" I: alsa-sink.c: Using 2 fragments of size 32768 bytes, buffer time is 341,33ms I: alsa-sink.c: Time scheduling watermark is 20,00ms D: alsa-sink.c: Latency set to 250,00ms D: alsa-sink.c: hwbuf_unused=17536 D: alsa-sink.c: setting avail_min=15425 I: alsa-sink.c: Volume ranges from 0 to 255. I: alsa-sink.c: Volume ranges from -51,00 dB to 0,00 dB. I: alsa-sink.c: No particular base volume set, fixing to 0 dB I: alsa-util.c: All 2 channels can be mapped to mixer channels. I: alsa-sink.c: Using hardware volume control. Hardware dB scale supported. I: alsa-sink.c: Using software mute control. D: alsa-util.c: snd_pcm_dump(): D: alsa-util.c: Soft volume PCM D: alsa-util.c: Control: PCM Playback Volume D: alsa-util.c: min_dB: -51 D: alsa-util.c: max_dB: 0 D: alsa-util.c: resolution: 256 D: alsa-util.c: Its setup is: D: alsa-util.c: stream : PLAYBACK D: alsa-util.c: access : MMAP_INTERLEAVED D: alsa-util.c: format : S16_LE D: alsa-util.c: subformat : STD D: alsa-util.c: channels : 2 D: alsa-util.c: rate : 48000 D: alsa-util.c: exact rate : 48000 (48000/1) D: alsa-util.c: msbits : 16 D: alsa-util.c: buffer_size : 16384 D: alsa-util.c: period_size : 8192 D: alsa-util.c: period_time : 170666 D: alsa-util.c: tstamp_mode : ENABLE D: alsa-util.c: period_step : 1 D: alsa-util.c: avail_min : 15425 D: alsa-util.c: period_event : 0 D: alsa-util.c: start_threshold : -1 D: alsa-util.c: stop_threshold : 1073741824 D: alsa-util.c: silence_threshold: 0 D: alsa-util.c: silence_size : 0 D: alsa-util.c: boundary : 1073741824 D: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0 D: alsa-util.c: Its setup is: D: alsa-util.c: stream : PLAYBACK D: alsa-util.c: access : MMAP_INTERLEAVED D: alsa-util.c: format : S16_LE D: alsa-util.c: subformat : STD D: alsa-util.c: channels : 2 D: alsa-util.c: rate : 48000 D: alsa-util.c: exact rate : 48000 (48000/1) D: alsa-util.c: msbits : 16 D: alsa-util.c: buffer_size : 16384 D: alsa-util.c: period_size : 8192 D: alsa-util.c: period_time : 170666 D: alsa-util.c: tstamp_mode : ENABLE D: alsa-util.c: period_step : 1 D: alsa-util.c: avail_min : 15425 D: alsa-util.c: period_event : 0 D: alsa-util.c: start_threshold : -1 D: alsa-util.c: stop_threshold : 1073741824 D: alsa-util.c: silence_threshold: 0 D: alsa-util.c: silence_size : 0 D: alsa-util.c: boundary : 1073741824 D: alsa-util.c: appl_ptr : 0 D: alsa-util.c: hw_ptr : 0 D: alsa-sink.c: Thread starting up D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29 D: alsa-sink.c: Requested volume: 0: 99% 1: 99% D: alsa-sink.c: Got hardware volume: 0: 99% 1: 99% D: alsa-sink.c: Calculated software volume: 0: 99% 1: 99% I: alsa-sink.c: Starting playback. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. I: module.c: Loaded "module-alsa-card" (index: #4; argument: "device_id=0 name=pci_8086_27d8_sound_card_0 card_name=alsa_card.pci_8086_27d8_sound_card_0 tsched=1"). D: module-hal-detect.c: Loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_control__1 I: module-hal-detect.c: Loaded 1 modules. I: module.c: Loaded "module-hal-detect" (index: #5; argument: ""). D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-bluetooth-discover.so': failure D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-esound-protocol-unix.so': success I: module.c: Loaded "module-esound-protocol-unix" (index: #6; argument: "socket=/tmp/.esd/socket"). I: module.c: Loaded "module-native-protocol-unix" (index: #7; argument: ""). D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-gconf.so': success I: module.c: Loaded "module-gconf" (index: #8; argument: ""). I: module-default-device-restore.c: Restored default sink 'alsa_output.pci_8086_27d8_sound_card_0'. D: core-subscribe.c: Dropped redundant event due to change event. I: module-default-device-restore.c: Restored default source 'alsa_output.pci_8086_27d8_sound_card_0.monitor'. I: module.c: Loaded "module-default-device-restore" (index: #9; argument: ""). I: module.c: Loaded "module-rescue-streams" (index: #10; argument: ""). I: module.c: Loaded "module-always-sink" (index: #11; argument: ""). D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. I: module.c: Loaded "module-suspend-on-idle" (index: #12; argument: ""). I: client.c: Created 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session1" D: module-console-kit.c: Added new session /org/freedesktop/ConsoleKit/Session1 I: module.c: Loaded "module-console-kit" (index: #13; argument: ""). I: module.c: Loaded "module-position-event-sounds" (index: #14; argument: ""). I: module.c: Loaded "module-cork-music-on-phone" (index: #15; argument: ""). D: main.c: Got org.pulseaudio.Server! I: main.c: Completato l'avvio del demone. D: module-hal-detect.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired D: module-console-kit.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired D: module-hal-detect.c: dbus: interface=org.freedesktop.Hal.Device, path=/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT1, member=PropertyModified D: module-console-kit.c: dbus: interface=org.freedesktop.Hal.Device, path=/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT1, member=PropertyModified D: module-hal-detect.c: dbus: interface=org.freedesktop.Hal.Device, path=/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT1, member=PropertyModified D: module-console-kit.c: dbus: interface=org.freedesktop.Hal.Device, path=/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT1, member=PropertyModified I: client.c: Created 1 "Native client (UNIX socket client)" D: protocol-native.c: Protocol version: remote 15, local 15 I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 D: protocol-native.c: SHM possible: yes D: protocol-native.c: Negotiated SHM: yes D: module-augment-properties.c: Looking for .desktop file for vlc I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:Lettore multimediale VLC. I: module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:Lettore multimediale VLC. D: reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0' I: alsa-sink.c: Trying resume... D: alsa-util.c: Maximum hw buffer size is 341 ms D: alsa-sink.c: Latency set to 250,00ms D: alsa-sink.c: hwbuf_unused=17536 D: alsa-sink.c: setting avail_min=15425 I: alsa-sink.c: Resumed successfully... I: alsa-sink.c: Starting playback. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes busy. I: resampler.c: Using resampler 'speex-float-3' I: resampler.c: Using float32le as working format. I: resampler.c: Choosing speex quality setting 3. D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: sink-input.c: Created input 0 "audio stream" on alsa_output.pci_8086_27d8_sound_card_0 with sample spec float32le ch 2 44100 Hz and channel map front-left,front-right I: protocol-native.c: Requested tlength=200,00 ms, minreq=20,00 ms D: protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: memblockq.c: memblockq requested: maxlength=141120, tlength=42336, base=8, prebuf=35288, minreq=7056 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=141120, tlength=42336, base=8, prebuf=35288, minreq=7056 maxrewind=0 I: protocol-native.c: Final latency 200,00 ms = 80,00 ms + 2*20,00 ms + 80,00 ms D: alsa-sink.c: Requested volume: 0: 99% 1: 99% D: alsa-sink.c: Got hardware volume: 0: 99% 1: 99% D: alsa-sink.c: Calculated software volume: 0: 99% 1: 99% D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Latency set to 80,00ms D: alsa-sink.c: hwbuf_unused=50176 D: alsa-sink.c: setting avail_min=15425 D: alsa-sink.c: Requesting rewind due to latency change. D: alsa-sink.c: Requested to rewind 65536 bytes. D: alsa-sink.c: Limited to 43840 bytes. D: alsa-sink.c: before: 10960 D: alsa-sink.c: after: 10960 D: alsa-sink.c: Rewound 43840 bytes. D: sink.c: Processing rewind... D: sink-input.c: Have to rewind 43840 bytes on render memblockq. D: source.c: Processing rewind... D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: core-subscribe.c: Dropped redundant event due to change event. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: protocol-native.c: Requesting rewind due to end of underrun. D: protocol-native.c: Underrun on 'audio stream', 0 bytes in queue. D: alsa-sink.c: Requested volume: 0: 99% 1: 99% D: alsa-sink.c: Got hardware volume: 0: 99% 1: 99% D: alsa-sink.c: Calculated software volume: 0: 99% 1: 99% D: alsa-sink.c: Wakeup from ALSA! D: alsa-sink.c: hwbuf_unused=0 D: alsa-sink.c: setting avail_min=15424 D: alsa-sink.c: Wakeup from ALSA! D: alsa-sink.c: Requested to rewind 65536 bytes. D: alsa-sink.c: Limited to 11488 bytes. D: alsa-sink.c: before: 2872 D: alsa-sink.c: after: 2872 D: alsa-sink.c: Rewound 11488 bytes. D: sink.c: Processing rewind... D: source.c: Processing rewind... D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: core.c: Hmm, no streams around, trying to vacuum. I: sink-input.c: Freeing input 0 "audio stream" I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 idle for too long, suspending ... I: alsa-sink.c: Device suspended...

and vlc -vvvv (from git a2e763951e8aa0e90ddadc034ab12dcad946f36a)

Code: Select all

VLC media player 1.1.0-git Goldeneye [0x99f78a8] main libvlc debug: VLC media player - version 1.1.0-git Goldeneye - (c) 1996-2009 the VideoLAN team [0x99f78a8] main libvlc debug: libvlc was configured with ./configure '--mandir=${prefix}/share/man' '--infodir=${prefix}/share/info' '--build=i486-linux-gnu' '--enable-maintainer-mode' '--enable-release' '--prefix=/usr' '--enable-fast-install' '--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=libxul-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' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--enable-faad' '--disable-zvbi' '--enable-telx' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-realrtsp' '--disable-dv' '--enable-x264' '--enable-alsa' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-svgalib' '--enable-gnomevfs' 'build_alias=i486-linux-gnu' 'CFLAGS=-Wall -g -O2' [0x99f78a8] main libvlc debug: translation test: code is "C" [0x99f78a8] main libvlc debug: checking plugin modules [0x99f78a8] main libvlc debug: loading plugins cache file /home/maththias/.cache/vlc/plugins-04041e.dat [0x99f78a8] main libvlc warning: could not open plugins cache file /home/maththias/.cache/vlc/plugins-04041e.dat for reading [0x99f78a8] main libvlc debug: recursively browsing `/usr/lib/vlc' [0x99f78a8] main libvlc debug: module bank initialized (397 modules) [0x99f78a8] main libvlc debug: opening config file (/home/maththias/.config/vlc/vlcrc) [0x99f78a8] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT FPU [0x99f78a8] main libvlc debug: looking for memcpy module: 3 candidates [0x99f78a8] main libvlc debug: using memcpy module "memcpymmxext" [0x9b9bfe8] main input debug: Creating an input for 'Raccolta multimediale' [0x9b9bfe8] main input debug: Input is a meta file: disabling unneeded options [0x9b9bfe8] main input debug: using timeshift granularity of 50 MBytes [0x9b9bfe8] main input debug: using timeshift path '/tmp' [0x9b9bfe8] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/maththias/.local/share/vlc/ml.xspf' [0x9b9bfe8] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9ba1ee8] main demux debug: looking for access_demux module: 1 candidate [0x9ba1ee8] main demux warning: no access_demux module matching "file" could be loaded [0x9ba1ee8] main demux debug: TIMER module_need() : 0,208 ms - Total 0,208 ms / 1 intvls (Avg 0,208 ms) [0x9b9bfe8] main input debug: creating access 'file' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9ba1ee8] main access debug: looking for access module: 3 candidates [0x9ba1ee8] access_file access debug: opening file `/home/maththias/.local/share/vlc/ml.xspf' [0x9ba1ee8] main access debug: using access module "access_file" [0x9ba1ee8] main access debug: TIMER module_need() : 0,329 ms - Total 0,329 ms / 1 intvls (Avg 0,329 ms) [0x9ba26f0] main stream debug: Using AStream*Stream [0x9ba26f0] main stream debug: pre buffering [0x9ba26f0] main stream debug: received first data after 0 ms [0x9ba26f0] main stream debug: pre-buffering done 304 bytes in 0s - 5709 kbytes/s [0x9ba2ff0] main stream debug: looking for stream_filter module: 4 candidates [0x9ba2ff0] main stream debug: TIMER module_need() : 0,116 ms - Total 0,116 ms / 1 intvls (Avg 0,116 ms) [0x9ba2ff0] main stream debug: looking for stream_filter module: 1 candidate [0x9ba2ff0] main stream debug: using stream_filter module "stream_filter_record" [0x9ba2ff0] main stream debug: TIMER module_need() : 0,140 ms - Total 0,140 ms / 1 intvls (Avg 0,140 ms) [0x9b9bfe8] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9ba3788] main demux debug: looking for demux module: 1 candidate [0x9ba3788] playlist demux debug: using XSPF playlist reader [0x9ba3788] main demux debug: using demux module "playlist" [0x9ba3788] main demux debug: TIMER module_need() : 0,192 ms - Total 0,192 ms / 1 intvls (Avg 0,192 ms) [0x9b9bfe8] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' successfully opened [0x9b9fad0] main xml debug: looking for xml module: 2 candidates [0x9b9fad0] main xml debug: using xml module "xml" [0x9b9fad0] main xml debug: TIMER module_need() : 0,220 ms - Total 0,220 ms / 1 intvls (Avg 0,220 ms) [0x9ba3788] playlist demux debug: parsed 0 tracks successfully [0x9b9fad0] main xml debug: removing module "xml" [0x9b9bfe8] main input debug: EOF reached [0x9ba3788] main demux debug: removing module "playlist" [0x9ba2ff0] main stream debug: removing module "stream_filter_record" [0x9ba1ee8] main access debug: removing module "access_file" [0x9b9bfe8] main input debug: TIMER input launching for 'Raccolta multimediale' : 6,259 ms - Total 6,259 ms / 1 intvls (Avg 6,259 ms) [0x9a179b0] main playlist debug: rebuilding array of current - root Scaletta [0x9a179b0] main playlist debug: rebuild done - 0 items, index -1 [0x9a179b0] main playlist debug: Activated [0x9b9bfe8] main interface debug: looking for interface module: 1 candidate [0x9b9bfe8] main interface debug: using interface module "hotkeys" [0x9b9bfe8] main interface debug: TIMER module_need() : 0,199 ms - Total 0,199 ms / 1 intvls (Avg 0,199 ms) [0x9b9bfe8] main interface debug: thread started [0x9b9bfe8] main interface debug: thread (interface) created at priority 0 (interface/interface.c:160) [0x9ba35e8] main interface debug: looking for interface module: 1 candidate [0x9ba35e8] main interface debug: using interface module "inhibit" [0x9ba35e8] main interface debug: TIMER module_need() : 14,579 ms - Total 14,579 ms / 1 intvls (Avg 14,579 ms) [0x9b9ee90] main interface debug: looking for interface module: 1 candidate [0x9b9ee90] main interface debug: using interface module "screensaver" [0x9b9ee90] main interface debug: TIMER module_need() : 0,425 ms - Total 0,425 ms / 1 intvls (Avg 0,425 ms) [0x9ba1c70] main interface debug: looking for interface module: 1 candidate [0x9ba1c70] main interface debug: using interface module "signals" [0x9ba1c70] main interface debug: TIMER module_need() : 0,196 ms - Total 0,196 ms / 1 intvls (Avg 0,196 ms) [0x9ba4100] main interface debug: looking for interface module: 1 candidate [0x9ba4100] main interface debug: using interface module "globalhotkeys" [0x9ba4100] main interface debug: TIMER module_need() : 30,618 ms - Total 30,618 ms / 1 intvls (Avg 30,618 ms) [0x99f78a8] main libvlc: Esecuzione di vlc con l'interfaccia predefinita. Usa 'cvlc' per utilizzare vlc senza interfaccia. [0x9ba4b80] main interface debug: looking for interface module: 4 candidates [0x99f78a8] main libvlc warning: Could not inherit value for var dialog-fatal from config. Invalid Type [0x99f78a8] main libvlc warning: Could not inherit value for var dialog-login from config. Invalid Type [0x99f78a8] main libvlc warning: Could not inherit value for var dialog-question from config. Invalid Type [0x99f78a8] main libvlc warning: Could not inherit value for var dialog-progress-bar from config. Invalid Type [0x9ba4b80] main interface debug: using interface module "qt4" [0x9ba4b80] main interface debug: TIMER module_need() : 390,815 ms - Total 390,815 ms / 1 intvls (Avg 390,815 ms) [0x9ba4b80] qt4 interface debug: Error while initializing qt-specific localization [0x9a179b0] main playlist debug: adding item `01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' ( /home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3 ) [0x9ba4b80] qt4 interface debug: Adding a new MRL to recent ones: /home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3 [0x9a179b0] main playlist debug: rebuilding array of current - root Scaletta [0x9a179b0] main playlist debug: rebuild done - 1 items, index -1 [0x9a179b0] main playlist debug: processing request item 01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3 node null skip 0 [0x9a179b0] main playlist debug: resyncing on 01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3 [0x9a179b0] main playlist debug: 01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3 is at 0 [0x9a179b0] main playlist debug: starting new item [0x9a179b0] main playlist debug: creating new input thread [0x9ba6990] main input debug: Creating an input for '01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' [0x9ba6990] main input debug: thread started [0x9ba6990] main input debug: using timeshift granularity of 50 MBytes [0x9ba6990] main input debug: using timeshift path '/tmp' [0x9ba6990] main input debug: thread (input) created at priority 10 (input/input.c:230) [0x9ba4b80] qt4 interface debug: IM: Setting an input [0x9ba4b80] qt4 interface debug: Updating the geometry [0x9ba6990] main input debug: `/home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' gives access `' demux `' path `/home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' [0x9ba6990] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' [0x9ebe4f8] main demux debug: looking for access_demux module: 7 candidates [0x9ebe4f8] main demux debug: TIMER module_need() : 0,477 ms - Total 0,477 ms / 1 intvls (Avg 0,477 ms) [0x9ba6990] main input debug: creating access '' path='/home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' [0x9ebe058] main access debug: looking for access module: 8 candidates [0x9ebe058] vcd access debug: trying .cue file: /home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.cue [0x9ebe058] vcd access debug: could not find .cue file [0x9ebe058] access_file access debug: opening file `/home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' [0x9ebe058] main access debug: using access module "access_file" [0x9ebe058] main access debug: TIMER module_need() : 2,197 ms - Total 2,197 ms / 1 intvls (Avg 2,197 ms) [0x9ebe360] main stream debug: Using AStream*Stream [0x9ebe360] main stream debug: pre buffering [0x9ebe360] main stream debug: received first data after 0 ms [0x9ebe360] main stream debug: pre-buffering done 1024 bytes in 0s - 9523 kbytes/s [0x9ed1b50] main stream debug: looking for stream_filter module: 4 candidates [0x9ed1b50] main stream debug: TIMER module_need() : 0,296 ms - Total 0,296 ms / 1 intvls (Avg 0,296 ms) [0x9ed1b50] main stream debug: looking for stream_filter module: 1 candidate [0x9ed1b50] main stream debug: using stream_filter module "stream_filter_record" [0x9ed1b50] main stream debug: TIMER module_need() : 0,360 ms - Total 0,360 ms / 1 intvls (Avg 0,360 ms) [0x9ba6990] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' [0x9ed22f8] main demux debug: ID3v2.3 revision 0 tag found, skipping 1296 bytes [0x9ed22f8] main demux debug: looking for demux module: 53 candidates [0x9ed22f8] es demux debug: detected format mpga [0x9ed2b58] main decoder debug: looking for packetizer module: 21 candidates [0x9ed2b58] main decoder debug: using packetizer module "mpeg_audio" [0x9ed2b58] main decoder debug: TIMER module_need() : 0,401 ms - Total 0,401 ms / 1 intvls (Avg 0,401 ms) [0x9ed22f8] main demux debug: using demux module "es" [0x9ed22f8] main demux debug: TIMER module_need() : 1,099 ms - Total 1,099 ms / 1 intvls (Avg 1,099 ms) [0x9ba6990] main input debug: looking for a subtitle file in /home/maththias/Mussik/Presa/ [0x9ed22f8] main demux debug: looking for meta reader module: 1 candidate [0x9ed22f8] main demux debug: using meta reader module "taglib" [0x9ed22f8] main demux debug: TIMER module_need() : 12,529 ms - Total 12,529 ms / 1 intvls (Avg 12,529 ms) [0x9ed22f8] main demux debug: removing module "taglib" [0x9ba6990] main input debug: `/home/maththias/Mussik/Presa/01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' successfully opened [0x9ed22f8] es demux debug: did not sync on first block [0x9ed2b58] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:320 [0x9ba6990] main input debug: selecting program id=0 [0x9ed38f0] main decoder debug: looking for decoder module: 31 candidates [0x9ed38f0] main decoder debug: using decoder module "mpeg_audio" [0x9ed38f0] main decoder debug: TIMER module_need() : 0,949 ms - Total 0,949 ms / 1 intvls (Avg 0,949 ms) [0x9ed38f0] main decoder debug: thread started [0x9ed38f0] main decoder debug: thread (decoder) created at priority 5 (input/decoder.c:302) [0x9ba6990] main input debug: Buffering 0% [0x9ba6990] main input debug: Buffering 8% [0x9ba6990] main input debug: Buffering 17% [0x9ba6990] main input debug: Buffering 26% [0x9ba6990] main input debug: Buffering 34% [0x9ba6990] main input debug: Buffering 43% [0x9ba6990] main input debug: Buffering 52% [0x9ba6990] main input debug: Buffering 60% [0x9ba6990] main input debug: Buffering 69% [0x9ba6990] main input debug: Buffering 78% [0x9ba6990] main input debug: Buffering 87% [0x9ba6990] main input debug: Buffering 95% [0x9ba6990] main input debug: Stream buffering done (313 ms in 14 ms) [0x9ed38f0] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:320 [0x9ba6990] main input debug: creating aout [0x9ecdd40] main audio output debug: looking for audio output module: 6 candidates [0x9ecdd40] pulse audio output: No. of Audio Channels: 2 [0x9ba4b80] qt4 interface debug: Updating the geometry [0x9ecdd40] pulse audio output debug: Pulse mainloop started [0x9ecdd40] pulse audio output debug: Pulse stream connected [0x9ecdd40] pulse audio output debug: Pulse initialized successfully [0x9ecdd40] pulse audio output debug: Buffer metrics: maxlength=141120, tlength=42336, prebuf=35288, minreq=7056 [0x9ecdd40] pulse audio output debug: Using sample spec 'float32le ch 2 44100 Hz', channel map 'front-left,front-right'. [0x9ecdd40] pulse audio output debug: Connected to device alsa_output.pci_8086_27d8_sound_card_0 (0, not suspended). [0x9ecdd40] main audio output debug: using audio output module "pulse" [0x9ecdd40] main audio output debug: TIMER module_need() : 133,997 ms - Total 133,997 ms / 1 intvls (Avg 133,997 ms) [0x9ecdd40] main audio output debug: output 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9ecdd40] main audio output debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x9ecdd40] main audio output debug: no need for any filter [0x9ecdd40] main audio output debug: looking for audio mixer module: 3 candidates [0x9ecdd40] main audio output debug: using audio mixer module "float32_mixer" [0x9ecdd40] main audio output debug: TIMER module_need() : 0,138 ms - Total 0,138 ms / 1 intvls (Avg 0,138 ms) [0x9ecdd40] main audio output debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes [0x9eeaf40] main audio filter debug: looking for audio filter module: 1 candidate [0x9eeaf40] scaletempo audio filter warning: bad input or output format [0x9eeaf40] main audio filter warning: no audio filter module matching "scaletempo" could be loaded [0x9eeaf40] main audio filter debug: TIMER module_need() : 0,126 ms - Total 0,126 ms / 1 intvls (Avg 0,126 ms) [0x9eeaf40] main audio filter debug: looking for audio filter module: 1 candidate [0x9eeaf40] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32 [0x9eeaf40] scaletempo audio filter debug: params: 30 stride, 0,200 overlap, 14 search [0x9eeaf40] scaletempo audio filter debug: 1,000 scale, 1323,000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode [0x9eeaf40] main audio filter debug: using audio filter module "scaletempo" [0x9eeaf40] main audio filter debug: TIMER module_need() : 0,380 ms - Total 0,380 ms / 1 intvls (Avg 0,380 ms) [0x9ecdd40] main audio output debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo [0x9ef06f0] main audio output debug: looking for audio filter module: 24 candidates [0x9ef06f0] main audio output debug: using audio filter module "mpgatofixed32" [0x9ef06f0] main audio output debug: TIMER module_need() : 0,122 ms - Total 0,122 ms / 1 intvls (Avg 0,122 ms) [0x9ecdd40] main audio output debug: found a filter for the whole conversion [0x9ecdd40] main audio output debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo [0x9eeb050] main audio output debug: looking for audio filter module: 24 candidates [0x9eeb050] main audio output debug: using audio filter module "bandlimited_resampler" [0x9eeb050] main audio output debug: TIMER module_need() : 0,204 ms - Total 0,204 ms / 1 intvls (Avg 0,204 ms) [0x9ecdd40] main audio output debug: found a filter for the whole conversion [0x9ed38f0] main decoder debug: End of audio preroll [0x9ba6990] main input debug: Decoder buffering done in 138 ms [0x9ecdd40] main audio output warning: PTS is out of range (-8713), dropping buffer [0x9ecdd40] main audio output warning: PTS is out of range (-34812), dropping buffer [0x9ecdd40] pulse audio output debug: Pulse stream started [0x99f78a8] main libvlc debug: deactivating the playlist [0x9a179b0] main playlist debug: Deactivate [0x9a179b0] main playlist debug: incoming request - stopping current input [0x9a179b0] main playlist debug: dying input [0x9ba6990] main input debug: control type=0 [0x9ba6990] main input debug: control: stopping input [0x9a179b0] main playlist debug: dying input [0x9ed38f0] main decoder debug: removing module "mpeg_audio" [0x9ed38f0] main decoder debug: killing decoder fourcc `mpga', 0 PES in FIFO [0x9ef06f0] main audio output debug: removing module "mpgatofixed32" [0x9eeaf40] main audio filter debug: removing module "scaletempo" [0x9eeb050] main audio output debug: removing module "bandlimited_resampler" [0x9ecdd40] pulse audio output debug: Pulse Close [0x9ecdd40] main audio output debug: removing module "pulse" [0x9ecdd40] main audio output debug: removing module "float32_mixer" [0x9ba6990] main input debug: releasing aout [0x9ed2b58] main decoder debug: removing module "mpeg_audio" [0x9ed22f8] main demux debug: removing module "es" [0x9ed1b50] main stream debug: removing module "stream_filter_record" [0x9ebe058] main access debug: removing module "access_file" [0x9ba6990] main input debug: Program doesn't contain anymore ES [0x9ba6990] main input debug: thread ended [0x9a179b0] main playlist debug: dead input [0x9a179b0] main playlist debug: saving Raccolta multimediale to file /home/maththias/.local/share/vlc/ml.xspf [0x9a179b0] main playlist debug: looking for playlist export module: 1 candidate [0x9a179b0] main playlist debug: using playlist export module "export" [0x9a179b0] main playlist debug: TIMER module_need() : 0,337 ms - Total 0,337 ms / 1 intvls (Avg 0,337 ms) [0x9a179b0] main playlist debug: removing module "export" [0x9a179b0] main playlist debug: Deactivated [0x99f78a8] main libvlc debug: removing all services discovery tasks [0x99f78a8] main libvlc debug: removing all interfaces [0x9ba4b80] qt4 interface debug: IM: Deleting the input [0x9ba4b80] qt4 interface debug: Updating the geometry [0x9ba4b80] qt4 interface debug: Updating the geometry [0x9ba4b80] qt4 interface debug: Quitting the Qt4 Interface [0x9ba4b80] qt4 interface debug: destroying the main Qt4 interface [0x9ba4b80] qt4 interface debug: Destroying the main interface [0x9ba4b80] main interface debug: removing module "qt4" [0x9ba4100] main interface debug: removing module "globalhotkeys" [0x9ba1c70] main interface debug: removing module "signals" [0x9b9ee90] main interface debug: removing module "screensaver" [0x9ba6990] main input debug: TIMER input launching for '01 - Franco Battiato - Fleurs 2 - Tutto l' Universo Obbedisce all' Amore.mp3' : 40,913 ms - Total 40,913 ms / 1 intvls (Avg 40,913 ms) [0x9ba35e8] main interface debug: removing module "inhibit" [0x9b9bfe8] main interface debug: removing module "hotkeys" [0x99f78a8] main libvlc debug: removing playlist [0x9a179b0] main playlist debug: Destroyed [0x99f78a8] main libvlc debug: TIMER ML Load : Total 7,145 ms / 1 intvls (Avg 7,145 ms) [0x99f78a8] main libvlc debug: TIMER Items array build : Total 0,162 ms / 2 intvls (Avg 0,081 ms) [0x99f78a8] main libvlc debug: TIMER ML Dump : Total 0,655 ms / 1 intvls (Avg 0,655 ms) [0x99f78a8] main libvlc debug: removing stats [0x99f78a8] main libvlc debug: removing module "memcpymmxext" [0x99f78a8] main libvlc debug: writing plugins cache /home/maththias/.cache/vlc/plugins-04041e.dat

Still no sound...
This evening I try to build git b8f23ea716693d8d07dd8bd0cb4c9ba8ed05f568 and I will report here my impressions.

[kwizart]

Does it works once you have reverted with :
git revert b8f23ea716693d8d07dd8bd0cb4c9ba8ed05f568

[Maththias]

Next time I will revert, right.

but now I've build git b8f23ea716693d8d07dd8bd0cb4c9ba8ed05f568 (tar.gz from repo.or.cz) and still no sound:

pulseaudio -vvvv

Code: Select all

D: main.c: Started as real root: no, suid root: yes I: main.c: Attualmente nel gruppo "pulse-rt", che consente scheduling high-priority. I: main.c: setrlimit(RLIMIT_NICE, (31, 31)) non riuscita: Operation not permitted I: main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) non riuscita: Operation not permitted I: core-util.c: Successfully gained nice level -11. D: main.c: Can realtime: yes, can high-priority: yes I: main.c: Abbandono del CAP_NICE D: main.c: Can realtime: no, can high-priority: no I: main.c: Questo è PulseAudio 0.9.15 D: main.c: Host di compilazione: i486-pc-linux-gnu D: main.c: CFLAGS di compilazione: -g -O2 -g -Wall -O2 -Wall -W -Wextra -pipe -Wno-long-long -Winline -Wvla -Wno-overlength-strings -Wunsafe-loop-optimizations -Wundef -Wformat=2 -Wlogical-op -Wsign-compare -Wformat-security -Wmissing-include-dirs -Wformat-nonliteral -Wold-style-definition -Wpointer-arith -Winit-self -Wdeclaration-after-statement -Wfloat-equal -Wmissing-prototypes -Wstrict-prototypes -Wredundant-decls -Wmissing-declarations -Wmissing-noreturn -Wshadow -Wendif-labels -Wcast-align -Wstrict-aliasing=2 -Wwrite-strings -Wno-unused-parameter -ffast-math -Wp,-D_FORTIFY_SOURCE=2 -fno-common -fdiagnostics-show-option D: main.c: In esecuzione sull'host: Linux i686 2.6.29-2-686 #1 SMP Sun May 17 17:56:29 UTC 2009 D: main.c: Trovate 1 CPU. I: main.c: La dimensione di pagina è 4096 byte D: main.c: Compilato con supporto a Valgrind: no D: main.c: In esecuzione in modalità valgrind: no D: main.c: Build ottimizzata: sì D: main.c: All asserts enabled. I: main.c: L'ID della macchina è 8f238b7da18f7de326baca00484fc8eb I: main.c: Session ID is 8f238b7da18f7de326baca00484fc8eb-1244541955.214253-1319776856. I: main.c: In uso directory di runtime /home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:runtime. I: main.c: In uso directory di stato /home/maththias/.pulse. I: main.c: In esecuzione in modalità sistema: no I: main.c: Disponibili timer high-resolution freschi freschi! Buon appetito! D: rtclock.c: Timer slack is set to 50 us. D: memblock.c: Using shared memory pool with 1024 slots of size 64,0 KiB each, total size is 64,0 MiB, maximum usable slot size is 65496 I: module-device-restore.c: Sucessfully opened database file '/home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:device-volumes.i486-pc-linux-gnu.gdbm'. I: module.c: Loaded "module-device-restore" (index: #0; argument: ""). I: module-stream-restore.c: Sucessfully opened database file '/home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:stream-volumes.i486-pc-linux-gnu.gdbm'. I: module.c: Loaded "module-stream-restore" (index: #1; argument: ""). I: module-card-restore.c: Sucessfully opened database file '/home/maththias/.pulse/8f238b7da18f7de326baca00484fc8eb:card-database.i486-pc-linux-gnu.gdbm'. I: module.c: Loaded "module-card-restore" (index: #2; argument: ""). I: module.c: Loaded "module-augment-properties" (index: #3; argument: ""). D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-hal-detect.so': success D: dbus-util.c: Successfully connected to D-Bus system bus d745dd4d2757c936af25e9ab4a2e33e2 as :1.31 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_timer D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_sequencer D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_playback_0 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_capture_0 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_hw_specific_1 D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_hw_specific_0 D: module-hal-detect.c: Loading module-alsa-card with arguments 'device_id=0 name=pci_8086_27d8_sound_card_0 card_name=alsa_card.pci_8086_27d8_sound_card_0 tsched=1' D: dbus-util.c: Successfully connected to D-Bus session bus e94118417dccdfcccb78410c4a2e3404 as :1.47 D: reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0' D: alsa-util.c: Checking for playback on analog-mono (hw) D: alsa-util.c: Trying hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying hw:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:hw:0: Invalid argument D: alsa-util.c: Checking for playback on analog-stereo (front) D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. D: alsa-util.c: Checking for capture on analog-mono (hw) D: alsa-util.c: Trying hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying hw:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:hw:0: Invalid argument D: alsa-util.c: Checking for capture on analog-stereo (front) D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. I: module-alsa-card.c: Found output profile 'Output Analog Stereo + Input Stereo analogico' D: alsa-util.c: Checking for capture on iec958-stereo (iec958) D: alsa-util.c: Trying iec958:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D1c failed I: alsa-util.c: Error opening PCM device iec958:0: No such file or directory D: alsa-util.c: Checking for capture on hdmi-stereo (hdmi) D: alsa-util.c: Trying hdmi:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D3c failed I: alsa-util.c: Error opening PCM device hdmi:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-40 (surround40) D: alsa-util.c: Trying surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround40:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround40:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-40 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-41 (surround41) D: alsa-util.c: Trying surround41:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround41:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround41:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround41:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround41:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-50 (surround50) D: alsa-util.c: Trying surround50:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround50:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround50:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround50:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround50:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-51 (surround51) D: alsa-util.c: Trying surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround51:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround51:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-51 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-71 (surround71) D: alsa-util.c: Trying surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround71:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround71:0: Invalid argument I: module-alsa-card.c: Found output profile 'Output Stereo analogico' D: alsa-util.c: Checking for playback on iec958-stereo (iec958) D: alsa-util.c: Trying iec958:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D1p failed I: alsa-util.c: Error opening PCM device iec958:0: No such file or directory D: alsa-util.c: Checking for playback on hdmi-stereo (hdmi) D: alsa-util.c: Trying hdmi:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D3p failed I: alsa-util.c: Error opening PCM device hdmi:0: No such file or directory D: alsa-util.c: Checking for playback on analog-surround-40 (surround40) D: alsa-util.c: Trying surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround40:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround40:0: Invalid argument D: alsa-util.c: Checking for playback on iec958-ac3-surround-40 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for playback on analog-surround-41 (surround41) D: alsa-util.c: Trying surround41:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround41:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround41:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround41:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround41:0: Invalid argument D: alsa-util.c: Checking for playback on analog-surround-50 (surround50) D: alsa-util.c: Trying surround50:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround50:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround50:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround50:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround50:0: Invalid argument D: alsa-util.c: Checking for playback on analog-surround-51 (surround51) D: alsa-util.c: Trying surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround51:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround51:0: Invalid argument D: alsa-util.c: Checking for playback on iec958-ac3-surround-51 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for playback on analog-surround-71 (surround71) D: alsa-util.c: Trying surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround71:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround71:0: Invalid argument D: alsa-util.c: Checking for capture on analog-mono (hw) D: alsa-util.c: Trying hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying hw:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:hw:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:hw:0: Invalid argument D: alsa-util.c: Checking for capture on analog-stereo (front) D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. I: module-alsa-card.c: Found output profile 'Input Stereo analogico' D: alsa-util.c: Checking for capture on iec958-stereo (iec958) D: alsa-util.c: Trying iec958:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D1c failed I: alsa-util.c: Error opening PCM device iec958:0: No such file or directory D: alsa-util.c: Checking for capture on hdmi-stereo (hdmi) D: alsa-util.c: Trying hdmi:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_hw.c: open /dev/snd/pcmC0D3c failed I: alsa-util.c: Error opening PCM device hdmi:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-40 (surround40) D: alsa-util.c: Trying surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround40:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround40:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround40:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-40 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-41 (surround41) D: alsa-util.c: Trying surround41:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround41:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround41:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround41:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround41:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-50 (surround50) D: alsa-util.c: Trying surround50:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround50:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround50:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable D: alsa-util.c: Trying plug:surround50:0 without SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm_params.c: Slave PCM not usable I: alsa-util.c: Failed to set hardware parameters on plug:surround50:0: Invalid argument D: alsa-util.c: Checking for capture on analog-surround-51 (surround51) D: alsa-util.c: Trying surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround51:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround51:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround51:0: Invalid argument D: alsa-util.c: Checking for capture on iec958-ac3-surround-51 (a52) D: alsa-util.c: Trying a52:0 with SND_PCM_NO_AUTO_FORMAT ... I: (alsa-lib)pcm.c: Unknown PCM a52:0 I: alsa-util.c: Error opening PCM device a52:0: No such file or directory D: alsa-util.c: Checking for capture on analog-surround-71 (surround71) D: alsa-util.c: Trying surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying surround71:0 without SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Trying plug:surround71:0 without SND_PCM_NO_AUTO_FORMAT ... I: alsa-util.c: Failed to set hardware parameters on plug:surround71:0: Invalid argument I: module-card-restore.c: Restoring profile for card alsa_card.pci_8086_27d8_sound_card_0. I: card.c: Created 0 "alsa_card.pci_8086_27d8_sound_card_0" D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ... D: alsa-util.c: Maximum hw buffer size is 341 ms I: alsa-util.c: Device front:0 doesn't support 44100 Hz, changed to 48000 Hz. I: alsa-sink.c: Successfully opened device front:0. I: alsa-sink.c: Selected configuration 'Analog Stereo' (analog-stereo). I: alsa-sink.c: Successfully enabled mmap() mode. I: alsa-sink.c: Successfully enabled timer-based scheduling mode. I: (alsa-lib)control.c: Invalid CTL front:0 I: alsa-util.c: Unable to attach to mixer front:0: No such file or directory I: alsa-util.c: Successfully attached to mixer 'hw:0' I: alsa-util.c: Cannot find mixer control "Master" or mixer control is no combination of switch/volume. I: alsa-util.c: Cannot find fallback mixer control "PCM" or mixer control is no combination of switch/volume. I: alsa-util.c: Using mixer control "PCM". I: module-device-restore.c: Restoring volume for sink alsa_output.pci_8086_27d8_sound_card_0. I: module-device-restore.c: Restoring mute state for sink alsa_output.pci_8086_27d8_sound_card_0. I: sink.c: Created sink 0 "alsa_output.pci_8086_27d8_sound_card_0" with sample spec s16le ch 2 48000 Hz and channel map front-left,front-right I: sink.c: alsa.resolution_bits = "16" I: sink.c: alsa.mixer_element = "PCM" I: sink.c: device.api = "alsa" I: sink.c: device.class = "sound" I: sink.c: alsa.class = "generic" I: sink.c: alsa.subclass = "generic-mix" I: sink.c: alsa.name = "ALC861 Analog" I: sink.c: alsa.id = "ALC861 Analog" I: sink.c: alsa.subdevice = "0" I: sink.c: alsa.subdevice_name = "subdevice #0" I: sink.c: alsa.device = "0" I: sink.c: alsa.card = "0" I: sink.c: alsa.card_name = "HDA Intel" I: sink.c: alsa.long_card_name = "HDA Intel at 0xdc440000 irq 22" I: sink.c: alsa.driver_name = "snd_hda_intel" I: sink.c: hal.udi = "/org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0" I: sink.c: hal.product = "HDA Intel Sound Card" I: sink.c: hal.card_id = "HDA Intel" I: sink.c: device.string = "front:0" I: sink.c: device.buffering.buffer_size = "65536" I: sink.c: device.buffering.fragment_size = "32768" I: sink.c: device.access_mode = "mmap+timer" I: sink.c: device.profile.name = "analog-stereo" I: sink.c: device.profile.description = "Analog Stereo" I: sink.c: device.description = "HDA Intel" I: sink.c: device.icon_name = "audio-card" I: module-device-restore.c: Restoring volume for source alsa_output.pci_8086_27d8_sound_card_0.monitor. I: module-device-restore.c: Restoring mute state for source alsa_output.pci_8086_27d8_sound_card_0.monitor. I: source.c: Created source 0 "alsa_output.pci_8086_27d8_sound_card_0.monitor" with sample spec s16le ch 2 48000 Hz and channel map front-left,front-right I: source.c: device.description = "Monitor of HDA Intel" I: source.c: device.class = "monitor" I: source.c: alsa.card = "0" I: source.c: alsa.card_name = "HDA Intel" I: source.c: alsa.long_card_name = "HDA Intel at 0xdc440000 irq 22" I: source.c: alsa.driver_name = "snd_hda_intel" I: source.c: hal.udi = "/org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0" I: source.c: hal.product = "HDA Intel Sound Card" I: source.c: hal.card_id = "HDA Intel" I: source.c: device.string = "0" I: source.c: device.icon_name = "audio-card" I: alsa-sink.c: Using 2 fragments of size 32768 bytes, buffer time is 341,33ms I: alsa-sink.c: Time scheduling watermark is 20,00ms D: alsa-sink.c: Latency set to 250,00ms D: alsa-sink.c: hwbuf_unused=17536 D: alsa-sink.c: setting avail_min=15425 I: alsa-sink.c: Volume ranges from 0 to 255. I: alsa-sink.c: Volume ranges from -51,00 dB to 0,00 dB. I: alsa-sink.c: No particular base volume set, fixing to 0 dB I: alsa-util.c: All 2 channels can be mapped to mixer channels. I: alsa-sink.c: Using hardware volume control. Hardware dB scale supported. I: alsa-sink.c: Using software mute control. D: alsa-util.c: snd_pcm_dump(): D: alsa-util.c: Soft volume PCM D: alsa-util.c: Control: PCM Playback Volume D: alsa-util.c: min_dB: -51 D: alsa-util.c: max_dB: 0 D: alsa-util.c: resolution: 256 D: alsa-util.c: Its setup is: D: alsa-util.c: stream : PLAYBACK D: alsa-util.c: access : MMAP_INTERLEAVED D: alsa-util.c: format : S16_LE D: alsa-util.c: subformat : STD D: alsa-util.c: channels : 2 D: alsa-util.c: rate : 48000 D: alsa-util.c: exact rate : 48000 (48000/1) D: alsa-util.c: msbits : 16 D: alsa-util.c: buffer_size : 16384 D: alsa-util.c: period_size : 8192 D: alsa-util.c: period_time : 170666 D: alsa-util.c: tstamp_mode : ENABLE D: alsa-util.c: period_step : 1 D: alsa-util.c: avail_min : 15425 D: alsa-util.c: period_event : 0 D: alsa-util.c: start_threshold : -1 D: alsa-util.c: stop_threshold : 1073741824 D: alsa-util.c: silence_threshold: 0 D: alsa-util.c: silence_size : 0 D: alsa-util.c: boundary : 1073741824 D: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0 D: alsa-util.c: Its setup is: D: alsa-util.c: stream : PLAYBACK D: alsa-util.c: access : MMAP_INTERLEAVED D: alsa-util.c: format : S16_LE D: alsa-util.c: subformat : STD D: alsa-util.c: channels : 2 D: alsa-util.c: rate : 48000 D: alsa-util.c: exact rate : 48000 (48000/1) D: alsa-util.c: msbits : 16 D: alsa-util.c: buffer_size : 16384 D: alsa-util.c: period_size : 8192 D: alsa-util.c: period_time : 170666 D: alsa-util.c: tstamp_mode : ENABLE D: alsa-util.c: period_step : 1 D: alsa-util.c: avail_min : 15425 D: alsa-util.c: period_event : 0 D: alsa-util.c: start_threshold : -1 D: alsa-util.c: stop_threshold : 1073741824 D: alsa-util.c: silence_threshold: 0 D: alsa-util.c: silence_size : 0 D: alsa-util.c: boundary : 1073741824 D: alsa-util.c: appl_ptr : 0 D: alsa-util.c: hw_ptr : 0 D: alsa-sink.c: Thread starting up D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29 D: alsa-sink.c: Requested volume: 0: 100% 1: 100% D: alsa-sink.c: Got hardware volume: 0: 100% 1: 100% D: alsa-sink.c: Calculated software volume: 0: 100% 1: 100% I: alsa-sink.c: Starting playback. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. I: module.c: Loaded "module-alsa-card" (index: #4; argument: "device_id=0 name=pci_8086_27d8_sound_card_0 card_name=alsa_card.pci_8086_27d8_sound_card_0 tsched=1"). D: module-hal-detect.c: Loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_control__1 I: module-hal-detect.c: Loaded 1 modules. I: module.c: Loaded "module-hal-detect" (index: #5; argument: ""). D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-bluetooth-discover.so': failure D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-esound-protocol-unix.so': success I: module.c: Loaded "module-esound-protocol-unix" (index: #6; argument: "socket=/tmp/.esd/socket"). I: module.c: Loaded "module-native-protocol-unix" (index: #7; argument: ""). D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9.15/modules/module-gconf.so': success I: module.c: Loaded "module-gconf" (index: #8; argument: ""). I: module-default-device-restore.c: Restored default sink 'alsa_output.pci_8086_27d8_sound_card_0'. D: core-subscribe.c: Dropped redundant event due to change event. I: module-default-device-restore.c: Restored default source 'alsa_output.pci_8086_27d8_sound_card_0.monitor'. I: module.c: Loaded "module-default-device-restore" (index: #9; argument: ""). I: module.c: Loaded "module-rescue-streams" (index: #10; argument: ""). I: module.c: Loaded "module-always-sink" (index: #11; argument: ""). D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. I: module.c: Loaded "module-suspend-on-idle" (index: #12; argument: ""). I: client.c: Created 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session1" D: module-console-kit.c: Added new session /org/freedesktop/ConsoleKit/Session1 I: module.c: Loaded "module-console-kit" (index: #13; argument: ""). I: module.c: Loaded "module-position-event-sounds" (index: #14; argument: ""). I: module.c: Loaded "module-cork-music-on-phone" (index: #15; argument: ""). D: main.c: Got org.pulseaudio.Server! I: main.c: Completato l'avvio del demone. D: module-hal-detect.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired D: module-console-kit.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 idle for too long, suspending ... I: alsa-sink.c: Device suspended... us e94118417dccdfcccb78410c4a2e3404 as :1.4I: client.c: Created 1 "Native client (UNIX socket client)" D: protocol-native.c: Protocol version: remote 15, local 15 I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 D: protocol-native.c: SHM possible: yes D: protocol-native.c: Negotiated SHM: yes D: module-augment-properties.c: Looking for .desktop file for vlc I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:Lettore multimediale VLC. I: module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:Lettore multimediale VLC. D: reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0' I: alsa-sink.c: Trying resume... D: alsa-util.c: Maximum hw buffer size is 341 ms D: alsa-sink.c: Latency set to 250,00ms D: alsa-sink.c: hwbuf_unused=17536 D: alsa-sink.c: setting avail_min=15425 I: alsa-sink.c: Resumed successfully... I: alsa-sink.c: Starting playback. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Wakeup from ALSA! I: alsa-sink.c: Underrun! N: alsa-sink.c: Increasing wakeup watermark to 30,00 ms D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes busy. I: resampler.c: Using resampler 'speex-float-3' I: resampler.c: Using float32le as working format. I: resampler.c: Choosing speex quality setting 3. D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: sink-input.c: Created input 0 "audio stream" on alsa_output.pci_8086_27d8_sound_card_0 with sample spec float32le ch 2 44100 Hz and channel map front-left,front-right I: protocol-native.c: Requested tlength=200,00 ms, minreq=20,00 ms D: protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: memblockq.c: memblockq requested: maxlength=141120, tlength=42336, base=8, prebuf=35288, minreq=7056 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=141120, tlength=42336, base=8, prebuf=35288, minreq=7056 maxrewind=0 I: protocol-native.c: Final latency 200,00 ms = 80,00 ms + 2*20,00 ms + 80,00 ms D: alsa-sink.c: Requested volume: 0: 100% 1: 100% D: alsa-sink.c: Got hardware volume: 0: 100% 1: 100% D: alsa-sink.c: Calculated software volume: 0: 100% 1: 100% D: alsa-sink.c: Latency set to 80,00ms D: alsa-sink.c: hwbuf_unused=50176 D: alsa-sink.c: setting avail_min=14945 D: alsa-sink.c: Requesting rewind due to latency change. D: alsa-sink.c: Requested to rewind 65536 bytes. D: alsa-sink.c: Limited to 41760 bytes. D: alsa-sink.c: before: 10440 D: alsa-sink.c: after: 10440 D: alsa-sink.c: Rewound 41760 bytes. D: sink.c: Processing rewind... D: sink-input.c: Have to rewind 41760 bytes on render memblockq. D: source.c: Processing rewind... D: core-subscribe.c: Dropped redundant event due to change event. D: alsa-sink.c: Wakeup from ALSA! D: protocol-native.c: Requesting rewind due to end of underrun. D: protocol-native.c: Underrun on 'audio stream', 0 bytes in queue. D: alsa-sink.c: Requested volume: 0: 100% 1: 100% D: alsa-sink.c: Got hardware volume: 0: 100% 1: 100% D: alsa-sink.c: Calculated software volume: 0: 100% 1: 100% D: alsa-sink.c: hwbuf_unused=0 D: alsa-sink.c: setting avail_min=14944 D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: alsa-sink.c: Requested to rewind 65536 bytes. D: alsa-sink.c: Limited to 9152 bytes. D: alsa-sink.c: before: 2288 D: alsa-sink.c: after: 2288 D: alsa-sink.c: Rewound 9152 bytes. D: sink.c: Processing rewind... D: source.c: Processing rewind... D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: core.c: Hmm, no streams around, trying to vacuum. I: sink-input.c: Freeing input 0 "audio stream" I: client.c: Freed 1 "Lettore multimediale VLC" I: protocol-native.c: Connection died. I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 idle for too long, suspending ... I: alsa-sink.c: Device suspended...

vlc -vvvv

Code: Select all

VLC media player 1.0.0-rc1 Goldeneye [0x8cb78a8] main libvlc debug: VLC media player - version 1.0.0-rc1 Goldeneye - (c) 1996-2009 the VideoLAN team [0x8cb78a8] main libvlc debug: libvlc was configured with ./configure '--prefix=/usr' '--mandir=${prefix}/share/man' '--infodir=${prefix}/share/info' '--build=i486-linux-gnu' '--enable-maintainer-mode' '--enable-release' '--enable-fast-install' '--disable-update-check' '--disable-fb' '--enable-ggi' '--disable-sdl' '--enable-mad' '--enable-jack' '--enable-pulse' '--enable-lirc' '--enable-a52' '--enable-aa' '--enable-dvbpsi' '--enable-mozilla' '--with-mozilla-pkg=libxul-plugin' '--enable-dvb' '--enable-ogg' '--enable-vorbis' '--enable-shout' '--enable-qt4' '--enable-flac' '--enable-skins2' '--enable-freetype' '--enable-mkv' '--enable-speex' '--enable-live555' '--enable-libmpeg2' '--enable-fribidi' '--enable-mod' '--enable-theora' '--disable-dvdnav' '--enable-gnutls' '--enable-ncurses' '--enable-smb' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--enable-faad' '--disable-zvbi' '--enable-telx' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-realrtsp' '--disable-dv' '--enable-x264' '--enable-alsa' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-svgalib' '--enable-gnomevfs' 'build_alias=i486-linux-gnu' 'CFLAGS=-Wall -g -O2' [0x8cb78a8] main libvlc debug: translation test: code is "C" [0x8cb78a8] main libvlc debug: checking plugin modules [0x8cb78a8] main libvlc debug: loading plugins cache file /home/maththias/.cache/vlc/plugins-04041e.dat [0x8cb78a8] main libvlc debug: recursively browsing `/usr/lib/vlc' [0x8cb78a8] main libvlc debug: module bank initialized (382 modules) [0x8cb78a8] main libvlc debug: opening config file (/home/maththias/.config/vlc/vlcrc) [0x8cb78a8] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT FPU [0x8cb78a8] main libvlc debug: looking for memcpy module: 3 candidates [0x8cb78a8] main libvlc debug: using memcpy module "memcpymmxext" [0x8d68fa8] main input debug: Creating an input for 'Raccolta multimediale' [0x8d68fa8] main input debug: Input is a meta file: disabling unneeded options [0x8d68fa8] main input debug: using timeshift granularity of 50 MBytes [0x8d68fa8] main input debug: using timeshift path '/tmp' [0x8d68fa8] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/maththias/.local/share/vlc/ml.xspf' [0x8d68fa8] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x8d6fa48] main demux debug: looking for access_demux module: 0 candidates [0x8d6fa48] main demux debug: no access_demux module matched "file" [0x8d6fa48] main demux debug: TIMER module_need() : 0,178 ms - Total 0,178 ms / 1 intvls (Avg 0,178 ms) [0x8d68fa8] main input debug: creating access 'file' path='/home/maththias/.local/share/vlc/ml.xspf' [0x8d6fa48] main access debug: looking for access module: 3 candidates [0x8d6fa48] access_file access debug: opening file `/home/maththias/.local/share/vlc/ml.xspf' [0x8d6fa48] main access debug: using access module "access_file" [0x8d6fa48] main access debug: TIMER module_need() : 0,824 ms - Total 0,824 ms / 1 intvls (Avg 0,824 ms) [0x8d718f8] main stream debug: Using AStream*Stream [0x8d718f8] main stream debug: pre buffering [0x8d718f8] main stream debug: received first data after 25 ms [0x8d718f8] main stream debug: pre-buffering done 304 bytes in 0s - 11 kbytes/s [0x8d70950] main stream debug: looking for stream_filter module: 4 candidates [0x8d70950] main stream debug: TIMER module_need() : 27,111 ms - Total 27,111 ms / 1 intvls (Avg 27,111 ms) [0x8d70950] main stream debug: looking for stream_filter module: 1 candidate [0x8d70950] main stream debug: using stream_filter module "stream_filter_record" [0x8d70950] main stream debug: TIMER module_need() : 0,609 ms - Total 0,609 ms / 1 intvls (Avg 0,609 ms) [0x8d68fa8] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x8d731d8] main demux debug: looking for demux module: 1 candidate [0x8d731d8] playlist demux debug: using XSPF playlist reader [0x8d731d8] main demux debug: using demux module "playlist" [0x8d731d8] main demux debug: TIMER module_need() : 22,095 ms - Total 22,095 ms / 1 intvls (Avg 22,095 ms) [0x8d68fa8] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' successfully opened [0x8d742c0] main xml debug: looking for xml module: 2 candidates [0x8d742c0] main xml debug: using xml module "xml" [0x8d742c0] main xml debug: TIMER module_need() : 1,184 ms - Total 1,184 ms / 1 intvls (Avg 1,184 ms) [0x8d731d8] playlist demux debug: parsed 0 tracks successfully [0x8d742c0] main xml debug: removing module "xml" [0x8d68fa8] main input debug: EOF reached [0x8d731d8] main demux debug: removing module "playlist" [0x8d70950] main stream debug: removing module "stream_filter_record" [0x8d6fa48] main access debug: removing module "access_file" [0x8d68fa8] main input debug: TIMER input launching for 'Raccolta multimediale' : 83,193 ms - Total 83,193 ms / 1 intvls (Avg 83,193 ms) [0x8d652f0] main playlist debug: Activated [0x8d652f0] main playlist debug: rebuilding array of current - root Scaletta [0x8d652f0] main playlist debug: rebuild done - 0 items, index -1 [0x8d742c0] main interface debug: looking for interface module: 1 candidate [0x8d742c0] main interface debug: using interface module "hotkeys" [0x8d742c0] main interface debug: TIMER module_need() : 11,908 ms - Total 11,908 ms / 1 intvls (Avg 11,908 ms) [0x8d742c0] main interface debug: thread started [0x8d742c0] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x8d769d0] main interface debug: looking for interface module: 1 candidate [0x8d769d0] main interface debug: using interface module "inhibit" [0x8d769d0] main interface debug: TIMER module_need() : 6,808 ms - Total 6,808 ms / 1 intvls (Avg 6,808 ms) [0x8d769d0] main interface debug: thread started [0x8d769d0] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x8d757e8] main interface debug: looking for interface module: 1 candidate [0x8d757e8] main interface debug: using interface module "screensaver" [0x8d757e8] main interface debug: TIMER module_need() : 0,678 ms - Total 0,678 ms / 1 intvls (Avg 0,678 ms) [0x8d757e8] main interface debug: thread started [0x8d757e8] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x8d6ff68] main interface debug: looking for interface module: 1 candidate [0x8d6ff68] main interface debug: using interface module "signals" [0x8d6ff68] main interface debug: TIMER module_need() : 0,633 ms - Total 0,633 ms / 1 intvls (Avg 0,633 ms) [0x8d6ff68] main interface debug: thread started [0x8d6ff68] main interface debug: thread ended [0x8d6ff68] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x8d6d608] main interface debug: looking for interface module: 1 candidate [0x8d6d608] main interface debug: using interface module "globalhotkeys" [0x8d6d608] main interface debug: TIMER module_need() : 43,145 ms - Total 43,145 ms / 1 intvls (Avg 43,145 ms) [0x8d6d608] main interface debug: thread started [0x8d6d608] main interface debug: thread ended [0x8d6d608] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x8cb78a8] main libvlc: Esecuzione di vlc con l'interfaccia predefinita. Usa 'cvlc' per utilizzare vlc senza interfaccia. [0x8d778b0] main interface debug: looking for interface module: 4 candidates [0x8d778b0] main interface debug: using interface module "qt4" [0x8d778b0] main interface debug: TIMER module_need() : 564,937 ms - Total 564,937 ms / 1 intvls (Avg 564,937 ms) [0x8d778b0] main interface debug: thread started [0x8d778b0] main interface debug: thread ended [0x8d778b0] main interface debug: thread (interface) created at priority 0 (interface/interface.c:156) [0x8d778b0] qt4 interface debug: Error while initializing qt-specific localization [0x8d652f0] main playlist debug: adding item `01 - Pretty Little Thing.mp3' ( /home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3 ) [0x8d778b0] qt4 interface debug: Adding a new MRL to recent ones: /home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3 [0x8d652f0] main playlist debug: rebuilding array of current - root Scaletta [0x8d652f0] main playlist debug: rebuild done - 1 items, index -1 [0x8d652f0] main playlist debug: processing request item 01 - Pretty Little Thing.mp3 node null skip 0 [0x8d652f0] main playlist debug: resyncing on 01 - Pretty Little Thing.mp3 [0x8d652f0] main playlist debug: 01 - Pretty Little Thing.mp3 is at 0 [0x8d652f0] main playlist debug: starting new item [0x8d652f0] main playlist debug: creating new input thread [0x909e558] main input debug: Creating an input for '01 - Pretty Little Thing.mp3' [0x909e558] main input debug: thread started [0x909e558] main input debug: using timeshift granularity of 50 MBytes [0x909e558] main input debug: using timeshift path '/tmp' [0x909e558] main input debug: thread (input) created at priority 10 (input/input.c:230) [0x8d778b0] qt4 interface debug: IM: Setting an input [0x8d778b0] qt4 interface debug: Updating the geometry [0x909e558] main input debug: `/home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3' gives access `' demux `' path `/home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3' [0x909e558] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3' [0x90af4f0] main demux debug: looking for access_demux module: 6 candidates [0x8d778b0] qt4 interface debug: Updating the geometry [0x90af4f0] main demux debug: TIMER module_need() : 79,613 ms - Total 79,613 ms / 1 intvls (Avg 79,613 ms) [0x909e558] main input debug: creating access '' path='/home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3' [0x90c5d80] main access debug: looking for access module: 8 candidates [0x90c5d80] vcd access debug: trying .cue file: /home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.cue [0x90c5d80] vcd access debug: could not find .cue file [0x90c5d80] access_file access debug: opening file `/home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3' [0x90c5d80] main access debug: using access module "access_file" [0x90c5d80] main access debug: TIMER module_need() : 81,988 ms - Total 81,988 ms / 1 intvls (Avg 81,988 ms) [0x90af4f0] main stream debug: Using AStream*Stream [0x90af4f0] main stream debug: pre buffering [0x90af4f0] main stream debug: received first data after 10 ms [0x90af4f0] main stream debug: pre-buffering done 1024 bytes in 0s - 92 kbytes/s [0x90c9de0] main stream debug: looking for stream_filter module: 4 candidates [0x90c9de0] main stream debug: TIMER module_need() : 0,162 ms - Total 0,162 ms / 1 intvls (Avg 0,162 ms) [0x90c9de0] main stream debug: looking for stream_filter module: 1 candidate [0x90c9de0] main stream debug: using stream_filter module "stream_filter_record" [0x90c9de0] main stream debug: TIMER module_need() : 0,135 ms - Total 0,135 ms / 1 intvls (Avg 0,135 ms) [0x909e558] main input debug: creating demux: access='' demux='' path='/home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3' [0x90c9ea8] main demux debug: ID3v2.3 revision 0 tag found, skipping 38554 bytes [0x90c9ea8] main demux debug: looking for demux module: 50 candidates [0x90c9ea8] es demux debug: xing vbr value present (78) [0x90c9ea8] es demux debug: xing frames&bytes value present (6412272 bytes, 10530 frames, 1152 samples/frame) [0x90c9ea8] es demux debug: detected format mpga [0x90cd490] main packetizer debug: looking for packetizer module: 21 candidates [0x90cd490] main packetizer debug: using packetizer module "mpeg_audio" [0x90cd490] main packetizer debug: TIMER module_need() : 93,801 ms - Total 93,801 ms / 1 intvls (Avg 93,801 ms) [0x90c9ea8] main demux debug: using demux module "es" [0x90c9ea8] main demux debug: TIMER module_need() : 161,502 ms - Total 161,502 ms / 1 intvls (Avg 161,502 ms) [0x909e558] main input debug: looking for a subtitle file in /home/maththias/Mussik/Presa/Biscuits For Breakfast/ [0x90c9ea8] main demux debug: looking for meta reader module: 1 candidate TagLib: ID3v2.4 no longer supports the frame type TDAT. It will be discarded from the tag. [0x90c9ea8] taglib demux debug: Found embedded art: 0 (image/jpeg) is 38120 bytes [0x90c9ea8] main demux debug: using meta reader module "taglib" [0x90c9ea8] main demux debug: TIMER module_need() : 95,537 ms - Total 95,537 ms / 1 intvls (Avg 95,537 ms) [0x90c9ea8] main demux debug: removing module "taglib" [0x8d652f0] main playlist debug: album art saved to file:///home/maththias/.cache/vlc/art/artistalbum/Fink/Biscuits For Breakfast/art.jpg [0x909e558] main input debug: `/home/maththias/Mussik/Presa/Biscuits For Breakfast/01 - Pretty Little Thing.mp3' successfully opened [0x90cd490] mpeg_audio packetizer debug: MPGA channels:2 samplerate:44100 bitrate:128 [0x909e558] main input debug: selecting program id=0 [0x90e44c0] main decoder debug: looking for decoder module: 30 candidates [0x90e44c0] main decoder debug: using decoder module "mpeg_audio" [0x90e44c0] main decoder debug: TIMER module_need() : 60,786 ms - Total 60,786 ms / 1 intvls (Avg 60,786 ms) [0x90e44c0] main decoder debug: thread started [0x90e44c0] main decoder debug: thread (decoder) created at priority 5 (input/decoder.c:315) [0x909e558] main input debug: Buffering 0% [0x909e558] main input debug: Buffering 8% [0x90e44c0] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:128 [0x909e558] main input debug: creating aout [0x90e2a68] main audio output debug: looking for audio output module: 5 candidates [0x90e2a68] pulse audio output: No. of Audio Channels: 2 [0x90e2a68] pulse audio output debug: Pulse mainloop started [0x909e558] main input debug: Buffering 17% [0x909e558] main input debug: Buffering 26% [0x909e558] main input debug: Buffering 34% [0x909e558] main input debug: Buffering 43% [0x909e558] main input debug: Buffering 52% [0x909e558] main input debug: Buffering 60% [0x909e558] main input debug: Buffering 69% [0x909e558] main input debug: Buffering 78% [0x909e558] main input debug: Buffering 87% [0x909e558] main input debug: Buffering 95% [0x909e558] main input debug: Stream buffering done (313 ms in 66 ms) [0x90e2a68] pulse audio output debug: Pulse stream connected [0x90e2a68] pulse audio output debug: Pulse initialized successfully [0x90e2a68] pulse audio output debug: Buffer metrics: maxlength=141120, tlength=42336, prebuf=35288, minreq=7056 [0x90e2a68] pulse audio output debug: Using sample spec 'float32le ch 2 44100 Hz', channel map 'front-left,front-right'. [0x90e2a68] pulse audio output debug: Connected to device alsa_output.pci_8086_27d8_sound_card_0 (0, not suspended). [0x90e2a68] main audio output debug: using audio output module "pulse" [0x90e2a68] main audio output debug: TIMER module_need() : 89,904 ms - Total 89,904 ms / 1 intvls (Avg 89,904 ms) [0x90e2a68] main audio output debug: output 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x90e2a68] main audio output debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes [0x90e2a68] main audio output debug: no need for any filter [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,375 ms - Total 0,375 ms / 1 intvls (Avg 0,375 ms) [0x90e2a68] main audio output debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes [0x90e8228] main audio filter debug: looking for audio filter module: 1 candidate [0x90e8228] scaletempo audio filter warning: bad input or output format [0x90e8228] main audio filter warning: no audio filter module matching "scaletempo" could be loaded [0x90e8228] main audio filter debug: TIMER module_need() : 0,642 ms - Total 0,642 ms / 1 intvls (Avg 0,642 ms) [0x90e8228] main audio filter debug: looking for audio filter module: 1 candidate [0x90e8228] scaletempo audio filter debug: format: 44100 rate, 2 nch, 4 bps, fl32 [0x90e8228] scaletempo audio filter debug: params: 30 stride, 0,200 overlap, 14 search [0x90e8228] scaletempo audio filter debug: 1,000 scale, 1323,000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode [0x90e8228] main audio filter debug: using audio filter module "scaletempo" [0x90e8228] main audio filter debug: TIMER module_need() : 0,378 ms - Total 0,378 ms / 1 intvls (Avg 0,378 ms) [0x90e2a68] main audio output debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo [0x90f7938] main audio output debug: looking for audio filter module: 24 candidates [0x90f7938] main audio output debug: using audio filter module "mpgatofixed32" [0x90f7938] main audio output debug: TIMER module_need() : 0,736 ms - Total 0,736 ms / 1 intvls (Avg 0,736 ms) [0x90e2a68] main audio output debug: found a filter for the whole conversion [0x90e2a68] main audio output debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo [0x91002d8] main audio output debug: looking for audio filter module: 24 candidates [0x91002d8] main audio output debug: using audio filter module "bandlimited_resampler" [0x91002d8] main audio output debug: TIMER module_need() : 0,708 ms - Total 0,708 ms / 1 intvls (Avg 0,708 ms) [0x90e2a68] main audio output debug: found a filter for the whole conversion [0x90e44c0] main decoder debug: End of audio preroll [0x909e558] main input debug: Decoder buffering done in 28 ms [0x90e2a68] main audio output warning: PTS is out of range (-9775), dropping buffer [0x90e2a68] main audio output warning: PTS is out of range (-35846), dropping buffer [0x90e2a68] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x90e2a68] pulse audio output debug: Pulse stream started [0x90e2a68] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x90e2a68] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: New caching: 100 [0x8d778b0] qt4 interface debug: New caching: 100 [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,551 ms - Total 0,551 ms / 1 intvls (Avg 0,551 ms) [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,412 ms - Total 0,412 ms / 1 intvls (Avg 0,412 ms) [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,457 ms - Total 0,457 ms / 1 intvls (Avg 0,457 ms) [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,593 ms - Total 0,593 ms / 1 intvls (Avg 0,593 ms) [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,480 ms - Total 0,480 ms / 1 intvls (Avg 0,480 ms) [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,594 ms - Total 0,594 ms / 1 intvls (Avg 0,594 ms) [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,488 ms - Total 0,488 ms / 1 intvls (Avg 0,488 ms) [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x90e2a68] main audio output debug: looking for audio mixer module: 3 candidates [0x90e2a68] main audio output debug: using audio mixer module "float32_mixer" [0x90e2a68] main audio output debug: TIMER module_need() : 0,451 ms - Total 0,451 ms / 1 intvls (Avg 0,451 ms) [0x8d652f0] main playlist debug: incoming request - stopping current input [0x8d652f0] main playlist debug: dying input [0x909e558] main input debug: control type=0 [0x909e558] main input debug: control: stopping input [0x8d652f0] main playlist debug: dying input [0x90e44c0] main decoder debug: removing module "mpeg_audio" [0x90e44c0] main decoder debug: killing decoder fourcc `mpga', 0 PES in FIFO [0x90f7938] main audio output debug: removing module "mpgatofixed32" [0x90e8228] main audio filter debug: removing module "scaletempo" [0x91002d8] main audio output debug: removing module "bandlimited_resampler" [0x90e2a68] pulse audio output debug: Pulse Close [0x90e2a68] main audio output debug: removing module "pulse" [0x90e2a68] main audio output debug: removing module "float32_mixer" [0x909e558] main input debug: releasing aout [0x90cd490] main packetizer debug: removing module "mpeg_audio" [0x90c9ea8] main demux debug: removing module "es" [0x90c9de0] main stream debug: removing module "stream_filter_record" [0x90c5d80] main access debug: removing module "access_file" [0x909e558] main input debug: Program doesn't contain anymore ES [0x8d652f0] main playlist debug: dead input [0x909e558] main input debug: thread ended [0x8d778b0] qt4 interface debug: IM: Deleting the input [0x8d778b0] qt4 interface debug: Updating the geometry [0x8d778b0] qt4 interface debug: Updating the geometry [0x909e558] main input debug: TIMER input launching for '01 - Pretty Little Thing.mp3' : 506,471 ms - Total 506,471 ms / 1 intvls (Avg 506,471 ms)

What's wrong?

[danwood76]

This is my patch that seems to be causing issues.
The patch forces pulse to use a lower latency mode and reduces clicking significantly during playback with multiple channel streams, it also adjusts the buffer sizes to be more in-line with the newer pulseaudio server.

On what I have tested it works perfectly, this is Ubuntu of the last few revisions and with vanilla pulseaudio 0.9.13 - 0.9.15.
There is an issue that has cropped up in addition to debian where it is not working on fedora, which I am currently trying to install although the installer keeps crashing (great distribution so far).

The crash will be in pulseaudio so debug outputs from that would be more useful than VLC output as vlc wont complain much about the pulse plugin as it doesnt have to do much to output the stream.
So get pulse audio debugs, first kill pulseaudio with 'pulseaudio -k' then restart in verbose mode with 'pulseaudio -vvv' the verbose mode will throw out loads of stuff but the info I am interested in is when vlc connects so start vlc after running pusleaudio and post the connection info.

I am looking into this now and with a bit of luck I will soon have fedora installed to test (and try to break).
Could you please tell me versions of pulseaudio (pulseaudio --version) and linux distribution?
This will help me find where the issue is.

Also the clicking at the start of the tracks happens in all of the vlc-pulse plugins versions I have used and has something to do with a race condition between vlc filling the buffer and pulse reading from it. It usually settles after 3-4 seconds and in my patched version is reduced.

[Snjezana]

I hope I can help with this:

Code: Select all

user@computer:~> vlc --version VLC media player 1.0.0-rc3 Goldeneye VLC-Version 1.0.0-rc3 Goldeneye Kompiliert von abuild@messiah. Compiler: gcc version 4.3.2 [gcc-4_3-branch revision 141291] (SUSE Linux) Dieses Programm kommt OHNE GEWÄHRLEISTUNG. Sie dürfen das Programm unter den Bedingungen der GNU General Public License weitergegeben; Schauen Sie für Details in die Datei COPYING. Geschrieben vom VideoLAN Team; Schauen Sie in die Datei AUTHORS. user@computer:~> rpm -qi vlc Name : vlc Relocations: (not relocatable) Version : 0.9.9_1.0.0rc3 Vendor: VideoLAN Project (http://www.videolan.org) Release : 1.1 Build Date: Sa 06 Jun 2009 19:17:55 CEST Install Date: Mi 10 Jun 2009 11:53:37 CEST Build Host: messiah Group : Productivity/Multimedia/Video/Players Source RPM: vlc-0.9.9_1.0.0rc3-1.1.src.rpm Size : 1342276 License: GPL v2 Signature : DSA/SHA1, Sa 06 Jun 2009 19:19:44 CEST, Key ID 33de8fb7c8da93d2 Summary : VLC - Video Lan Client Description : VLC media player is a highly portable multimedia player for various audio and video formats (MPEG-1, MPEG-2, MPEG-4, DivX, mp3, ogg, ...) as well as DVDs, VCDs, and various streaming protocols. It can also be used as a server to stream in unicast or multicast in IPv4 or IPv6 on a high-bandwidth network. Distribution: VideoLAN / 11.1

Code: Select all

user@computer:~> rpm -qi pulseaudio Name : pulseaudio Relocations: (not relocatable) Version : 0.9.14 Vendor: openSUSE Build Service Release : 5.7 Build Date: Mi 20 Mai 2009 21:38:40 CEST Install Date: Fr 22 Mai 2009 00:40:51 CEST Build Host: build19 Group : System/Sound Daemons Source RPM: pulseaudio-0.9.14-5.7.src.rpm Size : 1693046 License: GPL v2 or later; LGPL v2.1 or later Signature : DSA/SHA1, Mi 20 Mai 2009 21:39:58 CEST, Key ID dd5eb0f5e1bf12f6 URL : http://pulseaudio.org Summary : A Networked Sound Server Description : pulseaudio is a networked sound server for Linux and other Unix like operating systems and Microsoft Windows. It is intended to be an improved drop-in replacement for the Enlightened Sound Daemon (ESOUND). Authors: -------- Lennart Poettering Pierre Ossman Distribution: multimedia:libs / openSUSE_11.1

And here is the output of pulseaudio -vvv (without the output before vlc started):

Code: Select all

I: client.c: Created 2 "Native client (UNIX socket client)" D: protocol-native.c: Protocol version: remote 14, local 14 I: protocol-native.c: Got credentials: uid=1000 gid=100 success=1 D: protocol-native.c: SHM possible: yes D: protocol-native.c: Negotiated SHM: yes I: module-stream-restore.c: Restoring device for stream sink-input-by-application-name:VLC media player. I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:VLC media player. D: module-stream-restore.c: Not restoring mute state for sink input sink-input-by-application-name:VLC media player, because already set. I: module-alsa-sink.c: Trying resume... D: alsa-util.c: Maximum hw buffer size is 371 ms D: module-alsa-sink.c: hwbuf_unused_frames=0 D: module-alsa-sink.c: setting avail_min=62005 I: module-alsa-sink.c: Resumed successfully... I: module-alsa-sink.c: Starting playback. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes idle. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes busy. I: resampler.c: Using resampler 'speex-float-3' I: resampler.c: Using float32le as working format. I: resampler.c: Choosing speex quality setting 3. D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: sink-input.c: Created input 1 "audio stream" on alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 with sample spec float32le 2ch 48000Hz and channel map front-left,front-right I: protocol-native.c: Requested tlength=200,00 ms, minreq=20,00 ms D: protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: memblockq.c: memblockq requested: maxlength=153600, tlength=46080, base=8, prebuf=46080, minreq=7680 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=153600, tlength=46080, base=8, prebuf=46080, minreq=7680 maxrewind=0 I: protocol-native.c: Final latency 200,00 ms = 80,00 ms + 2*20,00 ms + 80,00 ms D: module-alsa-sink.c: latency set to 80,00ms D: module-alsa-sink.c: hwbuf_unused_frames=12856 D: module-alsa-sink.c: setting avail_min=23441 D: module-alsa-sink.c: Requesting rewind due to latency change. D: module-alsa-sink.c: Requested to rewind 65536 bytes. D: module-alsa-sink.c: Limited to 61832 bytes. D: module-alsa-sink.c: before: 15458 D: module-alsa-sink.c: after: 15458 D: module-alsa-sink.c: Rewound 61832 bytes. D: sink.c: Processing rewind... D: sink-input.c: Have to rewind 61832 bytes on render memblockq. D: protocol-native.c: Requesting rewind due to end of underrun. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes idle. D: module-alsa-sink.c: hwbuf_unused_frames=0 D: module-alsa-sink.c: setting avail_min=62005 D: module-alsa-sink.c: Requested to rewind 65536 bytes. D: module-alsa-sink.c: Limited to 10120 bytes. D: module-alsa-sink.c: before: 2530 D: module-alsa-sink.c: after: 2530 D: module-alsa-sink.c: Rewound 10120 bytes. D: sink.c: Processing rewind... D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes idle. I: sink-input.c: Freeing input 1 "audio stream" I: client.c: Freed 2 "VLC media player" I: protocol-native.c: Connection died. I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 idle for too long, suspending ... I: module-alsa-sink.c: Device suspended...

And that is the output of pulseaudio playing the same file as before but with kaffeine (sound is working here):

Code: Select all

I: socket-server.c: TCP connection accepted by tcpwrap. I: protocol-native.c: Client authenticated anonymously. I: client.c: Created 2 "Native client (TCP/IP client from 127.0.0.1:36719)" D: protocol-native.c: Protocol version: remote 14, local 14 D: protocol-native.c: SHM possible: no D: protocol-native.c: Negotiated SHM: no I: module-stream-restore.c: Restoring device for stream sink-input-by-application-name:kaffeine. I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:kaffeine. D: module-stream-restore.c: Not restoring mute state for sink input sink-input-by-application-name:kaffeine, because already set. I: module-alsa-sink.c: Trying resume... D: alsa-util.c: Maximum hw buffer size is 371 ms D: module-alsa-sink.c: hwbuf_unused_frames=0 D: module-alsa-sink.c: setting avail_min=62005 I: module-alsa-sink.c: Resumed successfully... I: module-alsa-sink.c: Starting playback. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes idle. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes busy. I: resampler.c: Using resampler 'speex-float-3' I: resampler.c: Using float32le as working format. I: resampler.c: Choosing speex quality setting 3. D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: sink-input.c: Created input 0 "Audio Stream" on alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 with sample spec s16le 2ch 48000Hz and channel map front-left,front-right I: protocol-native.c: Requested tlength=250,00 ms, minreq=20,00 ms D: protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq. D: memblockq.c: memblockq requested: maxlength=4194304, tlength=48000, base=4, prebuf=48000, minreq=3840 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=48000, base=4, prebuf=48000, minreq=3840 maxrewind=0 I: protocol-native.c: Final latency 460,00 ms = 210,00 ms + 2*20,00 ms + 210,00 ms D: module-alsa-sink.c: latency set to 210,00ms D: module-alsa-sink.c: hwbuf_unused_frames=7123 D: module-alsa-sink.c: setting avail_min=40640 D: module-alsa-sink.c: Requesting rewind due to latency change. D: module-alsa-sink.c: Requested to rewind 65536 bytes. D: module-alsa-sink.c: Limited to 61500 bytes. D: module-alsa-sink.c: before: 15375 D: module-alsa-sink.c: after: 15375 D: module-alsa-sink.c: Rewound 61500 bytes. D: sink.c: Processing rewind... D: sink-input.c: Have to rewind 61500 bytes on render memblockq. D: protocol-native.c: Requesting rewind due to end of underrun. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes idle. D: module-alsa-sink.c: hwbuf_unused_frames=0 D: module-alsa-sink.c: setting avail_min=62005 D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 becomes idle. I: sink-input.c: Freeing input 0 "Audio Stream" I: client.c: Freed 2 "kaffeine" I: protocol-native.c: Connection died. D: module-alsa-sink.c: Requested to rewind 65536 bytes. D: module-alsa-sink.c: Limited to 24776 bytes. D: module-alsa-sink.c: before: 6194 D: module-alsa-sink.c: after: 6194 D: module-alsa-sink.c: Rewound 24776 bytes. D: sink.c: Processing rewind... I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_266e_sound_card_0_alsa_playback_0 idle for too long, suspending ... I: module-alsa-sink.c: Device suspended...

On a first look I see that the type of connection is different.
Vlc connects via UDP and kaffeine aka xine connects via TCP.
The next difference is that vlc uses 32 bit float numbers and kaffeine 16 bit signed integer for the sound data.

[danwood76]

After I finally managed to get fedora installed and sound coming out I have worked out the source of the problem and have submitted the following patch upstream.
Basically the prebuf size needs halving.

Could you test this for me please?

I have it working on my fedora 11 and Ubuntu 9.10 Alpha now.

kind regards,
Danny

Code: Select all

diff -ur a/modules/audio_output/pulse.c b/modules/audio_output/pulse.c --- a/modules/audio_output/pulse.c 2009-05-14 17:58:13.000000000 +0100 +++ b/modules/audio_output/pulse.c 2009-06-14 09:43:49.559465467 +0100 @@ -181,7 +181,7 @@ */ a.tlength = pa_bytes_per_second(&ss)/5; a.maxlength = a.tlength * 2; - a.prebuf = a.tlength; + a.prebuf = a.tlength / 2; a.minreq = a.tlength / 10; /* Buffer size is 20mS */

[Maththias]

Finally sound comes out

I've tried rc1 with your patch and vlc uses pulseaudio direcly without problems, so I've buildt rc3 git f84c91c6581c2f0eeaeb7df78c69518ef2eeca0a of today (that has your patch included) and sound comes out clear and nice.

Just for debug
pulseaudio -vvv (with pulseaudio-dbg package installed and vlc started):

Code: Select all

I: client.c: Created 1 "Native client (UNIX socket client)" D: protocol-native.c: Protocol version: remote 15, local 15 I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 D: protocol-native.c: SHM possible: yes D: protocol-native.c: Negotiated SHM: yes D: module-augment-properties.c: Looking for .desktop file for vlc I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:Lettore multimediale VLC. I: module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:Lettore multimediale VLC. D: reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0' I: alsa-sink.c: Trying resume... D: alsa-util.c: Maximum hw buffer size is 341 ms D: alsa-sink.c: Latency set to 250,00ms D: alsa-sink.c: hwbuf_unused=17536 D: alsa-sink.c: setting avail_min=14945 I: alsa-sink.c: Resumed successfully... I: alsa-sink.c: Starting playback. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes busy. I: resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. I: resampler.c: Using resampler 'copy' I: resampler.c: Using float32le as working format. D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: sink-input.c: Created input 0 "audio stream" on alsa_output.pci_8086_27d8_sound_card_0 with sample spec float32le ch 2 48000 Hz and channel map front-left,front-right I: protocol-native.c: Requested tlength=200,00 ms, minreq=20,00 ms D: protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: memblockq.c: memblockq requested: maxlength=153600, tlength=46080, base=8, prebuf=38400, minreq=7680 maxrewind=0 D: memblockq.c: memblockq sanitized: maxlength=153600, tlength=46080, base=8, prebuf=38400, minreq=7680 maxrewind=0 I: protocol-native.c: Final latency 200,00 ms = 80,00 ms + 2*20,00 ms + 80,00 ms D: alsa-sink.c: Requested volume: 0: 100% 1: 100% D: alsa-sink.c: Got hardware volume: 0: 100% 1: 100% D: alsa-sink.c: Calculated software volume: 0: 100% 1: 100% D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Latency set to 80,00ms D: alsa-sink.c: hwbuf_unused=50176 D: alsa-sink.c: setting avail_min=14945 D: alsa-sink.c: Requesting rewind due to latency change. D: alsa-sink.c: Requested to rewind 65536 bytes. D: alsa-sink.c: Limited to 41792 bytes. D: alsa-sink.c: before: 10448 D: alsa-sink.c: after: 10448 D: alsa-sink.c: Rewound 41792 bytes. D: sink.c: Processing rewind... D: sink-input.c: Have to rewind 41792 bytes on render memblockq. D: source.c: Processing rewind... D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: core-subscribe.c: Dropped redundant event due to change event. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: protocol-native.c: Requesting rewind due to end of underrun. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: alsa-sink.c: Cutting sleep time for the initial iterations by half. D: protocol-native.c: Requesting rewind due to end of underrun. D: alsa-sink.c: Wakeup from ALSA! D: protocol-native.c: Underrun on 'audio stream', 0 bytes in queue. D: alsa-sink.c: Requested volume: 0: 100% 1: 100% D: alsa-sink.c: Got hardware volume: 0: 100% 1: 100% D: alsa-sink.c: Calculated software volume: 0: 100% 1: 100% D: alsa-sink.c: hwbuf_unused=0 D: alsa-sink.c: setting avail_min=14944 D: alsa-sink.c: Requested to rewind 65536 bytes. D: alsa-sink.c: Limited to 8224 bytes. D: alsa-sink.c: before: 2056 D: alsa-sink.c: after: 2056 D: alsa-sink.c: Rewound 8224 bytes. D: sink.c: Processing rewind... D: source.c: Processing rewind... D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 becomes idle. D: core.c: Hmm, no streams around, trying to vacuum. I: sink-input.c: Freeing input 0 "audio stream" I: client.c: Freed 1 "Lettore multimediale VLC" I: protocol-native.c: Connection died. I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0 idle for too long, suspending ... I: alsa-sink.c: Device suspended...

vlc- vvvv

Code: Select all

VLC media player 1.1.0-git Goldeneye [0x9a818a8] main libvlc debug: VLC media player - version 1.1.0-git Goldeneye - (c) 1996-2009 the VideoLAN team [0x9a818a8] main libvlc debug: libvlc was configured with ./configure '--mandir=${prefix}/share/man' '--infodir=${prefix}/share/info' '--build=i486-linux-gnu' '--enable-maintainer-mode' '--enable-release' '--prefix=/usr' '--enable-fast-install' '--disable-update-check' '--disable-fb' '--enable-ggi' '--disable-sdl' '--enable-mad' '--enable-jack' '--enable-pulse' '--enable-lirc' '--enable-a52' '--enable-aa' '--enable-dvbpsi' '--enable-mozilla' '--with-mozilla-pkg=libxul-plugin' '--enable-dvb' '--enable-ogg' '--enable-vorbis' '--enable-shout' '--enable-qt4' '--enable-flac' '--enable-skins2' '--enable-freetype' '--enable-mkv' '--enable-speex' '--disable-caca' '--enable-live555' '--enable-libmpeg2' '--enable-fribidi' '--enable-mod' '--enable-theora' '--disable-dvdnav' '--enable-gnutls' '--enable-ncurses' '--enable-smb' '--enable-bonjour' '--enable-mpc' '--enable-vcd' '--enable-vcdx' '--enable-notify' '--enable-twolame' '--enable-faad' '--disable-zvbi' '--enable-telx' '--disable-atmo' '--enable-taglib' '--enable-libass' '--enable-realrtsp' '--disable-dv' '--enable-x264' '--enable-alsa' '--enable-v4l' '--enable-v4l2' '--enable-pvr' '--enable-svgalib' '--enable-gnomevfs' 'build_alias=i486-linux-gnu' 'CFLAGS=-Wall -g -O2' [0x9a818a8] main libvlc debug: translation test: code is "C" [0x9a818a8] main libvlc debug: checking plugin modules [0x9a818a8] main libvlc debug: loading plugins cache file /home/maththias/.cache/vlc/plugins-04041e.dat [0x9a818a8] main libvlc debug: recursively browsing `/usr/lib/vlc' [0x9a818a8] main libvlc debug: module bank initialized (392 modules) [0x9a818a8] main libvlc debug: opening config file (/home/maththias/.config/vlc/vlcrc) [0x9a818a8] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT FPU [0x9a818a8] main libvlc debug: looking for memcpy module: 3 candidates [0x9a818a8] main libvlc debug: using memcpy module "memcpymmxext" [0x9b28f18] main input debug: Creating an input for 'Raccolta multimediale' [0x9b28f18] main input debug: Input is a meta file: disabling unneeded options [0x9b28f18] main input debug: using timeshift granularity of 50 MBytes [0x9b28f18] main input debug: using timeshift path '/tmp' [0x9b28f18] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/maththias/.local/share/vlc/ml.xspf' [0x9b28f18] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9b3ad60] main demux debug: looking for access_demux module: 0 candidates [0x9b3ad60] main demux debug: no access_demux module matched "file" [0x9b3ad60] main demux debug: TIMER module_need() : 0,148 ms - Total 0,148 ms / 1 intvls (Avg 0,148 ms) [0x9b28f18] main input debug: creating access 'file' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9b3ad60] main access debug: looking for access module: 3 candidates [0x9b3ad60] access_file access debug: opening file `/home/maththias/.local/share/vlc/ml.xspf' [0x9b3ad60] main access debug: using access module "access_file" [0x9b3ad60] main access debug: TIMER module_need() : 0,677 ms - Total 0,677 ms / 1 intvls (Avg 0,677 ms) [0x9b3d038] main stream debug: Using AStream*Stream [0x9b3d038] main stream debug: pre buffering [0x9b3d038] main stream debug: received first data after 0 ms [0x9b3d038] main stream debug: pre-buffering done 304 bytes in 0s - 6316 kbytes/s [0x9b3bcc0] main stream debug: looking for stream_filter module: 4 candidates [0x9b3bcc0] main stream debug: TIMER module_need() : 0,515 ms - Total 0,515 ms / 1 intvls (Avg 0,515 ms) [0x9b3bcc0] main stream debug: looking for stream_filter module: 1 candidate [0x9b3bcc0] main stream debug: using stream_filter module "stream_filter_record" [0x9b3bcc0] main stream debug: TIMER module_need() : 0,233 ms - Total 0,233 ms / 1 intvls (Avg 0,233 ms) [0x9b28f18] main input debug: creating demux: access='file' demux='xspf-open' path='/home/maththias/.local/share/vlc/ml.xspf' [0x9b3e580] main demux debug: looking for demux module: 1 candidate [0x9b3e580] playlist demux debug: using XSPF playlist reader [0x9b3e580] main demux debug: using demux module "playlist" [0x9b3e580] main demux debug: TIMER module_need() : 0,445 ms - Total 0,445 ms / 1 intvls (Avg 0,445 ms) [0x9b28f18] main input debug: `file/xspf-open:///home/maththias/.local/share/vlc/ml.xspf' successfully opened [0x9b3f680] main xml debug: looking for xml module: 2 candidates [0x9b3f680] main xml debug: using xml module "xml" [0x9b3f680] main xml debug: TIMER module_need() : 0,581 ms - Total 0,581 ms / 1 intvls (Avg 0,581 ms) [0x9b3e580] playlist demux debug: parsed 0 tracks successfully [0x9b3f680] main xml debug: removing module "xml" [0x9b28f18] main input debug: EOF reached [0x9b3e580] main demux debug: removing module "playlist" [0x9b3bcc0] main stream debug: removing module "stream_filter_record" [0x9b3ad60] main access debug: removing module "access_file" [0x9b28f18] main input debug: TIMER input launching for 'Raccolta multimediale' : 6,571 ms - Total 6,571 ms / 1 intvls (Avg 6,571 ms) [0x9b34888] main playlist debug: rebuilding array of current - root Scaletta [0x9b34888] main playlist debug: rebuild done - 0 items, index -1 [0x9b34888] main playlist debug: Activated [0x9b3f680] main interface debug: looking for interface module: 1 candidate [0x9b3f680] main interface debug: using interface module "hotkeys" [0x9b3f680] main interface debug: TIMER module_need() : 0,503 ms - Total 0,503 ms / 1 intvls (Avg 0,503 ms) [0x9b3f680] main interface debug: thread started [0x9b3f680] main interface debug: thread (interface) created at priority 0 (interface/interface.c:160) [0x9b420e0] main interface debug: looking for interface module: 1 candidate [0x9b420e0] main interface debug: using interface module "inhibit" [0x9b420e0] main interface debug: TIMER module_need() : 3,724 ms - Total 3,724 ms / 1 intvls (Avg 3,724 ms) [0x9b3e630] main interface debug: looking for interface module: 1 candidate [0x9b3e630] main interface debug: using interface module "screensaver" [0x9b3e630] main interface debug: TIMER module_need() : 0,470 ms - Total 0,470 ms / 1 intvls (Avg 0,470 ms) [0x9a81a40] main interface debug: looking for interface module: 1 candidate [0x9a81a40] main interface debug: using interface module "signals" [0x9a81a40] main interface debug: TIMER module_need() : 0,286 ms - Total 0,286 ms / 1 intvls (Avg 0,286 ms) [0x9b40910] main interface debug: looking for interface module: 1 candidate [0x9b40910] main interface debug: using interface module "globalhotkeys" [0x9b40910] main interface debug: TIMER module_need() : 9,498 ms - Total 9,498 ms / 1 intvls (Avg 9,498 ms) [0x9a818a8] main libvlc: Esecuzione di vlc con l'interfaccia predefinita. Usa 'cvlc' per utilizzare vlc senza interfaccia. [0x9b440b8] main interface debug: looking for interface module: 4 candidates [0x9b440b8] main interface debug: using interface module "qt4" [0x9b440b8] main interface debug: TIMER module_need() : 292,207 ms - Total 292,207 ms / 1 intvls (Avg 292,207 ms) *** LibVLC Exception not handled: Interface initialization failed Set a breakpoint in 'libvlc_exception_not_handled' to debug. [0x9b440b8] qt4 interface debug: Error while initializing qt-specific localization [0xabd037f0] main generic debug: thread ended [0x89a8c38] freetype spu text debug: using fontsize: 2 [0x89a8c38] main spu text debug: using text renderer module "freetype" [0x89a8c38] main spu text debug: TIMER module_need() : 50,986 ms - Total 50,986 ms / 1 intvls (Avg 50,986 ms) [0x89a3c80] main scale debug: looking for video filter2 module: 17 candidates [0x89a3c80] swscale scale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality) [0x89a3c80] main scale debug: using video filter2 module "swscale" [0x89a3c80] main scale debug: TIMER module_need() : 1,246 ms - Total 1,246 ms / 1 intvls (Avg 1,246 ms) [0x8aa8b60] main scale debug: looking for video filter2 module: 17 candidates [0x8aa8b60] yuvp scale debug: YUVP to YUVA converter [0x8aa8b60] main scale debug: using video filter2 module "yuvp" [0x8aa8b60] main scale debug: TIMER module_need() : 0,249 ms - Total 0,249 ms / 1 intvls (Avg 0,249 ms) [0x8aff0b8] main video output debug: window size: 576x320 [0x8aff0b8] main video output debug: looking for video output module: 6 candidates [0x8aff0b8] xvideo video output debug: adaptor 0, port 80, format 0x32315659 (YV12) planar [0x8a3a990] main window debug: looking for xwindow module: 4 candidates [0x8a3a990] qt4 window debug: requesting video... [0x8678428] qt4 interface debug: Video was requested -1, -1 [0x8678428] qt4 interface debug: Video is resizing to: 576 320 [0x8678428] qt4 interface debug: Updating the geometry [0x8a3a990] main window debug: using xwindow module "qt4" [0x8a3a990] main window debug: TIMER module_need() : 2,946 ms - Total 2,946 ms / 1 intvls (Avg 2,946 ms) QPainter::begin: Paint device returned engine == 0, type: 1 QPainter::begin: Paint device returned engine == 0, type: 1 [0x8aff0b8] xvideo video output debug: XShm video extension v1.1 (without pixmaps, opcode: 139) [0x8aff0b8] xvideo video output debug: Window manager supports NetWM [0x8aff0b8] xvideo video output debug: Window manager supports _NET_WM_STATE_FULLSCREEN [0x8aff0b8] xvideo video output debug: Window manager supports _NET_WM_STATE_ABOVE [0x8aff0b8] xvideo video output debug: Window manager supports _NET_WM_STATE_BELOW [0x8aff0b8] main video output debug: using video output module "xvideo" [0x8aff0b8] main video output debug: TIMER module_need() : 76,513 ms - Total 76,513 ms / 1 intvls (Avg 76,513 ms) [0x8aff0b8] main video output debug: Deinterlacing available [0x8aff0b8] main video output debug: got 16 direct buffer(s) [0x8aff0b8] main video output debug: pic render sz 576x320, of (0,0), vsz 576x320, 4cc I420, ar 9:5, sar 1:1, msk r0x0 g0x0 b0x0 [0x8aff0b8] main video output debug: pic in sz 576x320, of (0,0), vsz 576x320, 4cc I420, ar 9:5, sar 1:1, msk r0x0 g0x0 b0x0 [0x8aff0b8] main video output debug: pic out sz 576x320, of (0,0), vsz 576x320, 4cc I420, ar 9:5, sar 1:1, msk r0x0 g0x0 b0x0 [0x8aff0b8] main video output debug: direct render, mapping render pictures 0-14 to system pictures 1-15 [0x8aff0b8] qt4 video output debug: Qt: Entering Fullscreen [0x8a26338] main decoder debug: End of video preroll [0x8a26338] main decoder debug: Received first picture [0x8994930] main input debug: Decoder buffering done in 134 ms [0x899f880] main audio output warning: PTS is out of range (-7311), dropping buffer [0x899f880] main audio output warning: PTS is out of range (-31254), dropping buffer [0x899f880] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x899f880] pulse audio output debug: Pulse stream started [0x89a8c38] freetype spu text debug: using fontsize: 20 [0x89bf5d8] main blend debug: looking for video blending module: 1 candidate [0x89bf5d8] blend blend debug: chroma: YUVA -> I420 [0x89bf5d8] main blend debug: using video blending module "blend" [0x89bf5d8] main blend debug: TIMER module_need() : 0,261 ms - Total 0,261 ms / 1 intvls (Avg 0,261 ms) [0x899f880] main audio output warning: output date isn't PTS date, requesting resampling (123648) [0x899f880] main audio output warning: audio drift is too big (123648), dropping buffer [0x8aff0b8] main video output debug: Post-processing available [0x899f880] main audio output warning: buffer is 99648 late, triggering upsampling [0x899f880] main audio output warning: output date isn't PTS date, requesting resampling (70917) [0x899f880] main audio output warning: audio drift is too big (170398), dropping buffer [0x899f880] main audio output warning: audio drift is too big (146398), dropping buffer [0x899f880] main audio output warning: output date isn't PTS date, requesting resampling (48103) [0x899f880] main audio output warning: audio drift is too big (170501), dropping buffer [0x899f880] main audio output warning: audio drift is too big (146501), dropping buffer [0x899f880] main audio output warning: audio drift is too big (122501), dropping buffer [0x899f880] main audio output warning: output date isn't PTS date, requesting resampling (79838) [0x899f880] main audio output warning: audio drift is too big (178339), dropping buffer [0x899f880] main audio output warning: audio drift is too big (154339), dropping buffer [0x899f880] main audio output warning: output date isn't PTS date, requesting resampling (47905) [0x899f880] main audio output warning: audio drift is too big (178244), dropping buffer [0x899f880] main audio output warning: audio drift is too big (154244), dropping buffer [0x899f880] main audio output warning: audio drift is too big (130244), dropping buffer [0x899f880] main audio output debug: audio output is starving (24844), playing silence [0x899f880] main audio output debug: audio output is starving (26389), playing silence [0x899f880] main audio output debug: audio output is starving (21205), playing silence [0x899f880] main audio output debug: audio output is starving (22183), playing silence [0x899f880] main audio output debug: audio output is starving (24144), playing silence [0x899f880] main audio output debug: audio output is starving (21541), playing silence [0x8994930] main input debug: control type=5 [0x89a6f58] avi demux debug: seek requested: 943 seconds 15% [0x89a6f58] avi demux debug: old:2919583 < new 943570031 [0x89a6f58] avi demux debug: old:2976000 < new 943570031 [0x89a6f58] avi demux debug: seek: 943 seconds [0x8994930] main input debug: Buffering 0% [0x8678428] qt4 interface debug: New caching: 0 [0x8678428] qt4 interface debug: New caching: 0 [0x8994930] main input debug: Buffering 8% [0x8994930] main input debug: Buffering 16% [0x8994930] main input debug: Buffering 25% [0x8994930] main input debug: Buffering 33% [0x8994930] main input debug: Buffering 41% [0x8994930] main input debug: Buffering 50% [0x8994930] main input debug: Buffering 58% [0x8994930] main input debug: Buffering 66% [0x8994930] main input debug: Buffering 75% [0x8994930] main input debug: Buffering 83% [0x8994930] main input debug: Buffering 91% [0x8994930] main input debug: Buffering 100% [0x8994930] main input debug: Stream buffering done (325 ms in 54 ms) [0x8678428] qt4 interface debug: New caching: 100 [0x8678428] qt4 interface debug: New caching: 100 [0x8aa8fa8] main decoder debug: End of audio preroll [0x8a26338] main decoder debug: End of video preroll [0x8a26338] main decoder debug: Received first picture [0x8994930] main input debug: Decoder buffering done in 267 ms [0x899f880] main audio output warning: PTS is out of range (-23329), dropping buffer [0x899f880] main audio output warning: the mixer got a packet in the past (44115) [0x899f880] main audio output warning: the mixer got a packet in the past (20115) [0x899f880] main audio output warning: mixer start isn't output start (7724) [0x899f880] main audio output warning: output date isn't PTS date, requesting resampling (131292) [0x899f880] main audio output warning: audio drift is too big (131459), dropping buffer [0x899f880] main audio output warning: buffer is 107459 late, triggering upsampling [0x899f880] main audio output debug: audio output is starving (21888), playing silence [0x899f880] main audio output debug: audio output is starving (20003), playing silence [0x899f880] main audio output debug: audio output is starving (22529), playing silence [0x899f880] main audio output debug: audio output is starving (20533), playing silence [0x899f880] main audio output debug: audio output is starving (20440), playing silence [0x8994930] main input debug: control type=5 [0x89a6f58] avi demux debug: seek requested: 1640 seconds 27% [0x89a6f58] avi demux debug: old:947363083 < new 1640730011 [0x89a6f58] avi demux debug: old:947424000 < new 1640730011 [0x89a6f58] avi demux debug: seek: 1640 seconds [0x8994930] main input debug: Buffering 0% [0x8678428] qt4 interface debug: New caching: 0 [0x8678428] qt4 interface debug: New caching: 0 [0x8994930] main input debug: Buffering 8% [0x8994930] main input debug: Buffering 16% [0x8994930] main input debug: Buffering 25% [0x8994930] main input debug: Buffering 33% [0x8994930] main input debug: Buffering 41% [0x8994930] main input debug: Buffering 50% [0x8994930] main input debug: Buffering 58% [0x8994930] main input debug: Buffering 66% [0x8994930] main input debug: Buffering 75% [0x8994930] main input debug: Buffering 83% [0x8994930] main input debug: Buffering 91% [0x8994930] main input debug: Buffering 100% [0x8994930] main input debug: Stream buffering done (325 ms in 20 ms) [0x8aa8fa8] main decoder debug: End of audio preroll [0x8678428] qt4 interface debug: New caching: 100 [0x8678428] qt4 interface debug: New caching: 100 [0x8a26338] main decoder debug: End of video preroll [0x8a26338] main decoder debug: Received first picture [0x8994930] main input debug: Decoder buffering done in 100 ms [0x899f880] main audio output warning: PTS is out of range (-15571), dropping buffer [0x899f880] main audio output warning: PTS is out of range (-39539), dropping buffer [0x899f880] mpgatofixed32 audio output debug: libmad error: bad main_data_begin pointer [0x899f880] main audio output warning: the mixer got a packet in the past (208704) [0x899f880] main audio output warning: the mixer got a packet in the past (184704) [0x899f880] main audio output warning: the mixer got a packet in the past (160704) [0x899f880] main audio output warning: the mixer got a packet in the past (136704) [0x899f880] main audio output warning: the mixer got a packet in the past (112704) [0x899f880] main audio output warning: the mixer got a packet in the past (88704) [0x899f880] main audio output warning: the mixer got a packet in the past (64704) [0x899f880] main audio output warning: the mixer got a packet in the past (40704) [0x899f880] main audio output warning: the mixer got a packet in the past (16704) [0x899f880] main audio output warning: mixer start isn't output start (6414) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,413 ms - Total 0,413 ms / 1 intvls (Avg 0,413 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,414 ms - Total 0,414 ms / 1 intvls (Avg 0,414 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,462 ms - Total 0,462 ms / 1 intvls (Avg 0,462 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,427 ms - Total 0,427 ms / 1 intvls (Avg 0,427 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,432 ms - Total 0,432 ms / 1 intvls (Avg 0,432 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,401 ms - Total 0,401 ms / 1 intvls (Avg 0,401 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,413 ms - Total 0,413 ms / 1 intvls (Avg 0,413 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,410 ms - Total 0,410 ms / 1 intvls (Avg 0,410 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "trivial_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,491 ms - Total 0,491 ms / 1 intvls (Avg 0,491 ms) [0x899f880] main audio output debug: removing module "trivial_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,401 ms - Total 0,401 ms / 1 intvls (Avg 0,401 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,406 ms - Total 0,406 ms / 1 intvls (Avg 0,406 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,425 ms - Total 0,425 ms / 1 intvls (Avg 0,425 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,407 ms - Total 0,407 ms / 1 intvls (Avg 0,407 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 1,021 ms - Total 1,021 ms / 1 intvls (Avg 1,021 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,413 ms - Total 0,413 ms / 1 intvls (Avg 0,413 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,375 ms - Total 0,375 ms / 1 intvls (Avg 0,375 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,413 ms - Total 0,413 ms / 1 intvls (Avg 0,413 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,395 ms - Total 0,395 ms / 1 intvls (Avg 0,395 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,403 ms - Total 0,403 ms / 1 intvls (Avg 0,403 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,410 ms - Total 0,410 ms / 1 intvls (Avg 0,410 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,532 ms - Total 0,532 ms / 1 intvls (Avg 0,532 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,524 ms - Total 0,524 ms / 1 intvls (Avg 0,524 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,433 ms - Total 0,433 ms / 1 intvls (Avg 0,433 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,408 ms - Total 0,408 ms / 1 intvls (Avg 0,408 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,436 ms - Total 0,436 ms / 1 intvls (Avg 0,436 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,412 ms - Total 0,412 ms / 1 intvls (Avg 0,412 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,417 ms - Total 0,417 ms / 1 intvls (Avg 0,417 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,414 ms - Total 0,414 ms / 1 intvls (Avg 0,414 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,523 ms - Total 0,523 ms / 1 intvls (Avg 0,523 ms) [0x899f880] main audio output debug: removing module "float32_mixer" [0x899f880] main audio output debug: looking for audio mixer module: 3 candidates [0x899f880] main audio output debug: using audio mixer module "float32_mixer" [0x899f880] main audio output debug: TIMER module_need() : 0,416 ms - Total 0,416 ms / 1 intvls (Avg 0,416 ms) [0x84d28a8] main libvlc debug: deactivating the playlist [0x84f2900] main playlist debug: Deactivate [0x84f2900] main playlist debug: incoming request - stopping current input [0x84f2900] main playlist debug: dying input [0x8994930] main input debug: control type=0 [0x8994930] main input debug: control: stopping input [0x84f2900] main playlist debug: dying input [0x8a26338] avcodec decoder debug: ffmpeg codec (MPEG-4 Video) stopped [0x8a26338] main decoder debug: removing module "avcodec" [0x8a26338] main decoder debug: killing decoder fourcc `mp4v', 0 PES in FIFO [0x8aff0b8] main video output debug: [0] 2 0 [0x8aff0b8] main video output debug: [1] 2 0 [0x8aff0b8] main video output debug: [2] 2 0 [0x8aff0b8] main video output debug: [3] 2 0 [0x8aff0b8] main video output debug: [4] 4 0 [0x8aff0b8] main video output debug: [5] 4 0 [0x8aff0b8] main video output debug: [6] 4 0 [0x8aff0b8] main video output debug: [7] 4 0 [0x8aff0b8] main video output debug: [8] 4 0 [0x8aff0b8] main video output debug: [9] 4 0 [0x8aff0b8] main video output debug: [10] 4 0 [0x8aff0b8] main video output debug: [11] 4 0 [0x8aff0b8] main video output debug: [12] 2 0 [0x8aff0b8] main video output debug: [13] 2 0 [0x8aff0b8] main video output debug: [14] 2 0 [0x8994930] main input debug: saving a free vout [0x8aa8fa8] main decoder debug: removing module "mpeg_audio" [0x8aa8fa8] main decoder debug: killing decoder fourcc `mpga', 0 PES in FIFO [0x8a37ac0] main audio output debug: removing module "mpgatofixed32" [0x89a5c18] main audio filter debug: removing module "scaletempo" [0x8a37bd0] main audio output debug: removing module "bandlimited_resampler" [0x899f880] pulse audio output debug: Pulse Close [0x899f880] main audio output debug: removing module "pulse" [0x899f880] main audio output debug: removing module "float32_mixer" [0x8994930] main input debug: releasing aout [0x89bc810] avi stream debug: free chunk avih [0x89bc810] avi stream debug: free chunk strh [0x89bc810] avi stream debug: free chunk strf [0x89bc810] avi stream debug: free chunk JUNK [0x89bc810] avi stream debug: free chunk LIST [0x89bc810] avi stream debug: free chunk strh [0x89bc810] avi stream debug: free chunk strf [0x89bc810] avi stream debug: free chunk JUNK [0x89bc810] avi stream debug: free chunk LIST [0x89bc810] avi stream warning: unknown chunk (not unloaded) [0x89bc810] avi stream debug: free chunk LIST [0x89bc810] avi stream debug: free chunk LIST [0x89bc810] avi stream debug: free chunk ISFT [0x89bc810] avi stream warning: unknown chunk (not unloaded) [0x89bc810] avi stream debug: free chunk LIST [0x89bc810] avi stream debug: free chunk JUNK [0x89bc810] avi stream debug: free chunk LIST [0x89bc810] avi stream debug: free chunk idx1 [0x89bc810] avi stream debug: free chunk RIFF [0x89bc810] avi stream debug: free chunk JUNK [0x89bc810] avi stream debug: free chunk LIST [0x89a6f58] main demux debug: removing module "avi" [0x89bc810] main stream debug: removing module "stream_filter_record" [0x887eb08] main access debug: removing module "access_file" [0x8994930] main input debug: Program doesn't contain anymore ES [0x8994930] main input debug: thread ended [0x84f2900] main playlist debug: dead input [0x8aff0b8] main video output debug: destroying useless vout [0x8678428] qt4 interface debug: IM: Deleting the input [0x8678428] qt4 interface debug: Updating the geometry [0x8678428] qt4 interface debug: Updating the geometry [0x8aff0b8] qt4 video output debug: Qt: Entering Fullscreen [0x8a3a990] qt4 window debug: releasing video... [0x8678428] qt4 interface debug: Video is not needed anymore [0x8678428] qt4 interface debug: Updating the geometry [0x8a3a990] main window debug: removing module "qt4" [0x8aff0b8] main video output debug: removing module "xvideo" [0x89bf5d8] main blend debug: removing module "blend" [0x89a8c38] main spu text debug: removing module "freetype" [0x8aa8b60] main scale debug: removing module "yuvp" [0x89a3c80] main scale debug: removing module "swscale" [0x84f2900] main playlist debug: saving Raccolta multimediale to file /home/maththias/.local/share/vlc/ml.xspf [0x84f2900] main playlist debug: looking for playlist export module: 1 candidate [0x84f2900] main playlist debug: using playlist export module "export" [0x84f2900] main playlist debug: TIMER module_need() : 0,189 ms - Total 0,189 ms / 1 intvls (Avg 0,189 ms) [0x84f2900] main playlist debug: removing module "export" [0x84f2900] main playlist debug: Deactivated [0x84d28a8] main libvlc debug: removing all services discovery tasks [0x84d28a8] main libvlc debug: removing all interfaces [0x8678428] qt4 interface debug: Quitting the Qt4 Interface [0x8678428] qt4 interface debug: destroying the main Qt4 interface [0x8678428] qt4 interface debug: Destroying the main interface [0x8678428] main interface debug: removing module "qt4" [0x84d2de0] main interface debug: removing module "globalhotkeys" [0x84d2a40] main interface debug: removing module "signals" [0x86745e0] main interface debug: removing module "screensaver" [0x8994930] main input debug: TIMER input launching for 'A Scanner Darkly Un Oscuro Scrutare 2006 [ITA].avi' : 424,967 ms - Total 424,967 ms / 1 intvls (Avg 424,967 ms) [0x8675d28] main interface debug: removing module "inhibit" [0x8675610] main interface debug: removing module "hotkeys" [0x84d28a8] main libvlc debug: removing playlist [0x84f2900] main playlist debug: Destroyed [0x84d28a8] main libvlc debug: TIMER ML Load : Total 22,094 ms / 1 intvls (Avg 22,094 ms) [0x84d28a8] main libvlc debug: TIMER Items array build : Total 0,180 ms / 2 intvls (Avg 0,090 ms) [0x84d28a8] main libvlc debug: TIMER ML Dump : Total 0,370 ms / 1 intvls (Avg 0,370 ms) [0x84d28a8] main libvlc debug: removing stats [0x84d28a8] main libvlc debug: removing module "memcpymmxext" [0x84d28a8] main libvlc debug: writing plugins cache /home/maththias/.cache/vlc/plugins-04041e.dat Interface initialization failed

Pulseaudio's packages version is 0.9.15-3 on debian sid i386

Many thanks for your work danwood.

[Jean-Baptiste Kempf]

Thanks for the report.

[danwood76]

Absolutely no problem!
Pulseaudio is great when it works

[Maththias]

Thanks for the report.

No problem.

Thank you too for your work.