WMV9, vbr, won't play with DXVA

[bugreporter]

This video won't play if you have DXVA enabled. It will play if you turn it off: http://www.speedyshare.com/files/28692352/test.wmv

Same happens with XBMC. Both on my AMD Zacate (6xxx) and GTS250 laptop. Windows Mediaplayer plays the file correctly, with DXVA (since I can watch it on my Zacate), so I'm guessing it's a bug in FFMPEG. But I thought I'd report it here anyway.

[Jean-Baptiste Kempf]

Please share the logs when playing back.

[bugreporter]

Code: Select all

main debug: processing request item test.wmv node Playlist skip 0 main debug: resyncing on test.wmv main debug: test.wmv is at 0 main debug: starting new item main debug: creating new input thread main debug: Creating an input for 'test.wmv' 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\adlaptop\AppData\Local\Temp' main debug: `file:///E:/movies/_gezien/test.wmv' gives access `file' demux `' path `E:\movies\_gezien\test.wmv' main debug: creating demux: access='file' demux='' path='E:\movies\_gezien\test.wmv' 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='E:\movies\_gezien\test.wmv' main debug: looking for access module: 2 candidates filesystem debug: opening file `E:\movies\_gezien\test.wmv' main debug: using access module "filesystem" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.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() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms) main debug: creating demux: access='file' demux='' path='E:\movies\_gezien\test.wmv' main debug: looking for demux module: 52 candidates asf debug: found object guid: 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:5346 asf debug: read "header object" subobj:7, reserved1:1, reserved2:2 asf debug: found object guid: 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104 asf debug: read "file properties object" file_id:0xa369fbfe-0x927c-0x4697-0xbb08892679031cd5 file_size:8389365816 creation_date:129438144704060000 data_packets_count:306874 play_duration:64905420000 send_duration:64874560000 preroll:3000 flags:2 min_data_packet_size:27338 max_data_packet_size:27338 max_bitrate:29504877 asf debug: found object guid: 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:4193 asf debug: read "header extension object" reserved1:0xabd3d211-0xa9ba-0x11cf-0x8ee600c00c205365 reserved2:6 header_extension_size:4147 asf debug: found object guid: 0x7c4346a9-0xefe0-0x4bfc-0xb229393ede415c85 size:39 asf debug: read "language list object" 1 entries asf debug: - 'en-us' asf debug: found object guid: 0x26f18b5d-0x4584-0x47ec-0x9f5f0e651f0452c9 size:26 asf warning: unknown asf object (not loaded) asf debug: found object guid: 0xc5f8cbea-0x5baf-0x4877-0x8467aa8c44fa4cca size:472 asf debug: read "metadata object" 8 entries asf debug: - IsVBR=0 asf debug: - DeviceConformanceTemplate=M2 asf debug: - IsVBR=1 asf debug: - DeviceConformanceTemplate=AP@L3 asf debug: - WM/WMADRCPeakReference=32767 asf debug: - WM/WMADRCPeakTarget=32767 asf debug: - WM/WMADRCAverageReference=2357 asf debug: - WM/WMADRCAverageTarget=2357 asf debug: found object guid: 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 size:3374 asf warning: unknown asf object (not loaded) asf debug: found object guid: 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:88 asf debug: read "extended stream properties object": asf debug: - start=0 end=0 asf debug: - data bitrate=640008 buffer=3000 initial fullness=0 asf debug: - alternate data bitrate=640008 buffer=3000 initial fullness=0 asf debug: - maximum object size=27307 asf debug: - flags=0x2 asf debug: - stream number=1 language=0 asf debug: - average time per frame=3411575 asf debug: - stream name count=0 asf debug: - payload extension system count=0 asf debug: found object guid: 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:110 asf debug: read "extended stream properties object": asf debug: - start=0 end=0 asf debug: - data bitrate=9677000 buffer=228996 initial fullness=0 asf debug: - alternate data bitrate=28815635 buffer=3000 initial fullness=0 asf debug: - maximum object size=457048 asf debug: - flags=0x2 asf debug: - stream number=2 language=0 asf debug: - average time per frame=417084 asf debug: - stream name count=0 asf debug: - payload extension system count=1 asf debug: found object guid: 0xd9aade20-0x7c17-0x4f9c-0xbc288555dd98e2a2 size:38 asf warning: unknown asf object (not loaded) asf debug: found object guid: 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 size:394 asf debug: read "extended content description object" asf debug: - 'WMFSDKVersion' = '11.0.5721.5265' asf debug: - 'WMFSDKNeeded' = '0.0.0.0000' asf debug: - 'IsVBR' = 'true' asf debug: - 'ASFLeakyBucketPairs' = '0000C05D0000652A4BA6307500006522F584C8AF0000106D825890E2000031A0954400C2010018A2562280A90300660648103057050084F60A0B20A10700482D9D0790230B0024DD170540420F00D7179D03C05C150017807802200B200051B08401404B4C00F1516A0080969800A1780600' asf debug: - 'VBR Peak' = '28815635' asf debug: - 'Buffer Average' = '228996' asf debug: found object guid: 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:314 asf debug: read "codec list object" reserved_guid:0x86d15241-0x311d-0x11d0-0xa3a400a0c90348f6 codec_entries_count:2 asf debug: - codec[0] audio name:"Windows Media Audio 10 Professional" description:"640 kbps, 48 kHz, 5.1 channel 24 bit 2-pass CBR" information_length:2 asf debug: - codec[1] video name:"Windows Media Video 9 Advanced Profile" description:"" information_length:4 asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:122 asf debug: read "stream Properties object" stream_type:0xf8699e40-0x5b4d-0x11cf-0xa8fd00805f5c442b error_correction_type:0xbfc3cd50-0x618f-0x11cf-0x8bb200aa00b4e220 time_offset:0 type_specific_data_length:36 error_correction_data_length:8 flags:0x1 stream_number:1 asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:151 asf debug: read "stream Properties object" stream_type:0xbc19efc0-0x5b4d-0x11cf-0xa8fd00805f5c442b error_correction_type:0x20fb5700-0x5b55-0x11cf-0xa8fd00805f5c442b time_offset:0 type_specific_data_length:73 error_correction_data_length:0 flags:0x2 stream_number:2 asf debug: found object guid: 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 size:38 asf debug: read "stream bitrate properties object" asf debug: - stream=1 bitrate=644936 asf debug: - stream=2 bitrate=28859941 asf debug: found object guid: 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:8389321462 asf debug: read "data object" file_id:0xa369fbfe-0x927c-0x4697-0xbb08892679031cd5 total data packet:306874 reserved:257 asf debug: found object guid: 0x33000890-0xe5b1-0x11cf-0x89f400a0c90349cb size:39008 asf debug: read "index object" file_id:0xa369fbfe-0x927c-0x4697-0xbb08892679031cd5 index_entry_time_interval:10000000 max_packet_count:17 index_entry_count:6492 asf debug: + 'Unknown' GUID 0x0-0x0-0x0-0x0000000000000000 size:0pos:0 asf debug: + 'Header' GUID 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:5346pos:0 asf debug: | + 'File Properties' GUID 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104pos:30 asf debug: | + 'Header Extension' GUID 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:4193pos:134 asf debug: | | + 'Language List' GUID 0x7c4346a9-0xefe0-0x4bfc-0xb229393ede415c85 size:39pos:180 asf debug: | | + 'Unknown' GUID 0x26f18b5d-0x4584-0x47ec-0x9f5f0e651f0452c9 size:26pos:219 asf debug: | | + 'Metadata' GUID 0xc5f8cbea-0x5baf-0x4877-0x8467aa8c44fa4cca size:472pos:245 asf debug: | | + 'Padding' GUID 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 size:3374pos:717 asf debug: | | + 'Extended Stream Properties' GUID 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:88pos:4091 asf debug: | | + 'Extended Stream Properties' GUID 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:110pos:4179 asf debug: | | + 'Unknown' GUID 0xd9aade20-0x7c17-0x4f9c-0xbc288555dd98e2a2 size:38pos:4289 asf debug: | + 'Extended content description' GUID 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 size:394pos:4327 asf debug: | + 'Codec List' GUID 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:314pos:4721 asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:122pos:5035 asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:151pos:5157 asf debug: | + 'Stream Bitrate Properties' GUID 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 size:38pos:5308 asf debug: + 'Data' GUID 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:8389321462pos:5346 asf debug: + 'Index' GUID 0x33000890-0xe5b1-0x11cf-0x89f400a0c90349cb size:39008pos:8389326808 asf debug: found 2 streams asf debug: added new audio stream(codec:0x162,ID:1) main debug: selecting program id=0 asf debug: added new video stream(ID:2) main debug: using demux module "asf" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: looking for a subtitle file in E:\movies\_gezien\ main debug: autodetected subtitle: E:\movies\_gezien\test.srt with priority 4 main debug: `file:///E:/movies/_gezien/test.srt' gives access `file' demux `' path `E:\movies\_gezien\test.srt' main debug: creating demux: access='file' demux='subtitle' path='E:\movies\_gezien\test.srt' 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.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating access 'file' path='E:\movies\_gezien\test.srt' main debug: looking for access module: 2 candidates filesystem debug: opening file `E:\movies\_gezien\test.srt' 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='subtitle' path='E:\movies\_gezien\test.srt' 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... qt4 debug: IM: Setting an input subtitle debug: loaded 656 subtitles main debug: using demux module "subtitle" main debug: TIMER module_need() : 14.000 ms - Total 14.000 ms / 1 intvls (Avg 14.000 ms) main debug: looking for decoder module: 34 candidates avcodec debug: libavcodec already initialized avcodec debug: ffmpeg codec (Windows Media Audio Professional) started avcodec debug: Using 196608 bytes output buffer main debug: using decoder module "avcodec" main debug: TIMER module_need() : 6.000 ms - Total 6.000 ms / 1 intvls (Avg 6.000 ms) main debug: thread started main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:301) main debug: looking for decoder module: 34 candidates avcodec debug: libavcodec already initialized avcodec debug: trying to use direct rendering avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Trying DXVA2 avcodec debug: DLLs loaded avcodec debug: D3dCreateDevice succeed avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec info: obtained IDirect3DDeviceManager9 avcodec info: DXVA2CreateVideoService Success! avcodec debug: - 'DXVA2_ModeMPEG2_IDCT' is supported by hardware avcodec debug: - 'DXVA2_ModeMPEG2_VLD' is supported by hardware avcodec warning: - Unknown GUID = 1B81BEA4-a0c7-11d3-XXXX avcodec debug: - 'VC-1 VLD' is supported by hardware avcodec debug: - 'VC-1 IDCT' is supported by hardware avcodec debug: - 'Windows Media Video 9 IDCT' is supported by hardware avcodec warning: - Unknown GUID = 32FCFE3F-de46-4a49-XXXX avcodec debug: - 'H.264 VLD, no FGT' is supported by hardware avcodec warning: - Unknown GUID = 9947EC6F-689b-11dc-XXXX avcodec warning: - Unknown GUID = B194EB52-19a0-41f0-XXXX avcodec debug: Trying to use 'VC-1 VLD' as input avcodec debug: NV12 is supported for output avcodec debug: Using 'VC-1 VLD' to decode to 'NV12' avcodec debug: DxCreateVideoDecoder id 73 1920x800 avcodec debug: IDirectXVideoAccelerationService_CreateSurface succeed with 3 surfaces (1920x800) avcodec debug: we got 1 decoder configurations avcodec debug: configuration[0] ConfigBitstreamRaw 1 avcodec debug: IDirectXVideoDecoderService_CreateVideoDecoder succeed avcodec info: Using DXVA2 (NVIDIA GeForce GTS 250M, vendor 4318(NVIDIA), device 3241, revision 162) for hardware decoding. avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Trying DXVA2 avcodec debug: DLLs loaded avcodec debug: D3dCreateDevice succeed avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec info: obtained IDirect3DDeviceManager9 avcodec info: DXVA2CreateVideoService Success! avcodec debug: - 'DXVA2_ModeMPEG2_IDCT' is supported by hardware avcodec debug: - 'DXVA2_ModeMPEG2_VLD' is supported by hardware avcodec warning: - Unknown GUID = 1B81BEA4-a0c7-11d3-XXXX avcodec debug: - 'VC-1 VLD' is supported by hardware avcodec debug: - 'VC-1 IDCT' is supported by hardware avcodec debug: - 'Windows Media Video 9 IDCT' is supported by hardware avcodec warning: - Unknown GUID = 32FCFE3F-de46-4a49-XXXX avcodec debug: - 'H.264 VLD, no FGT' is supported by hardware avcodec warning: - Unknown GUID = 9947EC6F-689b-11dc-XXXX avcodec warning: - Unknown GUID = B194EB52-19a0-41f0-XXXX avcodec debug: Trying to use 'VC-1 VLD' as input avcodec debug: NV12 is supported for output avcodec debug: Using 'VC-1 VLD' to decode to 'NV12' avcodec debug: DxCreateVideoDecoder id 73 1920x800 avcodec debug: IDirectXVideoAccelerationService_CreateSurface succeed with 3 surfaces (1920x800) avcodec debug: we got 1 decoder configurations avcodec debug: configuration[0] ConfigBitstreamRaw 1 avcodec debug: IDirectXVideoDecoderService_CreateVideoDecoder succeed avcodec info: Using DXVA2 (NVIDIA GeForce GTS 250M, vendor 4318(NVIDIA), device 3241, revision 162) for hardware decoding. avcodec debug: ffmpeg codec (Windows Media Video VC1) started main debug: using decoder module "avcodec" main debug: TIMER module_need() : 304.000 ms - Total 304.000 ms / 1 intvls (Avg 304.000 ms) main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:301) main debug: thread started 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() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.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\adlaptop\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: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Users\adlaptop\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:///E:/movies/_gezien/test.wmv' successfully opened main debug: Buffering 0% main debug: Buffering 13% main debug: Buffering 27% main debug: Buffering 41% main debug: Buffering 55% main debug: Buffering 69% main debug: Buffering 83% main debug: Buffering 97% main debug: Buffering 97% main debug: Buffering 0% main debug: Stream buffering done (341 ms in 1 ms) main debug: reusing aout main info: Reparenting an object is dangerous (01e0f538 -> 01e0f8c8)! main debug: looking for audio output module: 4 candidates aout_directx debug: Opening DirectSound Audio Output aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: Speakers (Conexant Pebble High Definition SmartAudio) aout_directx debug: found device: SPDIF Interface (Conexant Pebble High Definition SmartAudio) main debug: no usable vout present, spawning one main debug: looking for text renderer module: 2 candidates freetype debug: Building font databases. aout_directx debug: device supports 5.1 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 aout_directx debug: creating DirectSoundThread main debug: thread started aout_directx debug: DirectSoundThread ready main debug: thread (DirectSound Notification Thread) created at priority 15 (../../.././modules/audio_output/directx.c:321) main debug: using audio output module "aout_directx" main debug: TIMER module_need() : 43.000 ms - Total 43.000 ms / 1 intvls (Avg 43.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: 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() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.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 'f32l' 48000 Hz 3F2R/LFE frame=1 samples/24 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) 'f32l'->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->Stereo main debug: looking for audio filter module: 14 candidates main debug: using audio filter module "simple_channel_mixer" 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: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates bandlimited_resampler debug: f32l/52800KHz/2->f32l/48000KHz/2 main debug: using audio filter module "bandlimited_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 freetype debug: Took 204000 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() : 206.000 ms - Total 206.000 ms / 1 intvls (Avg 206.000 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.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 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() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms) main debug: window size: 1920x800 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 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 qt4 debug: embedded video ready (handle 000e00cc) main debug: using vout window hwnd module "qt4" main debug: TIMER module_need() : 102.000 ms - Total 102.000 ms / 1 intvls (Avg 102.000 ms) direct3d debug: created video sub-window direct3d debug: Vout EventThread running direct3d debug: disabling screen saver main debug: VoutDisplayEvent 'resize' 1936x838 window direct3d debug: DirectXUpdateRects image_dst_clipped coords: 0,0,1680,806 direct3d debug: DirectXUpdateRects image_src_clipped coords: 0,0,1666,800 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() : 155.000 ms - Total 155.000 ms / 1 intvls (Avg 155.000 ms) main debug: using video output module "vout_wrapper" main debug: TIMER module_need() : 155.000 ms - Total 155.000 ms / 1 intvls (Avg 155.000 ms) main debug: got 1 direct buffer(s) main debug: pic render sz 1920x800, of (0,0), vsz 1920x800, 4cc YV12, sar 1:1, msk r0x0 g0x0 b0x0 main debug: pic in sz 1920x800, of (0,0), vsz 1920x800, 4cc YV12, sar 1:1, msk r0x0 g0x0 b0x0 main debug: pic out sz 1920x800, of (0,0), vsz 1920x800, 4cc YV12, 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' 1676x800 window direct3d debug: DirectXUpdateRects image_dst_clipped coords: 0,0,1676,698 direct3d debug: DirectXUpdateRects image_src_clipped coords: 0,0,1920,800 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' (06944520) appended to chain main debug: End of video preroll main debug: Received first picture freetype debug: using fontsize: 50 main debug: looking for video blending module: 1 candidate blend debug: chroma: YUVA -> YV12 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: Post-processing available main debug: Filter 06944520 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' (06944520) appended to chain main warning: vlc_object_find_name(postproc) is not safe! main debug: Decoder buffering done in 566 ms main warning: buffer is 42335 in advance, triggering downsampling main warning: late picture skipped (-8000 > -11005) main warning: output date isn't PTS date, requesting resampling (73335) main debug: auto hidding mouse main debug: incoming request - stopping current input main debug: dying input main debug: control type=0 main debug: control: stopping input main debug: dying input main warning: received buffer in the future main debug: thread times: real 0m6.372364s, kernel 0m0.000000s, user 0m0.171601s avcodec debug: ffmpeg codec (Windows Media Audio Professional) stopped main debug: removing module "avcodec" main debug: killing decoder fourcc `WMAP', 0 PES in FIFO main debug: removing module "simple_channel_mixer" main debug: removing module "scaletempo" main debug: removing module "bandlimited_resampler" aout_directx debug: closing audio device aout_directx debug: DirectSoundThread exiting main debug: thread ended main debug: thread times: real 0m6.129350s, kernel 0m0.000000s, user 0m0.031200s main debug: removing module "aout_directx" main debug: removing module "audio_format" main debug: removing module "float32_mixer" main debug: releasing aout main debug: thread times: real 0m6.205354s, kernel 0m0.171601s, user 0m0.187201s avcodec debug: ffmpeg codec (Windows Media Video VC1) stopped main debug: removing module "avcodec" main debug: killing decoder fourcc `VC-1', 0 PES in FIFO main debug: [0] 2 0 main debug: [1] 2 0 main debug: [2] 2 0 main debug: [3] 2 0 main debug: [4] 2 0 main debug: [5] 4 0 main debug: [6] 2 0 main debug: [7] 2 0 main debug: [8] 2 0 main debug: [9] 4 0 main debug: [10] 2 0 main debug: [11] 2 0 main debug: [12] 2 0 main debug: [13] 2 0 main debug: [14] 2 0 main debug: [15] 2 0 main debug: [16] 2 0 main debug: [17] 4 0 main debug: [18] 2 0 main debug: [19] 2 0 main debug: [20] 2 0 main debug: [21] 2 0 main debug: [22] 2 0 main debug: [23] 4 0 main debug: saving a free vout main debug: thread times: real 0m6.267358s, kernel 0m0.000000s, user 0m0.000000s main debug: removing module "subsdec" main debug: killing decoder fourcc `subt', 0 PES in FIFO asf debug: free asf object 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 asf debug: free asf object 0x7c4346a9-0xefe0-0x4bfc-0xb229393ede415c85 asf warning: unknown asf object 0x26f18b5d-0x4584-0x47ec-0x9f5f0e651f0452c9 asf debug: free asf object 0xc5f8cbea-0x5baf-0x4877-0x8467aa8c44fa4cca asf warning: unknown asf object 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 asf debug: free asf object 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a asf debug: free asf object 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a asf warning: unknown asf object 0xd9aade20-0x7c17-0x4f9c-0xbc288555dd98e2a2 asf debug: free asf object 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 asf debug: free asf object 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 asf debug: free asf object 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 asf debug: free asf object 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 asf debug: free asf object 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 asf debug: free asf object 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 asf debug: free asf object 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c asf debug: free asf object 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c asf debug: free asf object 0x33000890-0xe5b1-0x11cf-0x89f400a0c90349cb main debug: removing module "asf" 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 0m6.604377s, kernel 0m0.280801s, user 0m0.234001s main debug: destroying useless vout main debug: Filter 06944520 removed from chain main debug: removing module "deinterlace" direct3d debug: Direct3D scene released successfully direct3d debug: DirectXEventThread terminating direct3d debug: DirectXCloseWindow direct3d debug: WinProc WM_DESTROY qt4 debug: releasing video... qt4 debug: IM: Deleting the input main debug: Destroying the input for 'test.wmv' main debug: TIMER input launching for 'test.wmv' : 339.000 ms - Total 339.000 ms / 1 intvls (Avg 339.000 ms) qt4 debug: Video is not needed anymore main debug: removing module "qt4" main debug: removing module "direct3d" main debug: removing module "vout_wrapper" main debug: removing module "blend" main debug: removing module "freetype" main debug: removing module "yuvp" main debug: removing module "swscale"

