VLC 2.0.4 PowerPC - Unusually high CPU load

macOS specific usage questions
B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 25 Oct 2012 03:21

eMac G4 1.25 GHz, Radeon 9200 video card.

VLC 2.0.4 seems to put an unusually high load on the CPU.
For example, I'm trying to watch the following Youtube video at 360p resolution:
http://www.youtube.com/watch?v=2aCOyOvOw5c
Activity Monitor gives me these results for CPU load:

VLC 2.0.4 --> 80 - 85 %
VLC 1.1.12 --> 25 - 35 %

Unfortunately I wasn't able to test version 2.0.3 as I get an internal server error when I try to download it from your ftp server. In my experience, though, I'd say on this machine it requires around 10 % more power from the CPU than VLC 1.1.12, but nothing close to what I'm seeing from 2.0.4.

I hope this can be fixed. I also would like to thank you for supporting these old Macs in 2012. It must take a lot of effort, and I really appreciate that.

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: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby Jean-Baptiste Kempf » 25 Oct 2012 12:09

This will not be fixed until someone shares the logs correctly of both playback.
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.

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 25 Oct 2012 23:16

Here are the logs for 1.1.12 and 2.0.4. I had to stop playback after 30 seconds to get a complete log from start to finish for 2.0.4.


VLC 1.1.12

