Downmix issue

[rvdboom]

Hi,
because I tend to use fairly recent git versions of x264 and ffmpeg for video creation purposes, I switched also to VLC git recently.
Seemed to work quite well until last week I played a DVD where the sound seemed very... let's say distant.
I that time I thought there was something wrong with the DVD and didn't pay much attention but yesterday, I realized that the files I created with 5.1 sound did also have a trouble with the sound, some of the sounds were strangely muted (the L and R apparently, though I'm not 100% sure). mplayer was not showing the same problem and I recalled playing some of the files some weeks ago without any such issue.
I pull vlc-git again, so I tried with git from yesterday and the problem still showed.
Since I keep some of my older VLC packages, I reverted back to them and found a git pull from the 24/04/2013 to work as expected. Nothing changes between the tests except the VLC package. So I suspect something was changed between this date and probably june (I had a package from june 22 that already failed) that affects how downmix from 5.1 to stereo audio is done.
I'm not a git expert so I don't know how to bisect or whatever and to be honest, I don't have the time right now.
I'm running Slackware64-current (64 bits), with self-compiled VLC, ffmpeg and most multimedia stuff.
Thanks for any feedback!

[Jean-Baptiste Kempf]

Please share the logs

[rvdboom]

Sorry about that.
I'm not quite sure about the logs you want so, as a starter, here are just the outputs on the console. The binaries are not compiled with debug though. I may do it if necessary.
Version git20130424 which plays 5.1 sound fine (I just played a file with such a sound and moved to a moment where the problem is quite obvious) :

Code: Select all

VLC media player 2.1.0-git Rincewind (revision 1.3.0-git-6415-g113f783) [0x1ab4b78] main libvlc warning: This doesn't look like a valid plugins cache [0x1ab4b78] main libvlc warning: cannot load module `/usr/lib64/vlc/plugins/codec/libx264_plugin.so' (libx264.so.132: Ne peut ouvrir le fichier d'objet partagé: Aucun fichier ou dossier de ce type) [0x1ab4b78] main libvlc: Lancement de vlc avec l'interface par défaut. Utilisez « cvlc » pour démarrer VLC sans interface. Fontconfig warning: "/etc/fonts/conf.d/50-user.conf", line 14: reading configurations from ~/.fonts.conf is deprecated. [0x1bf9e78] qt4 interface error: Unable to load extensions module [0x7fafa0c015d8] mp4 demux warning: elst box found [0x7fafa0c015d8] mp4 demux warning: CTTS table [0x7fafa0c015d8] mp4 demux warning: elst box found [0x7fafa13f50a8] faad decoder warning: decoded zero sample Fontconfig warning: FcPattern object size does not accept value "0" Fontconfig warning: FcPattern object size does not accept value "0" Fontconfig warning: FcPattern object size does not accept value "0" Fontconfig warning: FcPattern object size does not accept value "0" [0x7fafa11e6168] avcodec decoder warning: disabling direct rendering [0x1ac9778] alsa audio output warning: device cannot be paused bash-4.2$

and here is with git20130710, where the downmix does not run as expected :

Code: Select all

bash-4.2$ /usr/bin/vlc -v VLC media player 2.2.0-git Weatherwax (revision 2.1.0-git-135-ge7807a9) [0x16acb78] main libvlc warning: This doesn't look like a valid plugins cache [0x16acb78] main libvlc: Lancement de vlc avec l'interface par défaut. Utilisez « cvlc » pour démarrer VLC sans interface. Fontconfig warning: "/etc/fonts/conf.d/50-user.conf", line 14: reading configurations from ~/.fonts.conf is deprecated. [0x17eaec8] qt4 interface error: Unable to load extensions module [0x7f3c98c015d8] mp4 demux warning: elst box found [0x7f3c98c015d8] mp4 demux warning: CTTS table [0x7f3c98c015d8] mp4 demux warning: elst box found Fontconfig warning: FcPattern object size does not accept value "0" Fontconfig warning: FcPattern object size does not accept value "0" [0x7f3c993f50a8] faad decoder warning: decoded zero sample Fontconfig warning: FcPattern object size does not accept value "0" Fontconfig warning: FcPattern object size does not accept value "0" [0x16c3dc8] alsa audio output warning: device cannot be paused [0x7f3c800988d8] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c80001248] main vout display error: Failed to resize display [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c800988d8] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 4001 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 3959 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 3876 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 3876 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 3834 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 3793 ms) [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 13171 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 13129 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 13087 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 13046 ms) [0x7f3c800988d8] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 13018 ms) [0x7f3c840cb878] main video output warning: picture is too late to be displayed (missing 12994 ms) [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c800988d8] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [h264 @ 0x7f3c99265440] mmco: unref short failure [h264 @ 0x7f3c99318760] mmco: unref short failure [0x7f3c991e6138] main decoder warning: early picture skipped [0x7f3c991e6138] main decoder warning: early picture skipped [0x7f3c98c015d8] mp4 demux warning: track[0x2] will be disabled (eof?) [0x7f3c991e6138] main decoder warning: can't get output picture [0x7f3c991e6138] avcodec decoder warning: disabling direct rendering [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c80001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7f3c80001248] main vout display error: Failed to resize display Inconsistency detected by ld.so: dl-close.c: 765: _dl_close: Assertion `map->l_init_called' failed! bash-4.2$

Though I see an issue linking with x264 lib in the first run (normal since I upgraded x264 since then) and many video related messages, it's important to note that the video is properly decoded in both cases. It's just the audio that has this issue.
I can provide you with similar logs when reading a DVD with 5.1 sound, if you want.

[Jean-Baptiste Kempf]

Use -vvv to get all the logs.

[rvdboom]

Here we go.
Good 5.1 audio playback :

Code: Select all

