[vlc] vlc stutters on 3.9 linux kernel

[Grant]

>>>>>> 3.8 works great but vlc stutters a bit on 3.9.  If I use jack the
>>>>>> sound is very bad like the CPU can't keep up but top shows 90% of the
>>>>>> CPU idle.  If I don't use jack the sound is fine but I notice the
>>>>>> video stutters a bit.  mplayer and mpd work fine.
>>>>>>
>>>>>> - Grant
>
> You're STILL having that problem?

Yes, I just tried 3.9-rc8 but the problem persists.

> Since VLC's video rendering is different from mplayer, it might be
> triggering a bug in this particular kernel.
>
> How about compiling with --
>
> X a52 aac aalib alsa avcodec avformat dbus dirac dts dvbpsi dvd elibc_glibc
> ffmpeg flac libass libsamplerate matroska mmx mp3 mpeg musepack ogg opengl
> png qt4 schroedinger sdl sdl-image sid speex sse svg swscale taglib theora
> truetype twolame udev vorbis x264 xcb xv

Just tried that but same result.

> Also run VLC with
>
> vlc --verbose 2147483647
>
> Check it out yourself and attach it here.

I've attached it.  I think the problem is here:

[0x7f2ba4000958] main audio output warning: PTS is out of range
(-9941), dropping buffer
[0x7f2ba4000958] main audio output warning: PTS is out of range
(-33889), dropping buffer
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad
main_data_begin pointer
[0x7f2ba4000958] main audio output warning: audio output out of sync,
adjusting dates (70832 us)
[0x7f2bac06ca68] main video output debug: picture might be displayed
late (missing 10 ms)
[0x7f2ba4000958] main audio output warning: not synchronized (70834
us), resampling
[0x7f2ba4000958] main audio output warning: buffer too late  (70417),
up-sampling
[0x7f2ba4000958] main audio output debug: audio output is starving
(-89164), playing silence
[0x7f2ba4000958] main audio output debug: audio output is starving
(-68444), playing silence

