rendered paste body12:03:20 T:3028998032 M:3079139328 ERROR: Failed to create common scripts symlink.
12:03:20 T:3028998032 M:3079139328 NOTICE: -----------------------------------------------------------------------
12:03:20 T:3028998032 M:3079139328 NOTICE: Starting XBMC, Platform: GNU/Linux. Built on Dec 24 2009 (SVN:26018)
12:03:20 T:3028998032 M:3079139328 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
12:03:20 T:3028998032 M:3079139328 NOTICE: special://masterprofile/ is mapped to: /home/xbmc/.xbmc/userdata
12:03:20 T:3028998032 M:3079139328 NOTICE: special://home/ is mapped to: /home/xbmc/.xbmc
12:03:20 T:3028998032 M:3079139328 NOTICE: special://temp/ is mapped to: /home/xbmc/.xbmc/temp
12:03:20 T:3028998032 M:3079139328 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
12:03:20 T:3028998032 M:3079139328 NOTICE: Log File is located: /home/xbmc/.xbmc/temp/xbmc.log
12:03:20 T:3028998032 M:3079139328 NOTICE: -----------------------------------------------------------------------
12:03:20 T:3028998032 M:3078885376 NOTICE: Setup SDL
12:03:20 T:3028998032 M:3078377472 NOTICE: load settings...
12:03:20 T:3028998032 M:3078377472 NOTICE: special://profile/ is mapped to: special://masterprofile/
12:03:20 T:3028998032 M:3078377472 NOTICE: loading special://masterprofile/guisettings.xml
12:03:20 T:3028998032 M:3078377472 NOTICE: Getting hardware information now...
12:03:20 T:3028998032 M:3078377472 NOTICE: Checking resolution 12
12:03:20 T:3028998032 M:3078377472 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
12:03:20 T:3028998032 M:3078377472 NOTICE: Loaded playercorefactory configuration
12:03:20 T:3028998032 M:3078377472 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
12:03:20 T:3028998032 M:3078377472 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
12:03:20 T:3028998032 M:3078377472 NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml)
12:03:20 T:3028998032 M:3078377472 NOTICE: Default DVD Player: dvdplayer
12:03:20 T:3028998032 M:3078377472 NOTICE: Default Video Player: dvdplayer
12:03:20 T:3028998032 M:3078377472 NOTICE: Default Audio Player: paplayer
12:03:20 T:3028998032 M:3078377472 NOTICE: Enabled debug logging due to GUI setting (2)
12:03:20 T:3028998032 M:3078377472 NOTICE: special://masterprofile/sources.xml
12:03:20 T:3028998032 M:3078377472 INFO: XRANDR: /usr/share/xbmc/xbmc-xrandr --output default --mode 0x146
12:03:21 T:3028998032 M:3073622016 NOTICE: Using fbConfig[0]
12:03:21 T:3028998032 M:3073368064 INFO: GL: Maximum texture width: 8192
12:03:21 T:3028998032 M:3073368064 DEBUG: Loading: /usr/share/xbmc/system/ImageLib-i486-linux.so
12:03:21 T:3028998032 M:3072491520 DEBUG: GLX_EXTENSIONS: GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control GLX_EXT_texture_from_pixmap GLX_ARB_create_context GLX_ARB_create_context_profile GLX_ARB_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_EXT_framebuffer_sRGB GLX_ARB_get_proc_address
12:03:21 T:3028998032 M:3072491520 NOTICE: GL_VENDOR = NVIDIA Corporation
12:03:21 T:3028998032 M:3072491520 NOTICE: GL_RENDERER = ION/PCI/SSE2
12:03:21 T:3028998032 M:3072491520 NOTICE: GL_VERSION = 3.2.0 NVIDIA 190.53
12:03:21 T:3028998032 M:3072491520 NOTICE: GL_EXTENSIONS = GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_fragment_coord_conventions GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_map_buffer_range GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_provoking_vertex GL_ARB_seamless_cube_map GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_shadow GL_ARB_sync GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_compression GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_bindable_uniform GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent GL_EXT_texture_sRGB GL_EXT_texture_swizzle GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KTX_buffer_region GL_NV_blend_square GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_buffer_load GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
12:03:21 T:3028998032 M:3072491520 ERROR: GLX: Same window as before, refreshing context
12:03:21 T:3028998032 M:3072491520 INFO: GL: Maximum texture width: 8192
12:03:21 T:3028998032 M:3072491520 INFO: load language info file: special://xbmc/language/English/langinfo.xml
12:03:21 T:3028998032 M:3070713856 INFO: load language file:special://xbmc/language/English/strings.xml
12:03:21 T:3028998032 M:3070459904 INFO: load keymapping
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/keymaps/remote.xml
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://masterprofile/keymaps/remote.xml
12:03:21 T:3028998032 M:3070459904 ERROR: Keymapping error: no such action 'smallstepforward' defined
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://profile/keymaps/remote.xml
12:03:21 T:3028998032 M:3070459904 ERROR: Keymapping error: no such action 'smallstepforward' defined
12:03:21 T:3028998032 M:3070459904 INFO: Loading special://xbmc/system/Lircmap.xml
12:03:21 T:3028998032 M:3070459904 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
12:03:21 T:3028998032 M:3070459904 INFO: Checking skin version of: Transparency!
12:03:21 T:3028998032 M:3070459904 INFO: Skin version is: 2.11 (2.110000)
12:03:21 T:3028998032 M:3070459904 INFO: GUI format 1920x1080 1920x1080 @ 50.00 - Full Screen
12:03:21 T:3028998032 M:3070459904 DEBUG: Selected HAL as storage provider
12:03:21 T:3028998032 M:3070459904 INFO: HAL: Starting initializing
12:03:21 T:3028998032 M:3070459904 DEBUG: HAL: Clearing old global device list, if any
12:03:21 T:3028998032 M:3070459904 NOTICE: HAL: Generating global device list
12:03:21 T:3028998032 M:3070459904 DEBUG: HAL: Added - removable disk | HotPlugged YES | Type 0 |Approved NO
12:03:21 T:3028998032 M:3070459904 DEBUG: HAL: Added - removable disk | UUID 8d29942d-1430-4c08-ac36-7571741801e4 | FileSystem swap | HotPlugged YES | Type 0 |Approved NO
12:03:21 T:3028998032 M:3070459904 DEBUG: HAL: Added - removable disk | UUID 8688b015-45a0-494e-808b-4bf639199289 | FileSystem ext4 | Mounted on / | HotPlugged YES | Type 0 |Approved NO
12:03:21 T:3028998032 M:3070578688 INFO: HAL: Generated global device list, found 112
12:03:21 T:3028998032 M:3070578688 INFO: HAL: Sucessfully initialized
12:03:21 T:3028998032 M:3070578688 INFO: creating subdirectories
12:03:21 T:3028998032 M:3070578688 INFO: userdata folder: special://masterprofile/
12:03:21 T:3028998032 M:3070578688 INFO: recording folder:
12:03:21 T:3028998032 M:3070578688 INFO: screenshots folder:
12:03:21 T:3028998032 M:3070578688 INFO: thumbnails folder: special://masterprofile/Thumbnails
12:03:21 T:3028998032 M:3070578688 NOTICE: start dvd mediatype detection
12:03:21 T:3028998032 M:3070578688 NOTICE: initializing playlistplayer
12:03:21 T:3028998032 M:3070578688 NOTICE: DONE initializing playlistplayer
12:03:21 T:3026717552 M:3070578688 DEBUG: thread start, auto delete: 0
12:03:21 T:3026717552 M:3070578688 DEBUG: Compiled with libcdio Version 0.78
12:03:21 T:3028998032 M:3070578688 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
12:03:21 T:3028998032 M:3070578688 NOTICE: load default skin:[Transparency!]
12:03:21 T:3028998032 M:3070578688 INFO: load skin from:special://xbmc/skin/Transparency!
12:03:21 T:3028998032 M:3070578688 INFO: delete old skin...
12:03:21 T:3028998032 M:3070578688 DEBUG: ------ Window Deinit (Pointer.xml) ------
12:03:21 T:3028998032 M:3070578688 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
12:03:21 T:3028998032 M:3070578688 INFO: Default 4:3 resolution directory is special://xbmc/skin/Transparency!/720p
12:03:21 T:3028998032 M:3070578688 INFO: Default 16:9 resolution directory is special://xbmc/skin/Transparency!/720p
12:03:21 T:3028998032 M:3070578688 INFO: Skin version is: 2.11
12:03:21 T:3028998032 M:3070578688 INFO: Loading skin includes from /usr/share/xbmc/skin/Transparency!/720p/Includes.xml
12:03:22 T:3028998032 M:3067785216 INFO: load fonts for skin...
12:03:22 T:3028998032 M:3067785216 INFO: Loading fonts from special://xbmc/skin/Transparency!/720p/Font.xml
12:03:22 T:3028998032 M:3055595520 INFO: load new skin...
12:03:22 T:3028998032 M:3055595520 INFO: Skin version is: 2.11 (2.110000)
12:03:22 T:3028998032 M:3055595520 INFO: Loading skin file: Home.xml
12:03:22 T:3028998032 M:3055087616 DEBUG: Load Home.xml: 94.50ms
12:03:22 T:3028998032 M:3055087616 INFO: Loading user windows, path special://xbmc/skin/Transparency!/720p
12:03:22 T:3028998032 M:3055087616 INFO: Loading skin file: special://xbmc/skin/Transparency!/720p/custom_SkinSettings.xml
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: special://xbmc/skin/Transparency!/720p/custom_SkinCredits.xml
12:03:22 T:3028998032 M:3054325760 INFO: Loading user windows, path special://xbmc/skin/Transparency!/720p
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: special://xbmc/skin/Transparency!/720p/custom_SkinSettings.xml
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: special://xbmc/skin/Transparency!/720p/custom_SkinCredits.xml
12:03:22 T:3028998032 M:3054325760 INFO: Loading user windows, path special://xbmc/skin/Transparency!/720p
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: special://xbmc/skin/Transparency!/720p/custom_SkinSettings.xml
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: special://xbmc/skin/Transparency!/720p/custom_SkinCredits.xml
12:03:22 T:3028998032 M:3054325760 DEBUG: Load Skin XML: 270.29ms
12:03:22 T:3028998032 M:3054325760 INFO: initialize new skin...
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: Pointer.xml
12:03:22 T:3028998032 M:3054325760 DEBUG: Load Pointer.xml: 2.16ms
12:03:22 T:3028998032 M:3054325760 DEBUG: Alloc resources: 6.66ms (2.45 ms skin load)
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: DialogVolumeBar.xml
12:03:22 T:3028998032 M:3054325760 DEBUG: Load DialogVolumeBar.xml: 3.47ms
12:03:22 T:3028998032 M:3054325760 DEBUG: Alloc resources: 4.00ms (3.80 ms skin load)
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: DialogSeekBar.xml
12:03:22 T:3028998032 M:3054325760 DEBUG: Load DialogSeekBar.xml: 14.36ms
12:03:22 T:3028998032 M:3054325760 DEBUG: Alloc resources: 15.20ms (14.89 ms skin load)
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: DialogKaiToast.xml
12:03:22 T:3028998032 M:3054325760 DEBUG: Load DialogKaiToast.xml: 2.76ms
12:03:22 T:3028998032 M:3054325760 DEBUG: Alloc resources: 3.07ms (3.07 ms skin load)
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: DialogMuteBug.xml
12:03:22 T:3028998032 M:3054325760 DEBUG: Load DialogMuteBug.xml: 3.54ms
12:03:22 T:3028998032 M:3054325760 DEBUG: Alloc resources: 3.90ms (3.87 ms skin load)
12:03:22 T:3028998032 M:3054325760 DEBUG: CGUIAudioManager::Initialize
12:03:22 T:3028998032 M:3054325760 INFO: Loading special://xbmc/skin/Transparency!/sounds/sounds.xml
12:03:22 T:3028998032 M:3054325760 INFO: skin loaded...
12:03:22 T:3028998032 M:3054325760 DEBUG: Loading: libcurl.so.4
12:03:22 T:3028998032 M:3054325760 DEBUG: Activating window ID: 12999
12:03:22 T:3028998032 M:3054325760 DEBUG: Checking if window ID 12999 is locked.
12:03:22 T:3028998032 M:3054325760 DEBUG: ------ Window Init (Startup.xml) ------
12:03:22 T:3028998032 M:3054325760 INFO: Loading skin file: Startup.xml
12:03:22 T:3028998032 M:3054325760 DEBUG: Load Startup.xml: 2.18ms
12:03:22 T:3028998032 M:3054325760 DEBUG: Alloc resources: 2.44ms (2.44 ms skin load)
12:03:22 T:3028998032 M:3054325760 INFO: removing tempfiles
12:03:22 T:3028998032 M:3054325760 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
12:03:22 T:3028998032 M:3054325760 DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.GLib.UnmappedError.EggDBusError.Code5 - Remote Exception invoking org.freedesktop.PolicyKit1.Authority.CheckAuthorization() on /org/freedesktop/PolicyKit1/Authority at name org.freedesktop.PolicyKit1: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.PolicyKit1 was not provided by any .service files
12:03:22 T:3028998032 M:3054325760 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
12:03:22 T:3028998032 M:3054325760 DEBUG: DeviceKit.Power: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
12:03:22 T:3028998032 M:3054325760 NOTICE: initialize done
12:03:22 T:3028998032 M:3054325760 NOTICE: Running the application...
12:03:22 T:3028998032 M:3054325760 DEBUG: ExecuteXBMCAction : Translating Skin.SetBool(Startup)
12:03:22 T:3028998032 M:3054325760 DEBUG: ExecuteXBMCAction : To Skin.SetBool(Startup)
12:03:22 T:3028998032 M:3054325760 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
12:03:22 T:3028998032 M:3054325760 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
12:03:22 T:3028998032 M:3054325760 DEBUG: Activating window ID: 10000
12:03:22 T:3028998032 M:3054325760 DEBUG: Checking if window ID 10000 is locked.
12:03:22 T:3028998032 M:3054325760 DEBUG: ------ Window Deinit (Startup.xml) ------
12:03:22 T:3028998032 M:3054325760 DEBUG: ------ Window Init (Home.xml) ------
12:03:22 T:3028998032 M:3054325760 DEBUG: Alloc resources: 0.11ms (0.00 ms skin load)
12:03:22 T:3028998032 M:3054325760 DEBUG: Process - no user autoexec.py (/home/xbmc/.xbmc/scripts/autoexec.py) found, skipping
12:03:22 T:3028998032 M:3054325760 DEBUG: Process - no system autoexec.py (/usr/share/xbmc/scripts/autoexec.py) found, skipping
12:03:22 T:3028998032 M:3054325760 DEBUG: NetworkMessage - Starting network services
12:03:22 T:3028998032 M:3054325760 NOTICE: Webserver: Starting...
12:03:22 T:3028998032 M:3054325760 DEBUG: xbmcHttpShim starts
12:03:22 T:3017894768 M:3054325760 DEBUG: thread start, auto delete: 0
12:03:22 T:3017894768 M:3054325760 INFO: WebServer: Server starting using /usr/share/xbmc/web on 192.168.2.3:8080
12:03:22 T:3017894768 M:3054325760 DEBUG: DB: Registering database table <users>
12:03:22 T:3017894768 M:3054325760 DEBUG: DB: Registering database table <groups>
12:03:22 T:3017894768 M:3054325760 DEBUG: DB: Registering database table <access>
12:03:22 T:3017894768 M:3054325760 DEBUG: webs: Listening for HTTP requests at address 192.168.2.3:8080
12:03:22 T:3017894768 M:3054325760 NOTICE: Webserver: Started
12:03:22 T:3028998032 M:3054325760 INFO: CZeroconfAvahi::clientCallback: avahi server not available. But may become later...
12:03:22 T:3028998032 M:3054325760 NOTICE: ES: Starting event server
12:03:22 T:3028998032 M:3054325760 NOTICE: DS: Starting dbus server
12:03:22 T:2985016176 M:3054325760 DEBUG: thread start, auto delete: 0
12:03:22 T:2985016176 M:3054071808 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
12:03:22 T:2985016176 M:3054071808 NOTICE: UDP: Listening on port 9777
12:03:22 T:3028998032 M:3054071808 ERROR: DS: Failed to connect to the D-Bus session daemon: Empty address ''
12:03:22 T:3028998032 M:3054071808 NOTICE: starting zeroconf publishing
12:03:22 T:3028998032 M:3054071808 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
12:03:22 T:3028998032 M:3054071808 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
12:03:22 T:3028998032 M:3054071808 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webapi type: _xbmc-web._tcp name:XBMC HTTP API port:8080
12:03:22 T:3028998032 M:3054071808 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
12:03:22 T:3028998032 M:3054071808 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:XBMC Web Server port:8080
12:03:22 T:3028998032 M:3054071808 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
12:03:22 T:3028998032 M:3054071808 DEBUG: CLastfmScrobbler: Clearing session.
12:03:22 T:3028998032 M:3054071808 DEBUG: CLibrefmScrobbler: Clearing session.
12:03:22 T:2974526320 M:3054071808 DEBUG: thread start, auto delete: 1
12:03:22 T:2960739184 M:3050352640 DEBUG: thread start, auto delete: 0
12:03:22 T:2960739184 M:3050352640 DEBUG: FileCurl::Open(0xb0793120) http://feeds.feedburner.com/xbmc
12:03:22 T:2960739184 M:3050352640 INFO: easy_aquire - Created session to http://feeds.feedburner.com
12:03:22 T:3028998032 M:3051114496 DEBUG: ExecuteXBMCAction : Translating XBMC.RunScript(special://skin/scripts/RecentlyAdded.py,limit=3&albums=True&totals=True)
12:03:22 T:3028998032 M:3051114496 DEBUG: ExecuteXBMCAction : To XBMC.RunScript(special://skin/scripts/RecentlyAdded.py,limit=3&albums=True&totals=True)
12:03:22 T:3028998032 M:3051114496 INFO: initializing python engine.
12:03:22 T:3028998032 M:3051114496 DEBUG: Loading: /usr/share/xbmc/system/python/python24-i486-linux.so
12:03:22 T:3028998032 M:3051114496 DEBUG: Python wrapper library linked with internal Python library
12:03:23 T:2974526320 M:3049115648 DEBUG: DoWork - took 214 ms to load special://skin/images/backgrounds/videos.jpg
12:03:23 T:2960739184 M:3049115648 DEBUG: FileCurl::Close(0xb0793120) http://feeds.feedburner.com/xbmc
12:03:23 T:2960739184 M:3049115648 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc
12:03:23 T:2960739184 M:3049115648 DEBUG: RSS feed encoding: ISO-8859-1
12:03:23 T:2960739184 M:3049115648 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc
12:03:23 T:2960739184 M:3049115648 DEBUG: Thread 2960739184 terminating
12:03:24 T:3028998032 M:3048099840 DEBUG: GetZipList - Processing zip://%2fusr%2fshare%2fxbmc%2fsystem%2fpython%2fpython24%2ezip/sitecustomize
12:03:24 T:3028998032 M:3046588416 DEBUG: new python thread created. id=1
12:03:24 T:2956917616 M:3046588416 DEBUG: thread start, auto delete: 0
12:03:24 T:2956917616 M:3046588416 DEBUG: Python thread: start processing
12:03:24 T:3028998032 M:3046588416 DEBUG: ExecuteXBMCAction : Translating Skin.Reset(Startup)
12:03:24 T:3028998032 M:3046588416 DEBUG: ExecuteXBMCAction : To Skin.Reset(Startup)
12:03:24 T:3028998032 M:3046334464 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000)
12:03:24 T:3028998032 M:3046334464 DEBUG: ExecuteXBMCAction : To SetFocus(9000)
12:03:24 T:2974526320 M:3043786752 INFO: WEATHER: Downloading weather
12:03:24 T:2974526320 M:3043786752 DEBUG: FileCurl::Open(0xb14b9100) http://xoap.weather.com/weather/local/ASXX0230?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
12:03:24 T:2974526320 M:3043786752 INFO: easy_aquire - Created session to http://xoap.weather.com
12:03:24 T:2956917616 M:3042009088 NOTICE: -->Python Interpreter Initialized<--
12:03:24 T:2956917616 M:3042009088 DEBUG: Process - The source file to load is special://skin/scripts/RecentlyAdded.py
12:03:24 T:2956917616 M:3042009088 DEBUG: Process - Setting the Python path to /usr/share/xbmc/skin/Transparency!/scripts:/usr/share/xbmc/system/python/python24.zip:/usr/share/xbmc/system/python/lib/python24.zip:/usr/share/xbmc/system/python/lib/python2.4/:/usr/share/xbmc/system/python/lib/python2.4/plat-linux2:/usr/share/xbmc/system/python/lib/python2.4/lib-tk:/usr/share/xbmc/system/python/lib/python2.4/lib-dynload
12:03:24 T:2956917616 M:3042009088 DEBUG: Process - Entering source directory /usr/share/xbmc/skin/Transparency!/scripts
12:03:25 T:2956917616 M:3042136064 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/strop.so. flags: 2
12:03:25 T:2956917616 M:3042136064 DEBUG: Loading: /home/xbmc/.xbmc/temp/strop.so
12:03:25 T:2956917616 M:3042136064 DEBUG: RegisterExtensionLib, adding strop.so (0xaab1de0)
12:03:25 T:2956917616 M:3042136064 DEBUG: xbp_dlsym - load symbol initstrop
12:03:25 T:2956917616 M:3041882112 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/_socket.so. flags: 2
12:03:25 T:2956917616 M:3041882112 DEBUG: Loading: /home/xbmc/.xbmc/temp/_socket.so
12:03:25 T:2956917616 M:3041882112 DEBUG: RegisterExtensionLib, adding _socket.so (0xaa5a860)
12:03:25 T:2956917616 M:3041882112 DEBUG: xbp_dlsym - load symbol init_socket
12:03:25 T:2956917616 M:3041882112 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/time.so. flags: 2
12:03:25 T:2956917616 M:3041882112 DEBUG: Loading: /home/xbmc/.xbmc/temp/time.so
12:03:25 T:2956917616 M:3041882112 DEBUG: RegisterExtensionLib, adding time.so (0xab5c9f8)
12:03:25 T:2956917616 M:3041882112 DEBUG: xbp_dlsym - load symbol inittime
12:03:25 T:3028998032 M:3041882112 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat
12:03:25 T:3028998032 M:3041882112 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; OpenRecord;<record>
12:03:25 T:3028998032 M:3041882112 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; CloseRecord;</record>
12:03:25 T:2956917616 M:3041882112 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/datetime.so. flags: 2
12:03:25 T:2956917616 M:3041882112 DEBUG: Loading: /home/xbmc/.xbmc/temp/datetime.so
12:03:25 T:2956917616 M:3041882112 DEBUG: RegisterExtensionLib, adding datetime.so (0xaf504a48)
12:03:25 T:2956917616 M:3041882112 DEBUG: xbp_dlsym - load symbol initdatetime
12:03:25 T:3028998032 M:3041882112 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select count(1), count(playCount), * from movieview group by lastPlayed
12:03:25 T:3028998032 M:3041882112 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select count(1), count(playCount) from musicvideoview
12:03:25 T:3028998032 M:3041882112 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select count(1), sum(totalCount), sum(watched), sum(watchedCount) from tvshowview
12:03:25 T:3028998032 M:3041882112 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select count(1), count(distinct strAlbum), count(distinct strArtist) from songview
12:03:25 T:3028998032 M:3042131968 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from movieview order by idMovie desc limit 3
12:03:25 T:3028998032 M:3042131968 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from episodeview order by idepisode desc limit 3
12:03:25 T:3028998032 M:3042131968 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select idAlbum from albumview order by idAlbum desc limit 3
12:03:25 T:2956917616 M:3042131968 INFO: Scriptresult: Success
12:03:25 T:2974526320 M:3042131968 DEBUG: FileCurl::Close(0xb14b9100) http://xoap.weather.com/weather/local/ASXX0230?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
12:03:25 T:2974526320 M:3042131968 INFO: WEATHER: Weather download successful
12:03:26 T:2956917616 M:3041759232 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/collections.so. flags: 2
12:03:26 T:2956917616 M:3041759232 DEBUG: Loading: /home/xbmc/.xbmc/temp/collections.so
12:03:26 T:2956917616 M:3041759232 DEBUG: RegisterExtensionLib, adding collections.so (0xb3dcaf68)
12:03:26 T:2956917616 M:3041759232 DEBUG: xbp_dlsym - load symbol initcollections
12:03:26 T:2956917616 M:3041759232 INFO: Python script stopped
12:03:26 T:2956917616 M:3041759232 DEBUG: Thread 2956917616 terminating
12:03:26 T:3028998032 M:3041759232 DEBUG: python thread 1 destructed
12:03:26 T:3028998032 M:3041759232 INFO: Python, unloading python24.dll because no scripts are running anymore
12:03:26 T:3028998032 M:3041759232 DEBUG: UnloadExtensionLibs, clearing python extension libraries
12:03:26 T:3028998032 M:3041759232 DEBUG: Unloading: strop.so
12:03:26 T:3028998032 M:3041759232 DEBUG: Unloading: _socket.so
12:03:26 T:3028998032 M:3041759232 DEBUG: Unloading: time.so
12:03:26 T:3028998032 M:3041759232 DEBUG: Unloading: datetime.so
12:03:26 T:3028998032 M:3041759232 DEBUG: Unloading: collections.so
12:03:26 T:3028998032 M:3041759232 DEBUG: Unloading: python24-i486-linux.so
12:03:42 T:3026717552 M:3041796096 DEBUG: Drive closed media present, remounting...
12:03:42 T:3026717552 M:3041796096 INFO: Detecting DVD-ROM media filesystem...
12:03:42 T:3028998032 M:3041669120 DEBUG: HAL: Device (/org/freedesktop/Hal/devices/storage_serial_BENQ_DVD_DD_DW1620_0_0_0) Property storage.removable.media_available modified
12:03:42 T:3028998032 M:3041669120 DEBUG: HAL: Device (/org/freedesktop/Hal/devices/storage_serial_BENQ_DVD_DD_DW1620_0_0_0) Property storage.cdrom.write_speeds modified
12:03:42 T:3028998032 M:3041669120 DEBUG: HAL: Device (/org/freedesktop/Hal/devices/volume_part_1_size_715085824) Added
12:03:42 T:3028998032 M:3041669120 DEBUG: HAL: Added - cdrom | HotPlugged YES | Type 2 |Approved NO
12:03:46 T:3026717552 M:3041529856 INFO: CD Analysis Report
12:03:46 T:3026717552 M:3041529856 INFO: __________________________________
12:03:46 T:3026717552 M:3041529856 INFO: CD-ROM with audio tracks
12:03:46 T:3026717552 M:3041529856 INFO: Tracks overall:19; Audio tracks:19; Data tracks:0
12:03:46 T:3026717552 M:3041529856 INFO: Using protocol cdda://local/
12:03:47 T:3028998032 M:3041398784 DEBUG: LIRC: Update - NEW at 26812:0001004600005ffe 00 right DVICO_MCE (right)
12:03:47 T:3028998032 M:3041398784 DEBUG: OnKey: 168 pressed, action is 2
12:03:47 T:2974526320 M:3037589504 DEBUG: DoWork - took 190 ms to load special://skin/images/backgrounds/home-weather-conditional/34/weather-34.jpg
12:03:48 T:3028998032 M:3037081600 DEBUG: LIRC: Update - NEW at 27535:0001004600005ffe 00 right DVICO_MCE (right)
12:03:48 T:3028998032 M:3037081600 DEBUG: OnKey: 168 pressed, action is 2
12:03:48 T:2974526320 M:3034038272 DEBUG: DoWork - took 186 ms to load special://skin/images/backgrounds/playdisc.jpg
12:03:49 T:3028998032 M:3034038272 DEBUG: LIRC: Update - NEW at 28491:0001004600005efe 00 ok DVICO_MCE (ok)
12:03:49 T:3028998032 M:3034038272 DEBUG: OnKey: 11 pressed, action is 7
12:03:49 T:3028998032 M:3034038272 DEBUG: ExecuteXBMCAction : Translating XBMC.PlayDVD()
12:03:49 T:3028998032 M:3034038272 DEBUG: ExecuteXBMCAction : To XBMC.PlayDVD()
12:03:50 T:3028998032 M:3034169344 DEBUG: new file set audiostream:0
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers(cdda://local/01.cdda)
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:03:50 T:3028998032 M:3034169344 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
12:03:50 T:3028998032 M:3018170368 DEBUG: CFileCache::Open - opening <01.cdda> using cache
12:03:50 T:2956917616 M:3018170368 DEBUG: thread start, auto delete: 0
12:03:50 T:3028998032 M:3018170368 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/libdts-i486-linux.so
12:03:51 T:2956917616 M:3018170368 DEBUG: Thread 2956917616 terminating
12:03:51 T:3028998032 M:3018170368 DEBUG: CFileCache::Open - opening <01.cdda> using cache
12:03:51 T:3028998032 M:3018170368 INFO: PAPlayer: Playing cdda://local/01.cdda
12:03:51 T:2956917616 M:3018170368 DEBUG: thread start, auto delete: 0
12:03:51 T:3028998032 M:3018170368 DEBUG: PAPlayer: Creating new audio renderer
12:03:51 T:3028998032 M:3018170368 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 44100 - SampleBit: 16 - Resample false - Codec - IsMusic true - IsPassthrough false - audioDevice: plug:hdmi
12:03:51 T:3028998032 M:3018170368 DEBUG: RemoveActiveDevice - Removing device 1
12:03:51 T:3028998032 M:3018170368 DEBUG: CGUIAudioManager::DeInitialize
12:03:51 T:3028998032 M:3018170368 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
12:03:51 T:3028998032 M:3018170368 DEBUG: RemoveActiveDevice - Removing device 0
12:03:51 T:3028998032 M:3018170368 DEBUG: Initialize - using alsa device plug:hdmi
12:03:51 T:3028998032 M:3018170368 DEBUG: CALSADirectSound::Initialize - packet size:1996, packet count:16, buffer size:7996
12:03:51 T:3006274416 M:3018170368 DEBUG: thread start, auto delete: 0
12:03:51 T:3006274416 M:3018170368 DEBUG: PAPlayer: Thread started
12:03:51 T:3028998032 M:3018170368 DEBUG: OnPlayBackStarted - Playback has started
12:03:51 T:3028998032 M:3018170368 DEBUG: Loading: /usr/share/xbmc/visualisations/ProjectM.vis
12:03:51 T:3028998032 M:3018170368 DEBUG: create:1839x1040 at 43x22 ProjectM
12:03:52 T:3028998032 M:3016953856 DEBUG: OnInitialize() started
12:03:52 T:3028998032 M:3016953856 DEBUG: Visualisation::Start()
12:03:52 T:3028998032 M:3016953856 DEBUG: OnInitialize() done
12:03:52 T:2974526320 M:3016953856 INFO: msg: PICTURE::LoadImage: Unable to open image: //cdart.png Error: (2)
12:03:52 T:2974526320 M:3016953856 ERROR: Texture manager unable to load file: //cdart.png
12:03:52 T:3028998032 M:3015430144 DEBUG: CGUIInfoManager::SetCurrentSong(cdda://local/01.cdda)
12:03:52 T:3028998032 M:3015430144 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/01.cdda
12:03:53 T:3028998032 M:3015434240 DEBUG: cddb local cache hit.
12:03:53 T:3028998032 M:3015434240 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:03:53 T:3006274416 M:3015434240 INFO: AudioDecoder: File is queued
12:03:53 T:3028998032 M:3015434240 DEBUG: Loading additional tag info for file cdda://local/01.cdda
12:03:53 T:3028998032 M:3015434240 DEBUG: cddb local cache hit.
12:03:53 T:3028998032 M:3015434240 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:03:53 T:2974526320 M:3022417920 INFO: msg: PICTURE::LoadImage: Unable to open image: The Blackeyed Susans/Reveal Yourself/cdart.png Error: (2)
12:03:53 T:2974526320 M:3022417920 ERROR: Texture manager unable to load file: The Blackeyed Susans/Reveal Yourself/cdart.png
12:03:53 T:3028998032 M:3020394496 INFO: CheckIdle - Closing session to http://feeds.feedburner.com (easy=0xb3d17da0, multi=0xb3d204e8)
12:03:56 T:3028998032 M:3020398592 INFO: CheckIdle - Closing session to http://xoap.weather.com (easy=0xb3da2e70, multi=0xb3da1b38)
12:03:57 T:2956917616 M:3020398592 ERROR: file cdda: Reading 52 sectors of audio data starting at lsn 2756 failed
12:03:57 T:2956917616 M:3020398592 INFO: CFileCache::Process - Hit eof.
12:04:21 T:3028998032 M:3020414976 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/players/dvdplayer/libdts-i486-linux.so)
12:04:21 T:3028998032 M:3020414976 DEBUG: Unloading: libdts-i486-linux.so
12:04:23 T:2974526320 M:3020419072 DEBUG: Thread 2974526320 terminating (autodelete)
12:04:23 T:3028998032 M:3020427264 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/ImageLib-i486-linux.so)
12:04:23 T:3028998032 M:3020427264 DEBUG: Unloading: ImageLib-i486-linux.so
12:04:27 T:3006274416 M:3020427264 ERROR: CAudioDecoder: Error while decoding 1
12:04:27 T:2956917616 M:3020935168 DEBUG: Thread 2956917616 terminating
12:04:27 T:3006274416 M:3036680192 INFO: PAPlayer: End of playback reached
12:04:27 T:3006274416 M:3036680192 DEBUG: OnPlayBackEnded - Playback has finished
12:04:27 T:3006274416 M:3036680192 DEBUG: PAPlayer: Thread end
12:04:27 T:3006274416 M:3036680192 DEBUG: Thread 3006274416 terminating
12:04:27 T:3028998032 M:3036680192 DEBUG: new file set audiostream:0
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers(cdda://local/02.cdda)
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:04:27 T:3028998032 M:3036680192 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
12:04:27 T:3028998032 M:3021443072 DEBUG: CFileCache::Open - opening <02.cdda> using cache
12:04:27 T:3006274416 M:3021443072 DEBUG: thread start, auto delete: 0
12:04:27 T:3028998032 M:3021443072 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/libdts-i486-linux.so
12:04:27 T:3006274416 M:3021451264 DEBUG: Thread 3006274416 terminating
12:04:27 T:3028998032 M:3021459456 DEBUG: CFileCache::Open - opening <02.cdda> using cache
12:04:27 T:3028998032 M:3021459456 INFO: PAPlayer: Playing cdda://local/02.cdda
12:04:27 T:3028998032 M:3021459456 DEBUG: PAPlayer: Using existing audio renderer
12:04:27 T:3006274416 M:3021459456 DEBUG: thread start, auto delete: 0
12:04:27 T:3028998032 M:3021459456 DEBUG: OnInitialize() started
12:04:27 T:3028998032 M:3021459456 DEBUG: Visualisation::Start()
12:04:27 T:3028998032 M:3021459456 DEBUG: OnInitialize() done
12:04:27 T:2956917616 M:3021459456 DEBUG: thread start, auto delete: 0
12:04:27 T:3028998032 M:3021459456 DEBUG: OnPlayBackStarted - Playback has started
12:04:27 T:3028998032 M:3021459456 DEBUG: SaveFileState - Saving file state for audio item cdda://local/01.cdda
12:04:27 T:2974526320 M:3021459456 DEBUG: thread start, auto delete: 1
12:04:27 T:2974526320 M:3021459456 DEBUG: Loading: /usr/share/xbmc/system/ImageLib-i486-linux.so
12:04:27 T:3028998032 M:3021459456 DEBUG: CGUIInfoManager::SetCurrentSong(cdda://local/02.cdda)
12:04:27 T:2956917616 M:3021459456 DEBUG: PAPlayer: Thread started
12:04:27 T:2974526320 M:3021459456 INFO: msg: PICTURE::LoadImage: Unable to open image: //cdart.png Error: (2)
12:04:27 T:2974526320 M:3021459456 ERROR: Texture manager unable to load file: //cdart.png
12:04:27 T:3028998032 M:3021459456 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/02.cdda
12:04:27 T:3028998032 M:3021459456 DEBUG: cddb local cache hit.
12:04:27 T:3028998032 M:3021459456 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:04:27 T:3028998032 M:3021459456 DEBUG: Loading additional tag info for file cdda://local/02.cdda
12:04:27 T:3028998032 M:3021459456 DEBUG: cddb local cache hit.
12:04:27 T:3028998032 M:3021459456 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:04:27 T:2974526320 M:3021459456 INFO: msg: PICTURE::LoadImage: Unable to open image: The Blackeyed Susans/Reveal Yourself/cdart.png Error: (2)
12:04:27 T:2974526320 M:3021459456 ERROR: Texture manager unable to load file: The Blackeyed Susans/Reveal Yourself/cdart.png
12:04:27 T:2956917616 M:3021459456 INFO: AudioDecoder: File is queued
12:04:30 T:3006274416 M:3021447168 ERROR: file cdda: Reading 52 sectors of audio data starting at lsn 17145 failed
12:04:30 T:3006274416 M:3021447168 INFO: CFileCache::Process - Hit eof.
12:04:57 T:3028998032 M:3021467648 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/players/dvdplayer/libdts-i486-linux.so)
12:04:57 T:3028998032 M:3021467648 DEBUG: Unloading: libdts-i486-linux.so
12:04:57 T:2974526320 M:3021467648 DEBUG: Thread 2974526320 terminating (autodelete)
12:04:58 T:3028998032 M:3021467648 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/ImageLib-i486-linux.so)
12:04:58 T:3028998032 M:3021467648 DEBUG: Unloading: ImageLib-i486-linux.so
12:05:00 T:2956917616 M:3021484032 ERROR: CAudioDecoder: Error while decoding 1
12:05:00 T:3006274416 M:3021484032 DEBUG: Thread 3006274416 terminating
12:05:00 T:2956917616 M:3036975104 INFO: PAPlayer: End of playback reached
12:05:00 T:2956917616 M:3036975104 DEBUG: OnPlayBackEnded - Playback has finished
12:05:00 T:2956917616 M:3036975104 DEBUG: PAPlayer: Thread end
12:05:00 T:2956917616 M:3036975104 DEBUG: Thread 2956917616 terminating
12:05:00 T:3006274416 M:3036975104 DEBUG: thread start, auto delete: 1
12:05:00 T:3006274416 M:3036975104 DEBUG: Loading: /usr/share/xbmc/system/ImageLib-i486-linux.so
12:05:00 T:3006274416 M:3036975104 INFO: msg: PICTURE::LoadImage: Unable to open image: //cdart.png Error: (2)
12:05:00 T:3006274416 M:3036975104 ERROR: Texture manager unable to load file: //cdart.png
12:05:00 T:3028998032 M:3036975104 DEBUG: new file set audiostream:0
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers(cdda://local/03.cdda)
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:05:00 T:3028998032 M:3036975104 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
12:05:00 T:3028998032 M:3021230080 DEBUG: CFileCache::Open - opening <03.cdda> using cache
12:05:00 T:2956917616 M:3021365248 DEBUG: thread start, auto delete: 0
12:05:00 T:3028998032 M:3021365248 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/libdts-i486-linux.so
12:05:00 T:2956917616 M:3021361152 DEBUG: Thread 2956917616 terminating
12:05:00 T:3028998032 M:3021361152 DEBUG: CFileCache::Open - opening <03.cdda> using cache
12:05:00 T:3028998032 M:3021361152 INFO: PAPlayer: Playing cdda://local/03.cdda
12:05:00 T:3028998032 M:3021361152 DEBUG: PAPlayer: Using existing audio renderer
12:05:00 T:2956917616 M:3021361152 DEBUG: thread start, auto delete: 0
12:05:00 T:3028998032 M:3021361152 DEBUG: OnInitialize() started
12:05:00 T:3028998032 M:3021361152 DEBUG: Visualisation::Start()
12:05:00 T:3028998032 M:3021361152 DEBUG: OnInitialize() done
12:05:00 T:2974526320 M:3021361152 DEBUG: thread start, auto delete: 0
12:05:00 T:2974526320 M:3021361152 DEBUG: PAPlayer: Thread started
12:05:00 T:3028998032 M:3021361152 DEBUG: OnPlayBackStarted - Playback has started
12:05:00 T:3028998032 M:3021361152 DEBUG: SaveFileState - Saving file state for audio item cdda://local/02.cdda
12:05:00 T:3028998032 M:3021107200 DEBUG: CGUIInfoManager::SetCurrentSong(cdda://local/03.cdda)
12:05:00 T:3028998032 M:3021107200 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/03.cdda
12:05:00 T:3028998032 M:3021107200 DEBUG: cddb local cache hit.
12:05:00 T:3028998032 M:3021107200 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:00 T:3028998032 M:3021107200 DEBUG: Loading additional tag info for file cdda://local/03.cdda
12:05:00 T:3028998032 M:3021107200 DEBUG: cddb local cache hit.
12:05:00 T:3028998032 M:3021107200 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:00 T:3006274416 M:3021107200 INFO: msg: PICTURE::LoadImage: Unable to open image: The Blackeyed Susans/Reveal Yourself/cdart.png Error: (2)
12:05:00 T:3006274416 M:3021107200 ERROR: Texture manager unable to load file: The Blackeyed Susans/Reveal Yourself/cdart.png
12:05:00 T:2956917616 M:3021107200 ERROR: file cdda: Reading 52 sectors of audio data starting at lsn 32999 failed
12:05:00 T:2956917616 M:3021107200 INFO: CFileCache::Process - Hit eof.
12:05:00 T:2974526320 M:3021107200 ERROR: CAudioDecoder: Error while decoding 1
12:05:00 T:2956917616 M:3021869056 DEBUG: Thread 2956917616 terminating
12:05:00 T:2974526320 M:3037614080 INFO: PAPlayer: End of playback reached
12:05:00 T:2974526320 M:3037614080 DEBUG: OnPlayBackEnded - Playback has finished
12:05:00 T:2974526320 M:3037614080 DEBUG: PAPlayer: Thread end
12:05:00 T:2974526320 M:3037614080 DEBUG: Thread 2974526320 terminating
12:05:01 T:3028998032 M:3037614080 DEBUG: new file set audiostream:0
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers(cdda://local/04.cdda)
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:05:01 T:3028998032 M:3037614080 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
12:05:01 T:3028998032 M:3021107200 DEBUG: CFileCache::Open - opening <04.cdda> using cache
12:05:01 T:2974526320 M:3021107200 DEBUG: thread start, auto delete: 0
12:05:01 T:2974526320 M:3021107200 DEBUG: Thread 2974526320 terminating
12:05:01 T:3028998032 M:3021107200 DEBUG: CFileCache::Open - opening <04.cdda> using cache
12:05:01 T:3028998032 M:3021107200 INFO: PAPlayer: Playing cdda://local/04.cdda
12:05:01 T:3028998032 M:3021107200 DEBUG: PAPlayer: Using existing audio renderer
12:05:01 T:2974526320 M:3021107200 DEBUG: thread start, auto delete: 0
12:05:01 T:3028998032 M:3021107200 DEBUG: OnInitialize() started
12:05:01 T:3028998032 M:3021107200 DEBUG: Visualisation::Start()
12:05:01 T:3028998032 M:3021107200 DEBUG: OnInitialize() done
12:05:01 T:2956917616 M:3021107200 DEBUG: thread start, auto delete: 0
12:05:01 T:2956917616 M:3021107200 DEBUG: PAPlayer: Thread started
12:05:01 T:3028998032 M:3021107200 DEBUG: OnPlayBackStarted - Playback has started
12:05:01 T:3028998032 M:3021107200 DEBUG: CGUIInfoManager::SetCurrentSong(cdda://local/04.cdda)
12:05:01 T:3028998032 M:3021107200 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/04.cdda
12:05:01 T:3028998032 M:3021107200 DEBUG: cddb local cache hit.
12:05:01 T:3028998032 M:3021107200 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:01 T:3028998032 M:3021107200 DEBUG: Loading additional tag info for file cdda://local/04.cdda
12:05:01 T:3028998032 M:3021107200 DEBUG: cddb local cache hit.
12:05:01 T:3028998032 M:3021107200 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:01 T:2974526320 M:3021107200 ERROR: file cdda: Reading 52 sectors of audio data starting at lsn 54421 failed
12:05:01 T:2974526320 M:3021107200 INFO: CFileCache::Process - Hit eof.
12:05:01 T:2956917616 M:3021107200 ERROR: CAudioDecoder: Error while decoding 1
12:05:01 T:2974526320 M:3021869056 DEBUG: Thread 2974526320 terminating
12:05:01 T:2956917616 M:3037655040 INFO: PAPlayer: End of playback reached
12:05:01 T:2956917616 M:3037655040 DEBUG: OnPlayBackEnded - Playback has finished
12:05:01 T:2956917616 M: 0 DEBUG: PAPlayer: Thread end
12:05:01 T:2956917616 M: 0 DEBUG: Thread 2956917616 terminating
12:05:01 T:3028998032 M:3037655040 DEBUG: new file set audiostream:0
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers(cdda://local/05.cdda)
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:05:01 T:3028998032 M:3037655040 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
12:05:01 T:3028998032 M:3021107200 DEBUG: CFileCache::Open - opening <05.cdda> using cache
12:05:01 T:2956917616 M:3021107200 DEBUG: thread start, auto delete: 0
12:05:01 T:2956917616 M:3020972032 DEBUG: Thread 2956917616 terminating
12:05:01 T:3028998032 M:3020972032 DEBUG: CFileCache::Open - opening <05.cdda> using cache
12:05:01 T:3028998032 M:3020972032 INFO: PAPlayer: Playing cdda://local/05.cdda
12:05:01 T:3028998032 M:3020972032 DEBUG: PAPlayer: Using existing audio renderer
12:05:01 T:2956917616 M:3020972032 DEBUG: thread start, auto delete: 0
12:05:01 T:3028998032 M:3020972032 DEBUG: OnInitialize() started
12:05:01 T:3028998032 M:3020972032 DEBUG: Visualisation::Start()
12:05:01 T:3028998032 M:3020972032 DEBUG: OnInitialize() done
12:05:01 T:2974526320 M:3020972032 DEBUG: thread start, auto delete: 0
12:05:01 T:2974526320 M:3020972032 DEBUG: PAPlayer: Thread started
12:05:01 T:3028998032 M:3020972032 DEBUG: OnPlayBackStarted - Playback has started
12:05:01 T:3028998032 M:3020972032 DEBUG: SaveFileState - Saving file state for audio item cdda://local/04.cdda
12:05:01 T:3028998032 M:3020972032 DEBUG: CGUIInfoManager::SetCurrentSong(cdda://local/05.cdda)
12:05:01 T:3028998032 M:3020972032 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/05.cdda
12:05:01 T:3028998032 M:3020972032 DEBUG: cddb local cache hit.
12:05:01 T:3028998032 M:3020972032 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:01 T:3028998032 M:3020972032 DEBUG: Loading additional tag info for file cdda://local/05.cdda
12:05:02 T:3028998032 M:3020972032 DEBUG: cddb local cache hit.
12:05:02 T:3028998032 M:3020972032 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:02 T:2974526320 M:3020972032 INFO: AudioDecoder: File is queued
12:05:03 T:3028998032 M:3020857344 DEBUG: LIRC: Update - NEW at 102487:00010046000059fe 00 more DVICO_MCE (more)
12:05:03 T:3028998032 M:3020857344 DEBUG: OnKey: 195 pressed, action is 122
12:05:03 T:3028998032 M:3020857344 DEBUG: Activating window ID: 10007
12:05:03 T:3028998032 M:3020857344 DEBUG: Checking if window ID 10007 is locked.
12:05:03 T:3028998032 M:3020857344 DEBUG: ------ Window Deinit (Home.xml) ------
12:05:03 T:2956917616 M:3020857344 ERROR: file cdda: Reading 52 sectors of audio data starting at lsn 72579 failed
12:05:03 T:2956917616 M:3020857344 INFO: CFileCache::Process - Hit eof.
12:05:03 T:3028998032 M:3020857344 DEBUG: FreeVisualisation() started
12:05:03 T:3028998032 M:3020857344 DEBUG: Visualisation::Stop()
12:05:03 T:3028998032 M:3020857344 DEBUG: delete Visualisation()
12:05:03 T:3028998032 M:3020857344 DEBUG: SECTION:UnloadDll(special://xbmc/visualisations/ProjectM.vis)
12:05:03 T:3028998032 M:3020857344 DEBUG: Unloading: ProjectM.vis
12:05:03 T:3028998032 M:3020857344 DEBUG: FreeVisualisation() done
12:05:03 T:3028998032 M:3020857344 DEBUG: ------ Window Init (SettingsSystemInfo.xml) ------
12:05:03 T:3028998032 M:3020857344 INFO: Loading skin file: SettingsSystemInfo.xml
12:05:03 T:3028998032 M:3020857344 DEBUG: Load SettingsSystemInfo.xml: 26.10ms
12:05:03 T:3028998032 M:3020857344 DEBUG: Alloc resources: 27.52ms (26.82 ms skin load)
12:05:03 T:3006274416 M:3020857344 INFO: easy_aquire - Created session to http://www.google.com
12:05:03 T:2910096240 M:3020857344 DEBUG: thread start, auto delete: 1
12:05:04 T:2910096240 M:3015016448 DEBUG: DoWork - took 306 ms to load special://skin/images/backgrounds/background-settings.jpg
12:05:04 T:3006274416 M:3016032256 DEBUG: FileCurl::Close(0xb33000a0) http://www.google.com/
12:05:06 T:3028998032 M:3018571776 DEBUG: LIRC: Update - NEW at 105559:00010046000059fe 00 more DVICO_MCE (more)
12:05:06 T:3028998032 M:3018571776 DEBUG: OnKey: 195 pressed, action is 11
12:05:08 T:3028998032 M:3018698752 DEBUG: LIRC: Update - NEW at 107159:0001004600004dfe 00 dvd_menu DVICO_MCE (dvd_menu)
12:05:08 T:3028998032 M:3018698752 DEBUG: OnKey: 247 pressed, action is 10
12:05:08 T:3028998032 M:3018698752 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
12:05:08 T:3028998032 M:3018698752 DEBUG: ------ Window Deinit (SettingsSystemInfo.xml) ------
12:05:08 T:3028998032 M:3018698752 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
12:05:08 T:3028998032 M:3018698752 DEBUG: ------ Window Init (Home.xml) ------
12:05:08 T:3028998032 M:3018698752 INFO: Loading skin file: Home.xml
12:05:08 T:3028998032 M:3018706944 DEBUG: Load Home.xml: 55.48ms
12:05:08 T:3028998032 M:3018706944 DEBUG: Alloc resources: 57.69ms (57.57 ms skin load)
12:05:08 T:3028998032 M:3018706944 DEBUG: Loading: /usr/share/xbmc/visualisations/ProjectM.vis
12:05:08 T:3028998032 M:3018706944 DEBUG: create:1839x1040 at 43x22 ProjectM
12:05:08 T:2910096240 M:3013373952 DEBUG: DoWork - took 194 ms to load special://skin/images/backgrounds/playdisc.jpg
12:05:09 T:3028998032 M:3015405568 DEBUG: OnInitialize() started
12:05:09 T:3028998032 M:3015405568 DEBUG: Visualisation::Start()
12:05:09 T:3028998032 M:3015405568 DEBUG: OnInitialize() done
12:05:09 T:3006274416 M:3015405568 INFO: msg: PICTURE::LoadImage: Unable to open image: The Blackeyed Susans/Reveal Yourself/cdart.png Error: (2)
12:05:09 T:3006274416 M:3015405568 ERROR: Texture manager unable to load file: The Blackeyed Susans/Reveal Yourself/cdart.png
12:05:09 T:3028998032 M:3014897664 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000)
12:05:09 T:3028998032 M:3014897664 DEBUG: ExecuteXBMCAction : To SetFocus(9000)
12:05:13 T:3028998032 M:3017695232 DEBUG: LIRC: Update - NEW at 112107:00010046000059fe 00 more DVICO_MCE (more)
12:05:13 T:3028998032 M:3017695232 DEBUG: OnKey: 195 pressed, action is 122
12:05:13 T:3028998032 M:3017695232 DEBUG: Activating window ID: 10007
12:05:13 T:3028998032 M:3017695232 DEBUG: Checking if window ID 10007 is locked.
12:05:13 T:3028998032 M:3017695232 DEBUG: ------ Window Deinit (Home.xml) ------
12:05:13 T:3028998032 M:3017695232 DEBUG: FreeVisualisation() started
12:05:13 T:3028998032 M:3017695232 DEBUG: Visualisation::Stop()
12:05:13 T:3028998032 M:3017695232 DEBUG: delete Visualisation()
12:05:13 T:3028998032 M:3017695232 DEBUG: SECTION:UnloadDll(special://xbmc/visualisations/ProjectM.vis)
12:05:13 T:3028998032 M:3017695232 DEBUG: Unloading: ProjectM.vis
12:05:13 T:3028998032 M:3017695232 DEBUG: FreeVisualisation() done
12:05:13 T:3028998032 M:3017695232 DEBUG: ------ Window Init (SettingsSystemInfo.xml) ------
12:05:13 T:3028998032 M:3017695232 INFO: Loading skin file: SettingsSystemInfo.xml
12:05:13 T:3028998032 M:3017695232 DEBUG: Load SettingsSystemInfo.xml: 24.36ms
12:05:13 T:3028998032 M:3017695232 DEBUG: Alloc resources: 25.67ms (25.09 ms skin load)
12:05:13 T:2910096240 M:3015536640 DEBUG: DoWork - took 283 ms to load special://skin/images/backgrounds/background-settings.jpg
12:05:14 T:3028998032 M:3015536640 DEBUG: LIRC: Update - NEW at 113500:00010046000059fe 00 more DVICO_MCE (more)
12:05:14 T:3028998032 M:3015536640 DEBUG: OnKey: 195 pressed, action is 11
12:05:16 T:3028998032 M:3018719232 DEBUG: LIRC: Update - NEW at 116059:00010046000059fe 00 more DVICO_MCE (more)
12:05:16 T:3028998032 M:3018719232 DEBUG: OnKey: 195 pressed, action is 11
12:05:20 T:3006274416 M:3018973184 DEBUG: FileCurl::Close(0xb33000a0) http://www.google.com/
12:05:32 T:3028998032 M:3018981376 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/players/dvdplayer/libdts-i486-linux.so)
12:05:32 T:3028998032 M:3018981376 DEBUG: Unloading: libdts-i486-linux.so
12:05:33 T:2974526320 M:3018985472 ERROR: CAudioDecoder: Error while decoding 1
12:05:33 T:2956917616 M:3019747328 DEBUG: Thread 2956917616 terminating
12:05:36 T:2974526320 M:3032956928 INFO: PAPlayer: End of playback reached
12:05:36 T:2974526320 M:3032956928 DEBUG: OnPlayBackEnded - Playback has finished
12:05:36 T:2974526320 M:3032956928 DEBUG: PAPlayer: Thread end
12:05:36 T:2974526320 M:3032956928 DEBUG: Thread 2974526320 terminating
12:05:36 T:3028998032 M:3032956928 DEBUG: new file set audiostream:0
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers(cdda://local/06.cdda)
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:05:36 T:3028998032 M:3032956928 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
12:05:36 T:3028998032 M:3016704000 DEBUG: CFileCache::Open - opening <06.cdda> using cache
12:05:36 T:2974526320 M:3019243520 DEBUG: thread start, auto delete: 0
12:05:36 T:3028998032 M:3019243520 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/libdts-i486-linux.so
12:05:36 T:2974526320 M:3019243520 DEBUG: Thread 2974526320 terminating
12:05:36 T:2910096240 M:3023323136 DEBUG: FileCurl::Close(0xad7470a0) http://www.google.com/
12:05:36 T:3028998032 M:3019767808 DEBUG: CFileCache::Open - opening <06.cdda> using cache
12:05:36 T:2974526320 M:3019763712 DEBUG: thread start, auto delete: 0
12:05:36 T:3028998032 M:3019763712 INFO: PAPlayer: Playing cdda://local/06.cdda
12:05:36 T:3028998032 M:3019763712 DEBUG: PAPlayer: Using existing audio renderer
12:05:36 T:2956917616 M:3019763712 DEBUG: thread start, auto delete: 0
12:05:36 T:2956917616 M:3019763712 DEBUG: PAPlayer: Thread started
12:05:36 T:3028998032 M:3019763712 DEBUG: OnPlayBackStarted - Playback has started
12:05:36 T:3028998032 M:3019763712 DEBUG: SaveFileState - Saving file state for audio item cdda://local/05.cdda
12:05:36 T:3028998032 M:3019763712 DEBUG: CGUIInfoManager::SetCurrentSong(cdda://local/06.cdda)
12:05:36 T:3028998032 M:3019763712 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/06.cdda
12:05:36 T:3028998032 M:3019763712 DEBUG: cddb local cache hit.
12:05:36 T:3028998032 M:3019763712 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:36 T:3028998032 M:3019763712 DEBUG: Loading additional tag info for file cdda://local/06.cdda
12:05:36 T:3028998032 M:3019763712 DEBUG: cddb local cache hit.
12:05:36 T:3028998032 M:3019763712 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:37 T:2956917616 M:3019890688 INFO: AudioDecoder: File is queued
12:05:39 T:2974526320 M:3019763712 ERROR: file cdda: Reading 52 sectors of audio data starting at lsn 87867 failed
12:05:39 T:2974526320 M:3019763712 INFO: CFileCache::Process - Hit eof.
12:05:42 T:3028998032 M:3019763712 DEBUG: LIRC: Update - NEW at 141662:0001004600001ffe 00 zoom DVICO_MCE (zoom)
12:05:42 T:3028998032 M:3019763712 DEBUG: OnKey: 213 pressed, action is 18
12:05:42 T:3028998032 M:3019763712 DEBUG: Activating window ID: 12006
12:05:42 T:3028998032 M:3019763712 DEBUG: Checking if window ID 12006 is locked.
12:05:42 T:3028998032 M:3019763712 DEBUG: ------ Window Deinit (SettingsSystemInfo.xml) ------
12:05:42 T:3028998032 M:3019763712 DEBUG: ------ Window Init (MusicVisualisation.xml) ------
12:05:42 T:3028998032 M:3019763712 INFO: Loading skin file: MusicVisualisation.xml
12:05:43 T:3028998032 M:3019763712 DEBUG: Load MusicVisualisation.xml: 28.49ms
12:05:43 T:3028998032 M:3019763712 DEBUG: Alloc resources: 29.39ms (29.32 ms skin load)
12:05:43 T:3028998032 M:3019763712 DEBUG: Loading: /usr/share/xbmc/visualisations/ProjectM.vis
12:05:43 T:3028998032 M:3019763712 DEBUG: create:1839x1040 at 43x22 ProjectM
12:05:43 T:3028998032 M:3019255808 DEBUG: OnInitialize() started
12:05:43 T:3028998032 M:3019255808 DEBUG: Visualisation::Start()
12:05:43 T:3028998032 M:3019255808 DEBUG: OnInitialize() done
12:05:43 T:3006274416 M:3019255808 INFO: msg: PICTURE::LoadImage: Unable to open image: The Blackeyed Susans/Reveal Yourself/cdart.png Error: (2)
12:05:43 T:3006274416 M:3019255808 ERROR: Texture manager unable to load file: The Blackeyed Susans/Reveal Yourself/cdart.png
12:05:43 T:3028998032 M:3017732096 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/07.cdda
12:05:43 T:3028998032 M:3017732096 DEBUG: cddb local cache hit.
12:05:43 T:3028998032 M:3017732096 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:05:43 T:3028998032 M:3017732096 WARNING: CreateFile, successfuly opened </usr/share/xbmc/skin/Transparency!/images/flags/music/audiocodec/cdda.png> instead of </usr/share/xbmc/skin/Transparency!/images/flags/music/audiocodec/CDDA.png>
12:05:43 T:3028998032 M:3017732096 ERROR: Texture manager unable to load file: special://skin/images/flags/music/recordlabel/
12:05:44 T:3028998032 M:3017375744 DEBUG: LIRC: Update - NEW at 144094:0001004600001ffe 00 zoom DVICO_MCE (zoom)
12:05:44 T:3028998032 M:3017375744 DEBUG: OnKey: 213 pressed, action is 18
12:05:45 T:3028998032 M:3017375744 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
12:05:45 T:3028998032 M:3017375744 DEBUG: ------ Window Deinit (MusicVisualisation.xml) ------
12:05:47 T:3028998032 M:3017502720 DEBUG: FreeVisualisation() started
12:05:47 T:3028998032 M:3017502720 DEBUG: Visualisation::Stop()
12:05:47 T:3028998032 M:3017502720 DEBUG: delete Visualisation()
12:05:47 T:3028998032 M:3017502720 DEBUG: SECTION:UnloadDll(special://xbmc/visualisations/ProjectM.vis)
12:05:47 T:3028998032 M:3017502720 DEBUG: Unloading: ProjectM.vis
12:05:47 T:3028998032 M:3017502720 DEBUG: FreeVisualisation() done
12:05:47 T:3028998032 M:3017502720 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
12:05:47 T:3028998032 M:3017502720 DEBUG: ------ Window Init (SettingsSystemInfo.xml) ------
12:05:47 T:3028998032 M:3017502720 INFO: Loading skin file: SettingsSystemInfo.xml
12:05:47 T:3028998032 M:3017502720 DEBUG: Load SettingsSystemInfo.xml: 24.13ms
12:05:47 T:3028998032 M:3017502720 DEBUG: Alloc resources: 25.47ms (24.86 ms skin load)
12:05:48 T:3028998032 M:3018391552 DEBUG: LIRC: Update - NEW at 147143:0001004600001ffe 00 zoom DVICO_MCE (zoom)
12:05:48 T:3028998032 M:3018391552 DEBUG: OnKey: 213 pressed, action is 18
12:05:48 T:3028998032 M:3018391552 DEBUG: Activating window ID: 12006
12:05:48 T:3028998032 M:3018391552 DEBUG: Checking if window ID 12006 is locked.
12:05:48 T:3028998032 M:3018391552 DEBUG: ------ Window Deinit (SettingsSystemInfo.xml) ------
12:05:48 T:3028998032 M:3018391552 DEBUG: ------ Window Init (MusicVisualisation.xml) ------
12:05:48 T:3028998032 M:3018391552 INFO: Loading skin file: MusicVisualisation.xml
12:05:48 T:3028998032 M:3018391552 DEBUG: Load MusicVisualisation.xml: 26.10ms
12:05:48 T:3028998032 M:3018391552 DEBUG: Alloc resources: 26.98ms (26.91 ms skin load)
12:05:48 T:3028998032 M:3018391552 DEBUG: Loading: /usr/share/xbmc/visualisations/ProjectM.vis
12:05:48 T:3028998032 M:3018391552 DEBUG: create:1839x1040 at 43x22 ProjectM
12:05:48 T:3028998032 M:3018399744 DEBUG: OnInitialize() started
12:05:48 T:3028998032 M:3018399744 DEBUG: Visualisation::Start()
12:05:48 T:3028998032 M:3018399744 DEBUG: OnInitialize() done
12:05:48 T:2910096240 M:3018145792 INFO: msg: PICTURE::LoadImage: Unable to open image: The Blackeyed Susans/Reveal Yourself/cdart.png Error: (2)
12:05:48 T:2910096240 M:3018145792 ERROR: Texture manager unable to load file: The Blackeyed Susans/Reveal Yourself/cdart.png
12:05:48 T:3028998032 M:3017129984 WARNING: CreateFile, successfuly opened </usr/share/xbmc/skin/Transparency!/images/flags/music/audiocodec/cdda.png> instead of </usr/share/xbmc/skin/Transparency!/images/flags/music/audiocodec/CDDA.png>
12:05:48 T:3028998032 M:3017129984 ERROR: Texture manager unable to load file: special://skin/images/flags/music/recordlabel/
12:05:50 T:3028998032 M:3021070336 DEBUG: LIRC: Update - NEW at 150086:00010046000059fe 00 more DVICO_MCE (more)
12:05:50 T:3028998032 M:3021070336 DEBUG: OnKey: 195 pressed, action is 11
12:06:06 T:3028998032 M:3021082624 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/players/dvdplayer/libdts-i486-linux.so)
12:06:06 T:3028998032 M:3021082624 DEBUG: Unloading: libdts-i486-linux.so
12:06:06 T:3028998032 M:3021082624 INFO: CheckIdle - Closing session to http://www.google.com (easy=0xb3d337c8, multi=(nil))
12:06:09 T:2956917616 M:3021086720 ERROR: CAudioDecoder: Error while decoding 1
12:06:09 T:2974526320 M:3021594624 DEBUG: Thread 2974526320 terminating
12:06:09 T:2956917616 M:3037339648 INFO: PAPlayer: End of playback reached
12:06:09 T:2956917616 M:3037339648 DEBUG: OnPlayBackEnded - Playback has finished
12:06:09 T:2956917616 M:3037339648 DEBUG: PAPlayer: Thread end
12:06:09 T:2956917616 M:3037339648 DEBUG: Thread 2956917616 terminating
12:06:09 T:3028998032 M:3037339648 DEBUG: new file set audiostream:0
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerCoreFactory::GetPlayers(cdda://local/07.cdda)
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:06:09 T:3028998032 M:3037339648 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
12:06:09 T:3028998032 M:3037483008 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
12:06:09 T:3028998032 M:3037483008 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
12:06:09 T:3028998032 M:3037483008 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:06:09 T:3028998032 M:3037483008 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:06:09 T:3028998032 M:3037483008 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
12:06:09 T:3028998032 M:3021737984 DEBUG: CFileCache::Open - opening <07.cdda> using cache
12:06:09 T:2956917616 M:3021611008 DEBUG: thread start, auto delete: 0
12:06:09 T:3028998032 M:3021611008 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/libdts-i486-linux.so
12:06:09 T:2956917616 M:3021611008 DEBUG: Thread 2956917616 terminating
12:06:09 T:3028998032 M:3021611008 DEBUG: CFileCache::Open - opening <07.cdda> using cache
12:06:09 T:3028998032 M:3021611008 INFO: PAPlayer: Playing cdda://local/07.cdda
12:06:09 T:3028998032 M:3021611008 DEBUG: PAPlayer: Using existing audio renderer
12:06:09 T:2956917616 M:3021611008 DEBUG: thread start, auto delete: 0
12:06:09 T:3028998032 M:3021611008 DEBUG: OnInitialize() started
12:06:09 T:3028998032 M:3021611008 DEBUG: Visualisation::Start()
12:06:09 T:3028998032 M:3021611008 DEBUG: OnInitialize() done
12:06:09 T:2974526320 M:3021611008 DEBUG: thread start, auto delete: 0
12:06:09 T:2974526320 M:3021611008 DEBUG: PAPlayer: Thread started
12:06:09 T:3028998032 M:3021611008 DEBUG: OnPlayBackStarted - Playback has started
12:06:09 T:3028998032 M:3021611008 DEBUG: SaveFileState - Saving file state for audio item cdda://local/06.cdda
12:06:10 T:3028998032 M:3021611008 DEBUG: CGUIInfoManager::SetCurrentSong(cdda://local/07.cdda)
12:06:10 T:3028998032 M:3021611008 DEBUG: Loading additional tag info for file cdda://local/07.cdda
12:06:10 T:3028998032 M:3021611008 DEBUG: cddb local cache hit.
12:06:10 T:3028998032 M:3021611008 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:06:10 T:3028998032 M:3021611008 DEBUG: LoadMusicTag: loading tag information for file: cdda://local/08.cdda
12:06:10 T:3028998032 M:3021611008 DEBUG: cddb local cache hit.
12:06:10 T:3028998032 M:3021611008 DEBUG: Xcddb::queryCDinfo discid [2a122f13] already cached
12:06:10 T:2974526320 M:3021611008 INFO: AudioDecoder: File is queued
12:06:11 T:2956917616 M:3021598720 ERROR: file cdda: Reading 52 sectors of audio data starting at lsn 106066 failed
12:06:11 T:2956917616 M:3021598720 INFO: CFileCache::Process - Hit eof.
12:06:13 T:3006274416 M:3021606912 DEBUG: Thread 3006274416 terminating (autodelete)
12:06:16 T:3028998032 M:3021615104 DEBUG: LIRC: Update - NEW at 175434:0001004600004dfe 00 dvd_menu DVICO_MCE (dvd_menu)
12:06:16 T:3028998032 M:3021615104 DEBUG: OnKey: 247 pressed, action is 122
12:06:16 T:3028998032 M:3021615104 DEBUG: Activating window ID: 10120
12:06:16 T:3028998032 M:3021615104 DEBUG: Checking if window ID 10120 is locked.
12:06:16 T:3028998032 M:3021615104 DEBUG: ------ Window Init (MusicOSD.xml) ------
12:06:16 T:3028998032 M:3021615104 INFO: Loading skin file: MusicOSD.xml
12:06:16 T:3028998032 M:3021615104 DEBUG: Load MusicOSD.xml: 21.48ms
12:06:16 T:3028998032 M:3020288000 DEBUG: Alloc resources: 60.96ms (22.03 ms skin load)
12:06:16 T:3028998032 M:3020259328 DEBUG: ------ Window Init (MusicOverlay.xml) ------
12:06:16 T:3028998032 M:3020259328 INFO: Loading skin file: MusicOverlay.xml
12:06:16 T:3028998032 M:3020259328 DEBUG: Load MusicOverlay.xml: 2.55ms
12:06:16 T:3028998032 M:3020259328 DEBUG: Alloc resources: 2.80ms (2.80 ms skin load)
12:06:17 T:3028998032 M:3020271616 DEBUG: LIRC: Update - NEW at 177058:0001004600004dfe 00 dvd_menu DVICO_MCE (dvd_menu)
12:06:17 T:3028998032 M:3020271616 DEBUG: OnKey: 247 pressed, action is 51
12:06:18 T:3028998032 M:3020271616 DEBUG: ------ Window Deinit (MusicOSD.xml) ------
12:06:18 T:3028998032 M:3020779520 DEBUG: ------ Window Deinit (MusicOverlay.xml) ------
12:06:18 T:3028998032 M:3020779520 WARNING: CreateFile, successfuly opened </usr/share/xbmc/skin/Transparency!/images/flags/music/audiocodec/cdda.png> instead of </usr/share/xbmc/skin/Transparency!/images/flags/music/audiocodec/CDDA.png>
12:06:18 T:2910096240 M:3020783616 DEBUG: Thread 2910096240 terminating (autodelete)
12:06:22 T:3028998032 M:3020795904 DEBUG: LIRC: Update - NEW at 181699:0001004600000dfe 00 stop DVICO_MCE (stop)
12:06:22 T:3028998032 M:3020795904 DEBUG: OnKey: 224 pressed, action is 13
12:06:22 T:3028998032 M:3020795904 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
12:06:22 T:3028998032 M:3020795904 DEBUG: ------ Window Deinit (MusicVisualisation.xml) ------
12:06:24 T:3028998032 M:3020926976 DEBUG: FreeVisualisation() started
12:06:24 T:3028998032 M:3020926976 DEBUG: Visualisation::Stop()
12:06:24 T:3028998032 M:3020926976 DEBUG: delete Visualisation()
12:06:24 T:3028998032 M:3020926976 DEBUG: SECTION:UnloadDll(special://xbmc/visualisations/ProjectM.vis)
12:06:24 T:3028998032 M:3020926976 DEBUG: Unloading: ProjectM.vis
12:06:24 T:3028998032 M:3020926976 DEBUG: FreeVisualisation() done
12:06:24 T:3028998032 M:3020926976 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
12:06:24 T:3028998032 M:3020926976 DEBUG: ------ Window Init (SettingsSystemInfo.xml) ------
12:06:24 T:3028998032 M:3020926976 INFO: Loading skin file: SettingsSystemInfo.xml
12:06:24 T:3028998032 M:3020926976 DEBUG: Load SettingsSystemInfo.xml: 24.12ms
12:06:24 T:3028998032 M:3020926976 DEBUG: Alloc resources: 25.50ms (24.92 ms skin load)
12:06:24 T:2974526320 M:3020926976 INFO: PAPlayer: End of playback reached
12:06:24 T:2974526320 M:3020926976 DEBUG: OnPlayBackStopped - Playback was stopped
12:06:24 T:2974526320 M:3020926976 DEBUG: PAPlayer: Thread end
12:06:24 T:2974526320 M:3020926976 DEBUG: Thread 2974526320 terminating
12:06:24 T:2956917616 M:3021434880 DEBUG: Thread 2956917616 terminating
12:06:24 T:3028998032 M:3037179904 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
12:06:24 T:3028998032 M:3037179904 DEBUG: RemoveActiveDevice - Removing device 2
12:06:24 T:2956917616 M:3037179904 DEBUG: thread start, auto delete: 1
12:06:24 T:2956917616 M:3037179904 INFO: easy_aquire - Created session to http://www.google.com
12:06:24 T:2974526320 M:3037179904 DEBUG: thread start, auto delete: 1
12:06:24 T:3028998032 M:3038703616 DEBUG: SaveFileState - Saving file state for audio item cdda://local/07.cdda
12:06:24 T:3028998032 M:3038449664 DEBUG: CGUIAudioManager::Initialize
12:06:24 T:3028998032 M:3037687808 DEBUG: LIRC: Update - NEW at 184041:0001004600000dfe 00 stop DVICO_MCE (stop)
12:06:24 T:3028998032 M:3037687808 DEBUG: OnKey: 224 pressed, action is 13
12:06:25 T:2974526320 M:3033878528 DEBUG: DoWork - took 298 ms to load special://skin/images/backgrounds/background-settings.jpg
12:06:25 T:2956917616 M:3035979776 DEBUG: FileCurl::Close(0xb03ee0a0) http://www.google.com/
12:06:27 T:3028998032 M:3035979776 DEBUG: LIRC: Update - NEW at 186146:0001004600004dfe 00 dvd_menu DVICO_MCE (dvd_menu)
12:06:27 T:3028998032 M:3035979776 DEBUG: OnKey: 247 pressed, action is 10
12:06:27 T:3028998032 M:3035979776 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
12:06:27 T:3028998032 M:3035979776 DEBUG: ------ Window Deinit (SettingsSystemInfo.xml) ------
12:06:27 T:3028998032 M:3035979776 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
12:06:27 T:3028998032 M:3035979776 DEBUG: ------ Window Init (Home.xml) ------
12:06:27 T:3028998032 M:3035979776 INFO: Loading skin file: Home.xml
12:06:27 T:3028998032 M:3035979776 DEBUG: Load Home.xml: 55.54ms
12:06:27 T:3028998032 M:3035979776 DEBUG: Alloc resources: 57.62ms (57.50 ms skin load)
12:06:27 T:3028998032 M:3036487680 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000)
12:06:27 T:3028998032 M:3036487680 DEBUG: ExecuteXBMCAction : To SetFocus(9000)
12:06:27 T:2974526320 M:3033186304 DEBUG: DoWork - took 207 ms to load special://skin/images/backgrounds/playdisc.jpg
12:06:28 T:3028998032 M:3033313280 DEBUG: LIRC: Update - NEW at 187964:00010046000053fe 00 down DVICO_MCE (down)
12:06:28 T:3028998032 M:3033313280 DEBUG: OnKey: 167 pressed, action is 4
12:06:29 T:3028998032 M:3033313280 DEBUG: LIRC: Update - NEW at 188604:0001004600005efe 00 ok DVICO_MCE (ok)
12:06:29 T:3028998032 M:3033313280 DEBUG: OnKey: 11 pressed, action is 7
12:06:29 T:3028998032 M:3033313280 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(ShutdownMenu)
12:06:29 T:3028998032 M:3033313280 DEBUG: ExecuteXBMCAction : To ActivateWindow(ShutdownMenu)
12:06:29 T:3028998032 M:3033313280 DEBUG: Activating window ID: 10111
12:06:29 T:3028998032 M:3033313280 DEBUG: Checking if window ID 10111 is locked.
12:06:29 T:3028998032 M:3033313280 DEBUG: ------ Window Init (DialogButtonMenu.xml) ------
12:06:29 T:3028998032 M:3033313280 INFO: Loading skin file: DialogButtonMenu.xml
12:06:29 T:3028998032 M:3033313280 DEBUG: Load DialogButtonMenu.xml: 21.03ms
12:06:29 T:3028998032 M:3033313280 DEBUG: Alloc resources: 40.04ms (21.70 ms skin load)
12:06:29 T:2956917616 M:3032805376 DEBUG: DoWork - took 193 ms to load special://skin/images/backgrounds/shutdown.jpg
12:06:31 T:3028998032 M:3033067520 DEBUG: LIRC: Update - NEW at 190148:0001004600005bfe 00 left DVICO_MCE (left)
12:06:31 T:3028998032 M:3033067520 DEBUG: OnKey: 169 pressed, action is 1
12:06:31 T:3028998032 M:3033067520 DEBUG: LIRC: Update - NEW at 191047:0001004600005bfe 00 left DVICO_MCE (left)
12:06:31 T:3028998032 M:3033067520 DEBUG: OnKey: 169 pressed, action is 1
12:06:32 T:3028998032 M:3033067520 DEBUG: LIRC: Update - NEW at 191748:0001004600005efe 00 ok DVICO_MCE (ok)
12:06:32 T:3028998032 M:3033067520 DEBUG: OnKey: 11 pressed, action is 7
12:06:32 T:3028998032 M:3033067520 DEBUG: ExecuteXBMCAction : Translating dialog.close(all,true)
12:06:32 T:3028998032 M:3033067520 DEBUG: ExecuteXBMCAction : To dialog.close(all,true)
12:06:32 T:3028998032 M:3033067520 DEBUG: ------ Window Deinit (DialogButtonMenu.xml) ------
12:06:32 T:3028998032 M:3033067520 DEBUG: ExecuteXBMCAction : Translating XBMC.Quit()
12:06:32 T:3028998032 M:3033067520 DEBUG: ExecuteXBMCAction : To XBMC.Quit()
12:06:32 T:2974526320 M:3033067520 DEBUG: Thread 2974526320 terminating (autodelete)
12:06:32 T:2956917616 M:3033067520 DEBUG: Thread 2956917616 terminating (autodelete)
12:06:32 T:3028998032 M:3033067520 NOTICE: Storing total System Uptime
12:06:32 T:3028998032 M:3033067520 NOTICE: Saving settings
12:06:32 T:3028998032 M:3033067520 NOTICE: stop all
12:06:32 T:3028998032 M:3033067520 DEBUG: NetworkMessage - Signaling network services to stop
12:06:32 T:3028998032 M:3033067520 NOTICE: Webserver: Stopping...
12:06:32 T:3028998032 M:3033067520 NOTICE: ES: Stopping event server
12:06:32 T:3028998032 M:3033067520 DEBUG: NetworkMessage - Waiting for network services to stop
12:06:32 T:3028998032 M:3033067520 NOTICE: stopping zeroconf publishing
12:06:32 T:3028998032 M:3033067520 DEBUG: CLastfmScrobbler: Journal with 0 entries saved to special://masterprofile/LastfmScrobbler.xml
12:06:32 T:3028998032 M:3033067520 DEBUG: CLibrefmScrobbler: Journal with 0 entries saved to special://masterprofile/LibrefmScrobbler.xml
12:06:33 T:3017894768 M:3033096192 DEBUG: WebServer:Exiting thread sockReady=0, sockSelect=0.
12:06:33 T:3017894768 M:3033096192 DEBUG: WebServer:OnExit - Exit web server.
12:06:33 T:3017894768 M:3033096192 DEBUG: Thread 3017894768 terminating
12:06:33 T:3028998032 M:3033096192 DEBUG: xbmcHttpShim ends
12:06:33 T:3028998032 M:3033096192 DEBUG: xbmcHttp ends
12:06:33 T:3028998032 M:3033096192 NOTICE: Webserver: Stopped...
12:06:33 T:2985016176 M:3033096192 NOTICE: ES: UDP Event server stopped
12:06:33 T:2985016176 M:3033096192 DEBUG: Thread 2985016176 terminating
12:06:33 T:3028998032 M:3033096192 NOTICE: stop dvd detect media
12:06:33 T:3026717552 M:3033096192 DEBUG: Thread 3026717552 terminating
12:06:33 T:3028998032 M:3033096192 NOTICE: stop sap announcement listener
12:06:33 T:3028998032 M:3033096192 NOTICE: clean cached files!
12:06:33 T:3028998032 M:3033096192 NOTICE: unload skin
12:06:33 T:3028998032 M:3033096192 DEBUG: CGUIAudioManager::DeInitialize
12:06:33 T:3028998032 M:3033096192 DEBUG: ------ Window Deinit (Home.xml) ------
12:06:33 T:3028998032 M:3033096192 DEBUG: ------ Window Deinit (Pointer.xml) ------
12:06:33 T:3028998032 M:3033096192 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
12:06:33 T:3028998032 M:3033096192 WARNING: Cleanup: Having to cleanup texture disc_mask.png
12:06:33 T:3028998032 M:3050110976 NOTICE: stop python
12:06:33 T:3028998032 M:3050110976 NOTICE: stopped
12:06:33 T:3028998032 M:3050110976 NOTICE: destroy
12:06:33 T:3028998032 M:3050110976 NOTICE: unload sections
12:06:33 T:3028998032 M:3050110976 DEBUG: SECTION:UnloadAll(SECTION: LIBHTTP)
12:06:33 T:3028998032 M:3050110976 DEBUG: SECTION:UnloadAll(DLL: libcurl.so.4)
12:06:33 T:3028998032 M:3050110976 DEBUG: Unloading: libcurl.so.4
12:06:33 T:3028998032 M:3050110976 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/ImageLib-i486-linux.so)
12:06:33 T:3028998032 M:3050110976 DEBUG: Unloading: ImageLib-i486-linux.so
12:06:33 T:3028998032 M:3050110976 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/libdts-i486-linux.so)
12:06:33 T:3028998032 M:3050110976 DEBUG: Unloading: libdts-i486-linux.so
12:06:33 T:3028998032 M:3050110976 DEBUG: CLastfmScrobbler: Journal with 0 entries saved to special://masterprofile/LastfmScrobbler.xml
12:06:33 T:3028998032 M:3050110976 DEBUG: CLibrefmScrobbler: Journal with 0 entries saved to special://masterprofile/LibrefmScrobbler.xml
12:06:33 T:3028998032 M:3050110976 INFO: lastfm destroyed
12:06:33 T:3028998032 M:3050110976 DEBUG: object 0 --> 0 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 1 --> 1 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 2 --> 13 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 3 --> 0 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 4 --> 32 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 5 --> 0 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 6 --> 0 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 7 --> 0 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 8 --> 0 instances
12:06:33 T:3028998032 M:3050110976 DEBUG: object 9 --> 0 instances