rendered paste body01:21:36 T:2684407808 M: 70111232 NOTICE: -----------------------------------------------------------------------
01:21:36 T:2684407808 M: 70107136 NOTICE: Starting XBMC, Platform: Mac OS X. Built on Dec 23 2009 (SVN:26018)
01:21:36 T:2684407808 M: 70107136 NOTICE: special://xbmc/ is mapped to: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC
01:21:36 T:2684407808 M: 70107136 NOTICE: special://masterprofile/ is mapped to: /Users/frontrow/Library/Application Support/XBMC/userdata
01:21:36 T:2684407808 M: 70107136 NOTICE: special://home/ is mapped to: /Users/frontrow/Library/Application Support/XBMC
01:21:36 T:2684407808 M: 70107136 NOTICE: special://temp/ is mapped to: /Users/frontrow/.xbmc/temp
01:21:36 T:2684407808 M: 70107136 NOTICE: The executable running is: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/MacOS/XBMC
01:21:36 T:2684407808 M: 70107136 NOTICE: Log File is located: /Users/frontrow/Library/Logs/xbmc.log
01:21:36 T:2684407808 M: 70107136 NOTICE: -----------------------------------------------------------------------
01:21:36 T:2684407808 M: 70107136 NOTICE: Setup SDL
01:21:37 T:2684407808 M: 69685248 NOTICE: load settings...
01:21:37 T:2684407808 M: 69685248 NOTICE: special://profile/ is mapped to: special://home/userdata/
01:21:37 T:2684407808 M: 69685248 NOTICE: loading special://masterprofile/guisettings.xml
01:21:37 T:2684407808 M: 69246976 NOTICE: Getting hardware information now...
01:21:37 T:2684407808 M: 69246976 NOTICE: Checking resolution 12
01:21:37 T:2684407808 M: 69128192 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
01:21:37 T:2684407808 M: 69115904 NOTICE: Loaded playercorefactory configuration
01:21:37 T:2684407808 M: 69111808 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
01:21:37 T:2684407808 M: 69111808 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
01:21:37 T:2684407808 M: 69107712 NOTICE: Loaded advancedsettings.xml from special://profile/advancedsettings.xml
01:21:37 T:2684407808 M: 69107712 NOTICE: Contents of special://profile/advancedsettings.xml are...
<advancedsettings>
<skiploopfilter>8</skiploopfilter>
<samba>
<statfiles>false</statfiles>
</samba>
<osx_gl_fullscreen>true</osx_gl_fullscreen>
</advancedsettings>
01:21:37 T:2684407808 M: 69107712 NOTICE: Getting hardware information now...
01:21:37 T:2684407808 M: 69107712 NOTICE: Checking resolution 12
01:21:37 T:2684407808 M: 69107712 NOTICE: Default DVD Player: dvdplayer
01:21:37 T:2684407808 M: 69107712 NOTICE: Default Video Player: dvdplayer
01:21:37 T:2684407808 M: 69107712 NOTICE: Default Audio Player: paplayer
01:21:37 T:2684407808 M: 69107712 NOTICE: Enabled debug logging due to GUI setting (2)
01:21:37 T:2684407808 M: 69107712 NOTICE: special://home/userdata/sources.xml
01:21:37 T:2684407808 M: 69095424 DEBUG: Setting <default> source to : Music
01:21:38 T:2684407808 M: 64086016 NOTICE: GL_VENDOR = NVIDIA Corporation
01:21:38 T:2684407808 M: 64081920 NOTICE: GL_RENDERER = NVIDIA GeForce Go 7300 OpenGL Engine
01:21:38 T:2684407808 M: 64081920 NOTICE: GL_VERSION = 1.5 NVIDIA-1.4.60
01:21:38 T:2684407808 M: 64081920 NOTICE: GL_EXTENSIONS = GL_ARB_transpose_matrix GL_ARB_vertex_program GL_ARB_vertex_blend GL_ARB_window_pos GL_ARB_shader_objects GL_ARB_vertex_shader GL_EXT_multi_draw_arrays GL_EXT_clip_volume_hint GL_EXT_rescale_normal GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_gpu_program_parameters GL_APPLE_client_storage GL_APPLE_specular_vector GL_APPLE_transform_hint GL_APPLE_packed_pixels GL_APPLE_fence GL_APPLE_vertex_array_object GL_APPLE_vertex_program_evaluators GL_APPLE_element_array GL_APPLE_flush_render GL_NV_texgen_reflection GL_NV_light_max_exponent GL_IBM_rasterpos_clip GL_SGIS_generate_mipmap GL_ARB_shading_language_100 GL_ARB_imaging GL_ARB_point_parameters GL_ARB_texture_env_crossbar GL_ARB_texture_border_clamp GL_ARB_multitexture GL_ARB_texture_env_add GL_ARB_texture_cube_map GL_ARB_texture_env_dot3 GL_ARB_multisample GL_ARB_texture_env_combine GL_ARB_texture_compression GL_ARB_texture_mirrored_repeat GL_ARB_shadow GL_ARB_depth_texture GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_occlusion_query GL_ARB_point_sprite GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ARB_pixel_buffer_object GL_ARB_draw_buffers GL_ARB_shader_texture_lod GL_EXT_compiled_vertex_array GL_EXT_framebuffer_object GL_EXT_texture_rectangle GL_ARB_texture_rectangle GL_EXT_texture_env_add GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_texture_lod_bias GL_EXT_abgr GL_EXT_bgra GL_EXT_stencil_wrap GL_EXT_texture_filter_anisotropic GL_EXT_secondary_color GL_EXT_blend_func_separate GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_texture_compression_s3tc GL_EXT_texture_compression_dxt1 GL_EXT_blend_equation_separate GL_EXT_texture_mirror_clamp GL_EXT_packed_depth_stencil GL_APPLE_flush_buffer_range GL_APPLE_ycbcr_422 GL_APPLE_vertex_array_range GL_APPLE_texture_range GL_APPLE_float_pixels GL_ATI_texture_float GL_ARB_texture_float GL_APPLE_pixel_buffer GL_NV_point_sprite GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_blend_square GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_fog_distance GL_NV_depth_clamp GL_NV_multisample_filter_hint GL_NV_fragment_program_option GL_NV_vertex_program2_option GL_ATI_texture_mirror_once GL_ATI_texture_env_combine3 GL_ATI_separate_stencil GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod
01:21:38 T:2684407808 M: 63713280 INFO: GL: Maximum texture width: 2048
01:21:38 T:2684407808 M: 63713280 INFO: GL: Maximum texture width: 2048
01:21:38 T:2684407808 M: 63713280 INFO: load language info file: special://xbmc/language/English/langinfo.xml
01:21:38 T:2684407808 M: 63627264 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/ImageLib-x86-osx.so
01:21:38 T:2684407808 M: 60960768 INFO: load language file:special://xbmc/language/English/strings.xml
01:21:38 T:2684407808 M: 60301312 INFO: load keymapping
01:21:38 T:2684407808 M: 60293120 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
01:21:38 T:2684407808 M: 60235776 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
01:21:38 T:2684407808 M: 60223488 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
01:21:38 T:2684407808 M: 60198912 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
01:21:38 T:2684407808 M: 60190720 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
01:21:38 T:2684407808 M: 60166144 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
01:21:38 T:2684407808 M: 60162048 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
01:21:38 T:2684407808 M: 60149760 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
01:21:38 T:2684407808 M: 60133376 INFO: Loading special://xbmc/system/keymaps/remote.xml
01:21:39 T:2684407808 M: 60116992 INFO: Checking skin version of: Confluence
01:21:39 T:2684407808 M: 60112896 INFO: Skin version is: 2.11 (2.110000)
01:21:39 T:2684407808 M: 60112896 INFO: GUI format 1280x720 1280x720 @ 60.00 - Full Screen
01:21:39 T:2684407808 M: 60088320 INFO: creating subdirectories
01:21:39 T:2684407808 M: 60088320 INFO: userdata folder: special://home/userdata/
01:21:39 T:2684407808 M: 60088320 INFO: recording folder:
01:21:39 T:2684407808 M: 60088320 INFO: screenshots folder:
01:21:39 T:2684407808 M: 60084224 INFO: thumbnails folder: special://home/userdata/Thumbnails
01:21:39 T:2684407808 M: 60084224 NOTICE: start dvd mediatype detection
01:21:39 T:42529280 M: 60080128 DEBUG: thread start, auto delete: 0
01:21:39 T:42529280 M: 60076032 DEBUG: Compiled with libcdio Version 0.81
01:21:39 T:2684407808 M: 60051456 NOTICE: initializing playlistplayer
01:21:39 T:2684407808 M: 60051456 NOTICE: DONE initializing playlistplayer
01:21:39 T:2684407808 M: 60047360 NOTICE: load default skin:[Confluence]
01:21:39 T:2684407808 M: 60018688 INFO: load skin from:special://xbmc/skin/Confluence
01:21:39 T:2684407808 M: 60018688 INFO: delete old skin...
01:21:39 T:2684407808 M: 60018688 DEBUG: ------ Window Deinit (Pointer.xml) ------
01:21:39 T:2684407808 M: 60018688 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
01:21:39 T:2684407808 M: 59998208 INFO: Default 4:3 resolution directory is special://xbmc/skin/Confluence/720p
01:21:39 T:2684407808 M: 59998208 INFO: Default 16:9 resolution directory is special://xbmc/skin/Confluence/720p
01:21:39 T:2684407808 M: 59998208 INFO: Skin version is: 2.11
01:21:39 T:2684407808 M: 59830272 INFO: Loading skin includes from /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/skin/Confluence/720p/includes.xml
01:21:39 T:2684407808 M: 58691584 INFO: load fonts for skin...
01:21:39 T:2684407808 M: 58679296 INFO: Loading colors from special://xbmc/skin/Confluence/colors/SKINDEFAULT.xml
01:21:39 T:2684407808 M: 58679296 INFO: Loading fonts from special://xbmc/skin/Confluence/720p/Font.xml
01:21:39 T:2684407808 M: 56029184 INFO: load new skin...
01:21:39 T:2684407808 M: 56029184 INFO: Skin version is: 2.11 (2.110000)
01:21:39 T:2684407808 M: 56029184 INFO: Loading skin file: Home.xml
01:21:39 T:2684407808 M: 53534720 DEBUG: Load Home.xml: 272.22ms
01:21:39 T:2684407808 M: 53534720 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
01:21:39 T:2684407808 M: 53534720 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
01:21:39 T:2684407808 M: 53469184 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
01:21:39 T:2684407808 M: 53465088 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
01:21:39 T:2684407808 M: 53465088 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
01:21:39 T:2684407808 M: 53465088 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
01:21:40 T:2684407808 M: 53465088 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
01:21:40 T:2684407808 M: 53465088 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
01:21:40 T:2684407808 M: 53465088 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
01:21:40 T:2684407808 M: 53465088 DEBUG: Load Skin XML: 406.76ms
01:21:40 T:2684407808 M: 53465088 INFO: initialize new skin...
01:21:40 T:2684407808 M: 53465088 INFO: Loading skin file: Pointer.xml
01:21:40 T:2684407808 M: 53460992 DEBUG: Load Pointer.xml: 16.51ms
01:21:40 T:2684407808 M: 53047296 DEBUG: Alloc resources: 49.39ms (16.73 ms skin load)
01:21:40 T:2684407808 M: 53047296 INFO: Loading skin file: DialogVolumeBar.xml
01:21:40 T:2684407808 M: 53043200 DEBUG: Load DialogVolumeBar.xml: 14.49ms
01:21:40 T:2684407808 M: 53035008 DEBUG: Alloc resources: 26.10ms (14.73 ms skin load)
01:21:40 T:2684407808 M: 53030912 INFO: Loading skin file: DialogSeekBar.xml
01:21:40 T:2684407808 M: 53018624 DEBUG: Load DialogSeekBar.xml: 25.18ms
01:21:40 T:2684407808 M: 53014528 DEBUG: Alloc resources: 26.08ms (25.49 ms skin load)
01:21:40 T:2684407808 M: 53014528 INFO: Loading skin file: DialogKaiToast.xml
01:21:40 T:2684407808 M: 53010432 DEBUG: Load DialogKaiToast.xml: 13.37ms
01:21:40 T:2684407808 M: 53010432 DEBUG: Alloc resources: 13.60ms (13.60 ms skin load)
01:21:40 T:2684407808 M: 53010432 INFO: Loading skin file: DialogMuteBug.xml
01:21:40 T:2684407808 M: 53006336 DEBUG: Load DialogMuteBug.xml: 5.64ms
01:21:40 T:2684407808 M: 53006336 DEBUG: Alloc resources: 5.86ms (5.86 ms skin load)
01:21:40 T:2684407808 M: 53002240 INFO: skin loaded...
01:21:40 T:2684407808 M: 52654080 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/libcurl-x86-osx.so
01:21:40 T:2684407808 M: 52264960 DEBUG: Activating window ID: 12999
01:21:40 T:2684407808 M: 52264960 DEBUG: Checking if window ID 12999 is locked.
01:21:40 T:2684407808 M: 52264960 DEBUG: ------ Window Init (Startup.xml) ------
01:21:40 T:2684407808 M: 52264960 INFO: Loading skin file: Startup.xml
01:21:40 T:2684407808 M: 52523008 DEBUG: Load Startup.xml: 68.43ms
01:21:40 T:2684407808 M: 52523008 DEBUG: Alloc resources: 68.75ms (68.75 ms skin load)
01:21:40 T:2684407808 M: 52523008 INFO: removing tempfiles
01:21:40 T:2684407808 M: 52482048 NOTICE: initialize done
01:21:40 T:2684407808 M: 52477952 NOTICE: Running the application...
01:21:40 T:2684407808 M: 52477952 DEBUG: ExecuteXBMCAction : Translating SetFocus(10)
01:21:40 T:2684407808 M: 52477952 DEBUG: ExecuteXBMCAction : To SetFocus(10)
01:21:40 T:2684407808 M: 52477952 DEBUG: Process - no user autoexec.py (/Users/frontrow/Library/Application Support/XBMC/scripts/autoexec.py) found, skipping
01:21:40 T:2684407808 M: 52477952 DEBUG: Process - no system autoexec.py (/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/scripts/autoexec.py) found, skipping
01:21:40 T:2684407808 M: 52477952 DEBUG: NetworkMessage - Starting network services
01:21:40 T:2684407808 M: 52473856 NOTICE: ES: Starting event server
01:21:40 T:43323904 M: 52469760 DEBUG: thread start, auto delete: 0
01:21:40 T:43323904 M: 52469760 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
01:21:40 T:43323904 M: 52469760 NOTICE: UDP: Listening on port 9777
01:21:40 T:2684407808 M: 52465664 NOTICE: starting zeroconf publishing
01:21:40 T:2684407808 M: 52465664 DEBUG: CZeroconfOSX::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
01:21:40 T:2684407808 M: 52387840 DEBUG: CLastfmScrobbler: Clearing session.
01:21:40 T:2684407808 M: 52387840 DEBUG: CLibrefmScrobbler: Clearing session.
01:21:41 T:2684407808 M: 50368512 INFO: GL: Disabling VSYNC
01:21:41 T:43348992 M: 50057216 DEBUG: thread start, auto delete: 1
01:21:41 T:2684407808 M: 51113984 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
01:21:41 T:2684407808 M: 51113984 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
01:21:41 T:2684407808 M: 51113984 DEBUG: Activating window ID: 10000
01:21:41 T:2684407808 M: 51113984 DEBUG: Checking if window ID 10000 is locked.
01:21:41 T:2684407808 M: 51113984 DEBUG: ------ Window Deinit (Startup.xml) ------
01:21:41 T:2684407808 M: 51113984 DEBUG: ------ Window Init (Home.xml) ------
01:21:41 T:2684407808 M: 50925568 DEBUG: Alloc resources: 3.56ms (0.00 ms skin load)
01:21:41 T:43313152 M: 50921472 DEBUG: thread start, auto delete: 1
01:21:41 T:43313152 M: 50921472 INFO: WEATHER: Downloading weather
01:21:41 T:43313152 M: 50921472 DEBUG: FileCurl::Open(0xb01c0d8c) http://xoap.weather.com/weather/local/GMXX0007?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
01:21:41 T:43313152 M: 50794496 INFO: easy_aquire - Created session to http://xoap.weather.com
01:21:41 T:43403776 M: 47267840 DEBUG: thread start, auto delete: 1
01:21:41 T:43313152 M: 40534016 DEBUG: FileCurl::Close(0xb01c0d8c) http://xoap.weather.com/weather/local/GMXX0007?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
01:21:41 T:43313152 M: 40534016 INFO: WEATHER: Weather download successful
01:21:42 T:2684407808 M: 29609984 DEBUG: ExecuteXBMCAction : Translating XBMC.RunScript(special://skin/scripts/RecentlyAdded.py,limit=4)
01:21:42 T:2684407808 M: 29609984 DEBUG: ExecuteXBMCAction : To XBMC.RunScript(special://skin/scripts/RecentlyAdded.py,limit=4)
01:21:42 T:2684407808 M: 29609984 INFO: initializing python engine.
01:21:42 T:2684407808 M: 29609984 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/python24-x86-osx.so
01:21:42 T:43348992 M: 31272960 DEBUG: DoWork - took 1018 ms to load special://skin/backgrounds/videos.jpg
01:21:42 T:2684407808 M: 34750464 DEBUG: Python wrapper library linked with internal Python library
01:21:42 T:43403776 M: 31453184 DEBUG: DoWork - took 914 ms to load special://skin/backgrounds/videos.jpg
01:21:42 T:2684407808 M: 34353152 DEBUG: new python thread created. id=1
01:21:42 T:43614720 M: 34349056 DEBUG: thread start, auto delete: 0
01:21:42 T:43614720 M: 34349056 DEBUG: Python thread: start processing
01:21:43 T:2684407808 M: 34205696 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000)
01:21:43 T:2684407808 M: 34205696 DEBUG: ExecuteXBMCAction : To SetFocus(9000)
01:21:43 T:43614720 M: 29990912 NOTICE: -->Python Interpreter Initialized<--
01:21:43 T:43614720 M: 29990912 DEBUG: Process - The source file to load is special://skin/scripts/RecentlyAdded.py
01:21:43 T:43614720 M: 29990912 DEBUG: Process - Setting the Python path to /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/skin/Confluence/scripts:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/Lib:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/lib/python24.zip:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/lib/python2.4/:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/lib/python2.4/plat-darwin:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/lib/python2.4/plat-mac:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/lib/python2.4/plat-mac/lib-scriptpackages:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/lib/python2.4/lib-tk:/mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/python/lib/python2.4/lib-dynload
01:21:43 T:43614720 M: 29990912 DEBUG: Process - Entering source directory /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/skin/Confluence/scripts
01:21:43 T:43614720 M: 29667328 DEBUG: xbmcHttpShim starts
01:21:43 T:2684407808 M: 29667328 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat
01:21:43 T:2684407808 M: 29667328 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; OpenRecord;<record>
01:21:43 T:2684407808 M: 29667328 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; CloseRecord;</record>
01:21:43 T:2684407808 M: 29667328 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from movieview order by idMovie desc limit 4
01:21:43 T:2684407808 M: 29667328 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from episodeview order by idepisode desc limit 4
01:21:43 T:43614720 M: 29667328 INFO: Scriptresult: Success
01:21:44 T:43614720 M: 29646848 INFO: Python script stopped
01:21:44 T:43614720 M: 29646848 DEBUG: Thread 43614720 terminating
01:21:44 T:2684407808 M: 29708288 DEBUG: python thread 1 destructed
01:21:44 T:2684407808 M: 29708288 INFO: Python, unloading python24.dll because no scripts are running anymore
01:21:44 T:2684407808 M: 29708288 DEBUG: UnloadExtensionLibs, clearing python extension libraries
01:21:44 T:2684407808 M: 29708288 DEBUG: Unloading: python24-x86-osx.so
01:21:48 T:2684407808 M: 30334976 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoFiles)
01:21:48 T:2684407808 M: 30334976 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoFiles)
01:21:48 T:2684407808 M: 30334976 DEBUG: Activating window ID: 10024
01:21:48 T:2684407808 M: 30334976 DEBUG: Checking if window ID 10024 is locked.
01:21:48 T:2684407808 M: 30334976 DEBUG: ------ Window Deinit (Home.xml) ------
01:21:48 T:2684407808 M: 30334976 INFO: Attempting to default to:
01:21:48 T:2684407808 M: 29937664 DEBUG: ------ Window Init (MyVideo.xml) ------
01:21:48 T:2684407808 M: 29933568 INFO: Loading skin file: MyVideo.xml
01:21:48 T:2684407808 M: 29921280 DEBUG: Load MyVideo.xml: 128.48ms
01:21:48 T:2684407808 M: 29724672 DEBUG: Alloc resources: 225.38ms (128.87 ms skin load)
01:21:48 T:2684407808 M: 29724672 DEBUG: CGUIMediaWindow::GetDirectory ()
01:21:48 T:2684407808 M: 29724672 DEBUG: ParentPath = []
01:21:48 T:43878400 M: 29720576 DEBUG: thread start, auto delete: 0
01:21:48 T:43878400 M: 29712384 DEBUG: Thread 43878400 terminating
01:21:49 T:43348992 M: 29528064 DEBUG: DoWork - took 580 ms to load special://home/userdata/Thumbnails/Programs/dbf47c45.tbn
01:21:50 T:43403776 M: 29130752 DEBUG: DoWork - took 42 ms to load special://home/userdata/Thumbnails/Programs/6da013cd.tbn
01:21:51 T:2684407808 M: 27344896 DEBUG: CGUIMediaWindow::GetDirectory (smb://NAS/video/Documentaries/)
01:21:51 T:2684407808 M: 27344896 DEBUG: ParentPath = []
01:21:51 T:2684407808 M: 27340800 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries
01:21:53 T:2684407808 M: 27262976 DEBUG: Saving fileitems [smb://NAS/video/Documentaries/]
01:21:53 T:2684407808 M: 27258880 DEBUG: -- items: 28, sort method: 0, ascending: false
01:21:54 T:43878400 M: 26947584 DEBUG: thread start, auto delete: 0
01:21:54 T:43878400 M: 26898432 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/BBC
01:21:54 T:43878400 M: 27566080 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/Cosmos
01:21:54 T:43878400 M: 27582464 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/Discovery
01:21:54 T:43878400 M: 27582464 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/HBO
01:21:54 T:43878400 M: 27582464 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/National%20Geographic
01:21:54 T:43878400 M: 27582464 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/No%20Maps%20For%20This%20Territories
01:21:54 T:43878400 M: 27582464 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/Spanish
01:21:54 T:43878400 M: 27582464 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries/TED
01:21:54 T:43313152 M: 27533312 DEBUG: host name = nas
01:21:55 T:43878400 M: 26996736 DEBUG: Thread 43878400 terminating
01:22:02 T:2684407808 M: 27193344 DEBUG: new file set audiostream:0
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerCoreFactory::GetPlayers(smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi)
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
01:22:02 T:2684407808 M: 27185152 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
01:22:02 T:2684407808 M: 27181056 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
01:22:02 T:2684407808 M: 27181056 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
01:22:02 T:2684407808 M: 27181056 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
01:22:02 T:2684407808 M: 27181056 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
01:22:02 T:2684407808 M: 27181056 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
01:22:02 T:2684407808 M: 25939968 NOTICE: DVDPlayer: Opening: smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi
01:22:02 T:44157952 M: 25935872 DEBUG: thread start, auto delete: 1
01:22:02 T:2684407808 M: 25935872 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
01:22:02 T:44164096 M: 25931776 DEBUG: thread start, auto delete: 0
01:22:02 T:44164096 M: 25931776 NOTICE: Creating InputStream
01:22:02 T:44164096 M: 25907200 DEBUG: CFileSMB::Open - opened video/Documentaries/I Love Being HIV+ (2006).avi, fd=10000
01:22:02 T:44164096 M: 25907200 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
01:22:02 T:44164096 M: 25907200 DEBUG: CacheSubtitles: START
01:22:02 T:44164096 M: 25907200 DEBUG: CacheSubtitles: Checking for common subirs...
01:22:02 T:44164096 M: 25907200 DEBUG: CacheSubtitles: Done (time: 47 ms)
01:22:02 T:44164096 M: 25907200 DEBUG: CacheSubtitles: Searching for subtitles...
01:22:02 T:44164096 M: 25907200 DEBUG: OpenDir - Using authentication url smb://user:pass@NAS/video/Documentaries
01:22:02 T:44164096 M: 25907200 DEBUG: CacheSubtitles: Done (time: 12 ms)
01:22:02 T:44164096 M: 25907200 DEBUG: CacheSubtitles: END (total time: 59 ms)
01:22:02 T:44164096 M: 25907200 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
01:22:02 T:44164096 M: 25907200 NOTICE: Creating Demuxer
01:22:02 T:44164096 M: 25903104 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/players/dvdplayer/avutil-50-x86-osx.so
01:22:02 T:44164096 M: 25886720 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/players/dvdplayer/avcodec-52-x86-osx.so
01:22:02 T:44164096 M: 24920064 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/players/dvdplayer/avformat-52-x86-osx.so
01:22:02 T:44164096 M: 24780800 DEBUG: Open - probing detected format [avi]
01:22:03 T:44164096 M: 20733952 DEBUG: Open - av_find_stream_info starting
01:22:03 T:44164096 M: 20463616 DEBUG: ffmpeg[2A1E400]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
01:22:03 T:44164096 M: 20156416 DEBUG: Open - av_find_stream_info finished
01:22:03 T:44164096 M: 20152320 INFO: ffmpeg[2A1E400]: Input #0, avi, from 'smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi':
01:22:03 T:44164096 M: 20152320 INFO: ffmpeg[2A1E400]: Duration: 00:56:01.20, start: 0.000000, bitrate: 878 kb/s
01:22:03 T:44164096 M: 20152320 INFO: ffmpeg[2A1E400]: Stream #0.0: Video: mpeg4, yuv420p, 512x288 [PAR 1:1 DAR 16:9], 25 tbr, 25 tbn, 25 tbc
01:22:03 T:44164096 M: 20152320 INFO: ffmpeg[2A1E400]: Stream #0.1: Audio: mp3, 48000 Hz, 2 channels, s16, 128 kb/s
01:22:03 T:44164096 M: 20152320 NOTICE: Opening video stream: 0 source: 256
01:22:03 T:44164096 M: 20152320 NOTICE: Creating video codec with codec id: 13
01:22:03 T:44164096 M: 20152320 DEBUG: FactoryCodec - Video: - Opening
01:22:03 T:44164096 M: 20152320 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/players/dvdplayer/swscale-0.6.1-x86-osx.so
01:22:03 T:44164096 M: 20045824 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2
01:22:03 T:44164096 M: 20045824 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
01:22:03 T:44164096 M: 20045824 NOTICE: Creating video thread
01:22:03 T:44243456 M: 20037632 DEBUG: thread start, auto delete: 0
01:22:03 T:44243456 M: 20037632 DEBUG: LinuxRendererGL: Cleaning up GL resources
01:22:03 T:44243456 M: 20033536 NOTICE: running thread: video_thread
01:22:03 T:44164096 M: 20033536 NOTICE: Opening audio stream: 1 source: 256
01:22:03 T:44164096 M: 20033536 NOTICE: Finding audio codec for: 86017
01:22:03 T:44164096 M: 20021248 DEBUG: FactoryCodec - Audio: passthrough - Opening
01:22:03 T:44164096 M: 20021248 DEBUG: FactoryCodec - Audio: passthrough - Failed
01:22:03 T:44164096 M: 20025344 DEBUG: FactoryCodec - Audio: libmad - Opening
01:22:03 T:44164096 M: 20025344 DEBUG: Loading: /mnt/Scratch/Users/frontrow/Applications/XBMC.app/Contents/Resources/XBMC/system/players/dvdplayer/libmad-x86-osx.so
01:22:03 T:44164096 M: 19984384 DEBUG: FactoryCodec - Audio: libmad - Opened
01:22:03 T:44164096 M: 19984384 NOTICE: Creating audio thread
01:22:03 T:44246528 M: 19980288 DEBUG: thread start, auto delete: 0
01:22:03 T:44246528 M: 19980288 NOTICE: running thread: CDVDPlayerAudio::Process()
01:22:03 T:44164096 M: 19980288 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi
01:22:03 T:2684407808 M: 19980288 DEBUG: OnPlayBackStarted - Playback has started
01:22:03 T:44164096 M: 19980288 INFO: Loading skin file: DialogProgress.xml
01:22:03 T:44164096 M: 11960320 DEBUG: Load DialogProgress.xml: 297.41ms
01:22:03 T:44246528 M: 11960320 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(24000.000000, 1)
01:22:03 T:44246528 M: 11948032 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through
01:22:03 T:44246528 M: 11948032 INFO: AudioRendererFactory: not a explicit device, trying to autodetect.
01:22:03 T:44243456 M: 11972608 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
01:22:03 T:2684407808 M: 11677696 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi)
01:22:03 T:44157952 M: 11677696 DEBUG: Thread 44157952 terminating (autodelete)
01:22:03 T:44246528 M: 11677696 DEBUG: SetActiveDevice - SetActiveDevice from 1 to 2
01:22:03 T:44243456 M: 11677696 DEBUG: ffmpeg[2A31A00]: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected
01:22:03 T:44246528 M: 11657216 DEBUG: RemoveActiveDevice - Removing device 1
01:22:03 T:44246528 M: 11657216 DEBUG: CCoreAudioHardware::FindAudioDevice: Searching for device - Built-in Line Output.
01:22:03 T:44243456 M: 11444224 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_DELAY(16000.000000)
01:22:03 T:44243456 M: 11407360 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(40000.000000, 0)
01:22:03 T:44243456 M: 11190272 NOTICE: fps: 25.000000, pwidth: 512, pheight: 288, dwidth: 512, dheight: 288
01:22:03 T:44243456 M: 11190272 DEBUG: OutputPicture - change configuration. 512x288. framerate: 25.00
01:22:03 T:44243456 M: 11190272 NOTICE: Display resolution DESKTOP : 1280x720 @ 60.00 - Full Screen (12)
01:22:03 T:2684407808 M: 10334208 DEBUG: GetMovieId (smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi), query = select idMovie from movie where idFile=4649
01:22:03 T:2684407808 M: 8978432 DEBUG: GetEpisodeId (smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi), query = select idEpisode from episode where idFile=4649
01:22:03 T:44246528 M: 8830976 DEBUG: CCoreAudioDevice::GetTotalOutputChannels: Found 4 channels in 2 buffers
01:22:03 T:44246528 M: 8830976 DEBUG: CCoreAudioHardware::FindAudioDevice: Device[0x0102] - Name: 'Built-in Line Output', Total Ouput Channels: 4.
01:22:03 T:44246528 M: 8830976 DEBUG: CCoreAudioDevice::Close: Closed device 0x0102
01:22:03 T:2684407808 M: 8634368 DEBUG: GetMusicVideoId (smb://NAS/video/Documentaries/I Love Being HIV+ (2006).avi), query = select idMVideo from musicvideo where idFile=4649
01:22:03 T:2684407808 M: 7970816 DEBUG: Activating window ID: 12005
01:22:03 T:2684407808 M: 7966720 DEBUG: Checking if window ID 12005 is locked.
01:22:03 T:2684407808 M: 7970816 DEBUG: ------ Window Deinit (MyVideo.xml) ------
01:22:03 T:2684407808 M: 7892992 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
01:22:03 T:2684407808 M: 7905280 INFO: Loading skin file: VideoFullScreen.xml
01:22:04 T:2684407808 M: 7503872 DEBUG: Load VideoFullScreen.xml: 39.15ms
01:22:04 T:2684407808 M: 7499776 DEBUG: Alloc resources: 39.67ms (39.67 ms skin load)
01:22:04 T:2684407808 M: 7499776 INFO: Loading skin file: VideoOSD.xml
01:22:04 T:2684407808 M: 7106560 DEBUG: Load VideoOSD.xml: 54.14ms
01:22:04 T:2684407808 M: 6205440 DEBUG: Alloc resources: 56.15ms (0.00 ms skin load)
01:22:04 T:2684407808 M: 6221824 INFO: Loading skin file: VideoOSDSettings.xml
01:22:04 T:2684407808 M: 6098944 DEBUG: Load VideoOSDSettings.xml: 22.38ms
01:22:04 T:44246528 M: 5976064 DEBUG: CoreAudioRenderer::Initialize: Input Stream Format [lpcm] Mixable 2 Channel 16-bit Signed Integer (48000Hz)
01:22:04 T:44246528 M: 5992448 DEBUG: CoreAudioRenderer::Initialize: Output Stream Format [lpcm] Mixable 4 Channel 32-bit Floating Point (44100Hz)
01:22:04 T:44246528 M: 5980160 DEBUG: CoreAudioRenderer::Initialize: Renderer Configuration - Chunk Len: 2048, Max Cache: 192000 (1000ms).
01:22:04 T:44246528 M: 5976064 INFO: CoreAudioRenderer::Initialize: Successfully configured audio output.
01:22:04 T:44246528 M: 5976064 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
01:22:04 T:44246528 M: 5971968 DEBUG: CoreAudioRenderer::Resume: Resuming Playback.
01:22:04 T:44246528 M: 5312512 DEBUG: CDVDPlayerAudio:: Discontinuty - was:628688.356000, should be:24000.000000, error:-604688.356000
01:22:04 T:2684407808 M: 4743168 DEBUG: Alloc resources: 73.73ms (0.00 ms skin load)
01:22:04 T:2684407808 M: 4739072 INFO: Loading skin file: VideoOSDSettings.xml
01:22:04 T:2684407808 M: 4587520 DEBUG: Load VideoOSDSettings.xml: 17.41ms
01:22:04 T:2684407808 M: 4603904 DEBUG: Alloc resources: 0.29ms (0.00 ms skin load)
01:22:04 T:2684407808 M: 3817472 INFO: GL: Enabling VSYNC
01:22:04 T:2684407808 M: 3833856 INFO: GL: Selected vsync mode 10
01:22:04 T:2684407808 M: 3817472 NOTICE: Using GL_TEXTURE_2D
01:22:04 T:2684407808 M: 3817472 DEBUG: GL: Requested render method: 0
01:22:04 T:2684407808 M: 3817472 NOTICE: GL: ARB shaders support detected
01:22:04 T:2684407808 M: 3559424 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader
01:22:04 T:2684407808 M: 3575808 NOTICE: GL: No vertex shader, fixed pipeline in use
01:22:04 T:2684407808 M: 3493888 NOTICE: GL: NPOT texture support detected
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 512 x 288
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 256 x 144
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 256 x 144
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 512 x 144
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 512 x 144
01:22:04 T:2684407808 M: 3489792 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 3485696 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 3485696 DEBUG: GL: Creating YUV NPOT texture of size 512 x 288
01:22:04 T:2684407808 M: 3485696 DEBUG: GL: Creating YUV NPOT texture of size 256 x 144
01:22:04 T:2684407808 M: 3485696 DEBUG: GL: Creating YUV NPOT texture of size 256 x 144
01:22:04 T:2684407808 M: 3485696 DEBUG: GL: Creating YUV NPOT texture of size 512 x 144
01:22:04 T:2684407808 M: 3485696 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 3485696 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 3473408 DEBUG: GL: Creating YUV NPOT texture of size 512 x 144
01:22:04 T:2684407808 M: 3469312 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 3461120 DEBUG: GL: Creating YUV NPOT texture of size 256 x 72
01:22:04 T:2684407808 M: 15626240 INFO: GL: ARB Imaging extension supported
01:22:05 T:2684407808 M: 11616256 DEBUG: ------ Window Init (DialogVolumeBar.xml) ------
01:22:05 T:2684407808 M: 11616256 DEBUG: Alloc resources: 1.99ms (1.98 ms skin load)
01:22:05 T:44246528 M: 11423744 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1045502.292000, should be:1021535.682262, error:-23966.609738
01:22:08 T:44243456 M: 18427904 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00
01:22:08 T:2684407808 M: 18366464 DEBUG: ------ Window Deinit (DialogVolumeBar.xml) ------
01:22:13 T:2684407808 M: 18223104 INFO: CheckIdle - Closing session to http://xoap.weather.com (easy=0xa262000, multi=0xa780720)
01:22:25 T:43348992 M: 20414464 DEBUG: Thread 43348992 terminating (autodelete)
01:22:25 T:43403776 M: 20418560 DEBUG: Thread 43403776 terminating (autodelete)
01:22:25 T:43313152 M: 20467712 DEBUG: Thread 43313152 terminating (autodelete)
01:22:34 T:2684407808 M: 20533248 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/ImageLib-x86-osx.so)
01:22:34 T:2684407808 M: 20549632 DEBUG: Unloading: ImageLib-x86-osx.so
01:23:08 T:43323904 M: 21372928 NOTICE: ES: Client from 127.0.0.1 timed out
01:23:14 T:44246528 M: 21372928 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue
01:23:14 T:44243456 M: 21372928 WARNING: CDVDMessageQueue(video)::Get - retrieved last data packet of queue
01:23:15 T:44243456 M: 21372928 INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps
01:23:15 T:44243456 M: 21372928 DEBUG: CPullupCorrection: pattern lost
01:23:15 T:43319808 M: 21368832 DEBUG: CoreAudioRenderer::Pause: Pausing Playback.
01:23:15 T:43319808 M: 21368832 DEBUG: CCoreAudioRenderer::OnRender: Buffer underrun.
01:23:19 T:44243456 M: 21684224 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00