- Grant
-------------- next part --------------
[0x19dd138] main libvlc debug: VLC media player - 2.0.5 Twoflower
[0x19dd138] main libvlc debug: Copyright © 1996-2012 VLC authors and VideoLAN
[0x19dd138] main libvlc debug: revision 2.0.5-0-g1661b7d
[0x19dd138] main libvlc debug: configured with ./configure  '--prefix=/usr' '--build=x86_64-pc-linux-gnu' '--host=x86_64-pc-linux-gnu' '--mandir=/usr/share/man' '--infodir=/usr/share/info' '--datadir=/usr/share' '--sysconfdir=/etc' '--localstatedir=/var/lib' '--libdir=/usr/lib64' '--disable-dependency-tracking' '--docdir=/usr/share/doc/vlc-2.0.5' '--enable-a52' '--enable-aa' '--enable-faad' '--enable-alsa' '--disable-altivec' '--disable-atmo' '--disable-audioqueue' '--disable-bonjour' '--enable-avcodec' '--enable-avformat' '--disable-fribidi' '--disable-bluray' '--enable-vcd' '--disable-libcddb' '--enable-dbus' '--enable-dbus-control' '--enable-dirac' '--disable-direct2d' '--disable-directfb' '--disable-directx' '--disable-dc1394' '--disable-debug' '--disable-dshow' '--enable-dca' '--enable-dvbpsi' '--enable-dvdread' '--enable-dvdnav' '--disable-dxva2' '--disable-egl' '--enable-sout' '--disable-fbosd' '--enable-flac' '--disable-fluidsynth' '--enable-fontconfig' '--enable-libgcrypt' '--disable-gme' '--disable-gnomevfs' '--disable-gnutls' '--disable-growl' '--disable-httpd' '--disable-dv' '--disable-ios-vout' '--enable-jack' '--disable-kate' '--without-kde-solid' '--enable-libass' '--disable-caca' '--enable-notify' '--disable-libproxy' '--enable-samplerate' '--disable-libtar' '--disable-tiger' '--disable-linsys' '--disable-lirc' '--disable-live555' '--disable-lua' '--disable-macosx-audio' '--disable-macosx-dialog-provider' '--disable-macosx-eyetv' '--disable-macosx-qtkit' '--disable-macosx-quartztext' '--disable-macosx-vout' '--enable-mkv' '--disable-media-library' '--enable-mmx' '--disable-mod' '--enable-mad' '--enable-libmpeg2' '--disable-mtp' '--enable-mpc' '--enable-ncurses' '--disable-neon' '--enable-ogg' '--enable-mux_ogg' '--disable-omxil' '--enable-glx' '--disable-opus' '--disable-optimize-memory' '--disable-oss' '--enable-png' '--disable-portaudio' '--enable-postproc' '--disable-projectm' '--disable-pulse' '--disable-pvr' '--enable-qt4' '--disable-realrtsp' '--disable-run-as-root' '--disable-smb' '--enable-schroedinger' '--enable-sdl' '--enable-sdl-image' '--disable-shine' '--enable-sid' '--disable-shout' '--disable-skins2' '--enable-speex' '--disable-sqlite' '--enable-sse' '--enable-svg' '--disable-switcher' '--enable-swscale' '--enable-taglib' '--enable-theora' '--enable-freetype' '--enable-twolame' '--enable-udev' '--disable-upnp' '--disable-v4l2' '--enable-libva' '--disable-vcdx' '--disable-vlm' '--enable-vorbis' '--disable-waveout' '--disable-loader' '--disable-wingdi' '--disable-wma-fixed' '--with-x' '--enable-x264' '--enable-xcb' '--enable-libxml2' '--disable-xosd' '--enable-xvideo' '--disable-zvbi' '--enable-telx' '--disable-optimizations' '--without-tuning' '--enable-fast-install' 'build_alias=x86_64-pc-linux-gnu' 'host_alias=x86_64-pc-linux-gnu' 'CFLAGS=-march=native -O2 -pipe -fomit-frame-pointer' 'LDFLAGS=-Wl,-O1 -Wl,--as-needed -L/usr/lib64/sidplay/builders/' 'CXXFLAGS=-march=native -O2 -pipe -fomit-frame-pointer' 'PKG_CONFIG_PATH=/usr/lib64/pkgconfig'
[0x19dd138] main libvlc debug: searching plug-in modules
[0x19dd138] main libvlc debug: loading plugins cache file /usr/lib64/vlc/plugins/plugins.dat
[0x19dd138] main libvlc debug: recursively browsing `/usr/lib64/vlc/plugins'
[0x19dd138] main libvlc debug: saving plugins cache /usr/lib64/vlc/plugins/plugins.dat
[0x19dd138] main libvlc debug: plug-ins loaded: 370 modules
[0x19dd138] main libvlc debug: opening config file (/home/grant/.config/vlc/vlcrc)
[0x19dd138] main libvlc debug: translation test: code is "C"
[0x19dd138] main libvlc debug: CPU has capabilities MMX MMXEXT SSE SSE2 SSE3 SSSE3 SSE4.1 SSE4.2 FPU 
[0x19dd138] main libvlc debug: looking for memcpy module: 4 candidates
[0x19dd138] main libvlc debug: using memcpy module "memcpymmxext"
[0x1aa6408] main input debug: Creating an input for 'Media Library'
[0x1aa6408] main input debug: Input is a meta file: disabling unneeded options
[0x1aa6408] main input debug: using timeshift granularity of 50 MiB, in path '/tmp'
[0x1aa6408] main input debug: `file/xspf-open:///home/grant/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/grant/.local/share/vlc/ml.xspf'
[0x1aa6408] main input debug: creating demux: access='file' demux='xspf-open' location='/home/grant/.local/share/vlc/ml.xspf' file='/home/grant/.local/share/vlc/ml.xspf'
[0x1a95918] main demux debug: looking for access_demux module: 2 candidates
[0x1a95918] main demux debug: no access_demux module matching "file" could be loaded
[0x1a95918] main demux debug: TIMER module_need() : 0.373 ms - Total 0.373 ms / 1 intvls (Avg 0.373 ms)
[0x1aa6408] main input debug: creating access 'file' location='/home/grant/.local/share/vlc/ml.xspf', path='/home/grant/.local/share/vlc/ml.xspf'
[0x1a966c8] main access debug: looking for access module: 2 candidates
[0x1a966c8] filesystem access debug: opening file `/home/grant/.local/share/vlc/ml.xspf'
[0x1a966c8] main access debug: using access module "filesystem"
[0x1a966c8] main access debug: TIMER module_need() : 0.204 ms - Total 0.204 ms / 1 intvls (Avg 0.204 ms)
[0x1a97698] main stream debug: Using stream method for AStream*
[0x1a97698] main stream debug: starting pre-buffering
[0x1a97698] main stream debug: received first data after 0 ms
[0x1a97698] main stream debug: pre-buffering done 296 bytes in 0s - 18066 KiB/s
[0x1a978f8] main stream debug: looking for stream_filter module: 7 candidates
[0x1a978f8] main stream debug: no stream_filter module matching "any" could be loaded
[0x1a978f8] main stream debug: TIMER module_need() : 1.945 ms - Total 1.945 ms / 1 intvls (Avg 1.945 ms)
[0x1a978f8] main stream debug: looking for stream_filter module: 1 candidate
[0x1a978f8] main stream debug: using stream_filter module "stream_filter_record"
[0x1a978f8] main stream debug: TIMER module_need() : 0.146 ms - Total 0.146 ms / 1 intvls (Avg 0.146 ms)
[0x1aa6408] main input debug: creating demux: access='file' demux='xspf-open' location='/home/grant/.local/share/vlc/ml.xspf' file='/home/grant/.local/share/vlc/ml.xspf'
[0x1a9f2b8] main demux debug: looking for demux module: 1 candidate
[0x1a9f2b8] playlist demux debug: using XSPF playlist reader
[0x1a9f2b8] main demux debug: using demux module "playlist"
[0x1a9f2b8] main demux debug: TIMER module_need() : 0.183 ms - Total 0.183 ms / 1 intvls (Avg 0.183 ms)
[0x1a994e8] main demux meta debug: looking for meta reader module: 1 candidate
[0x1a994e8] main demux meta debug: no meta reader module matching "any" could be loaded
[0x1a994e8] main demux meta debug: TIMER module_need() : 1.379 ms - Total 1.379 ms / 1 intvls (Avg 1.379 ms)
[0x1aa6408] main input debug: `file/xspf-open:///home/grant/.local/share/vlc/ml.xspf' successfully opened
[0x1aa9728] main xml reader debug: looking for xml reader module: 1 candidate
[0x1aa9728] main xml reader debug: using xml reader module "xml"
[0x1aa9728] main xml reader debug: TIMER module_need() : 0.617 ms - Total 0.617 ms / 1 intvls (Avg 0.617 ms)
[0x1a9f2b8] playlist demux debug: parsed 0 tracks successfully
[0x1aa6408] main input debug: EOF reached
[0x1a9f2b8] main demux debug: removing module "playlist"
[0x1a978f8] main stream debug: removing module "stream_filter_record"
[0x1a966c8] main access debug: removing module "filesystem"
[0x1aa6408] main input debug: TIMER input launching for 'Media Library' : 4.741 ms - Total 4.741 ms / 1 intvls (Avg 4.741 ms)
[0x1ab2d08] main interface debug: looking for interface module: 1 candidate
[0x1ab2d08] main interface debug: using interface module "hotkeys"
[0x1ab2d08] main interface debug: TIMER module_need() : 0.156 ms - Total 0.156 ms / 1 intvls (Avg 0.156 ms)
[0x1aa6408] main interface debug: looking for interface module: 1 candidate
[0x1aa8028] main playlist debug: playlist threads correctly activated
[0x1aa6408] main interface debug: using interface module "inhibit"
[0x1aa8028] main playlist debug: rebuilding array of current - root Playlist
[0x1aa6408] main interface debug: TIMER module_need() : 0.750 ms - Total 0.750 ms / 1 intvls (Avg 0.750 ms)
[0x1aa8028] main playlist debug: rebuild done - 0 items, index -1
[0x1aa8028] main playlist debug: adding item `S09E01-They Came at Us with Spears!.avi' ( file:///home/grant/Downloads/SURVIVOR/S09-%20Vanuatu/S09E01-They%20Came%20at%20Us%20with%20Spears%21.avi )
[0x7f2bbc000a28] main input debug: Creating an input for 'S09E01-They Came at Us with Spears!.avi'
[0x1a94758] main interface debug: looking for interface module: 1 candidate
[0x1a94758] main interface debug: using interface module "globalhotkeys"
[0x1a94758] main interface debug: TIMER module_need() : 0.868 ms - Total 0.868 ms / 1 intvls (Avg 0.868 ms)
[0x19dd138] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[0x1a94098] main interface debug: looking for interface module: 3 candidates
[0x1aa8028] main playlist debug: no fetch required for (null) (art currently (null))
[0x7f2bb80d62e8] main generic debug: looking for extension module: 0 candidates
[0x7f2bb80d62e8] main generic debug: no extension module matched "any"
[0x7f2bb80d62e8] main generic debug: TIMER module_need() : 0.129 ms - Total 0.129 ms / 1 intvls (Avg 0.129 ms)
[0x1a94098] qt4 interface error: Unable to load extensions module
[0x1a94098] main interface debug: using interface module "qt4"
[0x1a94098] main interface debug: TIMER module_need() : 81.805 ms - Total 81.805 ms / 1 intvls (Avg 81.805 ms)
[0x1aa8028] main playlist debug: rebuilding array of current - root Playlist
[0x1aa8028] main playlist debug: rebuild done - 1 items, index -1
[0x1aa8028] main playlist debug: processing request item: null, node: Playlist, skip: 0
[0x1aa8028] main playlist debug: starting playback of the new playlist item
[0x1aa8028] main playlist debug: resyncing on S09E01-They Came at Us with Spears!.avi
[0x1aa8028] main playlist debug: S09E01-They Came at Us with Spears!.avi is at 0
[0x1aa8028] main playlist debug: creating new input thread
[0x7f2bc4000b78] main input debug: Creating an input for 'S09E01-They Came at Us with Spears!.avi'
[0x7f2bc4000b78] main input debug: using timeshift granularity of 50 MiB, in path '/tmp'
[0x7f2bc4000b78] main input debug: `file:///home/grant/Downloads/SURVIVOR/S09-%20Vanuatu/S09E01-They%20Came%20at%20Us%20with%20Spears%21.avi' gives access `file' demux `' path `/home/grant/Downloads/SURVIVOR/S09-%20Vanuatu/S09E01-They%20Came%20at%20Us%20with%20Spears%21.avi'
[0x7f2bc4000b78] main input debug: creating demux: access='file' demux='' location='/home/grant/Downloads/SURVIVOR/S09-%20Vanuatu/S09E01-They%20Came%20at%20Us%20with%20Spears%21.avi' file='/home/grant/Downloads/SURVIVOR/S09- Vanuatu/S09E01-They Came at Us with Spears!.avi'
[0x7f2bbc001588] main demux debug: looking for access_demux module: 2 candidates
[0x7f2bbc001588] main demux debug: no access_demux module matching "file" could be loaded
[0x7f2bbc001588] main demux debug: TIMER module_need() : 0.179 ms - Total 0.179 ms / 1 intvls (Avg 0.179 ms)
[0x7f2bc4000b78] main input debug: creating access 'file' location='/home/grant/Downloads/SURVIVOR/S09-%20Vanuatu/S09E01-They%20Came%20at%20Us%20with%20Spears%21.avi', path='/home/grant/Downloads/SURVIVOR/S09- Vanuatu/S09E01-They Came at Us with Spears!.avi'
[0x7f2bbc004138] main access debug: looking for access module: 2 candidates
[0x7f2bbc004138] filesystem access debug: opening file `/home/grant/Downloads/SURVIVOR/S09- Vanuatu/S09E01-They Came at Us with Spears!.avi'
[0x7f2bbc004138] main access debug: using access module "filesystem"
[0x7f2bbc004138] main access debug: TIMER module_need() : 0.141 ms - Total 0.141 ms / 1 intvls (Avg 0.141 ms)
[0x7f2bbc001858] main stream debug: Using stream method for AStream*
[0x7f2bbc001858] main stream debug: starting pre-buffering
[0x7f2bbc001858] main stream debug: received first data after 0 ms
[0x7f2bbc001858] main stream debug: pre-buffering done 1024 bytes in 0s - 38461 KiB/s
[0x7f2bbc001588] main stream debug: looking for stream_filter module: 7 candidates
[0x7f2bbc001588] main stream debug: no stream_filter module matching "any" could be loaded
[0x7f2bbc001588] main stream debug: TIMER module_need() : 0.121 ms - Total 0.121 ms / 1 intvls (Avg 0.121 ms)
[0x7f2bbc001588] main stream debug: looking for stream_filter module: 1 candidate
[0x7f2bbc001588] main stream debug: using stream_filter module "stream_filter_record"
[0x7f2bbc001588] main stream debug: TIMER module_need() : 0.088 ms - Total 0.088 ms / 1 intvls (Avg 0.088 ms)
[0x7f2bc4000b78] main input debug: creating demux: access='file' demux='' location='/home/grant/Downloads/SURVIVOR/S09-%20Vanuatu/S09E01-They%20Came%20at%20Us%20with%20Spears%21.avi' file='/home/grant/Downloads/SURVIVOR/S09- Vanuatu/S09E01-They Came at Us with Spears!.avi'
[0x7f2bbc003eb8] main demux debug: looking for demux module: 51 candidates
[0x7f2bbc001588] avi stream debug: <list 'AVI '>
[0x7f2bbc001588] avi stream debug: <list 'hdrl'>
[0x7f2bbc001588] avi stream debug: <list 'strl'>
[0x7f2bbc001588] avi stream debug: </list 'strl'>
[0x7f2bbc001588] avi stream debug: <list 'strl'>
[0x7f2bbc001588] avi stream debug: </list 'strl'>
[0x7f2bbc001588] avi stream debug: <list 'odml'>
[0x7f2bbc001588] avi stream warning: unknown chunk (not loaded)
[0x7f2bbc001588] avi stream debug: </list 'odml'>
[0x7f2bbc001588] avi stream debug: </list 'hdrl'>
[0x7f2bbc001588] avi stream debug: <list 'INFO'>
[0x7f2bbc001588] avi stream debug: </list 'INFO'>
[0x7f2bbc001588] avi stream debug: skipping movi chunk
[0x7f2bbc001588] avi stream debug: </list 'AVI '>
[0x7f2bbc001588] avi stream debug: * LIST-root size:366702592 pos:0
[0x7f2bbc001588] avi stream debug:      + RIFF-AVI  size:366701028 pos:0
[0x7f2bbc001588] avi stream debug:      |    + LIST-hdrl size:8830 pos:12
[0x7f2bbc001588] avi stream debug:      |    |    + avih size:56 pos:24
[0x7f2bbc001588] avi stream debug:      |    |    + LIST-strl size:4244 pos:88
[0x7f2bbc001588] avi stream debug:      |    |    |    + strh size:56 pos:100
[0x7f2bbc001588] avi stream debug:      |    |    |    + strf size:40 pos:164
[0x7f2bbc001588] avi stream debug:      |    |    |    + JUNK size:4120 pos:212
[0x7f2bbc001588] avi stream debug:      |    |    + LIST-strl size:4234 pos:4340
[0x7f2bbc001588] avi stream debug:      |    |    |    + strh size:56 pos:4352
[0x7f2bbc001588] avi stream debug:      |    |    |    + strf size:30 pos:4416
[0x7f2bbc001588] avi stream debug:      |    |    |    + JUNK size:4120 pos:4454
[0x7f2bbc001588] avi stream debug:      |    |    + LIST-odml size:260 pos:8582
[0x7f2bbc001588] avi stream debug:      |    |    |    + dmlh size:248 pos:8594
[0x7f2bbc001588] avi stream debug:      |    + LIST-INFO size:28 pos:8850
[0x7f2bbc001588] avi stream debug:      |    |    + ISFT size:15 pos:8862
[0x7f2bbc001588] avi stream debug:      |    + JUNK size:1346 pos:8886
[0x7f2bbc001588] avi stream debug:      |    + LIST-movi size:363688204 pos:10240
[0x7f2bbc001588] avi stream debug:      |    + idx1 size:3002576 pos:363698452
[0x7f2bbc001588] avi stream debug:      + JUNK size:1548 pos:366701036
[0x7f2bbc003eb8] avi demux debug: AVIH: 2 stream, flags  HAS_INDEX IS_INTERLEAVED 
[0x7f2bbc003eb8] avi demux debug: stream[0] rate:2997 scale:100 samplesize:0
[0x7f2bbc003eb8] avi demux debug: stream[0] video(XVID) 512x384 12bpp 29.969999fps
[0x7f2bc4000b78] main input debug: selecting program id=0
[0x7f2bbc003eb8] avi demux debug: stream[1] rate:48000 scale:1152 samplesize:0
[0x7f2bbc003eb8] avi demux debug: stream[1] audio(0x55 - MPEG Audio layer 1/2/3) 2 channels 48000Hz 0bits
[0x7f2bbc003eb8] avi demux debug: selected standard index for stream[0]
[0x7f2bbc003eb8] avi demux debug: selected standard index for stream[1]
[0x7f2bbc003eb8] avi demux debug: stream[0] created 78510 index entries
[0x7f2bbc003eb8] avi demux debug: stream[1] created 109151 index entries
[0x7f2bbc003eb8] avi demux debug: stream[0] length:2619 (based on index)
[0x7f2bbc003eb8] avi demux debug: stream[1] length:2619 (based on index)
[0x7f2bbc003eb8] main demux debug: using demux module "avi"
[0x7f2bbc003eb8] main demux debug: TIMER module_need() : 16.027 ms - Total 16.027 ms / 1 intvls (Avg 16.027 ms)
[0x7f2bc4000b78] main input debug: looking for a subtitle file in /home/grant/Downloads/SURVIVOR/S09- Vanuatu/
[0x7f2bbcc048f8] main decoder debug: looking for decoder module: 29 candidates
[0x7f2bbcc048f8] avcodec decoder debug: libavcodec initialized (interface 0x353d64)
[0x7f2bbcc048f8] avcodec decoder debug: trying to use direct rendering
[0x7f2bbcc048f8] avcodec decoder debug: allowing 1 thread(s) for decoding
[0x7f2bbcc048f8] avcodec decoder debug: ffmpeg codec (MPEG-4 Video) started
[0x7f2bbcc048f8] main decoder debug: using decoder module "avcodec"
[0x7f2bbcc048f8] main decoder debug: TIMER module_need() : 6.398 ms - Total 6.398 ms / 1 intvls (Avg 6.398 ms)
[0x7f2bbcc06008] main decoder debug: looking for decoder module: 29 candidates
[0x7f2bbcc06008] main decoder debug: using decoder module "mpeg_audio"
[0x7f2bbcc06008] main decoder debug: TIMER module_need() : 0.143 ms - Total 0.143 ms / 1 intvls (Avg 0.143 ms)
[0x7f2bbcc06df8] main demux meta debug: looking for meta reader module: 1 candidate
[0x7f2bbcc06df8] main demux meta debug: no meta reader module matching "any" could be loaded
[0x7f2bbcc06df8] main demux meta debug: TIMER module_need() : 0.085 ms - Total 0.085 ms / 1 intvls (Avg 0.085 ms)
[0x7f2bc4000b78] main input debug: `file:///home/grant/Downloads/SURVIVOR/S09-%20Vanuatu/S09E01-They%20Came%20at%20Us%20with%20Spears%21.avi' successfully opened
[0x7f2bc4000b78] main input debug: Buffering 0%
[0x7f2bc4000b78] main input debug: Buffering 8%
[0x7f2bbcc06008] mpeg_audio decoder debug: MPGA channels:2 samplerate:48000 bitrate:128
[0x1aa8028] main playlist debug: creating audio output
[0x7f2bc4000b78] main input debug: Buffering 16%
[0x7f2bc4000b78] main input debug: Buffering 25%
[0x7f2bc4000b78] main input debug: Buffering 33%
[0x7f2bc4000b78] main input debug: Buffering 41%
[0x7f2bc4000b78] main input debug: Buffering 50%
[0x7f2ba4000958] main audio output debug: looking for audio output module: 2 candidates
[0x7f2bc4000b78] main input debug: Buffering 58%
[0x7f2bc4000b78] main input debug: Buffering 66%
[0x7f2bc4000b78] main input debug: Buffering 75%
[0x7f2bac06de28] main spu text debug: looking for text renderer module: 3 candidates
[0x7f2bc4000b78] main input debug: Buffering 83%
[0x7f2bc4000b78] main input debug: Buffering 91%
[0x7f2bc4000b78] main input debug: Buffering 100%
[0x7f2bc4000b78] main input debug: Stream buffering done (325 ms in 0 ms)
[0x7f2bac06de28] freetype spu text debug: Building font databases.
[0x7f2bac06de28] freetype spu text debug: Took 0 microseconds
[0x7f2bac06de28] freetype spu text debug: Using DejaVu Sans as font from file /usr/share/fonts/dejavu/DejaVuSans.ttf
[0x7f2bac06de28] freetype spu text debug: using fontsize: 2
[0x7f2bac06de28] main spu text debug: using text renderer module "freetype"
[0x7f2bac06de28] main spu text debug: TIMER module_need() : 1.967 ms - Total 1.967 ms / 1 intvls (Avg 1.967 ms)
[0x7f2bac06f128] main scale debug: looking for video filter2 module: 18 candidates
[0x7f2bac06f128] swscale scale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
[0x7f2bac06f128] main scale debug: using video filter2 module "swscale"
[0x7f2bac06f128] main scale debug: TIMER module_need() : 0.796 ms - Total 0.796 ms / 1 intvls (Avg 0.796 ms)
[0x7f2bac077518] main scale debug: looking for video filter2 module: 18 candidates
[0x7f2bac077518] yuvp scale debug: YUVP to YUVA converter
[0x7f2bac077518] main scale debug: using video filter2 module "yuvp"
[0x7f2bac077518] main scale debug: TIMER module_need() : 0.713 ms - Total 0.713 ms / 1 intvls (Avg 0.713 ms)
[0x7f2bac06ca68] main video output debug: Deinterlacing available
[0x7f2bac06ca68] main video output debug: deinterlace 0, mode blend, is_needed 0
[0x7f2bac06ca68] main video output debug: Opening vout display wrapper
[0x7f2ba0001268] main vout display debug: looking for vout display module: 5 candidates
[0x7f2ba0006bf8] main window debug: looking for vout window xid module: 3 candidates
[0x7f2ba0006bf8] qt4 window debug: requesting video...
[0x7f2ba4000958] jack audio output debug: JACK graph reordered. Our maximum latency=0.
[0x7f2ba4000958] jack audio output debug: JACK graph reordered. Our maximum latency=0.
[0x7f2ba4000958] jack audio output debug: connecting port vlc_15103:out_1 to port system:playback_1
[0x7f2ba4000958] jack audio output debug: JACK graph reordered. Our maximum latency=0.
[0x7f2ba4000958] jack audio output debug: connecting port vlc_15103:out_2 to port system:playback_2
[0x7f2ba4000958] jack audio output debug: JACK audio output initialized (2 channels, rate=48000)
[0x7f2ba4000958] main audio output debug: using audio output module "jack"
[0x7f2ba4000958] main audio output debug: TIMER module_need() : 5.378 ms - Total 5.378 ms / 1 intvls (Avg 5.378 ms)
[0x7f2ba4000958] main audio output debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
[0x7f2ba4000958] main audio output debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
[0x7f2ba4000958] main audio output debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x7f2ba4000958] main audio output debug: conversion pipeline completed
[0x7f2ba4005948] main mixer debug: looking for audio mixer module: 2 candidates
[0x7f2ba4005948] main mixer debug: using audio mixer module "float32_mixer"
[0x7f2ba4005948] main mixer debug: TIMER module_need() : 0.149 ms - Total 0.149 ms / 1 intvls (Avg 0.149 ms)
[0x7f2ba4000958] main audio output debug: input 'mpga' 48000 Hz Stereo frame=1152 samples/969 bytes
[0x7f2ba4000958] main audio output debug: filter(s) 'mpga'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x7f2ba40030e8] main audio filter debug: looking for audio filter module: 13 candidates
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: mpga->f32l, bits per sample: 32
[0x7f2ba40030e8] main audio filter debug: using audio filter module "mpgatofixed32"
[0x7f2ba40030e8] main audio filter debug: TIMER module_need() : 0.248 ms - Total 0.248 ms / 1 intvls (Avg 0.248 ms)
[0x7f2ba4000958] main audio output debug: conversion pipeline completed
[0x7f2ba4000958] main audio output debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo
[0x7f2ba4003ef8] main audio filter debug: looking for audio filter module: 13 candidates
[0x7f2ba4003ef8] main audio filter debug: using audio filter module "samplerate"
[0x7f2ba4003ef8] main audio filter debug: TIMER module_need() : 0.519 ms - Total 0.519 ms / 1 intvls (Avg 0.519 ms)
[0x7f2ba4000958] main audio output debug: conversion pipeline completed
[0x7f2bbcc06008] main decoder debug: End of audio preroll
[0x1a94098] qt4 interface debug: IM: Setting an input
[0x1a94098] qt4 interface debug: Video was requested 0, 0
[0x7f2ba0006bf8] main window debug: using vout window xid module "qt4"
[0x7f2ba0006bf8] main window debug: TIMER module_need() : 24.620 ms - Total 24.620 ms / 1 intvls (Avg 24.620 ms)
[0x7f2ba0006ee8] main inhibit debug: looking for inhibit module: 2 candidates
[0x7f2ba0006ee8] main inhibit debug: using inhibit module "xdg_screensaver"
[0x7f2ba0006ee8] main inhibit debug: TIMER module_need() : 0.321 ms - Total 0.321 ms / 1 intvls (Avg 0.321 ms)
[0x7f2ba0006ee8] xdg_screensaver inhibit debug: started xdg-screensaver (PID = 15115)
[0x7f2ba0001268] xcb_xv vout display debug: connected to X11.0 server
[0x7f2ba0001268] xcb_xv vout display debug:  vendor : The X.Org Foundation
[0x7f2ba0001268] xcb_xv vout display debug:  version: 11304000
[0x7f2ba0001268] xcb_xv vout display debug: using screen 0xd6
[0x7f2ba0001268] xcb_xv vout display debug: using XVideo extension v2.2
[0x7f2ba0001268] xcb_xv vout display debug: using adaptor Intel(R) Textured Video
[0x7f2ba0001268] xcb_xv vout display debug: using port 95
[0x7f2ba0001268] xcb_xv vout display debug: using image format 0x30323449
[0x7f2ba0001268] xcb_xv vout display debug: using X11 visual ID 0x20 (depth: 24)
[0x7f2ba0001268] xcb_xv vout display debug: using X11 window 0x03c00000
[0x7f2ba0001268] xcb_xv vout display debug: using X11 graphic context 0x03c00002
[0x7f2ba0001268] main vout display debug: VoutDisplayEvent 'fullscreen' 0
[0x7f2ba0001268] main vout display debug: VoutDisplayEvent 'resize' 512x384 window
[0x7f2ba0001268] main vout display debug: using vout display module "xcb_xv"
[0x7f2ba0001268] main vout display debug: TIMER module_need() : 42.598 ms - Total 42.598 ms / 1 intvls (Avg 42.598 ms)
[0x7f2bac06ca68] main video output debug: original format sz 512x384, of (0,0), vsz 512x384, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
[0x7f2bac06de28] main spu text debug: removing module "freetype"
[0x7f2bac06de28] main spu text debug: looking for text renderer module: 3 candidates
[0x7f2bac06de28] freetype spu text debug: Building font databases.
[0x7f2bac06de28] freetype spu text debug: Took 0 microseconds
[0x7f2bac06de28] freetype spu text debug: Using DejaVu Sans as font from file /usr/share/fonts/dejavu/DejaVuSans.ttf
[0x7f2bac06de28] freetype spu text debug: using fontsize: 2
[0x7f2bac06de28] main spu text debug: using text renderer module "freetype"
[0x7f2bac06de28] main spu text debug: TIMER module_need() : 0.905 ms - Total 0.905 ms / 1 intvls (Avg 0.905 ms)
[0x7f2bbcc048f8] avcodec decoder debug: using direct rendering
[0x7f2bbcc048f8] main decoder debug: End of video preroll
[0x7f2bbcc048f8] main decoder debug: Received first picture
[0x7f2ba000df58] main blend debug: looking for video blending module: 1 candidate
[0x7f2ba000df58] main blend debug: using video blending module "blend"
[0x7f2ba000df58] main blend debug: TIMER module_need() : 0.265 ms - Total 0.265 ms / 1 intvls (Avg 0.265 ms)
[0x7f2bac06ca68] main video output debug: Post-processing available
[0x7f2ba0001268] xcb_xv vout display debug: display is visible
[0x7f2bc4000b78] main input debug: Decoder buffering done in 69 ms
[0x7f2ba4000958] main audio output warning: PTS is out of range (-9941), dropping buffer
[0x7f2ba4000958] main audio output warning: PTS is out of range (-33889), dropping buffer
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (70832 us)
[0x7f2bac06ca68] main video output debug: picture might be displayed late (missing 10 ms)
[0x7f2ba4000958] main audio output warning: not synchronized (70834 us), resampling
[0x7f2ba4000958] main audio output warning: buffer too late  (70417), up-sampling
[0x7f2ba4000958] main audio output debug: audio output is starving (-89164), playing silence
[0x7f2ba4000958] main audio output debug: audio output is starving (-68444), playing silence
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (84967 us)
[0x7f2ba4000958] main audio output warning: not synchronized (84967 us), resampling
[0x7f2ba4000958] main audio output warning: timing screwed, stopping resampling
[0x7f2ba4000958] main audio output debug: audio output is starving (-88749), playing silence
[0x7f2ba4000958] main audio output warning: buffer too late  (155280), up-sampling
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85681 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85681 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (240961), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-89064), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (216961), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-68426), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (192961), dropping buffer
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (64067 us)
[0x7f2ba4000958] main audio output warning: not synchronized (64067 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (233007), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (209007), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-89786), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (185007), dropping buffer
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output debug: audio output is starving (-68517), playing silence
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (63952 us)
[0x7f2ba4000958] main audio output warning: not synchronized (63952 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (224917), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-89790), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (200917), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-68133), playing silence
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output debug: audio output is starving (-46852), playing silence
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85010 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85010 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (261781), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (237781), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (213781), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-88816), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (189781), dropping buffer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (64654 us)
[0x7f2ba4000958] main audio output warning: not synchronized (64655 us), resampling
[0x7f2ba4000958] main audio output debug: audio output is starving (-89792), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (230374), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (206374), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-68122), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (182374), dropping buffer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85002 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85002 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (243126), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-89129), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (219126), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (195126), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-68464), playing silence
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85672 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85673 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (256695), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (232695), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-90131), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (208695), dropping buffer
[0x7f2ba0001268] main vout display debug: auto hiding mouse cursor
[0x7f2ba4000958] main audio output warning: buffer way too late (184695), dropping buffer
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85291 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85291 us), resampling
[0x7f2ba4000958] main audio output debug: audio output is starving (-89754), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (245923), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (221923), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (197923), dropping buffer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (63741 us)
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output warning: not synchronized (63741 us), resampling
[0x7f2ba4000958] main audio output debug: audio output is starving (-89819), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (237623), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-68154), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (213623), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (189623), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-47306), playing silence
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (64186 us)
[0x7f2ba4000958] main audio output warning: not synchronized (64187 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (229643), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-90003), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (205643), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (181643), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-47009), playing silence
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85091 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85092 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (242506), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-89710), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (218506), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (194506), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-68097), playing silence
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output debug: audio output is starving (-46936), playing silence
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85385 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85385 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (255307), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (231307), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-89278), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (207307), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (183307), dropping buffer
[0x7f2ba40030e8] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (85650 us)
[0x7f2ba4000958] main audio output warning: not synchronized (85650 us), resampling
[0x7f2ba4000958] main audio output warning: buffer way too late (244791), dropping buffer
[0x7f2ba4000958] main audio output debug: audio output is starving (-89907), playing silence
[0x7f2ba4000958] main audio output warning: buffer way too late (220791), dropping buffer
[0x7f2ba4000958] main audio output warning: buffer way too late (196791), dropping buffer
[0x7f2ba4000958] main audio output warning: audio output out of sync, adjusting dates (63557 us)
[0x19dd138] main libvlc debug: exiting
[0x19dd138] main libvlc debug: deactivating the playlist
[0x1aa8028] main playlist debug: deactivating the playlist
[0x1aa8028] main playlist debug: incoming request - stopping current input
[0x1aa8028] main playlist debug: dying input
[0x7f2bc4000b78] main input debug: control: stopping input
[0x7f2ba4000958] main audio output debug: audio output is starving (-89824), playing silence
[0x1aa8028] main playlist debug: dying input
[0x7f2bbcc048f8] main decoder debug: removing module "avcodec"
[0x7f2bbcc048f8] avcodec decoder debug: ffmpeg codec (MPEG-4 Video) stopped
[0x7f2bbcc048f8] main decoder debug: killing decoder fourcc `mp4v', 0 PES in FIFO
[0x1aa8028] main playlist debug: saving a free vout
[0x1aa8028] main playlist debug: reusing provided vout
[0x7f2bbcc06008] main decoder debug: removing module "mpeg_audio"
[0x7f2bbcc06008] main decoder debug: killing decoder fourcc `mpga', 0 PES in FIFO
[0x7f2ba40030e8] main audio filter debug: removing module "mpgatofixed32"
[0x7f2ba4003ef8] main audio filter debug: removing module "samplerate"
[0x7f2ba4000958] main audio output debug: removing module "jack"
[0x7f2ba4000958] jack audio output debug: JACK graph reordered. Our maximum latency=0.
[0x7f2ba4000958] jack audio output debug: JACK graph reordered. Our maximum latency=0.
[0x7f2ba4005948] main mixer debug: removing module "float32_mixer"
[0x1aa8028] main playlist debug: releasing audio output
[0x7f2bbc003eb8] main demux debug: removing module "avi"
[0x7f2bbc001588] avi stream warning: unknown chunk (not unloaded)
[0x7f2bbc001588] main stream debug: removing module "stream_filter_record"
[0x7f2bbc004138] main access debug: removing module "filesystem"
[0x7f2bc4000b78] main input debug: Program doesn't contain anymore ES
[0x1aa8028] main playlist debug: dead input
[0x7f2bac06ca68] main video output debug: destroying useless vout
[0x1a94098] qt4 interface debug: IM: Deleting the input
[0x7f2ba000df58] main blend debug: removing module "blend"
[0x7f2ba0001268] main vout display debug: removing module "xcb_xv"
[0x7f2ba0006ee8] main inhibit debug: removing module "xdg_screensaver"
[0x7f2ba0006ee8] xdg_screensaver inhibit debug: started xdg-screensaver (PID = 15145)
[0x7f2ba0006bf8] qt4 window debug: releasing video...
[0x1a94098] qt4 interface debug: Video is not needed anymore
[0x7f2bac06de28] main spu text debug: removing module "freetype"
[0x7f2bac077518] main scale debug: removing module "yuvp"
[0x7f2bac06f128] main scale debug: removing module "swscale"
[0x1accf68] main playlist export debug: saving Media Library to file /home/grant/.local/share/vlc/ml.xspf
[0x1accf68] main playlist export debug: looking for playlist export module: 1 candidate
[0x1accf68] main playlist export debug: using playlist export module "export"
[0x1accf68] main playlist export debug: TIMER module_need() : 0.243 ms - Total 0.243 ms / 1 intvls (Avg 0.243 ms)
[0x1accf68] main playlist export debug: removing module "export"
[0x1aa8028] main playlist debug: playlist correctly deactivated
[0x19dd138] main libvlc debug: removing all services discovery tasks
[0x19dd138] main libvlc debug: removing all interfaces
[0x1a94098] main interface debug: removing module "qt4"
[0x1a94098] qt4 interface debug: requesting exit...
[0x1a94098] qt4 interface debug: waiting for UI thread...
[0x1a94098] qt4 interface debug: QApp exec() finished
[0x1a94098] qt4 interface debug: Video is not needed anymore
[0x1a94098] qt4 interface debug: Killing extension dialog provider
[0x1a94758] main interface debug: removing module "globalhotkeys"
[0x1aa6408] main interface debug: removing module "inhibit"
[0x7f2bc4000b78] main input debug: TIMER input launching for 'S09E01-They Came at Us with Spears!.avi' : 24.345 ms - Total 24.345 ms / 1 intvls (Avg 24.345 ms)
[0x1ab2d08] main interface debug: removing module "hotkeys"
[0x1aa8028] main playlist debug: destroying
[0x19dd138] main libvlc debug: TIMER ML Load : Total 5.711 ms / 1 intvls (Avg 5.711 ms)
[0x19dd138] main libvlc debug: TIMER Items array build : Total 0.154 ms / 2 intvls (Avg 0.077 ms)
[0x19dd138] main libvlc debug: TIMER Preparse run : Total 17.968 ms / 1 intvls (Avg 17.968 ms)
[0x19dd138] main libvlc debug: TIMER ML Dump : Total 0.416 ms / 1 intvls (Avg 0.416 ms)
[0x19dd138] main libvlc debug: removing stats
[0x19dd138] main libvlc debug: removing module "memcpymmxext"
[0x19dd138] main libvlc debug: opening config file (/home/grant/.config/vlc/vlcrc)