[bugreporter]

Not sure if this is helpful to a developer, but these are the DXVA api calls in WMP to play a frame correctly in the video sample that I posted:

DXVA2_ProcessBlt, 6348, 00:00:11.1179043
DXVA2_DecodeDeviceGetBuffer, 6348, 00:00:11.1187653
DXVA2_DecodeDeviceGetBuffer, 6348, 00:00:11.1187691
DXVA2_DecodeDeviceGetBuffer, 6348, 00:00:11.1187711
DXVA2_DecodeDeviceBeginFrame, 6348, 00:00:11.1289430
DXVA2_DecodeDeviceBeginFrame, 6348, 00:00:11.1289654
DXVA2_DecodeDeviceGetBuffer, 6348, 00:00:11.1289674
DXVA2_DecodeDeviceExecute, 6348, 00:00:11.1289706
DXVA2_DecodeDeviceExecute, 6348, 00:00:11.1289796
DXVA2_DecodeDeviceEndFrame, 6348, 00:00:11.1294107
DXVA2_DecodeDeviceEndFrame, 6348, 00:00:11.1296404

And this is how XBMC does it:


DXVA2_ProcessBlt, XBMC, 00:00:05.7270504
DXVA2_DecodeDeviceExecute, XBMC, 00:00:05.7271139
DXVA2_DecodeDeviceBeginFrame, XBMC, 00:00:05.7294833
DXVA2_DecodeDeviceGetBuffer, XBMC, 00:00:05.7295013
DXVA2_DecodeDeviceGetBuffer, XBMC, 00:00:05.7295077
DXVA2_DecodeDeviceGetBuffer, XBMC, 00:00:05.7295635
DXVA2_DecodeDeviceExecute, XBMC, 00:00:05.7295667
DXVA2_DecodeDeviceEndFrame, XBMC, 00:00:05.7296527
DXVA2_DecodeDeviceExecute, XBMC, 00:00:05.7300178

