VLC 1.1.5 fails to play TS file with external SRT present
Posted: 11 Jan 2011 18:15
I began with an AVI file and an external SRT of the same filename. I encoded the pair to a hardsubbed MPEG-TS file under a different filename using soverlay. Interestingly, however, the TS file will not play in VLC while the SRT from the original encode is still present in the same directory. Simply by moving the SRT file into a different directory, the problem is resolved. I found a thread explaining this same problem in 2006 [http://forums.afterdawn.com/thread_view.cfm/331855]. Any ideas why this would occur?
With .SRT in same directory:
Without SRT in same directory:
With .SRT in same directory:
Code: Select all
main debug: adding item `klaus.mpeg' ( file:///C:/Program%20Files%20%28x86%29/VideoLAN/VLC/klaus.mpeg )
qt4 debug: Adding a new MRL to recent ones: C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 2 items, index 0
main debug: processing request item klaus.mpeg node Playlist skip 0
main debug: resyncing on klaus.mpeg
main debug: klaus.mpeg is at 1
main debug: Creating an input for 'klaus.mpeg'
main debug: starting new item
main debug: creating new input thread
main debug: Creating an input for 'klaus.mpeg'
main debug: thread started
main debug: thread (input) created at priority 1 (../.././src/input/input.c:214)
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path 'C:\Users\DrGamut\AppData\Local\Temp'
main debug: `file:///C:/Program%20Files%20%28x86%29/VideoLAN/VLC/klaus.mpeg' gives access `file' demux `' path `C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: creating demux: access='file' demux='' path='C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: looking for access_demux module: 2 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.307 ms - Total 0.307 ms / 1 intvls (Avg 0.307 ms)
main debug: creating access 'file' path='C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: looking for access module: 2 candidates
filesystem debug: opening file `C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.386 ms - Total 0.386 ms / 1 intvls (Avg 0.386 ms)
main debug: Using AStream*Stream
main debug: pre buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 43478 KiB/s
main debug: looking for stream_filter module: 2 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.133 ms - Total 0.133 ms / 1 intvls (Avg 0.133 ms)
main debug: looking for stream_filter module: 1 candidate
main debug: using stream_filter module "stream_filter_record"
main debug: TIMER module_need() : 0.131 ms - Total 0.131 ms / 1 intvls (Avg 0.131 ms)
main debug: creating demux: access='file' demux='' path='C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: looking for demux module: 51 candidates
ts debug: PATCallBack called
ts debug: new PAT ts_id=46617 version=4 current_next=1
ts debug: * number=1 pid=66
ts debug: PMTCallBack called
ts debug: new PMT program number=1 version=18 pid_pcr=69
ts debug: * descriptor : IOD (0x1d)
ts error: MPEG-4 descriptor not found
ts debug: * es pid=68 type=15 fcc=mp4a
main debug: selecting program id=1
ts debug: * es pid=69 type=27 fcc=h264
filesystem warning: unimplemented query 4097 in control
main debug: using demux module "ts"
main debug: TIMER module_need() : 0.966 ms - Total 0.966 ms / 1 intvls (Avg 0.966 ms)
main debug: looking for a subtitle file in C:\Program Files (x86)\VideoLAN\VLC\
main debug: autodetected subtitle: C:\Program Files (x86)\VideoLAN\VLC\staplerfahrer-klaus-xvid.srt with priority 3
main debug: `file:///C:/Program%20Files%20%28x86%29/VideoLAN/VLC/staplerfahrer-klaus-xvid.srt' gives access `file' demux `' path `C:\Program Files (x86)\VideoLAN\VLC\staplerfahrer-klaus-xvid.srt'
main debug: creating demux: access='file' demux='subtitle' path='C:\Program Files (x86)\VideoLAN\VLC\staplerfahrer-klaus-xvid.srt'
main debug: meta ok for (null), need to fetch art
main debug: looking for access_demux module: 2 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.285 ms - Total 0.285 ms / 1 intvls (Avg 0.285 ms)
main debug: creating access 'file' path='C:\Program Files (x86)\VideoLAN\VLC\staplerfahrer-klaus-xvid.srt'
main debug: looking for access module: 2 candidates
filesystem debug: opening file `C:\Program Files (x86)\VideoLAN\VLC\staplerfahrer-klaus-xvid.srt'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.249 ms - Total 0.249 ms / 1 intvls (Avg 0.249 ms)
main debug: Using AStream*Stream
main debug: pre buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 33333 KiB/s
main debug: looking for meta fetcher module: 1 candidate
main debug: looking for stream_filter module: 2 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.111 ms - Total 0.111 ms / 1 intvls (Avg 0.111 ms)
lua debug: Trying Lua scripts in C:\Users\DrGamut\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher
main debug: looking for stream_filter module: 1 candidate
main debug: using stream_filter module "stream_filter_record"
main debug: TIMER module_need() : 0.117 ms - Total 0.117 ms / 1 intvls (Avg 0.117 ms)
main debug: creating demux: access='file' demux='subtitle' path='C:\Program Files (x86)\VideoLAN\VLC\staplerfahrer-klaus-xvid.srt'
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher\tvrage.lua
main debug: looking for demux module: 3 candidates
vobsub debug: this doesn't seem to be a vobsub file
main error: option sub-original-fps does not exist
subtitle debug: Movie fps: -1.000000
subtitle debug: autodetecting subtitle format
subtitle debug: detected SubRIP format
subtitle debug: loading all subtitles...
main debug: using meta fetcher module "lua"
main debug: TIMER module_need() : 1.013 ms - Total 1.013 ms / 1 intvls (Avg 1.013 ms)
main debug: removing module "lua"
main debug: searching art for klaus.mpeg
main debug: looking for art finder module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\DrGamut\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\01_googleimage.lua
subtitle debug: loaded 85 subtitles
main debug: unselecting program id=1
main debug: selecting program id=0
main debug: using demux module "subtitle"
main debug: TIMER module_need() : 1.695 ms - Total 1.695 ms / 1 intvls (Avg 1.695 ms)
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.lua
main debug: looking for decoder module: 34 candidates
avcodec debug: libavcodec already initialized
avcodec debug: codec not found (Text subtitles with various tags)
subsdec debug: trying configured character encoding: not specified
subsdec debug: trying default character encoding: CP1252
subsdec debug: using automatic UTF-8 detection
main debug: using decoder module "subsdec"
main debug: TIMER module_need() : 0.500 ms - Total 0.500 ms / 1 intvls (Avg 0.500 ms)
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.lua
main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:301)
main debug: thread started
ts debug: DEMUX_SET_GROUP 0 00000000
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\04_musicbrainz.lua
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\DrGamut\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader\filename.lua
main debug: no art finder module matching "any" could be loaded
main debug: TIMER module_need() : 2.931 ms - Total 2.931 ms / 1 intvls (Avg 2.931 ms)
main debug: art not found for klaus.mpeg
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 1.323 ms - Total 1.323 ms / 1 intvls (Avg 1.323 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\DrGamut\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader\filename.lua
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 0.930 ms - Total 0.930 ms / 1 intvls (Avg 0.930 ms)
main debug: `file:///C:/Program%20Files%20%28x86%29/VideoLAN/VLC/klaus.mpeg' successfully opened
main debug: slave 0 EOF
qt4 debug: IM: Setting an input
ts debug: eof ?
main debug: EOF reached
main debug: finished input
main warning: no vout found, dropping subpicture
main warning: can't get output subpicture
subsdec warning: can't get spu buffer
main debug: thread times: real 0m0.403021s, kernel 0m0.000000s, user 0m0.000000s
main debug: removing module "subsdec"
main debug: killing decoder fourcc `subt', 84 PES in FIFO
ts debug: pid list:
ts debug: - pid[0] seen
ts debug: - pid[66] seen
ts debug: - pid[68] seen
main debug: Program doesn't contain anymore ES
ts debug: - pid[69] seen
ts debug: - pid[8191] seen
main debug: removing module "ts"
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: removing module "subtitle"
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: Program doesn't contain anymore ES
main debug: thread ended
main debug: dead input
main debug: thread times: real 0m0.410022s, kernel 0m0.171601s, user 0m0.202801s
main debug: changing item without a request (current 1/2)
main debug: nothing to play
qt4 debug: IM: Deleting the input
main debug: TIMER input launching for 'klaus.mpeg' : 9.370 ms - Total 9.370 ms / 1 intvls (Avg 9.370 ms)Code: Select all
main debug: adding item `klaus.mpeg' ( file:///C:/Program%20Files%20%28x86%29/VideoLAN/VLC/klaus.mpeg )
qt4 debug: Adding a new MRL to recent ones: C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 1 items, index -1
main debug: Creating an input for 'klaus.mpeg'
main debug: processing request item klaus.mpeg node null skip 0
main debug: resyncing on klaus.mpeg
main debug: klaus.mpeg is at 0
main debug: starting new item
main debug: creating new input thread
main debug: Creating an input for 'klaus.mpeg'
main debug: thread (input) created at priority 1 (../.././src/input/input.c:214)
main debug: thread started
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path 'C:\Users\DrGamut\AppData\Local\Temp'
main debug: `file:///C:/Program%20Files%20%28x86%29/VideoLAN/VLC/klaus.mpeg' gives access `file' demux `' path `C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: creating demux: access='file' demux='' path='C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: looking for access_demux module: 2 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.288 ms - Total 0.288 ms / 1 intvls (Avg 0.288 ms)
main debug: creating access 'file' path='C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: looking for access module: 2 candidates
filesystem debug: opening file `C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.271 ms - Total 0.271 ms / 1 intvls (Avg 0.271 ms)
main debug: Using AStream*Stream
main debug: pre buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 47619 KiB/s
main debug: looking for stream_filter module: 2 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.161 ms - Total 0.161 ms / 1 intvls (Avg 0.161 ms)
main debug: looking for stream_filter module: 1 candidate
main debug: using stream_filter module "stream_filter_record"
main debug: TIMER module_need() : 0.130 ms - Total 0.130 ms / 1 intvls (Avg 0.130 ms)
main debug: creating demux: access='file' demux='' path='C:\Program Files (x86)\VideoLAN\VLC\klaus.mpeg'
main debug: looking for demux module: 51 candidates
ts debug: PATCallBack called
ts debug: new PAT ts_id=46617 version=4 current_next=1
ts debug: * number=1 pid=66
ts debug: PMTCallBack called
ts debug: new PMT program number=1 version=18 pid_pcr=69
ts debug: * descriptor : IOD (0x1d)
ts error: MPEG-4 descriptor not found
ts debug: * es pid=68 type=15 fcc=mp4a
main debug: selecting program id=1
ts debug: * es pid=69 type=27 fcc=h264
filesystem warning: unimplemented query 4097 in control
main debug: using demux module "ts"
main debug: TIMER module_need() : 6.249 ms - Total 6.249 ms / 1 intvls (Avg 6.249 ms)
main debug: looking for a subtitle file in C:\Program Files (x86)\VideoLAN\VLC\
main debug: looking for decoder module: 34 candidates
main debug: meta ok for (null), need to fetch art
main debug: looking for meta fetcher module: 1 candidate
lua debug: Trying Lua scripts in C:\Users\DrGamut\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher\tvrage.lua
main debug: using meta fetcher module "lua"
main debug: TIMER module_need() : 1.277 ms - Total 1.277 ms / 1 intvls (Avg 1.277 ms)
main debug: removing module "lua"
main debug: searching art for klaus.mpeg
main debug: looking for art finder module: 2 candidates
main debug: using decoder module "faad"
main debug: TIMER module_need() : 4.026 ms - Total 4.026 ms / 1 intvls (Avg 4.026 ms)
main debug: looking for packetizer module: 21 candidates
lua debug: Trying Lua scripts in C:\Users\DrGamut\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\01_googleimage.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.lua
packetizer_mpeg4audio debug: running MPEG4 audio packetizer
packetizer_mpeg4audio debug: no decoder specific info, must be an ADTS or LOAS stream
main debug: using packetizer module "packetizer_mpeg4audio"
main debug: TIMER module_need() : 1.647 ms - Total 1.647 ms / 1 intvls (Avg 1.647 ms)
main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:301)
main debug: thread started
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.lua
main debug: looking for decoder module: 34 candidates
qt4 debug: IM: Setting an input
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\04_musicbrainz.lua
main debug: no art finder module matching "any" could be loaded
main debug: TIMER module_need() : 7.810 ms - Total 7.810 ms / 1 intvls (Avg 7.810 ms)
main debug: art not found for klaus.mpeg
avcodec debug: libavcodec initialized (interface 0x345d00)
avcodec debug: trying to use direct rendering
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 7.093 ms - Total 7.093 ms / 1 intvls (Avg 7.093 ms)
main debug: looking for packetizer module: 21 candidates
main debug: using packetizer module "packetizer_h264"
main debug: TIMER module_need() : 0.651 ms - Total 0.651 ms / 1 intvls (Avg 0.651 ms)
main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:301)
main debug: thread started
ts debug: DEMUX_SET_GROUP 0 00000000
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\DrGamut\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader\filename.lua
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 1.093 ms - Total 1.093 ms / 1 intvls (Avg 1.093 ms)
main debug: `file:///C:/Program%20Files%20%28x86%29/VideoLAN/VLC/klaus.mpeg' successfully opened
main debug: Buffering 0%
main debug: Buffering 23%
packetizer_mpeg4audio debug: detected ADTS format
main debug: Buffering 47%
packetizer_h264 debug: found NAL_SPS (sps_id=0)
packetizer_h264 debug: found NAL_PPS (pps_id=0 sps_id=0)
packetizer_mpeg4audio info: AAC channels: 2 samplerate: 44100
main debug: Buffering 73%
main debug: Stream buffering done (302 ms in 0 ms)
main debug: no usable vout present, spawning one
faad warning: decoded zero sample
main debug: looking for text renderer module: 2 candidates
freetype debug: Building font databases.
freetype debug: Took 142292 microseconds
freetype debug: Using Arial as font from file C:/Windows/fonts/arial.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 144.942 ms - Total 144.942 ms / 1 intvls (Avg 144.942 ms)
main debug: looking for video filter2 module: 18 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 1.631 ms - Total 1.631 ms / 1 intvls (Avg 1.631 ms)
main debug: looking for video filter2 module: 18 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 2.630 ms - Total 2.630 ms / 1 intvls (Avg 2.630 ms)
main debug: window size: 640x360
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: looking for video output module: 1 candidate
vout_wrapper debug: Opening vout display wrapper
main debug: looking for vout display module: 8 candidates
direct3d debug: creating Vout EventThread
direct3d debug: DirectXCreateWindow
main debug: looking for vout window hwnd module: 3 candidates
qt4 debug: requesting video...
qt4 debug: Video was requested 0, 0
main debug: using vout window hwnd module "qt4"
main debug: TIMER module_need() : 17.096 ms - Total 17.096 ms / 1 intvls (Avg 17.096 ms)
direct3d debug: created video sub-window
direct3d debug: Vout EventThread running
direct3d debug: disabling screen saver
main debug: VoutDisplayEvent 'resize' 656x398 window
direct3d warning: trying surface pixel format: YV12
direct3d debug: selected surface pixel format is YV12
direct3d debug: Direct3D scene created successfully
direct3d debug: Direct3D device adapter successfully initialized
main debug: using vout display module "direct3d"
main debug: TIMER module_need() : 96.603 ms - Total 96.603 ms / 1 intvls (Avg 96.603 ms)
main debug: using video output module "vout_wrapper"
main debug: TIMER module_need() : 97.229 ms - Total 97.229 ms / 1 intvls (Avg 97.229 ms)
main debug: got 1 direct buffer(s)
main debug: pic render sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic in sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic out sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: direct render, mapping render pictures 0-23 to system pictures 1-24
main debug: VoutDisplayEvent 'resize' 640x360 window
main debug: creating aout
avcodec debug: using direct rendering
main debug: looking for audio output module: 4 candidates
aout_directx debug: Opening DirectSound Audio Output
main debug: End of video preroll
main debug: Received first picture
main debug: Post-processing available
main warning: vlc_object_find_name(postproc) is not safe!
aout_directx debug: found device: Primary Sound Driver
aout_directx debug: found device: Speakers (High Definition Audio Device)
aout_directx debug: found device: Digital Audio (S/PDIF) (High Definition Audio Device)
aout_directx debug: device supports 2 channels (DEFAULT!)
aout_directx debug: device supports 1 channel
aout_directx debug: Windows says your SpeakerConfig is stereo
aout_directx debug: creating DirectSoundThread
main debug: thread (DirectSound Notification Thread) created at priority 15 (../../.././modules/audio_output/directx.c:321)
main debug: thread started
main debug: using audio output module "aout_directx"
aout_directx debug: DirectSoundThread ready
main debug: TIMER module_need() : 24.375 ms - Total 24.375 ms / 1 intvls (Avg 24.375 ms)
main debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 14 candidates
audio_format debug: f32l->s16l, bits per sample: 32->16
main debug: using audio filter module "audio_format"
main debug: TIMER module_need() : 2.839 ms - Total 2.839 ms / 1 intvls (Avg 2.839 ms)
main debug: found a filter for the whole conversion
main debug: looking for audio mixer module: 3 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 0.330 ms - Total 0.330 ms / 1 intvls (Avg 0.330 ms)
main debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1323.000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.412 ms - Total 0.412 ms / 1 intvls (Avg 0.412 ms)
main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 14 candidates
bandlimited_resampler debug: f32l/48510KHz/2->f32l/44100KHz/2
main debug: using audio filter module "bandlimited_resampler"
main debug: TIMER module_need() : 0.127 ms - Total 0.127 ms / 1 intvls (Avg 0.127 ms)
main debug: found a filter for the whole conversion
main debug: End of audio preroll
main debug: Decoder buffering done in 279 ms
freetype debug: using fontsize: 22
main debug: looking for video blending module: 1 candidate
blend debug: chroma: YUVA -> I420
main debug: using video blending module "blend"
main debug: TIMER module_need() : 0.674 ms - Total 0.674 ms / 1 intvls (Avg 0.674 ms)
main warning: output date isn't PTS date, requesting resampling (60767)
main warning: buffer is 60770 late, triggering upsampling
main debug: auto hidding mouse