bash-4.2$ vlc -vvv VLC media player 2.1.0-git Rincewind (revision 1.3.0-git-6415-g113f783) [0x840b78] main libvlc debug: VLC media player - 2.1.0-git Rincewind [0x840b78] main libvlc debug: Copyright © 1996-2013 the VideoLAN team [0x840b78] main libvlc debug: revision 1.3.0-git-6415-g113f783 [0x840b78] main libvlc debug: configured with ./configure '--sysconfdir=/etc' '--localstatedir=/var' '--mandir=/usr/man' '--libdir=/usr/lib64' '--build=x86_64-slackware-linux' '--disable-lua' 'build_alias=x86_64-slackware-linux' 'CFLAGS=-O3 -fPIC -march=opteron' 'CXXFLAGS=-O3 -fPIC -march=opteron' 'PKG_CONFIG_PATH=/usr/local/lib64/pkgconfig:/usr/lib64/pkgconfig' [0x840b78] main libvlc debug: searching plug-in modules [0x840b78] main libvlc debug: loading plugins cache file /usr/lib64/vlc/plugins/plugins.dat [0x840b78] main libvlc warning: This doesn't look like a valid plugins cache [0x840b78] main libvlc debug: recursively browsing `/usr/lib64/vlc/plugins' [0x840b78] main libvlc warning: cannot load module `/usr/lib64/vlc/plugins/codec/libx264_plugin.so' (libx264.so.132: Ne peut ouvrir le fichier d'objet partagé: Aucun fichier ou dossier de ce type) [0x840b78] main libvlc debug: saving plugins cache /usr/lib64/vlc/plugins/plugins.dat [0x840b78] main libvlc debug: plug-ins loaded: 375 modules [0x840b78] main libvlc debug: opening config file (/home/vdb/.config/vlc/vlcrc) [0x840b78] main libvlc debug: translation test: code is "fr" [0x840b78] main libvlc debug: CPU has capabilities MMX MMXEXT SSE SSE2 SSE3 SSE4A 3DNow! FPU [0x96cb48] main input debug: Creating an input for 'Bibliothèque' [0x96cb48] main input debug: Input is a meta file: disabling unneeded options [0x96cb48] main input debug: using timeshift granularity of 50 MiB, in path '/tmp' [0x96cb48] main input debug: `file/xspf-open:///home/vdb/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/vdb/.local/share/vlc/ml.xspf' [0x96cb48] main input debug: creating demux: access='file' demux='xspf-open' location='/home/vdb/.local/share/vlc/ml.xspf' file='/home/vdb/.local/share/vlc/ml.xspf' [0x8721e8] main demux debug: looking for access_demux module matching "file": 15 candidates [0x8721e8] main demux debug: no access_demux modules matched [0x96cb48] main input debug: creating access 'file' location='/home/vdb/.local/share/vlc/ml.xspf', path='/home/vdb/.local/share/vlc/ml.xspf' [0x8548f8] main access debug: looking for access module matching "file": 20 candidates [0x8548f8] filesystem access debug: opening file `/home/vdb/.local/share/vlc/ml.xspf' [0x8548f8] main access debug: using access module "filesystem" [0x855778] main stream debug: Using stream method for AStream* [0x855778] main stream debug: starting pre-buffering [0x855778] main stream debug: received first data after 19 ms [0x855778] main stream debug: pre-buffering done 296 bytes in 0s - 14 KiB/s [0x8559d8] main stream debug: looking for stream_filter module matching "any": 9 candidates [0x8559d8] main stream debug: no stream_filter modules matched [0x8559d8] main stream debug: looking for stream_filter module matching "stream_filter_record": 9 candidates [0x8559d8] main stream debug: no stream_filter modules matched [0x96cb48] main input debug: creating demux: access='file' demux='xspf-open' location='/home/vdb/.local/share/vlc/ml.xspf' file='/home/vdb/.local/share/vlc/ml.xspf' [0x8559d8] main demux debug: looking for demux module matching "xspf-open": 56 candidates [0x8559d8] playlist demux debug: using XSPF playlist reader [0x8559d8] main demux debug: using demux module "playlist" [0x858708] main demux meta debug: looking for meta reader module matching "any": 1 candidates [0x858708] main demux meta debug: no meta reader modules matched [0x96cb48] main input debug: `file/xspf-open:///home/vdb/.local/share/vlc/ml.xspf' successfully opened [0x858658] main xml reader debug: looking for xml reader module matching "any": 1 candidates [0x858658] main xml reader debug: using xml reader module "xml" [0x8559d8] playlist demux debug: parsed 0 tracks successfully [0x96cb48] main input debug: EOF reached [0x8559d8] main demux debug: removing module "playlist" [0x8548f8] main access debug: removing module "filesystem" [0x971d98] main playlist debug: creating audio output [0x855778] main audio output debug: looking for audio output module matching "any": 4 candidates [0x855778] alsa audio output debug: Available ALSA PCM devices: [0x855778] alsa audio output debug: Discard all samples (playback) or generate zero samples (capture) (null) [0x855778] alsa audio output debug: HDA ATI SB, ALC889A Analog Default Audio Device (default:CARD=SB) [0x855778] alsa audio output debug: HDA ATI SB, ALC889A Analog Default Audio Device (sysdefault:CARD=SB) [0x855778] alsa audio output debug: HDA ATI SB, ALC889A Analog Front speakers (front:CARD=SB,DEV=0) [0x855778] alsa audio output debug: HDA ATI SB, ALC889A Analog 4.1 Surround output to Front, Rear and Subwoofer speakers (surround41:CARD=SB,DEV=0) [0x855778] alsa audio output debug: HDA ATI SB, ALC889A Analog 5.0 Surround output to Front, Center and Rear speakers (surround50:CARD=SB,DEV=0) [0x855778] alsa audio output debug: HDA ATI SB, ALC889A Analog 7.1 Surround output to Front, Center, Side, Rear and Woofer speakers (surround71:CARD=SB,DEV=0) [0x855778] alsa audio output debug: HDA ATI SB, ALC889A Digital IEC958 (S/PDIF) Digital Audio Output (iec958:CARD=SB,DEV=0) [0x855778] alsa audio output debug: HDA ATI HDMI, HDMI 0 HDMI Audio Output (hdmi:CARD=HDMI,DEV=0) [0x855778] main audio output debug: using audio output module "alsa" [0x971d98] main playlist debug: keeping audio output [0x96cf68] main interface debug: looking for interface module matching "hotkeys,none": 13 candidates [0x96cf68] main interface debug: using interface module "hotkeys" [0x993568] main interface debug: looking for interface module matching "globalhotkeys,none": 13 candidates [0x993568] main interface debug: using interface module "globalhotkeys" [0x85b4e8] main interface debug: looking for interface module matching "dbus,none": 13 candidates [0x85b4e8] dbus interface debug: listening on dbus as: org.mpris.MediaPlayer2.vlc.instance1562 [0x85b4e8] main interface debug: using interface module "dbus" [0x840b78] main libvlc: Lancement de vlc avec l'interface par défaut. Utilisez « cvlc » pour démarrer VLC sans interface. [0x985e58] main interface debug: looking for interface module matching "any": 13 candidates [0x85b4e8] dbus interface debug: Getting property Identity [0x85b4e8] dbus interface debug: Getting All properties [0x85b4e8] dbus interface debug: Getting All properties [0x85b4e8] dbus interface debug: Getting property Volume Fontconfig warning: "/etc/fonts/conf.d/50-user.conf", line 14: reading configurations from ~/.fonts.conf is deprecated. [0x85b4e8] dbus interface debug: Getting All properties [0x85b4e8] dbus interface debug: Getting All properties [0x85b4e8] dbus interface debug: Getting property Identity [0x85b4e8] dbus interface debug: Getting property Volume [0x7ffb20276c58] main generic debug: looking for extension module matching "any": 0 candidates [0x7ffb20276c58] main generic debug: no extension modules [0x985e58] qt4 interface error: Unable to load extensions module [0x985e58] main interface debug: using interface module "qt4" [0x971d98] main playlist debug: adding item `Palmipedarium_HD.mp4' ( file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4 ) [0x985e58] qt4 interface debug: Adding a new MRL to recent ones: file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4 [0x971d98] main playlist debug: processing request item: Palmipedarium_HD.mp4, node: null, skip: 0 [0x971d98] main playlist debug: rebuilding array of current - root Liste de lecture [0x7ffb0c000958] main input debug: Creating an input for 'Palmipedarium_HD.mp4' [0x971d98] main playlist debug: rebuild done - 1 items, index 0 [0x971d98] main playlist debug: starting playback of the new playlist item [0x971d98] main playlist debug: resyncing on Palmipedarium_HD.mp4 [0x971d98] main playlist debug: Palmipedarium_HD.mp4 is at 0 [0x971d98] main playlist debug: creating new input thread [0x7ffb040009b8] main input debug: Creating an input for 'Palmipedarium_HD.mp4' [0x7ffb040009b8] main input debug: using timeshift granularity of 50 MiB, in path '/tmp' [0x7ffb040009b8] main input debug: `file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' gives access `file' demux `' path `/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffb040009b8] main input debug: creating demux: access='file' demux='' location='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' file='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffb08000e68] main demux debug: looking for access_demux module matching "file": 15 candidates [0x7ffb08000e68] main demux debug: no access_demux modules matched [0x7ffb040009b8] main input debug: creating access 'file' location='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4', path='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffb080011c8] main access debug: looking for access module matching "file": 20 candidates [0x7ffb080011c8] filesystem access debug: opening file `/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffb080011c8] main access debug: using access module "filesystem" [0x7ffb08001348] main stream debug: Using stream method for AStream* [0x7ffb08001348] main stream debug: starting pre-buffering [0x7ffb08001348] main stream debug: received first data after 0 ms [0x7ffb08001348] main stream debug: pre-buffering done 1024 bytes in 0s - 1000000 KiB/s [0x7ffb08c015d8] main stream debug: looking for stream_filter module matching "any": 9 candidates [0x7ffb08c015d8] main stream debug: no stream_filter modules matched [0x7ffb08c015d8] main stream debug: looking for stream_filter module matching "stream_filter_record": 9 candidates [0x7ffb08c015d8] main stream debug: no stream_filter modules matched [0x7ffb040009b8] main input debug: creating demux: access='file' demux='' location='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' file='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffb08c017d8] main demux debug: looking for demux module matching "mp4": 56 candidates [0x7ffb08001348] mp4 stream debug: dumping root Box "root" [0x7ffb08001348] mp4 stream debug: | + ftyp size 32 [0x7ffb08001348] mp4 stream debug: | + free size 8 [0x7ffb08001348] mp4 stream debug: | + mdat size 177224812 [0x7ffb08001348] mp4 stream debug: | + moov size 430377 [0x7ffb08001348] mp4 stream debug: | | + mvhd size 108 [0x7ffb08001348] mp4 stream debug: | | + trak size 237010 [0x7ffb08001348] mp4 stream debug: | | | + tkhd size 92 [0x7ffb08001348] mp4 stream debug: | | | + edts size 36 [0x7ffb08001348] mp4 stream debug: | | | | + elst size 28 [0x7ffb08001348] mp4 stream debug: | | | + mdia size 236874 [0x7ffb08001348] mp4 stream debug: | | | | + mdhd size 32 [0x7ffb08001348] mp4 stream debug: | | | | + hdlr size 45 [0x7ffb08001348] mp4 stream debug: | | | | + minf size 236789 [0x7ffb08001348] mp4 stream debug: | | | | | + vmhd size 20 [0x7ffb08001348] mp4 stream debug: | | | | | + dinf size 36 [0x7ffb08001348] mp4 stream debug: | | | | | | + dref size 28 [0x7ffb08001348] mp4 stream debug: | | | | | | | + url size 12 [0x7ffb08001348] mp4 stream debug: | | | | | + stbl size 236725 [0x7ffb08001348] mp4 stream debug: | | | | | | + stsd size 153 [0x7ffb08001348] mp4 stream debug: | | | | | | | + avc1 size 137 [0x7ffb08001348] mp4 stream debug: | | | | | | | | + avcC size 51 [0x7ffb08001348] mp4 stream debug: | | | | | | + stts size 24 [0x7ffb08001348] mp4 stream debug: | | | | | | + stss size 560 [0x7ffb08001348] mp4 stream debug: | | | | | | + ctts size 117640 [0x7ffb08001348] mp4 stream debug: | | | | | | + stsc size 40 [0x7ffb08001348] mp4 stream debug: | | | | | | + stsz size 59156 [0x7ffb08001348] mp4 stream debug: | | | | | | + stco size 59144 [0x7ffb08001348] mp4 stream debug: | | + trak size 193056 [0x7ffb08001348] mp4 stream debug: | | | + tkhd size 92 [0x7ffb08001348] mp4 stream debug: | | | + edts size 36 [0x7ffb08001348] mp4 stream debug: | | | | + elst size 28 [0x7ffb08001348] mp4 stream debug: | | | + mdia size 192920 [0x7ffb08001348] mp4 stream debug: | | | | + mdhd size 32 [0x7ffb08001348] mp4 stream debug: | | | | + hdlr size 45 [0x7ffb08001348] mp4 stream debug: | | | | + minf size 192835 [0x7ffb08001348] mp4 stream debug: | | | | | + smhd size 16 [0x7ffb08001348] mp4 stream debug: | | | | | + dinf size 36 [0x7ffb08001348] mp4 stream debug: | | | | | | + dref size 28 [0x7ffb08001348] mp4 stream debug: | | | | | | | + url size 12 [0x7ffb08001348] mp4 stream debug: | | | | | + stbl size 192775 [0x7ffb08001348] mp4 stream debug: | | | | | | + stsd size 103 [0x7ffb08001348] mp4 stream debug: | | | | | | | + mp4a size 87 [0x7ffb08001348] mp4 stream debug: | | | | | | | | + esds size 51 [0x7ffb08001348] mp4 stream debug: | | | | | | + stts size 32 [0x7ffb08001348] mp4 stream debug: | | | | | | + stsc size 16648 [0x7ffb08001348] mp4 stream debug: | | | | | | + stsz size 116840 [0x7ffb08001348] mp4 stream debug: | | | | | | + stco size 59144 [0x7ffb08001348] mp4 stream debug: | | + udta size 195 [0x7ffb08001348] mp4 stream debug: | | | + meta size 187 [0x7ffb08001348] mp4 stream debug: | | | | + hdlr size 33 [0x7ffb08001348] mp4 stream debug: | | | | + ilst size 142 [0x7ffb08001348] mp4 stream debug: | | | | | + cnam size 37 [0x7ffb08001348] mp4 stream debug: | | | | | + ctoo size 36 [0x7ffb08001348] mp4 stream debug: | | | | | + ccmt size 61 [0x7ffb08c017d8] mp4 demux debug: ISO Media file (isom) version 512. [0x7ffb08c017d8] mp4 demux debug: found 2 tracks [0x985e58] qt4 interface debug: IM: Setting an input [0x85b4e8] dbus interface debug: Getting All properties [0x7ffb08c017d8] mp4 demux warning: elst box found [0x7ffb08c017d8] mp4 demux debug: - [0] duration=616000ms media time=83ms) rate=1.0 [0x85b4e8] dbus interface debug: Getting All properties [0x85b4e8] dbus interface debug: Getting All properties [0x85b4e8] dbus interface debug: Getting All properties [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] read 14782 chunk [0x7ffb08c017d8] mp4 demux warning: CTTS table [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] read 14784 samples length:616s [0x7ffb040009b8] main input debug: selecting program id=0 [0x7ffb08c017d8] mp4 demux debug: adding track[Id 0x1] video (enable) language fre [0x7ffb08c017d8] mp4 demux warning: elst box found [0x7ffb08c017d8] mp4 demux debug: - [0] duration=623024ms media time=0ms) rate=1.0 [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] read 14782 chunk [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] read 29205 samples length:623s [0x7ffb08c017d8] mp4 demux debug: adding track[Id 0x2] audio (enable) language fre [0x7ffb08c017d8] main demux debug: using demux module "mp4" [0x7ffb040009b8] main input debug: looking for a subtitle file in /home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/ [0x7ffb091e5fb8] main decoder debug: looking for decoder module matching "any": 36 candidates [0x971d98] main playlist debug: no fetch required for Palmipedarium (art currently (null)) [0x7ffb091e5fb8] avcodec decoder debug: trying to use direct rendering [0x7ffb091e5fb8] avcodec decoder debug: allowing 4 thread(s) for decoding [0x7ffb091e5fb8] avcodec decoder debug: avcodec codec (H264 - MPEG-4 AVC (part 10)) started [0x7ffb091e5fb8] avcodec decoder debug: using frame thread mode with 4 threads [0x7ffb091e5fb8] main decoder debug: using decoder module "avcodec" [0x7ffb093f52b8] main decoder debug: looking for decoder module matching "any": 36 candidates [0x7ffb093f52b8] main decoder debug: using decoder module "faad" [0x7ffb093fa368] main demux meta debug: looking for meta reader module matching "any": 1 candidates [0x7ffb093fa368] main demux meta debug: using meta reader module "taglib" [0x7ffb08c017d8] main demux debug: removing module "taglib" [0x7ffb040009b8] main input debug: `file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' successfully opened [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 0ms (movie)-> 83ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffb08c017d8] mp4 demux debug: stts gives 2 --> 0 (sample number) [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 0ms (movie)-> 0ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb093f52b8] faad decoder warning: decoded zero sample [0x7ffb040009b8] main input debug: Buffering 33% [0x971d98] main playlist debug: reusing audio output [0x7ffb040009b8] main input debug: Buffering 66% [0x7ffb040009b8] main input debug: Buffering 100% [0x7ffb0c002358] main spu text debug: looking for text renderer module matching "any": 3 candidates [0x7ffb0c002358] freetype spu text debug: Building font databases. [0x7ffb0c002358] freetype spu text debug: Took 0 microseconds Fontconfig warning: FcPattern object size does not accept value "0" [0x7ffb040009b8] main input debug: Stream buffering done (400 ms in 8 ms) Fontconfig warning: FcPattern object size does not accept value "0" [0x855778] alsa audio output debug: using ALSA device: default [0x855778] alsa audio output debug: Plug PCM: Soft volume PCM Control: PCM Playback Volume min_dB: -51 max_dB: 0 resolution: 256 Slave: Direct Stream Mixing PCM Hardware PCM card 0 'HDA ATI SB' device 0 subdevice 0 Its setup is: stream : PLAYBACK access : MMAP_INTERLEAVED format : S32_LE subformat : STD channels : 2 rate : 48000 exact rate : 48000 (48000/1) msbits : 32 buffer_size : 8192 period_size : 1024 period_time : 21333 tstamp_mode : ENABLE period_step : 1 avail_min : 1024 period_event : 0 start_threshold : 1 stop_threshold : 0 silence_threshold: 0 silence_size : 0 boundary : 4611686018427387904 appl_ptr : 0 hw_ptr : 7967911 [0x855778] alsa audio output debug: device name : ALC889A Analog [0x855778] alsa audio output debug: device ID : ALC889A Analog [0x855778] alsa audio output debug: subdevice name: subdevice #0 [0x7ffb0c002358] freetype spu text debug: Using Serif Bold as font from file /usr/share/fonts/TTF/DejaVuSans.ttf [0x855778] alsa audio output debug: initial hardware setup: ACCESS: MMAP_INTERLEAVED MMAP_NONINTERLEAVED MMAP_COMPLEX RW_INTERLEAVED RW_NONINTERLEAVED FORMAT: S8 U8 S16_LE S16_BE U16_LE U16_BE S24_LE S24_BE U24_LE U24_BE S32_LE S32_BE U32_LE U32_BE FLOAT_LE FLOAT_BE FLOAT64_LE FLOAT64_BE MU_LAW A_LAW IMA_ADPCM S24_3LE S24_3BE U24_3LE U24_3BE S20_3LE S20_3BE U20_3LE U20_3BE S18_3LE S18_3BE U18_3LE U18_3BE SUBFORMAT: STD SAMPLE_BITS: [4 64] FRAME_BITS: [4 640000] CHANNELS: [1 10000] RATE: 48000 PERIOD_TIME: (21333 21334) PERIOD_SIZE: 1024 PERIOD_BYTES: [512 81920000] PERIODS: [2 8] BUFFER_TIME: (42666 170667) BUFFER_SIZE: [2048 8192] BUFFER_BYTES: [1024 655360000] TICK_TIME: ALL [0x7ffb0c002358] freetype spu text debug: using fontsize: 2 [0x7ffb0c002358] main spu text debug: using text renderer module "freetype" [0x855778] alsa audio output debug: downmixing from 6 to 2 channels [0x855778] alsa audio output debug: final HW setup: ACCESS: RW_INTERLEAVED FORMAT: FLOAT_LE SUBFORMAT: STD SAMPLE_BITS: 32 FRAME_BITS: 64 CHANNELS: 2 RATE: 48000 PERIOD_TIME: (21333 21334) PERIOD_SIZE: 1024 PERIOD_BYTES: 8192 PERIODS: 8 BUFFER_TIME: (170666 170667) BUFFER_SIZE: 8192 BUFFER_BYTES: 65536 TICK_TIME: 0 [0x855778] alsa audio output debug: initial software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1024 start_threshold: 1 stop_threshold: 8192 silence_threshold: 0 silence_size: 0 boundary: 4611686018427387904 [0x855778] alsa audio output debug: final software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1024 start_threshold: 1 stop_threshold: 8192 silence_threshold: 0 silence_size: 0 boundary: 4611686018427387904 [0x855778] alsa audio output warning: device cannot be paused [0x855778] main audio output debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes [0x7ffaf0012e38] main volume debug: looking for audio volume module matching "any": 2 candidates [0x7ffaf0012e38] main volume debug: using audio volume module "float_mixer" [0x855778] main audio output debug: input 'f32l' 48000 Hz 3F2R/LFE frame=1 samples/24 bytes [0x7ffaf00386a8] main audio filter debug: looking for audio filter module matching "scaletempo": 14 candidates [0x7ffaf00386a8] scaletempo audio filter debug: format: 48000 rate, 6 nch, 4 bps, fl32 [0x7ffaf00386a8] scaletempo audio filter debug: params: 30 stride, 0.200 overlap, 14 search [0x7ffaf00386a8] scaletempo audio filter debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode [0x7ffaf00386a8] main audio filter debug: using audio filter module "scaletempo" [0x855778] main audio output debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE [0x855778] main audio output debug: conversion pipeline complete [0x855778] main audio output debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->Stereo [0x7ffaf0039368] main audio converter debug: looking for audio converter module matching "any": 10 candidates [0x7ffaf0039368] main audio converter debug: using audio converter module "simple_channel_mixer" [0x855778] main audio output debug: conversion pipeline complete [0x7ffb0cfb4118] main scale debug: looking for video filter2 module matching "any": 56 candidates [0x7ffaf00637c8] main audio resampler debug: looking for audio resampler module matching "any": 2 candidates [0x7ffaf00637c8] main audio resampler debug: using audio resampler module "samplerate" [0x7ffb093f52b8] main decoder debug: End of audio preroll [0x7ffb0cfb4118] swscale scale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) [0x7ffb0cfb4118] main scale debug: using video filter2 module "swscale" [0x7ffb0cfd1578] main scale debug: looking for video filter2 module matching "any": 56 candidates [0x7ffb0cfd1578] yuvp scale debug: YUVP to YUVA converter [0x7ffb0cfd1578] main scale debug: using video filter2 module "yuvp" [0x7ffb0c001388] main video output debug: Deinterlacing available [0x7ffb0c001388] main video output debug: deinterlace 0, mode blend, is_needed 0 [0x7ffb0c001388] main video output debug: Opening vout display wrapper [0x7ffaec001298] main vout display debug: looking for vout display module matching "any": 11 candidates [0x7ffaec004178] main window debug: looking for vout window xid module matching "qt4,any": 4 candidates [0x7ffaec004178] qt4 window debug: requesting video window... [0x985e58] qt4 interface debug: Video was requested 0, 0 [0x7ffaec004178] main window debug: using vout window xid module "qt4" [0x7ffaec0043e8] main inhibit debug: looking for inhibit module matching "any": 2 candidates [0x7ffaec0043e8] dbus_screensaver inhibit debug: found service org.freedesktop.ScreenSaver [0x7ffaec0043e8] main inhibit debug: using inhibit module "dbus_screensaver" [0x7ffaec001298] xcb_xv vout display debug: connected to X11.0 server [0x7ffaec001298] xcb_xv vout display debug: vendor : The X.Org Foundation [0x7ffaec001298] xcb_xv vout display debug: version: 11400000 [0x7ffaec001298] xcb_xv vout display debug: using screen 0x2c5 [0x7ffaec001298] xcb_xv vout display debug: using XVideo extension v2.2 [0x7ffaec001298] xcb_xv vout display debug: using adaptor Radeon Textured Video [0x7ffaec001298] xcb_xv vout display debug: using port 63 [0x7ffaec001298] xcb_xv vout display debug: using image format 0x30323449 [0x7ffaec001298] xcb_xv vout display debug: using X11 visual ID 0x21 (depth: 24) [0x7ffaec001298] xcb_xv vout display debug: using X11 window 0x09a00000 [0x7ffaec001298] xcb_xv vout display debug: using X11 graphic context 0x09a00002 [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'fullscreen' 0 [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1920x1080 window [0x7ffaec001298] main vout display debug: using vout display module "xcb_xv" [0x7ffb0c001388] main video output debug: original format sz 1920x1080, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 [0x7ffb0c002358] main spu text debug: removing module "freetype" [0x7ffb0c002358] main spu text debug: looking for text renderer module matching "any": 3 candidates [0x7ffb0c002358] freetype spu text debug: Building font databases. [0x7ffb0c002358] freetype spu text debug: Took 0 microseconds Fontconfig warning: FcPattern object size does not accept value "0" Fontconfig warning: FcPattern object size does not accept value "0" [0x7ffb0c002358] freetype spu text debug: Using Serif Bold as font from file /usr/share/fonts/TTF/DejaVuSans.ttf [0x7ffb0c002358] freetype spu text debug: using fontsize: 2 [0x7ffb0c002358] main spu text debug: using text renderer module "freetype" [0x7ffb091e5fb8] avcodec decoder warning: disabling direct rendering [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] xcb_xv vout display debug: unhandled event 18 [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1794x973 window [0x7ffaec001298] xcb_xv vout display debug: unhandled event 19 [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffb091e5fb8] main decoder debug: End of video preroll [0x7ffb091e5fb8] main decoder debug: Received first picture [0x7ffaec012638] main blend debug: looking for video blending module matching "any": 1 candidates [0x7ffaec012638] main blend debug: using video blending module "blend" [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffb040009b8] main input debug: Decoder buffering done in 275 ms [0x855778] main audio output debug: inserting 1455 zeroes [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 2 ms) [0x7ffaec001298] main vout display debug: auto hiding mouse cursor [0x7ffb0c001388] main video output warning: picture is too late to be displayed (missing 53 ms) [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 12 ms) [0x7ffaec001298] main vout display debug: auto hiding mouse cursor [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 1 ms) [0x7ffaec001298] main vout display debug: auto hiding mouse cursor [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1818x987 window [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 4 ms) [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1794x973 window [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 18 ms) [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1782x973 window [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 15 ms) [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1738x938 window [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1278x714 window [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 1 ms) [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffaec001298] main vout display debug: VoutDisplayEvent 'resize' 1236x701 window [0x7ffaec001298] xcb_xv vout display debug: display is visible [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 2 ms) [0x7ffaec001298] main vout display debug: auto hiding mouse cursor [0x7ffb0c001388] main video output warning: picture is too late to be displayed (missing 27 ms) [0x7ffb0c001388] main video output warning: picture is too late to be displayed (missing 20 ms) [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 15 ms) [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 2 ms) [0x7ffaec001298] main vout display debug: auto hiding mouse cursor [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 484089ms (movie)-> 484172ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffb08c017d8] mp4 demux debug: stts gives 11620 --> 11613 (sample number) [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 484089ms (movie)-> 484089ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 33% [0x7ffb093f52b8] main decoder debug: End of audio preroll [0x7ffb040009b8] main input debug: Buffering 66% [0x7ffb040009b8] main input debug: Buffering 99% [0x7ffb040009b8] main input debug: Stream buffering done (399 ms in 22 ms) [0x7ffb091e5fb8] main decoder debug: End of video preroll [0x7ffb091e5fb8] main decoder debug: Received first picture [0x7ffb040009b8] main input debug: Decoder buffering done in 199 ms [0x7ffb093f52b8] main decoder debug: discarded audio buffer [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 474121ms (movie)-> 474204ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffb08c017d8] mp4 demux debug: stts gives 11380 --> 11268 (sample number) [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 474121ms (movie)-> 474121ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb093f52b8] main decoder debug: End of audio preroll [0x7ffb040009b8] main input debug: Buffering 33% [0x7ffb040009b8] main input debug: Buffering 66% [0x7ffb040009b8] main input debug: Buffering 99% [0x7ffb040009b8] main input debug: Stream buffering done (399 ms in 35 ms) [0x7ffb091e5fb8] main decoder debug: End of video preroll [0x7ffb091e5fb8] main decoder debug: Received first picture [0x7ffb040009b8] main input debug: Decoder buffering done in 1178 ms [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 16 ms) [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 471006ms (movie)-> 471089ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffb08c017d8] mp4 demux debug: stts gives 11306 --> 11268 (sample number) [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 471006ms (movie)-> 471006ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 33% [0x7ffb093f52b8] main decoder debug: End of audio preroll [0x7ffb040009b8] main input debug: Buffering 66% [0x7ffb040009b8] main input debug: Buffering 99% [0x7ffb040009b8] main input debug: Stream buffering done (399 ms in 5 ms) [0x7ffb091e5fb8] main decoder debug: End of video preroll [0x7ffb091e5fb8] main decoder debug: Received first picture [0x7ffb040009b8] main input debug: Decoder buffering done in 447 ms [0x7ffb091e5fb8] main decoder warning: early picture skipped [0x7ffb091e5fb8] main decoder warning: early picture skipped [0x7ffb091e5fb8] main decoder warning: early picture skipped [0x7ffb091e5fb8] main decoder warning: early picture skipped [0x7ffb091e5fb8] main decoder warning: early picture skipped [0x7ffb093f52b8] main decoder debug: discarded audio buffer [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 471006ms (movie)-> 471089ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffb08c017d8] mp4 demux debug: stts gives 11306 --> 11268 (sample number) [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 471006ms (movie)-> 471006ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb093f52b8] main decoder debug: End of audio preroll [0x7ffb040009b8] main input debug: Buffering 33% [0x7ffb040009b8] main input debug: Buffering 66% [0x7ffb040009b8] main input debug: Buffering 99% [0x7ffb040009b8] main input debug: Stream buffering done (399 ms in 6 ms) [0x7ffb091e5fb8] main decoder debug: End of video preroll [0x7ffb091e5fb8] main decoder debug: Received first picture [0x7ffb040009b8] main input debug: Decoder buffering done in 436 ms [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 462283ms (movie)-> 462367ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffb08c017d8] mp4 demux debug: stts gives 11096 --> 11016 (sample number) [0x7ffb08c017d8] mp4 demux debug: elst (0) gives 462283ms (movie)-> 462283ms (track) [0x7ffb08c017d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 0% [0x7ffb040009b8] main input debug: Buffering 33% [0x7ffb040009b8] main input debug: Buffering 66% [0x7ffb040009b8] main input debug: Buffering 99% [0x7ffb040009b8] main input debug: Stream buffering done (399 ms in 27 ms) [0x7ffb093f52b8] main decoder debug: End of audio preroll [0x7ffb091e5fb8] main decoder debug: End of video preroll [0x7ffb091e5fb8] main decoder debug: Received first picture [0x7ffb040009b8] main input debug: Decoder buffering done in 815 ms [0x7ffb0c001388] main video output debug: picture might be displayed late (missing 2 ms) [0x7ffaec001298] main vout display debug: auto hiding mouse cursor [0x7ffaec001298] main vout display debug: auto hiding mouse cursor [0x840b78] main libvlc debug: exiting [0x840b78] main libvlc debug: removing all interfaces [0x840b78] main libvlc debug: exiting [0x985e58] main interface debug: removing module "qt4" [0x971d98] main playlist debug: deactivating the playlist [0x971d98] main playlist debug: incoming request - stopping current input [0x7ffb040009b8] main input debug: control: stopping input [0x971d98] main playlist debug: incoming request - stopping current input [0x971d98] main playlist debug: finished input [0x7ffb091e5fb8] main decoder debug: removing module "avcodec" [0x7ffb091e5fb8] avcodec decoder debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped [0x7ffb091e5fb8] main decoder debug: killing decoder fourcc `h264', 0 PES in FIFO [0x971d98] main playlist debug: saving a free vout [0x971d98] main playlist debug: reusing provided vout [0x7ffb093f52b8] main decoder debug: removing module "faad" [0x7ffb093f52b8] main decoder debug: killing decoder fourcc `mp4a', 19 PES in FIFO [0x7ffaf00637c8] main audio resampler debug: removing module "samplerate" [0x7ffaf00386a8] main audio filter debug: removing module "scaletempo" [0x7ffaf0039368] main audio converter debug: removing module "simple_channel_mixer" [0x7ffaf0012e38] main volume debug: removing module "float_mixer" [0x971d98] main playlist debug: keeping audio output [0x7ffb08c017d8] main demux debug: removing module "mp4" [0x7ffb08c017d8] mp4 demux debug: freeing all memory [0x7ffb080011c8] main access debug: removing module "filesystem" [0x7ffb040009b8] main input debug: Program doesn't contain anymore ES [0x971d98] main playlist debug: incoming request - stopping current input [0x971d98] main playlist debug: dead input [0x855778] main audio output debug: removing module "alsa" [0x985e58] qt4 interface debug: IM: Deleting the input [0x7ffb0c001388] main video output debug: destroying useless vout [0x7ffaec012638] main blend debug: removing module "blend" [0x7ffaec001298] main vout display debug: removing module "xcb_xv" [0x7ffaec0043e8] dbus_screensaver inhibit debug: got cookie 5243 [0x7ffaec0043e8] main inhibit debug: removing module "dbus_screensaver" [0x7ffaec004178] qt4 window debug: releasing video... [0x985e58] qt4 interface debug: Video is not needed anymore [0x7ffb0c002358] main spu text debug: removing module "freetype" [0x7ffb0cfd1578] main scale debug: removing module "yuvp" [0x7ffb0cfb4118] main scale debug: removing module "swscale" [0x985e58] qt4 interface debug: requesting exit... [0x985e58] qt4 interface debug: waiting for UI thread... [0x985e58] qt4 interface debug: QApp exec() finished [0x985e58] qt4 interface debug: Video is not needed anymore [0x985e58] qt4 interface debug: Killing extension dialog provider [0x85b4e8] main interface debug: removing module "dbus" [0x993568] main interface debug: removing module "globalhotkeys" [0x96cf68] main interface debug: removing module "hotkeys" [0x971d98] main playlist debug: destroying [0x96e2f8] main playlist export debug: saving Bibliothèque to file /home/vdb/.local/share/vlc/ml.xspf [0x96e2f8] main playlist export debug: looking for playlist export module matching "export-xspf": 4 candidates [0x96e2f8] main playlist export debug: using playlist export module "export" [0x96e2f8] main playlist export debug: removing module "export" [0x840b78] main libvlc debug: removing stats bash-4.2$

