DVD - Choppy playback, audio drift problem

For questions and discussion that is NOT (I repeat NOT) specific to a certain Operating System.
jvlan
New Cone
New Cone
Posts: 7
Joined: 07 Aug 2008 01:06

DVD - Choppy playback, audio drift problem

Postby jvlan » 24 Feb 2009 21:50

Hi, I've seen this fault posted before here and there, but never seen a resolution yet.

I've been using VLC in Windows and Linux since v8.6c, and this problem has occurred with all versions I've tried.
Quite a few of my DVDs have this audio drift problem which makes the playback choppy at odd moments. Different DVDs have different places where the problem happens, but for any particular DVD the chop will occur in the same spots every time.
All the affected DVDs work fine with SMPlayer, KMplayer, and Totem.

Setup:

Windows XP Home SP 2 (VLC 9.8a)
Ubuntu 7.10 64 bit (VLC 8.6c)
Mepis 7.0 64 bit (VLC8.6a)
MSI K9A2 CF
AMD Athlon64 X2 5200+
4G ram
MSI Radeon HD 3870
Creative SB X-Fi Elite (Windows)
Realtek ALC888 (Linux)
PIONEER DVD-RW DVR-115D


Debug Messages (cut to fit):

Code: Select all

gamma:-0.628100 main debug: using audio filter module "equalizer" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: filter(s) 'a52 '->'fl32' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "a52tofloat32" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: found a filter for the whole conversion main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=2 dvdnav debug: - pgN=2 dvdnav debug: - cell_length=22740000 dvdnav debug: - pg_length=22740000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=40395000 dvdnav debug: - pg_start=40395000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-320144), clearing out main warning: mixer start isn't output start (-412581) main debug: audio output is starving (383069), playing silence main warning: dts != current_pts (-320153) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=3 dvdnav debug: - pgN=3 dvdnav debug: - cell_length=16923000 dvdnav debug: - pg_length=16923000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=63135000 dvdnav debug: - pg_start=63135000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-330311), clearing out main warning: mixer start isn't output start (-433510) main debug: audio output is starving (413172), playing silence main warning: dts != current_pts (-330316) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=4 dvdnav debug: - pgN=4 dvdnav debug: - cell_length=30897000 dvdnav debug: - pg_length=30897000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=80058000 dvdnav debug: - pg_start=80058000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-329977), clearing out main warning: mixer start isn't output start (-407781) main debug: audio output is starving (370024), playing silence main warning: dts != current_pts (-329991) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=5 dvdnav debug: - pgN=5 dvdnav debug: - cell_length=26355000 dvdnav debug: - pg_length=26355000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=110955000 dvdnav debug: - pg_start=110955000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-330233), clearing out main warning: mixer start isn't output start (-435724) main debug: audio output is starving (403173), playing silence main warning: dts != current_pts (-330246) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=6 dvdnav debug: - pgN=6 dvdnav debug: - cell_length=25173000 dvdnav debug: - pg_length=25173000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=137310000 dvdnav debug: - pg_start=137310000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-321966), clearing out main warning: mixer start isn't output start (-393944) main debug: audio output is starving (349014), playing silence main warning: dts != current_pts (-321969) main warning: output date isn't PTS date, requesting resampling (-49473) main warning: buffer is 49473 in advance, triggering downsampling main warning: resampling stopped after 12387000 usec (drift: -818) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=7 dvdnav debug: - pgN=7 dvdnav debug: - cell_length=36225000 dvdnav debug: - pg_length=36225000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=162483000 dvdnav debug: - pg_start=162483000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-329015), clearing out main warning: mixer start isn't output start (-379552) main debug: audio output is starving (333473), playing silence main warning: dts != current_pts (-330005) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=8 dvdnav debug: - pgN=8 dvdnav debug: - cell_length=43197000 dvdnav debug: - pg_length=43197000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=198708000 dvdnav debug: - pg_start=198708000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-298677), clearing out main warning: mixer start isn't output start (-378635) main debug: audio output is starving (367879), playing silence main warning: dts != current_pts (-298685) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=9 dvdnav debug: - pgN=9 dvdnav debug: - cell_length=13923000 dvdnav debug: - pg_length=13923000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=241905000 dvdnav debug: - pg_start=241905000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-318744), clearing out main warning: mixer start isn't output start (-422489) main debug: audio output is starving (391081), playing silence main warning: dts != current_pts (-318753) main warning: output date isn't PTS date, requesting resampling (-41852) main warning: buffer is 41852 in advance, triggering downsampling main warning: resampling stopped after 11359000 usec (drift: -439) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=10 dvdnav debug: - pgN=10 dvdnav debug: - cell_length=48870000 dvdnav debug: - pg_length=48870000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=255828000 dvdnav debug: - pg_start=255828000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-361605), clearing out main warning: mixer start isn't output start (-442440) main debug: audio output is starving (386146), playing silence main warning: dts != current_pts (-362217) main warning: output date isn't PTS date, requesting resampling (-40021) main warning: buffer is 40021 in advance, triggering downsampling main warning: resampling stopped after 11168000 usec (drift: -854) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=11 dvdnav debug: - pgN=11 dvdnav debug: - cell_length=34725000 dvdnav debug: - pg_length=34725000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=304698000 dvdnav debug: - pg_start=304698000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-329246), clearing out main warning: mixer start isn't output start (-392010) main debug: audio output is starving (344496), playing silence main warning: dts != current_pts (-330272) main warning: output date isn't PTS date, requesting resampling (-44225) main warning: buffer is 44225 in advance, triggering downsampling main warning: resampling stopped after 11679000 usec (drift: -504) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=12 dvdnav debug: - pgN=12 dvdnav debug: - cell_length=17757000 dvdnav debug: - pg_length=17757000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=339423000 dvdnav debug: - pg_start=339423000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-329530), clearing out main warning: mixer start isn't output start (-419817) main debug: audio output is starving (369404), playing silence main warning: dts != current_pts (-330208) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=13 dvdnav debug: - pgN=13 dvdnav debug: - cell_length=28767000 dvdnav debug: - pg_length=46020000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=357180000 dvdnav debug: - pg_start=357180000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=2 t=1 main warning: audio drift is too big (-362222), clearing out main warning: mixer start isn't output start (-442623) main debug: audio output is starving (406897), playing silence main warning: dts != current_pts (-362231) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=14 dvdnav debug: - pgN=13 dvdnav debug: - cell_length=17253000 dvdnav debug: - pg_length=46020000 dvdnav debug: - pgc_length=576282000 dvdnav debug: - cell_start=385947000 dvdnav debug: - pg_start=357180000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=128 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=2 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE
In this particular DVD, the fault happens at each chapter change. This is the only one I have that behaves this way.


Code: Select all

