VLC 2.0/2.0.1/2.0.2 audio stuttering/lag

[Rémi Denis-Courmont]

Try disabling power management features in Windows and/or BIOS and see...

[SSonik]

Scaling CPU frequency down causes "buffer too early" messages.
Scaling CPU frequency up causes "buffer too late" messages.

1.6GHz (bus at 133MHz) video/audio is stable, but buffer too late messages still appearing, but less frequently.

Edit: I should clarify that I'm not looking for troubleshooting tips or workarounds - I'm interested in trialing scenarios that may permanently fix this problem for everyone (without requiring average users to fiddle with any system settings).

[Rémi Denis-Courmont]

• Get a VLC nightly build from September 7th 2012 or later, or compile VLC 2.1.0 manually from git.
• Start VLC.
• Open the preferences.
• Select All then Advanced.
• Change the Clock source.
• Save and exit.
• Start VLC and play something.

Reiterate until you find a clock that does not break your audio output... if any

[Ausrick]

Hello everyone!

I am having the same problem as I guess everyone here. I'm getting frustrated as everyone is on my case to fix it, and people who aren't in IT assume I just have some sort of magic wand.

Synopsis: we have the following machine, which plays videos (mpg2/mpga) on a schedule via a program that launches VLC from the command line from a bat file. The problem occurred after upgrading from 1.1.11 to 2.0.3.