I split it into 2 messages, as I reached the limit of allowed characters in a message.

[rvdboom]

Bad 5.1 audio playback :

Code: Select all

bash-4.2$ vlc -vvv [0x110ab78] main libvlc debug: VLC media player - 2.2.0-git Weatherwax [0x110ab78] main libvlc debug: Copyright © 1996-2013 the VideoLAN team [0x110ab78] main libvlc debug: revision 2.1.0-git-135-ge7807a9 [0x110ab78] main libvlc debug: configured with ./configure '--prefix=/usr' '--sysconfdir=/etc' '--localstatedir=/var' '--mandir=/usr/man' '--libdir=/usr/lib64' '--build=x86_64-slackware-linux' '--disable-lua' 'build_alias=x86_64-slackware-linux' 'CFLAGS=-O3 -fPIC -march=opteron' 'CXXFLAGS=-O3 -fPIC -march=opteron' 'PKG_CONFIG_PATH=/usr/local/lib64/pkgconfig:/usr/lib64/pkgconfig' [0x110ab78] main libvlc debug: searching plug-in modules [0x110ab78] main libvlc debug: loading plugins cache file /usr/lib64/vlc/plugins/plugins.dat [0x110ab78] main libvlc warning: This doesn't look like a valid plugins cache [0x110ab78] main libvlc debug: recursively browsing `/usr/lib64/vlc/plugins' [0x110ab78] main libvlc debug: saving plugins cache /usr/lib64/vlc/plugins/plugins.dat [0x110ab78] main libvlc debug: plug-ins loaded: 384 modules [0x110ab78] main libvlc debug: opening config file (/home/vdb/.config/vlc/vlcrc) [0x110ab78] main libvlc debug: translation test: code is "fr" [0x110ab78] main libvlc debug: CPU has capabilities MMX MMXEXT SSE SSE2 SSE3 SSE4A 3DNow! FPU [0x113cea8] main input debug: Creating an input for 'Bibliothèque' [0x113cea8] main input debug: Input is a meta file: disabling unneeded options [0x113cea8] main input debug: using timeshift granularity of 50 MiB, in path '/tmp' [0x113cea8] main input debug: `file/xspf-open:///home/vdb/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/vdb/.local/share/vlc/ml.xspf' [0x113cea8] main input debug: creating demux: access='file' demux='xspf-open' location='/home/vdb/.local/share/vlc/ml.xspf' file='/home/vdb/.local/share/vlc/ml.xspf' [0x124dcd8] main demux debug: looking for access_demux module matching "file": 15 candidates [0x124dcd8] main demux debug: no access_demux modules matched [0x113cea8] main input debug: creating access 'file' location='/home/vdb/.local/share/vlc/ml.xspf', path='/home/vdb/.local/share/vlc/ml.xspf' [0x1121118] main access debug: looking for access module matching "file": 20 candidates [0x1121118] filesystem access debug: opening file `/home/vdb/.local/share/vlc/ml.xspf' [0x1121118] main access debug: using access module "filesystem" [0x1121eb8] main stream debug: Using stream method for AStream* [0x1121eb8] main stream debug: starting pre-buffering [0x1121eb8] main stream debug: received first data after 0 ms [0x1121eb8] main stream debug: pre-buffering done 296 bytes in 0s - 289062 KiB/s [0x1122118] main stream debug: looking for stream_filter module matching "any": 9 candidates [0x1122118] main stream debug: no stream_filter modules matched [0x1122118] main stream debug: looking for stream_filter module matching "stream_filter_record": 9 candidates [0x1122118] main stream debug: no stream_filter modules matched [0x113cea8] main input debug: creating demux: access='file' demux='xspf-open' location='/home/vdb/.local/share/vlc/ml.xspf' file='/home/vdb/.local/share/vlc/ml.xspf' [0x1122118] main demux debug: looking for demux module matching "xspf-open": 56 candidates [0x1122118] playlist demux debug: using XSPF playlist reader [0x1122118] main demux debug: using demux module "playlist" [0x1127638] main demux meta debug: looking for meta reader module matching "any": 1 candidates [0x1127638] main demux meta debug: no meta reader modules matched [0x113cea8] main input debug: `file/xspf-open:///home/vdb/.local/share/vlc/ml.xspf' successfully opened [0x1127588] main xml reader debug: looking for xml reader module matching "any": 1 candidates [0x1127588] main xml reader debug: using xml reader module "xml" [0x1122118] playlist demux debug: parsed 0 tracks successfully [0x113cea8] main input debug: EOF reached [0x1122118] main demux debug: removing module "playlist" [0x1121118] main access debug: removing module "filesystem" [0x12449b8] main playlist debug: creating audio output [0x1121eb8] main audio output debug: looking for audio output module matching "any": 4 candidates [0x1121eb8] alsa audio output debug: Available ALSA PCM devices: [0x1121eb8] alsa audio output debug: Discard all samples (playback) or generate zero samples (capture) (null) [0x1121eb8] alsa audio output debug: HDA ATI SB, ALC889A Analog Default Audio Device (default:CARD=SB) [0x1121eb8] alsa audio output debug: HDA ATI SB, ALC889A Analog Default Audio Device (sysdefault:CARD=SB) [0x1121eb8] alsa audio output debug: HDA ATI SB, ALC889A Analog Front speakers (front:CARD=SB,DEV=0) [0x1121eb8] alsa audio output debug: HDA ATI SB, ALC889A Analog 4.1 Surround output to Front, Rear and Subwoofer speakers (surround41:CARD=SB,DEV=0) [0x1121eb8] alsa audio output debug: HDA ATI SB, ALC889A Analog 5.0 Surround output to Front, Center and Rear speakers (surround50:CARD=SB,DEV=0) [0x1121eb8] alsa audio output debug: HDA ATI SB, ALC889A Analog 7.1 Surround output to Front, Center, Side, Rear and Woofer speakers (surround71:CARD=SB,DEV=0) [0x1121eb8] alsa audio output debug: HDA ATI SB, ALC889A Digital IEC958 (S/PDIF) Digital Audio Output (iec958:CARD=SB,DEV=0) [0x1121eb8] alsa audio output debug: HDA ATI HDMI, HDMI 0 HDMI Audio Output (hdmi:CARD=HDMI,DEV=0) [0x1121eb8] main audio output debug: using audio output module "alsa" [0x12449b8] main playlist debug: keeping audio output [0x12ac268] main interface debug: looking for interface module matching "hotkeys,none": 14 candidates [0x12ac268] main interface debug: using interface module "hotkeys" [0x124d788] main interface debug: looking for interface module matching "globalhotkeys,none": 14 candidates [0x124d788] main interface debug: using interface module "globalhotkeys" [0x124db98] main interface debug: looking for interface module matching "dbus,none": 14 candidates [0x124db98] dbus interface debug: listening on dbus as: org.mpris.MediaPlayer2.vlc.instance3802 [0x124db98] main interface debug: using interface module "dbus" [0x110ab78] main libvlc: Lancement de vlc avec l'interface par défaut. Utilisez « cvlc » pour démarrer VLC sans interface. [0x1275d18] main interface debug: looking for interface module matching "any": 14 candidates [0x124db98] dbus interface debug: Getting property Identity [0x124db98] dbus interface debug: Getting All properties [0x124db98] dbus interface debug: Getting All properties [0x124db98] dbus interface debug: Getting property Volume [0x124db98] dbus interface debug: Getting All properties [0x124db98] dbus interface debug: Getting All properties Fontconfig warning: "/etc/fonts/conf.d/50-user.conf", line 14: reading configurations from ~/.fonts.conf is deprecated. [0x124db98] dbus interface debug: Getting property Identity [0x124db98] dbus interface debug: Getting property Volume [0x7ffa402c0cc8] main generic debug: looking for extension module matching "any": 0 candidates [0x7ffa402c0cc8] main generic debug: no extension modules [0x1275d18] qt4 interface error: Unable to load extensions module [0x1275d18] main interface debug: using interface module "qt4" [0x12449b8] main playlist debug: adding item `Palmipedarium_HD.mp4' ( file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4 ) [0x12449b8] main playlist debug: processing request item: Palmipedarium_HD.mp4, node: null, skip: 0 [0x12449b8] main playlist debug: rebuilding array of current - root Liste de lecture [0x1275d18] qt4 interface debug: Adding a new MRL to recent ones: file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4 [0x12449b8] main playlist debug: rebuild done - 1 items, index 0 [0x12449b8] main playlist debug: starting playback of the new playlist item [0x12449b8] main playlist debug: resyncing on Palmipedarium_HD.mp4 [0x12449b8] main playlist debug: Palmipedarium_HD.mp4 is at 0 [0x12449b8] main playlist debug: creating new input thread [0x7ffa340009b8] main input debug: Creating an input for 'Palmipedarium_HD.mp4' [0x7ffa340009b8] main input debug: using timeshift granularity of 50 MiB, in path '/tmp' [0x7ffa340009b8] main input debug: `file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' gives access `file' demux `' path `/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffa340009b8] main input debug: creating demux: access='file' demux='' location='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' file='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffa30000958] main input debug: Creating an input for 'Palmipedarium_HD.mp4' [0x7ffa2c000e68] main demux debug: looking for access_demux module matching "file": 15 candidates [0x7ffa2c000e68] main demux debug: no access_demux modules matched [0x7ffa340009b8] main input debug: creating access 'file' location='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4', path='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffa2c0011c8] main access debug: looking for access module matching "file": 20 candidates [0x7ffa2c0011c8] filesystem access debug: opening file `/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffa2c0011c8] main access debug: using access module "filesystem" [0x7ffa2c001348] main stream debug: Using stream method for AStream* [0x7ffa2c001348] main stream debug: starting pre-buffering [0x7ffa2c001348] main stream debug: received first data after 0 ms [0x7ffa2c001348] main stream debug: pre-buffering done 1024 bytes in 0s - 1000000 KiB/s [0x7ffa2cc015d8] main stream debug: looking for stream_filter module matching "any": 9 candidates [0x7ffa2cc015d8] main stream debug: no stream_filter modules matched [0x7ffa2cc015d8] main stream debug: looking for stream_filter module matching "stream_filter_record": 9 candidates [0x7ffa2cc015d8] main stream debug: no stream_filter modules matched [0x7ffa340009b8] main input debug: creating demux: access='file' demux='' location='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' file='/home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' [0x7ffa2cc015d8] main demux debug: looking for demux module matching "mp4": 56 candidates [0x1275d18] qt4 interface debug: IM: Setting an input [0x7ffa2c001348] mp4 stream debug: dumping root Box "root" [0x7ffa2c001348] mp4 stream debug: | + ftyp size 32 [0x7ffa2c001348] mp4 stream debug: | + free size 8 [0x7ffa2c001348] mp4 stream debug: | + mdat size 177224812 [0x7ffa2c001348] mp4 stream debug: | + moov size 430377 [0x7ffa2c001348] mp4 stream debug: | | + mvhd size 108 [0x7ffa2c001348] mp4 stream debug: | | + trak size 237010 [0x7ffa2c001348] mp4 stream debug: | | | + tkhd size 92 [0x7ffa2c001348] mp4 stream debug: | | | + edts size 36 [0x7ffa2c001348] mp4 stream debug: | | | | + elst size 28 [0x7ffa2c001348] mp4 stream debug: | | | + mdia size 236874 [0x7ffa2c001348] mp4 stream debug: | | | | + mdhd size 32 [0x7ffa2c001348] mp4 stream debug: | | | | + hdlr size 45 [0x7ffa2c001348] mp4 stream debug: | | | | + minf size 236789 [0x7ffa2c001348] mp4 stream debug: | | | | | + vmhd size 20 [0x7ffa2c001348] mp4 stream debug: | | | | | + dinf size 36 [0x7ffa2c001348] mp4 stream debug: | | | | | | + dref size 28 [0x7ffa2c001348] mp4 stream debug: | | | | | | | + url size 12 [0x7ffa2c001348] mp4 stream debug: | | | | | + stbl size 236725 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stsd size 153 [0x7ffa2c001348] mp4 stream debug: | | | | | | | + avc1 size 137 [0x7ffa2c001348] mp4 stream debug: | | | | | | | | + avcC size 51 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stts size 24 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stss size 560 [0x7ffa2c001348] mp4 stream debug: | | | | | | + ctts size 117640 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stsc size 40 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stsz size 59156 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stco size 59144 [0x7ffa2c001348] mp4 stream debug: | | + trak size 193056 [0x7ffa2c001348] mp4 stream debug: | | | + tkhd size 92 [0x7ffa2c001348] mp4 stream debug: | | | + edts size 36 [0x7ffa2c001348] mp4 stream debug: | | | | + elst size 28 [0x7ffa2c001348] mp4 stream debug: | | | + mdia size 192920 [0x7ffa2c001348] mp4 stream debug: | | | | + mdhd size 32 [0x7ffa2c001348] mp4 stream debug: | | | | + hdlr size 45 [0x7ffa2c001348] mp4 stream debug: | | | | + minf size 192835 [0x7ffa2c001348] mp4 stream debug: | | | | | + smhd size 16 [0x7ffa2c001348] mp4 stream debug: | | | | | + dinf size 36 [0x7ffa2c001348] mp4 stream debug: | | | | | | + dref size 28 [0x7ffa2c001348] mp4 stream debug: | | | | | | | + url size 12 [0x7ffa2c001348] mp4 stream debug: | | | | | + stbl size 192775 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stsd size 103 [0x7ffa2c001348] mp4 stream debug: | | | | | | | + mp4a size 87 [0x7ffa2c001348] mp4 stream debug: | | | | | | | | + esds size 51 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stts size 32 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stsc size 16648 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stsz size 116840 [0x7ffa2c001348] mp4 stream debug: | | | | | | + stco size 59144 [0x7ffa2c001348] mp4 stream debug: | | + udta size 195 [0x7ffa2c001348] mp4 stream debug: | | | + meta size 187 [0x7ffa2c001348] mp4 stream debug: | | | | + hdlr size 33 [0x7ffa2c001348] mp4 stream debug: | | | | + ilst size 142 [0x7ffa2c001348] mp4 stream debug: | | | | | + cnam size 37 [0x7ffa2c001348] mp4 stream debug: | | | | | + ctoo size 36 [0x7ffa2c001348] mp4 stream debug: | | | | | + ccmt size 61 [0x7ffa2cc015d8] mp4 demux debug: ISO Media file (isom) version 512. [0x7ffa2cc015d8] mp4 demux debug: found 2 tracks [0x7ffa2cc015d8] mp4 demux warning: elst box found [0x7ffa2cc015d8] mp4 demux debug: - [0] duration=616000ms media time=83ms) rate=1.0 [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x1] read 14782 chunk [0x124db98] dbus interface debug: Getting All properties [0x124db98] dbus interface debug: Getting All properties [0x124db98] dbus interface debug: Getting All properties [0x124db98] dbus interface debug: Getting All properties [0x7ffa2cc015d8] mp4 demux warning: CTTS table [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x1] read 14784 samples length:616s [0x7ffa340009b8] main input debug: selecting program id=0 [0x7ffa2cc015d8] mp4 demux debug: adding track[Id 0x1] video (enable) language fre [0x7ffa2cc015d8] mp4 demux warning: elst box found [0x7ffa2cc015d8] mp4 demux debug: - [0] duration=623024ms media time=0ms) rate=1.0 [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x2] read 14782 chunk [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x2] read 29205 samples length:623s [0x7ffa2cc015d8] mp4 demux debug: adding track[Id 0x2] audio (enable) language fre [0x7ffa2cc015d8] main demux debug: using demux module "mp4" [0x7ffa340009b8] main input debug: looking for a subtitle file in /home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/ [0x7ffa2d1e6648] main decoder debug: looking for decoder module matching "any": 36 candidates [0x12449b8] main playlist debug: no fetch required for Palmipedarium (art currently (null)) [0x7ffa2d1e6648] avcodec decoder debug: trying to use direct rendering [0x7ffa2d1e6648] avcodec decoder debug: allowing 4 thread(s) for decoding [0x7ffa2d1e6648] avcodec decoder debug: avcodec codec (H264 - MPEG-4 AVC (part 10)) started [0x7ffa2d1e6648] avcodec decoder debug: using frame thread mode with 4 threads [0x7ffa2d1e6648] main decoder debug: using decoder module "avcodec" [0x7ffa2d3f51f8] main decoder debug: looking for decoder module matching "any": 36 candidates [0x7ffa2d3f51f8] main decoder debug: using decoder module "faad" [0x7ffa2d3fa2c8] main demux meta debug: looking for meta reader module matching "any": 1 candidates [0x7ffa2d3fa2c8] main demux meta debug: using meta reader module "taglib" [0x7ffa2cc015d8] main demux debug: removing module "taglib" [0x7ffa340009b8] main input debug: `file:///home/vdb/Papy3D/productions/isos_presskits/Palmipedarium/Palmipedarium_HD.mp4' successfully opened [0x7ffa2cc015d8] mp4 demux debug: elst (0) gives 0ms (movie)-> 83ms (track) [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffa2cc015d8] mp4 demux debug: stts gives 2 --> 0 (sample number) [0x7ffa2cc015d8] mp4 demux debug: elst (0) gives 0ms (movie)-> 0ms (track) [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffa340009b8] main input debug: Buffering 0% [0x7ffa2d3f51f8] faad decoder warning: decoded zero sample [0x7ffa340009b8] main input debug: Buffering 0% [0x12449b8] main playlist debug: reusing audio output [0x7ffa340009b8] main input debug: Buffering 33% [0x7ffa180ccc68] main spu text debug: looking for text renderer module matching "any": 3 candidates [0x7ffa340009b8] main input debug: Buffering 66% [0x7ffa180ccc68] freetype spu text debug: Building font databases. [0x7ffa180ccc68] freetype spu text debug: Took 0 microseconds Fontconfig warning: FcPattern object size does not accept value "0" [0x1121eb8] alsa audio output debug: using ALSA device: default [0x1121eb8] alsa audio output debug: Plug PCM: Soft volume PCM Control: PCM Playback Volume min_dB: -51 max_dB: 0 resolution: 256 Slave: Direct Stream Mixing PCM Hardware PCM card 0 'HDA ATI SB' device 0 subdevice 0 Its setup is: stream : PLAYBACK access : MMAP_INTERLEAVED format : S32_LE subformat : STD channels : 2 rate : 48000 exact rate : 48000 (48000/1) msbits : 32 buffer_size : 8192 period_size : 1024 period_time : 21333 tstamp_mode : ENABLE period_step : 1 avail_min : 1024 period_event : 0 start_threshold : 1 stop_threshold : 0 silence_threshold: 0 silence_size : 0 boundary : 4611686018427387904 appl_ptr : 0 hw_ptr : 5043316 [0x1121eb8] alsa audio output debug: device name : ALC889A Analog [0x1121eb8] alsa audio output debug: device ID : ALC889A Analog [0x1121eb8] alsa audio output debug: subdevice name: subdevice #0 [0x1121eb8] alsa audio output debug: initial hardware setup: ACCESS: MMAP_INTERLEAVED MMAP_NONINTERLEAVED MMAP_COMPLEX RW_INTERLEAVED RW_NONINTERLEAVED FORMAT: S8 U8 S16_LE S16_BE U16_LE U16_BE S24_LE S24_BE U24_LE U24_BE S32_LE S32_BE U32_LE U32_BE FLOAT_LE FLOAT_BE FLOAT64_LE FLOAT64_BE MU_LAW A_LAW IMA_ADPCM S24_3LE S24_3BE U24_3LE U24_3BE S20_3LE S20_3BE U20_3LE U20_3BE S18_3LE S18_3BE U18_3LE U18_3BE SUBFORMAT: STD SAMPLE_BITS: [4 64] FRAME_BITS: [4 640000] CHANNELS: [1 10000] RATE: 48000 PERIOD_TIME: (21333 21334) PERIOD_SIZE: 1024 PERIOD_BYTES: [512 81920000] PERIODS: [2 8] BUFFER_TIME: (42666 170667) BUFFER_SIZE: [2048 8192] BUFFER_BYTES: [1024 655360000] TICK_TIME: ALL [0x1121eb8] alsa audio output debug: using channels map 2, type 1, 6 channel(s) [0x1121eb8] alsa audio output debug: final HW setup: ACCESS: RW_INTERLEAVED FORMAT: FLOAT_LE SUBFORMAT: STD SAMPLE_BITS: 32 FRAME_BITS: 192 CHANNELS: 6 RATE: 48000 PERIOD_TIME: (21333 21334) PERIOD_SIZE: 1024 PERIOD_BYTES: 24576 PERIODS: 8 BUFFER_TIME: (170666 170667) BUFFER_SIZE: 8192 BUFFER_BYTES: 196608 TICK_TIME: 0 [0x1121eb8] alsa audio output debug: initial software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1024 start_threshold: 1 stop_threshold: 8192 silence_threshold: 0 silence_size: 0 boundary: 4611686018427387904 [0x1121eb8] alsa audio output debug: final software parameters: tstamp_mode: NONE period_step: 1 avail_min: 1024 start_threshold: 1 stop_threshold: 8192 silence_threshold: 0 silence_size: 0 boundary: 4611686018427387904 [0x1121eb8] alsa audio output warning: device cannot be paused [0x1121eb8] main audio output debug: output 'f32l' 48000 Hz 3F2R/LFE frame=1 samples/24 bytes [0x7ffa20012e38] main volume debug: looking for audio volume module matching "any": 2 candidates [0x7ffa20012e38] main volume debug: using audio volume module "float_mixer" [0x1121eb8] main audio output debug: input 'f32l' 48000 Hz 3F2R/LFE frame=1 samples/24 bytes [0x7ffa200386a8] main audio filter debug: looking for audio filter module matching "scaletempo": 14 candidates [0x7ffa200386a8] scaletempo audio filter debug: format: 48000 rate, 6 nch, 4 bps, fl32 [0x7ffa200386a8] scaletempo audio filter debug: params: 30 stride, 0.200 overlap, 14 search [0x7ffa200386a8] scaletempo audio filter debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode [0x7ffa200386a8] main audio filter debug: using audio filter module "scaletempo" [0x1121eb8] main audio output debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE [0x1121eb8] main audio output debug: conversion pipeline complete [0x1121eb8] main audio output debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE [0x1121eb8] main audio output debug: conversion pipeline complete [0x7ffa20039368] main audio resampler debug: looking for audio resampler module matching "any": 2 candidates [0x7ffa20039368] main audio resampler debug: using audio resampler module "samplerate" [0x7ffa2d3f51f8] main decoder debug: End of audio preroll Fontconfig warning: FcPattern object size does not accept value "0" [0x7ffa340009b8] main input debug: Buffering 100% [0x7ffa340009b8] main input debug: Stream buffering done (400 ms in 13 ms) [0x7ffa180ccc68] freetype spu text debug: Using Serif Bold as font from file /usr/share/fonts/TTF/DejaVuSans.ttf [0x7ffa180ccc68] freetype spu text debug: using fontsize: 2 [0x7ffa180ccc68] main spu text debug: using text renderer module "freetype" [0x7ffa180da4a8] main scale debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa180da4a8] swscale scale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) [0x7ffa180da4a8] main scale debug: using video filter2 module "swscale" [0x7ffa180f7b28] main scale debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa180f7b28] yuvp scale debug: YUVP to YUVA converter [0x7ffa180f7b28] main scale debug: using video filter2 module "yuvp" [0x7ffa180cb878] main video output debug: Deinterlacing available [0x7ffa180cb878] main video output debug: deinterlace 0, mode blend, is_needed 0 [0x7ffa180cb878] main video output debug: Opening vout display wrapper [0x7ffa14001248] main vout display debug: looking for vout display module matching "any": 12 candidates [0x7ffa14001da8] main window debug: looking for vout window xid module matching "qt4,any": 4 candidates [0x7ffa14001da8] qt4 window debug: requesting video window... [0x1275d18] qt4 interface debug: Video was requested 0, 0 [0x7ffa14001da8] main window debug: using vout window xid module "qt4" [0x7ffa14001fd8] main inhibit debug: looking for inhibit module matching "any": 2 candidates [0x7ffa14001fd8] dbus_screensaver inhibit debug: found service org.freedesktop.ScreenSaver [0x7ffa14001fd8] main inhibit debug: using inhibit module "dbus_screensaver" [0x7ffa14001248] vdpau_display vout display debug: connected to X11.0 server [0x7ffa14001248] vdpau_display vout display debug: vendor : The X.Org Foundation [0x7ffa14001248] vdpau_display vout display debug: version: 11400000 [0x7ffa14001248] vdpau_display vout display debug: using screen 0x2c5 [0x7ffa14001248] vdpau_display vout display debug: using back-end G3DVL VDPAU Driver Shared Library version 1.0 [0x7ffa14001248] vdpau_display vout display debug: using RGBA format 2 [0x7ffa14001248] vdpau_display vout display debug: using X11 window 0x09a00001 [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'fullscreen' 0 [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1920x1080 window [0x7ffa14001248] main vout display debug: using vout display module "vdpau_display" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa14098a78] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa14098a78] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa14098a78) appended to chain [0x7ffa180cb878] main video output debug: original format sz 1920x1080, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 [0x7ffa180ccc68] main spu text debug: removing module "freetype" [0x7ffa180ccc68] main spu text debug: looking for text renderer module matching "any": 3 candidates [0x7ffa180ccc68] freetype spu text debug: Building font databases. [0x7ffa180ccc68] freetype spu text debug: Took 0 microseconds Fontconfig warning: FcPattern object size does not accept value "0" Fontconfig warning: FcPattern object size does not accept value "0" [0x7ffa180ccc68] freetype spu text debug: Using Serif Bold as font from file /usr/share/fonts/TTF/DejaVuSans.ttf [0x7ffa180ccc68] freetype spu text debug: using fontsize: 2 [0x7ffa180ccc68] main spu text debug: using text renderer module "freetype" [0x7ffa2d1e6648] avcodec decoder debug: using direct rendering [0x7ffa2d1e6648] main decoder debug: End of video preroll [0x7ffa2d1e6648] main decoder debug: Received first picture [0x7ffa14098a78] vdpau_chroma filter debug: using video mixer sharpness feature [0x7ffa14098a78] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7ffa14098a78] vdpau_chroma filter debug: using video mixer 7 [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1794x973 window [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display error: Failed to resize display [0x7ffa14001248] main vout display debug: Filter 0x7ffa14098a78 removed from chain [0x7ffa14098a78] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa14098a78] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa14098a78] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa14098a78) appended to chain [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1920x1080 window [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display debug: Filter 0x7ffa14098a78 removed from chain [0x7ffa14098a78] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa14098a78] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa14098a78] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa14098a78) appended to chain [0x7ffa340009b8] main input debug: Decoder buffering done in 187 ms [0x7ffa14098a78] vdpau_chroma filter debug: using video mixer sharpness feature [0x7ffa14098a78] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7ffa14098a78] vdpau_chroma filter debug: using video mixer 7 [0x1121eb8] main audio output debug: inserting 1407 zeroes [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa180cb878] main video output warning: picture is too late to be displayed (missing 21 ms) [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1794x973 window [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display debug: Filter 0x7ffa14098a78 removed from chain [0x7ffa14098a78] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa0f016988] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa0f016988] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa0f016988) appended to chain [0x7ffa0f016988] vdpau_chroma filter debug: using video mixer sharpness feature [0x7ffa0f016988] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7ffa0f016988] vdpau_chroma filter debug: using video mixer 7 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] main vout display debug: auto hiding mouse cursor [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1761x949 window [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display debug: Filter 0x7ffa0f016988 removed from chain [0x7ffa0f016988] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa0f016988] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa0f016988] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa0f016988) appended to chain [0x7ffa0f016988] vdpau_chroma filter debug: using video mixer sharpness feature [0x7ffa0f016988] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7ffa0f016988] vdpau_chroma filter debug: using video mixer 7 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa180cb878] main video output warning: picture is too late to be displayed (missing 28 ms) [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1638x853 window [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1513x800 window [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display debug: Filter 0x7ffa0f016988 removed from chain [0x7ffa0f016988] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa0f00c288] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa0f00c288] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa0f00c288) appended to chain [0x7ffa0f00c288] vdpau_chroma filter debug: using video mixer sharpness feature [0x7ffa0f00c288] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7ffa0f00c288] vdpau_chroma filter debug: using video mixer 7 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1436x768 window [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1374x725 window [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1341x702 window [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display debug: Filter 0x7ffa0f00c288 removed from chain [0x7ffa0f00c288] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa0f00c288] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa0f00c288] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa0f00c288) appended to chain [0x7ffa0f00c288] vdpau_chroma filter debug: using video mixer sharpness feature [0x7ffa0f00c288] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7ffa0f00c288] vdpau_chroma filter debug: using video mixer 7 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1313x695 window [0x7ffa14001248] main vout display debug: VoutDisplayEvent 'resize' 1311x694 window [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display debug: Filter 0x7ffa0f00c288 removed from chain [0x7ffa0f00c288] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa0f00c288] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa0f00c288] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa0f00c288) appended to chain [0x7ffa0f00c288] vdpau_chroma filter debug: using video mixer sharpness feature [0x7ffa0f00c288] vdpau_chroma filter error: video color space matrix failure: An invalid pointer was provided. Typically, this means that a NULL pointer was provided for an 'output' parameter. [0x7ffa0f00c288] vdpau_chroma filter debug: using video mixer 7 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: display is visible [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 (repeat) [0x7ffa180cb878] main video output debug: picture might be displayed late (missing 12 ms) [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] main vout display debug: auto hiding mouse cursor [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 (repeat) [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa2d1e6648] main decoder warning: early picture skipped [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa2cc015d8] mp4 demux debug: elst (0) gives 465398ms (movie)-> 465482ms (track) [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x1] using Sync Sample Box (stss) [0x7ffa2cc015d8] mp4 demux debug: stts gives 11171 --> 11100 (sample number) [0x7ffa2cc015d8] mp4 demux debug: elst (0) gives 465398ms (movie)-> 465398ms (track) [0x7ffa2cc015d8] mp4 demux debug: track[Id 0x2] does not provide Sync Sample Box (stss) [0x7ffa340009b8] main input debug: Buffering 0% [0x7ffa340009b8] main input debug: Buffering 0% [0x7ffa2d3f51f8] main decoder debug: End of audio preroll [0x7ffa340009b8] main input debug: Buffering 33% [0x7ffa340009b8] main input debug: Buffering 66% [0x7ffa340009b8] main input debug: Buffering 99% [0x7ffa340009b8] main input debug: Stream buffering done (399 ms in 8 ms) [0x7ffa2d1e6648] main decoder debug: End of video preroll [0x7ffa2d1e6648] main decoder debug: Received first picture [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa340009b8] main input debug: Decoder buffering done in 747 ms [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 (repeat) [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] main vout display debug: auto hiding mouse cursor [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x110ab78] main libvlc debug: exiting [0x110ab78] main libvlc debug: removing all interfaces [0x110ab78] main libvlc debug: exiting [0x1275d18] main interface debug: removing module "qt4" [0x12449b8] main playlist debug: deactivating the playlist [0x12449b8] main playlist debug: incoming request - stopping current input [0x7ffa340009b8] main input debug: control: stopping input [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x12449b8] main playlist debug: incoming request - stopping current input [0x12449b8] main playlist debug: finished input [0x7ffa2d1e6648] main decoder debug: removing module "avcodec" [0x7ffa2d1e6648] main decoder warning: can't get output picture [0x7ffa2d1e6648] avcodec decoder warning: disabling direct rendering [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa14001248] vdpau_display vout display debug: surface status: 2 [0x7ffa2d1e6648] avcodec decoder debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped [0x7ffa2d1e6648] main decoder debug: killing decoder fourcc `h264', 0 PES in FIFO [0x12449b8] main playlist debug: saving a free vout [0x12449b8] main playlist debug: reusing provided vout [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] main vout display warning: VoutDisplayEvent 'pictures invalid' [0x7ffa14001248] vdpau_display vout display debug: resetting pictures [0x7ffa14001248] main vout display error: Failed to resize display [0x7ffa14001248] main vout display debug: Filter 0x7ffa0f00c288 removed from chain [0x7ffa0f00c288] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: A filter to adapt decoder to display is needed [0x7ffa0f00c288] main filter debug: looking for video filter2 module matching "any": 61 candidates [0x7ffa0f00c288] main filter debug: using video filter2 module "vdpau_chroma" [0x7ffa14001248] main vout display debug: Filter 'VDPAU' (0x7ffa0f00c288) appended to chain [0x7ffa2d3f51f8] main decoder debug: removing module "faad" [0x7ffa2d3f51f8] main decoder debug: killing decoder fourcc `mp4a', 10 PES in FIFO [0x7ffa20039368] main audio resampler debug: removing module "samplerate" [0x7ffa200386a8] main audio filter debug: removing module "scaletempo" [0x7ffa20012e38] main volume debug: removing module "float_mixer" [0x12449b8] main playlist debug: keeping audio output [0x7ffa2cc015d8] main demux debug: removing module "mp4" [0x7ffa2cc015d8] mp4 demux debug: freeing all memory [0x7ffa2c0011c8] main access debug: removing module "filesystem" [0x7ffa340009b8] main input debug: Program doesn't contain anymore ES [0x12449b8] main playlist debug: incoming request - stopping current input [0x12449b8] main playlist debug: dead input [0x1121eb8] main audio output debug: removing module "alsa" [0x1275d18] qt4 interface debug: IM: Deleting the input [0x7ffa180cb878] main video output debug: destroying useless vout [0x7ffa14001248] main vout display debug: Filter 0x7ffa0f00c288 removed from chain [0x7ffa0f00c288] main filter debug: removing module "vdpau_chroma" [0x7ffa14001248] main vout display debug: removing module "vdpau_display" [0x7ffa14001fd8] dbus_screensaver inhibit debug: got cookie 5246 [0x7ffa14001fd8] main inhibit debug: removing module "dbus_screensaver" [0x7ffa14001da8] qt4 window debug: releasing video... [0x1275d18] qt4 interface debug: Video is not needed anymore [0x7ffa180ccc68] main spu text debug: removing module "freetype" [0x7ffa180f7b28] main scale debug: removing module "yuvp" [0x7ffa180da4a8] main scale debug: removing module "swscale" [0x1275d18] qt4 interface debug: requesting exit... [0x1275d18] qt4 interface debug: waiting for UI thread... [0x1275d18] qt4 interface debug: QApp exec() finished [0x1275d18] qt4 interface debug: Video is not needed anymore [0x1275d18] qt4 interface debug: Killing extension dialog provider [0x124db98] main interface debug: removing module "dbus" [0x124d788] main interface debug: removing module "globalhotkeys" [0x12ac268] main interface debug: removing module "hotkeys" [0x12449b8] main playlist debug: destroying [0x124c228] main playlist export debug: saving Bibliothèque to file /home/vdb/.local/share/vlc/ml.xspf [0x124c228] main playlist export debug: looking for playlist export module matching "export-xspf": 4 candidates [0x124c228] main playlist export debug: using playlist export module "export" [0x124c228] main playlist export debug: removing module "export" [0x110ab78] main libvlc debug: removing stats Inconsistency detected by ld.so: dl-close.c: 765: _dl_close: Assertion `map->l_init_called' failed! bash-4.2$

