[FFmpeg-devel] need fix: correct muxing to VOB with LPCM audio

[Pavel Sokolov]

> After this patch VLC plays audio, but there is no video 
> http://patches.libav.org/patch/18699/
>
resulting file: http://sokolov.me/tmp/out-with-libavpatch_18699.vob
VLC logs is attached

-- 

With best regards, Pavel A. Sokolov
mobile: +7(921)419-1819
skype: pavel_a_sokolov

-------------- next part --------------
pavel at pavel-ThinkPad-X220:~/videosamples$ vlc -v -v -v -v -v -v -v out-with-libavpatch_18699.vob
VLC media player 1.1.12 The Luggage (revision exported)
[0x1eb5120] main libvlc debug: VLC media player - 1.1.12 The Luggage
[0x1eb5120] main libvlc debug: Copyright © 1996-2011 the VideoLAN team
[0x1eb5120] main libvlc debug: revision exported
[0x1eb5120] main libvlc debug: configured with ./configure  '--enable-static' '--build=x86_64-linux-gnu' '--config-cache' '--disable-maintainer-mode' '--disable-silent-rules' '--disable-update-check' '--enable-fast-install' '--prefix=/usr' '--docdir=/usr/share/doc/vlc-nox' '--sysconfdir=/etc' '--with-binary-version=2~oneiric1' '--enable-a52' '--enable-aa' '--enable-bonjour' '--enable-caca' '--enable-dca' '--enable-dirac' '--enable-dvb' '--enable-dvbpsi' '--enable-dvdnav' '--enable-faad' '--enable-flac' '--enable-fluidsynth' '--enable-freetype' '--enable-fribidi' '--enable-ggi' '--enable-gnutls' '--enable-jack' '--enable-kate' '--enable-libass' '--enable-libmpeg2' '--enable-libproxy' '--enable-libxml2' '--enable-lirc' '--enable-live555' '--enable-mad' '--enable-mkv' '--enable-mod' '--enable-mozilla' '--enable-mpc' '--enable-mtp' '--enable-mux_ogg' '--enable-ncurses' '--enable-notify' '--enable-ogg' '--enable-pulse' '--enable-qt4' '--enable-realrtsp' '--enable-schroedinger' '--enable-sdl' '--enable-shout' '--enable-skins2' '--enable-smb' '--enable-speex' '--enable-svg' '--enable-taglib' '--enable-theora' '--enable-twolame' '--enable-upnp' '--enable-vcd' '--enable-vcdx' '--enable-vorbis' '--enable-x264' '--enable-zvbi' '--with-kde-solid=/usr/share/kde4/apps/solid/actions/' '--with-mozilla-pkg=mozilla-plugin' '--disable-dxva2' '--disable-gnomevfs' '--disable-goom' '--disable-osso_screensaver' '--disable-portaudio' '--disable-projectm' '--disable-sqlite' '--disable-telx' '--enable-alsa' '--enable-atmo' '--enable-dc1394' '--enable-dv' '--enable-libva' '--enable-pvr' '--enable-udev' '--enable-v4l2' '--enable-svgalib' 'build_alias=x86_64-linux-gnu' 'CFLAGS=-g -O2' 'LDFLAGS=-Wl,--as-needed' 'CPPFLAGS=' 'CXXFLAGS=-g -O2'
[0x1eb5120] main libvlc debug: translation test: code is "ru"
[0x1eb5120] main libvlc debug: checking plugin modules
[0x1eb5120] main libvlc debug: loading plugins cache file /usr/lib/vlc/plugins/plugins-04081e-fe8.dat
[0x1eb5120] main libvlc debug: recursively browsing `/usr/lib/vlc/plugins'
[0x1eb5120] main libvlc debug: saving plugins cache /usr/lib/vlc/plugins/plugins-04081e-fe8.dat
[0x1eb5120] main libvlc debug: module bank initialized (393 modules)
[0x1eb5120] main libvlc debug: opening config file (/home/pavel/.config/vlc/vlcrc)
[0x1eb5120] main libvlc debug: CPU has capabilities MMX MMXEXT SSE SSE2 SSE3 SSSE3 SSE4.1 SSE4.2 FPU
[0x1eb5120] main libvlc debug: looking for memcpy module: 3 candidates
[0x1eb5120] main libvlc debug: using memcpy module "memcpymmxext"
[0x1fa54c0] main input debug: Creating an input for 'Медиатека'
[0x1fa54c0] main input debug: Input is a meta file: disabling unneeded options
[0x1fa54c0] main input debug: using timeshift granularity of 50 MiB
[0x1fa54c0] main input debug: using timeshift path '/tmp'
[0x1fa54c0] main input debug: `file/xspf-open:///home/pavel/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/pavel/.local/share/vlc/ml.xspf'
[0x1fa54c0] main input debug: creating demux: access='file' demux='xspf-open' path='/home/pavel/.local/share/vlc/ml.xspf'
[0x1fac0b0] main demux debug: looking for access_demux module: 2 candidates
[0x1fac0b0] main demux debug: no access_demux module matching "file" could be loaded
[0x1fac0b0] main demux debug: TIMER module_need() : 0.358 ms - Total 0.358 ms / 1 intvls (Avg 0.358 ms)
[0x1fa54c0] main input debug: creating access 'file' path='/home/pavel/.local/share/vlc/ml.xspf'
[0x1faeec0] main access debug: looking for access module: 2 candidates
[0x1faeec0] filesystem access debug: opening file `/home/pavel/.local/share/vlc/ml.xspf'
[0x1faeec0] main access debug: using access module "filesystem"
[0x1faeec0] main access debug: TIMER module_need() : 0.204 ms - Total 0.204 ms / 1 intvls (Avg 0.204 ms)
[0x1fbf6c0] main stream debug: Using AStream*Stream
[0x1fbf6c0] main stream debug: pre buffering
[0x1fbf6c0] main stream debug: received first data after 0 ms
[0x1fbf6c0] main stream debug: pre-buffering done 301 bytes in 0s - 11305 KiB/s
[0x1fad880] main stream debug: looking for stream_filter module: 5 candidates
[0x1fad880] main stream debug: no stream_filter module matching "any" could be loaded
[0x1fad880] main stream debug: TIMER module_need() : 0.280 ms - Total 0.280 ms / 1 intvls (Avg 0.280 ms)
[0x1fbe5e0] main stream debug: looking for stream_filter module: 1 candidate
[0x1fbe5e0] main stream debug: using stream_filter module "stream_filter_record"
[0x1fbe5e0] main stream debug: TIMER module_need() : 0.116 ms - Total 0.116 ms / 1 intvls (Avg 0.116 ms)
[0x1fa54c0] main input debug: creating demux: access='file' demux='xspf-open' path='/home/pavel/.local/share/vlc/ml.xspf'
[0x1fbed80] main demux debug: looking for demux module: 1 candidate
[0x1fbed80] playlist demux debug: using XSPF playlist reader
[0x1fbed80] main demux debug: using demux module "playlist"
[0x1fbed80] main demux debug: TIMER module_need() : 0.165 ms - Total 0.165 ms / 1 intvls (Avg 0.165 ms)
[0x1fc3260] main demux meta debug: looking for meta reader module: 2 candidates
[0x1fc3260] lua demux meta debug: Trying Lua scripts in /home/pavel/.local/share/vlc/lua/meta/reader
[0x1fc3260] lua demux meta debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/reader
[0x1fc3260] lua demux meta debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/reader/filename.luac
[0x1fc3260] lua demux meta debug: Trying Lua scripts in /usr/share/vlc/lua/meta/reader
[0x1fc3260] main demux meta debug: no meta reader module matching "any" could be loaded
[0x1fc3260] main demux meta debug: TIMER module_need() : 1.825 ms - Total 1.825 ms / 1 intvls (Avg 1.825 ms)
[0x1fa54c0] main input debug: `file/xspf-open:///home/pavel/.local/share/vlc/ml.xspf' successfully opened
[0x1fcc060] main xml debug: looking for xml module: 2 candidates
[0x1fcc060] main xml debug: using xml module "xml"
[0x1fcc060] main xml debug: TIMER module_need() : 0.342 ms - Total 0.342 ms / 1 intvls (Avg 0.342 ms)
Warning: call to srand(1331733553)
Warning: call to rand()
[0x1fbed80] playlist demux debug: parsed 0 tracks successfully
[0x1fcc060] main xml debug: removing module "xml"
[0x1fa54c0] main input debug: EOF reached
[0x1fbed80] main demux debug: removing module "playlist"
[0x1fbe5e0] main stream debug: removing module "stream_filter_record"
[0x1faeec0] main access debug: removing module "filesystem"
[0x1fa54c0] main input debug: TIMER input launching for 'Медиатека' : 3.444 ms - Total 3.444 ms / 1 intvls (Avg 3.444 ms)
[0x1fbf1a0] main interface debug: looking for interface module: 1 candidate
[0x1fbf1a0] main interface debug: using interface module "hotkeys"
[0x1fbf1a0] main interface debug: TIMER module_need() : 0.154 ms - Total 0.154 ms / 1 intvls (Avg 0.154 ms)
[0x1fada20] main interface debug: looking for interface module: 1 candidate
Blocked: call to unsetenv("DBUS_ACTIVATION_ADDRESS")
Blocked: call to unsetenv("DBUS_ACTIVATION_BUS_TYPE")
[0x1ec6ea0] main playlist debug: Activated
[0x1fada20] main interface debug: using interface module "inhibit"
[0x1fada20] main interface debug: TIMER module_need() : 6.587 ms - Total 6.587 ms / 1 intvls (Avg 6.587 ms)
[0x1ec6ea0] main playlist debug: rebuilding array of current - root Плейлист
[0x1ec6ea0] main playlist debug: rebuild done - 0 items, index -1
[0x1ec6ea0] main playlist debug: adding item `out-with-libavpatch_18699.vob' ( file:///home/pavel/videosamples/out-with-libavpatch_18699.vob )
[0x7f19ec000be0] main input debug: Creating an input for 'out-with-libavpatch_18699.vob'
[0x1fa7d90] main interface debug: looking for interface module: 1 candidate
[0x1fa7d90] main interface debug: using interface module "signals"
[0x1fa7d90] main interface debug: TIMER module_need() : 0.311 ms - Total 0.311 ms / 1 intvls (Avg 0.311 ms)
[0x1fa7480] main interface debug: looking for interface module: 1 candidate
[0x1fa7480] main interface debug: using interface module "globalhotkeys"
[0x1fa7480] main interface debug: TIMER module_need() : 0.912 ms - Total 0.912 ms / 1 intvls (Avg 0.912 ms)
[0x1eb5120] main libvlc: Запуск vlc с интерфейсом по умолчанию. Используйте 'cvlc' для запуска vlc без интерфейса.
[0x1fcf5a0] main interface debug: looking for interface module: 5 candidates
Blocked: call to setlocale(6, "")
[0x1ec6ea0] main playlist debug: meta ok for (null), need to fetch art
[0x2022ff0] main demux meta debug: looking for meta fetcher module: 1 candidate
[0x2022ff0] lua demux meta debug: Trying Lua scripts in /home/pavel/.local/share/vlc/lua/meta/fetcher
[0x2022ff0] lua demux meta debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/fetcher
[0x2022ff0] lua demux meta debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/fetcher/tvrage.luac
[0x2022ff0] main demux meta debug: using meta fetcher module "lua"
[0x2022ff0] main demux meta debug: TIMER module_need() : 0.302 ms - Total 0.302 ms / 1 intvls (Avg 0.302 ms)
[0x2022ff0] main demux meta debug: removing module "lua"
[0x1ec6ea0] main playlist debug: searching art for out-with-libavpatch_18699.vob
[0x1fab440] main art finder debug: looking for art finder module: 2 candidates
[0x1fab440] lua art finder debug: Trying Lua scripts in /home/pavel/.local/share/vlc/lua/meta/art
[0x1fab440] lua art finder debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/art
[0x1fab440] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/01_googleimage.luac
[0x1fab440] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/02_frenchtv.luac
[0x1fab440] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/03_lastfm.luac
[0x1fab440] lua art finder debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/art/04_musicbrainz.luac
[0x1fab440] lua art finder debug: Trying Lua scripts in /usr/share/vlc/lua/meta/art
[0x1fab440] main art finder debug: no art finder module matching "any" could be loaded
[0x1fab440] main art finder debug: TIMER module_need() : 0.976 ms - Total 0.976 ms / 1 intvls (Avg 0.976 ms)
[0x1ec6ea0] main playlist debug: art not found for out-with-libavpatch_18699.vob
Blocked: call to setlocale(6, "")
QPixmap::handle(): Pixmap is not an X11 class pixmap
[0x2275740] main generic debug: looking for extension module: 1 candidate
[0x2275740] lua generic debug: Opening EXPERIMENTAL Lua Extension module
[0x2275740] lua generic debug: Trying Lua scripts in /home/pavel/.local/share/vlc/lua/extensions
[0x2275740] lua generic debug: Trying Lua scripts in /usr/lib/vlc/lua/extensions
[0x2275740] lua generic debug: Trying Lua playlist script /usr/lib/vlc/lua/extensions/allocine-fr.luac
[0x2275740] lua generic debug: Scanning Lua script /usr/lib/vlc/lua/extensions/allocine-fr.luac
[0x2275740] lua generic debug: Script /usr/lib/vlc/lua/extensions/allocine-fr.luac has the following capability flags: 0xc
[0x2275740] lua generic debug: Trying Lua scripts in /usr/share/vlc/lua/extensions
[0x2275740] main generic debug: using extension module "lua"
[0x2275740] main generic debug: TIMER module_need() : 0.441 ms - Total 0.441 ms / 1 intvls (Avg 0.441 ms)
QPixmap::handle(): Pixmap is not an X11 class pixmap
QPixmap::handle(): Pixmap is not an X11 class pixmap
[0x1fcf5a0] main interface debug: using interface module "qt4"
[0x1fcf5a0] main interface debug: TIMER module_need() : 116.788 ms - Total 116.788 ms / 1 intvls (Avg 116.788 ms)
[0x1ec6ea0] main playlist debug: rebuilding array of current - root Плейлист
[0x1ec6ea0] main playlist debug: rebuild done - 1 items, index -1
[0x1ec6ea0] main playlist debug: processing request item null node Плейлист skip 0
[0x1ec6ea0] main playlist debug: starting new item
[0x1ec6ea0] main playlist debug: creating new input thread
[0x7f19ecc16000] main input debug: Creating an input for 'out-with-libavpatch_18699.vob'
[0x7f19ecc16000] main input debug: thread (input) created at priority 10 (input/input.c:220)
[0x7f19ecc16000] main input debug: thread started
[0x7f19ecc16000] main input debug: using timeshift granularity of 50 MiB
[0x7f19ecc16000] main input debug: using timeshift path '/tmp'
[0x7f19ecc16000] main input debug: `file:///home/pavel/videosamples/out-with-libavpatch_18699.vob' gives access `file' demux `' path `/home/pavel/videosamples/out-with-libavpatch_18699.vob'
[0x7f19ecc16000] main input debug: creating demux: access='file' demux='' path='/home/pavel/videosamples/out-with-libavpatch_18699.vob'
[0x7f19ecc1c570] main demux debug: looking for access_demux module: 2 candidates
[0x7f19ecc1c570] main demux debug: no access_demux module matching "file" could be loaded
[0x7f19ecc1c570] main demux debug: TIMER module_need() : 0.422 ms - Total 0.422 ms / 1 intvls (Avg 0.422 ms)
[0x7f19ecc16000] main input debug: creating access 'file' path='/home/pavel/videosamples/out-with-libavpatch_18699.vob'
[0x2397da0] main access debug: looking for access module: 2 candidates
[0x2397da0] filesystem access debug: opening file `/home/pavel/videosamples/out-with-libavpatch_18699.vob'
[0x2397da0] main access debug: using access module "filesystem"
[0x2397da0] main access debug: TIMER module_need() : 0.181 ms - Total 0.181 ms / 1 intvls (Avg 0.181 ms)
[0x2364250] main stream debug: Using AStream*Stream
[0x2364250] main stream debug: pre buffering
[0x2364250] main stream debug: received first data after 0 ms
[0x2364250] main stream debug: pre-buffering done 1024 bytes in 0s - 58823 KiB/s
[0x2f9c810] main stream debug: looking for stream_filter module: 5 candidates
[0x2f9c810] main stream debug: no stream_filter module matching "any" could be loaded
[0x2f9c810] main stream debug: TIMER module_need() : 0.069 ms - Total 0.069 ms / 1 intvls (Avg 0.069 ms)
[0x2f9c810] main stream debug: looking for stream_filter module: 1 candidate
[0x2f9c810] main stream debug: using stream_filter module "stream_filter_record"
[0x2f9c810] main stream debug: TIMER module_need() : 0.061 ms - Total 0.061 ms / 1 intvls (Avg 0.061 ms)
[0x7f19ecc16000] main input debug: creating demux: access='file' demux='' path='/home/pavel/videosamples/out-with-libavpatch_18699.vob'
[0x2f9c950] main demux debug: looking for demux module: 52 candidates
[0x2f9c950] main demux debug: using demux module "ps"
[0x2f9c950] main demux debug: TIMER module_need() : 0.262 ms - Total 0.262 ms / 1 intvls (Avg 0.262 ms)
[0x7f19ecc16000] main input debug: looking for a subtitle file in /home/pavel/videosamples/
[0x2f9cbd0] main demux meta debug: looking for meta reader module: 2 candidates
[0x2f9cbd0] lua demux meta debug: Trying Lua scripts in /home/pavel/.local/share/vlc/lua/meta/reader
[0x2f9cbd0] lua demux meta debug: Trying Lua scripts in /usr/lib/vlc/lua/meta/reader
[0x2f9cbd0] lua demux meta debug: Trying Lua playlist script /usr/lib/vlc/lua/meta/reader/filename.luac
[0x2f9cbd0] lua demux meta debug: Trying Lua scripts in /usr/share/vlc/lua/meta/reader
[0x2f9cbd0] main demux meta debug: no meta reader module matching "any" could be loaded
[0x2f9cbd0] main demux meta debug: TIMER module_need() : 0.653 ms - Total 0.653 ms / 1 intvls (Avg 0.653 ms)
[0x7f19ecc16000] main input debug: `file:///home/pavel/videosamples/out-with-libavpatch_18699.vob' successfully opened
[0x2f9c950] ps demux warning: garbage at input, trying to resync...
[0x2f9c950] ps demux warning: found sync code
[0x2f9c950] ps demux debug: we found a length of: 599800001
[0x7f19ecc16000] main input debug: selecting program id=0
[0x2f9cf30] main decoder debug: looking for decoder module: 30 candidates
[0x2f9cf30] avcodec decoder debug: libavcodec initialized (interface 0x350500)
[0x2f9cf30] avcodec decoder debug: trying to use direct rendering
[0x2f9cf30] avcodec decoder debug: ffmpeg codec (MPEG-1/2 Video) started
[0x2f9cf30] main decoder debug: using decoder module "avcodec"
[0x2f9cf30] main decoder debug: TIMER module_need() : 6.656 ms - Total 6.656 ms / 1 intvls (Avg 6.656 ms)
[0x2fd6e20] main packetizer debug: looking for packetizer module: 21 candidates
[0x1fcf5a0] qt4 interface debug: IM: Setting an input
[0x2fd6e20] main packetizer debug: using packetizer module "packetizer_mpegvideo"
[0x2fd6e20] main packetizer debug: TIMER module_need() : 4.346 ms - Total 4.346 ms / 1 intvls (Avg 4.346 ms)
[0x2f9cf30] main decoder debug: thread (decoder) created at priority 0 (input/decoder.c:301)
[0x2f9cf30] main decoder debug: thread started
[0x7f19ecc16000] main input debug: Buffering 0%
[0x2fd6e20] packetizer_mpegvideo packetizer debug: waiting for sequence start
[0x2feaec0] main decoder debug: looking for decoder module: 30 candidates
[0x2feaec0] main decoder debug: using decoder module "lpcm"
[0x2feaec0] main decoder debug: TIMER module_need() : 0.187 ms - Total 0.187 ms / 1 intvls (Avg 0.187 ms)
[0x2feaec0] main decoder debug: thread (decoder) created at priority 5 (input/decoder.c:301)
[0x2feaec0] main decoder debug: thread started
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: creating aout
[0x7f19ecc247e0] main audio output debug: looking for audio output module: 4 candidates
[0x7f19ecc247e0] pulse audio output debug: using stereo channel map
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Buffering 0%
[0x7f19ecc16000] main input debug: Stream buffering done (500 ms in 3 ms)
[0x7f19ecc247e0] pulse audio output debug: connected to device alsa_output.pci-0000_00_1b.0.analog-stereo (0)
[0x7f19ecc247e0] pulse audio output debug: using buffer metrics: maxlength=4194304, tlength=18432, prebuf=0, minreq=3840
[0x7f19ecc247e0] main audio output debug: using audio output module "pulse"
[0x7f19ecc247e0] main audio output debug: TIMER module_need() : 9.528 ms - Total 9.528 ms / 1 intvls (Avg 9.528 ms)
[0x7f19ecc247e0] main audio output debug: output 's16b' 48000 Hz Stereo frame=1 samples/4 bytes
[0x7f19ecc247e0] main audio output debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
[0x7f19ecc247e0] main audio output debug: filter(s) 'f32l'->'s16b' 48000 Hz->48000 Hz Stereo->Stereo
[0x7f19ecc29d10] main audio filter debug: looking for audio filter module: 13 candidates
[0x7f19ecc29d10] audio_format audio filter debug: f32l->s16b, bits per sample: 32->16
[0x7f19ecc29d10] main audio filter debug: using audio filter module "audio_format"
[0x7f19ecc29d10] main audio filter debug: TIMER module_need() : 3.993 ms - Total 3.993 ms / 1 intvls (Avg 3.993 ms)
[0x7f19ecc247e0] main audio output debug: found a filter for the whole conversion
[0x306ce30] main generic debug: looking for audio mixer module: 3 candidates
[0x306ce30] main generic debug: using audio mixer module "float32_mixer"
[0x306ce30] main generic debug: TIMER module_need() : 0.263 ms - Total 0.263 ms / 1 intvls (Avg 0.263 ms)
[0x7f19ecc247e0] main audio output debug: input 's16b' 48000 Hz Stereo frame=1 samples/4 bytes
[0x7f19ecc360f0] main audio filter debug: looking for audio filter module: 1 candidate
[0x7f19ecc360f0] scaletempo audio filter debug: format: 48000 rate, 2 nch, 4 bps, fl32
[0x7f19ecc360f0] scaletempo audio filter debug: params: 30 stride, 0.200 overlap, 14 search
[0x7f19ecc360f0] scaletempo audio filter debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
[0x7f19ecc360f0] main audio filter debug: using audio filter module "scaletempo"
[0x7f19ecc360f0] main audio filter debug: TIMER module_need() : 4.588 ms - Total 4.588 ms / 1 intvls (Avg 4.588 ms)
[0x7f19ecc247e0] main audio output debug: filter(s) 's16b'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
[0x30652c0] main audio filter debug: looking for audio filter module: 13 candidates
[0x30652c0] audio_format audio filter debug: s16b->f32l, bits per sample: 16->32
[0x30652c0] main audio filter debug: using audio filter module "audio_format"
[0x30652c0] main audio filter debug: TIMER module_need() : 0.139 ms - Total 0.139 ms / 1 intvls (Avg 0.139 ms)
[0x7f19ecc247e0] main audio output debug: found a filter for the whole conversion
[0x7f19ecc247e0] main audio output debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo
[0x30656b0] main audio filter debug: looking for audio filter module: 13 candidates
[0x30656b0] main audio filter debug: using audio filter module "ugly_resampler"
[0x30656b0] main audio filter debug: TIMER module_need() : 0.066 ms - Total 0.066 ms / 1 intvls (Avg 0.066 ms)
[0x7f19ecc247e0] main audio output debug: found a filter for the whole conversion
[0x2feaec0] main decoder debug: End of audio preroll
[0x7f19ecc16000] main input debug: Decoder buffering done in 16 ms
[0x7f19ecc247e0] pulse audio output debug: deferring start (411414 us)
[0x7f19ecc247e0] pulse audio output debug: starting deferred
[0x7f19ecc247e0] pulse audio output debug: started
[0x2fd6e20] packetizer_mpegvideo packetizer debug: waiting for sequence start
[0x2fd6e20] packetizer_mpegvideo packetizer debug: waiting for sequence start