Playback H.264 fluidity problem, regression is possible
Posted: 23 Apr 2017 23:15
Hello,
Around two years ago, I could play Blu-ray files with 95% of images kept with a notebook. Now (VLC 2.2.4), I turn around 60% or worse. I do not remember the version of VLC was OK for me. But it seems not really important because I tried with MPC (1.7.11) and I got the first result which is 95% of images played.
OS: Windows 7 Starter 32 bits
MPC: default settings
VLC: DVXA 2.0 forced (the processor is too bad to decode without hardware acceleration)
Here is the log level 2 for the first seconds, then I stopped:
Around two years ago, I could play Blu-ray files with 95% of images kept with a notebook. Now (VLC 2.2.4), I turn around 60% or worse. I do not remember the version of VLC was OK for me. But it seems not really important because I tried with MPC (1.7.11) and I got the first result which is 95% of images played.
OS: Windows 7 Starter 32 bits
MPC: default settings
VLC: DVXA 2.0 forced (the processor is too bad to decode without hardware acceleration)
Here is the log level 2 for the first seconds, then I stopped:
Code: Select all
core debug: processing request item: 00012.m2ts, node: Liste de lecture, skip: 0
core debug: resyncing on 00012.m2ts
core debug: 00012.m2ts is at 0
core debug: starting playback of the new playlist item
core debug: resyncing on 00012.m2ts
core debug: 00012.m2ts is at 0
core debug: creating new input thread
core debug: Creating an input for '00012.m2ts'
core debug: requesting art for 00012.m2ts
qt4 debug: IM: Setting an input
core debug: using timeshift granularity of 50 MiB, in path 'C:\Users\USERNAME\AppData\Local\Temp'
core debug: `file:///C:/Users/USERNAME/Desktop/test/00012.m2ts' gives access `file' demux `' path `/C:/Users/USERNAME/Desktop/test/00012.m2ts'
core debug: specified demux `any'
core debug: creating demux: access='file' demux='any' location='/C:/Users/USERNAME/Desktop/test/00012.m2ts' file='C:\Users\USERNAME\Desktop\test\00012.m2ts'
core debug: looking for access_demux module matching "file": 12 candidates
core debug: no access_demux modules matched
core debug: creating access 'file' location='/C:/Users/USERNAME/Desktop/test/00012.m2ts', path='C:\Users\USERNAME\Desktop\test\00012.m2ts'
core debug: looking for access module matching "file": 21 candidates
filesystem debug: opening file `C:\Users\USERNAME\Desktop\test\00012.m2ts'
core debug: using access module "filesystem"
core debug: Using stream method for AStream*
core debug: starting pre-buffering
core debug: received first data after 0 ms
core debug: pre-buffering done 1024 bytes in 0s - 1000000 KiB/s
core debug: looking for stream_filter module matching "any": 6 candidates
core debug: no stream_filter modules matched
core debug: looking for stream_filter module matching "record": 6 candidates
core debug: using stream_filter module "record"
core debug: creating demux: access='file' demux='any' location='/C:/Users/USERNAME/Desktop/test/00012.m2ts' file='C:\Users\USERNAME\Desktop\test\00012.m2ts'
core debug: looking for demux module matching "any": 66 candidates
ts debug: PATCallBack called
ts debug: new PAT ts_id=0 version=0 current_next=1
ts debug: * number=0 pid=31
ts debug: * number=1 pid=256
ts debug: PMTCallBack called
ts debug: new PMT program number=1 version=0 pid_pcr=4097
ts debug: * descriptor : registration HDMV
ts debug: * descriptor : EACEM Simulcast HD
ts debug: * es pid=4113 type=27 dr->i_tag=0x5
ts debug: * es pid=4113 type=27 fcc=h264
core debug: selecting program id=1
ts debug: * es pid=4352 type=128 dr->i_tag=0x5
ts debug: es HDMV registration applied to pid 0x1100 type 0x80
ts debug: * es pid=4352 type=128 fcc=bpcm
ts debug: * es pid=5120 type=145 fcc=a52b
core debug: using demux module "ts"
core debug: looking for a subtitle file in C:\Users\USERNAME\Desktop\test\
core debug: looking for decoder module matching "any": 43 candidates
avcodec debug: CPU flags: 0x010010db
avcodec debug: trying to use direct rendering
avcodec debug: allowing 1 thread(s) for decoding
avcodec warning: threaded frame decoding is not compatible with DXVA2, disabled
avcodec debug: avcodec codec (H264 - MPEG-4 AVC (part 10)) started
core debug: using decoder module "avcodec"
core debug: looking for packetizer module matching "any": 23 candidates
core debug: using packetizer module "packetizer_h264"
core debug: looking for meta fetcher module matching "any": 1 candidates
lua debug: Trying Lua scripts in C:\Users\USERNAME\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
core debug: no meta fetcher modules matched
core debug: searching art for 00012.m2ts
core debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\USERNAME\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
core debug: no art finder modules matched
core debug: looking for meta fetcher module matching "any": 1 candidates
lua debug: Trying Lua scripts in C:\Users\USERNAME\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
core debug: using meta fetcher module "lua"
core debug: removing module "lua"
core debug: searching art for 00012.m2ts
core debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\USERNAME\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
core debug: no art finder modules matched
core debug: art not found for 00012.m2ts
core debug: looking for decoder module matching "any": 43 candidates
core debug: using decoder module "lpcm"
ts debug: DEMUX_SET_GROUP 0 00000000
core debug: looking for meta reader module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\USERNAME\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac
core debug: no meta reader modules matched
core debug: `file:///C:/Users/USERNAME/Desktop/test/00012.m2ts' successfully opened
ts debug: pid[31] unknown
ts debug: pid[4097] unknown
core debug: Buffering 0%
core debug: Buffering 29%
core debug: Buffering 59%
core debug: Buffering 89%
core debug: Stream buffering done (359 ms in 9 ms)
core debug: reusing audio output
directsound debug: Opening DirectSound Audio Output
packetizer_h264 debug: found NAL_SPS (sps_id=0)
packetizer_h264 debug: found NAL_PPS (pps_id=0 sps_id=0)
avcodec debug: available hardware decoder output format 61 (dxva2_vld)
avcodec debug: available hardware decoder output format 128 (d3d11va_vld)
avcodec debug: available software decoder output format 0 (yuv420p)
core debug: looking for hw decoder module matching "dxva2": 1 candidates
dxva2 debug: DLLs loaded
dxva2 debug: D3dCreateDevice succeed
dxva2 debug: OurDirect3DCreateDeviceManager9 Success!
dxva2 info: obtained IDirect3DDeviceManager9
dxva2 info: DXVA2CreateVideoService Success!
dxva2 debug: - 'MPEG-2 variable-length decoder' is supported by hardware
dxva2 debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware
dxva2 debug: - 'H.264 variable-length decoder, no film grain technology' is supported by hardware
dxva2 debug: - 'H.264 variable-length decoder, no film grain technology, Flash' is supported by hardware
dxva2 warning: - Unknown GUID = 0x6719b6fb-0x5cad-0x4acb-0xb00af3bfdec38727
dxva2 debug: - 'VC-1 variable-length decoder' is supported by hardware
dxva2 warning: - Unknown GUID = 0xca15d19a-0x2b48-0x43d6-0x979e7a6e9c802ff8
dxva2 debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, Avivo' is supported by hardware
dxva2 debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, no GMC' is supported by hardware
dxva2 debug: Trying to use 'H.264 variable-length decoder, no film grain technology' as input
dxva2 debug: NV12 is supported for output
dxva2 debug: Using 'H.264 variable-length decoder, no film grain technology' to decode to 'NV12'
core debug: using hw decoder module "dxva2"
dxva2 debug: DxCreateVideoDecoder id 28 1920x1088
dxva2 debug: IDirectXVideoAccelerationService_CreateSurface succeed with 19 surfaces (1920x1088)
dxva2 debug: we got 2 decoder configurations
dxva2 debug: configuration[0] ConfigBitstreamRaw 2
dxva2 debug: configuration[1] ConfigBitstreamRaw 2
dxva2 debug: IDirectXVideoDecoderService_CreateVideoDecoder succeed
avcodec info: Using DXVA2 (MY CARD) for hardware decoding.
core debug: looking for text renderer module matching "any": 2 candidates
freetype debug: Using Arial as font from file C:\windows\Fonts\arial.ttf
freetype debug: Using Courier New as mono-font from file C:\windows\Fonts\cour.ttf
freetype debug: using fontsize: 2
core debug: using text renderer module "freetype"
core debug: looking for video filter2 module matching "any": 58 candidates
swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality)
core debug: using video filter2 module "swscale"
core debug: looking for video filter2 module matching "any": 58 candidates
yuvp debug: YUVP to YUVA converter
core debug: using video filter2 module "yuvp"
core debug: Deinterlacing available
core debug: deinterlace 0, mode blend, is_needed 0
core debug: Opening vout display wrapper
core debug: looking for vout display module matching "any": 11 candidates
direct3d debug: creating Vout EventThread
direct3d debug: Win32VoutCreateWindow
core debug: looking for vout window hwnd module matching "qt4,any": 3 candidates
qt4 debug: requesting video window...
qt4 debug: Video was requested 0, 0
core debug: using vout window hwnd module "qt4"
direct3d debug: created video sub-window
direct3d debug: Vout EventThread running
direct3d debug: disabling screen saver
direct3d debug: Direct3d Device: MY CARD
core debug: VoutDisplayEvent 'resize' 1936x1115 window
direct3d warning: trying surface pixel format: YV12
direct3d debug: selected surface pixel format is YV12
direct3d debug: Using D3DTEXF_LINEAR for minification
direct3d debug: Using D3DTEXF_LINEAR for magnification
direct3d debug: Direct3D scene created successfully
direct3d debug: Direct3D device adapter successfully initialized
core debug: using vout display module "direct3d"
core debug: original format sz 1920x1088, of (0,0), vsz 1920x1080, 4cc YV12, sar 1:1, msk r0x0 g0x0 b0x0
core debug: VoutDisplayEvent 'resize' 1920x1080 window
core debug: removing module "freetype"
core debug: looking for text renderer module matching "any": 2 candidates
freetype debug: Using Arial as font from file C:\windows\Fonts\arial.ttf
freetype debug: Using Courier New as mono-font from file C:\windows\Fonts\cour.ttf
freetype debug: using fontsize: 2
core debug: using text renderer module "freetype"
core debug: End of video preroll
core debug: Received first picture
core debug: VoutDisplayEvent 'resize' 1028x512 window
core debug: VoutDisplayEvent 'mouse button' 0 t=8
core debug: VoutDisplayEvent 'mouse button' 0 t=9
core debug: VoutDisplayEvent 'mouse button' 0 t=8
core debug: VoutDisplayEvent 'mouse button' 0 t=9
directsound debug: Windows speaker config: Stereo and stream has 2 channels, using 2 channels
core debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
core debug: looking for audio volume module matching "any": 2 candidates
core debug: using audio volume module "float_mixer"
core debug: input 's16l' 48000 Hz Stereo frame=1 samples/4 bytes
core debug: looking for audio filter module matching "scaletempo": 14 candidates
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
core debug: using audio filter module "scaletempo"
core debug: conversion: 's16l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
core debug: looking for audio converter module matching "any": 12 candidates
audio_format debug: s16l->f32l, bits per sample: 16->32
core debug: using audio converter module "audio_format"
core debug: conversion pipeline complete
core debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
core debug: conversion pipeline complete
core debug: looking for audio resampler module matching "any": 3 candidates
core debug: using audio resampler module "samplerate"
core debug: End of audio preroll
core debug: Decoder wait done in 1124 ms
core warning: playback way too early (-642751): playing silence
core debug: inserting 30852 zeroes
core debug: auto hiding mouse cursor
core warning: picture is too late to be displayed (missing 21 ms)
core debug: picture might be displayed late (missing 5 ms)
core warning: picture is too late to be displayed (missing 30 ms)
core warning: picture is too late to be displayed (missing 69 ms)
core warning: picture is too late to be displayed (missing 115 ms)
core debug: auto hiding mouse cursor
core warning: picture is too late to be displayed (missing 170 ms)
core warning: picture is too late to be displayed (missing 209 ms)
core warning: picture is too late to be displayed (missing 266 ms)
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
core warning: picture is too late to be displayed (missing 26 ms)
core warning: picture is too late to be displayed (missing 57 ms)
core warning: picture is too late to be displayed (missing 104 ms)
core warning: picture is too late to be displayed (missing 145 ms)
core warning: picture is too late to be displayed (missing 132 ms)
core warning: picture is too late to be displayed (missing 208 ms)
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
core warning: picture is too late to be displayed (missing 96 ms)
core warning: picture is too late to be displayed (missing 87 ms)
core warning: picture is too late to be displayed (missing 111 ms)
core warning: picture is too late to be displayed (missing 141 ms)
core warning: picture is too late to be displayed (missing 142 ms)
core warning: picture is too late to be displayed (missing 175 ms)
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
avcodec warning: More than 4 late frames, dropping frame
core debug: picture might be displayed late (missing 7 ms)
core warning: picture is too late to be displayed (missing 35 ms)
core warning: picture is too late to be displayed (missing 98 ms)
core warning: picture is too late to be displayed (missing 120 ms)
core warning: picture is too late to be displayed (missing 115 ms)
core warning: picture is too late to be displayed (missing 138 ms)
core debug: auto hiding mouse cursor
core debug: auto hiding mouse cursor