Postby Chimel » 12 May 2013 19:39
It says "timing screwed" and "audio output is starving!"
Unfortunately my name is not "timing" nor "audio output."
Can't see a way to attach files, so here's the Tools|Messages output (I assume this is the log you mention.)
I copied two logs so you can compare, first when running on sector, then on battery power when the stutter occurs.
I opened VLC then used "Media|Open File" to select a song, the stutter was constant since the very beginning of the first song when running on battery, I stopped after 20 seconds or so.
Today KMPlayer is playing the same folder on battery without stutter, so it's not always the case, or maybe I was testing a more CPU intensive video when I found KMPlayer stuttering. In any case, KMPlayer seems to be a bit more resistant to this stuttering than VLC.
First log set at logging #2 level in Tools|Messages, everything is fine running on sector:
main debug: adding item `1-01 Panic Cord.m4a' ( file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a )
main debug: rebuilding array of current - root Playlist
qt4 debug: Adding a new MRL to recent ones: file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a
main debug: rebuild done - 1 items, index -1
main debug: processing request item: 1-01 Panic Cord.m4a, node: null, skip: 0
main debug: resyncing on 1-01 Panic Cord.m4a
main debug: 1-01 Panic Cord.m4a is at 0
main debug: starting playback of the new playlist item
main debug: resyncing on 1-01 Panic Cord.m4a
main debug: 1-01 Panic Cord.m4a is at 0
main debug: creating new input thread
main debug: Creating an input for '1-01 Panic Cord.m4a'
main debug: Creating an input for '1-01 Panic Cord.m4a'
main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\Michel\LOCALS~1\Temp'
main debug: `file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' gives access `file' demux `' path `/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a'
main debug: creating demux: access='file' demux='' location='/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' file='D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: looking for access_demux module: 3 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.957 ms - Total 0.957 ms / 1 intvls (Avg 0.957 ms)
main debug: creating access 'file' location='/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a', path='D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: looking for access module: 3 candidates
filesystem debug: opening file `D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.685 ms - Total 0.685 ms / 1 intvls (Avg 0.685 ms)
main debug: Using stream method for AStream*
main debug: starting pre-buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 2717 KiB/s
main debug: looking for stream_filter module: 4 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.401 ms - Total 0.401 ms / 1 intvls (Avg 0.401 ms)
main debug: looking for stream_filter module: 1 candidate
main debug: using stream_filter module "stream_filter_record"
main debug: TIMER module_need() : 0.673 ms - Total 0.673 ms / 1 intvls (Avg 0.673 ms)
main debug: creating demux: access='file' demux='' location='/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' file='D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: looking for demux module: 55 candidates
mp4 warning: unknown box type sbtd (incompletely loaded)
mp4 warning: unknown box type pinf (incompletely loaded)
mp4 warning: unknown box type ---- (incompletely loaded)
mp4 warning: unknown box type ---- (incompletely loaded)
mp4 warning: unknown box type aART (incompletely loaded)
mp4 warning: unknown box type disk (incompletely loaded)
mp4 warning: unknown box type cpil (incompletely loaded)
mp4 warning: unknown box type pgap (incompletely loaded)
mp4 warning: unknown box type apID (incompletely loaded)
mp4 warning: unknown box type cnID (incompletely loaded)
mp4 warning: unknown box type rtng (incompletely loaded)
mp4 warning: unknown box type atID (incompletely loaded)
mp4 warning: unknown box type cmID (incompletely loaded)
mp4 warning: unknown box type plID (incompletely loaded)
mp4 warning: unknown box type geID (incompletely loaded)
mp4 warning: unknown box type sfID (incompletely loaded)
mp4 warning: unknown box type akID (incompletely loaded)
mp4 warning: unknown box type stik (incompletely loaded)
mp4 warning: unknown box type purd (incompletely loaded)
mp4 warning: unknown box type xid (incompletely loaded)
mp4 warning: unknown box type flvr (incompletely loaded)
mp4 warning: unknown box type ---- (incompletely loaded)
mp4 warning: unknown box type covr (incompletely loaded)
mp4 debug: dumping root Box "root"
mp4 debug: | + ftyp size 32
mp4 debug: | + moov size 147078
mp4 debug: | | + mvhd size 108
mp4 debug: | | + trak size 70160
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 70060
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 34
mp4 debug: | | | | + minf size 69986
mp4 debug: | | | | | + smhd size 16
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 69926
mp4 debug: | | | | | | + stsd size 32886
mp4 debug: | | | | | | | + mp4a size 32870
mp4 debug: | | | | | | | | + esds size 51
mp4 debug: | | | | | | | | + sbtd size 15
mp4 debug: | | | | | | | | + pinf size 32768
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stsc size 40
mp4 debug: | | | | | | + stsz size 35344
mp4 debug: | | | | | | + stco size 1624
mp4 debug: | | + udta size 76802
mp4 debug: | | | + meta size 76794
mp4 debug: | | | | + hdlr size 34
mp4 debug: | | | | + ilst size 74700
mp4 debug: | | | | | + ---- size 188
mp4 debug: | | | | | + ---- size 162
mp4 debug: | | | | | + cnam size 34
mp4 debug: | | | | | + cART size 39
mp4 debug: | | | | | + aART size 39
mp4 debug: | | | | | + cwrt size 38
mp4 debug: | | | | | + calb size 53
mp4 debug: | | | | | + cgen size 41
mp4 debug: | | | | | + trkn size 32
mp4 debug: | | | | | + disk size 30
mp4 debug: | | | | | + cpil size 25
mp4 debug: | | | | | + pgap size 25
mp4 debug: | | | | | + cday size 44
mp4 debug: | | | | | + apID size 47
mp4 debug: | | | | | + cprt size 99
mp4 debug: | | | | | + cnID size 28
mp4 debug: | | | | | + rtng size 25
mp4 debug: | | | | | + atID size 28
mp4 debug: | | | | | + cmID size 28
mp4 debug: | | | | | + plID size 32
mp4 debug: | | | | | + geID size 28
mp4 debug: | | | | | + sfID size 28
mp4 debug: | | | | | + akID size 25
mp4 debug: | | | | | + stik size 25
mp4 debug: | | | | | + purd size 43
mp4 debug: | | | | | + xid size 45
mp4 debug: | | | | | + flvr size 29
mp4 debug: | | | | | + ---- size 397
mp4 debug: | | | | | + covr size 73035
mp4 debug: | | | | + free size 2048
mp4 debug: | + free size 447906
mp4 debug: | + mdat size 6757593
mp4 debug: unrecognized major file specification (M4A ).
mp4 debug: found 1 tracks
mp4 debug: track[Id 0x1] read 402 chunk
mp4 debug: track[Id 0x1] read 8831 samples length:205s
main debug: selecting program id=0
mp4 debug: adding track[Id 0x1] audio (enable) language en
main debug: using demux module "mp4"
main debug: TIMER module_need() : 17.540 ms - Total 17.540 ms / 1 intvls (Avg 17.540 ms)
main debug: looking for a subtitle file in D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\
main debug: looking for decoder module: 32 candidates
main debug: using decoder module "faad"
main debug: TIMER module_need() : 43.611 ms - Total 43.611 ms / 1 intvls (Avg 43.611 ms)
main debug: looking for meta reader module: 2 candidates
taglib debug: Found embedded art (image/jpeg) is 73011 bytes
main debug: using meta reader module "taglib"
main debug: TIMER module_need() : 24.883 ms - Total 24.883 ms / 1 intvls (Avg 24.883 ms)
main debug: removing module "taglib"
main debug: `file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' successfully opened
mp4 debug: track[Id 0x1] does not provide Sync Sample Box (stss)
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 33%
main debug: Buffering 66%
main debug: Buffering 100%
main debug: Stream buffering done (400 ms in 1 ms)
faad warning: decoded zero sample
main debug: creating audio output
main debug: looking for audio output module: 2 candidates
aout_directx debug: Opening DirectSound Audio Output
main debug: no fetch required for Panic Cord (art currently file:///C:/Documents%20and%20Settings/Michel/Application%20Data/vlc/art/artistalbum/Gabrielle%20Aplin/English%20Rain%20%28Deluxe%20Edition%29/art.jpg)
qt4 debug: IM: Setting an input
aout_directx debug: found device: Primary Sound Driver
aout_directx debug: found device: Realtek HD Audio output
aout_directx debug: device supports 2 channels (DEFAULT!)
aout_directx debug: device supports 1 channel
aout_directx debug: creating DirectSoundThread
aout_directx debug: DirectSoundThread ready
main debug: using audio output module "aout_directx"
main debug: TIMER module_need() : 258.167 ms - Total 258.167 ms / 1 intvls (Avg 258.167 ms)
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: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
audio_format debug: f32l->s16l, bits per sample: 32->16
main debug: using audio filter module "audio_format"
main debug: TIMER module_need() : 16.515 ms - Total 16.515 ms / 1 intvls (Avg 16.515 ms)
main debug: conversion pipeline completed
main debug: looking for audio mixer module: 2 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 1.570 ms - Total 1.570 ms / 1 intvls (Avg 1.570 ms)
main debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32
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
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 2.019 ms - Total 2.019 ms / 1 intvls (Avg 2.019 ms)
main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
main debug: using audio filter module "samplerate"
main debug: TIMER module_need() : 0.719 ms - Total 0.719 ms / 1 intvls (Avg 0.719 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
main debug: Decoder buffering done in 289 ms
main warning: PTS is out of range (-31820), dropping buffer
Same song, when the netbook is running on battery power:
main debug: adding item `1-01 Panic Cord.m4a' ( file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a )
main debug: rebuilding array of current - root Playlist
qt4 debug: Adding a new MRL to recent ones: file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a
main debug: rebuild done - 1 items, index -1
main debug: processing request item: 1-01 Panic Cord.m4a, node: null, skip: 0
main debug: resyncing on 1-01 Panic Cord.m4a
main debug: 1-01 Panic Cord.m4a is at 0
main debug: starting playback of the new playlist item
main debug: resyncing on 1-01 Panic Cord.m4a
main debug: 1-01 Panic Cord.m4a is at 0
main debug: creating new input thread
main debug: Creating an input for '1-01 Panic Cord.m4a'
main debug: Creating an input for '1-01 Panic Cord.m4a'
main debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\Michel\LOCALS~1\Temp'
main debug: `file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' gives access `file' demux `' path `/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a'
main debug: creating demux: access='file' demux='' location='/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' file='D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: looking for access_demux module: 3 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 1.469 ms - Total 1.469 ms / 1 intvls (Avg 1.469 ms)
main debug: creating access 'file' location='/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a', path='D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: looking for access module: 3 candidates
filesystem debug: opening file `D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 1.616 ms - Total 1.616 ms / 1 intvls (Avg 1.616 ms)
main debug: Using stream method for AStream*
main debug: starting pre-buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 5847 KiB/s
main debug: looking for stream_filter module: 4 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.658 ms - Total 0.658 ms / 1 intvls (Avg 0.658 ms)
main debug: looking for stream_filter module: 1 candidate
main debug: using stream_filter module "stream_filter_record"
main debug: TIMER module_need() : 0.560 ms - Total 0.560 ms / 1 intvls (Avg 0.560 ms)
main debug: creating demux: access='file' demux='' location='/D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' file='D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\1-01 Panic Cord.m4a'
main debug: looking for demux module: 55 candidates
mp4 warning: unknown box type sbtd (incompletely loaded)
mp4 warning: unknown box type pinf (incompletely loaded)
mp4 warning: unknown box type ---- (incompletely loaded)
mp4 warning: unknown box type ---- (incompletely loaded)
mp4 warning: unknown box type aART (incompletely loaded)
mp4 warning: unknown box type disk (incompletely loaded)
mp4 warning: unknown box type cpil (incompletely loaded)
mp4 warning: unknown box type pgap (incompletely loaded)
mp4 warning: unknown box type apID (incompletely loaded)
mp4 warning: unknown box type cnID (incompletely loaded)
mp4 warning: unknown box type rtng (incompletely loaded)
mp4 warning: unknown box type atID (incompletely loaded)
mp4 warning: unknown box type cmID (incompletely loaded)
mp4 warning: unknown box type plID (incompletely loaded)
mp4 warning: unknown box type geID (incompletely loaded)
mp4 warning: unknown box type sfID (incompletely loaded)
mp4 warning: unknown box type akID (incompletely loaded)
mp4 warning: unknown box type stik (incompletely loaded)
mp4 warning: unknown box type purd (incompletely loaded)
mp4 warning: unknown box type xid (incompletely loaded)
mp4 warning: unknown box type flvr (incompletely loaded)
mp4 warning: unknown box type ---- (incompletely loaded)
mp4 warning: unknown box type covr (incompletely loaded)
mp4 debug: dumping root Box "root"
mp4 debug: | + ftyp size 32
mp4 debug: | + moov size 147078
mp4 debug: | | + mvhd size 108
mp4 debug: | | + trak size 70160
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 70060
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 34
mp4 debug: | | | | + minf size 69986
mp4 debug: | | | | | + smhd size 16
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 69926
mp4 debug: | | | | | | + stsd size 32886
mp4 debug: | | | | | | | + mp4a size 32870
mp4 debug: | | | | | | | | + esds size 51
mp4 debug: | | | | | | | | + sbtd size 15
mp4 debug: | | | | | | | | + pinf size 32768
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stsc size 40
mp4 debug: | | | | | | + stsz size 35344
mp4 debug: | | | | | | + stco size 1624
mp4 debug: | | + udta size 76802
mp4 debug: | | | + meta size 76794
mp4 debug: | | | | + hdlr size 34
mp4 debug: | | | | + ilst size 74700
mp4 debug: | | | | | + ---- size 188
mp4 debug: | | | | | + ---- size 162
mp4 debug: | | | | | + cnam size 34
mp4 debug: | | | | | + cART size 39
mp4 debug: | | | | | + aART size 39
mp4 debug: | | | | | + cwrt size 38
mp4 debug: | | | | | + calb size 53
mp4 debug: | | | | | + cgen size 41
mp4 debug: | | | | | + trkn size 32
mp4 debug: | | | | | + disk size 30
mp4 debug: | | | | | + cpil size 25
mp4 debug: | | | | | + pgap size 25
mp4 debug: | | | | | + cday size 44
mp4 debug: | | | | | + apID size 47
mp4 debug: | | | | | + cprt size 99
mp4 debug: | | | | | + cnID size 28
mp4 debug: | | | | | + rtng size 25
mp4 debug: | | | | | + atID size 28
mp4 debug: | | | | | + cmID size 28
mp4 debug: | | | | | + plID size 32
mp4 debug: | | | | | + geID size 28
mp4 debug: | | | | | + sfID size 28
mp4 debug: | | | | | + akID size 25
mp4 debug: | | | | | + stik size 25
mp4 debug: | | | | | + purd size 43
mp4 debug: | | | | | + xid size 45
mp4 debug: | | | | | + flvr size 29
mp4 debug: | | | | | + ---- size 397
mp4 debug: | | | | | + covr size 73035
mp4 debug: | | | | + free size 2048
mp4 debug: | + free size 447906
mp4 debug: | + mdat size 6757593
mp4 debug: unrecognized major file specification (M4A ).
mp4 debug: found 1 tracks
mp4 debug: track[Id 0x1] read 402 chunk
mp4 debug: track[Id 0x1] read 8831 samples length:205s
main debug: selecting program id=0
mp4 debug: adding track[Id 0x1] audio (enable) language en
main debug: using demux module "mp4"
main debug: TIMER module_need() : 185.979 ms - Total 185.979 ms / 1 intvls (Avg 185.979 ms)
main debug: looking for a subtitle file in D:\music\To listen to\Gabrielle_Aplin-English_Rain_(iTunes_Deluxe_Edition)-2013\
main debug: looking for decoder module: 32 candidates
qt4 debug: IM: Setting an input
main debug: no fetch required for Panic Cord (art currently file:///C:/Documents%20and%20Settings/Michel/Application%20Data/vlc/art/artistalbum/Gabrielle%20Aplin/English%20Rain%20%28Deluxe%20Edition%29/art.jpg)
main debug: using decoder module "faad"
main debug: TIMER module_need() : 233.555 ms - Total 233.555 ms / 1 intvls (Avg 233.555 ms)
main debug: looking for meta reader module: 2 candidates
taglib debug: Found embedded art (image/jpeg) is 73011 bytes
main debug: using meta reader module "taglib"
main debug: TIMER module_need() : 22.965 ms - Total 22.965 ms / 1 intvls (Avg 22.965 ms)
main debug: removing module "taglib"
main debug: `file:///D:/music/To%20listen%20to/Gabrielle_Aplin-English_Rain_%28iTunes_Deluxe_Edition%29-2013/1-01%20Panic%20Cord.m4a' successfully opened
mp4 debug: track[Id 0x1] does not provide Sync Sample Box (stss)
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 33%
main debug: Buffering 66%
main debug: Buffering 100%
main debug: Stream buffering done (400 ms in 1 ms)
faad warning: decoded zero sample
main debug: creating audio output
main debug: looking for audio output module: 2 candidates
aout_directx debug: Opening DirectSound Audio Output
aout_directx debug: found device: Primary Sound Driver
aout_directx debug: found device: Realtek HD Audio output
aout_directx debug: device supports 2 channels (DEFAULT!)
aout_directx debug: device supports 1 channel
aout_directx debug: creating DirectSoundThread
aout_directx debug: DirectSoundThread ready
main debug: using audio output module "aout_directx"
main debug: TIMER module_need() : 898.312 ms - Total 898.312 ms / 1 intvls (Avg 898.312 ms)
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: filter(s) 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
audio_format debug: f32l->s16l, bits per sample: 32->16
main debug: using audio filter module "audio_format"
main debug: TIMER module_need() : 129.438 ms - Total 129.438 ms / 1 intvls (Avg 129.438 ms)
main debug: conversion pipeline completed
main debug: looking for audio mixer module: 2 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 15.732 ms - Total 15.732 ms / 1 intvls (Avg 15.732 ms)
main debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32
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
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 16.383 ms - Total 16.383 ms / 1 intvls (Avg 16.383 ms)
main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
main debug: using audio filter module "samplerate"
main debug: TIMER module_need() : 1.780 ms - Total 1.780 ms / 1 intvls (Avg 1.780 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
main debug: Decoder buffering done in 1100 ms
main warning: PTS is out of range (-33164), dropping buffer
main warning: audio output out of sync, adjusting dates (73829 us)
main warning: not synchronized (73833 us), resampling
main warning: buffer too late (73379), up-sampling
main debug: audio output is starving (-65907), playing silence
main debug: audio output is starving (-67768), playing silence
main debug: audio output is starving (-83948), playing silence
main debug: audio output is starving (-68571), playing silence
main debug: audio output is starving (-91939), playing silence
main debug: audio output is starving (-77481), playing silence
main debug: audio output is starving (-64747), playing silence
main debug: audio output is starving (-92696), playing silence
main debug: audio output is starving (-75781), playing silence
main debug: audio output is starving (-60924), playing silence
main debug: audio output is starving (-87017), playing silence
main debug: audio output is starving (-73845), playing silence
main warning: audio output out of sync, adjusting dates (-40138 us)
main warning: not synchronized (-40134 us), resampling
main debug: audio output is starving (-57282), playing silence
main debug: audio output is starving (-83220), playing silence
main warning: audio output out of sync, adjusting dates (-49057 us)
main warning: not synchronized (-49052 us), resampling
main debug: audio output is starving (-57042), playing silence
main debug: audio output is starving (-79328), playing silence
main debug: audio output is starving (-69150), playing silence
main debug: audio output is starving (-92218), playing silence
main debug: audio output is starving (-81253), playing silence
main warning: audio output out of sync, adjusting dates (-48528 us)
main warning: not synchronized (-48523 us), resampling
main debug: audio output is starving (-53431), playing silence
main debug: audio output is starving (-81561), playing silence
main debug: audio output is starving (-67693), playing silence
main debug: audio output is starving (-88343), playing silence
main debug: audio output is starving (-75897), playing silence
main warning: audio output out of sync, adjusting dates (-46377 us)
main warning: not synchronized (-46372 us), resampling
main warning: buffer way too early (-141088), clearing queue
main warning: timing screwed, stopping resampling
main debug: audio output is starving (-306922), playing silence
main warning: audio output out of sync, adjusting dates (-48334 us)
main warning: not synchronized (-48329 us), resampling
main warning: buffer too early (-48329), down-sampling
main debug: audio output is starving (-51495), playing silence
main debug: audio output is starving (-76171), playing silence
main warning: audio output out of sync, adjusting dates (-43213 us)
main warning: not synchronized (-43209 us), resampling
main warning: timing screwed, stopping resampling
main warning: buffer too early (-91197), down-sampling
main debug: audio output is starving (-54950), playing silence
main debug: audio output is starving (-83905), playing silence
main debug: audio output is starving (-65942), playing silence
main debug: audio output is starving (-91925), playing silence
main debug: audio output is starving (-78496), playing silence
main warning: audio output out of sync, adjusting dates (-45892 us)
main warning: not synchronized (-45888 us), resampling
main warning: buffer way too early (-135159), clearing queue
main warning: timing screwed, stopping resampling
main debug: audio output is starving (-279360), playing silence
main debug: audio output is starving (-67798), playing silence
main debug: audio output is starving (-88692), playing silence
main debug: audio output is starving (-79717), playing silence
main warning: audio output out of sync, adjusting dates (-47862 us)
main warning: not synchronized (-47857 us), resampling
main warning: buffer too early (-47857), down-sampling
main debug: audio output is starving (-53666), playing silence
main debug: audio output is starving (-80484), playing silence
main debug: audio output is starving (-68374), playing silence
main debug: audio output is starving (-88778), playing silence
main debug: audio output is starving (-77263), playing silence
main warning: audio output out of sync, adjusting dates (-45223 us)
main warning: not synchronized (-45219 us), resampling
main warning: timing screwed, stopping resampling
main warning: buffer too early (-91013), down-sampling
main debug: audio output is starving (-54514), playing silence
main debug: audio output is starving (-79724), playing silence
main debug: audio output is starving (-67836), playing silence
main debug: audio output is starving (-93495), playing silence
main debug: audio output is starving (-79864), playing silence
main warning: audio output out of sync, adjusting dates (-48719 us)
main warning: not synchronized (-48715 us), resampling
main warning: buffer way too early (-137596), clearing queue
main warning: timing screwed, stopping resampling
main debug: audio output is starving (-315081), playing silence
main debug: audio output is starving (-67610), playing silence
main warning: audio output out of sync, adjusting dates (-42884 us)
main warning: not synchronized (-42880 us), resampling
main warning: buffer too early (-42881), down-sampling
main warning: audio output out of sync, adjusting dates (-46557 us)
main warning: not synchronized (-46553 us), resampling
main warning: timing screwed, stopping resampling
main warning: buffer too early (-89388), down-sampling