TIMER module_need() : 0.717 ms - Total 0.717 ms / 1 intvls (Avg 0.717 ms)
looking for stream_filter module: 1 candidate
main debug: using stream_filter module "stream_filter_record"
main debug: TIMER module_need() : 0.640 ms - Total 0.640 ms / 1 intvls (Avg 0.640 ms)
main debug: creating demux: access='http' demux='' path='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18'
main debug: looking for demux module: 52 candidates
avcodec debug: couldn't guess format
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/playlist
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_streams.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_xml.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/appletrailers.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/bbc_co_uk.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/break.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/canalplus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/cue.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/dailymotion.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/france2.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/googlevideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/jamendo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/joox.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/katsomo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/koreus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/lelombrik.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/megavideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metacafe.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metachannels.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/mpora.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pinkbike.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/rockbox_fm_presets.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.lua
lua debug: Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.lua's probe() function was successful
main debug: using demux module "lua"
main debug: TIMER module_need() : 238.226 ms - Total 238.226 ms / 1 intvls (Avg 238.226 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 3.652 ms - Total 3.652 ms / 1 intvls (Avg 3.652 ms)
main debug: `http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' successfully opened
lua debug: Path: http://o-o---preferred---sn-nx5cvox-hpa ... 9066059BEC
lua debug: Name: Riding the Booster with enhanced sound
lua debug: Artist: interbartolo
lua debug: Description: From the upcoming Special Edition Ascent: Commemorating Space Shuttle DVD/BluRay by NASA/Glenn a movie from the point of view of the Solid Rocket Booster wit...
lua debug: ArtURL: http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg
main: stopping playback
main debug: deleting item `watch?v=2aCOyOvOw5c&fmt=18'
main debug: no fetch required for (null) (art currently http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg)
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
main debug: dying input
main debug: control type=0
main debug: control: stopping input
main debug: dying input
main debug: removing module "lua"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: dead input
main debug: thread ended
main debug: processing request item Riding the Booster with enhanced sound node Scaletta skip 0
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 1 items, index 0
main debug: starting new item
main debug: creating new input thread
main debug: Creating an input for 'Riding the Booster with enhanced sound'
main debug: thread (input) created at priority 22 (../../src/input/input.c:220)
main debug: thread started
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path '/tmp'
main debug: `http://o-o---preferred---sn-nx5cvox-hpa ... 9066059BEC' gives access `http' demux `' path `o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=SpENgkTQu24&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=927105%2C905701%2C904824%2C916806%2C900222%2C902547%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C916805%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351224346&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198871&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNUl9JUENONF9KR1ZEOmszcjUyR1hJQjE0&id=d9a08ec8ebcec397&signature=90B4ACEA8177BB9E89C9FF520785D08DC4B3AC60.6EA60952D49512CF6C980013C2D8989066059BEC'
main debug: creating demux: access='http' demux='' path='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=SpENgkTQu24&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=927105%2C905701%2C904824%2C916806%2C900222%2C902547%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C916805%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351224346&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198871&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNUl9JUENONF9KR1ZEOmszcjUyR1hJQjE0&id=d9a08ec8ebcec397&signature=90B4ACEA8177BB9E89C9FF520785D08DC4B3AC60.6EA60952D49512CF6C980013C2D8989066059BEC'
main debug: looking for access_demux module: 0 candidates
main debug: no access_demux module matched "http"
main debug: TIMER module_need() : 0.628 ms - Total 0.628 ms / 1 intvls (Avg 0.628 ms)
main debug: creating access 'http' path='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=SpENgkTQu24&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=927105%2C905701%2C904824%2C916806%2C900222%2C902547%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C916805%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351224346&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198871&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNUl9JUENONF9KR1ZEOmszcjUyR1hJQjE0&id=d9a08ec8ebcec397&signature=90B4ACEA8177BB9E89C9FF520785D08DC4B3AC60.6EA60952D49512CF6C980013C2D8989066059BEC'
main debug: looking for access module: 2 candidates
access_http debug: http: server='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com' port=80 file='/videoplayback?upn=SpENgkTQu24&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=927105%2C905701%2C904824%2C916806%2C900222%2C902547%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C916805%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351224346&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198871&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNUl9JUENONF9KR1ZEOmszcjUyR1hJQjE0&id=d9a08ec8ebcec397&signature=90B4ACEA8177BB9E89C9FF520785D08DC4B3AC60.6EA60952D49512CF6C980013C2D8989066059BEC'
main debug: net: connecting to o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com port 80
macosx debug: input has stopped, refreshing interface
main debug: TIMER input launching for 'watch?v=2aCOyOvOw5c&fmt=18' : 921.278 ms - Total 921.278 ms / 1 intvls (Avg 921.278 ms)
macosx debug: input has changed, refreshing interface
main debug: connection succeeded (socket = 8)
access_http debug: protocol 'HTTP' answer code 206
access_http debug: Content-Type: video/mp4
access_http debug: stream size=25839735,pos=0,remaining=25839735
access_http debug: this frame size=25839735
access_http debug: Connection: close
access_http debug: Server: gvs 1.0
main debug: using access module "access_http"
main debug: TIMER module_need() : 279.410 ms - Total 279.410 ms / 1 intvls (Avg 279.410 ms)
main debug: Using AStream*Stream
main debug: pre buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 4081 KiB/s
main debug: looking for stream_filter module: 5 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 1.043 ms - Total 1.043 ms / 1 intvls (Avg 1.043 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.484 ms - Total 0.484 ms / 1 intvls (Avg 0.484 ms)
main debug: creating demux: access='http' demux='' path='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=SpENgkTQu24&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=927105%2C905701%2C904824%2C916806%2C900222%2C902547%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C916805%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351224346&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198871&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNUl9JUENONF9KR1ZEOmszcjUyR1hJQjE0&id=d9a08ec8ebcec397&signature=90B4ACEA8177BB9E89C9FF520785D08DC4B3AC60.6EA60952D49512CF6C980013C2D8989066059BEC'
main debug: looking for demux module: 52 candidates
mp4 warning: MP4 plugin discarded (not fastseekable)
avcodec debug: detected format: mov,mp4,m4a,3gp,3g2,mj2
main debug: selecting program id=0
avcodec debug: adding es: video codec = h264
avcodec debug: adding es: audio codec = mp4a
avcodec debug: AVFormat supported stream
avcodec debug: - format = mov,mp4,m4a,3gp,3g2,mj2 (QuickTime/MPEG-4/Motion JPEG 2000 format)
avcodec debug: - start time = 0
avcodec debug: - duration = 511594417
main debug: using demux module "avcodec"
main debug: TIMER module_need() : 1093.545 ms - Total 1093.545 ms / 1 intvls (Avg 1093.545 ms)
main debug: looking for decoder module: 33 candidates
avcodec debug: libavcodec initialized (interface 0x350500)
avcodec debug: trying to use direct rendering
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 58.812 ms - Total 58.812 ms / 1 intvls (Avg 58.812 ms)
main debug: thread started
main debug: thread (decoder) created at priority 0 (../../src/input/decoder.c:301)
main debug: looking for decoder module: 33 candidates
main debug: using decoder module "faad"
main debug: TIMER module_need() : 1.662 ms - Total 1.662 ms / 1 intvls (Avg 1.662 ms)
main debug: thread started
main debug: thread (decoder) created at priority 22 (../../src/input/decoder.c:301)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 3.589 ms - Total 3.589 ms / 1 intvls (Avg 3.589 ms)
main debug: `http://o-o---preferred---sn-nx5cvox-hpa ... 9066059BEC' successfully opened
main debug: Buffering 0%
main debug: no usable vout present, spawning one
main debug: looking for text renderer module: 3 candidates
main debug: using text renderer module "quartztext"
main debug: TIMER module_need() : 2.915 ms - Total 2.915 ms / 1 intvls (Avg 2.915 ms)
main debug: looking for video filter2 module: 13 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 9.153 ms - Total 9.153 ms / 1 intvls (Avg 9.153 ms)
main debug: looking for video filter2 module: 13 candidates
yuvp debug: YUVP to YUVA converter
main debug: Buffering 3%
main debug: Buffering 6%
main debug: Buffering 10%
main debug: Buffering 13%
main debug: Buffering 17%
main debug: Buffering 20%
main debug: Buffering 24%
main debug: Buffering 27%
main debug: Buffering 31%
main debug: Buffering 34%
main debug: Buffering 38%
faad warning: decoded zero sample
main debug: Buffering 41%
main debug: Buffering 45%
main debug: Buffering 48%
main debug: Buffering 52%
main debug: Buffering 55%
main debug: Buffering 59%
main debug: Buffering 62%
main debug: Buffering 66%
main debug: Buffering 69%
main debug: Buffering 72%
main debug: Buffering 76%
main debug: Buffering 79%
main debug: Buffering 83%
main debug: Buffering 86%
main debug: Buffering 90%
main debug: Buffering 93%
main debug: Buffering 97%
main debug: Stream buffering done (1209 ms in 28 ms)
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 14.573 ms - Total 14.573 ms / 1 intvls (Avg 14.573 ms)
main debug: window size: 640x360
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: looking for video output module: 2 candidates
main debug: looking for opengl provider module: 2 candidates
macosx debug: display is Quartz Extreme accelerated
main debug: using opengl provider module "macosx"
main debug: TIMER module_need() : 225.588 ms - Total 225.588 ms / 1 intvls (Avg 225.588 ms)
main debug: using video output module "opengl"
main debug: TIMER module_need() : 228.196 ms - Total 228.196 ms / 1 intvls (Avg 228.196 ms)
main debug: got 1 direct buffer(s)
main debug: pic render sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic in sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic out sz 640x360, of (0,0), vsz 640x360, 4cc YUY2, sar 1:1, msk r0x0 g0x0 b0x0
main debug: looking for video filter2 module: 13 candidates
main debug: using video filter2 module "i420_yuy2_altivec"
main debug: TIMER module_need() : 0.726 ms - Total 0.726 ms / 1 intvls (Avg 0.726 ms)
main debug: indirect render, mapping render pictures 0-24 to system pictures 1-25
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
main debug: creating aout
main debug: looking for audio output module: 2 candidates
main debug: VLC is looking for: 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: Post-processing available
main warning: vlc_object_find_name(postproc) is not safe!
auhal debug: found 1 audio device(s)
main debug: looking for video blending module: 1 candidate
auhal debug: DevID: 258 DevName: Audio incorporato
blend debug: chroma: YUVA -> YUY2
auhal debug: found 1 stream formats
main debug: using video blending module "blend"
auhal debug: supported format: [44100][lpcm][30][4][1][4][2][16]
main debug: TIMER module_need() : 1108.711 ms - Total 1108.711 ms / 1 intvls (Avg 1108.711 ms)
auhal debug: audio device does not support digital output
main debug: skipped big render time 1143448 > 200010
auhal debug: current format is: [44100][lpcm][43][4][1][4][2][32]
auhal debug: layout of AUHAL has 2 channels
auhal debug: selected 2 physical channels for device output
auhal debug: VLC will output: Stereo
auhal debug: we set the AU format: [44100][lpcm][11][8][1][8][2][32]
auhal debug: the actual set AU format is [44100][lpcm][11][8][1][8][2][32]
auhal debug: analog output successfully opened
main debug: using audio output module "auhal"
main debug: TIMER module_need() : 1891.661 ms - Total 1891.661 ms / 1 intvls (Avg 1891.661 ms)
main debug: output 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: mixer 'f32b' 44100 Hz Stereo 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() : 2.061 ms - Total 2.061 ms / 1 intvls (Avg 2.061 ms)
main debug: input 'f32b' 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.398 ms - Total 2.398 ms / 1 intvls (Avg 2.398 ms)
main debug: filter(s) 'f32b'->'f32b' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
main debug: using audio filter module "ugly_resampler"
main debug: TIMER module_need() : 11.818 ms - Total 11.818 ms / 1 intvls (Avg 11.818 ms)
main debug: found a filter for the whole conversion
main debug: End of audio preroll
main debug: Decoder buffering done in 2179 ms
main warning: PTS is out of range (-19112), dropping buffer
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
main debug: control type=0
main debug: control: stopping input
macosx debug: input has stopped, refreshing interface
main debug: dying input
macosx debug: input has changed, refreshing interface
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
macosx debug: input has stopped, refreshing interface
main debug: removing module "avcodec"
main debug: killing decoder fourcc `h264', 8 PES in FIFO
main debug: [0] 4 0
main debug: [1] 4 0
main debug: [2] 4 0
main debug: [3] 4 0
main debug: [4] 4 0
main debug: [5] 4 0
main debug: [6] 4 0
main debug: [7] 4 0
main debug: [8] 4 0
main debug: [9] 4 0
main debug: [10] 4 0
main debug: [11] 4 0
main debug: [12] 4 0
main debug: [13] 4 0
main debug: [14] 4 0
main debug: [15] 2 0
main debug: [16] 4 0
main debug: [17] 4 0
main debug: [18] 4 0
main debug: [19] 4 0
main debug: [20] 4 0
main debug: [21] 4 0
main debug: [22] 4 0
main debug: [23] 4 0
main debug: [24] 4 0
main debug: saving a free vout
main debug: removing module "faad"
main debug: killing decoder fourcc `mp4a', 0 PES in FIFO
main debug: removing module "scaletempo"
main debug: removing module "ugly_resampler"
main debug: removing module "auhal"
main debug: removing module "float32_mixer"
main debug: releasing aout
main debug: removing module "avcodec"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: Program doesn't contain anymore ES
main debug: thread ended
main debug: dead input
main debug: destroying useless vout
main debug: removing module "i420_yuy2_altivec"
main debug: removing module "macosx"
main debug: removing module "opengl"

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 25 Oct 2012 23:18

VLC 2.0.4

main debug: Creating an input for 'watch?v=2aCOyOvOw5c&fmt=18'
main debug: `http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' gives access `http' demux `' path `www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18'
main debug: creating demux: access='http' demux='' location='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' file='(null)'
main debug: looking for access_demux module: 0 candidates
main debug: no access_demux module matched "http"
main debug: TIMER module_need() : 0.531 ms - Total 0.531 ms / 1 intvls (Avg 0.531 ms)
main debug: creating access 'http' location='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18', path='(null)'
main debug: looking for access module: 2 candidates
access_http debug: http: server='www.youtube.com' port=80 file='/watch?v=2aCOyOvOw5c&fmt=18'
main debug: net: connecting to http://www.youtube.com port 80
main debug: connection succeeded (socket = 10)
access_http debug: protocol 'HTTP' answer code 200
access_http debug: Server: gwiseguy/2.0
access_http debug: Accepting Cookie: use_hitbox=d5c5516c3379125f43aa0d495d100d6ddAEAAAAw; path=/; domain=.youtube.com
access_http debug: Accepting Cookie: VISITOR_INFO1_LIVE=mXbI-ASYPQM; path=/; domain=.youtube.com; expires=Sat, 22-Jun-2013 20:56:08 GMT
access_http debug: Accepting Cookie: recently_watched_video_id_list=c4733c3f136e8d11f553928eb77715d8WwEAAABzCwAAADJhQ095T3ZPdzVj; path=/; domain=.youtube.com
access_http debug: Accepting Cookie: PREF=f1=50000000; path=/; domain=.youtube.com; expires=Sun, 23-Oct-2022 20:56:08 GMT
access_http debug: Content-Type: text/html; charset=utf-8
access_http debug: Connection: close
main debug: using access module "access_http"
main debug: TIMER module_need() : 518.266 ms - Total 518.266 ms / 1 intvls (Avg 518.266 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 - 3584 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.504 ms - Total 0.504 ms / 1 intvls (Avg 0.504 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.388 ms - Total 0.388 ms / 1 intvls (Avg 0.388 ms)
main debug: creating demux: access='http' demux='' location='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' file='(null)'
main debug: looking for demux module: 55 candidates
mod debug: MOD validation failed (ext=)
ts debug: TS module discarded (lost sync)
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/playlist
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_streams.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_xml.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/appletrailers.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/bbc_co_uk.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/break.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/canalplus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/cue.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/dailymotion.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/extreme.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/france2.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/googlevideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/jamendo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/joox.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/katsomo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/koreus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/lelombrik.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metacafe.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metachannels.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/mpora.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pinkbike.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pluzz.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/rockbox_fm_presets.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/soundcloud.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/vimeo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.luac
lua debug: Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.luac's probe() function was successful
main debug: using demux module "lua"
main debug: TIMER module_need() : 134.680 ms - Total 134.680 ms / 1 intvls (Avg 134.680 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 12.036 ms - Total 12.036 ms / 1 intvls (Avg 12.036 ms)
main debug: `http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' successfully opened
macosx debug: prevented sleep through IOKit (300)
lua debug: Path: http://o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063
lua debug: Name: Riding the Booster with enhanced sound
lua debug: Artist: interbartolo
lua debug: Description: From the upcoming Special Edition Ascent: Commemorating Space Shuttle DVD/BluRay by NASA/Glenn a movie from the point of view of the Solid Rocket Booster wit...
lua debug: ArtURL: http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg
main: stopping playback
main debug: deleting item `watch?v=2aCOyOvOw5c&fmt=18'
main debug: no fetch required for (null) (art currently http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg)
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
macosx debug: releasing sleep blocker (300)
main debug: dying input
main debug: control: stopping input
main debug: dying input
main debug: removing module "lua"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: dead input
main debug: processing request item: Riding the Booster with enhanced sound, node: Scaletta, skip: 0
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 1 items, index 0
main debug: starting playback of the new playlist item
main debug: resyncing on Riding the Booster with enhanced sound
main debug: Riding the Booster with enhanced sound is at 0
main debug: creating new input thread
main debug: Creating an input for 'Riding the Booster with enhanced sound'
main debug: using timeshift granularity of 50 MiB, in path '/tmp'
main debug: `http://o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063' gives access `http' demux `' path `o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063'
main debug: creating demux: access='http' demux='' location='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063' file='(null)'
main debug: looking for access_demux module: 0 candidates
main debug: no access_demux module matched "http"
main debug: TIMER module_need() : 0.450 ms - Total 0.450 ms / 1 intvls (Avg 0.450 ms)
main debug: creating access 'http' location='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063', path='(null)'
main debug: looking for access module: 2 candidates
access_http debug: http: server='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com' port=80 file='/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063'
main debug: net: connecting to o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com port 80
macosx debug: prevented sleep through IOKit (300)
main debug: connection succeeded (socket = 14)
macosx debug: releasing sleep blocker (300)
main debug: TIMER input launching for 'watch?v=2aCOyOvOw5c&fmt=18' : 673.824 ms - Total 673.824 ms / 1 intvls (Avg 673.824 ms)
access_http debug: protocol 'HTTP' answer code 206
access_http debug: Content-Type: video/mp4
access_http debug: stream size=25839735,pos=0,remaining=25839735
access_http debug: this frame size=25839735
access_http debug: Connection: close
access_http debug: Server: gvs 1.0
main debug: using access module "access_http"
main debug: TIMER module_need() : 343.341 ms - Total 343.341 ms / 1 intvls (Avg 343.341 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 - 2463 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.796 ms - Total 0.796 ms / 1 intvls (Avg 0.796 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.381 ms - Total 0.381 ms / 1 intvls (Avg 0.381 ms)
main debug: creating demux: access='http' demux='' location='o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063' file='(null)'
main debug: looking for demux module: 55 candidates
mp4 warning: MP4 plugin discarded (not fastseekable)
mod debug: MOD validation failed (ext=)
ts debug: TS module discarded (lost sync)
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/playlist
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_streams.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_xml.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/appletrailers.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/bbc_co_uk.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/break.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/canalplus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/cue.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/dailymotion.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/extreme.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/france2.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/googlevideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/jamendo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/joox.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/katsomo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/koreus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/lelombrik.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metacafe.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metachannels.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/mpora.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pinkbike.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pluzz.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/rockbox_fm_presets.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/soundcloud.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/vimeo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube_homepage.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/zapiks.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/playlist
avcodec debug: trying url: http://o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063
avcodec debug: detected format: mov,mp4,m4a,3gp,3g2,mj2
main debug: selecting program id=0
avcodec debug: adding es: video codec = h264
avcodec debug: adding es: audio codec = mp4a
avcodec debug: AVFormat supported stream
avcodec debug: - format = mov,mp4,m4a,3gp,3g2,mj2 (QuickTime / MOV)
avcodec debug: - start time = 0
avcodec debug: - duration = 511594417
main debug: using demux module "avcodec"
main debug: TIMER module_need() : 472.888 ms - Total 472.888 ms / 1 intvls (Avg 472.888 ms)
main debug: looking for decoder module: 32 candidates
avcodec debug: libavcodec initialized (interface 0x361e00)
avcodec debug: trying to use direct rendering
avcodec debug: allowing 1 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 88.891 ms - Total 88.891 ms / 1 intvls (Avg 88.891 ms)
main debug: looking for decoder module: 32 candidates
main debug: using decoder module "faad"
main debug: TIMER module_need() : 4.300 ms - Total 4.300 ms / 1 intvls (Avg 4.300 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 15.471 ms - Total 15.471 ms / 1 intvls (Avg 15.471 ms)
main debug: `http://o-o---preferred---sn-nx5cvox-hpas---v13---lscache6.c.youtube.com/videoplayback?upn=jMpv1HVzwXA&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=923410%2C927200%2C924104%2C929201%2C916611%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351220746&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351198512&ip=95.238.160.126&mv=m&source=youtube&ms=au&cp=U0hURlJNTl9JUENONF9KR1JIOm9VYUotWVFVU1FP&id=d9a08ec8ebcec397&signature=7E15DED77BCA77A698773DF19F3A160E69393236.B2CC6A3E2D8A5993B05A5B3A8A02964F152FB063' successfully opened
main debug: Buffering 0%
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
faad warning: decoded zero sample
main debug: Buffering 2%
main debug: Buffering 4%
main debug: Buffering 6%
main debug: Buffering 9%
main debug: Buffering 11%
main debug: Buffering 13%
main debug: Buffering 16%
main debug: Buffering 18%
main debug: Buffering 20%
main debug: Buffering 23%
main debug: Buffering 25%
main debug: Buffering 27%
main debug: Buffering 30%
main debug: Buffering 32%
main debug: Buffering 34%
main debug: Buffering 37%
main debug: Buffering 39%
main debug: Buffering 41%
main debug: Buffering 44%
main debug: Buffering 45%
main debug: Buffering 45%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 51%
main debug: Buffering 53%
main debug: Buffering 55%
main debug: Buffering 58%
main debug: Buffering 60%
main debug: Buffering 62%
main debug: Buffering 65%
main debug: Buffering 67%
main debug: Buffering 69%
main debug: Buffering 71%
main debug: Buffering 74%
main debug: Buffering 76%
main debug: Buffering 78%
main debug: Buffering 81%
main debug: Buffering 83%
main debug: Buffering 85%
main debug: Buffering 88%
main debug: Buffering 90%
main debug: Buffering 91%
main debug: Buffering 91%
main debug: Buffering 91%
main debug: Buffering 95%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 99%
main debug: Stream buffering done (1021 ms in 25 ms)
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 30.281 ms - Total 30.281 ms / 1 intvls (Avg 30.281 ms)
main debug: looking for video filter2 module: 13 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 7.325 ms - Total 7.325 ms / 1 intvls (Avg 7.325 ms)
main debug: looking for video filter2 module: 13 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 7.086 ms - Total 7.086 ms / 1 intvls (Avg 7.086 ms)
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module: 2 candidates
vout_macosx debug: Quartz Extreme acceleration is active
main debug: looking for vout window nsobject module: 1 candidate
macosx debug: prevented sleep through IOKit (300)
macosx debug: returning videoview with x=0, y=36, width=640, height=360
main debug: using vout window nsobject module "macosx"
main debug: TIMER module_need() : 362.598 ms - Total 362.598 ms / 1 intvls (Avg 362.598 ms)
macosx debug: releasing sleep blocker (300)
macosx debug: prevented sleep through IOKit (300)
main debug: VoutDisplayEvent 'resize' 640x360 window
main debug: using vout display module "vout_macosx"
main debug: TIMER module_need() : 635.834 ms - Total 635.834 ms / 1 intvls (Avg 635.834 ms)
main debug: A filter to adapt decoder to display is needed
main debug: looking for video filter2 module: 13 candidates
main debug: using video filter2 module "i420_yuy2_altivec"
main debug: TIMER module_need() : 0.492 ms - Total 0.492 ms / 1 intvls (Avg 0.492 ms)
main debug: Filter 'i420_yuy2_altivec' (0x965c98) appended to chain
main debug: original format sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
main debug: VoutDisplayEvent 'resize' 640x360 window
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 70.711 ms - Total 70.711 ms / 1 intvls (Avg 70.711 ms)
avcodec debug: using direct rendering
main debug: creating audio output
main debug: looking for audio output module: 1 candidate
main debug: VLC is looking for: 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
auhal debug: found 1 audio device(s)
auhal debug: DevID: 258 DevName: Audio incorporato
auhal debug: found 1 stream formats
auhal debug: supported format: [44100.000000][lpcm][30][4][1][4][2][16]
main debug: End of video preroll
auhal debug: current format is: [44100.000000][lpcm][43][4][1][4][2][32]
auhal debug: layout of AUHAL has 2 channels
auhal debug: selected 2 physical channels for device output
auhal debug: VLC will output: Stereo
auhal debug: we set the AU format: [44100.000000][lpcm][11][8][1][8][2][32]
auhal debug: the actual set AU format is [44100.000000][lpcm][11][8][1][8][2][32]
main debug: Received first picture
freetype debug: looking for Arial Unicode MS
auhal debug: analog output successfully opened
main debug: using audio output module "auhal"
main debug: TIMER module_need() : 36.504 ms - Total 36.504 ms / 1 intvls (Avg 36.504 ms)
main debug: output 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: mixer 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo
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.928 ms - Total 1.928 ms / 1 intvls (Avg 1.928 ms)
main debug: input 'f32b' 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.197 ms - Total 2.197 ms / 1 intvls (Avg 2.197 ms)
main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32b'->'f32b' 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() : 4.560 ms - Total 4.560 ms / 1 intvls (Avg 4.560 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: looking for video blending module: 1 candidate
main debug: using video blending module "blend"
main debug: TIMER module_need() : 2.232 ms - Total 2.232 ms / 1 intvls (Avg 2.232 ms)
main debug: Post-processing available
main debug: Decoder buffering done in 921 ms
main warning: PTS is out of range (-32412), dropping buffer
main warning: picture is too late to be displayed (missing 74 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main warning: PTS is out of range (-33421), dropping buffer
main warning: audio output out of sync, adjusting dates (66949 us)
main warning: not synchronized (66950 us), resampling
main warning: PTS is out of range (24819), dropping buffer
main warning: PTS is out of range (4599), dropping buffer
main warning: PTS is out of range (-15976), dropping buffer
main warning: PTS is out of range (-36146), dropping buffer
main debug: picture might be displayed late (missing 10 ms)
main warning: computed PTS is out of range (21544), clearing out
main warning: PTS is out of range (-28060), dropping buffer
main warning: computed PTS is out of range (26201), clearing out
main warning: PTS is out of range (-37169), dropping buffer
main warning: computed PTS is out of range (7386), clearing out
main warning: PTS is out of range (-15834), dropping buffer
main warning: computed PTS is out of range (9835), clearing out
main warning: PTS is out of range (-36605), dropping buffer
main warning: computed PTS is out of range (18982), clearing out
main warning: PTS is out of range (-38331), dropping buffer
main warning: computed PTS is out of range (74901), clearing out
main warning: PTS is out of range (51680), dropping buffer
main warning: computed PTS is out of range (77018), clearing out
main warning: PTS is out of range (30577), dropping buffer
main warning: computed PTS is out of range (91244), clearing out
main warning: PTS is out of range (21583), dropping buffer
main warning: computed PTS is out of range (93143), clearing out
main warning: PTS is out of range (262), dropping buffer
main warning: computed PTS is out of range (94854), clearing out
main warning: PTS is out of range (-21247), dropping buffer
main warning: computed PTS is out of range (96522), clearing out
main debug: picture might be displayed late (missing 11 ms)
main warning: computed PTS is out of range (35338), clearing out
main warning: PTS is out of range (35337), dropping buffer
main warning: computed PTS is out of range (48663), clearing out
main warning: PTS is out of range (25442), dropping buffer
main warning: computed PTS is out of range (50579), clearing out
main warning: PTS is out of range (4139), dropping buffer
main warning: computed PTS is out of range (52289), clearing out
main warning: PTS is out of range (-17371), dropping buffer
main warning: computed PTS is out of range (54127), clearing out
main warning: PTS is out of range (-38753), dropping buffer
main warning: computed PTS is out of range (56535), clearing out
main debug: picture might be displayed late (missing 3 ms)
main warning: PTS is out of range (-37573), dropping buffer
main warning: PTS is out of range (-25796), dropping buffer
main warning: computed PTS is out of range (962), clearing out
main debug: picture might be displayed late (missing 4 ms)
main warning: PTS is out of range (-24617), dropping buffer
main debug: picture might be displayed late (missing 0 ms)
main warning: computed PTS is out of range (16730), clearing out
main warning: PTS is out of range (-6491), dropping buffer
main warning: computed PTS is out of range (18945), clearing out
main warning: PTS is out of range (-27496), dropping buffer
main warning: computed PTS is out of range (21026), clearing out
main warning: picture is too late to be displayed (missing 35 ms)
main warning: PTS is out of range (-35530), dropping buffer
main warning: audio output out of sync, adjusting dates (66892 us)
main warning: not synchronized (66892 us), resampling
main warning: PTS is out of range (-21143), dropping buffer
main warning: PTS is out of range (-39465), dropping buffer
main warning: computed PTS is out of range (4594), clearing out
main warning: PTS is out of range (1826), dropping buffer
main warning: computed PTS is out of range (9113), clearing out
main warning: PTS is out of range (-16875), dropping buffer
main warning: computed PTS is out of range (13714), clearing out
main warning: PTS is out of range (-35494), dropping buffer
main warning: computed PTS is out of range (15493), clearing out
main warning: PTS is out of range (-25835), dropping buffer
main warning: audio output out of sync, adjusting dates (64624 us)
main warning: PTS is out of range (-27130), dropping buffer
main warning: buffer too early (-46440), down-sampling
main warning: not synchronized (64625 us), resampling
main warning: resampling stopped after 65807 usec (drift: -18208)
main warning: PTS is out of range (-34824), dropping buffer
main warning: audio output out of sync, adjusting dates (68574 us)
main warning: not synchronized (68574 us), resampling
main warning: buffer too late (63561), up-sampling
main warning: PTS is out of range (-30720), dropping buffer
main warning: PTS is out of range (-24757), dropping buffer
main warning: PTS is out of range (-34166), dropping buffer
main warning: audio output out of sync, adjusting dates (69911 us)
main warning: not synchronized (69911 us), resampling
main warning: PTS is out of range (-36907), dropping buffer
main warning: PTS is out of range (-32185), dropping buffer
main warning: PTS is out of range (-36728), dropping buffer
main debug: audio output is too slow (32070 us): trashing 46440 us
main warning: computed PTS is out of range (25799), clearing out
main warning: PTS is out of range (-9799), dropping buffer
main warning: computed PTS is out of range (37205), clearing out
main warning: PTS is out of range (-21613), dropping buffer
main warning: computed PTS is out of range (47329), clearing out
main warning: PTS is out of range (-34709), dropping buffer
main warning: computed PTS is out of range (51897), clearing out
main warning: PTS is out of range (-26998), dropping buffer
main debug: audio output is too slow (14660 us): trashing 46440 us
main warning: PTS is out of range (-25245), dropping buffer
main warning: computed PTS is out of range (17832), clearing out
main warning: PTS is out of range (-28609), dropping buffer
main warning: computed PTS is out of range (21382), clearing out
main warning: PTS is out of range (-33280), dropping buffer
main warning: PTS is out of range (-28056), dropping buffer
main warning: buffer too early (-46440), down-sampling
main debug: audio output is too slow (14016 us): trashing 46440 us
main debug: audio output is too slow (2462 us): trashing 46440 us
main warning: computed PTS is out of range (34396), clearing out
main warning: timing screwed, stopping resampling
main warning: PTS is out of range (-11999), dropping buffer
main warning: computed PTS is out of range (36421), clearing out
main warning: PTS is out of range (-33194), dropping buffer
main warning: computed PTS is out of range (37890), clearing out
main warning: PTS is out of range (-33419), dropping buffer
main warning: computed PTS is out of range (9162), clearing out
main warning: PTS is out of range (-14058), dropping buffer
main warning: computed PTS is out of range (14936), clearing out
main warning: PTS is out of range (-31504), dropping buffer
main warning: computed PTS is out of range (25197), clearing out
main warning: PTS is out of range (-39912), dropping buffer
main warning: computed PTS is out of range (15669), clearing out
main warning: PTS is out of range (-7552), dropping buffer
main warning: computed PTS is out of range (25303), clearing out
main warning: PTS is out of range (-21138), dropping buffer
main warning: computed PTS is out of range (27110), clearing out
main warning: PTS is out of range (-39896), dropping buffer
main warning: PTS is out of range (-37670), dropping buffer
main debug: audio output is too slow (2520 us): trashing 46440 us
main warning: buffer too early (-46440), down-sampling
main debug: audio output is too slow (2556 us): trashing 46440 us
main warning: computed PTS is out of range (18553), clearing out
main warning: timing screwed, stopping resampling
main warning: PTS is out of range (-27842), dropping buffer
main warning: computed PTS is out of range (20540), clearing out
main warning: PTS is out of range (-38497), dropping buffer
main debug: audio output is too slow (25785 us): trashing 46440 us
main warning: computed PTS is out of range (61676), clearing out
main warning: PTS is out of range (38456), dropping buffer
main warning: computed PTS is out of range (99383), clearing out
main warning: PTS is out of range (52943), dropping buffer
main warning: computed PTS is out of range (103624), clearing out
main warning: PTS is out of range (33964), dropping buffer
main warning: computed PTS is out of range (107703), clearing out
main warning: PTS is out of range (14823), dropping buffer
main warning: computed PTS is out of range (114154), clearing out
main warning: PTS is out of range (-1946), dropping buffer
main warning: computed PTS is out of range (137442), clearing out
main warning: PTS is out of range (-1878), dropping buffer
main warning: computed PTS is out of range (139118), clearing out
main warning: PTS is out of range (-23422), dropping buffer
main warning: computed PTS is out of range (140933), clearing out
main debug: audio output is too slow (57273 us): trashing 46439 us
main warning: computed PTS is out of range (50050), clearing out
main warning: PTS is out of range (50049), dropping buffer
main warning: computed PTS is out of range (68783), clearing out
main warning: PTS is out of range (45562), dropping buffer
main warning: computed PTS is out of range (81910), clearing out
main warning: PTS is out of range (35469), dropping buffer
main warning: computed PTS is out of range (99844), clearing out
main warning: PTS is out of range (30183), dropping buffer
main warning: computed PTS is out of range (120611), clearing out
main warning: PTS is out of range (27730), dropping buffer
main warning: computed PTS is out of range (136836), clearing out
main warning: PTS is out of range (20735), dropping buffer
main warning: computed PTS is out of range (139443), clearing out
main warning: PTS is out of range (122), dropping buffer
main warning: computed PTS is out of range (141333), clearing out
main warning: PTS is out of range (-21208), dropping buffer
main warning: computed PTS is out of range (142902), clearing out
main warning: PTS is out of range (-11650), dropping buffer
main warning: PTS is out of range (-32507), dropping buffer
main warning: buffer too early (-46440), down-sampling
main debug: audio output is too slow (10812 us): trashing 46439 us
main warning: computed PTS is out of range (80780), clearing out
main warning: timing screwed, stopping resampling
main warning: PTS is out of range (34340), dropping buffer
main warning: computed PTS is out of range (89960), clearing out
main warning: PTS is out of range (20300), dropping buffer
main warning: computed PTS is out of range (119772), clearing out
main warning: PTS is out of range (26892), dropping buffer
main warning: computed PTS is out of range (126654), clearing out
main warning: PTS is out of range (10554), dropping buffer
main warning: computed PTS is out of range (128446), clearing out
main warning: PTS is out of range (-10874), dropping buffer
main warning: computed PTS is out of range (130695), clearing out
main warning: PTS is out of range (-31845), dropping buffer
main warning: computed PTS is out of range (132516), clearing out
main warning: picture is too late to be displayed (missing 34 ms)
main warning: PTS is out of range (-6341), dropping buffer
main warning: PTS is out of range (-27710), dropping buffer
main warning: buffer too early (-46441), down-sampling
main warning: computed PTS is out of range (32143), clearing out
main warning: timing screwed, stopping resampling
main warning: PTS is out of range (-14298), dropping buffer
main warning: computed PTS is out of range (34179), clearing out
main warning: PTS is out of range (-35482), dropping buffer
main warning: computed PTS is out of range (35640), clearing out
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
main debug: control: stopping input
main debug: dying input
macosx debug: releasing sleep blocker (300)
main warning: PTS is out of range (-20785), dropping buffer
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 2 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "faad"
main debug: killing decoder fourcc `mp4a', 59 PES in FIFO
main debug: removing module "scaletempo"
main debug: removing module "samplerate"
main debug: removing module "auhal"
main debug: removing module "float32_mixer"
main debug: releasing audio output
main debug: removing module "avcodec"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: destroying useless vout
main debug: removing module "blend"
main debug: Filter 0x965c98 removed from chain
main debug: removing module "i420_yuy2_altivec"
main debug: removing module "vout_macosx"
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"

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: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby Jean-Baptiste Kempf » 26 Oct 2012 15:49

Try to deactivate audio in preferences.
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.

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 26 Oct 2012 17:58

With audio disabled things got a bit better.

CPU load:
1.1.12 --> ~ 25%
2.0.4 --> ~ 45%

Here are the logs:

VLC 1.1.12

main debug: Activated
main debug: looking for services probe module: 5 candidates
main debug: no services probe module matching "any" could be loaded
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 0 items, index -1
main debug: TIMER module_need() : 14.954 ms - Total 14.954 ms / 1 intvls (Avg 14.954 ms)
macosx debug: using Leopard AR cookies
main debug: adding item `watch?v=2aCOyOvOw5c&fmt=18' ( http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18 )
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 1 items, index -1
main debug: processing request item null node Scaletta skip 0
main debug: starting new item
main debug: creating new input thread
main debug: Creating an input for 'watch?v=2aCOyOvOw5c&fmt=18'
main debug: thread (input) created at priority 22 (../../src/input/input.c:220)
main debug: thread started
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path '/tmp'
main debug: `http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' gives access `http' demux `' path `www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18'
main debug: creating demux: access='http' demux='' path='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18'
main debug: looking for access_demux module: 0 candidates
main debug: no access_demux module matched "http"
main debug: TIMER module_need() : 0.620 ms - Total 0.620 ms / 1 intvls (Avg 0.620 ms)
main debug: creating access 'http' path='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18'
main debug: looking for access module: 2 candidates
access_http debug: http: server='www.youtube.com' port=80 file='/watch?v=2aCOyOvOw5c&fmt=18'
main debug: net: connecting to www.youtube.com port 80
macosx debug: input has changed, refreshing interface
main debug: connection succeeded (socket = 8)
access_http debug: protocol 'HTTP' answer code 200
access_http debug: Server: gwiseguy/2.0
access_http debug: Accepting Cookie: use_hitbox=d5c5516c3379125f43aa0d495d100d6ddAEAAAAw; path=/; domain=.youtube.com
access_http debug: Accepting Cookie: VISITOR_INFO1_LIVE=1MlkalUyDnQ; path=/; domain=.youtube.com; expires=Sun, 23-Jun-2013 15:41:21 GMT
access_http debug: Accepting Cookie: recently_watched_video_id_list=c4733c3f136e8d11f553928eb77715d8WwEAAABzCwAAADJhQ095T3ZPdzVj; path=/; domain=.youtube.com
access_http debug: Accepting Cookie: PREF=f1=50000000; path=/; domain=.youtube.com; expires=Mon, 24-Oct-2022 15:41:21 GMT
access_http debug: Content-Type: text/html; charset=utf-8
access_http debug: Transfer-Encoding: chunked
main debug: using access module "access_http"
main debug: TIMER module_need() : 833.989 ms - Total 833.989 ms / 1 intvls (Avg 833.989 ms)
main debug: Using AStream*Stream
main debug: pre buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 3125 KiB/s
main debug: looking for stream_filter module: 5 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.628 ms - Total 0.628 ms / 1 intvls (Avg 0.628 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.435 ms - Total 0.435 ms / 1 intvls (Avg 0.435 ms)
main debug: creating demux: access='http' demux='' path='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18'
main debug: looking for demux module: 52 candidates
avcodec debug: couldn't guess format
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/playlist
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_streams.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_xml.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/appletrailers.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/bbc_co_uk.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/break.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/canalplus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/cue.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/dailymotion.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/france2.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/googlevideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/jamendo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/joox.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/katsomo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/koreus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/lelombrik.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/megavideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metacafe.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metachannels.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/mpora.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pinkbike.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/rockbox_fm_presets.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.lua
lua debug: Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.lua's probe() function was successful
main debug: using demux module "lua"
main debug: TIMER module_need() : 439.699 ms - Total 439.699 ms / 1 intvls (Avg 439.699 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 3.454 ms - Total 3.454 ms / 1 intvls (Avg 3.454 ms)
main debug: `http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' successfully opened
lua debug: Path: http://o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788
lua debug: Name: Riding the Booster with enhanced sound
lua debug: Artist: interbartolo
lua debug: Description: From the upcoming Special Edition Ascent: Commemorating Space Shuttle DVD/BluRay by NASA/Glenn a movie from the point of view of the Solid Rocket Booster wit...
lua debug: ArtURL: http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg
main: stopping playback
main debug: deleting item `watch?v=2aCOyOvOw5c&fmt=18'
main debug: no fetch required for (null) (art currently http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg)
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
main debug: dying input
main debug: control type=0
main debug: control: stopping input
main debug: dying input
main debug: removing module "lua"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: dead input
main debug: thread ended
main debug: processing request item Riding the Booster with enhanced sound node Scaletta skip 0
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 1 items, index 0
main debug: starting new item
main debug: creating new input thread
main debug: Creating an input for 'Riding the Booster with enhanced sound'
main debug: thread (input) created at priority 22 (../../src/input/input.c:220)
main debug: thread started
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path '/tmp'
main debug: `http://o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788' gives access `http' demux `' path `o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788'
main debug: creating demux: access='http' demux='' path='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788'
main debug: looking for access_demux module: 0 candidates
main debug: no access_demux module matched "http"
main debug: TIMER module_need() : 0.595 ms - Total 0.595 ms / 1 intvls (Avg 0.595 ms)
main debug: creating access 'http' path='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788'
main debug: looking for access module: 2 candidates
access_http debug: http: server='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com' port=80 file='/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788'
main debug: net: connecting to o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com port 80
macosx debug: input has stopped, refreshing interface
main debug: TIMER input launching for 'watch?v=2aCOyOvOw5c&fmt=18' : 1283.107 ms - Total 1283.107 ms / 1 intvls (Avg 1283.107 ms)
macosx debug: input has changed, refreshing interface
main debug: connection succeeded (socket = 8)
access_http debug: protocol 'HTTP' answer code 206
access_http debug: Content-Type: video/mp4
access_http debug: stream size=25839735,pos=0,remaining=25839735
access_http debug: this frame size=25839735
access_http debug: Connection: close
access_http debug: Server: gvs 1.0
main debug: using access module "access_http"
main debug: TIMER module_need() : 1075.558 ms - Total 1075.558 ms / 1 intvls (Avg 1075.558 ms)
main debug: Using AStream*Stream
main debug: pre buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 2109 KiB/s
main debug: looking for stream_filter module: 5 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.716 ms - Total 0.716 ms / 1 intvls (Avg 0.716 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.433 ms - Total 0.433 ms / 1 intvls (Avg 0.433 ms)
main debug: creating demux: access='http' demux='' path='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788'
main debug: looking for demux module: 52 candidates
mp4 warning: MP4 plugin discarded (not fastseekable)
avcodec debug: detected format: mov,mp4,m4a,3gp,3g2,mj2
main debug: selecting program id=0
avcodec debug: adding es: video codec = h264
avcodec debug: adding es: audio codec = mp4a
avcodec debug: AVFormat supported stream
avcodec debug: - format = mov,mp4,m4a,3gp,3g2,mj2 (QuickTime/MPEG-4/Motion JPEG 2000 format)
avcodec debug: - start time = 0
avcodec debug: - duration = 511594417
main debug: using demux module "avcodec"
main debug: TIMER module_need() : 1269.203 ms - Total 1269.203 ms / 1 intvls (Avg 1269.203 ms)
main debug: looking for decoder module: 33 candidates
avcodec debug: libavcodec initialized (interface 0x350500)
avcodec debug: trying to use direct rendering
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 63.106 ms - Total 63.106 ms / 1 intvls (Avg 63.106 ms)
main debug: thread (decoder) created at priority 0 (../../src/input/decoder.c:301)
main debug: thread started
main debug: audio is disabled, not selecting ES 0x1
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 3.474 ms - Total 3.474 ms / 1 intvls (Avg 3.474 ms)
main debug: `http://o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=M1TEa79dfkg&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=906357%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266013&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVKa2x4allkQW5T&id=d9a08ec8ebcec397&signature=C31C1AE88805912D18A60E33279C6D2FC46ADC99.9EEA213EBBEEB23F5D2C2E1B3DC7F7BBE3CF7788' successfully opened
main debug: Buffering 0%
main debug: Buffering 3%
main debug: Buffering 6%
main debug: Buffering 10%
main debug: Buffering 13%
main debug: Buffering 17%
main debug: Buffering 20%
main debug: Buffering 24%
main debug: Buffering 27%
main debug: Buffering 31%
main debug: Buffering 34%
main debug: Buffering 38%
main debug: Buffering 41%
main debug: Buffering 45%
main debug: Buffering 48%
main debug: Buffering 52%
main debug: Buffering 55%
main debug: Buffering 59%
main debug: Buffering 62%
main debug: Buffering 66%
main debug: Buffering 69%
main debug: Buffering 72%
main debug: Buffering 76%
main debug: Buffering 79%
main debug: Buffering 83%
main debug: Buffering 86%
main debug: Buffering 90%
main debug: Buffering 93%
main debug: Buffering 97%
main debug: Stream buffering done (1209 ms in 4 ms)
main debug: no usable vout present, spawning one
main debug: looking for text renderer module: 3 candidates
main debug: using text renderer module "quartztext"
main debug: TIMER module_need() : 2.787 ms - Total 2.787 ms / 1 intvls (Avg 2.787 ms)
main debug: looking for video filter2 module: 13 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 6.721 ms - Total 6.721 ms / 1 intvls (Avg 6.721 ms)
main debug: looking for video filter2 module: 13 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 8.317 ms - Total 8.317 ms / 1 intvls (Avg 8.317 ms)
main debug: window size: 640x360
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: looking for video output module: 2 candidates
main debug: looking for opengl provider module: 2 candidates
macosx debug: display is Quartz Extreme accelerated
main debug: using opengl provider module "macosx"
main debug: TIMER module_need() : 201.063 ms - Total 201.063 ms / 1 intvls (Avg 201.063 ms)
main debug: using video output module "opengl"
main debug: TIMER module_need() : 203.441 ms - Total 203.441 ms / 1 intvls (Avg 203.441 ms)
main debug: got 1 direct buffer(s)
main debug: pic render sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic in sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic out sz 640x360, of (0,0), vsz 640x360, 4cc YUY2, sar 1:1, msk r0x0 g0x0 b0x0
main debug: looking for video filter2 module: 13 candidates
main debug: using video filter2 module "i420_yuy2_altivec"
main debug: TIMER module_need() : 0.680 ms - Total 0.680 ms / 1 intvls (Avg 0.680 ms)
main debug: indirect render, mapping render pictures 0-24 to system pictures 1-25
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
main debug: Decoder buffering done in 279 ms
main debug: Post-processing available
main warning: vlc_object_find_name(postproc) is not safe!
main debug: looking for video blending module: 1 candidate
blend debug: chroma: YUVA -> YUY2
main debug: using video blending module "blend"
main debug: TIMER module_need() : 2.989 ms - Total 2.989 ms / 1 intvls (Avg 2.989 ms)
main warning: late picture skipped (10000 > -22623)
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
main debug: control type=0
main debug: control: stopping input
macosx debug: input has stopped, refreshing interface
main debug: dying input
macosx debug: input has changed, refreshing interface
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
macosx debug: input has stopped, refreshing interface
main debug: removing module "avcodec"
main debug: killing decoder fourcc `h264', 8 PES in FIFO
main debug: [0] 4 0
main debug: [1] 4 0
main debug: [2] 4 0
main debug: [3] 4 0
main debug: [4] 4 0
main debug: [5] 4 0
main debug: [6] 4 0
main debug: [7] 4 0
main debug: [8] 4 0
main debug: [9] 2 0
main debug: [10] 4 0
main debug: [11] 4 0
main debug: [12] 4 0
main debug: [13] 4 0
main debug: [14] 4 0
main debug: [15] 4 0
main debug: [16] 4 0
main debug: [17] 4 0
main debug: [18] 4 0
main debug: [19] 4 0
main debug: [20] 4 0
main debug: [21] 4 0
main debug: [22] 4 0
main debug: [23] 4 0
main debug: [24] 4 0
main debug: saving a free vout
main debug: removing module "avcodec"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: Program doesn't contain anymore ES
main debug: thread ended
main debug: dead input
main debug: destroying useless vout
main debug: removing module "i420_yuy2_altivec"
main debug: removing module "macosx"
main debug: removing module "opengl"

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 26 Oct 2012 17:59

VLC 2.0.4

main debug: using interface module "macosx"
main debug: TIMER module_need() : 510.436 ms - Total 510.436 ms / 1 intvls (Avg 510.436 ms)
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 0 items, index -1
main debug: playlist threads correctly activated
main debug: looking for services probe module: 5 candidates
main debug: no services probe module matching "any" could be loaded
main debug: TIMER module_need() : 24.259 ms - Total 24.259 ms / 1 intvls (Avg 24.259 ms)
macosx debug: using Leopard AR cookies
main debug: looking for extension module: 1 candidate
lua debug: Opening Lua Extension module
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/extensions
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/extensions
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/extensions
main debug: using extension module "lua"
main debug: TIMER module_need() : 13.333 ms - Total 13.333 ms / 1 intvls (Avg 13.333 ms)
macosx debug: Found 0 capture devices
main debug: adding item `watch?v=2aCOyOvOw5c&fmt=18' ( http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18 )
main debug: no fetch required for (null) (art currently (null))
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 1 items, index -1
macosx debug: no optical media found
main debug: processing request item: watch?v=2aCOyOvOw5c&fmt=18, node: Scaletta, skip: 0
main debug: resyncing on watch?v=2aCOyOvOw5c&fmt=18
main debug: watch?v=2aCOyOvOw5c&fmt=18 is at 0
main debug: starting playback of the new playlist item
main debug: resyncing on watch?v=2aCOyOvOw5c&fmt=18
main debug: watch?v=2aCOyOvOw5c&fmt=18 is at 0
main debug: creating new input thread
main debug: Creating an input for 'watch?v=2aCOyOvOw5c&fmt=18'
main debug: using timeshift granularity of 50 MiB, in path '/tmp'
main debug: `http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' gives access `http' demux `' path `www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18'
main debug: creating demux: access='http' demux='' location='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' file='(null)'
main debug: looking for access_demux module: 0 candidates
main debug: no access_demux module matched "http"
main debug: TIMER module_need() : 0.537 ms - Total 0.537 ms / 1 intvls (Avg 0.537 ms)
main debug: creating access 'http' location='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18', path='(null)'
main debug: looking for access module: 2 candidates
access_http debug: http: server='www.youtube.com' port=80 file='/watch?v=2aCOyOvOw5c&fmt=18'
main debug: net: connecting to www.youtube.com port 80
main debug: connection succeeded (socket = 10)
access_http debug: protocol 'HTTP' answer code 200
access_http debug: Server: gwiseguy/2.0
access_http debug: Accepting Cookie: use_hitbox=d5c5516c3379125f43aa0d495d100d6ddAEAAAAw; path=/; domain=.youtube.com
access_http debug: Accepting Cookie: VISITOR_INFO1_LIVE=1GrcXv_ANHA; path=/; domain=.youtube.com; expires=Sun, 23-Jun-2013 15:48:25 GMT
access_http debug: Accepting Cookie: recently_watched_video_id_list=c4733c3f136e8d11f553928eb77715d8WwEAAABzCwAAADJhQ095T3ZPdzVj; path=/; domain=.youtube.com
access_http debug: Accepting Cookie: PREF=f1=50000000; path=/; domain=.youtube.com; expires=Mon, 24-Oct-2022 15:48:25 GMT
access_http debug: Content-Type: text/html; charset=utf-8
access_http debug: Connection: close
main debug: using access module "access_http"
main debug: TIMER module_need() : 414.947 ms - Total 414.947 ms / 1 intvls (Avg 414.947 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 - 4048 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.561 ms - Total 0.561 ms / 1 intvls (Avg 0.561 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.347 ms - Total 0.347 ms / 1 intvls (Avg 0.347 ms)
main debug: creating demux: access='http' demux='' location='www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' file='(null)'
main debug: looking for demux module: 55 candidates
mod debug: MOD validation failed (ext=)
ts debug: TS module discarded (lost sync)
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/playlist
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_streams.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_xml.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/appletrailers.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/bbc_co_uk.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/break.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/canalplus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/cue.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/dailymotion.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/extreme.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/france2.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/googlevideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/jamendo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/joox.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/katsomo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/koreus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/lelombrik.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metacafe.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metachannels.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/mpora.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pinkbike.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pluzz.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/rockbox_fm_presets.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/soundcloud.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/vimeo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.luac
lua debug: Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.luac's probe() function was successful
main debug: using demux module "lua"
main debug: TIMER module_need() : 87.792 ms - Total 87.792 ms / 1 intvls (Avg 87.792 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 3.348 ms - Total 3.348 ms / 1 intvls (Avg 3.348 ms)
main debug: `http://www.youtube.com/watch?v=2aCOyOvOw5c&fmt=18' successfully opened
macosx debug: prevented sleep through IOKit (300)
lua debug: Path: http://o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888
lua debug: Name: Riding the Booster with enhanced sound
lua debug: Artist: interbartolo
lua debug: Description: From the upcoming Special Edition Ascent: Commemorating Space Shuttle DVD/BluRay by NASA/Glenn a movie from the point of view of the Solid Rocket Booster wit...
lua debug: ArtURL: http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg
main: stopping playback
main debug: deleting item `watch?v=2aCOyOvOw5c&fmt=18'
main debug: no fetch required for (null) (art currently http://i3.ytimg.com/vi/2aCOyOvOw5c/mqdefault.jpg)
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
macosx debug: releasing sleep blocker (300)
main debug: dying input
main debug: control: stopping input
main debug: dying input
main debug: removing module "lua"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: dead input
main debug: processing request item: Riding the Booster with enhanced sound, node: Scaletta, skip: 0
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 1 items, index 0
main debug: starting playback of the new playlist item
main debug: resyncing on Riding the Booster with enhanced sound
main debug: Riding the Booster with enhanced sound is at 0
main debug: creating new input thread
main debug: Creating an input for 'Riding the Booster with enhanced sound'
main debug: using timeshift granularity of 50 MiB, in path '/tmp'
main debug: `http://o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888' gives access `http' demux `' path `o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888'
main debug: creating demux: access='http' demux='' location='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888' file='(null)'
main debug: looking for access_demux module: 0 candidates
main debug: no access_demux module matched "http"
main debug: TIMER module_need() : 0.450 ms - Total 0.450 ms / 1 intvls (Avg 0.450 ms)
main debug: creating access 'http' location='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888', path='(null)'
main debug: looking for access module: 2 candidates
access_http debug: http: server='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com' port=80 file='/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888'
main debug: net: connecting to o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com port 80
macosx debug: prevented sleep through IOKit (300)
main debug: connection succeeded (socket = 10)
access_http debug: protocol 'HTTP' answer code 206
access_http debug: Content-Type: video/mp4
access_http debug: stream size=25839735,pos=0,remaining=25839735
access_http debug: this frame size=25839735
access_http debug: Connection: close
access_http debug: Server: gvs 1.0
main debug: using access module "access_http"
main debug: TIMER module_need() : 244.962 ms - Total 244.962 ms / 1 intvls (Avg 244.962 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 - 2724 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.755 ms - Total 0.755 ms / 1 intvls (Avg 0.755 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.378 ms - Total 0.378 ms / 1 intvls (Avg 0.378 ms)
main debug: creating demux: access='http' demux='' location='o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888' file='(null)'
main debug: looking for demux module: 55 candidates
mp4 warning: MP4 plugin discarded (not fastseekable)
mod debug: MOD validation failed (ext=)
ts debug: TS module discarded (lost sync)
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/playlist
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_streams.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/anevia_xml.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/appletrailers.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/bbc_co_uk.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/break.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/canalplus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/cue.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/dailymotion.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/extreme.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/france2.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/googlevideo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/jamendo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/joox.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/katsomo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/koreus.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/lelombrik.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metacafe.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/metachannels.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/mpora.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pinkbike.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/pluzz.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/rockbox_fm_presets.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/soundcloud.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/vimeo.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/youtube_homepage.luac
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/playlist/zapiks.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/playlist
macosx debug: releasing sleep blocker (300)
avcodec debug: trying url: http://o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888
avcodec debug: detected format: mov,mp4,m4a,3gp,3g2,mj2
main debug: TIMER input launching for 'watch?v=2aCOyOvOw5c&fmt=18' : 511.738 ms - Total 511.738 ms / 1 intvls (Avg 511.738 ms)
main debug: selecting program id=0
avcodec debug: adding es: video codec = h264
avcodec debug: adding es: audio codec = mp4a
avcodec debug: AVFormat supported stream
avcodec debug: - format = mov,mp4,m4a,3gp,3g2,mj2 (QuickTime / MOV)
avcodec debug: - start time = 0
avcodec debug: - duration = 511594417
main debug: using demux module "avcodec"
main debug: TIMER module_need() : 468.945 ms - Total 468.945 ms / 1 intvls (Avg 468.945 ms)
main debug: looking for decoder module: 32 candidates
avcodec debug: libavcodec initialized (interface 0x361e00)
avcodec debug: trying to use direct rendering
avcodec debug: allowing 1 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 28.807 ms - Total 28.807 ms / 1 intvls (Avg 28.807 ms)
main debug: audio is disabled, not selecting ES 0x1
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 3.894 ms - Total 3.894 ms / 1 intvls (Avg 3.894 ms)
main debug: `http://o-o---preferred---sn-nx5cvox-hpak---v13---lscache6.c.youtube.com/videoplayback?upn=41MeulcRJrk&sparams=cp%2Cid%2Cip%2Cipbits%2Citag%2Cratebypass%2Csource%2Cupn%2Cexpire&fexp=924801%2C913543%2C922401%2C920704%2C912806%2C927201%2C925003%2C913546%2C913556%2C920201%2C900816%2C911112%2C901451&key=yt1&expire=1351289146&itag=18&ipbits=8&sver=3&ratebypass=yes&mt=1351266492&ip=95.235.168.15&mv=m&source=youtube&ms=au&cp=U0hURlJTV19JUENONF9KTUFCOjVEcWRVdF9GS0hD&id=d9a08ec8ebcec397&signature=957BEFE9AE3EAF0C4EF091510B47632F8CC3F593.38CA7E4FFFCB479359FA333E5FDE409099D71888' successfully opened
main debug: Buffering 0%
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 2%
main debug: Buffering 4%
main debug: Buffering 6%
main debug: Buffering 9%
main debug: Buffering 11%
main debug: Buffering 13%
main debug: Buffering 16%
main debug: Buffering 18%
main debug: Buffering 20%
main debug: Buffering 23%
main debug: Buffering 25%
main debug: Buffering 27%
main debug: Buffering 30%
main debug: Buffering 32%
main debug: Buffering 34%
main debug: Buffering 37%
main debug: Buffering 39%
main debug: Buffering 41%
main debug: Buffering 44%
main debug: Buffering 45%
main debug: Buffering 45%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 51%
main debug: Buffering 53%
main debug: Buffering 55%
main debug: Buffering 58%
main debug: Buffering 60%
main debug: Buffering 62%
main debug: Buffering 65%
main debug: Buffering 67%
main debug: Buffering 69%
main debug: Buffering 71%
main debug: Buffering 74%
main debug: Buffering 76%
main debug: Buffering 78%
main debug: Buffering 81%
main debug: Buffering 83%
main debug: Buffering 85%
main debug: Buffering 88%
main debug: Buffering 90%
main debug: Buffering 91%
main debug: Buffering 91%
main debug: Buffering 91%
main debug: Buffering 95%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 97%
main debug: Buffering 99%
main debug: Stream buffering done (1021 ms in 21 ms)
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 68.707 ms - Total 68.707 ms / 1 intvls (Avg 68.707 ms)
main debug: looking for video filter2 module: 13 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 11.122 ms - Total 11.122 ms / 1 intvls (Avg 11.122 ms)
main debug: looking for video filter2 module: 13 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 10.013 ms - Total 10.013 ms / 1 intvls (Avg 10.013 ms)
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module: 2 candidates
vout_macosx debug: Quartz Extreme acceleration is active
main debug: looking for vout window nsobject module: 1 candidate
macosx debug: prevented sleep through IOKit (300)
macosx debug: returning videoview with x=0, y=36, width=640, height=360
main debug: using vout window nsobject module "macosx"
main debug: TIMER module_need() : 288.743 ms - Total 288.743 ms / 1 intvls (Avg 288.743 ms)
macosx debug: releasing sleep blocker (300)
macosx debug: prevented sleep through IOKit (300)
main debug: VoutDisplayEvent 'resize' 640x360 window
main debug: using vout display module "vout_macosx"
main debug: TIMER module_need() : 567.989 ms - Total 567.989 ms / 1 intvls (Avg 567.989 ms)
main debug: A filter to adapt decoder to display is needed
main debug: looking for video filter2 module: 13 candidates
main debug: using video filter2 module "i420_yuy2_altivec"
main debug: TIMER module_need() : 0.533 ms - Total 0.533 ms / 1 intvls (Avg 0.533 ms)
main debug: Filter 'i420_yuy2_altivec' (0x957698) appended to chain
main debug: original format sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
main debug: VoutDisplayEvent 'resize' 640x360 window
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 65.943 ms - Total 65.943 ms / 1 intvls (Avg 65.943 ms)
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: looking for video blending module: 1 candidate
main debug: using video blending module "blend"
main debug: TIMER module_need() : 2.482 ms - Total 2.482 ms / 1 intvls (Avg 2.482 ms)
main debug: Post-processing available
main debug: Decoder buffering done in 896 ms
main debug: picture might be displayed late (missing 13 ms)
main debug: picture might be displayed late (missing 0 ms)
main debug: incoming request - stopping current input
main debug: waitpipe: object killed
main debug: dying input
main debug: control: stopping input
main debug: dying input
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 2 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "avcodec"
main debug: removing module "stream_filter_record"
main debug: removing module "access_http"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: destroying useless vout
main debug: removing module "blend"
main debug: Filter 0x957698 removed from chain
main debug: removing module "i420_yuy2_altivec"
main debug: removing module "vout_macosx"
macosx debug: releasing sleep blocker (300)
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"

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: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby Jean-Baptiste Kempf » 27 Oct 2012 18:04

Could you do the same with a LOCAL file?
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.

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 27 Oct 2012 21:58

This is for VLC 2.0.4, playing a local MP4 (h264) file. Please tell me if you need the same data for version 1.1.12. Once again playback was stopped after 20 seconds in order to get complete logs with audio enabled.

CPU load:

Audio enabled --> 80 - 85 %
Audio disabled --> ~ 50%

Logs:

Audio enabled

main debug: using interface module "macosx"
main debug: TIMER module_need() : 516.939 ms - Total 516.939 ms / 1 intvls (Avg 516.939 ms)
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 0 items, index -1
main debug: playlist threads correctly activated
main debug: looking for services probe module: 5 candidates
main debug: no services probe module matching "any" could be loaded
main debug: TIMER module_need() : 13.449 ms - Total 13.449 ms / 1 intvls (Avg 13.449 ms)
macosx debug: using Leopard AR cookies
main debug: looking for extension module: 1 candidate
lua debug: Opening Lua Extension module
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/extensions
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/extensions
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/extensions
main debug: using extension module "lua"
main debug: TIMER module_need() : 7.340 ms - Total 7.340 ms / 1 intvls (Avg 7.340 ms)
macosx debug: Found 0 capture devices
macosx debug: no optical media found
main debug: adding item `Litter Genie- I Haz a Catnip in Mah Head.mp4' ( file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4 )
main debug: rebuilding array of current - root Scaletta
main debug: Creating an input for 'Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: rebuild done - 1 items, index -1
main debug: processing request item: Litter Genie- I Haz a Catnip in Mah Head.mp4, node: Scaletta, skip: 0
main debug: resyncing on Litter Genie- I Haz a Catnip in Mah Head.mp4
main debug: Litter Genie- I Haz a Catnip in Mah Head.mp4 is at 0
main debug: starting playback of the new playlist item
main debug: resyncing on Litter Genie- I Haz a Catnip in Mah Head.mp4
main debug: Litter Genie- I Haz a Catnip in Mah Head.mp4 is at 0
main debug: creating new input thread
main debug: Creating an input for 'Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: no fetch required for (null) (art currently (null))
main debug: no fetch required for (null) (art currently (null))
main debug: using timeshift granularity of 50 MiB, in path '/tmp'
main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' gives access `file' demux `' path `/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4'
main debug: creating demux: access='file' demux='' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' file='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
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.648 ms - Total 0.648 ms / 1 intvls (Avg 0.648 ms)
main debug: creating access 'file' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4', path='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: looking for access module: 3 candidates
filesystem debug: opening file `/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.569 ms - Total 0.569 ms / 1 intvls (Avg 0.569 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 - 4784 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.393 ms - Total 0.393 ms / 1 intvls (Avg 0.393 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.318 ms - Total 0.318 ms / 1 intvls (Avg 0.318 ms)
main debug: creating demux: access='file' demux='' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' file='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: looking for demux module: 55 candidates
mp4 warning: unknown box type btrt (incompletely loaded)
mp4 warning: unknown box type gsst (incompletely loaded)
mp4 warning: unknown box type gstd (incompletely loaded)
mp4 warning: unknown box type gssd (incompletely loaded)
mp4 warning: unknown box type gspu (incompletely loaded)
mp4 warning: unknown box type gspm (incompletely loaded)
mp4 warning: unknown box type gshh (incompletely loaded)
mp4 debug: dumping root Box "root"
mp4 debug: | + ftyp size 24
mp4 debug: | + moov size 24217
mp4 debug: | | + mvhd size 108
mp4 debug: | | + iods size 21
mp4 debug: | | + trak size 8504
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 8404
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 45
mp4 debug: | | | | + minf size 8319
mp4 debug: | | | | | + vmhd size 20
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 8255
mp4 debug: | | | | | | + stsd size 167
mp4 debug: | | | | | | | + avc1 size 151
mp4 debug: | | | | | | | | + avcC size 45
mp4 debug: | | | | | | | | + btrt size 20
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stss size 172
mp4 debug: | | | | | | + stsc size 52
mp4 debug: | | | | | | + stsz size 7164
mp4 debug: | | | | | | + stco size 668
mp4 debug: | | + trak size 14821
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 14721
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 76
mp4 debug: | | | | + minf size 14605
mp4 debug: | | | | | + smhd size 16
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 14545
mp4 debug: | | | | | | + stsd size 105
mp4 debug: | | | | | | | + mp4a size 89
mp4 debug: | | | | | | | | + esds size 53
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stsc size 892
mp4 debug: | | | | | | + stsz size 12852
mp4 debug: | | | | | | + stco size 664
mp4 debug: | | + udta size 755
mp4 debug: | | | + meta size 747
mp4 debug: | | | | + hdlr size 33
mp4 debug: | | | | + ilst size 702
mp4 debug: | | | | | + gsst size 25
mp4 debug: | | | | | + gstd size 29
mp4 debug: | | | | | + gssd size 56
mp4 debug: | | | | | + gspu size 152
mp4 debug: | | | | | + gspm size 152
mp4 debug: | | | | | + gshh size 280
mp4 debug: | + mdat size 6378483
mp4 debug: unrecognized major file specification (mp42).
mp4 debug: found 2 tracks
mp4 debug: track[Id 0x1] read 163 chunk
mp4 debug: track[Id 0x1] read 1786 samples length:74s
main debug: selecting program id=0
mp4 debug: adding track[Id 0x1] video (enable) language undef
mp4 debug: track[Id 0x2] read 162 chunk
mp4 debug: track[Id 0x2] read 3208 samples length:74s
mp4 debug: adding track[Id 0x2] audio (enable) language undef
main debug: using demux module "mp4"
main debug: TIMER module_need() : 10.047 ms - Total 10.047 ms / 1 intvls (Avg 10.047 ms)
main debug: looking for a subtitle file in /Users/Mic/Desktop/
main debug: looking for decoder module: 32 candidates
avcodec debug: libavcodec initialized (interface 0x361e00)
avcodec debug: trying to use direct rendering
avcodec debug: allowing 1 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 158.909 ms - Total 158.909 ms / 1 intvls (Avg 158.909 ms)
main debug: looking for decoder module: 32 candidates
main debug: using decoder module "faad"
main debug: TIMER module_need() : 1.472 ms - Total 1.472 ms / 1 intvls (Avg 1.472 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 8.298 ms - Total 8.298 ms / 1 intvls (Avg 8.298 ms)
main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' successfully opened
mp4 debug: track[Id 0x1] using Sync Sample Box (stss)
mp4 debug: stts gives 0 --> 0 (sample number)
mp4 debug: track[Id 0x2] does not provide Sync Sample Box (stss)
main debug: Buffering 0%
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
faad warning: decoded zero sample
main debug: Buffering 0%
main debug: Buffering 10%
main debug: Buffering 20%
main debug: Buffering 30%
main debug: Buffering 40%
main debug: Buffering 50%
main debug: Buffering 60%
main debug: Buffering 70%
main debug: Buffering 80%
main debug: Buffering 90%
main debug: Buffering 100%
main debug: Stream buffering done (1100 ms in 13 ms)
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 45.909 ms - Total 45.909 ms / 1 intvls (Avg 45.909 ms)
main debug: looking for video filter2 module: 13 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 8.687 ms - Total 8.687 ms / 1 intvls (Avg 8.687 ms)
main debug: looking for video filter2 module: 13 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 8.034 ms - Total 8.034 ms / 1 intvls (Avg 8.034 ms)
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module: 2 candidates
vout_macosx debug: Quartz Extreme acceleration is active
main debug: looking for vout window nsobject module: 1 candidate
macosx debug: prevented sleep through IOKit (300)
macosx debug: releasing sleep blocker (300)
macosx debug: prevented sleep through IOKit (300)
macosx debug: returning videoview with x=0, y=36, width=640, height=360
main debug: using vout window nsobject module "macosx"
main debug: TIMER module_need() : 436.926 ms - Total 436.926 ms / 1 intvls (Avg 436.926 ms)
macosx debug: releasing sleep blocker (300)
macosx debug: prevented sleep through IOKit (300)
main debug: VoutDisplayEvent 'resize' 640x360 window
main debug: using vout display module "vout_macosx"
main debug: TIMER module_need() : 662.722 ms - Total 662.722 ms / 1 intvls (Avg 662.722 ms)
main debug: A filter to adapt decoder to display is needed
main debug: looking for video filter2 module: 13 candidates
main debug: using video filter2 module "i420_yuy2_altivec"
main debug: TIMER module_need() : 0.551 ms - Total 0.551 ms / 1 intvls (Avg 0.551 ms)
main debug: Filter 'i420_yuy2_altivec' (0x945898) appended to chain
main debug: original format sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 26.460 ms - Total 26.460 ms / 1 intvls (Avg 26.460 ms)
avcodec debug: using direct rendering
main debug: creating audio output
main debug: looking for audio output module: 1 candidate
main debug: VLC is looking for: 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
auhal debug: found 1 audio device(s)
auhal debug: DevID: 258 DevName: Audio incorporato
auhal debug: found 1 stream formats
auhal debug: supported format: [44100.000000][lpcm][30][4][1][4][2][16]
auhal debug: current format is: [44100.000000][lpcm][43][4][1][4][2][32]
auhal debug: layout of AUHAL has 2 channels
auhal debug: selected 2 physical channels for device output
auhal debug: VLC will output: Stereo
auhal debug: we set the AU format: [44100.000000][lpcm][11][8][1][8][2][32]
auhal debug: the actual set AU format is [44100.000000][lpcm][11][8][1][8][2][32]
auhal debug: analog output successfully opened
main debug: using audio output module "auhal"
main debug: TIMER module_need() : 20.014 ms - Total 20.014 ms / 1 intvls (Avg 20.014 ms)
main debug: output 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: mixer 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo
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.677 ms - Total 1.677 ms / 1 intvls (Avg 1.677 ms)
main debug: input 'f32b' 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.173 ms - Total 2.173 ms / 1 intvls (Avg 2.173 ms)
main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32b'->'f32b' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
main debug: End of video preroll
main debug: Received first picture
freetype debug: looking for Arial Unicode MS
main debug: using audio filter module "samplerate"
main debug: TIMER module_need() : 11.439 ms - Total 11.439 ms / 1 intvls (Avg 11.439 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: looking for video blending module: 1 candidate
main debug: using video blending module "blend"
main debug: TIMER module_need() : 5.495 ms - Total 5.495 ms / 1 intvls (Avg 5.495 ms)
main debug: Post-processing available
main debug: Decoder buffering done in 888 ms
main warning: PTS is out of range (-32006), dropping buffer
main debug: picture might be displayed late (missing 15 ms)
main debug: VoutDisplayEvent 'resize' 640x360 window
main debug: picture might be displayed late (missing 2 ms)
main warning: computed PTS is out of range (63497), clearing out
main warning: PTS is out of range (63043), dropping buffer
main warning: computed PTS is out of range (65230), clearing out
main warning: PTS is out of range (41556), dropping buffer
main warning: computed PTS is out of range (66713), clearing out
main warning: PTS is out of range (19819), dropping buffer
main warning: computed PTS is out of range (68184), clearing out
main warning: PTS is out of range (-1930), dropping buffer
main warning: computed PTS is out of range (80759), clearing out
main warning: PTS is out of range (-12575), dropping buffer
main warning: computed PTS is out of range (82764), clearing out
main warning: PTS is out of range (-33790), dropping buffer
main warning: computed PTS is out of range (84344), clearing out
main warning: picture is too late to be displayed (missing 72 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main warning: computed PTS is out of range (25063), clearing out
main warning: PTS is out of range (25062), dropping buffer
main warning: computed PTS is out of range (26793), clearing out
main warning: PTS is out of range (3572), dropping buffer
main warning: computed PTS is out of range (28396), clearing out
main warning: PTS is out of range (-18045), dropping buffer
main warning: computed PTS is out of range (29862), clearing out
main warning: PTS is out of range (-39799), dropping buffer
main warning: computed PTS is out of range (31378), clearing out
main warning: computed PTS is out of range (5436), clearing out
main warning: PTS is out of range (5436), dropping buffer
main warning: computed PTS is out of range (28708), clearing out
main warning: PTS is out of range (5488), dropping buffer
main warning: computed PTS is out of range (42881), clearing out
main warning: PTS is out of range (-3559), dropping buffer
main warning: computed PTS is out of range (46543), clearing out
main warning: PTS is out of range (-23117), dropping buffer
main warning: computed PTS is out of range (56792), clearing out
main warning: PTS is out of range (-36088), dropping buffer
main warning: computed PTS is out of range (63827), clearing out
main warning: picture is too late to be displayed (missing 36 ms)
main warning: PTS is out of range (-24382), dropping buffer
main debug: picture might be displayed late (missing 9 ms)
main warning: audio output out of sync, adjusting dates (70878 us)
main debug: picture might be displayed late (missing 2 ms)
main warning: not synchronized (70879 us), resampling
main warning: PTS is out of range (-34793), dropping buffer
main warning: PTS is out of range (-2756), dropping buffer
main warning: PTS is out of range (-23965), dropping buffer
main debug: picture might be displayed late (missing 6 ms)
main warning: audio output out of sync, adjusting dates (69663 us)
main warning: PTS is out of range (-33116), dropping buffer
main warning: buffer too early (-45222), down-sampling
main warning: not synchronized (69663 us), resampling
main warning: PTS is out of range (-31824), dropping buffer
main warning: computed PTS is out of range (14866), clearing out
main warning: PTS is out of range (16110), dropping buffer
main warning: computed PTS is out of range (16976), clearing out
main warning: PTS is out of range (-5000), dropping buffer
main warning: computed PTS is out of range (20620), clearing out
main warning: PTS is out of range (-24576), dropping buffer
main warning: computed PTS is out of range (29805), clearing out
main warning: PTS is out of range (-38611), dropping buffer
main warning: computed PTS is out of range (49223), clearing out
main warning: PTS is out of range (-20608), dropping buffer
main warning: audio output out of sync, adjusting dates (70923 us)
main warning: computed PTS is out of range (17567), clearing out
main warning: PTS is out of range (-5654), dropping buffer
main warning: computed PTS is out of range (21763), clearing out
main warning: PTS is out of range (-24678), dropping buffer
main warning: computed PTS is out of range (25071), clearing out
main warning: not synchronized (70924 us), resampling
main warning: buffer too late (70923), up-sampling
main warning: PTS is out of range (-31298), dropping buffer
main warning: PTS is out of range (-6598), dropping buffer
main warning: PTS is out of range (-26666), dropping buffer
main debug: audio output is too slow (56533 us): trashing 46439 us
main warning: PTS is out of range (-3193), dropping buffer
main warning: computed PTS is out of range (2413), clearing out
main warning: PTS is out of range (-19543), dropping buffer
main warning: computed PTS is out of range (6735), clearing out
main warning: PTS is out of range (-38441), dropping buffer
main warning: computed PTS is out of range (16825), clearing out
main warning: PTS is out of range (-35691), dropping buffer
main warning: PTS is out of range (-27173), dropping buffer
main warning: buffer too early (-46441), down-sampling
main warning: computed PTS is out of range (33830), clearing out
main debug: audio output is too slow (44950 us): trashing 46439 us
main warning: timing screwed, stopping resampling
main warning: PTS is out of range (-12611), dropping buffer
main warning: computed PTS is out of range (40368), clearing out
main warning: PTS is out of range (-29293), dropping buffer
main warning: computed PTS is out of range (44238), clearing out
main warning: PTS is out of range (-34074), dropping buffer
main warning: PTS is out of range (-28302), dropping buffer
main warning: buffer too early (-46440), down-sampling
main warning: audio output out of sync, adjusting dates (71018 us)
main warning: computed PTS is out of range (4187), clearing out
main warning: timing screwed, stopping resampling
main warning: not synchronized (71018 us), resampling
main warning: buffer too late (71017), up-sampling
main debug: audio output is too slow (32465 us): trashing 46440 us
main warning: audio output out of sync, adjusting dates (70086 us)
main warning: not synchronized (70087 us), resampling
main warning: PTS is out of range (-36673), dropping buffer
main warning: buffer too late (108383), up-sampling
main warning: PTS is out of range (-20775), dropping buffer
main warning: buffer too late (85141), up-sampling
main warning: PTS is out of range (-36796), dropping buffer
main warning: buffer too late (61921), up-sampling
main debug: audio output is too slow (8846 us): trashing 46440 us
main warning: PTS is out of range (-1641), dropping buffer
main warning: PTS is out of range (-22058), dropping buffer
main debug: audio output is too slow (5515 us): trashing 46440 us
main debug: audio output is too slow (5538 us): trashing 46440 us
main debug: audio output is too slow (5534 us): trashing 46440 us
main debug: audio output is too slow (5526 us): trashing 46440 us
main warning: audio output out of sync, adjusting dates (66360 us)
main warning: not synchronized (66361 us), resampling
main warning: PTS is out of range (-7840), dropping buffer
main warning: PTS is out of range (-29355), dropping buffer
main warning: PTS is out of range (-34306), dropping buffer
main warning: PTS is out of range (-36541), dropping buffer
main debug: audio output is too slow (32017 us): trashing 46440 us
main debug: audio output is too slow (20404 us): trashing 46440 us
main debug: audio output is too slow (32172 us): trashing 46440 us
main warning: PTS is out of range (-33930), dropping buffer
main debug: audio output is too slow (43789 us): trashing 46440 us
main warning: computed PTS is out of range (46048), clearing out
main warning: PTS is out of range (11767), dropping buffer
main warning: computed PTS is out of range (81258), clearing out
main warning: PTS is out of range (23757), dropping buffer
main warning: computed PTS is out of range (86390), clearing out
main warning: PTS is out of range (5669), dropping buffer
main warning: computed PTS is out of range (90574), clearing out
main warning: PTS is out of range (-13367), dropping buffer
main warning: computed PTS is out of range (94391), clearing out
main warning: PTS is out of range (-32769), dropping buffer
main warning: computed PTS is out of range (112782), clearing out
main warning: PTS is out of range (-37598), dropping buffer
main warning: computed PTS is out of range (114997), clearing out
main warning: PTS is out of range (-25274), dropping buffer
main warning: PTS is out of range (-39290), dropping buffer
main warning: buffer too early (-46441), down-sampling
main warning: computed PTS is out of range (13461), clearing out
main warning: timing screwed, stopping resampling
main warning: PTS is out of range (-32934), dropping buffer
main warning: computed PTS is out of range (15623), clearing out
main warning: PTS is out of range (-25431), dropping buffer
main debug: audio output is too slow (6106 us): trashing 46440 us
main warning: PTS is out of range (-23733), dropping buffer
main warning: computed PTS is out of range (1808), clearing out
main warning: PTS is out of range (-5358), dropping buffer
main debug: audio output is too slow (6101 us): trashing 46439 us
main warning: computed PTS is out of range (13663), clearing out
main warning: PTS is out of range (-9558), dropping buffer
main warning: computed PTS is out of range (17819), clearing out
main warning: PTS is out of range (-28622), dropping buffer
main warning: computed PTS is out of range (22087), clearing out
main warning: PTS is out of range (-21865), dropping buffer
main warning: audio output out of sync, adjusting dates (66947 us)
main warning: not synchronized (66947 us), resampling
main debug: incoming request - stopping current input
main debug: dying input
main debug: control: stopping input
main debug: dying input
macosx debug: releasing sleep blocker (300)
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 7 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "faad"
main debug: killing decoder fourcc `mp4a', 44 PES in FIFO
main debug: removing module "scaletempo"
main debug: removing module "samplerate"
main debug: removing module "auhal"
main debug: removing module "float32_mixer"
main debug: releasing audio output
main debug: removing module "mp4"
mp4 debug: freeing all memory
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: destroying useless vout
main debug: removing module "blend"
main debug: Filter 0x945898 removed from chain
main debug: removing module "i420_yuy2_altivec"
main debug: removing module "vout_macosx"
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 27 Oct 2012 22:00

Audio disabled

main debug: using interface module "macosx"
main debug: TIMER module_need() : 536.213 ms - Total 536.213 ms / 1 intvls (Avg 536.213 ms)
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 0 items, index -1
main debug: playlist threads correctly activated
main debug: looking for services probe module: 5 candidates
main debug: no services probe module matching "any" could be loaded
main debug: TIMER module_need() : 14.711 ms - Total 14.711 ms / 1 intvls (Avg 14.711 ms)
macosx debug: using Leopard AR cookies
main debug: looking for extension module: 1 candidate
lua debug: Opening Lua Extension module
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/extensions
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/extensions
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/extensions
main debug: using extension module "lua"
main debug: TIMER module_need() : 8.833 ms - Total 8.833 ms / 1 intvls (Avg 8.833 ms)
macosx debug: Found 0 capture devices
macosx debug: no optical media found
main debug: adding item `Litter Genie- I Haz a Catnip in Mah Head.mp4' ( file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4 )
main debug: rebuilding array of current - root Scaletta
main debug: rebuild done - 1 items, index -1
main debug: Creating an input for 'Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: processing request item: Litter Genie- I Haz a Catnip in Mah Head.mp4, node: Scaletta, skip: 0
main debug: resyncing on Litter Genie- I Haz a Catnip in Mah Head.mp4
main debug: Litter Genie- I Haz a Catnip in Mah Head.mp4 is at 0
main debug: starting playback of the new playlist item
main debug: resyncing on Litter Genie- I Haz a Catnip in Mah Head.mp4
main debug: Litter Genie- I Haz a Catnip in Mah Head.mp4 is at 0
main debug: creating new input thread
main debug: Creating an input for 'Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: no fetch required for (null) (art currently (null))
main debug: no fetch required for (null) (art currently (null))
main debug: using timeshift granularity of 50 MiB, in path '/tmp'
main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' gives access `file' demux `' path `/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4'
main debug: creating demux: access='file' demux='' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' file='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
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.648 ms - Total 0.648 ms / 1 intvls (Avg 0.648 ms)
main debug: creating access 'file' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4', path='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: looking for access module: 3 candidates
filesystem debug: opening file `/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.576 ms - Total 0.576 ms / 1 intvls (Avg 0.576 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 - 4694 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.398 ms - Total 0.398 ms / 1 intvls (Avg 0.398 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.329 ms - Total 0.329 ms / 1 intvls (Avg 0.329 ms)
main debug: creating demux: access='file' demux='' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' file='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: looking for demux module: 55 candidates
mp4 warning: unknown box type btrt (incompletely loaded)
mp4 warning: unknown box type gsst (incompletely loaded)
mp4 warning: unknown box type gstd (incompletely loaded)
mp4 warning: unknown box type gssd (incompletely loaded)
mp4 warning: unknown box type gspu (incompletely loaded)
mp4 warning: unknown box type gspm (incompletely loaded)
mp4 warning: unknown box type gshh (incompletely loaded)
mp4 debug: dumping root Box "root"
mp4 debug: | + ftyp size 24
mp4 debug: | + moov size 24217
mp4 debug: | | + mvhd size 108
mp4 debug: | | + iods size 21
mp4 debug: | | + trak size 8504
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 8404
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 45
mp4 debug: | | | | + minf size 8319
mp4 debug: | | | | | + vmhd size 20
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 8255
mp4 debug: | | | | | | + stsd size 167
mp4 debug: | | | | | | | + avc1 size 151
mp4 debug: | | | | | | | | + avcC size 45
mp4 debug: | | | | | | | | + btrt size 20
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stss size 172
mp4 debug: | | | | | | + stsc size 52
mp4 debug: | | | | | | + stsz size 7164
mp4 debug: | | | | | | + stco size 668
mp4 debug: | | + trak size 14821
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 14721
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 76
mp4 debug: | | | | + minf size 14605
mp4 debug: | | | | | + smhd size 16
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 14545
mp4 debug: | | | | | | + stsd size 105
mp4 debug: | | | | | | | + mp4a size 89
mp4 debug: | | | | | | | | + esds size 53
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stsc size 892
mp4 debug: | | | | | | + stsz size 12852
mp4 debug: | | | | | | + stco size 664
mp4 debug: | | + udta size 755
mp4 debug: | | | + meta size 747
mp4 debug: | | | | + hdlr size 33
mp4 debug: | | | | + ilst size 702
mp4 debug: | | | | | + gsst size 25
mp4 debug: | | | | | + gstd size 29
mp4 debug: | | | | | + gssd size 56
mp4 debug: | | | | | + gspu size 152
mp4 debug: | | | | | + gspm size 152
mp4 debug: | | | | | + gshh size 280
mp4 debug: | + mdat size 6378483
mp4 debug: unrecognized major file specification (mp42).
mp4 debug: found 2 tracks
mp4 debug: track[Id 0x1] read 163 chunk
mp4 debug: track[Id 0x1] read 1786 samples length:74s
main debug: selecting program id=0
mp4 debug: adding track[Id 0x1] video (enable) language undef
mp4 debug: track[Id 0x2] read 162 chunk
mp4 debug: track[Id 0x2] read 3208 samples length:74s
mp4 debug: adding track[Id 0x2] audio (enable) language undef
main debug: using demux module "mp4"
main debug: TIMER module_need() : 19.204 ms - Total 19.204 ms / 1 intvls (Avg 19.204 ms)
main debug: looking for a subtitle file in /Users/Mic/Desktop/
main debug: looking for decoder module: 32 candidates
avcodec debug: libavcodec initialized (interface 0x361e00)
avcodec debug: trying to use direct rendering
avcodec debug: allowing 1 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 163.246 ms - Total 163.246 ms / 1 intvls (Avg 163.246 ms)
main debug: audio is disabled, not selecting ES 0x1
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 8.000 ms - Total 8.000 ms / 1 intvls (Avg 8.000 ms)
main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' successfully opened
mp4 debug: track[Id 0x1] using Sync Sample Box (stss)
mp4 debug: stts gives 0 --> 0 (sample number)
main debug: Buffering 0%
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
main debug: Buffering 0%
main debug: Buffering 10%
main debug: Buffering 20%
main debug: Buffering 30%
main debug: Buffering 40%
main debug: Buffering 50%
main debug: Buffering 60%
main debug: Buffering 70%
main debug: Buffering 80%
main debug: Buffering 90%
main debug: Buffering 100%
main debug: Stream buffering done (1100 ms in 8 ms)
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 19.783 ms - Total 19.783 ms / 1 intvls (Avg 19.783 ms)
main debug: looking for video filter2 module: 13 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 7.252 ms - Total 7.252 ms / 1 intvls (Avg 7.252 ms)
main debug: looking for video filter2 module: 13 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 7.269 ms - Total 7.269 ms / 1 intvls (Avg 7.269 ms)
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module: 2 candidates
vout_macosx debug: Quartz Extreme acceleration is active
main debug: looking for vout window nsobject module: 1 candidate
macosx debug: prevented sleep through IOKit (300)
macosx debug: releasing sleep blocker (300)
macosx debug: prevented sleep through IOKit (300)
macosx debug: returning videoview with x=0, y=36, width=640, height=360
main debug: using vout window nsobject module "macosx"
main debug: TIMER module_need() : 300.018 ms - Total 300.018 ms / 1 intvls (Avg 300.018 ms)
macosx debug: releasing sleep blocker (300)
macosx debug: prevented sleep through IOKit (300)
main debug: VoutDisplayEvent 'resize' 640x360 window
main debug: using vout display module "vout_macosx"
main debug: TIMER module_need() : 573.573 ms - Total 573.573 ms / 1 intvls (Avg 573.573 ms)
main debug: A filter to adapt decoder to display is needed
main debug: looking for video filter2 module: 13 candidates
main debug: using video filter2 module "i420_yuy2_altivec"
main debug: TIMER module_need() : 0.491 ms - Total 0.491 ms / 1 intvls (Avg 0.491 ms)
main debug: Filter 'i420_yuy2_altivec' (0x938e98) appended to chain
main debug: original format sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 23.846 ms - Total 23.846 ms / 1 intvls (Avg 23.846 ms)
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: looking for video blending module: 1 candidate
main debug: using video blending module "blend"
main debug: TIMER module_need() : 2.211 ms - Total 2.211 ms / 1 intvls (Avg 2.211 ms)
main debug: Post-processing available
main debug: Decoder buffering done in 724 ms
main debug: VoutDisplayEvent 'resize' 640x360 window
main debug: incoming request - stopping current input
main debug: dying input
main debug: control: stopping input
main debug: dying input
main debug: picture might be displayed late (missing 6 ms)
macosx debug: releasing sleep blocker (300)
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 7 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "mp4"
mp4 debug: freeing all memory
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: destroying useless vout
main debug: removing module "blend"
main debug: Filter 0x938e98 removed from chain
main debug: removing module "i420_yuy2_altivec"
main debug: removing module "vout_macosx"
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"

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: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby Jean-Baptiste Kempf » 29 Oct 2012 11:28

Hmmm, Can you share the 1.1.12 with no audio, please?
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.

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 29 Oct 2012 18:13

VLC 1.1.12 - Local file, audio disabled

received first data after 0 ms
pre-buffering done 1024 bytes in 0s - 4830 KiB/s
main debug: looking for stream_filter module: 5 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.486 ms - Total 0.486 ms / 1 intvls (Avg 0.486 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.488 ms - Total 0.488 ms / 1 intvls (Avg 0.488 ms)
main debug: creating demux: access='file' demux='' path='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4'
main debug: looking for demux module: 52 candidates
mp4 debug: found Box: ftyp size 24
mp4 debug: found Box: moov size 24217
mp4 debug: found Box: mvhd size 108
mp4 debug: read box: "mvhd" creation 734687d-07h:53m:54s modification 734687d-07h:53m:54s time scale 600 duration 694977d-48h:00m:00s rate 1.000000 volume 1.000000 next track id 3
mp4 debug: found Box: iods size 21
mp4 warning: unknown box type iods (incompletely loaded)
mp4 debug: found Box: trak size 8504
mp4 debug: found Box: tkhd size 92
mp4 debug: read box: "tkhd" creation 719084d-36h:00m:00s modification 734687d-07h:53m:54s duration 694977d-48h:00m:00s track ID 1 layer 0 volume 0.000000 width 640.000000 height 360.000000
mp4 debug: found Box: mdia size 8404
mp4 debug: found Box: mdhd size 32
mp4 debug: read box: "mdhd" creation 719084d-36h:00m:00s modification 734687d-07h:53m:54s time scale 48000 duration 695018d-21h:12m:52s language und
mp4 debug: found Box: hdlr size 45
mp4 debug: read box: "hdlr" handler type vide name VideoHandler
mp4 debug: found Box: minf size 8319
mp4 debug: found Box: vmhd size 20
mp4 debug: read box: "vmhd" graphics-mode 0 opcolor (0, 0, 0)
mp4 debug: found Box: dinf size 36
mp4 debug: found Box: dref size 28
mp4 debug: found Box: url size 12
mp4 debug: read box: "url" url: (null)
mp4 debug: read box: "dref" entry-count 1
mp4 debug: found Box: stbl size 8255
mp4 debug: found Box: stsd size 167
mp4 debug: found Box: avc1 size 151
mp4 debug: found Box: avcC size 45
mp4 debug: read box: "avcC" version=1 profile=0x42 level=0x1e length size=4 sps=1 pps=1
mp4 debug: - sps[0] length=22
mp4 debug: - pps[0] length=4
mp4 debug: found Box: btrt size 20
mp4 warning: unknown box type btrt (incompletely loaded)
mp4 debug: read box: "vide" in stsd 640x360 depth 24
mp4 debug: read box: "stsd" entry-count 1
mp4 debug: found Box: stts size 24
mp4 debug: read box: "stts" entry-count 1
mp4 debug: found Box: stss size 172
main debug: no fetch required for (null) (art currently (null))
mp4 debug: read box: "stss" entry-count 39
mp4 debug: found Box: stsc size 52
mp4 debug: read box: "stsc" entry-count 3
mp4 debug: found Box: stsz size 7164
mp4 debug: read box: "stsz" sample-size 0 sample-count 1786
mp4 debug: found Box: stco size 668
mp4 debug: read box: "co64" entry-count 163
mp4 debug: found Box: trak size 14821
mp4 debug: found Box: tkhd size 92
mp4 debug: read box: "tkhd" creation 734687d-07h:53m:54s modification 734687d-07h:53m:54s duration 694977d-48h:00m:00s track ID 2 layer 0 volume 1.000000 width 0.000000 height 0.000000
mp4 debug: found Box: mdia size 14721
mp4 debug: found Box: mdhd size 32
mp4 debug: read box: "mdhd" creation 734687d-07h:53m:54s modification 734687d-07h:53m:54s time scale 44100 duration 695015d-00h:29m:52s language und
mp4 debug: found Box: hdlr size 76
mp4 debug: read box: "hdlr" handler type soun name IsoMedia File Produced by Google, 5-11-2011
mp4 debug: found Box: minf size 14605
mp4 debug: found Box: smhd size 16
mp4 debug: read box: "smhd" balance 0.000000
mp4 debug: found Box: dinf size 36
mp4 debug: found Box: dref size 28
mp4 debug: found Box: url size 12
mp4 debug: read box: "url" url: (null)
mp4 debug: read box: "dref" entry-count 1
mp4 debug: found Box: stbl size 14545
mp4 debug: found Box: stsd size 105
mp4 debug: found Box: mp4a size 89
mp4 debug: read box: "soun" mp4 or qt1/2 (rest=53)
mp4 debug: found Box: esds size 53
mp4 debug: found esds MPEG4ESDescr (39Bytes)
mp4 debug: found esds MP4DecConfigDescr (31Bytes)
mp4 debug: found esds MP4DecSpecificDescr (16Bytes)
mp4 debug: read box: "soun" in stsd channel 2 sample size 16 sample rate 44100.000000
mp4 debug: read box: "stsd" entry-count 1
mp4 debug: found Box: stts size 24
mp4 debug: read box: "stts" entry-count 1
mp4 debug: found Box: stsc size 892
mp4 debug: read box: "stsc" entry-count 73
mp4 debug: found Box: stsz size 12852
mp4 debug: read box: "stsz" sample-size 0 sample-count 3208
mp4 debug: found Box: stco size 664
mp4 debug: read box: "co64" entry-count 162
mp4 debug: found Box: udta size 755
mp4 debug: found Box: meta size 747
mp4 debug: found Box: hdlr size 33
mp4 debug: read box: "hdlr" handler type mdir name
mp4 debug: found Box: ilst size 702
mp4 debug: found Box: gsst size 25
mp4 warning: unknown box type gsst (incompletely loaded)
mp4 debug: found Box: gstd size 29
mp4 warning: unknown box type gstd (incompletely loaded)
mp4 debug: found Box: gssd size 56
mp4 warning: unknown box type gssd (incompletely loaded)
mp4 debug: found Box: gspu size 152
mp4 warning: unknown box type gspu (incompletely loaded)
mp4 debug: found Box: gspm size 152
mp4 warning: unknown box type gspm (incompletely loaded)
mp4 debug: found Box: gshh size 280
mp4 warning: unknown box type gshh (incompletely loaded)
mp4 debug: found Box: mdat size 6378483
mp4 debug: skip box: "mdat"
mp4 debug: dumping root Box "root"
mp4 debug: | + ftyp size 24
mp4 debug: | + moov size 24217
mp4 debug: | | + mvhd size 108
mp4 debug: | | + iods size 21
mp4 debug: | | + trak size 8504
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 8404
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 45
mp4 debug: | | | | + minf size 8319
mp4 debug: | | | | | + vmhd size 20
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 8255
mp4 debug: | | | | | | + stsd size 167
mp4 debug: | | | | | | | + avc1 size 151
mp4 debug: | | | | | | | | + avcC size 45
mp4 debug: | | | | | | | | + btrt size 20
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stss size 172
mp4 debug: | | | | | | + stsc size 52
mp4 debug: | | | | | | + stsz size 7164
mp4 debug: | | | | | | + stco size 668
mp4 debug: | | + trak size 14821
mp4 debug: | | | + tkhd size 92
mp4 debug: | | | + mdia size 14721
mp4 debug: | | | | + mdhd size 32
mp4 debug: | | | | + hdlr size 76
mp4 debug: | | | | + minf size 14605
mp4 debug: | | | | | + smhd size 16
mp4 debug: | | | | | + dinf size 36
mp4 debug: | | | | | | + dref size 28
mp4 debug: | | | | | | | + url size 12
mp4 debug: | | | | | + stbl size 14545
mp4 debug: | | | | | | + stsd size 105
mp4 debug: | | | | | | | + mp4a size 89
mp4 debug: | | | | | | | | + esds size 53
mp4 debug: | | | | | | + stts size 24
mp4 debug: | | | | | | + stsc size 892
mp4 debug: | | | | | | + stsz size 12852
mp4 debug: | | | | | | + stco size 664
mp4 debug: | | + udta size 755
mp4 debug: | | | + meta size 747
mp4 debug: | | | | + hdlr size 33
mp4 debug: | | | | + ilst size 702
mp4 debug: | | | | | + gsst size 25
mp4 debug: | | | | | + gstd size 29
mp4 debug: | | | | | + gssd size 56
mp4 debug: | | | | | + gspu size 152
mp4 debug: | | | | | + gspm size 152
mp4 debug: | | | | | + gshh size 280
mp4 debug: | + mdat size 6378483
mp4 debug: unrecognized major file specification (mp42).
mp4 debug: found 2 tracks
mp4 debug: track[Id 0x1] read 163 chunk
mp4 debug: track[Id 0x1] read 1786 samples length:74s
main debug: selecting program id=0
mp4 debug: adding track[Id 0x1] video (enable) language undef
mp4 debug: track[Id 0x2] read 162 chunk
mp4 debug: track[Id 0x2] read 3208 samples length:74s
mp4 debug: adding track[Id 0x2] audio (enable) language undef
main debug: using demux module "mp4"
main debug: TIMER module_need() : 36.353 ms - Total 36.353 ms / 1 intvls (Avg 36.353 ms)
mp4 warning: control query 14 unimplemented
main debug: looking for a subtitle file in /Users/Mic/Desktop/
main debug: looking for decoder module: 33 candidates
avcodec debug: libavcodec initialized (interface 0x350500)
avcodec debug: trying to use direct rendering
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 1795.797 ms - Total 1795.797 ms / 1 intvls (Avg 1795.797 ms)
main debug: thread (decoder) created at priority 0 (../../src/input/decoder.c:301)
main debug: thread started
main debug: audio is disabled, not selecting ES 0x1
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 6.881 ms - Total 6.881 ms / 1 intvls (Avg 6.881 ms)
main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' successfully opened
mp4 debug: track[Id 0x1] using Sync Sample Box (stss)
mp4 debug: stts gives 0 --> 0 (sample number)
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 8%
main debug: Buffering 16%
main debug: Buffering 25%
main debug: Buffering 33%
main debug: no usable vout present, spawning one
main debug: looking for text renderer module: 3 candidates
main debug: using text renderer module "quartztext"
main debug: TIMER module_need() : 2.722 ms - Total 2.722 ms / 1 intvls (Avg 2.722 ms)
main debug: looking for video filter2 module: 13 candidates
main debug: Buffering 41%
main debug: Buffering 50%
main debug: Buffering 58%
main debug: Buffering 66%
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() : 11.625 ms - Total 11.625 ms / 1 intvls (Avg 11.625 ms)
main debug: looking for video filter2 module: 13 candidates
main debug: Buffering 75%
main debug: Buffering 83%
main debug: Buffering 91%
main debug: Buffering 100%
main debug: Stream buffering done (1300 ms in 22 ms)
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 10.700 ms - Total 10.700 ms / 1 intvls (Avg 10.700 ms)
main debug: window size: 640x360
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: looking for video output module: 2 candidates
main debug: looking for opengl provider module: 2 candidates
macosx debug: display is Quartz Extreme accelerated
main debug: using opengl provider module "macosx"
main debug: TIMER module_need() : 200.130 ms - Total 200.130 ms / 1 intvls (Avg 200.130 ms)
main debug: using video output module "opengl"
main debug: TIMER module_need() : 202.385 ms - Total 202.385 ms / 1 intvls (Avg 202.385 ms)
main debug: got 1 direct buffer(s)
main debug: pic render sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic in sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic out sz 640x360, of (0,0), vsz 640x360, 4cc YUY2, sar 1:1, msk r0x0 g0x0 b0x0
main debug: looking for video filter2 module: 13 candidates
main debug: using video filter2 module "i420_yuy2_altivec"
main debug: TIMER module_need() : 0.616 ms - Total 0.616 ms / 1 intvls (Avg 0.616 ms)
main debug: indirect render, mapping render pictures 0-24 to system pictures 1-25
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
main debug: Post-processing available
main warning: vlc_object_find_name(postproc) is not safe!
main debug: Decoder buffering done in 265 ms
main debug: looking for video blending module: 1 candidate
blend debug: chroma: YUVA -> YUY2
main debug: using video blending module "blend"
main debug: TIMER module_need() : 2.355 ms - Total 2.355 ms / 1 intvls (Avg 2.355 ms)
main warning: late picture skipped (51132 > -33716)
main warning: late picture skipped (9423 > -33716)
main warning: late picture skipped (-32285 > -33716)
main debug: incoming request - stopping current input
main debug: dying input
main debug: control type=0
main debug: control: stopping input
macosx debug: input has stopped, refreshing interface
main debug: dying input
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: removing module "avcodec"
main debug: killing decoder fourcc `h264', 12 PES in FIFO
main debug: [0] 2 0
main debug: [1] 2 0
main debug: [2] 2 0
main debug: [3] 2 0
main debug: [4] 2 0
main debug: [5] 2 0
main debug: [6] 2 0
main debug: [7] 2 0
main debug: [8] 2 0
main debug: [9] 2 0
main debug: [10] 2 0
main debug: [11] 2 0
main debug: [12] 2 0
main debug: [13] 4 0
main debug: [14] 4 0
main debug: [15] 2 0
main debug: [16] 2 0
main debug: [17] 2 0
main debug: [18] 2 0
main debug: [19] 2 0
main debug: [20] 2 0
main debug: [21] 2 0
main debug: [22] 2 0
main debug: [23] 2 0
main debug: [24] 2 0
main debug: saving a free vout
mp4 debug: freeing all memory
main debug: removing module "mp4"
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: thread ended
main debug: destroying useless vout
main debug: removing module "i420_yuy2_altivec"
main debug: removing module "macosx"
main debug: removing module "opengl"
main debug: removing module "blend"
main debug: removing module "quartztext"
main debug: removing module "yuvp"
main debug: removing module "swscale"

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 16 Dec 2012 22:36

VLC 2.0.5 behaves in the same way as 2.0.4, but I found this thread on the forums:
http://forum.videolan.org/viewtopic.php?f=12&t=98370
I removed libsamplerate_plugin.dylib and libspeex_resampler_plugin.dylib and things got a lot better.



CPU load while playing a local file, H264 640x360, 23.97 fps, planar 4:2:0 YUV (it's the same file I used to test 1.1.12 and 2.0.4):

WITH libsamplerate_plugin.dylib and libspeex_resampler_plugin.dylib --> 80 - 85 %
WITHOUT libsamplerate_plugin.dylib and libspeex_resampler_plugin.dylib --> ~ 60 %
(with VLC 1.1.12 the CPU load is around 35 %)

I'm going to post the logs for the unmodified VLC 2.0.5

AUDIO ENABLED

Code: Select all

main debug: looking for access module: 3 candidates main debug: using access module "filesystem" main debug: TIMER module_need() : 0.886 ms - Total 0.886 ms / 1 intvls (Avg 0.886 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 - 4830 KiB/s main debug: looking for stream_filter module: 7 candidates main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 0.433 ms - Total 0.433 ms / 1 intvls (Avg 0.433 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.319 ms - Total 0.319 ms / 1 intvls (Avg 0.319 ms) main debug: creating demux: access='file' demux='' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' file='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4' main debug: looking for demux module: 55 candidates mp4 warning: unknown box type btrt (incompletely loaded) mp4 warning: unknown box type gsst (incompletely loaded) mp4 warning: unknown box type gstd (incompletely loaded) mp4 warning: unknown box type gssd (incompletely loaded) mp4 warning: unknown box type gspu (incompletely loaded) mp4 warning: unknown box type gspm (incompletely loaded) mp4 warning: unknown box type gshh (incompletely loaded) mp4 debug: dumping root Box "root" mp4 debug: | + ftyp size 24 mp4 debug: | + moov size 24217 mp4 debug: | | + mvhd size 108 mp4 debug: | | + iods size 21 mp4 debug: | | + trak size 8504 mp4 debug: | | | + tkhd size 92 mp4 debug: | | | + mdia size 8404 mp4 debug: | | | | + mdhd size 32 mp4 debug: | | | | + hdlr size 45 mp4 debug: | | | | + minf size 8319 mp4 debug: | | | | | + vmhd size 20 mp4 debug: | | | | | + dinf size 36 mp4 debug: | | | | | | + dref size 28 mp4 debug: | | | | | | | + url size 12 mp4 debug: | | | | | + stbl size 8255 mp4 debug: | | | | | | + stsd size 167 mp4 debug: | | | | | | | + avc1 size 151 mp4 debug: | | | | | | | | + avcC size 45 mp4 debug: | | | | | | | | + btrt size 20 mp4 debug: | | | | | | + stts size 24 mp4 debug: | | | | | | + stss size 172 mp4 debug: | | | | | | + stsc size 52 mp4 debug: | | | | | | + stsz size 7164 mp4 debug: | | | | | | + stco size 668 mp4 debug: | | + trak size 14821 mp4 debug: | | | + tkhd size 92 mp4 debug: | | | + mdia size 14721 mp4 debug: | | | | + mdhd size 32 mp4 debug: | | | | + hdlr size 76 mp4 debug: | | | | + minf size 14605 mp4 debug: | | | | | + smhd size 16 mp4 debug: | | | | | + dinf size 36 mp4 debug: | | | | | | + dref size 28 mp4 debug: | | | | | | | + url size 12 mp4 debug: | | | | | + stbl size 14545 mp4 debug: | | | | | | + stsd size 105 mp4 debug: | | | | | | | + mp4a size 89 mp4 debug: | | | | | | | | + esds size 53 mp4 debug: | | | | | | + stts size 24 mp4 debug: | | | | | | + stsc size 892 mp4 debug: | | | | | | + stsz size 12852 mp4 debug: | | | | | | + stco size 664 mp4 debug: | | + udta size 755 mp4 debug: | | | + meta size 747 mp4 debug: | | | | + hdlr size 33 mp4 debug: | | | | + ilst size 702 mp4 debug: | | | | | + gsst size 25 mp4 debug: | | | | | + gstd size 29 mp4 debug: | | | | | + gssd size 56 mp4 debug: | | | | | + gspu size 152 mp4 debug: | | | | | + gspm size 152 mp4 debug: | | | | | + gshh size 280 mp4 debug: | + mdat size 6378483 mp4 debug: unrecognized major file specification (mp42). mp4 debug: found 2 tracks mp4 debug: track[Id 0x1] read 163 chunk mp4 debug: track[Id 0x1] read 1786 samples length:74s main debug: selecting program id=0 mp4 debug: adding track[Id 0x1] video (enable) language undef mp4 debug: track[Id 0x2] read 162 chunk mp4 debug: track[Id 0x2] read 3208 samples length:74s mp4 debug: adding track[Id 0x2] audio (enable) language undef main debug: using demux module "mp4" main debug: TIMER module_need() : 11.757 ms - Total 11.757 ms / 1 intvls (Avg 11.757 ms) main debug: looking for a subtitle file in /Users/Mic/Desktop/ main debug: looking for decoder module: 32 candidates avcodec debug: libavcodec initialized (interface 0x363f64) avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started main debug: using decoder module "avcodec" main debug: TIMER module_need() : 267.830 ms - Total 267.830 ms / 1 intvls (Avg 267.830 ms) main debug: looking for decoder module: 32 candidates main debug: using decoder module "faad" main debug: TIMER module_need() : 1.490 ms - Total 1.490 ms / 1 intvls (Avg 1.490 ms) main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 8.096 ms - Total 8.096 ms / 1 intvls (Avg 8.096 ms) main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' successfully opened mp4 debug: track[Id 0x1] using Sync Sample Box (stss) mp4 debug: stts gives 0 --> 0 (sample number) mp4 debug: track[Id 0x2] does not provide Sync Sample Box (stss) main debug: Buffering 0% main debug: looking for text renderer module: 3 candidates freetype debug: looking for Arial Unicode MS faad warning: decoded zero sample main debug: Buffering 0% main debug: Buffering 10% main debug: Buffering 20% main debug: Buffering 30% main debug: Buffering 40% freetype debug: found /Library/Fonts/Arial Unicode.ttf freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 17.692 ms - Total 17.692 ms / 1 intvls (Avg 17.692 ms) main debug: looking for video filter2 module: 13 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 7.943 ms - Total 7.943 ms / 1 intvls (Avg 7.943 ms) main debug: looking for video filter2 module: 13 candidates yuvp debug: YUVP to YUVA converter main debug: using video filter2 module "yuvp" main debug: TIMER module_need() : 7.801 ms - Total 7.801 ms / 1 intvls (Avg 7.801 ms) main debug: Deinterlacing available main debug: deinterlace 0, mode blend, is_needed 0 main debug: Opening vout display wrapper main debug: looking for vout display module: 2 candidates vout_macosx debug: Quartz Extreme acceleration is active main debug: looking for vout window nsobject module: 1 candidate main debug: Buffering 50% main debug: Buffering 60% main debug: Buffering 70% main debug: Buffering 80% main debug: Buffering 90% macosx debug: prevented sleep through IOKit (0) main debug: Buffering 100% main debug: Stream buffering done (1100 ms in 79 ms) macosx debug: returning videoview with x=0, y=36, width=640, height=360 main debug: using vout window nsobject module "macosx" main debug: TIMER module_need() : 245.969 ms - Total 245.969 ms / 1 intvls (Avg 245.969 ms) macosx debug: prevented sleep through IOKit (0) main debug: VoutDisplayEvent 'resize' 640x360 window main debug: using vout display module "vout_macosx" main debug: TIMER module_need() : 378.235 ms - Total 378.235 ms / 1 intvls (Avg 378.235 ms) main debug: A filter to adapt decoder to display is needed main debug: looking for video filter2 module: 13 candidates main debug: using video filter2 module "i420_yuy2_altivec" main debug: TIMER module_need() : 0.473 ms - Total 0.473 ms / 1 intvls (Avg 0.473 ms) main debug: Filter 'i420_yuy2_altivec' (0x938298) appended to chain main debug: original format sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 main debug: removing module "freetype" main debug: looking for text renderer module: 3 candidates freetype debug: looking for Arial Unicode MS freetype debug: found /Library/Fonts/Arial Unicode.ttf freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 6.755 ms - Total 6.755 ms / 1 intvls (Avg 6.755 ms) main debug: creating audio output main debug: looking for audio output module: 1 candidate main debug: VLC is looking for: 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes auhal debug: found 1 audio device(s) auhal debug: DevID: 258 DevName: Audio incorporato auhal debug: found 1 stream formats auhal debug: supported format: [44100.000000][lpcm][30][4][1][4][2][16] avcodec debug: using direct rendering auhal debug: current format is: [44100.000000][lpcm][43][4][1][4][2][32] auhal debug: layout of AUHAL has 2 channels auhal debug: selected 2 physical channels for device output auhal debug: VLC will output: Stereo auhal debug: we set the AU format: [44100.000000][lpcm][11][8][1][8][2][32] auhal debug: the actual set AU format is [44100.000000][lpcm][11][8][1][8][2][32] auhal debug: analog output successfully opened main debug: using audio output module "auhal" main debug: TIMER module_need() : 23.902 ms - Total 23.902 ms / 1 intvls (Avg 23.902 ms) main debug: output 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes main debug: mixer 'f32b' 44100 Hz Stereo frame=1 samples/8 bytes main debug: End of video preroll main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo 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.723 ms - Total 1.723 ms / 1 intvls (Avg 1.723 ms) main debug: input 'f32b' 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.086 ms - Total 2.086 ms / 1 intvls (Avg 2.086 ms) main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: filter(s) 'f32b'->'f32b' 44100 Hz->44100 Hz Stereo->Stereo main debug: conversion pipeline completed main debug: filter(s) 'f32b'->'f32b' 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() : 5.505 ms - Total 5.505 ms / 1 intvls (Avg 5.505 ms) main debug: conversion pipeline completed main debug: Received first picture freetype debug: looking for Arial Unicode MS main debug: End of audio preroll freetype debug: found /Library/Fonts/Arial Unicode.ttf main debug: looking for video blending module: 1 candidate main debug: using video blending module "blend" main debug: TIMER module_need() : 2.239 ms - Total 2.239 ms / 1 intvls (Avg 2.239 ms) main debug: Post-processing available main debug: Decoder buffering done in 481 ms main warning: PTS is out of range (-32832), dropping buffer main debug: picture might be displayed late (missing 6 ms) main debug: VoutDisplayEvent 'resize' 640x360 window main warning: picture is too late to be displayed (missing 97 ms) main warning: picture is too late to be displayed (missing 56 ms) main debug: picture might be displayed late (missing 15 ms) main warning: computed PTS is out of range (207415), clearing out main warning: PTS is out of range (206960), dropping buffer main warning: computed PTS is out of range (211956), clearing out main warning: PTS is out of range (188281), dropping buffer main warning: computed PTS is out of range (213506), clearing out main warning: PTS is out of range (166611), dropping buffer main warning: computed PTS is out of range (221586), clearing out main warning: PTS is out of range (151471), dropping buffer main warning: computed PTS is out of range (223184), clearing out main warning: PTS is out of range (129849), dropping buffer main warning: computed PTS is out of range (224631), clearing out main warning: PTS is out of range (108076), dropping buffer main warning: computed PTS is out of range (226030), clearing out main warning: PTS is out of range (86255), dropping buffer main warning: computed PTS is out of range (254582), clearing out main warning: PTS is out of range (91588), dropping buffer main warning: computed PTS is out of range (256303), clearing out main warning: PTS is out of range (70089), dropping buffer main warning: computed PTS is out of range (262794), clearing out main warning: PTS is out of range (53360), dropping buffer main warning: computed PTS is out of range (267304), clearing out main warning: PTS is out of range (34650), dropping buffer main warning: computed PTS is out of range (272175), clearing out main warning: PTS is out of range (16301), dropping buffer main warning: computed PTS is out of range (289028), clearing out main warning: PTS is out of range (9934), dropping buffer main warning: computed PTS is out of range (294645), clearing out main warning: PTS is out of range (-7669), dropping buffer main warning: computed PTS is out of range (317265), clearing out main warning: PTS is out of range (-8269), dropping buffer main warning: computed PTS is out of range (321443), clearing out main warning: PTS is out of range (-27311), dropping buffer main warning: computed PTS is out of range (326760), clearing out main warning: picture is too late to be displayed (missing 92 ms) main warning: picture is too late to be displayed (missing 51 ms) main debug: picture might be displayed late (missing 9 ms) main warning: computed PTS is out of range (5661), clearing out main warning: PTS is out of range (5660), dropping buffer main warning: computed PTS is out of range (8085), clearing out main warning: PTS is out of range (-15136), dropping buffer main warning: computed PTS is out of range (11149), clearing out main warning: PTS is out of range (-35292), dropping buffer main warning: computed PTS is out of range (12912), clearing out main warning: computed PTS is out of range (3063), clearing out main warning: PTS is out of range (3062), dropping buffer main warning: computed PTS is out of range (5135), clearing out main warning: PTS is out of range (-18086), dropping buffer main warning: computed PTS is out of range (7700), clearing out main warning: PTS is out of range (-38741), dropping buffer main warning: computed PTS is out of range (60169), clearing out main warning: PTS is out of range (-9492), dropping buffer main warning: computed PTS is out of range (64173), clearing out main warning: PTS is out of range (-28708), dropping buffer main warning: computed PTS is out of range (68854), clearing out main warning: PTS is out of range (-9837), dropping buffer main warning: PTS is out of range (-29726), dropping buffer main warning: buffer too early (-46440), down-sampling main warning: picture is too late to be displayed (missing 89 ms) main warning: picture is too late to be displayed (missing 48 ms) main debug: picture might be displayed late (missing 6 ms) main warning: audio output out of sync, adjusting dates (67310 us) main warning: not synchronized (67311 us), resampling main warning: PTS is out of range (-22012), dropping buffer main debug: picture might be displayed late (missing 16 ms) main warning: PTS is out of range (-15122), dropping buffer main warning: PTS is out of range (-34909), dropping buffer main warning: buffer too early (-48766), down-sampling main warning: computed PTS is out of range (964), clearing out main warning: timing screwed, stopping resampling main warning: computed PTS is out of range (7319), clearing out main warning: PTS is out of range (7318), dropping buffer main warning: computed PTS is out of range (9751), clearing out main warning: PTS is out of range (-13470), dropping buffer main warning: computed PTS is out of range (11592), clearing out main warning: PTS is out of range (-34849), dropping buffer main warning: computed PTS is out of range (13307), clearing out main warning: computed PTS is out of range (16425), clearing out main warning: PTS is out of range (16424), dropping buffer main warning: computed PTS is out of range (18592), clearing out main warning: PTS is out of range (-4629), dropping buffer main warning: computed PTS is out of range (20670), clearing out main warning: PTS is out of range (-25771), dropping buffer main warning: computed PTS is out of range (24850), clearing out main warning: computed PTS is out of range (11766), clearing out main warning: PTS is out of range (11765), dropping buffer main warning: computed PTS is out of range (32216), clearing out main warning: PTS is out of range (8995), dropping buffer main warning: computed PTS is out of range (34148), clearing out main warning: PTS is out of range (-12293), dropping buffer main warning: computed PTS is out of range (47281), clearing out main warning: PTS is out of range (-22379), dropping buffer main warning: computed PTS is out of range (49282), clearing out main debug: picture might be displayed late (missing 3 ms) main warning: PTS is out of range (-18657), dropping buffer main warning: PTS is out of range (-38722), dropping buffer main warning: buffer too early (-46441), down-sampling main warning: computed PTS is out of range (14880), clearing out main warning: timing screwed, stopping resampling main warning: PTS is out of range (-31561), dropping buffer main warning: computed PTS is out of range (17143), clearing out main warning: PTS is out of range (-31244), dropping buffer main warning: computed PTS is out of range (4500), clearing out main warning: PTS is out of range (-18721), dropping buffer main warning: computed PTS is out of range (6605), clearing out main warning: PTS is out of range (-39836), dropping buffer main warning: computed PTS is out of range (8615), clearing out main warning: PTS is out of range (-13235), dropping buffer main warning: PTS is out of range (-33261), dropping buffer main warning: computed PTS is out of range (2400), clearing out main warning: PTS is out of range (-33014), dropping buffer main warning: computed PTS is out of range (34525), clearing out main warning: PTS is out of range (11304), dropping buffer main warning: computed PTS is out of range (36570), clearing out main warning: PTS is out of range (-9871), dropping buffer main warning: computed PTS is out of range (38381), clearing out main warning: PTS is out of range (-31280), dropping buffer main warning: computed PTS is out of range (40170), clearing out main warning: computed PTS is out of range (1347), clearing out main warning: PTS is out of range (1346), dropping buffer main warning: computed PTS is out of range (5281), clearing out main warning: PTS is out of range (-17940), dropping buffer main warning: computed PTS is out of range (10498), clearing out main warning: PTS is out of range (-35943), dropping buffer main warning: computed PTS is out of range (23072), clearing out main warning: PTS is out of range (-1179), dropping buffer main warning: computed PTS is out of range (8695), clearing out main warning: PTS is out of range (-14526), dropping buffer main warning: computed PTS is out of range (10622), clearing out main warning: PTS is out of range (-35819), dropping buffer main warning: computed PTS is out of range (12333), clearing out main warning: computed PTS is out of range (14631), clearing out main warning: PTS is out of range (14630), dropping buffer main warning: computed PTS is out of range (39079), clearing out main warning: PTS is out of range (15858), dropping buffer main warning: computed PTS is out of range (41398), clearing out main warning: PTS is out of range (-5043), dropping buffer main warning: computed PTS is out of range (54485), clearing out main warning: PTS is out of range (-15176), dropping buffer main warning: computed PTS is out of range (56950), clearing out main warning: PTS is out of range (-35931), dropping buffer main warning: computed PTS is out of range (58819), clearing out main debug: picture might be displayed late (missing 12 ms) main debug: picture might be displayed late (missing 8 ms) main warning: PTS is out of range (-20979), dropping buffer main debug: picture might be displayed late (missing 16 ms) main warning: audio output out of sync, adjusting dates (67365 us) main warning: not synchronized (67366 us), resampling main debug: picture might be displayed late (missing 17 ms) main warning: PTS is out of range (10313), dropping buffer main warning: PTS is out of range (-11057), dropping buffer main warning: PTS is out of range (-32660), dropping buffer main warning: picture is too late to be displayed (missing 20 ms) main debug: picture might be displayed late (missing 0 ms) main warning: computed PTS is out of range (50610), clearing out main warning: PTS is out of range (25096), dropping buffer main warning: computed PTS is out of range (54957), clearing out main warning: PTS is out of range (6223), dropping buffer main warning: computed PTS is out of range (66800), clearing out main warning: PTS is out of range (-5154), dropping buffer main warning: computed PTS is out of range (69403), clearing out main warning: PTS is out of range (-25771), dropping buffer main warning: computed PTS is out of range (71358), clearing out main debug: picture might be displayed late (missing 15 ms) main debug: picture might be displayed late (missing 4 ms) main warning: PTS is out of range (-25268), dropping buffer main warning: computed PTS is out of range (21154), clearing out main warning: PTS is out of range (-2067), dropping buffer main warning: computed PTS is out of range (25362), clearing out main warning: PTS is out of range (-21079), dropping buffer main warning: computed PTS is out of range (29294), clearing out main debug: picture might be displayed late (missing 0 ms) main warning: PTS is out of range (-30156), dropping buffer main debug: picture might be displayed late (missing 16 ms) main warning: audio output out of sync, adjusting dates (67389 us) main warning: not synchronized (67390 us), resampling main warning: PTS is out of range (23204), dropping buffer main warning: PTS is out of range (1777), dropping buffer main warning: PTS is out of range (-19831), dropping buffer main debug: picture might be displayed late (missing 14 ms) main warning: audio output out of sync, adjusting dates (69667 us) main warning: not synchronized (69668 us), resampling main warning: PTS is out of range (-29243), dropping buffer main warning: picture is too late to be displayed (missing 45 ms) main debug: picture might be displayed late (missing 4 ms) main warning: PTS is out of range (1835), dropping buffer main warning: PTS is out of range (-19395), dropping buffer main warning: audio output out of sync, adjusting dates (69666 us) main warning: PTS is out of range (-33432), dropping buffer main warning: buffer too early (-48702), down-sampling main warning: not synchronized (69666 us), resampling main warning: PTS is out of range (7268), dropping buffer main warning: PTS is out of range (-12779), dropping buffer main warning: PTS is out of range (-33097), dropping buffer main warning: buffer too early (-48696), down-sampling main debug: picture might be displayed late (missing 7 ms) main debug: audio output is too slow (8800 us): trashing 46440 us main debug: picture might be displayed late (missing 7 ms) main debug: picture might be displayed late (missing 10 ms) main warning: computed PTS is out of range (32461), clearing out main debug: audio output is too slow (43652 us): trashing 46440 us main warning: timing screwed, stopping resampling main warning: PTS is out of range (-16235), dropping buffer main warning: computed PTS is out of range (38846), clearing out main warning: PTS is out of range (-33070), dropping buffer main warning: computed PTS is out of range (51422), clearing out main debug: picture might be displayed late (missing 5 ms) main warning: computed PTS is out of range (45785), clearing out main warning: PTS is out of range (45785), dropping buffer main warning: computed PTS is out of range (48063), clearing out main warning: PTS is out of range (24843), dropping buffer main warning: computed PTS is out of range (50015), clearing out main warning: PTS is out of range (3575), dropping buffer main warning: computed PTS is out of range (51868), clearing out main warning: PTS is out of range (-17792), dropping buffer main warning: computed PTS is out of range (57083), clearing out main warning: PTS is out of range (-35797), dropping buffer main warning: computed PTS is out of range (58979), clearing out main warning: picture is too late to be displayed (missing 31 ms) main warning: PTS is out of range (-11401), dropping buffer main warning: PTS is out of range (-31070), dropping buffer main warning: computed PTS is out of range (4705), clearing out main warning: PTS is out of range (-21964), dropping buffer main debug: audio output is too slow (6590 us): trashing 46439 us main warning: computed PTS is out of range (15012), clearing out main warning: PTS is out of range (-8209), dropping buffer main warning: computed PTS is out of range (34957), clearing out main warning: PTS is out of range (-11484), dropping buffer main warning: computed PTS is out of range (55887), clearing out main warning: PTS is out of range (-13774), dropping buffer main warning: computed PTS is out of range (60197), clearing out main warning: PTS is out of range (-32684), dropping buffer main warning: computed PTS is out of range (64042), clearing out main warning: computed PTS is out of range (24654), clearing out main warning: PTS is out of range (24654), dropping buffer main warning: computed PTS is out of range (28915), clearing out main warning: PTS is out of range (5695), dropping buffer main warning: computed PTS is out of range (35012), clearing out main warning: PTS is out of range (-11428), dropping buffer main warning: computed PTS is out of range (39172), clearing out main warning: PTS is out of range (-30488), dropping buffer main warning: computed PTS is out of range (57986), clearing out main warning: PTS is out of range (-34894), dropping buffer main warning: computed PTS is out of range (62195), clearing out main warning: PTS is out of range (-24397), dropping buffer main warning: PTS is out of range (-38949), dropping buffer main warning: buffer too early (-46441), down-sampling main debug: audio output is too slow (6625 us): trashing 46439 us main warning: computed PTS is out of range (3540), clearing out main warning: timing screwed, stopping resampling main warning: PTS is out of range (-30918), dropping buffer main warning: PTS is out of range (-31142), dropping buffer main warning: buffer too early (-46440), down-sampling main debug: audio output is too slow (6619 us): trashing 46439 us main warning: computed PTS is out of range (5778), clearing out main warning: timing screwed, stopping resampling main warning: PTS is out of range (-16373), dropping buffer main warning: PTS is out of range (-25808), dropping buffer main warning: computed PTS is out of range (14903), clearing out main warning: PTS is out of range (-31538), dropping buffer main warning: computed PTS is out of range (19411), clearing out main warning: PTS is out of range (-25757), dropping buffer main warning: computed PTS is out of range (189), clearing out main warning: PTS is out of range (-23031), dropping buffer main warning: computed PTS is out of range (38805), clearing out main warning: PTS is out of range (-7635), dropping buffer main warning: computed PTS is out of range (43087), clearing out main warning: PTS is out of range (-26573), dropping buffer main warning: computed PTS is out of range (51911), clearing out main warning: PTS is out of range (-36028), dropping buffer main warning: PTS is out of range (-35905), dropping buffer main warning: buffer too early (-46441), down-sampling main warning: audio output out of sync, adjusting dates (67536 us) main warning: not synchronized (67537 us), resampling main warning: resampling stopped after 136994 usec (drift: -21097) main warning: PTS is out of range (-9540), dropping buffer main warning: PTS is out of range (-30778), dropping buffer main warning: PTS is out of range (-35500), dropping buffer main debug: audio output is too slow (8815 us): trashing 46440 us main warning: buffer too early (-48563), down-sampling main warning: audio output out of sync, adjusting dates (70324 us) main warning: computed PTS is out of range (6487), clearing out main warning: timing screwed, stopping resampling main warning: not synchronized (70324 us), resampling main warning: buffer too late (70323), up-sampling main warning: PTS is out of range (-26177), dropping buffer main warning: PTS is out of range (-39600), dropping buffer main warning: audio output out of sync, adjusting dates (68216 us) main warning: not synchronized (68217 us), resampling main warning: buffer too late (92101), up-sampling main warning: PTS is out of range (-21564), dropping buffer main warning: PTS is out of range (-12571), dropping buffer main warning: PTS is out of range (-24318), dropping buffer main debug: audio output is too slow (20413 us): trashing 46440 us main warning: PTS is out of range (98), dropping buffer main warning: PTS is out of range (-20004), dropping buffer main warning: PTS is out of range (-39595), dropping buffer main warning: buffer too early (-47242), down-sampling main debug: audio output is too slow (101758 us): trashing 46440 us main warning: computed PTS is out of range (24725), clearing out main warning: timing screwed, stopping resampling main warning: PTS is out of range (-22517), dropping buffer main warning: computed PTS is out of range (34680), clearing out main warning: PTS is out of range (-35781), dropping buffer main warning: computed PTS is out of range (36771), clearing out main warning: PTS is out of range (-16165), dropping buffer main warning: PTS is out of range (-36761), dropping buffer main warning: buffer too early (-46441), down-sampling main debug: audio output is too slow (7386 us): trashing 46439 us main warning: computed PTS is out of range (8654), clearing out main warning: timing screwed, stopping resampling main warning: PTS is out of range (-37787), dropping buffer main warning: computed PTS is out of range (11291), clearing out main warning: PTS is out of range (-38059), dropping buffer main warning: audio output out of sync, adjusting dates (68255 us) main warning: PTS is out of range (-33840), dropping buffer main warning: buffer too early (-46441), down-sampling main warning: not synchronized (68256 us), resampling main warning: PTS is out of range (-33029), dropping buffer main warning: PTS is out of range (-18914), dropping buffer main warning: computed PTS is out of range (19976), clearing out main warning: PTS is out of range (-4649), dropping buffer main warning: computed PTS is out of range (53655), clearing out main warning: PTS is out of range (5811), dropping buffer main warning: computed PTS is out of range (62011), clearing out main warning: PTS is out of range (-9053), dropping buffer main warning: computed PTS is out of range (66338), clearing out main warning: PTS is out of range (-27946), dropping buffer main warning: computed PTS is out of range (88080), clearing out main warning: PTS is out of range (-29424), dropping buffer main warning: computed PTS is out of range (107216), clearing out main warning: PTS is out of range (-33508), dropping buffer main warning: computed PTS is out of range (126422), clearing out main warning: PTS is out of range (-37522), dropping buffer main warning: computed PTS is out of range (128590), clearing out main warning: PTS is out of range (-32121), dropping buffer main warning: PTS is out of range (-31082), dropping buffer main warning: buffer too early (-46441), down-sampling main warning: audio output out of sync, adjusting dates (68286 us) main warning: not synchronized (68287 us), resampling main warning: resampling stopped after 111903 usec (drift: -21869) main warning: PTS is out of range (3894), dropping buffer main warning: PTS is out of range (-9148), dropping buffer main warning: PTS is out of range (-29499), dropping buffer main warning: buffer too early (-47791), down-sampling main warning: computed PTS is out of range (4629), clearing out main warning: timing screwed, stopping resampling main warning: PTS is out of range (-29197), dropping buffer main debug: incoming request - stopping current input main debug: dying input main debug: control: stopping input main debug: dying input main warning: early picture skipped main debug: removing module "avcodec" avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped main debug: killing decoder fourcc `h264', 7 PES in FIFO main debug: saving a free vout main debug: reusing provided vout main warning: received buffer in the future main debug: removing module "faad" main debug: killing decoder fourcc `mp4a', 57 PES in FIFO main debug: removing module "scaletempo" main debug: removing module "samplerate" main debug: removing module "auhal" main debug: removing module "float32_mixer" main debug: releasing audio output main debug: removing module "mp4" mp4 debug: freeing all memory main debug: removing module "stream_filter_record" main debug: removing module "filesystem" main debug: Program doesn't contain anymore ES main debug: dead input main debug: destroying useless vout main debug: removing module "blend" main debug: Filter 0x938298 removed from chain main debug: removing module "i420_yuy2_altivec" main debug: removing module "vout_macosx" main debug: removing module "freetype" main debug: removing module "yuvp" main debug: removing module "swscale"
AUDIO DISABLED

Code: Select all

main debug: using interface module "macosx" main debug: TIMER module_need() : 501.991 ms - Total 501.991 ms / 1 intvls (Avg 501.991 ms) main debug: rebuilding array of current - root Playlist main debug: rebuild done - 0 items, index -1 main debug: playlist threads correctly activated main debug: looking for services probe module: 5 candidates main debug: no services probe module matching "any" could be loaded main debug: TIMER module_need() : 14.077 ms - Total 14.077 ms / 1 intvls (Avg 14.077 ms) macosx debug: using Leopard AR cookies main debug: looking for extension module: 1 candidate lua debug: Opening Lua Extension module lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/extensions lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/extensions lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/extensions main debug: using extension module "lua" main debug: TIMER module_need() : 9.721 ms - Total 9.721 ms / 1 intvls (Avg 9.721 ms) macosx debug: Found 0 capture devices macosx debug: no optical media found main debug: adding item `Litter Genie- I Haz a Catnip in Mah Head.mp4' ( file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4 ) main debug: rebuilding array of current - root Playlist main debug: Creating an input for 'Litter Genie- I Haz a Catnip in Mah Head.mp4' main debug: rebuild done - 1 items, index -1 main debug: processing request item: Litter Genie- I Haz a Catnip in Mah Head.mp4, node: Playlist, skip: 0 main debug: resyncing on Litter Genie- I Haz a Catnip in Mah Head.mp4 main debug: Litter Genie- I Haz a Catnip in Mah Head.mp4 is at 0 main debug: starting playback of the new playlist item main debug: resyncing on Litter Genie- I Haz a Catnip in Mah Head.mp4 main debug: Litter Genie- I Haz a Catnip in Mah Head.mp4 is at 0 main debug: creating new input thread main debug: Creating an input for 'Litter Genie- I Haz a Catnip in Mah Head.mp4' main debug: using timeshift granularity of 50 MiB, in path '/tmp' main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' gives access `file' demux `' path `/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' main debug: creating demux: access='file' demux='' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' file='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4' 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.884 ms - Total 0.884 ms / 1 intvls (Avg 0.884 ms) main debug: creating access 'file' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4', path='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4' main debug: looking for access module: 3 candidates filesystem debug: opening file `/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4' main debug: using access module "filesystem" main debug: TIMER module_need() : 0.664 ms - Total 0.664 ms / 1 intvls (Avg 0.664 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 - 5208 KiB/s main debug: looking for stream_filter module: 7 candidates main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 0.402 ms - Total 0.402 ms / 1 intvls (Avg 0.402 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.403 ms - Total 0.403 ms / 1 intvls (Avg 0.403 ms) main debug: creating demux: access='file' demux='' location='/Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' file='/Users/Mic/Desktop/Litter Genie- I Haz a Catnip in Mah Head.mp4' main debug: looking for demux module: 55 candidates mp4 warning: unknown box type btrt (incompletely loaded) main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) mp4 warning: unknown box type gsst (incompletely loaded) mp4 warning: unknown box type gstd (incompletely loaded) mp4 warning: unknown box type gssd (incompletely loaded) mp4 warning: unknown box type gspu (incompletely loaded) mp4 warning: unknown box type gspm (incompletely loaded) mp4 warning: unknown box type gshh (incompletely loaded) mp4 debug: dumping root Box "root" mp4 debug: | + ftyp size 24 mp4 debug: | + moov size 24217 mp4 debug: | | + mvhd size 108 mp4 debug: | | + iods size 21 mp4 debug: | | + trak size 8504 mp4 debug: | | | + tkhd size 92 mp4 debug: | | | + mdia size 8404 mp4 debug: | | | | + mdhd size 32 mp4 debug: | | | | + hdlr size 45 mp4 debug: | | | | + minf size 8319 mp4 debug: | | | | | + vmhd size 20 mp4 debug: | | | | | + dinf size 36 mp4 debug: | | | | | | + dref size 28 mp4 debug: | | | | | | | + url size 12 mp4 debug: | | | | | + stbl size 8255 mp4 debug: | | | | | | + stsd size 167 mp4 debug: | | | | | | | + avc1 size 151 mp4 debug: | | | | | | | | + avcC size 45 mp4 debug: | | | | | | | | + btrt size 20 mp4 debug: | | | | | | + stts size 24 mp4 debug: | | | | | | + stss size 172 mp4 debug: | | | | | | + stsc size 52 mp4 debug: | | | | | | + stsz size 7164 mp4 debug: | | | | | | + stco size 668 mp4 debug: | | + trak size 14821 mp4 debug: | | | + tkhd size 92 mp4 debug: | | | + mdia size 14721 mp4 debug: | | | | + mdhd size 32 mp4 debug: | | | | + hdlr size 76 mp4 debug: | | | | + minf size 14605 mp4 debug: | | | | | + smhd size 16 mp4 debug: | | | | | + dinf size 36 mp4 debug: | | | | | | + dref size 28 mp4 debug: | | | | | | | + url size 12 mp4 debug: | | | | | + stbl size 14545 mp4 debug: | | | | | | + stsd size 105 mp4 debug: | | | | | | | + mp4a size 89 mp4 debug: | | | | | | | | + esds size 53 mp4 debug: | | | | | | + stts size 24 mp4 debug: | | | | | | + stsc size 892 mp4 debug: | | | | | | + stsz size 12852 mp4 debug: | | | | | | + stco size 664 mp4 debug: | | + udta size 755 mp4 debug: | | | + meta size 747 mp4 debug: | | | | + hdlr size 33 mp4 debug: | | | | + ilst size 702 mp4 debug: | | | | | + gsst size 25 mp4 debug: | | | | | + gstd size 29 mp4 debug: | | | | | + gssd size 56 mp4 debug: | | | | | + gspu size 152 mp4 debug: | | | | | + gspm size 152 mp4 debug: | | | | | + gshh size 280 mp4 debug: | + mdat size 6378483 mp4 debug: unrecognized major file specification (mp42). mp4 debug: found 2 tracks mp4 debug: track[Id 0x1] read 163 chunk mp4 debug: track[Id 0x1] read 1786 samples length:74s main debug: selecting program id=0 mp4 debug: adding track[Id 0x1] video (enable) language undef mp4 debug: track[Id 0x2] read 162 chunk mp4 debug: track[Id 0x2] read 3208 samples length:74s mp4 debug: adding track[Id 0x2] audio (enable) language undef main debug: using demux module "mp4" main debug: TIMER module_need() : 23.606 ms - Total 23.606 ms / 1 intvls (Avg 23.606 ms) main debug: looking for a subtitle file in /Users/Mic/Desktop/ main debug: looking for decoder module: 32 candidates avcodec debug: libavcodec initialized (interface 0x363f64) avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started main debug: using decoder module "avcodec" main debug: TIMER module_need() : 230.207 ms - Total 230.207 ms / 1 intvls (Avg 230.207 ms) main debug: audio is disabled, not selecting ES 0x1 main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in /Users/Mic/Library/Application Support/org.videolan.vlc/lua/meta/reader lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader lua debug: Trying Lua playlist script /Applications/Video/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac lua debug: Trying Lua scripts in /Applications/Video/VLC.app/Contents/MacOS/share/share/lua/meta/reader main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 8.237 ms - Total 8.237 ms / 1 intvls (Avg 8.237 ms) main debug: `file:///Users/Mic/Desktop/Litter%20Genie-%20I%20Haz%20a%20Catnip%20in%20Mah%20Head.mp4' successfully opened mp4 debug: track[Id 0x1] using Sync Sample Box (stss) mp4 debug: stts gives 0 --> 0 (sample number) main debug: Buffering 0% main debug: looking for text renderer module: 3 candidates freetype debug: looking for Arial Unicode MS main debug: Buffering 0% main debug: Buffering 10% main debug: Buffering 20% main debug: Buffering 30% main debug: Buffering 40% main debug: Buffering 50% main debug: Buffering 60% main debug: Buffering 70% main debug: Buffering 80% main debug: Buffering 90% main debug: Buffering 100% main debug: Stream buffering done (1100 ms in 29 ms) freetype debug: found /Library/Fonts/Arial Unicode.ttf freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 45.304 ms - Total 45.304 ms / 1 intvls (Avg 45.304 ms) main debug: looking for video filter2 module: 13 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 8.875 ms - Total 8.875 ms / 1 intvls (Avg 8.875 ms) main debug: looking for video filter2 module: 13 candidates yuvp debug: YUVP to YUVA converter main debug: using video filter2 module "yuvp" main debug: TIMER module_need() : 7.075 ms - Total 7.075 ms / 1 intvls (Avg 7.075 ms) main debug: Deinterlacing available main debug: deinterlace 0, mode blend, is_needed 0 main debug: Opening vout display wrapper main debug: looking for vout display module: 2 candidates vout_macosx debug: Quartz Extreme acceleration is active main debug: looking for vout window nsobject module: 1 candidate macosx debug: prevented sleep through IOKit (0) macosx debug: prevented sleep through IOKit (0) macosx debug: returning videoview with x=0, y=36, width=640, height=360 main debug: using vout window nsobject module "macosx" main debug: TIMER module_need() : 208.812 ms - Total 208.812 ms / 1 intvls (Avg 208.812 ms) macosx debug: prevented sleep through IOKit (0) main debug: VoutDisplayEvent 'resize' 640x360 window main debug: using vout display module "vout_macosx" main debug: TIMER module_need() : 372.963 ms - Total 372.963 ms / 1 intvls (Avg 372.963 ms) main debug: A filter to adapt decoder to display is needed main debug: looking for video filter2 module: 13 candidates main debug: using video filter2 module "i420_yuy2_altivec" main debug: TIMER module_need() : 0.540 ms - Total 0.540 ms / 1 intvls (Avg 0.540 ms) main debug: Filter 'i420_yuy2_altivec' (0x93ae98) appended to chain main debug: original format sz 640x360, of (0,0), vsz 640x360, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 main debug: removing module "freetype" main debug: looking for text renderer module: 3 candidates freetype debug: looking for Arial Unicode MS freetype debug: found /Library/Fonts/Arial Unicode.ttf freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 7.024 ms - Total 7.024 ms / 1 intvls (Avg 7.024 ms) avcodec debug: using direct rendering main debug: End of video preroll main debug: Received first picture freetype debug: looking for Arial Unicode MS freetype debug: found /Library/Fonts/Arial Unicode.ttf main debug: looking for video blending module: 1 candidate main debug: using video blending module "blend" main debug: TIMER module_need() : 2.977 ms - Total 2.977 ms / 1 intvls (Avg 2.977 ms) main debug: Post-processing available main debug: Decoder buffering done in 537 ms main debug: VoutDisplayEvent 'resize' 640x360 window main debug: incoming request - stopping current input main debug: dying input main debug: control: stopping input main debug: dying input main warning: can't get output picture avcodec warning: disabling direct rendering main warning: can't get output picture main debug: removing module "avcodec" avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped main debug: killing decoder fourcc `h264', 5 PES in FIFO main debug: saving a free vout main debug: reusing provided vout main debug: removing module "mp4" mp4 debug: freeing all memory main debug: removing module "stream_filter_record" main debug: removing module "filesystem" main debug: Program doesn't contain anymore ES main debug: dead input main debug: destroying useless vout main debug: removing module "blend" main debug: Filter 0x93ae98 removed from chain main debug: removing module "i420_yuy2_altivec" main debug: removing module "vout_macosx" main debug: removing module "freetype" main debug: removing module "yuvp" main debug: removing module "swscale"

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: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby Jean-Baptiste Kempf » 18 Dec 2012 02:03

Yet, this does not explain why it goes from 35 to 60%...
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.

B-G
Blank Cone
Blank Cone
Posts: 18
Joined: 19 Feb 2012 14:39

Re: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby B-G » 28 Dec 2012 20:45

Yet, this does not explain why it goes from 35 to 60%...
Let me know if there is any test I can do to help you.

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: VLC 2.0.4 PowerPC - Unusually high CPU load

Postby Jean-Baptiste Kempf » 29 Dec 2012 01:20

Profiling is the next step, unfortunately.
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.


Return to “VLC media player for macOS Troubleshooting”

Who is online

Users browsing this forum: No registered users and 30 guests