main debug: looking for decoder module: 25 candidates main debug: using decoder module "a52" main debug: thread 1140881744 (decoder) created at priority 0 (input/decoder.c:159) a52: A/52 channels:6 samplerate:48000 bitrate:448000 main debug: looking for audio output module: 6 candidates alsa debug: opening ALSA device `default' main debug: thread 1174452560 (aout) created at priority 0 (alsa.c:662) main debug: using audio output module "alsa" main debug: output 's16l' 48000 Hz Stereo frame=1 samples/4 bytes main debug: mixer 'fl32' 48000 Hz Stereo frame=1 samples/8 bytes main debug: filter(s) 'fl32'->'s16l' 48000 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "float32tos16" main debug: found a filter for the whole conversion main debug: looking for audio mixer module: 3 candidates main debug: using audio mixer module "float32_mixer" main debug: input 'a52 ' 48000 Hz 3F2R/LFE frame=1536 samples/1792 bytes main debug: looking for audio filter module: 1 candidate equalizer warning: bad input or output format equalizer warning: input and output formats are not similar main warning: no audio filter module matching "equalizer" could be loaded main debug: looking for audio filter module: 1 candidate equalizer debug: equalizer loaded for 48000 Hz with 10 bands 1 pass equalizer debug: 60 Hz -> factor:0.000000 alpha:0.002769 beta:0.994462 gamma:1.994400 equalizer debug: 170 Hz -> factor:0.000000 alpha:0.007806 beta:0.984388 gamma:1.983897 equalizer debug: 310 Hz -> factor:0.000000 alpha:0.014143 beta:0.971714 gamma:1.970091 equalizer debug: 600 Hz -> factor:0.000000 alpha:0.027011 beta:0.945978 gamma:1.939979 equalizer debug: 1000 Hz -> factor:0.000000 alpha:0.044203 beta:0.911595 gamma:1.895241 equalizer debug: 3000 Hz -> factor:0.000000 alpha:0.121223 beta:0.757553 gamma:1.623767 equalizer debug: 6000 Hz -> factor:0.000000 alpha:0.212888 beta:0.574224 gamma:1.113145 equalizer debug: 12000 Hz -> factor:0.000000 alpha:0.331347 beta:0.337307 gamma:0.000000 equalizer debug: 14000 Hz -> factor:0.000000 alpha:0.355263 beta:0.289473 gamma:-0.333740 equalizer debug: 16000 Hz -> factor:0.000000 alpha:0.371900 beta:0.256201 gamma:-0.628100 main debug: using audio filter module "equalizer" main debug: filter(s) 'a52 '->'fl32' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "a52tofloat32" main debug: found a filter for the whole conversion main debug: looking for audio filter module: 1 candidate normvol warning: input and output formats are not similar main warning: no audio filter module matching "normvol" could be loaded main debug: looking for audio filter module: 1 candidate main debug: using audio filter module "normvol" main debug: filter(s) 'fl32'->'fl32' 48000 Hz->48000 Hz 3F2R/LFE->Stereo main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "simple_channel_mixer" main debug: found a filter for the whole conversion main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: found a filter for the whole conversion main debug: decoded 106/108 pictures dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=2 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=255000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=4647000 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 alsa debug: recovered from buffer underrun dvdnav debug: buttonUpdate not done b=1 t=1 main warning: dts != current_pts (-330571) main warning: computed PTS is out of range (3794), clearing out main warning: PTS is out of range (3802), dropping buffer main warning: output PTS is out of range (14474), clearing out main debug: audio output is starving (337796), playing silence main warning: backward_pts != current_pts (16682) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=3 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=2838000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=4902000 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=4 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=9645000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=7740000 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=5 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=6180000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=17385000 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=6 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=13707000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=23565000 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=7 dvdnav debug: - pgN=2 dvdnav debug: - cell_length=2973000 dvdnav debug: - pg_length=18615000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=37272000 dvdnav debug: - pg_start=37272000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=8 dvdnav debug: - pgN=2 dvdnav debug: - cell_length=3180000 dvdnav debug: - pg_length=18615000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=40245000 dvdnav debug: - pg_start=37272000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=9 dvdnav debug: - pgN=2 dvdnav debug: - cell_length=837000 dvdnav debug: - pg_length=18615000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=43425000 dvdnav debug: - pg_start=37272000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=10 dvdnav debug: - pgN=2 dvdnav debug: - cell_length=11625000 dvdnav debug: - pg_length=18615000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=44262000 dvdnav debug: - pg_start=37272000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 main warning: dts != current_pts (-331733) main warning: computed PTS is out of range (2177), clearing out main warning: PTS is out of range (2189), dropping buffer main warning: output PTS is out of range (2197), clearing out alsa debug: recovered from buffer underrun main debug: audio output is starving (328241), playing silence main warning: backward_pts != current_pts (16686) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=11 dvdnav debug: - pgN=3 dvdnav debug: - cell_length=26475000 dvdnav debug: - pg_length=26475000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=55887000 dvdnav debug: - pg_start=55887000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=12 dvdnav debug: - pgN=4 dvdnav debug: - cell_length=10680000 dvdnav debug: - pg_length=10680000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=82362000 dvdnav debug: - pg_start=82362000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 alsa debug: recovered from buffer underrun main warning: dts != current_pts (-319070) main warning: computed PTS is out of range (64133), clearing out main warning: PTS is out of range (64145), dropping buffer main warning: output PTS is out of range (74821), clearing out main debug: audio output is starving (263839), playing silence main warning: backward_pts != current_pts (16686) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=13 dvdnav debug: - pgN=5 dvdnav debug: - cell_length=8355000 dvdnav debug: - pg_length=8355000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=93042000 dvdnav debug: - pg_start=93042000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=14 dvdnav debug: - pgN=6 dvdnav debug: - cell_length=19080000 dvdnav debug: - pg_length=28560000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=101397000 dvdnav debug: - pg_start=101397000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=15 dvdnav debug: - pgN=6 dvdnav debug: - cell_length=3873000 dvdnav debug: - pg_length=28560000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=120477000 dvdnav debug: - pg_start=101397000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 main warning: dts != current_pts (-331573) main warning: computed PTS is out of range (226), clearing out main warning: PTS is out of range (233), dropping buffer main warning: output PTS is out of range (10906), clearing out alsa debug: recovered from buffer underrun main debug: audio output is starving (331865), playing silence main warning: backward_pts != current_pts (16686) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=16 dvdnav debug: - pgN=6 dvdnav debug: - cell_length=5607000 dvdnav debug: - pg_length=28560000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=124350000 dvdnav debug: - pg_start=101397000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=17 dvdnav debug: - pgN=7 dvdnav debug: - cell_length=2253000 dvdnav debug: - pg_length=11505000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=129957000 dvdnav debug: - pg_start=129957000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=18 dvdnav debug: - pgN=7 dvdnav debug: - cell_length=2295000 dvdnav debug: - pg_length=11505000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=132210000 dvdnav debug: - pg_start=129957000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=19 dvdnav debug: - pgN=7 dvdnav debug: - cell_length=6957000 dvdnav debug: - pg_length=11505000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=134505000 dvdnav debug: - pg_start=129957000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=20 dvdnav debug: - pgN=8 dvdnav debug: - cell_length=1890000 dvdnav debug: - pg_length=5340000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=141462000 dvdnav debug: - pg_start=141462000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=21 dvdnav debug: - pgN=8 dvdnav debug: - cell_length=3450000 dvdnav debug: - pg_length=5340000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=143352000 dvdnav debug: - pg_start=141462000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=22 dvdnav debug: - pgN=9 dvdnav debug: - cell_length=21768000 dvdnav debug: - pg_length=21768000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=146802000 dvdnav debug: - pg_start=146802000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 main warning: dts != current_pts (-339069) main warning: computed PTS is out of range (5640), clearing out main warning: PTS is out of range (5656), dropping buffer main warning: output PTS is out of range (16328), clearing out alsa debug: recovered from buffer underrun main debug: audio output is starving (336638), playing silence main warning: backward_pts != current_pts (16686) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=23 dvdnav debug: - pgN=10 dvdnav debug: - cell_length=13692000 dvdnav debug: - pg_length=13692000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=168570000 dvdnav debug: - pg_start=168570000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=24 dvdnav debug: - pgN=11 dvdnav debug: - cell_length=21435000 dvdnav debug: - pg_length=29253000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=182262000 dvdnav debug: - pg_start=182262000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=25 dvdnav debug: - pgN=11 dvdnav debug: - cell_length=7818000 dvdnav debug: - pg_length=29253000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=203697000 dvdnav debug: - pg_start=182262000 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 main warning: dts != current_pts (-308233) main warning: PTS is out of range (-25550), dropping buffer main warning: audio drift is too big (-323544), clearing out main warning: mixer start isn't output start (-124241) main debug: audio output is starving (296044), playing silence alsa debug: recovered from buffer underrun main warning: backward_pts != current_pts (16686)

In this DVD, the fault does not follow chapter changes. What's not apparent from reading the file is that the fault sometimes occurs a couple of minutes after the audio stream changes.


Some questions I have read before regarding this fault:

"Did you try deleting/resetting preferences?"
I have tried it, it does nothing.

"Did you try increasing the cache?"
Yes. Increasing the cache can help smooth out the chop a little bit, but it doesn't stop the problem from happening. And increasing the cache too much just makes the film take too long to load.

"Is this an MGM DVD?"
The majority of them are MGM, but I have two that aren't. One of those others is the first example, in fact.

"Is this a new DVD? Maybe it's a new copy protection scheme that we don't know about."
The affected DVDs range all the way from 2007 to 1998. I'm pretty sure that's not going to be the problem. :wink:


I hope you can get a handle on this problem. If there are any questions I've missed, ask away! I'll do my best to answer them.
Last edited by jvlan on 09 Mar 2009 15:51, edited 1 time in total.

Jean-Baptiste Kempf
Site Administrator
Site Administrator
Posts: 37523
Joined: 22 Jul 2005 15:29
VLC version: 4.0.0-git
Operating System: Linux, Windows, Mac
Location: Cone, France
Contact:

Re: DVD - Choppy playback, audio drift problem

Postby Jean-Baptiste Kempf » 25 Feb 2009 01:20

try to force ffmpeg codec with --codec ffmpeg
Jean-Baptiste Kempf
http://www.jbkempf.com/ - http://www.jbkempf.com/blog/category/Videolan
VLC media player developer, VideoLAN President and Sites administrator
If you want an answer to your question, just be specific and precise. Don't use Private Messages.

jvlan
New Cone
New Cone
Posts: 7
Joined: 07 Aug 2008 01:06

Re: DVD - Choppy playback, audio drift problem

Postby jvlan » 25 Feb 2009 16:01

Umm, OK I hope this is what you're after, if you mean something else let me know, I'll try it....

On the 'Open Disc' page:
"dvd://E:\" --codec ffmpeg


Then I get this in messages:

Code: Select all

qt4 debug: New item: dvd://E:\ main debug: adding item `dvd://E:\' ( dvd://E:\ ) main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: starting new item main debug: processing request item dvd://E:\ node null skip 0 main debug: resyncing on dvd://E:\ main debug: dvd://E:\ is at 0 qt4 debug: New item: --codec main debug: creating new input thread main debug: Creating an input for 'dvd://E:\' main debug: adding item `--codec' ( --codec ) main debug: waiting for thread initialization main debug: thread started main debug: thread 5416 (input) created at priority 1 (input/input.c:370) main debug: `dvd://E:\' gives access `dvd' demux `' path `E:\' main debug: creating demux: access='dvd' demux='' path='E:\' main debug: looking for access_demux module: 2 candidates qt4 debug: New item: ffmpeg main debug: adding item `ffmpeg' ( ffmpeg ) main debug: Creating an input for '--codec' main debug: no fetch required for --codec (art currently (null)) qt4 debug: Updating the stream status: 3 main debug: Creating an input for 'ffmpeg' main debug: no fetch required for ffmpeg (art currently (null)) dvdnav debug: trying to go to dvd menu main debug: thread 5484 (dvdnav event thread handler) created at priority 0 (dvdnav.c:351) main debug: using access_demux module "dvdnav" main debug: TIMER module_Need() : 2383.000 ms - Total 2383.000 ms / 1 intvls (Avg 2383.000 ms) main debug: thread started main debug: `dvd://E:\' successfully opened dvdnav debug: DVDNAV_HOP_CHANNEL main debug: control type=1 dvdnav debug: DVDNAV_VTS_CHANGE dvdnav debug: - vtsN=1 dvdnav debug: - domain=8 dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=1 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=4047000 dvdnav debug: - pg_length=4047000 dvdnav debug: - pgc_length=4047000 dvdnav debug: - cell_start=0 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=0 dvdnav debug: - physical_letterbox=0 dvdnav debug: - physical_pan_scan=1 dvdnav debug: buttonUpdate not done b=1 t=0 main debug: selecting program id=0 main debug: rebuilding array of current - root Playlist main debug: rebuild done - 3 items, index 0 main debug: looking for decoder module: 37 candidates qt4 debug: New Event: type 1103 qt4 debug: Updating the stream status: 3 qt4 debug: New Event: type 1108 kate debug: kate: OpenDecoder main debug: using decoder module "spudec" main debug: TIMER module_Need() : 23.000 ms - Total 23.000 ms / 1 intvls (Avg 23.000 ms) main debug: thread 5504 (decoder) created at priority 0 (input/decoder.c:217) dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 main debug: thread started dvdnav debug: buttonUpdate not done b=1 t=0 main debug: looking for decoder module: 37 candidates main debug: using decoder module "libmpeg2" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread 5512 (decoder) created at priority 0 (input/decoder.c:217) dvdnav debug: buttonUpdate not done b=1 t=0 main debug: thread started libmpeg2 debug: 720x480 (display 540,480), aspect 768000, sar 0:0, 29.971 fps main debug: no usable vout present, spawning one main debug: window size: 853x480 main debug: looking for video output module: 6 candidates vout_directx debug: creating DirectXEventThread main debug: waiting for thread initialization main debug: thread started vout_directx debug: DirectXCreateWindow main debug: looking for vout window module: 2 candidates qt4 debug: waiting for interface... qt4 debug: requesting window... qt4 debug: Video was requested -1, -1 qt4 debug: Video is resizing to: 853 480 main debug: using vout window module "qt4" qt4 debug: Updating the geometry main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) vout_directx debug: created video sub-window main debug: thread 5592 (Vout Events Thread) created at priority 0 (directx.c:281) vout_directx debug: Vout EventThread running vout_directx debug: DirectXInitDDraw vout_directx debug: directx-device: vout_directx debug: DirectXEnumCallback: Primary Display Driver, display vout_directx debug: DirectXEnumCallback: ATI Radeon HD 3800 Series, \\.\DISPLAY1 vout_directx debug: selecting ATI Radeon HD 3800 Series, \\.\DISPLAY1 vout_directx debug: screen dimensions (0x0,1680x1050) vout_directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 vout_directx debug: End DirectXInitDDraw vout_directx debug: DirectXCreateDisplay vout_directx debug: DirectXCreateClipper vout_directx debug: disabling screen saver main debug: using video output module "vout_directx" main debug: TIMER module_Need() : 12.000 ms - Total 12.000 ms / 1 intvls (Avg 12.000 ms) main debug: waiting for thread initialization main debug: thread started vout_directx debug: NewPictureVec overlay:yes chroma:YV12 vout_directx debug: YUV overlay created successfully vout_directx debug: End NewPictureVec (succeeded) main debug: got 1 direct buffer(s) main debug: picture in 720x480 (0,0,720x480), chroma I420, ar 16:9, sar 32:27 main debug: picture user 720x480 (0,0,720x480), chroma I420, ar 16:9, sar 32:27 main debug: picture out 720x480 (0,0,720x480), chroma I420, ar 16:9, sar 32:27 main debug: direct render, mapping render pictures 0-6 to system pictures 1-7 main debug: thread 5656 (video output) created at priority 1 (video_output/video_output.c:502) main warning: dts != current_pts (-830611) qt4 debug: New Event: type 1109 main warning: backward_pts != current_pts (-33368) main debug: looking for decoder module: 37 candidates main debug: using decoder module "a52" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread 5684 (decoder) created at priority 2 (input/decoder.c:217) main debug: thread started a52 info: A/52 channels:2 samplerate:48000 bitrate:192000 main debug: no aout present, spawning one main debug: looking for audio output module: 3 candidates aout_directx debug: OpenAudio aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: SB X-Fi Audio [E800] aout_directx debug: found device: Modem #11 Line Playback (emulated) aout_directx debug: found device: Modem #11 Handset Playback (emulated) aout_directx debug: device supports 2 channels (DEFAULT!) aout_directx debug: device supports 2 channels aout_directx debug: device supports 1 channel aout_directx debug: Windows says your SpeakerConfig is 5.1 aout_directx debug: device supports A/52 over S/PDIF aout_directx debug: creating DirectSoundThread main debug: thread started main debug: thread 6156 (DirectSound Notification Thread) created at priority 15 (directx.c:392) aout_directx debug: DirectSoundThread ready main debug: using audio output module "aout_directx" main debug: TIMER module_Need() : 117.000 ms - Total 117.000 ms / 1 intvls (Avg 117.000 ms) main debug: output 'fl32' 48000 Hz Dolby frame=1 samples/8 bytes main debug: mixer 'fl32' 48000 Hz Dolby frame=1 samples/8 bytes main debug: no need for any filter main debug: looking for audio mixer module: 3 candidates main debug: using audio mixer module "float32_mixer" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: input 'a52 ' 48000 Hz Dolby frame=1536 samples/768 bytes main debug: looking for audio filter module: 1 candidate equalizer warning: bad input or output format main warning: no audio filter module matching "equalizer" could be loaded main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for audio filter module: 1 candidate equalizer debug: equalizer loaded for 48000 Hz with 10 bands 1 pass equalizer debug: 60 Hz -> factor:0.000000 alpha:0.002769 beta:0.994462 gamma:1.994400 equalizer debug: 170 Hz -> factor:0.000000 alpha:0.007806 beta:0.984388 gamma:1.983897 equalizer debug: 310 Hz -> factor:0.000000 alpha:0.014143 beta:0.971714 gamma:1.970091 equalizer debug: 600 Hz -> factor:0.000000 alpha:0.027011 beta:0.945978 gamma:1.939979 equalizer debug: 1000 Hz -> factor:0.000000 alpha:0.044203 beta:0.911595 gamma:1.895241 equalizer debug: 3000 Hz -> factor:0.000000 alpha:0.121223 beta:0.757553 gamma:1.623767 equalizer debug: 6000 Hz -> factor:0.000000 alpha:0.212888 beta:0.574224 gamma:1.113145 equalizer debug: 12000 Hz -> factor:0.000000 alpha:0.331347 beta:0.337307 gamma:0.000000 equalizer debug: 14000 Hz -> factor:0.000000 alpha:0.355263 beta:0.289473 gamma:-0.333740 equalizer debug: 16000 Hz -> factor:0.000000 alpha:0.371900 beta:0.256201 gamma:-0.628100 main debug: using audio filter module "equalizer" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: filter(s) 'a52 '->'fl32' 48000 Hz->48000 Hz Dolby->Dolby main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "a52tofloat32" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: found a filter for the whole conversion main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz Dolby->Dolby main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: idx1=-1(??) idx2=-1(??) main debug: decoded 106/108 pictures main debug: looking for text renderer module: 2 candidates main debug: thread 6252 (fontlist builder) created at priority 0 (freetype.c:477) freetype debug: using fontsize: 30 main debug: using text renderer module "freetype" main debug: thread started main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: looking for video blending module: 1 candidate freetype debug: Building font database... freetype debug: Finished building font database. freetype debug: Took 0 seconds main debug: thread ended blend debug: chroma: YUVP -> I420 main debug: using video blending module "blend" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) dvdnav debug: DVDNAV_HIGHLIGHT dvdnav debug: - display=1 dvdnav debug: - buttonN=3 main debug: crop: 348,312,102,133, palette forced: 1 dvdnav debug: buttonUpdate 3 dvdnav debug: DVDNAV_HIGHLIGHT dvdnav debug: - display=1 dvdnav debug: - buttonN=4 main debug: crop: 462,309,120,131, palette forced: 1 dvdnav debug: buttonUpdate 4 dvdnav debug: DVDNAV_HIGHLIGHT dvdnav debug: - display=1 dvdnav debug: - buttonN=3 main debug: crop: 348,312,102,133, palette forced: 1 dvdnav debug: buttonUpdate 3 dvdnav debug: DVDNAV_HIGHLIGHT dvdnav debug: - display=1 dvdnav debug: - buttonN=2 main debug: crop: 222,325,112,115, palette forced: 1 dvdnav debug: buttonUpdate 2 dvdnav debug: DVDNAV_HIGHLIGHT dvdnav debug: - display=1 dvdnav debug: - buttonN=1 main debug: crop: 146,317,60,124, palette forced: 1 dvdnav debug: buttonUpdate 1 main debug: crop: 146,317,60,124, palette forced: 1 dvdnav debug: buttonUpdate 1 dvdnav debug: DVDNAV_HOP_CHANNEL dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=1 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=210000 dvdnav debug: - pg_length=210000 dvdnav debug: - pgc_length=210000 dvdnav debug: - cell_start=0 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=0 dvdnav debug: - physical_letterbox=0 dvdnav debug: - physical_pan_scan=1 main debug: crop: 146,317,60,124, palette forced: 1 dvdnav debug: buttonUpdate 1 main debug: removing module "spudec" main debug: thread ended main debug: thread times: real 0m9.328125s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 5504 joined (input/decoder.c:248) main debug: killing decoder fourcc `spu ', 0 PES in FIFO main debug: looking for decoder module: 37 candidates kate debug: kate: OpenDecoder main debug: using decoder module "spudec" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread 5492 (decoder) created at priority 0 (input/decoder.c:217) main debug: thread started dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=0 a52 debug: emulated sync word (no sync on following frame) main warning: audio drift is too big (-435500), clearing out main warning: mixer start isn't output start (-185664) main warning: dts != current_pts (-235001) main debug: audio output is starving (400850), playing silence dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_VTS_CHANGE dvdnav debug: - vtsN=1 dvdnav debug: - domain=2 main debug: removing module "libmpeg2" main debug: thread ended main debug: thread times: real 0m13.000000s, kernel 0m0.000000s, user 0m0.031250s main debug: thread 5512 joined (input/decoder.c:248) main debug: killing decoder fourcc `mpgv', 0 PES in FIFO vout_directx debug: DirectXCloseSurface vout_directx debug: DirectXCloseDisplay vout_directx debug: DirectXCloseDisplay clipper vout_directx debug: DirectXCloseDisplay display vout_directx debug: DirectXCloseDDraw main debug: thread ended main debug: thread times: real 0m13.015625s, kernel 0m0.031250s, user 0m0.000000s main debug: thread 5656 joined (video_output/video_output.c:536) vout_directx debug: DirectXEventThread terminating vout_directx debug: DirectXCloseWindow vout_directx debug: WinProc WM_DESTROY main debug: removing module "qt4" qt4 debug: Video is not needed anymore main debug: thread ended main debug: thread times: real 0m13.031250s, kernel 0m0.015625s, user 0m0.000000s main debug: thread 5592 joined (directx.c:513) qt4 debug: Updating the geometry main debug: removing module "vout_directx" main debug: removing module "spudec" main debug: thread ended main debug: removing module "blend" main debug: thread times: real 0m0.000000s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 6252 joined (freetype.c:511) main debug: removing module "freetype" main debug: thread times: real 0m3.718750s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 5492 joined (input/decoder.c:248) main debug: killing decoder fourcc `spu ', 0 PES in FIFO main debug: removing module "a52" main debug: thread ended main debug: thread times: real 0m12.890625s, kernel 0m0.046875s, user 0m0.000000s main debug: thread 5684 joined (input/decoder.c:248) main debug: killing decoder fourcc `a52 ', 0 PES in FIFO main debug: removing module "a52tofloat32" main debug: removing module "equalizer" main debug: removing module "bandlimited_resampler" aout_directx debug: closing audio device qt4 debug: New Event: type 1109 aout_directx debug: DirectSoundThread exiting main debug: thread ended main debug: thread times: real 0m12.796875s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 6156 joined (directx.c:664) main debug: removing module "aout_directx" main debug: removing module "float32_mixer" main debug: Program doesn't contain anymore ES dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=1 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=4647000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=0 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 main debug: looking for decoder module: 37 candidates main debug: using decoder module "libmpeg2" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread 5696 (decoder) created at priority 0 (input/decoder.c:217) dvdnav debug: buttonUpdate not done b=1 t=1 main debug: thread started libmpeg2 debug: 720x480 (display 720,480), aspect 768000, sar 0:0, 29.971 fps main debug: no usable vout present, spawning one main debug: window size: 853x480 main debug: looking for video output module: 6 candidates vout_directx debug: creating DirectXEventThread main debug: waiting for thread initialization main debug: thread started vout_directx debug: DirectXCreateWindow main debug: looking for vout window module: 2 candidates qt4 debug: waiting for interface... qt4 debug: requesting window... qt4 debug: Video was requested -1, -1 qt4 debug: Video is resizing to: 853 480 main debug: using vout window module "qt4" main debug: TIMER module_Need() : 19.000 ms - Total 19.000 ms / 1 intvls (Avg 19.000 ms) vout_directx debug: created video sub-window main debug: thread 6064 (Vout Events Thread) created at priority 0 (directx.c:281) vout_directx debug: Vout EventThread running vout_directx debug: DirectXInitDDraw vout_directx debug: directx-device: vout_directx debug: DirectXEnumCallback: Primary Display Driver, display vout_directx debug: DirectXEnumCallback: ATI Radeon HD 3800 Series, \\.\DISPLAY1 vout_directx debug: selecting ATI Radeon HD 3800 Series, \\.\DISPLAY1 vout_directx debug: screen dimensions (0x0,1680x1050) vout_directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 vout_directx debug: End DirectXInitDDraw vout_directx debug: DirectXCreateDisplay vout_directx debug: DirectXCreateClipper vout_directx debug: disabling screen saver main debug: using video output module "vout_directx" main debug: TIMER module_Need() : 27.000 ms - Total 27.000 ms / 1 intvls (Avg 27.000 ms) main debug: waiting for thread initialization main debug: thread started vout_directx debug: NewPictureVec overlay:yes chroma:YV12 vout_directx debug: YUV overlay created successfully vout_directx debug: End NewPictureVec (succeeded) main debug: got 1 direct buffer(s) main debug: picture in 720x480 (0,0,720x480), chroma I420, ar 16:9, sar 32:27 main debug: picture user 720x480 (0,0,720x480), chroma I420, ar 16:9, sar 32:27 main debug: picture out 720x480 (0,0,720x480), chroma I420, ar 16:9, sar 32:27 main debug: direct render, mapping render pictures 0-6 to system pictures 1-7 main debug: thread 6004 (video output) created at priority 1 (video_output/video_output.c:502) main warning: dts != current_pts (-821600) main warning: backward_pts != current_pts (-33362) qt4 debug: Updating the geometry qt4 debug: New Event: type 1109 main debug: looking for decoder module: 37 candidates main debug: using decoder module "a52" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread started main debug: thread 5976 (decoder) created at priority 2 (input/decoder.c:217) a52 info: A/52 channels:6 samplerate:48000 bitrate:448000 main debug: no aout present, spawning one main debug: looking for audio output module: 3 candidates aout_directx debug: OpenAudio aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: SB X-Fi Audio [E800] aout_directx debug: found device: Modem #11 Line Playback (emulated) aout_directx debug: found device: Modem #11 Handset Playback (emulated) aout_directx debug: device supports 5.1 channels aout_directx debug: device supports 4 channels aout_directx debug: device supports 2 channels aout_directx debug: device supports 1 channel aout_directx debug: Windows says your SpeakerConfig is 5.1 aout_directx debug: device supports A/52 over S/PDIF aout_directx debug: channel reordering needed aout_directx debug: creating DirectSoundThread main debug: thread 6248 (DirectSound Notification Thread) created at priority 15 (directx.c:392) main debug: using audio output module "aout_directx" main debug: thread started main debug: TIMER module_Need() : 59.000 ms - Total 59.000 ms / 1 intvls (Avg 59.000 ms) aout_directx debug: DirectSoundThread ready main debug: output 's16l' 48000 Hz 3F2R/LFE frame=1 samples/12 bytes main debug: mixer 'fl32' 48000 Hz 3F2R/LFE frame=1 samples/24 bytes main debug: filter(s) 'fl32'->'s16l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "converter_float" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: found a filter for the whole conversion main debug: looking for audio mixer module: 3 candidates main debug: using audio mixer module "float32_mixer" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: input 'a52 ' 48000 Hz 3F2R/LFE frame=1536 samples/1792 bytes main debug: looking for audio filter module: 1 candidate equalizer warning: bad input or output format main warning: no audio filter module matching "equalizer" could be loaded main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for audio filter module: 1 candidate equalizer debug: equalizer loaded for 48000 Hz with 10 bands 1 pass equalizer debug: 60 Hz -> factor:0.000000 alpha:0.002769 beta:0.994462 gamma:1.994400 equalizer debug: 170 Hz -> factor:0.000000 alpha:0.007806 beta:0.984388 gamma:1.983897 equalizer debug: 310 Hz -> factor:0.000000 alpha:0.014143 beta:0.971714 gamma:1.970091 equalizer debug: 600 Hz -> factor:0.000000 alpha:0.027011 beta:0.945978 gamma:1.939979 equalizer debug: 1000 Hz -> factor:0.000000 alpha:0.044203 beta:0.911595 gamma:1.895241 equalizer debug: 3000 Hz -> factor:0.000000 alpha:0.121223 beta:0.757553 gamma:1.623767 equalizer debug: 6000 Hz -> factor:0.000000 alpha:0.212888 beta:0.574224 gamma:1.113145 equalizer debug: 12000 Hz -> factor:0.000000 alpha:0.331347 beta:0.337307 gamma:0.000000 equalizer debug: 14000 Hz -> factor:0.000000 alpha:0.355263 beta:0.289473 gamma:-0.333740 equalizer debug: 16000 Hz -> factor:0.000000 alpha:0.371900 beta:0.256201 gamma:-0.628100 main debug: using audio filter module "equalizer" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: filter(s) 'a52 '->'fl32' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "a52tofloat32" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: decoded 106/108 pictures dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=2 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=255000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=4647000 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 main warning: audio drift is too big (-313877), clearing out main warning: mixer start isn't output start (-384626) main debug: audio output is starving (351576), playing silence main warning: dts != current_pts (-330571) main warning: backward_pts != current_pts (16682) dvdnav debug: DVDNAV_NOP dvdnav debug: DVDNAV_CELL_CHANGE dvdnav debug: - cellN=3 dvdnav debug: - pgN=1 dvdnav debug: - cell_length=2838000 dvdnav debug: - pg_length=37272000 dvdnav debug: - pgc_length=654528000 dvdnav debug: - cell_start=4902000 dvdnav debug: - pg_start=0 dvdnav debug: DVDNAV_SPU_CLUT_CHANGE dvdnav debug: DVDNAV_SPU_STREAM_CHANGE dvdnav debug: - physical_wide=128 dvdnav debug: - physical_letterbox=130 dvdnav debug: - physical_pan_scan=128 dvdnav debug: buttonUpdate not done b=1 t=1 dvdnav debug: DVDNAV_AUDIO_STREAM_CHANGE dvdnav debug: - physical=0 dvdnav debug: buttonUpdate not done b=1 t=1 main debug: incoming request - stopping current input main debug: dying input main debug: control type=0 main debug: control: stopping input qt4 debug: Updating the stream status: 8 main debug: removing module "libmpeg2" main debug: thread ended main debug: thread ended main debug: thread times: real 1m10.296875s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 5484 joined (dvdnav.c:367) main debug: thread times: real 0m57.109375s, kernel 0m0.031250s, user 0m0.046875s main debug: thread 5696 joined (input/decoder.c:248) main debug: killing decoder fourcc `mpgv', 279 PES in FIFO main debug: removing module "a52" main debug: thread ended vout_directx debug: DirectXCloseSurface vout_directx debug: DirectXCloseDisplay vout_directx debug: DirectXCloseDisplay clipper vout_directx debug: DirectXCloseDisplay display vout_directx debug: DirectXCloseDDraw main debug: thread ended main debug: thread times: real 0m57.093750s, kernel 0m0.031250s, user 0m0.000000s main debug: thread 6004 joined (video_output/video_output.c:536) vout_directx debug: DirectXEventThread terminating vout_directx debug: DirectXCloseWindow vout_directx debug: WinProc WM_DESTROY main debug: removing module "qt4" qt4 debug: Video is not needed anymore main debug: thread ended main debug: thread times: real 0m57.125000s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 6064 joined (directx.c:513) main debug: removing module "vout_directx" main debug: thread times: real 0m56.968750s, kernel 0m0.000000s, user 0m0.031250s main debug: thread 5976 joined (input/decoder.c:248) main debug: killing decoder fourcc `a52 ', 15 PES in FIFO main debug: removing module "a52tofloat32" main debug: removing module "equalizer" qt4 debug: Updating the geometry main debug: removing module "bandlimited_resampler" aout_directx debug: closing audio device main debug: dying input main debug: dying input aout_directx debug: DirectSoundThread exiting main debug: thread ended main debug: thread times: real 0m57.031250s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 6248 joined (directx.c:664) main debug: removing module "aout_directx" main debug: removing module "converter_float" main debug: removing module "float32_mixer" main debug: looking for decoder module: 37 candidates main debug: using decoder module "a52" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread 5964 (decoder) created at priority 2 (input/decoder.c:217) main debug: thread started main debug: removing module "a52" main debug: thread ended main debug: thread times: real 0m0.015625s, kernel 0m0.000000s, user 0m0.015625s main debug: thread 5964 joined (input/decoder.c:248) main debug: killing decoder fourcc `a52 ', 1 PES in FIFO main debug: looking for decoder module: 37 candidates main debug: using decoder module "a52" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread 5988 (decoder) created at priority 2 (input/decoder.c:217) main debug: thread started main debug: removing module "a52" main debug: thread ended main debug: thread times: real 0m0.000000s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 5988 joined (input/decoder.c:248) main debug: killing decoder fourcc `a52 ', 1 PES in FIFO main debug: Program doesn't contain anymore ES main debug: removing module "dvdnav" main debug: thread ended main debug: dead input main debug: thread times: real 1m12.812500s, kernel 0m0.046875s, user 0m0.140625s main debug: thread 5416 joined (playlist/engine.c:244) main debug: TIMER input launching for 'dvd://E:\' : 2387.000 ms - Total 2387.000 ms / 1 intvls (Avg 2387.000 ms)