[rvdboom]

Checking the logs, indeed I can see in the first one the following lines :

Code: Select all

PERIOD_TIME: (21333 21334) PERIOD_SIZE: 1024 PERIOD_BYTES: [512 81920000] PERIODS: [2 8] BUFFER_TIME: (42666 170667) BUFFER_SIZE: [2048 8192] BUFFER_BYTES: [1024 655360000] TICK_TIME: ALL [0x7ffb0c002358] freetype spu text debug: using fontsize: 2 [0x7ffb0c002358] main spu text debug: using text renderer module "freetype" [0x855778] alsa audio output debug: downmixing from 6 to 2 channels [0x855778] alsa audio output debug: final HW setup: ACCESS: RW_INTERLEAVED

while on the second the downmix disappear :

Code: Select all

PERIOD_TIME: (21333 21334) PERIOD_SIZE: 1024 PERIOD_BYTES: [512 81920000] PERIODS: [2 8] BUFFER_TIME: (42666 170667) BUFFER_SIZE: [2048 8192] BUFFER_BYTES: [1024 655360000] TICK_TIME: ALL [0x1121eb8] alsa audio output debug: using channels map 2, type 1, 6 channel(s) [0x1121eb8] alsa audio output debug: final HW setup: ACCESS: RW_INTERLEAVED