I had expected this to be the same as VLC, but interestingly, it's different. VLC uses even less calls:

DXVA2_DecodeDeviceBeginFrame, 5060, 00:00:04.4396770
DXVA2_DecodeDeviceGetBuffer, 5060, 00:00:04.4397014
DXVA2_DecodeDeviceGetBuffer, 5060, 00:00:04.4397065
DXVA2_DecodeDeviceGetBuffer, 5060, 00:00:04.4397097
DXVA2_DecodeDeviceExecute, 5060, 00:00:04.4397123
DXVA2_DecodeDeviceEndFrame, 5060, 00:00:04.4397393

I logged this with DXVA-checker. Too bad it doesn't have some more advanced features, but that's of course were windbg and olly come in

[bugreporter]

*EDIT*

[bugreporter]

Ah, I understand the difference between VLC and XBMC: XBMC does
1) Rendering as well with DXVA (that call to ProcessBlt)
2) Status processing (the 2 executes communicate GPU status to the host).

Ok, so in terms of decoding there doesn't seem to be any difference (which makes sense since XBMC uses the VLC code). But what's up with the WMP decoding cycle ? It seems to run 2 decoding processes in parallel ? And what's up with those 3 'getbuffer' calls outside the cycle ?

[bugreporter]

Ok, I fixed the FFMPEG DXVA code that is the source of the problems. Just released a test version (compiled) for XBMC: http://forum.xbmc.org/showthread.php?t=103578
I'll soon release the whole sourcecode, so the VLC users can use it as well (it's the same FFMPEG code).

[Jean-Baptiste Kempf]

We compile avcodec as static in the VLC plugins, so, well we need more to test.

[bugreporter]

We compile avcodec as static in the VLC plugins, so, well we need more to test.

Sure. I'm planning to release the source code this weekend, then you guys can see if you like it

[Jean-Baptiste Kempf]

Great!
Are you going to upstream the changes too?

[bugreporter]

I've posted my patch on the XBMC forums: http://trac.xbmc.org/ticket/11643
Should be easy to integrate into your VLC project. Enjoy it !