And in fact, it doesn't change anything.

I did also try changing the Settings to use ffmpeg as the Demux module, this is what I got for that:
Your input can't be opened:
VLC is unable to open the MRL 'dvd://E:\'. Check the log for details.
and the log:

Code: Select all

logger info: using logger... logger warning: no log filename provided, using `vlc-log.txt' logger debug: opening logfile `vlc-log.txt' main debug: using interface module "logger" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: thread 5380 (interface) created at priority 0 (interface/interface.c:168) main debug: thread started qt4 debug: New item: dvd://E:\ main debug: adding item `dvd://E:\' ( dvd://E:\ ) main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: starting new item main debug: processing request item dvd://E:\ node null skip 0 main debug: resyncing on dvd://E:\ main debug: dvd://E:\ is at 0 main debug: creating new input thread main debug: Creating an input for 'dvd://E:\' main debug: waiting for thread initialization main debug: thread started main debug: `dvd://E:\' gives access `dvd' demux `' path `E:\' main debug: thread 5428 (input) created at priority 1 (input/input.c:370) main debug: enforced demux ` avformat' main debug: creating access 'dvd' path='E:\' main debug: looking for access module: 0 candidates main error: no access module matched "dvd" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main error: open of `dvd://E:\' failed: could not create access: no access module matched "dvd" main debug: finished input main debug: dying input main debug: dying input main debug: dying input main debug: thread ended main debug: dead input main debug: thread times: real 0m0.109375s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 5428 joined (playlist/engine.c:244) main debug: TIMER input launching for 'dvd://E:\' : 160.000 ms - Total 160.000 ms / 1 intvls (Avg 160.000 ms) main debug: starting new item main debug: changing item without a request (current 0/1) main debug: nothing to play
Same way for Windows and Linux.