Windows XP, SP3, AMD 64 3000+ 1.81 GHz, 2.00 GB RAM. ATI Radeon 9550 / X1050 Series. (Audio hardware is the onboard (MSI mbo) nVidia(00EA) Realtek AC'97 ALC850 DirectX9.0c Driver Vers. 5.10.0.5870)

The sound glitches don't correlate with any CPU or Memory Usage spikes. Actually my CPU and Memory seem to have a fair amount of headroom.

I noticed some people were looking at power management options for clues. Mine are set to "Home/Office Desk" for power scheme, with Turn off monitor, hard disks, and system standby all set to "Never". The clock speeds and other bios settings are standard. no OC'ing. It seems like any changes I need to make to VLC will need to be done from the command line. For instance, to even get it to log; opening it, changing the settings, saving, and closing didn't create an output log. To get it to log I had to add "--extraintf=logger --verbose=2 --logfile=C:\VLC-LOG2.txt --logmode=text --file-logging --save-config" to my script. I also, after upgrading to 2.0.3 had to add "--volume=100" to keep it from making a "blown-out" distorted sound.

Here is my output log. I tried to shorten it where there are lots of cascades of identical errors because I doubt a 5mb text file will hold anyone's interest. Also, for some reason every line in my log is doubled. I don't know why but maybe one of you will. (excised sections below will be denoted with lines of .'s)

Code: Select all

-- logger module started -- main debug: using interface module "logger" main debug: TIMER module_need() : 36.430 ms - Total 36.430 ms / 1 intvls (Avg 36.430 ms) main debug: looking for interface module: 1 candidate main debug: using interface module "hotkeys" main debug: TIMER module_need() : 1.887 ms - Total 1.887 ms / 1 intvls (Avg 1.887 ms) main debug: looking for interface module: 1 candidate logger: VLC media player - 2.0.3 Twoflower logger: Copyright © 1996-2012 VLC authors and VideoLAN logger: Warning: if you cannot access the GUI anymore, open a command-line window, go to the directory where you installed VLC and run "vlc -I qt" logger: using logger. logger debug: opening logfile `C:\VLC-LOG2.txt' -- logger module started -- main debug: using interface module "logger" main debug: using interface module "logger" main debug: TIMER module_need() : 4.868 ms - Total 4.868 ms / 1 intvls (Avg 4.868 ms) main debug: TIMER module_need() : 4.868 ms - Total 4.868 ms / 1 intvls (Avg 4.868 ms) main debug: playlist threads correctly activated main debug: playlist threads correctly activated main debug: rebuilding array of current - root Playlist main debug: rebuilding array of current - root Playlist main debug: rebuild done - 0 items, index -1 main debug: rebuild done - 0 items, index -1 main debug: adding item `QUIT' ( VLC://QUIT ) main debug: adding item `QUIT' ( VLC://QUIT ) main debug: Creating an input for 'QUIT' main debug: Creating an input for 'QUIT' main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) main debug: adding item `RTC-TWO4-Ep8.mpg' ( file:///C:/Channel4/Movies/RTC-TWO4-Ep8.mpg ) main debug: adding item `RTC-TWO4-Ep8.mpg' ( file:///C:/Channel4/Movies/RTC-TWO4-Ep8.mpg ) main debug: Creating an input for 'RTC-TWO4-Ep8.mpg' main debug: Creating an input for 'RTC-TWO4-Ep8.mpg' main debug: adding item `rtcintro.mpg' ( file:///C:/Channel4/Movies/rtcintro.mpg ) main debug: adding item `rtcintro.mpg' ( file:///C:/Channel4/Movies/rtcintro.mpg ) main debug: looking for interface module: 1 candidate main debug: looking for interface module: 1 candidate main debug: using interface module "globalhotkeys" main debug: using interface module "globalhotkeys" main debug: TIMER module_need() : 43.126 ms - Total 43.126 ms / 1 intvls (Avg 43.126 ms) main debug: TIMER module_need() : 43.126 ms - Total 43.126 ms / 1 intvls (Avg 43.126 ms) main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main debug: looking for interface module: 4 candidates main debug: looking for interface module: 4 candidates main debug: looking for extension module: 1 candidate main debug: looking for extension module: 1 candidate lua debug: Opening Lua Extension module lua debug: Opening Lua Extension module lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\extensions lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\extensions lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\extensions lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\extensions main debug: using extension module "lua" main debug: using extension module "lua" main debug: TIMER module_need() : 7.054 ms - Total 7.054 ms / 1 intvls (Avg 7.054 ms) main debug: TIMER module_need() : 7.054 ms - Total 7.054 ms / 1 intvls (Avg 7.054 ms) main debug: using interface module "qt4" main debug: using interface module "qt4" main debug: TIMER module_need() : 573.011 ms - Total 573.011 ms / 1 intvls (Avg 573.011 ms) main debug: TIMER module_need() : 573.011 ms - Total 573.011 ms / 1 intvls (Avg 573.011 ms) main debug: rebuilding array of current - root Playlist main debug: rebuilding array of current - root Playlist main debug: rebuild done - 3 items, index -1 main debug: rebuild done - 3 items, index -1 main debug: processing request item: null, node: Playlist, skip: 0 main debug: processing request item: null, node: Playlist, skip: 0 main debug: starting playback of the new playlist item main debug: starting playback of the new playlist item main debug: resyncing on rtcintro.mpg main debug: resyncing on rtcintro.mpg main debug: rtcintro.mpg is at 0 main debug: rtcintro.mpg is at 0 main debug: creating new input thread main debug: creating new input thread main debug: Creating an input for 'rtcintro.mpg' main debug: Creating an input for 'rtcintro.mpg' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/rtcintro.mpg' main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/rtcintro.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: looking for access_demux module: 3 candidates main debug: looking for access_demux module: 3 candidates main debug: no access_demux module matching "file" could be loaded main debug: no access_demux module matching "file" could be loaded main debug: TIMER module_need() : 3.168 ms - Total 3.168 ms / 1 intvls (Avg 3.168 ms) main debug: TIMER module_need() : 3.168 ms - Total 3.168 ms / 1 intvls (Avg 3.168 ms) main debug: creating access 'file' location='/C:/Channel4/Movies/rtcintro.mpg', path='C:\Channel4\Movies\rtcintro.mpg' main debug: creating access 'file' location='/C:/Channel4/Movies/rtcintro.mpg', path='C:\Channel4\Movies\rtcintro.mpg' main debug: looking for access module: 3 candidates main debug: looking for access module: 3 candidates filesystem debug: opening file `C:\Channel4\Movies\rtcintro.mpg' filesystem debug: opening file `C:\Channel4\Movies\rtcintro.mpg' main debug: using access module "filesystem" main debug: using access module "filesystem" main debug: TIMER module_need() : 2.844 ms - Total 2.844 ms / 1 intvls (Avg 2.844 ms) main debug: TIMER module_need() : 2.844 ms - Total 2.844 ms / 1 intvls (Avg 2.844 ms) main debug: Using stream method for AStream* main debug: Using stream method for AStream* main debug: starting pre-buffering main debug: starting pre-buffering main debug: received first data after 0 ms main debug: received first data after 0 ms main debug: pre-buffering done 1024 bytes in 0s - 1300 KiB/s main debug: pre-buffering done 1024 bytes in 0s - 1300 KiB/s main debug: looking for stream_filter module: 4 candidates main debug: looking for stream_filter module: 4 candidates main debug: no stream_filter module matching "any" could be loaded main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 2.075 ms - Total 2.075 ms / 1 intvls (Avg 2.075 ms) main debug: TIMER module_need() : 2.075 ms - Total 2.075 ms / 1 intvls (Avg 2.075 ms) main debug: looking for stream_filter module: 1 candidate main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 1.921 ms - Total 1.921 ms / 1 intvls (Avg 1.921 ms) main debug: TIMER module_need() : 1.921 ms - Total 1.921 ms / 1 intvls (Avg 1.921 ms) main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: looking for demux module: 55 candidates main debug: looking for demux module: 55 candidates qt4 debug: IM: Setting an input qt4 debug: IM: Setting an input ts debug: TS module discarded (lost sync) ts debug: TS module discarded (lost sync) mod debug: MOD validation failed (ext=mpg) mod debug: MOD validation failed (ext=mpg) main debug: using demux module "ps" main debug: using demux module "ps" main debug: TIMER module_need() : 133.243 ms - Total 133.243 ms / 1 intvls (Avg 133.243 ms) main debug: TIMER module_need() : 133.243 ms - Total 133.243 ms / 1 intvls (Avg 133.243 ms) main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for meta reader module: 2 candidates main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\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 lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 20.827 ms - Total 20.827 ms / 1 intvls (Avg 20.827 ms) main debug: TIMER module_need() : 20.827 ms - Total 20.827 ms / 1 intvls (Avg 20.827 ms) main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' successfully opened main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' successfully opened main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) ps warning: garbage at input, trying to resync... ps warning: garbage at input, trying to resync... ps warning: found sync code ps warning: found sync code ps debug: we found a length of: 15815800 ps debug: we found a length of: 15815800 main debug: selecting program id=0 main debug: selecting program id=0 main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates main debug: using decoder module "mpeg_audio" main debug: using decoder module "mpeg_audio" main debug: TIMER module_need() : 311.936 ms - Total 311.936 ms / 1 intvls (Avg 311.936 ms) main debug: TIMER module_need() : 311.936 ms - Total 311.936 ms / 1 intvls (Avg 311.936 ms) main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates avcodec debug: libavcodec initialized (interface 0x361100) avcodec debug: libavcodec initialized (interface 0x361100) avcodec debug: trying to use direct rendering avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: allowing 1 thread(s) for decoding avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec debug: ffmpeg codec (MPEG-1/2 Video) started avcodec debug: ffmpeg codec (MPEG-1/2 Video) started main debug: using decoder module "avcodec" main debug: using decoder module "avcodec" main debug: TIMER module_need() : 282.476 ms - Total 282.476 ms / 1 intvls (Avg 282.476 ms) main debug: TIMER module_need() : 282.476 ms - Total 282.476 ms / 1 intvls (Avg 282.476 ms) main debug: looking for packetizer module: 21 candidates main debug: looking for packetizer module: 21 candidates main debug: using packetizer module "packetizer_mpegvideo" main debug: using packetizer module "packetizer_mpegvideo" main debug: TIMER module_need() : 208.134 ms - Total 208.134 ms / 1 intvls (Avg 208.134 ms) main debug: TIMER module_need() : 208.134 ms - Total 208.134 ms / 1 intvls (Avg 208.134 ms) main debug: Buffering 0% main debug: Buffering 0% . . . . . main debug: Buffering 14% main debug: Buffering 14% packetizer_mpegvideo debug: size 720x480 fps=29.970 packetizer_mpegvideo debug: size 720x480 fps=29.970 main debug: Buffering 15% main debug: Buffering 15% . . . . . main debug: Buffering 32% main debug: Buffering 32% mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:256 mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:256 main debug: creating audio output main debug: creating audio output main debug: looking for audio output module: 2 candidates main debug: looking for audio output module: 2 candidates main debug: Buffering 33% main debug: Buffering 33% . . . . . main debug: Buffering 49% main debug: Buffering 49% avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) main debug: Buffering 50% main debug: Buffering 50% . . . . . main debug: Buffering 99% main debug: Buffering 99% main debug: Stream buffering done (302 ms in 145 ms) main debug: Stream buffering done (302 ms in 145 ms) avcodec debug: Trying DXVA2 avcodec debug: Trying DXVA2 waveout debug: Drivername: Microsoft Sound Mapper waveout debug: Drivername: Microsoft Sound Mapper waveout debug: Driver Version: 5.0 waveout debug: Driver Version: 5.0 waveout debug: Manufacturer identifier: 0x1 waveout debug: Manufacturer identifier: 0x1 waveout debug: Product identifier: 0x2 waveout debug: Product identifier: 0x2 waveout debug: device supports 2 channels waveout debug: device supports 2 channels waveout debug: device supports 1 channel waveout debug: device supports 1 channel waveout debug: OpenWaveDevice-ID: 4294967295 waveout debug: OpenWaveDevice-ID: 4294967295 waveout debug: waveformat.Format.cbSize = 0 waveout debug: waveformat.Format.cbSize = 0 waveout debug: waveformat.Format.wFormatTag = 3 waveout debug: waveformat.Format.wFormatTag = 3 waveout debug: waveformat.Format.nChannels = 2 waveout debug: waveformat.Format.nChannels = 2 waveout debug: waveformat.Format.nSamplesPerSec = 44100 waveout debug: waveformat.Format.nSamplesPerSec = 44100 waveout debug: waveformat.Format.nAvgBytesPerSec = 352800 waveout debug: waveformat.Format.nAvgBytesPerSec = 352800 waveout debug: waveformat.Format.nBlockAlign = 8 waveout debug: waveformat.Format.nBlockAlign = 8 waveout debug: waveformat.Format.wBitsPerSample = 32 waveout debug: waveformat.Format.wBitsPerSample = 32 waveout debug: waveformat.Samples.wValidBitsPerSample = 32 waveout debug: waveformat.Samples.wValidBitsPerSample = 32 waveout debug: waveformat.Samples.wSamplesPerBlock = 32 waveout debug: waveformat.Samples.wSamplesPerBlock = 32 waveout debug: waveformat.dwChannelMask = 3 waveout debug: waveformat.dwChannelMask = 3 main debug: using audio output module "waveout" main debug: using audio output module "waveout" main debug: TIMER module_need() : 336.710 ms - Total 336.710 ms / 1 intvls (Avg 336.710 ms) main debug: TIMER module_need() : 336.710 ms - Total 336.710 ms / 1 intvls (Avg 336.710 ms) main debug: output 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes main debug: output 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes main debug: mixer 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes main debug: mixer 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio mixer module: 2 candidates main debug: looking for audio mixer module: 2 candidates avcodec debug: DLLs loaded avcodec debug: DLLs loaded main debug: using audio mixer module "float32_mixer" main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 44.869 ms - Total 44.869 ms / 1 intvls (Avg 44.869 ms) main debug: TIMER module_need() : 44.869 ms - Total 44.869 ms / 1 intvls (Avg 44.869 ms) main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1262 bytes main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1262 bytes main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32 scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search 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 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: using audio filter module "scaletempo" main debug: TIMER module_need() : 110.781 ms - Total 110.781 ms / 1 intvls (Avg 110.781 ms) main debug: TIMER module_need() : 110.781 ms - Total 110.781 ms / 1 intvls (Avg 110.781 ms) main debug: filter(s) 'mpga'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'mpga'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates mpgatofixed32 debug: mpga->f32l, bits per sample: 32 mpgatofixed32 debug: mpga->f32l, bits per sample: 32 main debug: using audio filter module "mpgatofixed32" main debug: using audio filter module "mpgatofixed32" main debug: TIMER module_need() : 145.793 ms - Total 145.793 ms / 1 intvls (Avg 145.793 ms) main debug: TIMER module_need() : 145.793 ms - Total 145.793 ms / 1 intvls (Avg 145.793 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate compressor debug: compressor successfully initialized compressor debug: compressor successfully initialized main debug: using audio filter module "compressor" main debug: using audio filter module "compressor" main debug: TIMER module_need() : 48.772 ms - Total 48.772 ms / 1 intvls (Avg 48.772 ms) main debug: TIMER module_need() : 48.772 ms - Total 48.772 ms / 1 intvls (Avg 48.772 ms) main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates main debug: using audio filter module "samplerate" main debug: using audio filter module "samplerate" main debug: TIMER module_need() : 44.475 ms - Total 44.475 ms / 1 intvls (Avg 44.475 ms) main debug: TIMER module_need() : 44.475 ms - Total 44.475 ms / 1 intvls (Avg 44.475 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: End of audio preroll main debug: End of audio preroll avcodec debug: D3dCreateDevice succeed avcodec debug: D3dCreateDevice succeed avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec: obtained IDirect3DDeviceManager9 avcodec: obtained IDirect3DDeviceManager9 avcodec: DXVA2CreateVideoService Success! avcodec: DXVA2CreateVideoService Success! avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec error: DxFindVideoServiceConversion failed avcodec error: DxFindVideoServiceConversion failed avcodec warning: Failed to open DXVA2 avcodec warning: Failed to open DXVA2 avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) main debug: looking for text renderer module: 2 candidates main debug: looking for text renderer module: 2 candidates freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: using fontsize: 2 freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: using text renderer module "freetype" main debug: TIMER module_need() : 88.366 ms - Total 88.366 ms / 1 intvls (Avg 88.366 ms) main debug: TIMER module_need() : 88.366 ms - Total 88.366 ms / 1 intvls (Avg 88.366 ms) main debug: looking for video filter2 module: 18 candidates main debug: looking for video filter2 module: 18 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 118.739 ms - Total 118.739 ms / 1 intvls (Avg 118.739 ms) main debug: TIMER module_need() : 118.739 ms - Total 118.739 ms / 1 intvls (Avg 118.739 ms) main debug: looking for video filter2 module: 18 candidates main debug: looking for video filter2 module: 18 candidates yuvp debug: YUVP to YUVA converter yuvp debug: YUVP to YUVA converter main debug: using video filter2 module "yuvp" main debug: using video filter2 module "yuvp" main debug: TIMER module_need() : 260.540 ms - Total 260.540 ms / 1 intvls (Avg 260.540 ms) main debug: TIMER module_need() : 260.540 ms - Total 260.540 ms / 1 intvls (Avg 260.540 ms) main debug: Deinterlacing available main debug: Deinterlacing available main debug: deinterlace 0, mode blend, is_needed 0 main debug: deinterlace 0, mode blend, is_needed 0 main debug: Opening vout display wrapper main debug: Opening vout display wrapper main debug: looking for vout display module: 6 candidates main debug: looking for vout display module: 6 candidates directx debug: creating Vout EventThread directx debug: creating Vout EventThread directx debug: DirectXCreateWindow directx debug: DirectXCreateWindow main debug: looking for vout window hwnd module: 2 candidates main debug: looking for vout window hwnd module: 2 candidates qt4 debug: requesting video... qt4 debug: requesting video... qt4 debug: Video was requested 0, 0 qt4 debug: Video was requested 0, 0 main debug: using vout window hwnd module "qt4" main debug: using vout window hwnd module "qt4" main debug: TIMER module_need() : 22.988 ms - Total 22.988 ms / 1 intvls (Avg 22.988 ms) main debug: TIMER module_need() : 22.988 ms - Total 22.988 ms / 1 intvls (Avg 22.988 ms) directx debug: created video sub-window directx debug: created video sub-window directx debug: Vout EventThread running directx debug: Vout EventThread running directx debug: disabling screen saver directx debug: disabling screen saver directx debug: directx-device: \\.\DISPLAY2 directx debug: directx-device: \\.\DISPLAY2 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series, \\.\DISPLAY1 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series, \\.\DISPLAY1 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: selecting ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: selecting ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: screen dimensions (1024x0,1824x600) directx debug: screen dimensions (1024x0,1824x600) directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 main debug: VoutDisplayEvent 'resize' 728x574 fullscreen main debug: VoutDisplayEvent 'resize' 728x574 fullscreen directx debug: YUV overlay surface created successfully directx debug: YUV overlay surface created successfully main debug: using vout display module "directx" main debug: using vout display module "directx" main debug: TIMER module_need() : 105.059 ms - Total 105.059 ms / 1 intvls (Avg 105.059 ms) main debug: TIMER module_need() : 105.059 ms - Total 105.059 ms / 1 intvls (Avg 105.059 ms) main debug: original format sz 720x480, of (0,0), vsz 720x480, 4cc I420, sar 8:9, msk r0x0 g0x0 b0x0 main debug: original format sz 720x480, of (0,0), vsz 720x480, 4cc I420, sar 8:9, msk r0x0 g0x0 b0x0 main debug: removing module "freetype" main debug: removing module "freetype" main debug: looking for text renderer module: 2 candidates main debug: looking for text renderer module: 2 candidates freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: using fontsize: 2 freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: using text renderer module "freetype" main debug: TIMER module_need() : 7.286 ms - Total 7.286 ms / 1 intvls (Avg 7.286 ms) main debug: TIMER module_need() : 7.286 ms - Total 7.286 ms / 1 intvls (Avg 7.286 ms) avcodec debug: using direct rendering avcodec debug: using direct rendering main debug: End of video preroll main debug: End of video preroll main debug: Received first picture main debug: Received first picture main debug: Post-processing available main debug: Post-processing available main debug: VoutDisplayEvent 'resize' 800x600 fullscreen main debug: VoutDisplayEvent 'resize' 800x600 fullscreen main debug: Decoder buffering done in 1381 ms main debug: Decoder buffering done in 1381 ms

It is here that all of the "waveout" fun begins...

Code: Select all

waveout debug: Wakeup sleeping output thread. waveout debug: Wakeup sleeping output thread. waveout debug: will start to play in 199359 us waveout debug: will start to play in 199359 us waveout warning: aout_PacketNext no buffer got next_date=-779556036 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779556036 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779555577 ms, 6 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779555577 ms, 6 frames to play, waiting... main debug: auto hiding mouse cursor main debug: auto hiding mouse cursor waveout warning: aout_PacketNext no buffer got next_date=-779555213 ms, 10 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779555213 ms, 10 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779554852 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779554852 ms, 5 frames to play, waiting... main warning: audio output out of sync, adjusting dates (-42555 us) main warning: audio output out of sync, adjusting dates (-42555 us) waveout warning: aout_PacketNext no buffer got next_date=-779554489 ms, 9 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779554489 ms, 9 frames to play, waiting... main warning: not synchronized (-42552 us), resampling main warning: not synchronized (-42552 us), resampling main warning: buffer too early (-42999), down-sampling main warning: buffer too early (-42999), down-sampling waveout warning: aout_PacketNext no buffer got next_date=-779554128 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779554128 ms, 5 frames to play, waiting... . . . . waveout warning: aout_PacketNext no buffer got next_date=-779553043 ms, 9 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779553043 ms, 9 frames to play, waiting... main warning: audio output out of sync, adjusting dates (-45061 us) main warning: audio output out of sync, adjusting dates (-45061 us) main warning: not synchronized (-45057 us), resampling main warning: not synchronized (-45057 us), resampling main warning: timing screwed, stopping resampling main warning: timing screwed, stopping resampling main warning: buffer too early (-85341), down-sampling main warning: buffer too early (-85341), down-sampling waveout warning: aout_PacketNext no buffer got next_date=-779552677 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779552677 ms, 5 frames to play, waiting... . . . . . . waveout warning: aout_PacketNext no buffer got next_date=-779551416 ms, 6 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779551416 ms, 6 frames to play, waiting... main warning: audio output out of sync, adjusting dates (-48368 us) main warning: audio output out of sync, adjusting dates (-48368 us) waveout warning: aout_PacketNext no buffer got next_date=-779551146 ms, 9 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779551146 ms, 9 frames to play, waiting... main warning: not synchronized (-48364 us), resampling main warning: not synchronized (-48364 us), resampling main warning: buffer way too early (-131389), clearing queue main warning: buffer way too early (-131389), clearing queue main warning: timing screwed, stopping resampling main warning: timing screwed, stopping resampling main debug: audio output is starving (-230149), playing silence main debug: audio output is starving (-230149), playing silence waveout warning: aout_PacketNext no buffer got next_date=-779551155 ms, 2 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779551155 ms, 2 frames to play, waiting... . . . About 300 lines of the same . . . main debug: EOF reached main debug: EOF reached main debug: waiting decoder fifos to empty main debug: waiting decoder fifos to empty . . . . . . main debug: waiting decoder fifos to empty main debug: waiting decoder fifos to empty main debug: removing module "mpeg_audio" main debug: removing module "mpeg_audio" main debug: killing decoder fourcc `mpga', 0 PES in FIFO main debug: killing decoder fourcc `mpga', 0 PES in FIFO main debug: removing module "mpgatofixed32" main debug: removing module "mpgatofixed32" main debug: removing module "scaletempo" main debug: removing module "scaletempo" main debug: removing module "compressor" main debug: removing module "compressor" main debug: removing module "samplerate" main debug: removing module "samplerate" main debug: removing module "waveout" main debug: removing module "waveout" main debug: removing module "float32_mixer" main debug: removing module "float32_mixer" main debug: releasing audio output main debug: releasing audio output main debug: removing module "avcodec" main debug: removing module "avcodec" avcodec debug: ffmpeg codec (MPEG-1/2 Video) stopped avcodec debug: ffmpeg codec (MPEG-1/2 Video) stopped main debug: killing decoder fourcc `mpgv', 0 PES in FIFO main debug: killing decoder fourcc `mpgv', 0 PES in FIFO main debug: saving a free vout main debug: saving a free vout main debug: reusing provided vout main debug: reusing provided vout main debug: removing module "packetizer_mpegvideo" main debug: removing module "packetizer_mpegvideo" main debug: removing module "ps" main debug: removing module "ps" main debug: Program doesn't contain anymore ES main debug: Program doesn't contain anymore ES main debug: removing module "stream_filter_record" main debug: removing module "stream_filter_record" main debug: removing module "filesystem" main debug: removing module "filesystem" main debug: finished input main debug: finished input main debug: dead input main debug: dead input qt4 debug: IM: Deleting the input qt4 debug: IM: Deleting the input

...At this point the player moves on to the next item.

Code: Select all

main debug: changing item without a request (current 0/3) main debug: changing item without a request (current 0/3) main debug: using item 1 main debug: using item 1 main debug: starting playback of the new playlist item main debug: starting playback of the new playlist item main debug: resyncing on RTC-TWO4-Ep8.mpg main debug: resyncing on RTC-TWO4-Ep8.mpg main debug: RTC-TWO4-Ep8.mpg is at 1 main debug: RTC-TWO4-Ep8.mpg is at 1 main debug: creating new input thread main debug: creating new input thread main debug: Creating an input for 'RTC-TWO4-Ep8.mpg' main debug: Creating an input for 'RTC-TWO4-Ep8.mpg' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: `file:///C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' main debug: `file:///C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' file='C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' file='C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' main debug: looking for access_demux module: 3 candidates main debug: looking for access_demux module: 3 candidates main debug: no access_demux module matching "file" could be loaded main debug: no access_demux module matching "file" could be loaded main debug: TIMER module_need() : 2.246 ms - Total 2.246 ms / 1 intvls (Avg 2.246 ms) main debug: TIMER module_need() : 2.246 ms - Total 2.246 ms / 1 intvls (Avg 2.246 ms) main debug: creating access 'file' location='/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg', path='C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' main debug: creating access 'file' location='/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg', path='C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' main debug: looking for access module: 3 candidates main debug: looking for access module: 3 candidates filesystem debug: opening file `C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' filesystem debug: opening file `C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' main debug: using access module "filesystem" main debug: using access module "filesystem" main debug: TIMER module_need() : 4.179 ms - Total 4.179 ms / 1 intvls (Avg 4.179 ms) main debug: TIMER module_need() : 4.179 ms - Total 4.179 ms / 1 intvls (Avg 4.179 ms) main debug: Using stream method for AStream* main debug: Using stream method for AStream* main debug: starting pre-buffering main debug: starting pre-buffering main debug: received first data after 0 ms main debug: received first data after 0 ms main debug: pre-buffering done 1024 bytes in 0s - 1138 KiB/s main debug: pre-buffering done 1024 bytes in 0s - 1138 KiB/s main debug: looking for stream_filter module: 4 candidates main debug: looking for stream_filter module: 4 candidates main debug: no stream_filter module matching "any" could be loaded main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 2.019 ms - Total 2.019 ms / 1 intvls (Avg 2.019 ms) main debug: TIMER module_need() : 2.019 ms - Total 2.019 ms / 1 intvls (Avg 2.019 ms) main debug: looking for stream_filter module: 1 candidate main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 2.032 ms - Total 2.032 ms / 1 intvls (Avg 2.032 ms) main debug: TIMER module_need() : 2.032 ms - Total 2.032 ms / 1 intvls (Avg 2.032 ms) main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' file='C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' file='C:\Channel4\Movies\RTC-TWO4-Ep8.mpg' main debug: looking for demux module: 55 candidates main debug: looking for demux module: 55 candidates ts debug: TS module discarded (lost sync) ts debug: TS module discarded (lost sync) mod debug: MOD validation failed (ext=mpg) mod debug: MOD validation failed (ext=mpg) main debug: using demux module "ps" main debug: using demux module "ps" main debug: TIMER module_need() : 3.539 ms - Total 3.539 ms / 1 intvls (Avg 3.539 ms) main debug: TIMER module_need() : 3.539 ms - Total 3.539 ms / 1 intvls (Avg 3.539 ms) main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for meta reader module: 2 candidates main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\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 lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 7.242 ms - Total 7.242 ms / 1 intvls (Avg 7.242 ms) main debug: TIMER module_need() : 7.242 ms - Total 7.242 ms / 1 intvls (Avg 7.242 ms) main debug: `file:///C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' successfully opened main debug: `file:///C:/Channel4/Movies/RTC-TWO4-Ep8.mpg' successfully opened ps warning: garbage at input, trying to resync... ps warning: garbage at input, trying to resync... ps warning: found sync code ps warning: found sync code ps debug: we found a length of: 3403728000 ps debug: we found a length of: 3403728000 ps debug: we found a length of: 3404000600 ps debug: we found a length of: 3404000600 main debug: selecting program id=0 main debug: selecting program id=0 main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates avcodec debug: libavcodec already initialized avcodec debug: libavcodec already initialized avcodec debug: trying to use direct rendering avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: allowing 1 thread(s) for decoding avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec debug: ffmpeg codec (MPEG-1/2 Video) started avcodec debug: ffmpeg codec (MPEG-1/2 Video) started main debug: using decoder module "avcodec" main debug: using decoder module "avcodec" main debug: TIMER module_need() : 8.017 ms - Total 8.017 ms / 1 intvls (Avg 8.017 ms) main debug: TIMER module_need() : 8.017 ms - Total 8.017 ms / 1 intvls (Avg 8.017 ms) main debug: looking for packetizer module: 21 candidates main debug: looking for packetizer module: 21 candidates main debug: using packetizer module "packetizer_mpegvideo" main debug: using packetizer module "packetizer_mpegvideo" main debug: TIMER module_need() : 2.006 ms - Total 2.006 ms / 1 intvls (Avg 2.006 ms) main debug: TIMER module_need() : 2.006 ms - Total 2.006 ms / 1 intvls (Avg 2.006 ms) main debug: Buffering 0% main debug: Buffering 0% . . . . main debug: Buffering 1% main debug: Buffering 1% main debug: TIMER input launching for 'rtcintro.mpg' : 211.285 ms - Total 211.285 ms / 1 intvls (Avg 211.285 ms) main debug: TIMER input launching for 'rtcintro.mpg' : 211.285 ms - Total 211.285 ms / 1 intvls (Avg 211.285 ms) main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates main debug: using decoder module "mpeg_audio" main debug: using decoder module "mpeg_audio" main debug: TIMER module_need() : 1.964 ms - Total 1.964 ms / 1 intvls (Avg 1.964 ms) main debug: TIMER module_need() : 1.964 ms - Total 1.964 ms / 1 intvls (Avg 1.964 ms) main debug: Buffering 2% main debug: Buffering 2% . . . . . main debug: Buffering 7% main debug: Buffering 7% mpeg_audio debug: MPGA channels:2 samplerate:48000 bitrate:224 mpeg_audio debug: MPGA channels:2 samplerate:48000 bitrate:224 main debug: recycling audio output main debug: recycling audio output main debug: looking for audio output module: 2 candidates main debug: looking for audio output module: 2 candidates waveout debug: Drivername: Microsoft Sound Mapper waveout debug: Drivername: Microsoft Sound Mapper waveout debug: Driver Version: 5.0 waveout debug: Driver Version: 5.0 waveout debug: Manufacturer identifier: 0x1 waveout debug: Manufacturer identifier: 0x1 waveout debug: Product identifier: 0x2 waveout debug: Product identifier: 0x2 waveout debug: device supports 2 channels waveout debug: device supports 2 channels waveout debug: device supports 1 channel waveout debug: device supports 1 channel waveout debug: OpenWaveDevice-ID: 4294967295 waveout debug: OpenWaveDevice-ID: 4294967295 waveout debug: waveformat.Format.cbSize = 0 waveout debug: waveformat.Format.cbSize = 0 waveout debug: waveformat.Format.wFormatTag = 3 waveout debug: waveformat.Format.wFormatTag = 3 waveout debug: waveformat.Format.nChannels = 2 waveout debug: waveformat.Format.nChannels = 2 waveout debug: waveformat.Format.nSamplesPerSec = 48000 waveout debug: waveformat.Format.nSamplesPerSec = 48000 waveout debug: waveformat.Format.nAvgBytesPerSec = 384000 waveout debug: waveformat.Format.nAvgBytesPerSec = 384000 waveout debug: waveformat.Format.nBlockAlign = 8 waveout debug: waveformat.Format.nBlockAlign = 8 waveout debug: waveformat.Format.wBitsPerSample = 32 waveout debug: waveformat.Format.wBitsPerSample = 32 waveout debug: waveformat.Samples.wValidBitsPerSample = 32 waveout debug: waveformat.Samples.wValidBitsPerSample = 32 waveout debug: waveformat.Samples.wSamplesPerBlock = 32 waveout debug: waveformat.Samples.wSamplesPerBlock = 32 waveout debug: waveformat.dwChannelMask = 3 waveout debug: waveformat.dwChannelMask = 3 main debug: using audio output module "waveout" main debug: using audio output module "waveout" main debug: TIMER module_need() : 18.677 ms - Total 18.677 ms / 1 intvls (Avg 18.677 ms) main debug: TIMER module_need() : 18.677 ms - Total 18.677 ms / 1 intvls (Avg 18.677 ms) main debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes main debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes main debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes main debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio mixer module: 2 candidates main debug: looking for audio mixer module: 2 candidates main debug: using audio mixer module "float32_mixer" main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 2.024 ms - Total 2.024 ms / 1 intvls (Avg 2.024 ms) main debug: TIMER module_need() : 2.024 ms - Total 2.024 ms / 1 intvls (Avg 2.024 ms) main debug: input 'mpga' 48000 Hz Stereo frame=1152 samples/1161 bytes main debug: input 'mpga' 48000 Hz Stereo frame=1152 samples/1161 bytes main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32 scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search 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 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: using audio filter module "scaletempo" main debug: TIMER module_need() : 5.491 ms - Total 5.491 ms / 1 intvls (Avg 5.491 ms) main debug: TIMER module_need() : 5.491 ms - Total 5.491 ms / 1 intvls (Avg 5.491 ms) main debug: filter(s) 'mpga'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: filter(s) 'mpga'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates mpgatofixed32 debug: mpga->f32l, bits per sample: 32 mpgatofixed32 debug: mpga->f32l, bits per sample: 32 main debug: using audio filter module "mpgatofixed32" main debug: using audio filter module "mpgatofixed32" main debug: TIMER module_need() : 2.638 ms - Total 2.638 ms / 1 intvls (Avg 2.638 ms) main debug: TIMER module_need() : 2.638 ms - Total 2.638 ms / 1 intvls (Avg 2.638 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate compressor debug: compressor successfully initialized compressor debug: compressor successfully initialized main debug: using audio filter module "compressor" main debug: using audio filter module "compressor" main debug: TIMER module_need() : 2.935 ms - Total 2.935 ms / 1 intvls (Avg 2.935 ms) main debug: TIMER module_need() : 2.935 ms - Total 2.935 ms / 1 intvls (Avg 2.935 ms) main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates main debug: using audio filter module "samplerate" main debug: using audio filter module "samplerate" main debug: TIMER module_need() : 2.091 ms - Total 2.091 ms / 1 intvls (Avg 2.091 ms) main debug: TIMER module_need() : 2.091 ms - Total 2.091 ms / 1 intvls (Avg 2.091 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: End of audio preroll main debug: End of audio preroll main debug: Buffering 8% main debug: Buffering 8% . . . . . main debug: Buffering 15% main debug: Buffering 15% packetizer_mpegvideo debug: size 720x480 fps=29.970 packetizer_mpegvideo debug: size 720x480 fps=29.970 qt4 debug: IM: Setting an input qt4 debug: IM: Setting an input avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Trying DXVA2 avcodec debug: Trying DXVA2 avcodec debug: DLLs loaded avcodec debug: DLLs loaded main debug: Buffering 16% main debug: Buffering 16% . . . . . main debug: Buffering 99% main debug: Buffering 99% main debug: Stream buffering done (320 ms in 243 ms) main debug: Stream buffering done (320 ms in 243 ms) avcodec debug: D3dCreateDevice succeed avcodec debug: D3dCreateDevice succeed avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec: obtained IDirect3DDeviceManager9 avcodec: obtained IDirect3DDeviceManager9 avcodec: DXVA2CreateVideoService Success! avcodec: DXVA2CreateVideoService Success! avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec error: DxFindVideoServiceConversion failed avcodec error: DxFindVideoServiceConversion failed avcodec warning: Failed to open DXVA2 avcodec warning: Failed to open DXVA2 avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) main debug: trying to reuse free vout main debug: trying to reuse free vout main debug: removing module "freetype" main debug: removing module "freetype" main debug: looking for text renderer module: 2 candidates main debug: looking for text renderer module: 2 candidates freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: using fontsize: 2 freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: using text renderer module "freetype" main debug: TIMER module_need() : 4.236 ms - Total 4.236 ms / 1 intvls (Avg 4.236 ms) main debug: TIMER module_need() : 4.236 ms - Total 4.236 ms / 1 intvls (Avg 4.236 ms) main debug: reusing provided vout main debug: reusing provided vout avcodec debug: using direct rendering avcodec debug: using direct rendering main debug: End of video preroll main debug: End of video preroll main debug: Received first picture main debug: Received first picture main debug: Detected interlaced video main debug: Detected interlaced video main debug: deinterlace 0, mode blend, is_needed 1 main debug: deinterlace 0, mode blend, is_needed 1 main debug: Decoder buffering done in 75 ms main debug: Decoder buffering done in 75 ms

And here we start the "waveout" fun for the second video

Code: Select all

waveout debug: Wakeup sleeping output thread. waveout debug: Wakeup sleeping output thread. waveout debug: will start to play in 236714 us waveout debug: will start to play in 236714 us waveout warning: aout_PacketNext no buffer got next_date=-779539332 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779539332 ms, 5 frames to play, waiting... . . . . . . main warning: audio output out of sync, adjusting dates (-44092 us) main warning: audio output out of sync, adjusting dates (-44092 us) waveout warning: aout_PacketNext no buffer got next_date=-779537660 ms, 6 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779537660 ms, 6 frames to play, waiting... main warning: not synchronized (-44088 us), resampling main warning: not synchronized (-44088 us), resampling main warning: buffer too early (-44505), down-sampling main warning: buffer too early (-44505), down-sampling waveout warning: aout_PacketNext no buffer got next_date=-779537328 ms, 10 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779537328 ms, 10 frames to play, waiting... . . . . . . main warning: audio output out of sync, adjusting dates (-47571 us) main warning: audio output out of sync, adjusting dates (-47571 us) waveout warning: aout_PacketNext no buffer got next_date=-779536000 ms, 10 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779536000 ms, 10 frames to play, waiting... main warning: not synchronized (-47567 us), resampling main warning: not synchronized (-47567 us), resampling main warning: timing screwed, stopping resampling main warning: timing screwed, stopping resampling main warning: buffer too early (-89426), down-sampling main warning: buffer too early (-89426), down-sampling waveout warning: aout_PacketNext no buffer got next_date=-779535669 ms, 6 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779535669 ms, 6 frames to play, waiting... . . . . . . main warning: audio output out of sync, adjusting dates (-48365 us) main warning: audio output out of sync, adjusting dates (-48365 us) waveout warning: aout_PacketNext no buffer got next_date=-779534427 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779534427 ms, 5 frames to play, waiting... main warning: not synchronized (-48361 us), resampling main warning: not synchronized (-48361 us), resampling main warning: buffer way too early (-135433), clearing queue main warning: buffer way too early (-135433), clearing queue main warning: timing screwed, stopping resampling main warning: timing screwed, stopping resampling main debug: audio output is starving (-252541), playing silence main debug: audio output is starving (-252541), playing silence waveout warning: aout_PacketNext no buffer got next_date=-779534426 ms, 2 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-779534426 ms, 2 frames to play, waiting... . . . . . About 120,000 lines following this pattern omitted. . . . . . main warning: audio output out of sync, adjusting dates (-46656 us) main warning: audio output out of sync, adjusting dates (-46656 us) waveout warning: aout_PacketNext no buffer got next_date=-776136936 ms, 5 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-776136936 ms, 5 frames to play, waiting... main warning: not synchronized (-46652 us), resampling main warning: not synchronized (-46652 us), resampling main warning: buffer way too early (-129024), clearing queue main warning: buffer way too early (-129024), clearing queue main warning: timing screwed, stopping resampling main warning: timing screwed, stopping resampling main debug: audio output is starving (-181443), playing silence main debug: audio output is starving (-181443), playing silence waveout warning: aout_PacketNext no buffer got next_date=-776136945 ms, 2 frames to play, waiting... waveout warning: aout_PacketNext no buffer got next_date=-776136945 ms, 2 frames to play, waiting... . . etc. etc. until the end of the file. . .

To anyone taking the time to read this and help me fix my problem, I thank you!

[rsbrux]

There's a simple fix, as suggested in this post:
viewtopic.php?f=14&t=103742&p=351643&hi ... .0#p351643
Just downgrade to v1.1.11, which works fine, and wait for the dust to settle!

[Rémi Denis-Courmont]

Mr Smartass, if all users downgrade and wait for the dust to settlte, the dust will never settle.

[SSonik]

I've tested setting the clock source to: perf, interrupt, tick, multimedia, wall, and leaving it as null. Unfortunately, none of the sources appear to change the playback (I'm also missing debug events that a new clock source has been selected, or if it's invalid).

I haven't cycled through different BIOS options yet, but I will do. Are there any other clock sources that I missed from the list above?

[Jean-Baptiste Kempf]

I'm not a pro coder, but I work in IT - and am willing to put in time to squash this bug.

Thanks for your help. We really need help.

Please try the 2.1 version and share logs with all options clocks. You need to restart VLC each time.
Please try to change the audio output in preferences (dsound vs waveout).
Please try to remove the resampler dll to just let the dummy one.
Please try with mp3 and flac files.

[Ausrick]

Hi again everyone!

I changed the audio output to directx and it bypassed the audio dropping glitch. We have nice clean audio... Except that now no one can hear it because it always launches at 33% volume instead of 100% like it used to. Directly below is the command line switches used when it runs:

Code: Select all

--aout=directx --volume=100

Below is the most recent log file, at least as much as I could manage to attach here with the 60k character limit on posts, totally redundant sections (e.g. buffering, are excised and replaced with .'s) Again, I don't know why almost every line in the log is doubled.

Code: Select all

-- logger module started -- main debug: using interface module "logger" main debug: TIMER module_need() : 44.362 ms - Total 44.362 ms / 1 intvls (Avg 44.362 ms) main debug: looking for interface module: 1 candidate main debug: using interface module "hotkeys" main debug: TIMER module_need() : 12.345 ms - Total 12.345 ms / 1 intvls (Avg 12.345 ms) main debug: looking for interface module: 1 candidate logger: VLC media player - 2.0.3 Twoflower logger: Copyright © 1996-2012 VLC authors and VideoLAN logger: Warning: if you cannot access the GUI anymore, open a command-line window, go to the directory where you installed VLC and run "vlc -I qt" logger: using logger. logger debug: opening logfile `C:\VLC-LOG-004.txt' -- logger module started -- main debug: using interface module "logger" main debug: using interface module "logger" main debug: TIMER module_need() : 4.725 ms - Total 4.725 ms / 1 intvls (Avg 4.725 ms) main debug: TIMER module_need() : 4.725 ms - Total 4.725 ms / 1 intvls (Avg 4.725 ms) main debug: playlist threads correctly activated main debug: playlist threads correctly activated main debug: rebuilding array of current - root Playlist main debug: rebuilding array of current - root Playlist main debug: rebuild done - 0 items, index -1 main debug: rebuild done - 0 items, index -1 main debug: adding item `QUIT' ( VLC://QUIT ) main debug: adding item `QUIT' ( VLC://QUIT ) main debug: Creating an input for 'QUIT' main debug: Creating an input for 'QUIT' main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) main debug: adding item `ZebrasFootballvsWabash.mpg' ( file:///C:/Channel4/Movies/ZebrasFootballvsWabash.mpg ) main debug: adding item `ZebrasFootballvsWabash.mpg' ( file:///C:/Channel4/Movies/ZebrasFootballvsWabash.mpg ) main debug: Creating an input for 'ZebrasFootballvsWabash.mpg' main debug: Creating an input for 'ZebrasFootballvsWabash.mpg' main debug: adding item `rtcintro.mpg' ( file:///C:/Channel4/Movies/rtcintro.mpg ) main debug: adding item `rtcintro.mpg' ( file:///C:/Channel4/Movies/rtcintro.mpg ) main debug: looking for interface module: 1 candidate main debug: looking for interface module: 1 candidate main debug: using interface module "globalhotkeys" main debug: using interface module "globalhotkeys" main debug: TIMER module_need() : 63.172 ms - Total 63.172 ms / 1 intvls (Avg 63.172 ms) main debug: TIMER module_need() : 63.172 ms - Total 63.172 ms / 1 intvls (Avg 63.172 ms) main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main debug: looking for interface module: 4 candidates main debug: looking for interface module: 4 candidates main debug: looking for extension module: 1 candidate main debug: looking for extension module: 1 candidate lua debug: Opening Lua Extension module lua debug: Opening Lua Extension module lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\extensions lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\extensions lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\extensions lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\extensions main debug: using extension module "lua" main debug: using extension module "lua" main debug: TIMER module_need() : 6.011 ms - Total 6.011 ms / 1 intvls (Avg 6.011 ms) main debug: TIMER module_need() : 6.011 ms - Total 6.011 ms / 1 intvls (Avg 6.011 ms) main debug: using interface module "qt4" main debug: using interface module "qt4" main debug: TIMER module_need() : 546.293 ms - Total 546.293 ms / 1 intvls (Avg 546.293 ms) main debug: TIMER module_need() : 546.293 ms - Total 546.293 ms / 1 intvls (Avg 546.293 ms) main debug: rebuilding array of current - root Playlist main debug: rebuilding array of current - root Playlist main debug: rebuild done - 3 items, index -1 main debug: rebuild done - 3 items, index -1 main debug: processing request item: null, node: Playlist, skip: 0 main debug: processing request item: null, node: Playlist, skip: 0 main debug: starting playback of the new playlist item main debug: starting playback of the new playlist item main debug: resyncing on rtcintro.mpg main debug: resyncing on rtcintro.mpg main debug: rtcintro.mpg is at 0 main debug: rtcintro.mpg is at 0 main debug: creating new input thread main debug: creating new input thread main debug: Creating an input for 'rtcintro.mpg' main debug: Creating an input for 'rtcintro.mpg' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/rtcintro.mpg' main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/rtcintro.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: looking for access_demux module: 3 candidates main debug: looking for access_demux module: 3 candidates main debug: no access_demux module matching "file" could be loaded main debug: no access_demux module matching "file" could be loaded main debug: TIMER module_need() : 2.541 ms - Total 2.541 ms / 1 intvls (Avg 2.541 ms) main debug: TIMER module_need() : 2.541 ms - Total 2.541 ms / 1 intvls (Avg 2.541 ms) main debug: creating access 'file' location='/C:/Channel4/Movies/rtcintro.mpg', path='C:\Channel4\Movies\rtcintro.mpg' main debug: creating access 'file' location='/C:/Channel4/Movies/rtcintro.mpg', path='C:\Channel4\Movies\rtcintro.mpg' main debug: looking for access module: 3 candidates main debug: looking for access module: 3 candidates filesystem debug: opening file `C:\Channel4\Movies\rtcintro.mpg' filesystem debug: opening file `C:\Channel4\Movies\rtcintro.mpg' main debug: using access module "filesystem" main debug: using access module "filesystem" main debug: TIMER module_need() : 2.846 ms - Total 2.846 ms / 1 intvls (Avg 2.846 ms) main debug: TIMER module_need() : 2.846 ms - Total 2.846 ms / 1 intvls (Avg 2.846 ms) main debug: Using stream method for AStream* main debug: Using stream method for AStream* main debug: starting pre-buffering main debug: starting pre-buffering main debug: received first data after 0 ms main debug: received first data after 0 ms main debug: pre-buffering done 1024 bytes in 0s - 1321 KiB/s main debug: pre-buffering done 1024 bytes in 0s - 1321 KiB/s main debug: looking for stream_filter module: 4 candidates main debug: looking for stream_filter module: 4 candidates main debug: no stream_filter module matching "any" could be loaded main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 25.658 ms - Total 25.658 ms / 1 intvls (Avg 25.658 ms) main debug: TIMER module_need() : 25.658 ms - Total 25.658 ms / 1 intvls (Avg 25.658 ms) main debug: looking for stream_filter module: 1 candidate main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 1.957 ms - Total 1.957 ms / 1 intvls (Avg 1.957 ms) main debug: TIMER module_need() : 1.957 ms - Total 1.957 ms / 1 intvls (Avg 1.957 ms) main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/rtcintro.mpg' file='C:\Channel4\Movies\rtcintro.mpg' main debug: looking for demux module: 55 candidates main debug: looking for demux module: 55 candidates qt4 debug: IM: Setting an input qt4 debug: IM: Setting an input ts debug: TS module discarded (lost sync) ts debug: TS module discarded (lost sync) mod debug: MOD validation failed (ext=mpg) mod debug: MOD validation failed (ext=mpg) main debug: using demux module "ps" main debug: using demux module "ps" main debug: TIMER module_need() : 251.662 ms - Total 251.662 ms / 1 intvls (Avg 251.662 ms) main debug: TIMER module_need() : 251.662 ms - Total 251.662 ms / 1 intvls (Avg 251.662 ms) main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for meta reader module: 2 candidates main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\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 lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 7.566 ms - Total 7.566 ms / 1 intvls (Avg 7.566 ms) main debug: TIMER module_need() : 7.566 ms - Total 7.566 ms / 1 intvls (Avg 7.566 ms) main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' successfully opened main debug: `file:///C:/Channel4/Movies/rtcintro.mpg' successfully opened main debug: no fetch required for (null) (art currently (null)) main debug: no fetch res warning: garbage at input, trying to resync... ps warning: garbage at input, trying to resync... ps warning: found sync code ps warning: found sync code quired for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (nulls debug: we found a length of: 15815800 ps debug: we found a length of: 15815800 )) main debug: selecting program id=0 main debug: selecting program id=0 main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates main debug: using decoder module "mpeg_audio" main debug: using decoder module "mpeg_audio" main debug: TIMER module_need() : 192.340 ms - Total 192.340 ms / 1 intvls (Avg 192.340 ms) main debug: TIMER module_need() : 192.340 ms - Total 192.340 ms / 1 intvls (Avg 192.340 ms) main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates avcodec debug: libavcodec initialized (interface 0x361100) avcodec debug: libavcodec initialized (interface 0x361100) avcodec debug: trying to use direct rendering avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: allowing 1 thread(s) for decoding avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec debug: ffmpeg codec (MPEG-1/2 Video) started avcodec debug: ffmpeg codec (MPEG-1/2 Video) started main debug: using decoder module "avcodec" main debug: using decoder module "avcodec" main debug: TIMER module_need() : 282.970 ms - Total 282.970 ms / 1 intvls (Avg 282.970 ms) main debug: TIMER module_need() : 282.970 ms - Total 282.970 ms / 1 intvls (Avg 282.970 ms) main debug: looking for packetizer module: 21 candidates main debug: looking for packetizer module: 21 candidates main debug: using packetizer module "packetizer_mpegvideo" main debug: using packetizer module "packetizer_mpegvideo" main debug: TIMER module_need() : 238.823 ms - Total 238.823 ms / 1 intvls (Avg 238.823 ms) main debug: TIMER module_need() : 238.823 ms - Total 238.823 ms / 1 intvls (Avg 238.823 ms) main debug: Buffering 0% main debug: Buffering 0% . . . main debug: Buffering 14% main debug: Buffering 14% packetizer_mpegvideo debug: size 720x480 fps=29.970 packetizer_mpegvideo debug: size 720x480 fps=29.970 main debug: Buffering 15% main debug: Buffering 15% . . . main debug: Buffering 32% main debug: Buffering 32% mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:256 mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:256 main debug: creating audio output main debug: creating audio output main debug: looking for audio output module: 2 candidates main debug: looking for audio output module: 2 candidates aout_directx debug: Opening DirectSound Audio Output aout_directx debug: Opening DirectSound Audio Output main debug: Buffering 33% main debug: Buffering 33% . . . main debug: Buffering 49% main debug: Buffering 49% avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) main debug: Buffering 50% main debug: Buffering 50% . . . main debug: Buffering 99% main debug: Buffering 99% main debug: Stream buffering done (302 ms in 158 ms) main debug: Stream buffering done (302 ms in 158 ms) avcodec debug: Trying DXVA2 avcodec debug: Trying DXVA2 aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: Realtek AC97 Audio aout_directx debug: found device: Realtek AC97 Audio avcodec debug: DLLs loaded avcodec debug: DLLs loaded aout_directx debug: device supports 2 channels (DEFAULT!) aout_directx debug: device supports 2 channels (DEFAULT!) aout_directx debug: device supports 1 channel aout_directx debug: device supports 1 channel aout_directx debug: Windows says your SpeakerConfig is 7.1 aout_directx debug: Windows says your SpeakerConfig is 7.1 aout_directx debug: creating DirectSoundThread aout_directx debug: creating DirectSoundThread aout_directx debug: DirectSoundThread ready aout_directx debug: DirectSoundThread ready main debug: using audio output module "aout_directx" main debug: using audio output module "aout_directx" main debug: TIMER module_need() : 388.185 ms - Total 388.185 ms / 1 intvls (Avg 388.185 ms) main debug: TIMER module_need() : 388.185 ms - Total 388.185 ms / 1 intvls (Avg 388.185 ms) main debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes 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: mixer 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes main debug: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates audio_format debug: f32l->s16l, bits per sample: 32->16 audio_format debug: f32l->s16l, bits per sample: 32->16 main debug: using audio filter module "audio_format" main debug: using audio filter module "audio_format" main debug: TIMER module_need() : 431.049 ms - Total 431.049 ms / 1 intvls (Avg 431.049 ms) main debug: TIMER module_need() : 431.049 ms - Total 431.049 ms / 1 intvls (Avg 431.049 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio mixer module: 2 candidates main debug: looking for audio mixer module: 2 candidates main debug: using audio mixer module "float32_mixer" main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 49.682 ms - Total 49.682 ms / 1 intvls (Avg 49.682 ms) main debug: TIMER module_need() : 49.682 ms - Total 49.682 ms / 1 intvls (Avg 49.682 ms) main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1262 bytes main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1262 bytes main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32 scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search 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 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: using audio filter module "scaletempo" main debug: TIMER module_need() : 63.377 ms - Total 63.377 ms / 1 intvls (Avg 63.377 ms) main debug: TIMER module_need() : 63.377 ms - Total 63.377 ms / 1 intvls (Avg 63.377 ms) main debug: filter(s) 'mpga'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'mpga'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates mpgatofixed32 debug: mpga->f32l, bits per sample: 32 mpgatofixed32 debug: mpga->f32l, bits per sample: 32 main debug: using audio filter module "mpgatofixed32" main debug: using audio filter module "mpgatofixed32" main debug: TIMER module_need() : 1.840 ms - Total 1.840 ms / 1 intvls (Avg 1.840 ms) main debug: TIMER module_need() : 1.840 ms - Total 1.840 ms / 1 intvls (Avg 1.840 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate compressor debug: compressor successfully initialized compressor debug: compressor successfully initialized main debug: using audio filter module "compressor" main debug: using audio filter module "compressor" main debug: TIMER module_need() : 30.172 ms - Total 30.172 ms / 1 intvls (Avg 30.172 ms) main debug: TIMER module_need() : 30.172 ms - Total 30.172 ms / 1 intvls (Avg 30.172 ms) main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates main debug: using audio filter module "samplerate" main debug: using audio filter module "samplerate" main debug: TIMER module_need() : 1.461 ms - Total 1.461 ms / 1 intvls (Avg 1.461 ms) main debug: TIMER module_need() : 1.461 ms - Total 1.461 ms / 1 intvls (Avg 1.461 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: End of audio preroll main debug: End of audio preroll avcodec debug: D3dCreateDevice succeed avcodec debug: D3dCreateDevice succeed avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec: obtained IDirect3DDeviceManager9 avcodec: obtained IDirect3DDeviceManager9 avcodec: DXVA2CreateVideoService Success! avcodec: DXVA2CreateVideoService Success! avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec error: DxFindVideoServiceConversion failed avcodec error: DxFindVideoServiceConversion failed avcodec warning: Failed to open DXVA2 avcodec warning: Failed to open DXVA2 avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) main debug: looking for text renderer module: 2 candidates main debug: looking for text renderer module: 2 candidates freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: using fontsize: 2 freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: using text renderer module "freetype" main debug: TIMER module_need() : 92.474 ms - Total 92.474 ms / 1 intvls (Avg 92.474 ms) main debug: TIMER module_need() : 92.474 ms - Total 92.474 ms / 1 intvls (Avg 92.474 ms) main debug: looking for video filter2 module: 18 candidates main debug: looking for video filter2 module: 18 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 155.408 ms - Total 155.408 ms / 1 intvls (Avg 155.408 ms) main debug: TIMER module_need() : 155.408 ms - Total 155.408 ms / 1 intvls (Avg 155.408 ms) main debug: looking for video filter2 module: 18 candidates main debug: looking for video filter2 module: 18 candidates yuvp debug: YUVP to YUVA converter yuvp debug: YUVP to YUVA converter main debug: using video filter2 module "yuvp" main debug: using video filter2 module "yuvp" main debug: TIMER module_need() : 300.505 ms - Total 300.505 ms / 1 intvls (Avg 300.505 ms) main debug: TIMER module_need() : 300.505 ms - Total 300.505 ms / 1 intvls (Avg 300.505 ms) main debug: Deinterlacing available main debug: Deinterlacing available main debug: deinterlace 0, mode blend, is_needed 0 main debug: deinterlace 0, mode blend, is_needed 0 main debug: Opening vout display wrapper main debug: Opening vout display wrapper main debug: looking for vout display module: 6 candidates main debug: looking for vout display module: 6 candidates directx debug: creating Vout EventThread directx debug: creating Vout EventThread directx debug: DirectXCreateWindow directx debug: DirectXCreateWindow main debug: looking for vout window hwnd module: 2 candidates main debug: looking for vout window hwnd module: 2 candidates qt4 debug: requesting video... qt4 debug: requesting video... qt4 debug: Video was requested 0, 0 qt4 debug: Video was requested 0, 0 main debug: using vout window hwnd module "qt4" main debug: using vout window hwnd module "qt4" main debug: TIMER module_need() : 21.067 ms - Total 21.067 ms / 1 intvls (Avg 21.067 ms) main debug: TIMER module_need() : 21.067 ms - Total 21.067 ms / 1 intvls (Avg 21.067 ms) directx debug: created video sub-window directx debug: created video sub-window directx debug: Vout EventThread running directx debug: Vout EventThread running directx debug: disabling screen saver directx debug: disabling screen saver directx debug: directx-device: \\.\DISPLAY2 directx debug: directx-device: \\.\DISPLAY2 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series, \\.\DISPLAY1 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series, \\.\DISPLAY1 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: DirectXEnumCallback: ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: selecting ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: selecting ATI Radeon 9550 / X1050 Series Secondary, \\.\DISPLAY2 directx debug: screen dimensions (1024x0,1824x600) directx debug: screen dimensions (1024x0,1824x600) directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 main debug: VoutDisplayEvent 'resize' 728x574 fullscreen main debug: VoutDisplayEvent 'resize' 728x574 fullscreen directx debug: YUV overlay surface created successfully directx debug: YUV overlay surface created successfully main debug: using vout display module "directx" main debug: using vout display module "directx" main debug: TIMER module_need() : 109.709 ms - Total 109.709 ms / 1 intvls (Avg 109.709 ms) main debug: TIMER module_need() : 109.709 ms - Total 109.709 ms / 1 intvls (Avg 109.709 ms) main debug: original format sz 720x480, of (0,0), vsz 720x480, 4cc I420, sar 8:9, msk r0x0 g0x0 b0x0 main debug: original format sz 720x480, of (0,0), vsz 720x480, 4cc I420, sar 8:9, msk r0x0 g0x0 b0x0 main debug: removing module "freetype" main debug: removing module "freetype" main debug: looking for text renderer module: 2 candidates main debug: looking for text renderer module: 2 candidates freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: using fontsize: 2 freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: using text renderer module "freetype" main debug: TIMER module_need() : 3.492 ms - Total 3.492 ms / 1 intvls (Avg 3.492 ms) main debug: TIMER module_need() : 3.492 ms - Total 3.492 ms / 1 intvls (Avg 3.492 ms) avcodec debug: using direct rendering avcodec debug: using direct rendering main debug: End of video preroll main debug: End of video preroll main debug: Received first picture main debug: Received first picture main debug: Post-processing available main debug: Post-processing available main debug: VoutDisplayEvent 'resize' 800x600 fullscreen main debug: VoutDisplayEvent 'resize' 800x600 fullscreen main debug: Decoder buffering done in 1673 ms main debug: Decoder buffering done in 1673 ms main debug: auto hiding mouse cursor main debug: auto hiding mouse cursor main debug: EOF reached main debug: EOF reached main debug: waiting decoder fifos to empty main debug: waiting decoder fifos to empty . . . main debug: waiting decoder fifos to empty main debug: waiting decoder fifos to empty main debug: removing module "mpeg_audio" main debug: removing module "mpeg_audio" main debug: killing decoder fourcc `mpga', 0 PES in FIFO main debug: killing decoder fourcc `mpga', 0 PES in FIFO main debug: removing module "mpgatofixed32" main debug: removing module "mpgatofixed32" main debug: removing module "scaletempo" main debug: removing module "scaletempo" main debug: removing module "compressor" main debug: removing module "compressor" main debug: removing module "samplerate" main debug: removing module "samplerate" main debug: removing module "aout_directx" main debug: removing module "aout_directx" aout_directx debug: closing audio device aout_directx debug: closing audio device main debug: finished input main debug: finished input aout_directx debug: DirectSoundThread exiting aout_directx debug: DirectSoundThread exiting main debug: removing module "audio_format" main debug: removing module "audio_format" main debug: removing module "float32_mixer" main debug: removing module "float32_mixer" main debug: releasing audio output main debug: releasing audio output main debug: removing module "avcodec" main debug: removing module "avcodec" avcodec debug: ffmpeg codec (MPEG-1/2 Video) stopped avcodec debug: ffmpeg codec (MPEG-1/2 Video) stopped main debug: killing decoder fourcc `mpgv', 0 PES in FIFO main debug: killing decoder fourcc `mpgv', 0 PES in FIFO main debug: saving a free vout main debug: saving a free vout main debug: reusing provided vout main debug: reusing provided vout main debug: removing module "packetizer_mpegvideo" main debug: removing module "packetizer_mpegvideo" main debug: removing module "ps" main debug: removing module "ps" main debug: Program doesn't contain anymore ES main debug: Program doesn't contain anymore ES main debug: removing module "stream_filter_record" main debug: removing module "stream_filter_record" main debug: removing module "filesystem" main debug: removing module "filesystem" main debug: dead input main debug: dead input qt4 debug: IM: Deleting the input qt4 debug: IM: Deleting the input main debug: changing item without a request (current 0/3) main debug: changing item without a request (current 0/3) main debug: using item 1 main debug: using item 1 main debug: starting playback of the new playlist item main debug: starting playback of the new playlist item main debug: resyncing on ZebrasFootballvsWabash.mpg main debug: resyncing on ZebrasFootballvsWabash.mpg main debug: ZebrasFootballvsWabash.mpg is at 1 main debug: ZebrasFootballvsWabash.mpg is at 1 main debug: creating new input thread main debug: creating new input thread main debug: Creating an input for 'ZebrasFootballvsWabash.mpg' main debug: Creating an input for 'ZebrasFootballvsWabash.mpg' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: `file:///C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' main debug: `file:///C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' gives access `file' demux `' path `/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' file='C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' file='C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' main debug: looking for access_demux module: 3 candidates main debug: looking for access_demux module: 3 candidates main debug: no access_demux module matching "file" could be loaded main debug: no access_demux module matching "file" could be loaded main debug: TIMER module_need() : 1.643 ms - Total 1.643 ms / 1 intvls (Avg 1.643 ms) main debug: TIMER module_need() : 1.643 ms - Total 1.643 ms / 1 intvls (Avg 1.643 ms) main debug: creating access 'file' location='/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg', path='C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' main debug: creating access 'file' location='/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg', path='C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' main debug: looking for access module: 3 candidates main debug: looking for access module: 3 candidates filesystem debug: opening file `C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' filesystem debug: opening file `C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' main debug: using access module "filesystem" main debug: using access module "filesystem" main debug: TIMER module_need() : 2.130 ms - Total 2.130 ms / 1 intvls (Avg 2.130 ms) main debug: TIMER module_need() : 2.130 ms - Total 2.130 ms / 1 intvls (Avg 2.130 ms) main debug: Using stream method for AStream* main debug: Using stream method for AStream* main debug: starting pre-buffering main debug: starting pre-buffering main debug: received first data after 0 ms main debug: received first data after 0 ms main debug: pre-buffering done 1024 bytes in 0s - 1915 KiB/s main debug: pre-buffering done 1024 bytes in 0s - 1915 KiB/s main debug: looking for stream_filter module: 4 candidates main debug: looking for stream_filter module: 4 candidates main debug: no stream_filter module matching "any" could be loaded main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 1.508 ms - Total 1.508 ms / 1 intvls (Avg 1.508 ms) main debug: TIMER module_need() : 1.508 ms - Total 1.508 ms / 1 intvls (Avg 1.508 ms) main debug: looking for stream_filter module: 1 candidate main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 1.280 ms - Total 1.280 ms / 1 intvls (Avg 1.280 ms) main debug: TIMER module_need() : 1.280 ms - Total 1.280 ms / 1 intvls (Avg 1.280 ms) main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' file='C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' main debug: creating demux: access='file' demux='' location='/C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' file='C:\Channel4\Movies\ZebrasFootballvsWabash.mpg' main debug: looking for demux module: 55 candidates main debug: looking for demux module: 55 candidates ts debug: TS module discarded (lost sync) ts debug: TS module discarded (lost sync) mod debug: MOD validation failed (ext=mpg) mod debug: MOD validation failed (ext=mpg) main debug: using demux module "ps" main debug: using demux module "ps" main debug: TIMER module_need() : 2.436 ms - Total 2.436 ms / 1 intvls (Avg 2.436 ms) main debug: TIMER module_need() : 2.436 ms - Total 2.436 ms / 1 intvls (Avg 2.436 ms) main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for a subtitle file in C:\Channel4\Movies\ main debug: looking for meta reader module: 2 candidates main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\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 lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 5.455 ms - Total 5.455 ms / 1 intvls (Avg 5.455 ms) main debug: TIMER module_need() : 5.455 ms - Total 5.455 ms / 1 intvls (Avg 5.455 ms) main debug: `file:///C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' successfully opened main debug: `file:///C:/Channel4/Movies/ZebrasFootballvsWabash.mpg' successfully opened main debug: TIMER input launching for 'rtcintro.mpg' : 329.391 ms - Total 329.391 ms / 1 intvls (Avg 329.391 ms) main debug: TIMER input launching for 'rtcintro.mpg' : 329.391 ms - Total 329.391 ms / 1 intvls (Avg 329.391 ms) qt4 debug: IM: Setting an input qt4 debug: IM: Setting an input ps warning: garbage at input, trying to resync... ps warning: garbage at input, trying to resync... ps warning: found sync code ps warning: found sync code ps debug: we found a length of: 8153391011 ps debug: we found a length of: 8153391011 ps debug: we found a length of: 8153945800 ps debug: we found a length of: 8153945800 main debug: selecting program id=0 main debug: selecting program id=0 main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates main debug: using decoder module "mpeg_audio" main debug: using decoder module "mpeg_audio" main debug: TIMER module_need() : 1.660 ms - Total 1.660 ms / 1 intvls (Avg 1.660 ms) main debug: TIMER module_need() : 1.660 ms - Total 1.660 ms / 1 intvls (Avg 1.660 ms) main debug: looking for decoder module: 31 candidates main debug: looking for decoder module: 31 candidates avcodec debug: libavcodec already initialized avcodec debug: libavcodec already initialized avcodec debug: trying to use direct rendering avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: allowing 1 thread(s) for decoding avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded frame decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec warning: threaded slice decoding is not compatible with ffmpeg-hw, disabled avcodec debug: ffmpeg codec (MPEG-1/2 Video) started avcodec debug: ffmpeg codec (MPEG-1/2 Video) started main debug: using decoder module "avcodec" main debug: using decoder module "avcodec" main debug: TIMER module_need() : 5.712 ms - Total 5.712 ms / 1 intvls (Avg 5.712 ms) main debug: TIMER module_need() : 5.712 ms - Total 5.712 ms / 1 intvls (Avg 5.712 ms) main debug: looking for packetizer module: 21 candidates main debug: looking for packetizer module: 21 candidates main debug: using packetizer module "packetizer_mpegvideo" main debug: using packetizer module "packetizer_mpegvideo" main debug: TIMER module_need() : 1.438 ms - Total 1.438 ms / 1 intvls (Avg 1.438 ms) main debug: TIMER module_need() : 1.438 ms - Total 1.438 ms / 1 intvls (Avg 1.438 ms) ps debug: contains a PSM ps debug: contains a PSM main debug: Buffering 0% main debug: Buffering 0% main debug: Buffering 0% main debug: Buffering 0% mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:224 mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:224 main debug: recycling audio output main debug: recycling audio output main debug: looking for audio output module: 2 candidates main debug: looking for audio output module: 2 candidates aout_directx debug: Opening DirectSound Audio Output aout_directx debug: Opening DirectSound Audio Output aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: Realtek AC97 Audio aout_directx debug: found device: Realtek AC97 Audio main debug: Buffering 1% main debug: Buffering 1% main debug: Buffering 1% main debug: Buffering 1% . . . main debug: Buffering 15% main debug: Buffering 15% aout_directx debug: device supports 2 channels (DEFAULT!) aout_directx debug: device supports 2 channels (DEFAULT!) aout_directx debug: device supports 1 channel aout_directx debug: device supports 1 channel aout_directx debug: Windows says your SpeakerConfig is 7.1 aout_directx debug: Windows says your SpeakerConfig is 7.1 aout_directx debug: creating DirectSoundThread aout_directx debug: creating DirectSoundThread aout_directx debug: DirectSoundThread ready aout_directx debug: DirectSoundThread ready main debug: using audio output module "aout_directx" main debug: using audio output module "aout_directx" main debug: TIMER module_need() : 24.817 ms - Total 24.817 ms / 1 intvls (Avg 24.817 ms) main debug: TIMER module_need() : 24.817 ms - Total 24.817 ms / 1 intvls (Avg 24.817 ms) main debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes 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: mixer 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes main debug: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates audio_format debug: f32l->s16l, bits per sample: 32->16 audio_format debug: f32l->s16l, bits per sample: 32->16 main debug: using audio filter module "audio_format" main debug: using audio filter module "audio_format" main debug: TIMER module_need() : 1.894 ms - Total 1.894 ms / 1 intvls (Avg 1.894 ms) main debug: TIMER module_need() : 1.894 ms - Total 1.894 ms / 1 intvls (Avg 1.894 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio mixer module: 2 candidates main debug: looking for audio mixer module: 2 candidates main debug: using audio mixer module "float32_mixer" main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 1.242 ms - Total 1.242 ms / 1 intvls (Avg 1.242 ms) main debug: TIMER module_need() : 1.242 ms - Total 1.242 ms / 1 intvls (Avg 1.242 ms) main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1262 bytes main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1262 bytes main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32 scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search 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 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: using audio filter module "scaletempo" main debug: TIMER module_need() : 3.917 ms - Total 3.917 ms / 1 intvls (Avg 3.917 ms) main debug: TIMER module_need() : 3.917 ms - Total 3.917 ms / 1 intvls (Avg 3.917 ms) main debug: filter(s) 'mpga'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'mpga'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates mpgatofixed32 debug: mpga->f32l, bits per sample: 32 mpgatofixed32 debug: mpga->f32l, bits per sample: 32 main debug: using audio filter module "mpgatofixed32" main debug: using audio filter module "mpgatofixed32" main debug: TIMER module_need() : 1.995 ms - Total 1.995 ms / 1 intvls (Avg 1.995 ms) main debug: TIMER module_need() : 1.995 ms - Total 1.995 ms / 1 intvls (Avg 1.995 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: looking for audio filter module: 1 candidate main debug: looking for audio filter module: 1 candidate compressor debug: compressor successfully initialized compressor debug: compressor successfully initialized main debug: using audio filter module "compressor" main debug: using audio filter module "compressor" main debug: TIMER module_need() : 3.566 ms - Total 3.566 ms / 1 intvls (Avg 3.566 ms) main debug: TIMER module_need() : 3.566 ms - Total 3.566 ms / 1 intvls (Avg 3.566 ms) main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo main debug: looking for audio filter module: 14 candidates main debug: looking for audio filter module: 14 candidates main debug: using audio filter module "samplerate" main debug: using audio filter module "samplerate" main debug: TIMER module_need() : 1.583 ms - Total 1.583 ms / 1 intvls (Avg 1.583 ms) main debug: TIMER module_need() : 1.583 ms - Total 1.583 ms / 1 intvls (Avg 1.583 ms) main debug: conversion pipeline completed main debug: conversion pipeline completed main debug: End of audio preroll main debug: End of audio preroll main debug: Buffering 20% main debug: Buffering 20% . . . main debug: Buffering 67% main debug: Buffering 67% packetizer_mpegvideo debug: size 720x480 fps=29.970 packetizer_mpegvideo debug: size 720x4ain debug: Buffering 68% main debug: Buffering 68% main debug: Buffering 68% . . . main debug: Buffering 94% main debug: Buffering 94% main debug: Stream buffering done (310 ms in 74 ms) main debug: Stream buffering done (310 ms in 74 ms) 80 fps=29.970 avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Trying DXVA2 avcodec debug: Trying DXVA2 avcodec debug: DLLs loaded avcodec debug: DLLs loaded avcodec debug: D3dCreateDevice succeed avcodec debug: D3dCreateDevice succeed avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec: obtained IDirect3DDeviceManager9 avcodec: obtained IDirect3DDeviceManager9 avcodec: DXVA2CreateVideoService Success! avcodec: DXVA2CreateVideoService Success! avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'MPEG-2 inverse discrete cosine transform' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 8 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 motion compensation' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec debug: - 'Windows Media Video 9 post processing' is supported by hardware avcodec error: DxFindVideoServiceConversion failed avcodec error: DxFindVideoServiceConversion failed avcodec warning: Failed to open DXVA2 avcodec warning: Failed to open DXVA2 avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 81 (Unknown) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) main debug: trying to reuse free vout main debug: trying to reuse free vout main debug: removing module "freetype" main debug: removing module "freetype" main debug: looking for text renderer module: 2 candidates main debug: looking for text renderer module: 2 candidates freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: Using Arial as font from file C:\WINDOWS\Fonts\ARIAL.TTF freetype debug: using fontsize: 2 freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: using text renderer module "freetype" main debug: TIMER module_need() : 3.558 ms - Total 3.558 ms / 1 intvls (Avg 3.558 ms) main debug: TIMER module_need() : 3.558 ms - Total 3.558 ms / 1 intvls (Avg 3.558 ms) main debug: reusing provided vout main debug: reusing provided vout avcodec debug: using direct rendering avcodec debug: using direct rendering main debug: End of video preroll main debug: End of video preroll main debug: Received first picture main debug: Received first picture main debug: Detected interlaced video main debug: Detected interlaced video main debug: deinterlace 0, mode blend, is_needed 1 main debug: deinterlace 0, mode blend, is_needed 1 main debug: Decoder buffering done in 152 ms main debug: Decoder buffering done in 152 ms main warning: audio output out of sync, adjusting dates (60230 us) main warning: audio output out of sync, adjusting dates (60230 us) main warning: not synchronized (60234 us), resampling main warning: not synchronized (60234 us), resampling main warning: audio output out of sync, adjusting dates (60071 us) main warning: audio output out of sync, adjusting dates (60071 us) main warning: not synchronized (60074 us), resampling main warning: not synchronized (60074 us), resampling main warning: buffer too late (119859), up-sampling main warning: buffer too late (119859), up-sampling main warning: resampling stopped after 16742531 usec (drift: 1023) main warning: resampling stopped after 16742531 usec (drift: 1023) main warning: audio output out of sync, adjusting dates (60076 us) main warning: audio output out of sync, adjusting dates (60076 us) main warning: not synchronized (60079 us), resampling main warning: not synchronized (60079 us), resampling main debug: EOF reached main debug: EOF reached main debug: waiting decoder fifos to empty . . . main debug: waiting decoder fifos to empty main debug: removing module "mpeg_audio" main debug: removing module "mpeg_audio" main debug: killing decoder fourcc `mpga', 0 PES in FIFO main debug: killing decoder fourcc `mpga', 0 PES in FIFO main debug: removing module "mpgatofixed32" main debug: removing module "mpgatofixed32" main debug: removing module "scaletempo" main debug: removing module "scaletempo" main debug: removing module "compressor" main debug: removing module "compressor" main debug: removing module "samplerate" main debug: removing module "samplerate" main debug: removing module "aout_directx" main debug: removing module "aout_directx" aout_directx debug: closing audio device aout_directx debug: closing audio device main debug: finished input main debug: finished input aout_directx debug: DirectSoundThread exiting aout_directx debug: DirectSoundThread exiting main debug: removing module "audio_format" main debug: removing module "audio_format" main debug: removing module "float32_mixer" main debug: removing module "float32_mixer" main debug: releasing audio output main debug: releasing audio output main debug: removing module "avcodec" main debug: removing module "avcodec" avcodec debug: ffmpeg codec (MPEG-1/2 Video) stopped avcodec debug: ffmpeg codec (MPEG-1/2 Video) stopped main debug: killing decoder fourcc `mpgv', 0 PES in FIFO main debug: killing decoder fourcc `mpgv', 0 PES in FIFO main debug: saving a free vout main debug: saving a free vout main debug: reusing provided vout main debug: reusing provided vout main debug: removing module "packetizer_mpegvideo" main debug: removing module "packetizer_mpegvideo" main debug: removing module "ps" main debug: removing module "ps" main debug: Program doesn't contain anymore ES main debug: Program doesn't contain anymore ES main debug: removing module "stream_filter_record" main debug: removing module "stream_filter_record" main debug: removing module "filesystem" main debug: removing module "filesystem" main debug: dead input main debug: dead input qt4 debug: IM: Deleting the input qt4 debug: IM: Deleting the input main debug: changing item without a request (current 1/3) main debug: changing item without a request (current 1/3) main debug: using item 2 main debug: using item 2 main debug: starting playback of the new playlist item main debug: starting playback of the new playlist item main debug: resyncing on QUIT main debug: resyncing on QUIT main debug: QUIT is at 2 main debug: QUIT is at 2 main debug: creating new input thread main debug: creating new input thread main debug: Creating an input for 'QUIT' main debug: Creating an input for 'QUIT' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\pcdave\LOCALS~1\Temp' main debug: `VLC://QUIT' gives access `VLC' demux `' path `QUIT' main debug: `VLC://QUIT' gives access `VLC' demux `' path `QUIT' main debug: creating demux: access='VLC' demux='' location='QUIT' file='\\QUIT' main debug: creating demux: access='VLC' demux='' location='QUIT' file='\\QUIT' main debug: looking for access_demux module: 1 candidate main debug: looking for access_demux module: 1 candidate main debug: TIMER input launching for 'ZebrasFootballvsWabash.mpg' : 31.059 ms - Total 31.059 ms / 1 intvls (Avg 31.059 ms) main debug: TIMER input launching for 'ZebrasFootballvsWabash.mpg' : 31.059 ms - Total 31.059 ms / 1 intvls (Avg 31.059 ms) qt4 debug: IM: Setting an input qt4 debug: IM: Setting an input idummy: command `quit' idummy: command `quit' main debug: exiting main debug: exiting main debug: using access_demux module "idummy" main debug: using access_demux module "idummy" main debug: TIMER module_need() : 26.234 ms - Total 26.234 ms / 1 intvls (Avg 26.234 ms) main debug: TIMER module_need() : 26.234 ms - Total 26.234 ms / 1 intvls (Avg 26.234 ms) main debug: looking for meta reader module: 2 candidates main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Documents and Settings\pcdave\Application Data\vlc\lua\meta\reader main debug: deactivating the playlist main debug: deactivating the playlist main debug: deactivating the playlist main debug: deactivating the playlist main debug: incoming request - stopping current input main debug: incoming request - stopping current input main debug: dying input main debug: dying input lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\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 lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 30.376 ms - Total 30.376 ms / 1 intvls (Avg 30.376 ms) main debug: TIMER module_need() : 30.376 ms - Total 30.376 ms / 1 intvls (Avg 30.376 ms) main debug: `VLC://QUIT' successfully opened main debug: `VLC://QUIT' successfully opened main debug: removing module "idummy" main debug: removing module "idummy" main debug: dying input main debug: dying input main debug: dead input main debug: dead input qt4 debug: IM: Deleting the input qt4 debug: IM: Deleting the input main debug: TIMER input launching for 'QUIT' : 62.211 ms - Total 62.211 ms / 1 intvls (Avg 62.211 ms) main debug: TIMER input launching for 'QUIT' : 62.211 ms - Total 62.211 ms / 1 intvls (Avg 62.211 ms) main debug: destroying useless vout main debug: destroying useless vout main debug: removing module "directx" main debug: removing module "directx" directx debug: DirectXEventThread terminating directx debug: DirectXEventThread terminating directx debug: DirectXCloseWindow directx debug: DirectXCloseWindow directx debug: WinProc WM_DESTROY directx debug: WinProc WM_DESTROY qt4 debug: releasing video... qt4 debug: releasing video... qt4 debug: Video is not needed anymore qt4 debug: Video is not needed anymore main debug: removing module "freetype" main debug: removing module "freetype" main debug: removing module "yuvp" main debug: removing module "yuvp" main debug: removing module "swscale" main debug: removing module "swscale" main debug: saving Media Library to file C:\Documents and Settings\pcdave\Application Data\vlc\ml.xspf main debug: saving Media Library to file C:\Documents and Settings\pcdave\Application Data\vlc\ml.xspf main debug: looking for playlist export module: 1 candidate main debug: looking for playlist export module: 1 candidate main debug: using playlist export module "export" main debug: using playlist export module "export" main debug: TIMER module_need() : 28.693 ms - Total 28.693 ms / 1 intvls (Avg 28.693 ms) main debug: TIMER module_need() : 28.693 ms - Total 28.693 ms / 1 intvls (Avg 28.693 ms) main debug: removing module "export" main debug: removing module "export" main debug: playlist correctly deactivated main debug: playlist correctly deactivated main debug: removing all services discovery tasks main debug: removing all services discovery tasks main debug: removing all interfaces main debug: removing all interfaces main debug: removing module "qt4" main debug: removing module "qt4" qt4 debug: requesting exit... qt4 debug: requesting exit... qt4 debug: QApp exec() finished qt4 debug: QApp exec() finished qt4 debug: waiting for UI thread... qt4 debug: waiting for UI thread... qt4 debug: Video is not needed anymore qt4 debug: Video is not needed anymore qt4 debug: Killing extension dialog provider qt4 debug: Killing extension dialog provider qt4 debug: ExtensionsDialogProvider is quitting... qt4 debug: ExtensionsDialogProvider is quitting... main debug: removing module "lua" main debug: removing module "lua" lua debug: Deactivating all loaded extensions l

[Jean-Baptiste Kempf]

Try
--aout=directx --volume=256

[RipTide]

Hello people,

I'm no expert in these things but I think I 'might have' found the actual source of audio stutter.
I got a fresh copy of Windows 7 ultimate 32-bit installed some weeks ago, and then installed VLC 2.0.1 too along with many other applications and drivers.
It worked fine for sometime but after that the audio started stuttering every 5 seconds or so, and that happened with any kind of media file.

This is similar to the error many people have reported here, and hence I read the whole thread discussions but wasn't able to find any effective fix.

While going through the list of applications installed on my system (while looking for any unwanted installation), I saw I had installed 'K-lite codec pack 880 mega', so I uninstalled it and the stutter disappeared on VLC!! It was TOTALLY hit-and-trial and I'll leave it to the technical experts to make some meaning of it.

It may or may not be the solution for everyone I couldn't know, but it indeed worked for me!

[Jean-Baptiste Kempf]

This is quite weird, to be honest.

[DanteElysium]

Hello !

I've just installed the 2.0.4 32bits and the problem is still here...
I've also tested the 2.0.2 64bits but it's the same problem...

I still have to manually change the mode of my CPU to "normal" to make it work. but in this mode, the fans are very noisy...

Bye

[chid]

It's amazing how irritating this bug is, it's difficult to describe how it affects the listening experience.

It seems to affect 32-bit 2.0.3 running on a 64bit OS.
I have switched to the 2.0.4 experimental branch Twoflower, compiled Oct 18th for 64bit

It plays gapless =)

http://download.videolan.org/pub/videol ... ast/win64/

Hope it works well for you too

Charley

[DanteElysium]

Hello,

I just Tried the 2.0.4 64bits but the problem is still here...

Bye

[Rémi Denis-Courmont]

Of course it's still there. Nobody came up with a solution that I could see.

[user957]

Look.

The whole purpose of VLC is that it is compatible with the most popular OSs. So regardless of your semantic discussions, we have encountered a fatal flaw in VLC. Now, most people (ie users of VLC) would not lift a finger to fix or help with these (so called leechers), so what do you reckon they will do? They'll download another player or revert to past versions of VLC. This of course negates, to an extent, the purpose of the hard work that the team puts into the development of the program.

My two cents are- just treat this as any other bug. The team are obviously extremely competent and does not need fruitless sematic arguing to fix the bug.

Anyway, good luck. I'll revert to 1.1 until further notice. VLC is STILL the best player at that version.

[Rémi Denis-Courmont]

The point is, the team does not need constant reminders of unfixed bugs... By the way, it is being treated like a normal bug. Like other bugs that are not understood, it is not being fixed. If that means a large slice of the user base stops upgrading or switches to the competition, so be it.

At this point, I guess the bug will remain for some time. Without conclusive analysis from someone able to reproduce it (none of the developers could so far that I'd know), there is little to no hope that a developer would (be able to) fix it. I have begged for a code base bissection of the regression for several months. Nobody cared to provide one. I spent hours adding coinfigurable clock sources, only one user bothered to try it out and report. There really is not much I, or the rest of the team, can do.

Maybe a developer will rework something in the audio decoding, filtering or output that will hide or remove the bug, but that would be accidental. That is not a possibility to be relied upon.

[user957]

All right, so tell me what information you need and I will do what I can to provide it. I'm not a pro in any way, but as a normal user I'll see what I can do. I can record a video of the problem if you want.

[serianox]

I am the creator of the thread, i've completely forgotten about this thread and now i see a lot of feedback on this bug and it makes me very happy. Thanks for your work devs. My motherboard comes with a software that underclocks/overclocks automaticaly the processor, disabling it completely solves the problem, so it must me a clock issue. however cores get hotter now. it would be nice to have this bug solved in a future vlc release. I can help you with whatever you need to test, just tell me, i'm a regular user though so take that in mind.
Thanks again for your work, and sorry if i typed something wrong i'm from Argentina

[serianox]

• Get a VLC nightly build from September 7th 2012 or later, or compile VLC 2.1.0 manually from git.
• Start VLC.
• Open the preferences.
• Select All then Advanced.
• Change the Clock source.
• Save and exit.
• Start VLC and play something.

Reiterate until you find a clock that does not break your audio output... if any

Did this with last build avariable VLC 2.1.0-git-20121106-0004 Rincewind
the problem is still there, no matter what clock source i choose i still get the stuttering, however it is less intense than the 2.0.x
is i have mentioned before, the problem solves if i remove the downclock of the processor
Thanks

pd: i did not try the System time (DANGEROUS!) clock source. should i?

[Rémi Denis-Courmont]

It won't harm your computer. It's just known to not work properly in ways unrelated to stuttering.

[_Rock_]

Hi everyone

im using vlc aswell in i got the same problem since 2.0 (now im using 2.0.4).
My hard-/software specs:
- Asus P6T Maiboard (with onboard Raltek HDA soundcard) and with EPU6-Engine
- NVidia Geforce 9700GTX with HDMI-Out (which I sometimes use as Audio-Out)
- Win7 x64

Like the others said, as long as I keep the power-saving of the EPU-Engine at Performance, there is no audio-lag, as soon as i put it to Power save, there are these annoying audio gaps.

By playing around with the clock settings I've found some way of setting which mostly kills these gaps. (Actually I didn't find the "Clock source"-Setting as mentioned above, but some other Clock-related settings)
My settings are set this way:

Code: Select all

Clock reference average Counter: 4 (Default: "40") Clock synchronisation: Enable (Default: "Default") Clock Jitter: 5000 (left at default)

using this settings, there are some gaps at the start of a audio-stream (for like the first 3-10secs), but then it keeps working!
Looking at the messages (first 5secs:)

Code: Select all

main warning: buffer way too late (265353), dropping buffer main warning: buffer way too late (218913), dropping buffer main warning: buffer way too late (258983), dropping buffer main warning: buffer way too late (238613), dropping buffer main warning: not synchronized (-40807 us), resampling main warning: audio output out of sync, adjusting dates (-40398 us) main warning: not synchronized (-40397 us), resampling

After these 5 Secs there are always these messages appearing (about every second):

Code: Select all

main warning: audio output out of sync, adjusting dates (-43580 us) main warning: not synchronized (-43579 us), resampling

But as mentioned before, no gaps anymore!

BUT I just found out, this only works for audio-Files. Using Video-Files with theses Settings just fails epic. The Videopicture mostly freezes and the audio is just beeing played like in 2x-mode. Also VLC is veeery unresponsive. Looking at messages theres a HUGE spam of following:

Code: Select all

main warning: buffer way too late (420093), dropping buffer main warning: buffer way too late (398759), dropping buffer main warning: buffer way too late (377426), dropping buffer

So as I interpret, this way of clock-setting only works for Streams with low bit-rates. At high bit-Rates/video, enabling clock-sync seems to be a bad idea...
I hope my information can somehow help to find that bug and somehow solve it!

If I can help with any more Information, just ask, Ill try to answer as good as possible.

[Rémi Denis-Courmont]

This just confirms the general suspicion that the problem is caused by a bogus real-time clock in ASUS devices rather a VLC bug. In fact, the bug tracker shows VLC 1.1.x already had the same problem albeit apparently more rarely than VLC 2.0.x.

Unfortunately, that does not really help solving the problem...

[Jean-Baptiste Kempf]

im using vlc aswell in i got the same problem since 2.0 (now im using 2.0.4).
My hard-/software specs:
- Asus P6T Maiboard (with onboard Raltek HDA soundcard) and with EPU6-Engine
- NVidia Geforce 9700GTX with HDMI-Out (which I sometimes use as Audio-Out)
- Win7 x64

Like the others said, as long as I keep the power-saving of the EPU-Engine at Performance, there is no audio-lag, as soon as i put it to Power save, there are these annoying audio gaps.

THanks a lot for the report.

What are you playing?