Of course, it may occur elsewhere in the new code, but it seems to fail to kick in.

[Rémi Denis-Courmont]

The dlopen() crash is due to a broken library, and the display drivers would be my best guess.

As for downmix, VLC is not doing it because your audio device reports 6 channels with the ALSA channel map support. That was added to VLC in december 2012 (1.3.0-git-4878-g345a3e3). It rather seems that your "good" build was made with an older version of ALSA-lib that did not support channel maps.

[Jean-Baptiste Kempf]

Can you force in the audio menu the 2 channels output?

[rvdboom]

As for downmix, VLC is not doing it because your audio device reports 6 channels with the ALSA channel map support. That was added to VLC in december 2012 (1.3.0-git-4878-g345a3e3). It rather seems that your "good" build was made with an older version of ALSA-lib that did not support channel maps.

That makes sense, Slackware upgraded to 1.0.27 in may and then to 1.0.27.1 in june. I don't know the Changelog of alsa-lib, but I guess you could be right here.
However, why does the "bad" log says at the begining of the playback :

Code: Select all

Hardware PCM card 0 'HDA ATI SB' device 0 subdevice 0 Its setup is: stream : PLAYBACK access : MMAP_INTERLEAVED format : S32_LE subformat : STD channels : 2 rate : 48000 exact rate : 48000 (48000/1)