jvlan
New Cone
New Cone
Posts: 7
Joined: 07 Aug 2008 01:06

Re: DVD - Choppy playback, audio drift problem

Postby jvlan » 09 Mar 2009 15:55

Edited to change 'quote' to 'code'.

Sorry about that. :oops:

Jean-Baptiste Kempf
Site Administrator
Site Administrator
Posts: 37523
Joined: 22 Jul 2005 15:29
VLC version: 4.0.0-git
Operating System: Linux, Windows, Mac
Location: Cone, France
Contact:

Re: DVD - Choppy playback, audio drift problem

Postby Jean-Baptiste Kempf » 10 Mar 2009 08:18

main error: no access module matched "dvd"

Where did you get your VLC?
Jean-Baptiste Kempf
http://www.jbkempf.com/ - http://www.jbkempf.com/blog/category/Videolan
VLC media player developer, VideoLAN President and Sites administrator
If you want an answer to your question, just be specific and precise. Don't use Private Messages.

jvlan
New Cone
New Cone
Posts: 7
Joined: 07 Aug 2008 01:06

Re: DVD - Choppy playback, audio drift problem

Postby jvlan » 10 Mar 2009 17:45

I downloaded it off your site, like I always do.


Edit: That one is .9.8a, BTW.

jvlan
New Cone
New Cone
Posts: 7
Joined: 07 Aug 2008 01:06

