output of vlc -vvv

vlc-vvv (text/plain), 44.11 KB, created by samurai.no.dojo on 2012-03-22 15:02:20 UTC

This denotes repeating pattern:
###########
... "whats repeating"
###########


%·> vlc -vvv
VLC media player 2.0.1 Twoflower (revision 2.0.1-0-gf432547)
[0x9b158b0] main libvlc debug: VLC media player - 2.0.1 Twoflower
[0x9b158b0] main libvlc debug: Copyright © 1996-2012 VLC authors and VideoLAN
[0x9b158b0] main libvlc debug: revision 2.0.1-0-gf432547
[0x9b158b0] main libvlc debug: configured with ./configure  '--prefix=/usr' '--build=i686-pc-linux-gnu' '--host=i686-pc-linux-gnu' '--mandir=/usr/share/man' '--infodir=/usr/share/info' '--datadir=/usr/share' '--sysconfdir=/etc' '--localstatedir=/var/lib' '--disable-dependency-tracking' '--docdir=/usr/share/doc/vlc-2.0.1' '--enable-a52' '--disable-aa' '--enable-faad' '--enable-alsa' '--disable-altivec' '--disable-atmo' '--disable-audioqueue' '--disable-bonjour' '--enable-avcodec' '--enable-avformat' '--enable-fribidi' '--disable-bluray' '--enable-vcd' '--enable-libcddb' '--enable-dbus' '--enable-dbus-control' '--disable-dirac' '--disable-direct2d' '--enable-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' '--disable-fontconfig' '--enable-libgcrypt' '--disable-gme' '--enable-gnomevfs' '--enable-gnutls' '--disable-growl' '--enable-httpd' '--disable-dv' '--disable-ios-vout' '--disable-jack' '--disable-kate' '--without-kde-solid' '--disable-libass' '--disable-caca' '--enable-notify' '--disable-libproxy' '--disable-samplerate' '--disable-libtar' '--disable-tiger' '--disable-linsys' '--disable-lirc' '--disable-live555' '--enable-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' '--disable-mpc' '--enable-ncurses' '--disable-neon' '--enable-ogg' '--disable-omxil' '--enable-glx' '--disable-optimize-memory' '--disable-oss' '--enable-png' '--disable-portaudio' '--enable-postproc' '--disable-projectm' '--enable-pulse' '--disable-pvr' '--enable-qt4' '--disable-realrtsp' '--disable-run-as-root' '--disable-smb' '--disable-schroedinger' '--enable-sdl' '--disable-sdl-image' '--disable-shine' '--disable-sid' '--disable-shout' '--enable-skins2' '--disable-speex' '--disable-sqlite' '--enable-sse' '--enable-svg' '--disable-switcher' '--enable-swscale' '--enable-taglib' '--enable-theora' '--enable-freetype' '--disable-twolame' '--enable-udev' '--disable-upnp' '--enable-v4l2' '--disable-libva' '--disable-vcdx' '--enable-vlm' '--enable-vorbis' '--disable-waveout' '--enable-loader' '--disable-wingdi' '--disable-wma-fixed' '--with-x' '--enable-x264' '--enable-xcb' '--enable-libxml2' '--enable-xosd' '--enable-xvideo' '--disable-zvbi' '--enable-telx' '--disable-optimizations' '--without-tuning' '--enable-fast-install' 'build_alias=i686-pc-linux-gnu' 'host_alias=i686-pc-linux-gnu' 'CFLAGS=-O2 -march=pentium-m -fomit-frame-pointer' 'LDFLAGS=-Wl,-O1 -Wl,--as-needed' 'CXXFLAGS=-O2 -march=pentium-m -fomit-frame-pointer'
[0x9b158b0] main libvlc debug: searching plug-in modules
[0x9b158b0] main libvlc debug: loading plugins cache file /usr/lib/vlc/plugins/plugins.dat
[0x9b158b0] main libvlc debug: recursively browsing `/usr/lib/vlc/plugins'
[0x9b158b0] main libvlc debug: saving plugins cache /usr/lib/vlc/plugins/plugins.dat
[0x9b158b0] main libvlc debug: plug-ins loaded: 382 modules
[0x9b158b0] main libvlc debug: opening config file (/home/a/.config/vlc/vlcrc)
[0x9b158b0] main libvlc debug: translation test: code is "C"
[0x9b158b0] main libvlc debug: No Media Player is running. Continuing normally.
[0x9b158b0] main libvlc debug: CPU has capabilities MMX MMXEXT SSE SSE2 FPU 
[0x9b158b0] main libvlc debug: looking for memcpy module: 4 candidates
[0x9b158b0] main libvlc debug: using memcpy module "memcpymmxext"
[0x9b24f40] main input debug: Creating an input for 'Media Library'
[0x9b24f40] main input debug: Input is a meta file: disabling unneeded options
[0x9b24f40] main input debug: using timeshift granularity of 50 MiB, in path '/tmp'
[0x9b24f40] main input debug: `file/xspf-open:///home/a/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/a/.local/share/vlc/ml.xspf'
[0x9b24f40] main input debug: creating demux: access='file' demux='xspf-open' location='/home/a/.local/share/vlc/ml.xspf' file='/home/a/.local/share/vlc/ml.xspf'
[0x9b96ab0] main demux debug: looking for access_demux module: 2 candidates
[0x9b96ab0] main demux debug: no access_demux module matching "file" could be loaded
[0x9b96ab0] main demux debug: TIMER module_need() : 1.005 ms - Total 1.005 ms / 1 intvls (Avg 1.005 ms)
[0x9b24f40] main input debug: creating access 'file' location='/home/a/.local/share/vlc/ml.xspf', path='/home/a/.local/share/vlc/ml.xspf'
[0x9b984b8] main access debug: looking for access module: 2 candidates
[0x9b984b8] filesystem access debug: opening file `/home/a/.local/share/vlc/ml.xspf'
[0x9b984b8] main access debug: using access module "filesystem"
[0x9b984b8] main access debug: TIMER module_need() : 1.134 ms - Total 1.134 ms / 1 intvls (Avg 1.134 ms)
[0x9b994a8] main stream debug: Using stream method for AStream*
[0x9b994a8] main stream debug: starting pre-buffering
[0x9b994a8] main stream debug: received first data after 8 ms
[0x9b994a8] main stream debug: pre-buffering done 296 bytes in 0s - 34 KiB/s
[0x9b98d08] main stream debug: looking for stream_filter module: 7 candidates
[0x9b98d08] main stream debug: no stream_filter module matching "any" could be loaded
[0x9b98d08] main stream debug: TIMER module_need() : 67.557 ms - Total 67.557 ms / 1 intvls (Avg 67.557 ms)
[0x9b98d08] main stream debug: looking for stream_filter module: 1 candidate
[0x9b98d08] main stream debug: using stream_filter module "stream_filter_record"
[0x9b98d08] main stream debug: TIMER module_need() : 16.582 ms - Total 16.582 ms / 1 intvls (Avg 16.582 ms)
[0x9b24f40] main input debug: creating demux: access='file' demux='xspf-open' location='/home/a/.local/share/vlc/ml.xspf' file='/home/a/.local/share/vlc/ml.xspf'
[0x9b99820] main demux debug: looking for demux module: 1 candidate
[0x9b99820] playlist demux debug: using XSPF playlist reader
[0x9b99820] main demux debug: using demux module "playlist"
[0x9b99820] main demux debug: TIMER module_need() : 0.484 ms - Total 0.484 ms / 1 intvls (Avg 0.484 ms)
[0x9b9bc18] main demux meta debug: looking for meta reader module: 2 candidates
[0x9b9bc18] lua demux meta debug: Trying Lua scripts in /home/a/.local/share/vlc/lua/meta/reader
[0x9b9bc18] lua demux meta debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/reader
[0x9b9bc18] lua demux meta debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/reader/filename.luac
[0x9b9bc18] lua demux meta debug: Trying Lua scripts in /usr/share/vlc/lua/meta/reader
[0x9b9bc18] main demux meta debug: no meta reader module matching "any" could be loaded
[0x9b9bc18] main demux meta debug: TIMER module_need() : 80.189 ms - Total 80.189 ms / 1 intvls (Avg 80.189 ms)
[0x9b24f40] main input debug: `file/xspf-open:///home/a/.local/share/vlc/ml.xspf' successfully opened
[0x9ba85a8] main xml reader debug: looking for xml reader module: 1 candidate
[0x9ba85a8] main xml reader debug: using xml reader module "xml"
[0x9ba85a8] main xml reader debug: TIMER module_need() : 16.760 ms - Total 16.760 ms / 1 intvls (Avg 16.760 ms)
[0x9b99820] playlist demux debug: parsed 0 tracks successfully
[0x9b24f40] main input debug: EOF reached
[0x9b99820] main demux debug: removing module "playlist"
[0x9b98d08] main stream debug: removing module "stream_filter_record"
[0x9b984b8] main access debug: removing module "filesystem"
[0x9b24f40] main input debug: TIMER input launching for 'Media Library' : 178.229 ms - Total 178.229 ms / 1 intvls (Avg 178.229 ms)
[0x9b994a8] main interface debug: looking for interface module: 1 candidate
[0x9b994a8] main interface debug: using interface module "hotkeys"
[0x9b994a8] main interface debug: TIMER module_need() : 2.435 ms - Total 2.435 ms / 1 intvls (Avg 2.435 ms)
[0x9b99820] main interface debug: looking for interface module: 1 candidate
[0x9b99820] dbus interface: listening on dbus as: org.mpris.MediaPlayer2.vlc
[0x9b92648] main playlist debug: playlist threads correctly activated
[0x9b99820] dbus interface debug: Adding dbus watch on fd 6
[0x9b99820] dbus interface debug: Watching fd 6 for writeability
[0x9b99820] dbus interface debug: Adding dbus watch on fd 6
[0x9b99820] dbus interface debug: Watching fd 6 for readability
[0x9b99820] main interface debug: using interface module "dbus"
[0x9b99820] main interface debug: TIMER module_need() : 4.413 ms - Total 4.413 ms / 1 intvls (Avg 4.413 ms)
[0x9b94ef8] main interface debug: looking for interface module: 1 candidate
[0x9b99820] dbus interface debug: 4 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9b92648] main playlist debug: rebuilding array of current - root Playlist
[0x9b92648] main playlist debug: rebuild done - 0 items, index -1
[0x9b94ef8] main interface debug: using interface module "inhibit"
[0x9b94ef8] main interface debug: TIMER module_need() : 5.310 ms - Total 5.310 ms / 1 intvls (Avg 5.310 ms)
[0x9b96958] main interface debug: looking for interface module: 1 candidate
[0x9b96958] main interface debug: using interface module "globalhotkeys"
[0x9b96958] main interface debug: TIMER module_need() : 47.340 ms - Total 47.340 ms / 1 intvls (Avg 47.340 ms)
[0x9b158b0] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[0x9ba79f0] main interface debug: looking for interface module: 6 candidates
[0x9c58be0] main generic debug: looking for extension module: 1 candidate
[0x9c58be0] lua generic debug: Opening Lua Extension module
[0x9c58be0] lua generic debug: Trying Lua scripts in /home/a/.local/share/vlc/lua/extensions
[0x9c58be0] lua generic debug: Trying Lua scripts in /usr/lib/vlc/lua/extensions
[0x9c58be0] lua generic debug: Trying Lua scripts in /usr/share/vlc/lua/extensions
[0x9c58be0] main generic debug: using extension module "lua"
[0x9c58be0] main generic debug: TIMER module_need() : 6.048 ms - Total 6.048 ms / 1 intvls (Avg 6.048 ms)
[0x9c99cf0] main probe debug: looking for services probe module: 7 candidates
[0x9c99cf0] main probe debug: no services probe module matching "any" could be loaded
[0x9c99cf0] main probe debug: TIMER module_need() : 240.294 ms - Total 240.294 ms / 1 intvls (Avg 240.294 ms)
[0x9ba79f0] qt4 interface debug: Normal PL/ML or SD
[0x9ba79f0] qt4 interface debug: Normal PL/ML or SD
[0x9ba79f0] main interface debug: using interface module "qt4"
[0x9ba79f0] main interface debug: TIMER module_need() : 1722.747 ms - Total 1722.747 ms / 1 intvls (Avg 1722.747 ms)
[0x9b92648] main playlist debug: adding item `shrek1.iso' ( file:///home/a/Videos/shrek1.iso )
[0x9b99820] dbus interface debug: Got a VLC event on playlist-item-append. The main loop needs to wake up in order to process it
[0x9b99820] dbus interface debug: Sending wakeup signal to the main loop
[0x9b99820] dbus interface debug: the main loop has been woken up
[0x9b99820] dbus interface debug: Removing a byte from the self-pipe
[0x9b92648] main playlist debug: rebuilding array of current - root Playlist
[0x9b92648] main playlist debug: rebuild done - 1 items, index -1
[0x9b99820] dbus interface debug: Nothing happened on fd 6
[0x9b99820] dbus interface debug: Dispatching incoming dbus message
[0x9b99820] dbus interface debug: Dispatching incoming dbus message
[0x9b99820] dbus interface debug: DBus dispatch status changed to COMPLETE.
[0x9b99820] dbus interface debug: 5013522 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9ba79f0] qt4 interface debug: Adding a new MRL to recent ones: file:///home/a/Videos/shrek1.iso
[0x9c0b528] main input debug: Creating an input for 'shrek1.iso'
[0x9b92648] main playlist debug: meta ok for (null), need to fetch art
[0x9c0b528] main demux meta debug: looking for meta fetcher module: 1 candidate
[0x9c0b528] lua demux meta debug: Trying Lua scripts in /home/a/.local/share/vlc/lua/meta/fetcher
[0x9c0b528] lua demux meta debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/fetcher
[0x9c0b528] lua demux meta debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/fetcher/tvrage.luac
[0x9c0b528] main demux meta debug: using meta fetcher module "lua"
[0x9c0b528] main demux meta debug: TIMER module_need() : 8.891 ms - Total 8.891 ms / 1 intvls (Avg 8.891 ms)
[0x9c0b528] main demux meta debug: removing module "lua"
[0x9b92648] main playlist debug: searching art for shrek1.iso
[0x9c0b528] main art finder debug: looking for art finder module: 2 candidates
[0x9c0b528] lua art finder debug: Trying Lua scripts in /home/a/.local/share/vlc/lua/meta/art
[0x9c0b528] lua art finder debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/art
[0x9c0b528] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/00_musicbrainz.luac
[0x9c0b528] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/01_googleimage.luac
[0x9c0b528] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/02_frenchtv.luac
[0x9c0b528] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/03_lastfm.luac
[0x9c0b528] lua art finder debug: Trying Lua scripts in /usr/share/vlc/lua/meta/art
[0x9c0b528] main art finder debug: no art finder module matching "any" could be loaded
[0x9c0b528] main art finder debug: TIMER module_need() : 8.951 ms - Total 8.951 ms / 1 intvls (Avg 8.951 ms)
[0x9b92648] main playlist debug: art not found for shrek1.iso
[0x9b92648] main playlist debug: processing request item: null, node: Playlist, skip: 0
[0x9b92648] main playlist debug: starting playback of the new playlist item
[0x9b92648] main playlist debug: creating new input thread
[0x9bff598] main input debug: Creating an input for 'shrek1.iso'
[0x9ba79f0] qt4 interface debug: IM: Setting an input
[0x9b99820] dbus interface debug: Got a VLC event on item-current. The main loop needs to wake up in order to process it
[0x9b99820] dbus interface debug: Sending wakeup signal to the main loop
[0x9b99820] dbus interface debug: the main loop has been woken up
[0x9b99820] dbus interface debug: Removing a byte from the self-pipe
[0x9b99820] dbus interface debug: Nothing happened on fd 6
[0x9b99820] dbus interface debug: 8597292 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9bff598] main input debug: using timeshift granularity of 50 MiB, in path '/tmp'
[0x9b99820] dbus interface debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
[0x9b99820] dbus interface debug: Sending wakeup signal to the main loop
[0x9b99820] dbus interface debug: the main loop has been woken up
[0x9b99820] dbus interface debug: Removing a byte from the self-pipe
[0x9b99820] dbus interface debug: Nothing happened on fd 6
[0x9b99820] dbus interface debug: 8443 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9bff598] main input debug: `file:///home/a/Videos/shrek1.iso' gives access `file' demux `' path `/home/a/Videos/shrek1.iso'
[0x9bff598] main input debug: creating demux: access='file' demux='' location='/home/a/Videos/shrek1.iso' file='/home/a/Videos/shrek1.iso'
[0x9d0ba98] main demux debug: looking for access_demux module: 2 candidates
libdvdnav: Using dvdnav version 4.2.0
libdvdread: Using libdvdcss version 1.2.11 for DVD access
libdvdnav: DVD Title: SHREK1
libdvdnav: DVD Serial Number: 012FC9F5DVDSHRNK
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find map file '/home/a/.dvdnav/SHREK1.map'
libdvdnav: DVD disk reports itself with Region mask 0x00c00000. Regions: 1 2 3 4 5 6
libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient
libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000139
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x000001ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x0001a15f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x001b8dc4
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x001b8df9
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x001c43c0
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x001c43f5
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x001cc87a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x001cc8b0
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x001cdcb8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x001cdcee
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x0021bad6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x0021bb0c
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x00222b3c
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x00222b72
libdvdread: Elapsed time 0
libdvdread: Found 7 VTS's
libdvdread: Elapsed time 0
[0x9d0ba98] dvdnav demux debug: trying to go to dvd menu
[0x9d0ba98] main demux debug: using access_demux module "dvdnav"
[0x9d0ba98] main demux debug: TIMER module_need() : 80.085 ms - Total 80.085 ms / 1 intvls (Avg 80.085 ms)
[0x9b99820] dbus interface debug: Got a VLC event on can-pause. The main loop needs to wake up in order to process it
[0x9b99820] dbus interface debug: Sending wakeup signal to the main loop
[0x9b99820] dbus interface debug: the main loop has been woken up
[0x9b99820] dbus interface debug: Removing a byte from the self-pipe
[0x9b99820] dbus interface debug: Nothing happened on fd 6
[0x9b99820] dbus interface debug: 88100 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9b99820] dbus interface debug: Got a VLC event on can-seek. The main loop needs to wake up in order to process it
[0x9b99820] dbus interface debug: Sending wakeup signal to the main loop
[0x9b99820] dbus interface debug: the main loop has been woken up
[0x9b99820] dbus interface debug: Removing a byte from the self-pipe
[0x9b99820] dbus interface debug: Nothing happened on fd 6
[0x9b99820] dbus interface debug: 374 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9bff598] main input debug: looking for a subtitle file in /home/a/Videos
[0x9d4e988] main demux meta debug: looking for meta reader module: 2 candidates
[0x9d4e988] lua demux meta debug: Trying Lua scripts in /home/a/.local/share/vlc/lua/meta/reader
[0x9d4e988] lua demux meta debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/reader
[0x9d4e988] lua demux meta debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/reader/filename.luac
[0x9d4e988] lua demux meta debug: Trying Lua scripts in /usr/share/vlc/lua/meta/reader
[0x9d4e988] main demux meta debug: no meta reader module matching "any" could be loaded
[0x9d4e988] main demux meta debug: TIMER module_need() : 4.007 ms - Total 4.007 ms / 1 intvls (Avg 4.007 ms)
[0x9b99820] dbus interface debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
[0x9b99820] dbus interface debug: Sending wakeup signal to the main loop
[0x9b99820] dbus interface debug: the main loop has been woken up
[0x9b99820] dbus interface debug: Removing a byte from the self-pipe
[0x9b99820] dbus interface debug: Nothing happened on fd 6
[0x9b99820] dbus interface debug: 11183 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9bff598] main input debug: `file:///home/a/Videos/shrek1.iso' successfully opened
[0x9d0ba98] dvdnav demux debug: DVDNAV_HOP_CHANNEL
[0x9bff598] main input error: ES_OUT_RESET_PCR called
[0x9d0ba98] dvdnav demux debug: DVDNAV_VTS_CHANGE
[0x9d0ba98] dvdnav demux debug:      - vtsN=1
[0x9d0ba98] dvdnav demux debug:      - domain=8
[0x9bff598] main input error: ES_OUT_RESET_PCR called
[0x9d0ba98] dvdnav demux debug: DVDNAV_CELL_CHANGE
[0x9d0ba98] dvdnav demux debug:      - cellN=1
[0x9d0ba98] dvdnav demux debug:      - pgN=1
[0x9d0ba98] dvdnav demux debug:      - cell_length=2833200
[0x9d0ba98] dvdnav demux debug:      - pg_length=2833200
[0x9d0ba98] dvdnav demux debug:      - pgc_length=2833200
[0x9d0ba98] dvdnav demux debug:      - cell_start=0
[0x9d0ba98] dvdnav demux debug:      - pg_start=0
[0x9d0ba98] dvdnav demux debug: DVDNAV_SPU_CLUT_CHANGE
[0x9d0ba98] dvdnav demux debug: DVDNAV_SPU_STREAM_CHANGE
[0x9d0ba98] dvdnav demux debug:      - physical_wide=0
[0x9d0ba98] dvdnav demux debug:      - physical_letterbox=1
[0x9d0ba98] dvdnav demux debug:      - physical_pan_scan=0
[0x9d0ba98] dvdnav demux debug: buttonUpdate not done b=1 t=0
[0x9bff598] main input debug: selecting program id=0
[0x9b99820] dbus interface debug: Got a VLC event on intf-event. The main loop needs to wake up in order to process it
[0x9b99820] dbus interface debug: Sending wakeup signal to the main loop
[0x9b99820] dbus interface debug: the main loop has been woken up
[0x9b99820] dbus interface debug: Removing a byte from the self-pipe
[0x9b99820] dbus interface debug: Nothing happened on fd 6
[0x9b99820] dbus interface debug: 4301 µs elapsed since last wakeup
[0x9b99820] dbus interface debug: Sleeping until something happens
[0x9d513a0] main decoder debug: looking for decoder module: 26 candidates
[0x9ba79f0] qt4 interface debug: Title 8
[0x9ba79f0] qt4 interface debug: Chapter: 7
[0x9d513a0] main decoder debug: using decoder module "spudec"
[0x9d513a0] main decoder debug: TIMER module_need() : 66.940 ms - Total 66.940 ms / 1 intvls (Avg 66.940 ms)
[0x9d513a0] spudec decoder debug: invalid starting packet (size < 4 or pts <=0)
[0x9d513a0] spudec decoder debug: spu size: 0, i_pts: 0 i_buffer: 128
[0x9d0ba98] dvdnav demux debug: DVDNAV_AUDIO_STREAM_CHANGE
[0x9d0ba98] dvdnav demux debug:      - physical=0
[0x9bff598] main input debug: Buffering 0%
[0x9d0ba98] dvdnav demux debug: buttonUpdate 1
[0x9bff598] main input debug: Buffering 0%
[0x9d80840] main decoder debug: looking for decoder module: 26 candidates
[0x9d80840] avcodec decoder debug: libavcodec initialized (interface 0x347b00)
[0x9d80840] avcodec decoder debug: trying to use direct rendering
[0x9d80840] avcodec decoder debug: allowing 1 thread(s) for decoding
[0x9d80840] avcodec decoder debug: ffmpeg codec (MPEG-1/2 Video) started
[0x9d80840] main decoder debug: using decoder module "avcodec"
[0x9d80840] main decoder debug: TIMER module_need() : 22.055 ms - Total 22.055 ms / 1 intvls (Avg 22.055 ms)
[0x9d81250] main packetizer debug: looking for packetizer module: 20 candidates
[0x9d81250] main packetizer debug: using packetizer module "packetizer_mpegvideo"
[0x9d81250] main packetizer debug: TIMER module_need() : 4.904 ms - Total 4.904 ms / 1 intvls (Avg 4.904 ms)
[0x9d0ba98] dvdnav demux debug: buttonUpdate 1
[0x9bff598] main input debug: Buffering 1%
[0x9bff598] main input debug: Buffering 1%
[0x9bff598] main input debug: idx1=-1(??) idx2=-1(??)
[0x9bff598] main input debug: Buffering 5%
[0x9bff598] main input debug: Buffering 6%
[0x9bff598] main input debug: Buffering 7%
[0x9bff598] main input debug: Buffering 7%
[0x9d81250] packetizer_mpegvideo packetizer debug: size 720x576 fps=25.000
[0x9bff598] main input debug: Buffering 8%
###########
... repeating text about buffering
###########
[0x9bff598] main input debug: Buffering 31%
[0x9d7fb48] main spu text debug: looking for text renderer module: 3 candidates
[0x9d7fb48] freetype spu text error: failed to load font file DejaVu Sans
[0x9bff598] main input debug: Buffering 31%
###########
... repeating text about buffering
###########
[0x9bff598] main input debug: Buffering 35%
[0x9d7b5b8] main decoder debug: looking for decoder module: 26 candidates
[0x9d7b5b8] main decoder debug: using decoder module "a52"
[0x9d7b5b8] main decoder debug: TIMER module_need() : 0.446 ms - Total 0.446 ms / 1 intvls (Avg 0.446 ms)
[0x9bff598] main input debug: Buffering 36%
###########
... repeating text about buffering
###########
[0x9bff598] main input debug: Buffering 68%
[0x9d7b5b8] a52 decoder debug: A/52 channels:2 samplerate:48000 bitrate:192000
[0x9bff598] main input debug: Buffering 69%
###########
... repeating text about buffering
###########
[0x9bff598] main input debug: Buffering 88%
[0x9bff598] main input debug: Stream buffering done (344 ms in 83 ms)
[0x9d7fb48] main spu text debug: using text renderer module "svg"
[0x9d7fb48] main spu text debug: TIMER module_need() : 75.654 ms - Total 75.654 ms / 1 intvls (Avg 75.654 ms)
[0x9d783f8] main scale debug: looking for video filter2 module: 18 candidates
[0x9d783f8] swscale scale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
[0x9d783f8] main scale debug: using video filter2 module "swscale"
[0x9d783f8] main scale debug: TIMER module_need() : 7.619 ms - Total 7.619 ms / 1 intvls (Avg 7.619 ms)
[0x9e601a8] main scale debug: looking for video filter2 module: 18 candidates
[0x9e601a8] yuvp scale debug: YUVP to YUVA converter
[0x9e601a8] main scale debug: using video filter2 module "yuvp"
[0x9e601a8] main scale debug: TIMER module_need() : 13.930 ms - Total 13.930 ms / 1 intvls (Avg 13.930 ms)
[0x9df5d08] main video output debug: Deinterlacing available
[0x9df5d08] main video output debug: deinterlace -1, mode blend, is_needed 0
[0x9df5d08] main video output debug: Opening vout display wrapper
[0x9e7a460] main vout display debug: looking for vout display module: 6 candidates
[0x9e7d938] main window debug: looking for vout window xid module: 4 candidates
[0x9e7d938] qt4 window debug: requesting video...
[0x9ba79f0] qt4 interface debug: Video was requested 0, 0
[0x9e7d938] main window debug: using vout window xid module "qt4"
[0x9e7d938] main window debug: TIMER module_need() : 80.572 ms - Total 80.572 ms / 1 intvls (Avg 80.572 ms)
[0x9e7dd68] main inhibit debug: looking for inhibit module: 2 candidates
[0x9e7dd68] main inhibit debug: using inhibit module "xdg_screensaver"
[0x9e7dd68] main inhibit debug: TIMER module_need() : 4.077 ms - Total 4.077 ms / 1 intvls (Avg 4.077 ms)
[0x9e7dd68] xdg_screensaver inhibit debug: started xdg-screensaver (PID = 8428)
[0x9e7a460] xcb_xv vout display debug: connected to X11.0 server
[0x9e7a460] xcb_xv vout display debug:  vendor : The X.Org Foundation
[0x9e7a460] xcb_xv vout display debug:  version: 11102000
[0x9e7a460] xcb_xv vout display debug: using screen 0x142
[0x9e7a460] xcb_xv vout display debug: using XVideo extension v2.2
[0x9e7a460] xcb_xv vout display debug: using adaptor SIS 300/315/330 series Video Overlay
[0x9e7a460] xcb_xv vout display debug: using port 62
[0x9e7a460] xcb_xv vout display debug: using image format 0x30323449
[0x9e7a460] xcb_xv vout display debug: using X11 visual ID 0x21 (depth: 24)
[0x9e7a460] xcb_xv vout display debug: using X11 window 0x03400000
[0x9e7a460] xcb_xv vout display debug: using X11 graphic context 0x03400002
[0x9e7a460] main vout display debug: VoutDisplayEvent 'fullscreen' 0
[0x9e7a460] main vout display debug: VoutDisplayEvent 'resize' 1024x576 window
[0x9e7a460] main vout display debug: using vout display module "xcb_xv"
[0x9e7a460] main vout display debug: TIMER module_need() : 357.331 ms - Total 357.331 ms / 1 intvls (Avg 357.331 ms)
[0x9df5d08] main video output debug: original format sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 64:45, msk r0x0 g0x0 b0x0
[0x9bff598] main input debug: crop: 134,455,71,65, palette forced: 1
[0x9d7fb48] main spu text debug: removing module "svg"
[0x9d7fb48] main spu text debug: looking for text renderer module: 3 candidates
[0x9d7fb48] freetype spu text error: failed to load font file DejaVu Sans
[0x9d7fb48] main spu text debug: using text renderer module "svg"
[0x9d7fb48] main spu text debug: TIMER module_need() : 4.055 ms - Total 4.055 ms / 1 intvls (Avg 4.055 ms)
[0x9d80840] avcodec decoder debug: using direct rendering
[0x9b92648] main playlist debug: creating audio output
[0x9e85948] main audio output debug: looking for audio output module: 2 candidates
[0x9e85948] pulse audio output debug: using stereo channel map
[0x9e85948] pulse audio output debug: using library version 1.1.0
[0x9e85948] pulse audio output debug:  (compiled with version 1.1.0, protocol 23)
[0x9e85948] pulse audio output debug: connected locally to unix:/home/a/.pulse/aff07c49c7444ba2008bb92000005a70-runtime/native as client #12
[0x9e85948] pulse audio output debug: using protocol 23, server protocol 23
[0x9e85948] pulse audio output debug: digital pass-through not available
[0x9e85948] pulse audio output debug: using buffer metrics: maxlength=4194304, tlength=21504, prebuf=0, minreq=7680
[0x9e85948] pulse audio output debug: connected to sink 0: alsa_output.pci-0000_00_02.7.analog-stereo
[0x9e85948] main audio output debug: using audio output module "pulse"
[0x9e85948] main audio output debug: TIMER module_need() : 57.289 ms - Total 57.289 ms / 1 intvls (Avg 57.289 ms)
[0x9e85948] main audio output debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
[0x9e85948] main audio output debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
[0x9e85948] main audio output debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x9e85948] main audio output debug: conversion pipeline completed
[0x9e8dea8] main mixer debug: looking for audio mixer module: 2 candidates
[0x9e8dea8] main mixer debug: using audio mixer module "float32_mixer"
[0x9e8dea8] main mixer debug: TIMER module_need() : 5.814 ms - Total 5.814 ms / 1 intvls (Avg 5.814 ms)
[0x9e85948] pulse audio output debug: listing sink alsa_output.pci-0000_00_02.7.analog-stereo (0): Internal Audio Analog Stereo
[0x9e85948] main audio output debug: input 'a52 ' 48000 Hz Stereo frame=1536 samples/768 bytes
[0x9e95728] main audio filter debug: looking for audio filter module: 1 candidate
[0x9e95728] scaletempo audio filter debug: format: 48000 rate, 2 nch, 4 bps, fl32
[0x9e95728] scaletempo audio filter debug: params: 30 stride, 0.200 overlap, 14 search
[0x9e95728] scaletempo audio filter debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
[0x9e95728] main audio filter debug: using audio filter module "scaletempo"
[0x9e95728] main audio filter debug: TIMER module_need() : 12.126 ms - Total 12.126 ms / 1 intvls (Avg 12.126 ms)
[0x9e85948] main audio output debug: filter(s) 'a52 '->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x9e85948] pulse audio output debug: base volume: 41350
[0x9e94860] main audio filter debug: looking for audio filter module: 12 candidates
[0x9e7a460] xcb_xv vout display debug: display is visible
No accelerated IMDCT transform found
[0x9e94860] main audio filter debug: using audio filter module "a52tofloat32"
[0x9e94860] main audio filter debug: TIMER module_need() : 58.154 ms - Total 58.154 ms / 1 intvls (Avg 58.154 ms)
[0x9e85948] main audio output debug: conversion pipeline completed
[0x9e85948] main audio output debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x9e85948] main audio output debug: conversion pipeline completed
[0x9e85948] main audio output debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo
[0x9e96020] main audio filter debug: looking for audio filter module: 12 candidates
[0x9e96020] main audio filter debug: using audio filter module "ugly_resampler"
[0x9e96020] main audio filter debug: TIMER module_need() : 36.702 ms - Total 36.702 ms / 1 intvls (Avg 36.702 ms)
[0x9e85948] main audio output debug: conversion pipeline completed
[0x9d80840] main decoder debug: End of video preroll
[0x9d7b5b8] main decoder debug: End of audio preroll
[0x9d80840] main decoder debug: Received first picture
[0x9ef6808] main blend debug: looking for video blending module: 1 candidate
[0x9ef6808] main blend debug: using video blending module "blend"
[0x9ef6808] main blend debug: TIMER module_need() : 6.178 ms - Total 6.178 ms / 1 intvls (Avg 6.178 ms)
[0x9df5d08] main video output debug: Post-processing available
[0x9df5d08] main video output debug: Detected interlaced video
[0x9df5d08] main video output debug: deinterlace -1, mode blend, is_needed 1
[0x9df5d08] main video output debug: Adding 'deinterlace' as static
[0x9efb6f8] main filter debug: looking for video filter2 module: 1 candidate
[0x9efb6f8] deinterlace filter debug: using blend deinterlace method
[0x9efb6f8] deinterlace filter debug: deinterlacing
[0x9efb6f8] main filter debug: using video filter2 module "deinterlace"
[0x9efb6f8] main filter debug: TIMER module_need() : 13.730 ms - Total 13.730 ms / 1 intvls (Avg 13.730 ms)
[0x9df5d08] main video output debug: Filter 'deinterlace' (0x9efb6f8) appended to chain
[0x9bff598] main input debug: Decoder buffering done in 869 ms
[0x9e85948] pulse audio output debug: deferring start (321402 us)
[0x9e7a460] main vout display debug: auto hiding mouse cursor
[0x9d859f0] main subpicture warning: original picture size is undefined
[0x9e85948] pulse audio output debug: starting deferred
[0x9e85948] pulse audio output debug: started
[0x9e85948] pulse audio output debug: listing sink alsa_output.pci-0000_00_02.7.analog-stereo (0): Internal Audio Analog Stereo
[0x9e7a460] main vout display debug: auto hiding mouse cursor
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 192 ms)
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 152 ms)
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 112 ms)
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 72 ms)
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 32 ms)
[0x9e7a460] xcb_xv vout display debug: display is not visible
[0x9e7a460] xcb_xv vout display debug: display is visible
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 110 ms)
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 70 ms)
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 30 ms)
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 28 ms)
[0x9df5d08] main video output debug: picture might be displayed late (missing 5 ms)
[0x9e7a460] xcb_xv vout display debug: display is visible
[0x9e7a460] xcb_xv vout display debug: display is visible
[0x9d0ba98] dvdnav demux debug: DVDNAV_HIGHLIGHT
[0x9d0ba98] dvdnav demux debug:      - display=1
[0x9d0ba98] dvdnav demux debug:      - buttonN=3
[0x9bff598] main input debug: crop: 291,450,102,76, palette forced: 1
[0x9d0ba98] dvdnav demux debug: buttonUpdate 3
[0x9df5d08] main video output warning: picture is too late to be displayed (missing 39 ms)
[0x9d0ba98] dvdnav demux debug: DVDNAV_HIGHLIGHT
[0x9d0ba98] dvdnav demux debug:      - display=1
[0x9d0ba98] dvdnav demux debug:      - buttonN=1
[0x9bff598] main input debug: crop: 134,455,71,65, palette forced: 1
[0x9d0ba98] dvdnav demux debug: buttonUpdate 1
[0x9e7a460] main vout display debug: auto hiding mouse cursor
[0x9e7a460] main vout display debug: VoutDisplayEvent 'mouse button' 0 t=8
[0x9bff598] main input debug: crop: 134,455,71,65, palette forced: 1
[0x9d0ba98] dvdnav demux debug: buttonUpdate 1
[0x9d0ba98] dvdnav demux debug: DVDNAV_HOP_CHANNEL
[0x9bff598] main input error: ES_OUT_RESET_PCR called
[0x9d513a0] spudec decoder debug: invalid starting packet (size < 4 or pts <=0)
[0x9d513a0] spudec decoder debug: spu size: 0, i_pts: 0 i_buffer: 128
[0x9e85948] pulse audio output warning: underflow
[0x9d0ba98] dvdnav demux debug: DVDNAV_CELL_CHANGE
[0x9d0ba98] dvdnav demux debug:      - cellN=1
[0x9d0ba98] dvdnav demux debug:      - pgN=1
[0x9d0ba98] dvdnav demux debug:      - cell_length=705600
[0x9d0ba98] dvdnav demux debug:      - pg_length=705600
[0x9d0ba98] dvdnav demux debug:      - pgc_length=705600
[0x9d0ba98] dvdnav demux debug:      - cell_start=0
[0x9d0ba98] dvdnav demux debug:      - pg_start=0
[0x9d0ba98] dvdnav demux debug: DVDNAV_SPU_CLUT_CHANGE
[0x9d0ba98] dvdnav demux debug: DVDNAV_SPU_STREAM_CHANGE
[0x9d0ba98] dvdnav demux debug:      - physical_wide=0
[0x9d0ba98] dvdnav demux debug:      - physical_letterbox=1
[0x9d0ba98] dvdnav demux debug:      - physical_pan_scan=0
[0x9bff598] main input debug: crop: 134,455,71,65, palette forced: 1
[0x9d0ba98] dvdnav demux debug: buttonUpdate 1
[0x9e85948] pulse audio output debug: listing sink alsa_output.pci-0000_00_02.7.analog-stereo (0): Internal Audio Analog Stereo
[0x9d513a0] main decoder debug: removing module "spudec"
[0x9d513a0] main decoder debug: killing decoder fourcc `spu ', 0 PES in FIFO
[0x9d513a0] main decoder debug: looking for decoder module: 26 candidates
[0x9d513a0] main decoder debug: using decoder module "spudec"
[0x9d513a0] main decoder debug: TIMER module_need() : 0.441 ms - Total 0.441 ms / 1 intvls (Avg 0.441 ms)
[0x9d513a0] spudec decoder debug: invalid starting packet (size < 4 or pts <=0)
[0x9d513a0] spudec decoder debug: spu size: 0, i_pts: 0 i_buffer: 128
[0x9d0ba98] dvdnav demux debug: DVDNAV_AUDIO_STREAM_CHANGE
[0x9d0ba98] dvdnav demux debug:      - physical=0
[0x9bff598] main input debug: Buffering 0%
[0x9d0ba98] dvdnav demux debug: buttonUpdate not done b=1 t=0
[0x9bff598] main input debug: Buffering 0%
###########
... repeating text about buffering
###########
[0x9bff598] main input debug: Buffering 38%
[0x9e7a460] main vout display debug: VoutDisplayEvent 'mouse button' 0 t=9
[0x9bff598] main input debug: Buffering 39%
[0x9bff598] main input debug: Buffering 39%
[0x9bff598] main input debug: Buffering 40%
[0x9d7b5b8] main decoder debug: End of audio preroll
[0x9bff598] main input debug: Buffering 44%
###########
... repeating text about buffering
###########
[0x9bff598] main input debug: Buffering 94%
[0x9bff598] main input debug: Stream buffering done (344 ms in 139 ms)
[0x9d80840] main decoder debug: End of video preroll
[0x9d80840] main decoder debug: Received first picture
[0x9bff598] main input debug: Decoder buffering done in 105 ms
[0x9e85948] pulse audio output debug: deferring start (321594 us)
[0x9d859f0] main subpicture warning: original picture size is undefined
[0x9ef6808] main blend debug: removing module "blend"
[0x9ef6808] main blend debug: looking for video blending module: 1 candidate
[0x9ef6808] main blend debug: using video blending module "blend"
[0x9ef6808] main blend debug: TIMER module_need() : 0.300 ms - Total 0.300 ms / 1 intvls (Avg 0.300 ms)
[0x9e85948] pulse audio output debug: starting deferred
[0x9e85948] pulse audio output debug: started
[0x9e85948] pulse audio output debug: listing sink alsa_output.pci-0000_00_02.7.analog-stereo (0): Internal Audio Analog Stereo
[0x9e7a460] main vout display debug: auto hiding mouse cursor
[0x9e7a460] xcb_xv vout display debug: display is visible
[0x9df5d08] main video output debug: picture might be displayed late (missing 7 ms)
[0x9e7a460] xcb_xv vout display debug: display is visible
[0x9e7a460] main vout display debug: auto hiding mouse cursor
[0x9e7a460] xcb_xv vout display debug: display is not visible
[0x9d0ba98] dvdnav demux debug: DVDNAV_NOP
[0x9d0ba98] dvdnav demux debug: DVDNAV_VTS_CHANGE
[0x9d0ba98] dvdnav demux debug:      - vtsN=1
[0x9d0ba98] dvdnav demux debug:      - domain=2
[0x9bff598] main input error: ES_OUT_RESET_PCR called
[0x9d513a0] spudec decoder debug: invalid starting packet (size < 4 or pts <=0)
[0x9d513a0] spudec decoder debug: spu size: 0, i_pts: 0 i_buffer: 128
[0x9d80840] main decoder debug: removing module "avcodec"
[0x9d80840] avcodec decoder debug: ffmpeg codec (MPEG-1/2 Video) stopped
[0x9d80840] main decoder debug: killing decoder fourcc `mpgv', 0 PES in FIFO
[0x9e85948] pulse audio output warning: too early by 541932 us
[0x9e85948] pulse audio output debug: changed sample rate to 47954 Hz
[0x9e85948] pulse audio output warning: underflow
[0x9b92648] main playlist debug: saving a free vout
[0x9b92648] main playlist debug: reusing provided vout
[0x9d81250] main packetizer debug: removing module "packetizer_mpegvideo"
[0x9d513a0] main decoder debug: removing module "spudec"
[0x9d513a0] main decoder debug: killing decoder fourcc `spu ', 0 PES in FIFO
[0x9d7b5b8] main decoder debug: removing module "a52"
[0x9d7b5b8] main decoder debug: killing decoder fourcc `a52 ', 0 PES in FIFO
[0x9e85948] pulse audio output debug: listing sink alsa_output.pci-0000_00_02.7.analog-stereo (0): Internal Audio Analog Stereo
[0x9e94860] main audio filter debug: removing module "a52tofloat32"
[0x9e95728] main audio filter debug: removing module "scaletempo"
[0x9e96020] main audio filter debug: removing module "ugly_resampler"
[0x9e85948] main audio output debug: removing module "pulse"
[0x9e8dea8] main mixer debug: removing module "float32_mixer"
[0x9b92648] main playlist debug: releasing audio output
[0x9bff598] main input debug: Program doesn't contain anymore ES
[0x9d0ba98] dvdnav demux debug: DVDNAV_CELL_CHANGE
[0x9d0ba98] dvdnav demux debug:      - cellN=1
[0x9d0ba98] dvdnav demux debug:      - pgN=1
[0x9d0ba98] dvdnav demux debug:      - cell_length=25624800
[0x9d0ba98] dvdnav demux debug:      - pg_length=25624800
[0x9d0ba98] dvdnav demux debug:      - pgc_length=466452000
[0x9d0ba98] dvdnav demux debug:      - cell_start=0
[0x9d0ba98] dvdnav demux debug:      - pg_start=0
[0x9d0ba98] dvdnav demux debug: DVDNAV_SPU_CLUT_CHANGE
[0x9d0ba98] dvdnav demux debug: DVDNAV_SPU_STREAM_CHANGE
[0x9ba79f0] qt4 interface debug: Title 8
[0x9ba79f0] qt4 interface debug: Chapter: 20
[0x9d0ba98] dvdnav demux debug:      - physical_wide=128
[0x9d0ba98] dvdnav demux debug:      - physical_letterbox=128
[0x9d0ba98] dvdnav demux debug:      - physical_pan_scan=128
[0x9d0ba98] dvdnav demux debug: buttonUpdate not done b=1 t=1
[0x9d0ba98] dvdnav demux debug: DVDNAV_AUDIO_STREAM_CHANGE
[0x9d0ba98] dvdnav demux debug:      - physical=0
[0x9bff598] main input debug: Buffering 0%
[0x9d0ba98] dvdnav demux debug: buttonUpdate not done b=1 t=1
[0x9bff598] main input debug: Buffering 0%
[0x9d7b5b8] main decoder debug: looking for decoder module: 26 candidates
[0x9d7b5b8] avcodec decoder debug: libavcodec already initialized
[0x9d7b5b8] avcodec decoder debug: trying to use direct rendering
[0x9d7b5b8] avcodec decoder debug: allowing 1 thread(s) for decoding
[0x9d7b5b8] avcodec decoder debug: ffmpeg codec (MPEG-1/2 Video) started
[0x9d7b5b8] main decoder debug: using decoder module "avcodec"
[0x9d7b5b8] main decoder debug: TIMER module_need() : 1.080 ms - Total 1.080 ms / 1 intvls (Avg 1.080 ms)
[0x9d513a0] main packetizer debug: looking for packetizer module: 20 candidates
[0x9d513a0] main packetizer debug: using packetizer module "packetizer_mpegvideo"
[0x9d513a0] main packetizer debug: TIMER module_need() : 0.136 ms - Total 0.136 ms / 1 intvls (Avg 0.136 ms)
[0x9d0ba98] dvdnav demux debug: buttonUpdate not done b=1 t=1
[0x9d513a0] packetizer_mpegvideo packetizer debug: size 720x576 fps=25.000
[0x9bff598] main input debug: Buffering 1%
[0x9bff598] main input debug: Buffering 1%
[0x9bff598] main input debug: Buffering 2%
[0x9bff598] main input debug: Buffering 2%
[0x9b92648] main playlist debug: trying to reuse free vout
[0x9d7fb48] main spu text debug: removing module "svg"
[0x9d7fb48] main spu text debug: looking for text renderer module: 3 candidates
[0x9d7fb48] freetype spu text error: failed to load font file DejaVu Sans
[0x9d7fb48] main spu text debug: using text renderer module "svg"
[0x9d7fb48] main spu text debug: TIMER module_need() : 6.191 ms - Total 6.191 ms / 1 intvls (Avg 6.191 ms)
[0x9bff598] main input debug: Buffering 13%
[0x9d81250] main decoder debug: looking for decoder module: 26 candidates
[0x9d81250] main decoder debug: using decoder module "a52"
[0x9d81250] main decoder debug: TIMER module_need() : 8.412 ms - Total 8.412 ms / 1 intvls (Avg 8.412 ms)
[0x9b92648] main playlist debug: reusing provided vout
[0x9d7b5b8] avcodec decoder debug: using direct rendering
[0x9bff598] main input debug: Buffering 13%
[0x9bff598] main input debug: Buffering 14%
[0x9d81250] a52 decoder debug: A/52 channels:6 samplerate:48000 bitrate:448000
[0x9b92648] main playlist debug: recycling audio output
[0x9e85948] main audio output debug: looking for audio output module: 2 candidates
[0x9e85948] pulse audio output debug: using surround-51 channel map
[0x9e85948] pulse audio output debug: using library version 1.1.0
[0x9e85948] pulse audio output debug:  (compiled with version 1.1.0, protocol 23)
Assertion 'pthread_setspecific(t->key, userdata) == 0' failed at pulsecore/thread-posix.c:247, function pa_tls_set(). Aborting.
zsh: abort      vlc -vvv