Postby DrHow » 20 Sep 2011 23:09
OK. After it got started, it became extremely redundant, so I have edited the log as indicated by comment in [brackets] near end:
_______________________________________________________________
main debug: adding item `Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv' ( file:///G:/Recorded%20TV/Masterpiece%20Mystery%21_KLRUDT_2011_09_18_19_58_00.wtv )
main debug: rebuilding array of current - root Playlist
qt4 debug: Adding a new MRL to recent ones: G:\Recorded TV\Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv
main debug: Creating an input for 'Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv'
main debug: rebuild done - 1 items, index -1
main debug: processing request item Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv node null skip 0
main debug: resyncing on Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv
main debug: Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv is at 0
main debug: starting new item
main debug: creating new input thread
main debug: Creating an input for 'Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv'
main debug: thread started
main debug: thread (input) created at priority 1 (../.././src/input/input.c:220)
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path 'C:\Users\David\AppData\Local\Temp'
main debug: `file:///G:/Recorded%20TV/Masterpiece%20Mystery%21_KLRUDT_2011_09_18_19_58_00.wtv' gives access `file' demux `' path `G:\Recorded TV\Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv'
main debug: creating demux: access='file' demux='' path='G:\Recorded TV\Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv'
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() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms)
main debug: creating access 'file' path='G:\Recorded TV\Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv'
main debug: looking for access module: 2 candidates
filesystem debug: opening file `G:\Recorded TV\Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 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 - 1000000 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.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 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.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: creating demux: access='file' demux='' path='G:\Recorded TV\Masterpiece Mystery!_KLRUDT_2011_09_18_19_58_00.wtv'
main debug: looking for demux module: 52 candidates
lua debug: Trying Lua scripts in C:\Users\David\AppData\Roaming\vlc\lua\playlist
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\playlist
qt4 debug: IM: Setting an input
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\anevia_streams.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\anevia_xml.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\appletrailers.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\bbc_co_uk.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\break.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\canalplus.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\cue.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\dailymotion.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\france2.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\googlevideo.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\jamendo.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\joox.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\katsomo.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\koreus.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\lelombrik.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\megavideo.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\metacafe.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\metachannels.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\mpora.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\pinkbike.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\rockbox_fm_presets.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\vimeo.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\youtube.lua
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\youtube_homepage.lua
avcodec debug: detected format: wtv
main debug: selecting program id=0
avcodec debug: adding es: audio codec = a52
avcodec debug: adding es: audio codec = a52
avcodec debug: adding es: video codec = mpgv
avcodec debug: adding es: subtitle codec = undf
avcodec warning: unsupported attachment type in ffmpeg demux
avcodec debug: adding es: attachment codec = MJPG
avcodec debug: AVFormat supported stream
avcodec debug: - format = wtv (Windows Television (WTV))
avcodec debug: - start time = 1526167
avcodec debug: - duration = 5697626881
main debug: using demux module "avcodec"
main debug: TIMER module_need() : 454.000 ms - Total 454.000 ms / 1 intvls (Avg 454.000 ms)
main debug: looking for a subtitle file in G:\Recorded TV\
main debug: looking for decoder module: 34 candidates
main debug: using decoder module "a52"
main debug: TIMER module_need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 ms)
main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:301)
main debug: thread started
main debug: looking for decoder module: 34 candidates
avcodec debug: libavcodec initialized (interface 0x350500)
avcodec debug: trying to use direct rendering
avcodec debug: ffmpeg codec (MPEG-1/2 Video) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 25.000 ms - Total 25.000 ms / 1 intvls (Avg 25.000 ms)
main debug: thread started
main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:301)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\David\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.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms)
main debug: `file:///G:/Recorded%20TV/Masterpiece%20Mystery%21_KLRUDT_2011_09_18_19_58_00.wtv' successfully opened
main debug: Buffering 0%
main debug: Buffering 11%
main debug: Buffering 22%
main debug: Buffering 22%
main debug: Buffering 33%
main debug: Buffering 33%
main debug: Buffering 44%
main debug: Buffering 44%
main debug: Buffering 55%
main debug: Buffering 55%
a52 debug: A/52 channels:5 samplerate:48000 bitrate:384000
main debug: Buffering 66%
main debug: creating aout
main debug: Buffering 66%
main debug: Buffering 77%
main debug: Buffering 77%
main debug: Buffering 88%
main debug: Buffering 88%
main debug: Stream buffering done (300 ms in 1 ms)
main debug: no usable vout present, spawning one
main debug: looking for audio output module: 4 candidates
aout_directx debug: Opening DirectSound Audio Output
main debug: looking for text renderer module: 2 candidates
main debug: meta ok for en-us, need to fetch art
main debug: looking for meta fetcher module: 1 candidate
freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf
lua debug: Trying Lua scripts in C:\Users\David\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
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 2.000 ms - Total 2.000 ms / 1 intvls (Avg 2.000 ms)
main debug: looking for video filter2 module: 18 candidates
main debug: using meta fetcher module "lua"
main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms)
main debug: removing module "lua"
main debug: searching art for en-us
main debug: looking for art finder module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\David\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
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() : 2.000 ms - Total 2.000 ms / 1 intvls (Avg 2.000 ms)
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.lua
main debug: looking for video filter2 module: 18 candidates
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.lua
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() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 ms)
main debug: art not found for en-us
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 2.000 ms - Total 2.000 ms / 1 intvls (Avg 2.000 ms)
main debug: window size: 1920x1080
main debug: Deinterlacing available
main debug: deinterlace 1, 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
aout_directx debug: found device: Primary Sound Driver
aout_directx debug: found device: Speakers (Realtek High Definition Audio)
aout_directx debug: found device: Realtek Digital Output (Realtek High Definition Audio)
aout_directx debug: found device: AMD HDMI Output (AMD High Definition Audio Device)
aout_directx debug: found device: Realtek Digital Output(Optical) (Realtek High Definition Audio)
aout_directx debug: device supports 5 channels
aout_directx debug: device supports 4 channels
aout_directx debug: device supports 2 channels
aout_directx debug: device supports 1 channel
aout_directx debug: Windows says your SpeakerConfig is stereo
direct3d debug: creating Vout EventThread
direct3d debug: DirectXCreateWindow
main debug: looking for vout window hwnd module: 3 candidates
qt4 debug: requesting video...
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() : 45.000 ms - Total 45.000 ms / 1 intvls (Avg 45.000 ms)
main debug: output 's16l' 48000 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'s16l' 48000 Hz->48000 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
audio_format debug: f32l->s16l, bits per sample: 32->16
main debug: using audio filter module "audio_format"
main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 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.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: input 'a52 ' 48000 Hz 3F2R frame=1536 samples/1536 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: filter(s) 'a52 '->'f32l' 48000 Hz->48000 Hz 3F2R->Stereo
main debug: looking for audio filter module: 13 candidates
main debug: using audio filter module "a52tofloat32"
main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms)
main debug: found a filter for the whole conversion
main debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
main debug: using audio filter module "ugly_resampler"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: found a filter for the whole conversion
main debug: End of audio preroll
qt4 debug: Video was requested 0, 0
main debug: using vout window hwnd module "qt4"
main debug: TIMER module_need() : 36.000 ms - Total 36.000 ms / 1 intvls (Avg 36.000 ms)
direct3d debug: created video sub-window
direct3d debug: Vout EventThread running
direct3d debug: disabling screen saver
main debug: VoutDisplayEvent 'resize' 1938x1125 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() : 111.000 ms - Total 111.000 ms / 1 intvls (Avg 111.000 ms)
main debug: using video output module "vout_wrapper"
main debug: TIMER module_need() : 111.000 ms - Total 111.000 ms / 1 intvls (Avg 111.000 ms)
main debug: got 1 direct buffer(s)
main debug: pic render sz 1920x1080, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic in sz 1920x1080, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic out sz 1920x1080, of (0,0), vsz 1920x1080, 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' 1354x625 window
main debug: looking for video filter2 module: 1 candidate
deinterlace debug: using blend deinterlace method
deinterlace debug: deinterlacing
main debug: using video filter2 module "deinterlace"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: Filter 'deinterlace' (068540bc) appended to chain
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
freetype debug: using fontsize: 67
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.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: Decoder buffering done in 170 ms
main warning: PTS is out of range (354767), dropping buffer
main warning: PTS is out of range (290767), dropping buffer
main warning: PTS is out of range (258767), dropping buffer
main warning: PTS is out of range (226767), dropping buffer
main warning: PTS is out of range (194767), dropping buffer
main warning: PTS is out of range (197767), dropping buffer
main warning: PTS is out of range (165767), dropping buffer
main warning: PTS is out of range (133767), dropping buffer
main warning: PTS is out of range (101767), dropping buffer
main debug: Post-processing available
main debug: Filter 068540bc removed from chain
main debug: removing module "deinterlace"
main debug: looking for video filter2 module: 1 candidate
deinterlace debug: using blend deinterlace method
deinterlace debug: deinterlacing
main debug: using video filter2 module "deinterlace"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: Filter 'deinterlace' (03276394) appended to chain
main warning: vlc_object_find_name(postproc) is not safe!
main warning: late picture skipped (79634 > -4007)
main warning: late picture skipped (46267 > -4007)
main warning: late picture skipped (12900 > -4007)
main debug: Detected interlaced video
main debug: deinterlace 1, mode blend, is_needed 1
main warning: PTS is out of range (211767), dropping buffer
main warning: PTS is out of range (179767), dropping buffer
main warning: PTS is out of range (147767), dropping buffer
main warning: PTS is out of range (115767), dropping buffer
main warning: PTS is out of range (250767), dropping buffer
main warning: PTS is out of range (218767), dropping buffer
[lots and lots more of same, with occasional:]
main warning: computed PTS is out of range (51767), clearing out