Note the "Channels : 2" which is the exact same report as in the "good" log, but in the later, downmix is enable just after this log when in the former above it's not and 6 channels seem to be used?

Can you force in the audio menu the 2 channels output?

I've tried all the peripherals that the audio menu offers but to no avail.

[Rémi Denis-Courmont]

VLC cannot access that channel count. It is hidden behind the softvol plugin, which claims to support 5.1.

Forcing hwsomething should work around the problem, but obviously you will loose the benefit of softvol.

[rvdboom]

OK, thanks for the explanation.
One more question : before this channel maping support, was the downmix done by VLC or alsa? In the former case, is there a way in the current git version to force downmix and stereo?

[Rémi Denis-Courmont]

VLC was downmixing if you set stereo in the ALSA config, since ALSA previously did not provide applications with the channel map.

[rvdboom]

I'm sorry, I must look very dumb to you devs but I can't grasp where my issue comes from exactly and how to solve it.
If I understand you well, VLC query alsa-lib for channel mapping, alsa answers 6 channels so VLC use 6 channels by default. Indeed, my sound chip, HDA ATI SB, ALC889A Analog, does support apparently 5.1 playback, so, until that points, it make sense to me.
However, I only have two speakers connected to front L-R port. Is alsa supposed to detect it and provide a feedback to VLC in order for the downmix to kick in? Or is it necessary to force stereo downmix somehow because the current behaviour is normal? And in that was, is it supposed to be done on the Alsa level or on the VLC level?
By the way, I tried changing the ALSA output module settings in Tools -> Preferences -> All Parameters -> Audio -> Output modules. I tried to force output channels to Stereo there, but still I don't see any difference.

[Rémi Denis-Courmont]

Unfortunately, the intent of the ALSA developers is not something that is particularly well documented. As far as I can tell, this is a bug in the ALSA softvol plugin, lying about channel maps (the underlying device only supports 2 channels afterall).

[rvdboom]

OK, I'll try to raise the issue on alsa side, then.
Thanks for your time.

[rvdboom]

Hi, Remi, looks like your commits from the 25/07/2013 indeed fixed my downmixing problems.
Thanks a lot!