Re: DVD - Choppy playback, audio drift problem

Postby jvlan » 12 Apr 2009 22:02

Ah well, not fixed with .9.9.

Maybe later...

jvlan
New Cone
New Cone
Posts: 7
Joined: 07 Aug 2008 01:06

Re: DVD - Choppy playback, audio drift problem

Postby jvlan » 11 Jul 2009 01:38

Ummmmmmm.........

VLC 1.0 actually made this problem worse.

Now, rather than a chop, it's actually a hesitation of nearly a second.

BUT, there is a different message to show you:

Code: Select all

main debug: adding item `dvdsimple://E' ( dvdsimple://E:\ ) main debug: rebuilding array of current - root Playlist main debug: rebuild done - 2 items, index 0 qt4 debug: Adding a new MRL to recent ones: dvdsimple://E:\ main debug: processing request item dvdsimple://E node null skip 0 main debug: resyncing on dvdsimple://E main debug: dvdsimple://E is at 1 main debug: starting new item main debug: creating new input thread main debug: Creating an input for 'dvdsimple://E' main debug: thread (input) created at priority 1 (../.././src/input/input.c:230) main debug: thread started main debug: using timeshift granularity of 50 MBytes main debug: using timeshift path 'C:\DOCUME~1\User\LOCALS~1\Temp' main debug: `dvdsimple://E:\' gives access `dvdsimple' demux `' path `E:\' main debug: creating demux: access='dvdsimple' demux='' path='E:\' main debug: looking for access_demux module: 1 candidate qt4 debug: IM: Setting an input qt4 debug: Updating the geometry qt4 debug: Updating the geometry dvdread debug: VMG opened dvdread debug: number of titles: 22 dvdread debug: title 0 has 19 chapters dvdread debug: title 1 has 1 chapters dvdread debug: title 2 has 2 chapters dvdread debug: title 3 has 4 chapters dvdread debug: title 4 has 3 chapters dvdread debug: title 5 has 2 chapters dvdread debug: title 6 has 2 chapters dvdread debug: title 7 has 2 chapters dvdread debug: title 8 has 2 chapters dvdread debug: title 9 has 2 chapters dvdread debug: title 10 has 1 chapters dvdread debug: title 11 has 1 chapters dvdread debug: title 12 has 1 chapters dvdread debug: title 13 has 1 chapters dvdread debug: title 14 has 1 chapters dvdread debug: title 15 has 2 chapters dvdread debug: title 16 has 2 chapters dvdread debug: title 17 has 3 chapters dvdread debug: title 18 has 1 chapters dvdread debug: title 19 has 2 chapters dvdread debug: title 20 has 1 chapters dvdread debug: title 21 has 1 chapters dvdread debug: open VTS 1, for title 1 dvdread debug: title 1 vts_title 1 pgc 1 pgn 1 start 0 end 2773229 blocks: 2773230 main error: ES_OUT_RESET_PCR called main debug: selecting program id=0 dvdread debug: audio position 0 dvdread debug: audio position 1 dvdread debug: audio position 2 dvdread debug: spu 1 0x80000000 main debug: using access_demux module "dvdread" main debug: TIMER module_need() : 1777.000 ms - Total 1777.000 ms / 1 intvls (Avg 1777.000 ms) main debug: looking for decoder module: 36 candidates main debug: using decoder module "libmpeg2" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315) main debug: thread started main debug: looking for decoder module: 36 candidates main debug: using decoder module "a52" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:315) main debug: thread started main debug: `dvdsimple://E:\' successfully opened qt4 debug: New caching: 0 qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Title 22 qt4 debug: Chapter: 19 qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry main error: Invalid PCR value in ES_OUT_SET_(GROUP_)PCR ! main debug: Buffering 0% main debug: Buffering 0% main debug: Buffering 0% libmpeg2 debug: 720x480 (display 720,480), aspect 768000, sar 32:27, 29.971 fps main debug: Buffering 0% main debug: no usable vout present, spawning one main debug: looking for text renderer module: 2 candidates main debug: Buffering 0% main debug: Buffering 0% main debug: Buffering 14% a52 info: A/52 channels:6 samplerate:48000 bitrate:448000 main debug: Buffering 14% main debug: Buffering 14% main debug: Buffering 14% main debug: Buffering 16% main debug: Buffering 16% main debug: thread (fontlist builder) created at priority 0 (../../.././modules/misc/freetype.c:473) freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for video filter2 module: 20 candidates main debug: thread started freetype debug: Building font database... freetype debug: Finished building font database. freetype debug: Took 4000 microseconds main debug: thread ended swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: looking for video filter2 module: 20 candidates yuvp debug: YUVP to YUVA converter main debug: using video filter2 module "yuvp" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: window size: 853x480 main debug: looking for video output module: 7 candidates vout_directx debug: creating DirectXEventThread main debug: thread (Vout Events Thread) created at priority 0 (../../../.././modules/video_output/msw/directx.c:282) main debug: thread started vout_directx debug: DirectXCreateWindow main debug: looking for hwnd module: 3 candidates qt4 debug: requesting video... qt4 debug: Video was requested -1, -1 main debug: Buffering 20% main debug: Buffering 22% main debug: Buffering 22% main debug: Buffering 23% main debug: Buffering 25% main debug: Buffering 26% main debug: Buffering 26% main debug: Buffering 26% main debug: Buffering 26% main debug: Buffering 27% main debug: Buffering 27% main debug: Buffering 27% main debug: Buffering 27% main debug: Buffering 29% main debug: Buffering 29% main debug: Buffering 29% main debug: Buffering 31% main debug: Buffering 33% main debug: Buffering 33% main debug: Buffering 33% main debug: Buffering 35% main debug: Buffering 39% main debug: Buffering 40% main debug: Buffering 40% main debug: Buffering 42% main debug: Buffering 44% main debug: Buffering 46% main debug: Buffering 46% main debug: Buffering 46% main debug: Buffering 48% qt4 debug: New caching: 48 main debug: Buffering 50% main debug: Buffering 50% main debug: Buffering 50% main debug: Buffering 52% main debug: Buffering 54% main debug: Buffering 54% main debug: Buffering 54% main debug: Buffering 54% main debug: Buffering 54% qt4 debug: New caching: 54 main debug: Buffering 54% main debug: Buffering 55% main debug: Buffering 55% main debug: Buffering 55% main debug: Buffering 55% main debug: Buffering 55% main debug: Buffering 55% main debug: Buffering 55% qt4 debug: New caching: 55 main debug: Buffering 57% main debug: Buffering 57% main debug: Buffering 59% main debug: Buffering 61% main debug: Buffering 61% main debug: Buffering 61% main debug: Buffering 63% main debug: Buffering 65% qt4 debug: New caching: 65 main debug: Buffering 67% main debug: Buffering 67% main debug: Buffering 67% main debug: Buffering 69% main debug: Buffering 71% main debug: Buffering 74% main debug: Buffering 74% main debug: Buffering 74% qt4 debug: New caching: 74 main debug: Buffering 78% main debug: Buffering 78% main debug: Buffering 78% main debug: Buffering 80% main debug: Buffering 82% main debug: Buffering 84% main debug: Buffering 84% main debug: Buffering 84% main debug: Buffering 86% main debug: Buffering 89% main debug: Buffering 91% main debug: Buffering 91% main debug: Buffering 91% main debug: Buffering 93% main debug: Buffering 95% main debug: Buffering 95% main debug: Buffering 97% main debug: Buffering 99% main debug: Stream buffering done (1527 ms in 62 ms) qt4 debug: Video is resizing to: 853 480 qt4 debug: New caching: 100 main debug: using hwnd module "qt4" main debug: TIMER module_need() : 74.000 ms - Total 74.000 ms / 1 intvls (Avg 74.000 ms) vout_directx debug: created video sub-window vout_directx debug: Vout EventThread running vout_directx debug: DirectXInitDDraw vout_directx debug: directx-device: vout_directx debug: DirectXEnumCallback: Primary Display Driver, display vout_directx debug: DirectXEnumCallback: ATI Radeon HD 3800 Series, \\.\DISPLAY1 vout_directx debug: selecting ATI Radeon HD 3800 Series, \\.\DISPLAY1 vout_directx debug: screen dimensions (0x0,1680x1050) vout_directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1 vout_directx debug: End DirectXInitDDraw vout_directx debug: DirectXCreateDisplay vout_directx debug: DirectXCreateClipper vout_directx debug: disabling screen saver main debug: using video output module "vout_directx" main debug: TIMER module_need() : 86.000 ms - Total 86.000 ms / 1 intvls (Avg 86.000 ms) main debug: Deinterlacing available vout_directx debug: NewPictureVec overlay:yes chroma:YV12 vout_directx debug: YUV overlay created successfully vout_directx debug: End NewPictureVec (succeeded) main debug: got 1 direct buffer(s) main debug: pic render sz 720x480, of (0,0), vsz 720x480, 4cc I420, ar 16:9, sar 32:27, msk r0x0 g0x0 b0x0 main debug: pic in sz 720x480, of (0,0), vsz 720x480, 4cc I420, ar 16:9, sar 32:27, msk r0x0 g0x0 b0x0 main debug: pic out sz 720x480, of (0,0), vsz 720x480, 4cc I420, ar 16:9, sar 32:27, msk r0x0 g0x0 b0x0 main debug: direct render, mapping render pictures 0-14 to system pictures 1-15 main debug: reusing aout main warning: dts != current_pts (-247265) main debug: looking for audio output module: 3 candidates aout_directx debug: OpenAudio main warning: backward_pts != current_pts (-33367) main debug: End of video preroll main debug: Received first picture aout_directx debug: found device: Primary Sound Driver aout_directx debug: found device: SB X-Fi Audio [E800] aout_directx debug: found device: Modem #12 Line Playback (emulated) aout_directx debug: found device: Modem #12 Handset Playback (emulated) aout_directx debug: device supports 5.1 channels aout_directx debug: device supports 4 channels aout_directx debug: device supports 2 channels aout_directx debug: device supports 1 channel aout_directx debug: Windows says your SpeakerConfig is 5.1 aout_directx debug: device supports A/52 over S/PDIF aout_directx debug: channel reordering needed aout_directx debug: creating DirectSoundThread main debug: thread (DirectSound Notification Thread) created at priority 15 (../../.././modules/audio_output/directx.c:427) main debug: using audio output module "aout_directx" main debug: TIMER module_need() : 98.000 ms - Total 98.000 ms / 1 intvls (Avg 98.000 ms) main debug: output 's16l' 48000 Hz 3F2R/LFE frame=1 samples/12 bytes main debug: mixer 'fl32' 48000 Hz 3F2R/LFE frame=1 samples/24 bytes main debug: filter(s) 'fl32'->'s16l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: thread started aout_directx debug: DirectSoundThread ready main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "converter_float" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: looking for audio mixer module: 3 candidates main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: input 'a52 ' 48000 Hz 3F2R/LFE frame=1536 samples/1792 bytes main debug: looking for audio filter module: 1 candidate scaletempo warning: bad input or output format main warning: no audio filter module matching "scaletempo" could be loaded main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for audio filter module: 1 candidate scaletempo debug: format: 48000 rate, 6 nch, 4 bps, fl32 scaletempo debug: params: 30 stride, 0.200 overlap, 14 search scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode main debug: using audio filter module "scaletempo" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: filter(s) 'a52 '->'fl32' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "a52tofloat32" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: looking for audio filter module: 1 candidate equalizer debug: equalizer loaded for 48000 Hz with 10 bands 1 pass equalizer debug: 60 Hz -> factor:0.000000 alpha:0.002769 beta:0.994462 gamma:1.994400 equalizer debug: 170 Hz -> factor:0.000000 alpha:0.007806 beta:0.984388 gamma:1.983897 equalizer debug: 310 Hz -> factor:0.000000 alpha:0.014143 beta:0.971714 gamma:1.970091 equalizer debug: 600 Hz -> factor:0.000000 alpha:0.027011 beta:0.945978 gamma:1.939979 equalizer debug: 1000 Hz -> factor:0.000000 alpha:0.044203 beta:0.911595 gamma:1.895241 equalizer debug: 3000 Hz -> factor:0.000000 alpha:0.121223 beta:0.757553 gamma:1.623767 equalizer debug: 6000 Hz -> factor:0.000000 alpha:0.212888 beta:0.574224 gamma:1.113145 equalizer debug: 12000 Hz -> factor:0.000000 alpha:0.331347 beta:0.337307 gamma:0.000000 equalizer debug: 14000 Hz -> factor:0.000000 alpha:0.355263 beta:0.289473 gamma:-0.333740 equalizer debug: 16000 Hz -> factor:0.000000 alpha:0.371900 beta:0.256201 gamma:-0.628100 main debug: using audio filter module "equalizer" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz 3F2R/LFE->3F2R/LFE main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: End of audio preroll main debug: Decoder buffering done in 161 ms qt4 debug: Updating the geometry main debug: Adding CC track 1 for es[0] main debug: Adding CC track 2 for es[0] main debug: Adding CC track 3 for es[0] main debug: Adding CC track 4 for es[0] qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Title 22 qt4 debug: Chapter: 19 main error: Invalid PCR value in ES_OUT_SET_(GROUP_)PCR ! main warning: clock gap, unexpected stream discontinuity main warning: feeding synchro with a new reference point trying to recover from clock gap main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: received buffer in the future main warning: early picture skipped main warning: early picture skipped main warning: received buffer in the future main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: early picture skipped main warning: dts != current_pts (449316480) main warning: computed PTS is out of range (492989), clearing out main warning: output PTS is out of range (520989), clearing out main debug: audio output is starving (393189), playing silence main warning: output date isn't PTS date, requesting resampling (-42960) main warning: buffer is 42960 in advance, triggering downsampling main warning: resampling stopped after 11550000 usec (drift: -790)


BTW, ThankyouThankyouThankyou for fixing DVDsimple!!! :D


Return to “General VLC media player Troubleshooting”

Who is online

Users browsing this forum: No registered users and 6 guests