rendered paste body17:03:56 T:3029284752 M:2084618240 ERROR: Failed to create common scripts symlink.
17:03:56 T:3029284752 M:2084618240 NOTICE: -----------------------------------------------------------------------
17:03:56 T:3029284752 M:2084618240 NOTICE: Starting XBMC, Platform: GNU/Linux. Built on Dec 24 2009 (SVN:26018)
17:03:56 T:3029284752 M:2084618240 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
17:03:56 T:3029284752 M:2084618240 NOTICE: special://masterprofile/ is mapped to: /home/chris/.xbmc/userdata
17:03:56 T:3029284752 M:2084618240 NOTICE: special://home/ is mapped to: /home/chris/.xbmc
17:03:56 T:3029284752 M:2084618240 NOTICE: special://temp/ is mapped to: /home/chris/.xbmc/temp
17:03:56 T:3029284752 M:2084626432 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
17:03:56 T:3029284752 M:2084626432 NOTICE: Log File is located: /home/chris/.xbmc/temp/xbmc.log
17:03:56 T:3029284752 M:2084626432 NOTICE: -----------------------------------------------------------------------
17:03:56 T:3029284752 M:2084499456 NOTICE: Setup SDL
17:03:56 T:3029284752 M:2083848192 NOTICE: load settings...
17:03:56 T:3029284752 M:2083848192 NOTICE: special://profile/ is mapped to: special://masterprofile/
17:03:56 T:3029284752 M:2083848192 NOTICE: loading special://masterprofile/guisettings.xml
17:03:56 T:3029284752 M:2083848192 NOTICE: Getting hardware information now...
17:03:56 T:3029284752 M:2083848192 NOTICE: Checking resolution 11
17:03:56 T:3029284752 M:2083848192 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
17:03:56 T:3029284752 M:2083848192 NOTICE: Loaded playercorefactory configuration
17:03:56 T:3029284752 M:2083848192 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
17:03:56 T:3029284752 M:2083848192 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
17:03:56 T:3029284752 M:2083848192 NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml)
17:03:56 T:3029284752 M:2083848192 NOTICE: Default DVD Player: dvdplayer
17:03:56 T:3029284752 M:2083848192 NOTICE: Default Video Player: dvdplayer
17:03:56 T:3029284752 M:2083848192 NOTICE: Default Audio Player: paplayer
17:03:56 T:3029284752 M:2083848192 NOTICE: Enabled debug logging due to GUI setting (2)
17:03:56 T:3029284752 M:2083848192 NOTICE: special://masterprofile/sources.xml
17:03:56 T:3029284752 M:2083848192 INFO: XRANDR: /usr/share/xbmc/xbmc-xrandr --output default --mode 0x146
17:03:56 T:3029284752 M:2078892032 NOTICE: Using fbConfig[0]
17:03:56 T:3029284752 M:2078892032 INFO: GL: Maximum texture width: 8192
17:03:56 T:3029284752 M:2078892032 DEBUG: Loading: /usr/share/xbmc/system/ImageLib-i486-linux.so
17:03:56 T:3029284752 M:2078384128 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
17:03:56 T:3029284752 M:2078384128 NOTICE: GL_VENDOR = NVIDIA Corporation
17:03:56 T:3029284752 M:2078384128 NOTICE: GL_RENDERER = GeForce 8200/PCI/SSE2/3DNOW!
17:03:56 T:3029284752 M:2078384128 NOTICE: GL_VERSION = 3.2.0 NVIDIA 190.53
17:03:56 T:3029284752 M:2078384128 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
17:03:56 T:3029284752 M:2078384128 INFO: GL: Maximum texture width: 8192
17:03:56 T:3029284752 M:2078384128 INFO: load language info file: special://xbmc/language/English/langinfo.xml
17:03:56 T:3029284752 M:2076225536 INFO: load language file:special://xbmc/language/English/strings.xml
17:03:56 T:3029284752 M:2076352512 INFO: load keymapping
17:03:56 T:3029284752 M:2076352512 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
17:03:56 T:3029284752 M:2076401664 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
17:03:56 T:3029284752 M:2076401664 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
17:03:56 T:3029284752 M:2076655616 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
17:03:56 T:3029284752 M:2076655616 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
17:03:56 T:3029284752 M:2076655616 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
17:03:56 T:3029284752 M:2076655616 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
17:03:56 T:3029284752 M:2076782592 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
17:03:56 T:3029284752 M:2076733440 INFO: Loading special://xbmc/system/keymaps/remote.xml
17:03:56 T:3029284752 M:2076782592 INFO: Loading special://xbmc/system/Lircmap.xml
17:03:56 T:3029284752 M:2077036544 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
17:03:56 T:3029284752 M:2077036544 INFO: Checking skin version of: PM3.HD
17:03:56 T:3029284752 M:2077036544 INFO: Skin version is: 2.11 (2.110000)
17:03:56 T:3029284752 M:2077036544 INFO: GUI format 1920x1009 Windowed
17:03:56 T:3029284752 M:2077036544 DEBUG: Selected DeviceKit.Disks as storage provider
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DaemonVersion 7
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: Querying available devices
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda3)
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda3 with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda3 with interface org.freedesktop.DBus.Properties and method GetAll
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda3: HasFileSystem ext4 IsMounted true IsRemovable false IsPartition true
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sr0 with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda)
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda5)
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda5 with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda5 with interface org.freedesktop.DBus.Properties and method GetAll
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda5: HasFileSystem swap IsMounted false IsRemovable false IsPartition true
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda1)
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda1 with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda1 with interface org.freedesktop.DBus.Properties and method GetAll
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda1: HasFileSystem ext4 IsMounted true IsRemovable false IsPartition true
17:03:56 T:3029284752 M:2077036544 NOTICE: DeviceKit.Disks: Added /media/hd
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda2)
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda2 with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda2 with interface org.freedesktop.DBus.Properties and method GetAll
17:03:56 T:3029284752 M:2077036544 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sda with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2077036544 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda2: HasFileSystem IsMounted false IsRemovable false IsPartition true
17:03:56 T:3029284752 M:2077036544 INFO: creating subdirectories
17:03:56 T:3029284752 M:2077036544 INFO: userdata folder: special://masterprofile/
17:03:56 T:3029284752 M:2077036544 INFO: recording folder:
17:03:56 T:3029284752 M:2077036544 INFO: screenshots folder:
17:03:56 T:3029284752 M:2077036544 INFO: thumbnails folder: special://masterprofile/Thumbnails
17:03:56 T:3029284752 M:2077036544 NOTICE: start dvd mediatype detection
17:03:56 T:3027098480 M:2077036544 DEBUG: thread start, auto delete: 0
17:03:56 T:3027098480 M:2077036544 DEBUG: Compiled with libcdio Version 0.78
17:03:56 T:3029284752 M:2077036544 NOTICE: initializing playlistplayer
17:03:56 T:3029284752 M:2077036544 NOTICE: DONE initializing playlistplayer
17:03:56 T:3029284752 M:2077036544 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
17:03:56 T:3029284752 M:2077036544 NOTICE: load default skin:[PM3.HD]
17:03:56 T:3029284752 M:2077036544 INFO: load skin from:special://xbmc/skin/PM3.HD
17:03:56 T:3029284752 M:2077036544 INFO: delete old skin...
17:03:56 T:3029284752 M:2077036544 DEBUG: ------ Window Deinit (Pointer.xml) ------
17:03:56 T:3029284752 M:2077036544 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
17:03:56 T:3029284752 M:2077036544 INFO: Default 4:3 resolution directory is special://xbmc/skin/PM3.HD/720p
17:03:56 T:3029284752 M:2077036544 INFO: Default 16:9 resolution directory is special://xbmc/skin/PM3.HD/720p
17:03:56 T:3029284752 M:2077036544 INFO: Skin version is: 2.11
17:03:56 T:3029284752 M:2077036544 INFO: Loading skin includes from /usr/share/xbmc/skin/PM3.HD/720p/includes.xml
17:03:56 T:3027098480 M:2077036544 DEBUG: Drive closed media present, remounting...
17:03:56 T:3027098480 M:2077036544 INFO: Detecting DVD-ROM media filesystem...
17:03:56 T:3027098480 M:2076909568 INFO: CD Analysis Report
17:03:56 T:3027098480 M:2076909568 INFO: __________________________________
17:03:56 T:3027098480 M:2076909568 INFO: CD-ROM with ISO 9660 filesystem
17:03:56 T:3027098480 M:2076909568 INFO: ISO 9660: 1151849 blocks, label `'
17:03:56 T:3027098480 M:2076909568 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1
17:03:56 T:3027098480 M:2076655616 INFO: Using protocol iso9660://
17:03:56 T:3027098480 M:2076655616 INFO: Disc label:
17:03:56 T:3027098480 M:2076655616 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.jpg]
17:03:56 T:3027098480 M:2076655616 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://Folder.jpg]
17:03:56 T:3027098480 M:2076655616 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.JPG]
17:03:56 T:3027098480 M:2076655616 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://Folder.JPG]
17:03:56 T:3029284752 M:2075893760 INFO: load fonts for skin...
17:03:56 T:3029284752 M:2075758592 INFO: Loading fonts from special://xbmc/skin/PM3.HD/720p/Font.xml
17:03:56 T:3029284752 M:2073853952 INFO: load new skin...
17:03:56 T:3029284752 M:2073853952 INFO: Skin version is: 2.11 (2.110000)
17:03:56 T:3029284752 M:2073853952 INFO: Loading skin file: Home.xml
17:03:56 T:3029284752 M:2073853952 DEBUG: Load Home.xml: 16.46ms
17:03:56 T:3029284752 M:2073853952 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
17:03:56 T:3029284752 M:2073853952 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
17:03:56 T:3029284752 M:2073726976 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
17:03:56 T:3029284752 M:2073726976 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
17:03:56 T:3029284752 M:2073726976 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
17:03:56 T:3029284752 M:2073726976 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
17:03:56 T:3029284752 M:2073726976 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
17:03:56 T:3029284752 M:2073726976 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
17:03:56 T:3029284752 M:2073726976 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
17:03:56 T:3029284752 M:2073726976 DEBUG: Load Skin XML: 34.05ms
17:03:56 T:3029284752 M:2073726976 INFO: initialize new skin...
17:03:56 T:3029284752 M:2073726976 INFO: Loading skin file: Pointer.xml
17:03:56 T:3029284752 M:2073726976 DEBUG: Load Pointer.xml: 0.66ms
17:03:56 T:3029284752 M:2073600000 DEBUG: Alloc resources: 2.20ms (0.73 ms skin load)
17:03:56 T:3029284752 M:2073600000 INFO: Loading skin file: DialogVolumeBar.xml
17:03:56 T:3029284752 M:2073600000 DEBUG: Load DialogVolumeBar.xml: 1.21ms
17:03:56 T:3029284752 M:2073600000 DEBUG: Alloc resources: 1.38ms (1.32 ms skin load)
17:03:56 T:3029284752 M:2073600000 INFO: Loading skin file: DialogSeekBar.xml
17:03:56 T:3029284752 M:2073600000 DEBUG: Load DialogSeekBar.xml: 3.30ms
17:03:56 T:3029284752 M:2073600000 DEBUG: Alloc resources: 3.59ms (3.49 ms skin load)
17:03:56 T:3029284752 M:2073600000 INFO: Loading skin file: DialogKaiToast.xml
17:03:56 T:3029284752 M:2073600000 DEBUG: Load DialogKaiToast.xml: 0.81ms
17:03:56 T:3029284752 M:2073600000 DEBUG: Alloc resources: 0.90ms (0.89 ms skin load)
17:03:56 T:3029284752 M:2073600000 INFO: Loading skin file: DialogMuteBug.xml
17:03:56 T:3029284752 M:2073600000 DEBUG: Load DialogMuteBug.xml: 0.61ms
17:03:56 T:3029284752 M:2073600000 DEBUG: Alloc resources: 0.68ms (0.68 ms skin load)
17:03:56 T:3029284752 M:2073600000 INFO: skin loaded...
17:03:56 T:3029284752 M:2073473024 DEBUG: Loading: libcurl.so.4
17:03:56 T:3029284752 M:2073473024 DEBUG: Activating window ID: 12999
17:03:56 T:3029284752 M:2073473024 DEBUG: Checking if window ID 12999 is locked.
17:03:56 T:3029284752 M:2073473024 DEBUG: ------ Window Init (Startup.xml) ------
17:03:56 T:3029284752 M:2073473024 INFO: Loading skin file: Startup.xml
17:03:56 T:3029284752 M:2073473024 DEBUG: Load Startup.xml: 0.68ms
17:03:56 T:3029284752 M:2073473024 DEBUG: Alloc resources: 0.75ms (0.75 ms skin load)
17:03:56 T:3029284752 M:2073473024 INFO: removing tempfiles
17:03:56 T:3029284752 M:2073473024 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
17:03:56 T:3029284752 M:2073473024 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
17:03:56 T:3029284752 M:2073473024 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
17:03:56 T:3029284752 M:2073473024 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Power on /org/freedesktop/DeviceKit/Power with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2073473024 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Power on /org/freedesktop/DeviceKit/Power with interface org.freedesktop.DBus.Properties and method Get
17:03:56 T:3029284752 M:2073473024 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanRestart
17:03:56 T:3029284752 M:2073473024 NOTICE: initialize done
17:03:56 T:3029284752 M:2073473024 NOTICE: Running the application...
17:03:56 T:3029284752 M:2073473024 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
17:03:56 T:3029284752 M:2073473024 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
17:03:56 T:3029284752 M:2073473024 DEBUG: Activating window ID: 10000
17:03:56 T:3029284752 M:2073473024 DEBUG: Checking if window ID 10000 is locked.
17:03:56 T:3029284752 M:2073473024 DEBUG: ------ Window Deinit (Startup.xml) ------
17:03:56 T:3029284752 M:2073473024 DEBUG: ------ Window Init (Home.xml) ------
17:03:56 T:3029284752 M:2073473024 DEBUG: Alloc resources: 0.83ms (0.00 ms skin load)
17:03:56 T:3029284752 M:2073473024 DEBUG: Process - no user autoexec.py (/home/chris/.xbmc/scripts/autoexec.py) found, skipping
17:03:56 T:3029284752 M:2073473024 DEBUG: Process - no system autoexec.py (/usr/share/xbmc/scripts/autoexec.py) found, skipping
17:03:56 T:3029284752 M:2073473024 DEBUG: NetworkMessage - Starting network services
17:03:56 T:3029284752 M:2073473024 NOTICE: ES: Starting event server
17:03:56 T:3029284752 M:2073473024 NOTICE: DS: Starting dbus server
17:03:56 T:3011505008 M:2073473024 DEBUG: thread start, auto delete: 0
17:03:56 T:3011505008 M:2073473024 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
17:03:56 T:3011505008 M:2073473024 NOTICE: UDP: Listening on port 9777
17:03:56 T:3003112304 M:2073473024 DEBUG: thread start, auto delete: 0
17:03:56 T:3003112304 M:2073473024 NOTICE: DS: Starting DBUS server in Run Application aka thread
17:03:56 T:3029284752 M:2073473024 NOTICE: starting zeroconf publishing
17:03:56 T:3011505008 M:2073473024 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
17:03:56 T:3029284752 M:2073473024 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
17:03:56 T:3029284752 M:2073473024 DEBUG: CZeroconfAvahi::addService() named: XBMC Event Server type: _xbmc-events._udp port:9777
17:03:56 T:3029284752 M:2073473024 DEBUG: CLastfmScrobbler: Clearing session.
17:03:56 T:3029284752 M:2073473024 DEBUG: CLibrefmScrobbler: Clearing session.
17:03:56 T:2986326896 M:2073473024 DEBUG: thread start, auto delete: 1
17:03:57 T:2986326896 M:2061123584 DEBUG: DoWork - took 82 ms to load special://skin/backgrounds/videos.jpg
17:03:57 T:3027028848 M:2061844480 DEBUG: thread start, auto delete: 0
17:03:57 T:3027028848 M:2061844480 DEBUG: FileCurl::Open(0xb46cb120) http://feeds.feedburner.com/xbmc
17:03:57 T:3027028848 M:2061844480 INFO: easy_aquire - Created session to http://feeds.feedburner.com
17:03:57 T:2986326896 M:2062942208 INFO: WEATHER: Downloading weather
17:03:57 T:2986326896 M:2062942208 DEBUG: FileCurl::Open(0xb1ffa100) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
17:03:57 T:2986326896 M:2062942208 INFO: easy_aquire - Created session to http://xoap.weather.com
17:03:57 T:2986326896 M:2063015936 DEBUG: FileCurl::Close(0xb1ffa100) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
17:03:57 T:2986326896 M:2063015936 INFO: WEATHER: Weather download successful
17:03:57 T:3027028848 M:2058825728 DEBUG: FileCurl::Close(0xb46cb120) http://feeds.feedburner.com/xbmc
17:03:57 T:3027028848 M:2058825728 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc
17:03:57 T:3027028848 M:2058825728 DEBUG: RSS feed encoding: ISO-8859-1
17:03:57 T:3027028848 M:2058825728 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc
17:03:57 T:3027028848 M:2063056896 DEBUG: Thread 3027028848 terminating
17:03:57 T:2994719600 M:2063056896 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
17:03:58 T:3029284752 M:2063024128 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
17:03:58 T:3029284752 M:2063024128 DEBUG: OnKey: 61453 pressed, action is 7
17:03:58 T:3029284752 M:2063024128 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoFiles)
17:03:58 T:3029284752 M:2063024128 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoFiles)
17:03:58 T:3029284752 M:2063024128 DEBUG: Activating window ID: 10024
17:03:58 T:3029284752 M:2063024128 DEBUG: Checking if window ID 10024 is locked.
17:03:58 T:3029284752 M:2063024128 DEBUG: ------ Window Deinit (Home.xml) ------
17:03:58 T:3029284752 M:2063024128 INFO: Attempting to default to:
17:03:58 T:3029284752 M:2063024128 DEBUG: ------ Window Init (MyVideo.xml) ------
17:03:58 T:3029284752 M:2063024128 INFO: Loading skin file: MyVideo.xml
17:03:58 T:3029284752 M:2062389248 DEBUG: Load MyVideo.xml: 30.69ms
17:03:58 T:3029284752 M:2062262272 DEBUG: Alloc resources: 33.93ms (31.56 ms skin load)
17:03:58 T:3029284752 M:2062262272 DEBUG: CGUIMediaWindow::GetDirectory ()
17:03:58 T:3029284752 M:2062262272 DEBUG: ParentPath = []
17:03:58 T:2965543792 M:2062262272 DEBUG: thread start, auto delete: 0
17:03:58 T:2965543792 M:2062262272 DEBUG: Thread 2965543792 terminating
17:04:01 T:3029284752 M:2063147008 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:04:01 T:3029284752 M:2063147008 DEBUG: OnKey: 61480 pressed, action is 4
17:04:02 T:3029284752 M:2062647296 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
17:04:02 T:3029284752 M:2062647296 DEBUG: OnKey: 61453 pressed, action is 7
17:04:02 T:3029284752 M:2062647296 DEBUG: CGUIMediaWindow::GetDirectory (/home/chris/Videos/)
17:04:02 T:3029284752 M:2062647296 DEBUG: ParentPath = []
17:04:02 T:2965543792 M:2063155200 DEBUG: thread start, auto delete: 0
17:04:03 T:2986326896 M:2062995456 DEBUG: DoWork - trying to extract thumb from video file /home/chris/Videos/Top.Chef.S06E12.HDTV.XviD-LMAO.avi
17:04:03 T:2986326896 M:2062995456 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avutil-50-i486-linux.so
17:04:03 T:2986326896 M:2062995456 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so
17:04:03 T:2986326896 M:2060328960 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avformat-52-i486-linux.so
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:04:03 T:2986326896 M:2060201984 ERROR: Open - error probing input format, /home/chris/Videos/Top.Chef.S06E12.HDTV.XviD-LMAO.avi
17:04:03 T:2986326896 M:2060201984 ERROR: ExtractThumb - Error creating demuxer
17:04:03 T:2986326896 M:2060201984 DEBUG: DoWork - trying to extract thumb from video file /home/chris/Videos/Mad.Men.S03E13.Shut.the.Door.Have.a.Seat.HDTV.XviD-FQM.avi
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:04:03 T:2986326896 M:2060201984 ERROR: Open - error probing input format, /home/chris/Videos/Mad.Men.S03E13.Shut.the.Door.Have.a.Seat.HDTV.XviD-FQM.avi
17:04:03 T:2986326896 M:2060201984 ERROR: ExtractThumb - Error creating demuxer
17:04:03 T:2986326896 M:2060201984 DEBUG: DoWork - trying to extract thumb from video file /home/chris/Videos/Curb.Your.Enthusiasm.S07E09.HDTV.XviD-NoTV.avi
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:04:03 T:2986326896 M:2060201984 ERROR: Open - error probing input format, /home/chris/Videos/Curb.Your.Enthusiasm.S07E09.HDTV.XviD-NoTV.avi
17:04:03 T:2986326896 M:2060201984 ERROR: ExtractThumb - Error creating demuxer
17:04:03 T:2986326896 M:2060201984 DEBUG: DoWork - trying to extract thumb from video file /home/chris/Videos/Top.Chef.S06E11.HDTV.XviD-SYS.avi
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:04:03 T:2986326896 M:2060201984 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:04:03 T:2986326896 M:2060206080 ERROR: Open - error probing input format, /home/chris/Videos/Top.Chef.S06E11.HDTV.XviD-SYS.avi
17:04:03 T:2986326896 M:2060206080 ERROR: ExtractThumb - Error creating demuxer
17:04:03 T:2965543792 M:2060247040 DEBUG: Thread 2965543792 terminating
17:04:04 T:3029284752 M:2060247040 DEBUG: SDLKeyboard: scancode: 117, sym: 281, unicode: 0, modifier: 0
17:04:04 T:3029284752 M:2060247040 DEBUG: OnKey: 61474 pressed, action is 6
17:04:05 T:3029284752 M:2060247040 DEBUG: SDLKeyboard: scancode: 117, sym: 281, unicode: 0, modifier: 0
17:04:05 T:3029284752 M:2060247040 DEBUG: OnKey: 61474 pressed, action is 6
17:04:07 T:3029284752 M:2052759552 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:04:07 T:3029284752 M:2052759552 DEBUG: OnKey: 61480 pressed, action is 4
17:04:07 T:3029284752 M:2052759552 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:04:07 T:3029284752 M:2052759552 DEBUG: OnKey: 61480 pressed, action is 4
17:04:07 T:3029284752 M:2052759552 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:04:07 T:3029284752 M:2052759552 DEBUG: OnKey: 61480 pressed, action is 4
17:04:08 T:3029284752 M:2052759552 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
17:04:08 T:3029284752 M:2052759552 DEBUG: OnKey: 61453 pressed, action is 7
17:04:08 T:3029284752 M:2052759552 DEBUG: new file set audiostream:0
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerCoreFactory::GetPlayers(/home/chris/Videos/killa.sampla.x264.mkv)
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
17:04:08 T:3029284752 M:2052759552 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
17:04:08 T:3029284752 M:2052124672 NOTICE: DVDPlayer: Opening: /home/chris/Videos/killa.sampla.x264.mkv
17:04:08 T:3029284752 M:2052124672 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
17:04:08 T:2945174384 M:2052124672 DEBUG: thread start, auto delete: 1
17:04:08 T:2936781680 M:2052124672 DEBUG: thread start, auto delete: 0
17:04:08 T:2936781680 M:2052124672 NOTICE: Creating InputStream
17:04:08 T:2936781680 M:2052124672 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
17:04:08 T:2936781680 M:2052124672 DEBUG: CacheSubtitles: START
17:04:08 T:2936781680 M:2052124672 DEBUG: CacheSubtitles: Checking for common subirs...
17:04:08 T:2936781680 M:2052124672 DEBUG: CacheSubtitles: Done (time: 1 ms)
17:04:08 T:2936781680 M:2052124672 DEBUG: CacheSubtitles: Searching for subtitles...
17:04:08 T:2936781680 M:2052124672 DEBUG: CacheSubtitles: Done (time: 5 ms)
17:04:08 T:2936781680 M:2052124672 DEBUG: CacheSubtitles: END (total time: 7 ms)
17:04:08 T:2936781680 M:2052124672 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
17:04:08 T:2936781680 M:2052124672 NOTICE: Creating Demuxer
17:04:08 T:2936781680 M:2052075520 DEBUG: Open - probing detected format [matroska]
17:04:08 T:2936781680 M:2052075520 DEBUG: Open - av_find_stream_info starting
17:04:08 T:2936781680 M:2042474496 DEBUG: Open - av_find_stream_info finished
17:04:08 T:2936781680 M:2042474496 INFO: ffmpeg[AF0BBB70]: Input #0, matroska, from '/home/chris/Videos/killa.sampla.x264.mkv':
17:04:08 T:2936781680 M:2042474496 INFO: ffmpeg[AF0BBB70]: Duration: 00:00:23.04, start: 0.000000, bitrate: N/A
17:04:08 T:2936781680 M:2042474496 INFO: ffmpeg[AF0BBB70]: Stream #0.0: Video: h264, yuv420p, 1920x1080, PAR 1:1 DAR 16:9, 47.92 tbr, 1k tbn, 47.95 tbc
17:04:08 T:2936781680 M:2042474496 INFO: ffmpeg[AF0BBB70]: Stream #0.1: Audio: ac3, 48000 Hz, 6 channels, s16
17:04:08 T:2936781680 M:2042474496 INFO: ffmpeg[AF0BBB70]: Metadata
17:04:08 T:2936781680 M:2042474496 INFO: ffmpeg[AF0BBB70]: title : Avidemux
17:04:08 T:2936781680 M:2042474496 NOTICE: Opening video stream: 0 source: 256
17:04:08 T:2936781680 M:2042474496 NOTICE: Creating video codec with codec id: 28
17:04:08 T:2936781680 M:2042474496 DEBUG: FactoryCodec - Video: - Opening
17:04:08 T:2936781680 M:2042474496 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so
17:04:08 T:2936781680 M:2042220544 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080)
17:04:08 T:2936781680 M:2041602048 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
17:04:08 T:2936781680 M:2041602048 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
17:04:08 T:2936781680 M:2041602048 DEBUG: FactoryCodec - Video: ff-h264_vdpau - Opened
17:04:08 T:2936781680 M:2041602048 NOTICE: Creating video thread
17:04:08 T:2926672752 M:2041602048 DEBUG: thread start, auto delete: 0
17:04:08 T:2926672752 M:2041602048 DEBUG: LinuxRendererGL: Cleaning up GL resources
17:04:08 T:2926672752 M:2041602048 ERROR: (VDPAU) ReleasePixmap called without valid pixmap
17:04:08 T:2926672752 M:2041602048 NOTICE: running thread: video_thread
17:04:08 T:2936781680 M:2041602048 NOTICE: Opening audio stream: 1 source: 256
17:04:08 T:2936781680 M:2041602048 NOTICE: Finding audio codec for: 86020
17:04:08 T:2936781680 M:2041602048 DEBUG: FactoryCodec - Audio: passthrough - Opening
17:04:08 T:2936781680 M:2041602048 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/liba52-i486-linux.so
17:04:08 T:2936781680 M:2041602048 DEBUG: FactoryCodec - Audio: passthrough - Opened
17:04:08 T:2936781680 M:2041602048 NOTICE: Creating audio thread
17:04:08 T:2918280048 M:2041602048 DEBUG: thread start, auto delete: 0
17:04:08 T:2918280048 M:2041602048 NOTICE: running thread: CDVDPlayerAudio::Process()
17:04:08 T:2936781680 M:2041602048 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: /home/chris/Videos/killa.sampla.x264.mkv
17:04:08 T:2936781680 M:2041602048 INFO: Loading skin file: DialogProgress.xml
17:04:08 T:3029284752 M:2041602048 DEBUG: OnPlayBackStarted - Playback has started
17:04:08 T:3029284752 M:2041348096 DEBUG: CGUIInfoManager::SetCurrentMovie(/home/chris/Videos/killa.sampla.x264.mkv)
17:04:08 T:2936781680 M:2041348096 DEBUG: Load DialogProgress.xml: 8.28ms
17:04:08 T:3029284752 M:2041348096 DEBUG: GetMovieId (/home/chris/Videos/killa.sampla.x264.mkv), query = select idMovie from movie where idFile=51
17:04:08 T:3029284752 M:2041348096 DEBUG: GetEpisodeId (/home/chris/Videos/killa.sampla.x264.mkv), query = select idEpisode from episode where idFile=51
17:04:08 T:3029284752 M:2041348096 DEBUG: GetMusicVideoId (/home/chris/Videos/killa.sampla.x264.mkv), query = select idMVideo from musicvideo where idFile=51
17:04:08 T:2945174384 M:2041348096 DEBUG: Thread 2945174384 terminating (autodelete)
17:04:08 T:2918280048 M:2041221120 DEBUG: ParseFrame - source flags changed flags:17 sr:48000 br:448000
17:04:08 T:2918280048 M:2041298944 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, pass-through
17:04:08 T:2918280048 M:2041298944 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec AC3 - IsMusic false - IsPassthrough true - audioDevice: hdmi
17:04:08 T:2918280048 M:2041327616 DEBUG: RemoveActiveDevice - Removing device 1
17:04:08 T:2918280048 M:2041327616 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
17:04:08 T:2918280048 M:2041327616 DEBUG: RemoveActiveDevice - Removing device 0
17:04:08 T:2918280048 M:2041327616 DEBUG: Initialize - using alsa device hdmi:AES0=0x6,AES1=0x82,AES2=0x0,AES3=0x2
17:04:08 T:2926672752 M:2041327616 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
17:04:08 T:2926672752 M:2041327616 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(83000.000000, 1)
17:04:08 T:2918280048 M:2041090048 ERROR: Initialize - device hdmi:AES0=0x6,AES1=0x82,AES2=0x0,AES3=0x2 busy retrying...
17:04:08 T:2926672752 M:2041004032 NOTICE: Creating 1920x1009 pixmap
17:04:08 T:2926672752 M:2041004032 DEBUG: Found 6 fbconfigs.
17:04:08 T:2926672752 M:2041004032 DEBUG: Using fbconfig index 0.
17:04:08 T:2926672752 M:2041004032 INFO: GLX: Creating Pixmap context
17:04:08 T:2926672752 M:2031603712 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 0). Creating a new one. TRY #1
17:04:08 T:2926672752 M:2027233280 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 1). Creating a new one. TRY #1
17:04:08 T:2926672752 M:2022420480 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 2). Creating a new one. TRY #1
17:04:08 T:2926672752 M:2018746368 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 3). Creating a new one. TRY #1
17:04:08 T:2926672752 M:2014445568 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 4). Creating a new one. TRY #1
17:04:08 T:2926672752 M:2010095616 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 5). Creating a new one. TRY #1
17:04:09 T:2926672752 M:2005311488 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 6). Creating a new one. TRY #1
17:04:09 T:2926672752 M:2001539072 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 7). Creating a new one. TRY #1
17:04:09 T:2918280048 M:1997877248 ERROR: Initialize - failed to open custom device hdmi:AES0=0x6,AES1=0x82,AES2=0x0,AES3=0x2, retry with default hdmi
17:04:09 T:2918280048 M:1996996608 ERROR: Initialize - pcm_open_lconf, alsa error: -16 - Device or resource busy
17:04:09 T:2918280048 M:1997094912 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
17:04:09 T:2918280048 M:1997017088 DEBUG: RemoveActiveDevice - Removing device 2
17:04:09 T:2918280048 M:1997017088 ERROR: Creating a Null Audio Renderer, Check your audio settings as this should not happen
17:04:09 T:2918280048 M:1997037568 DEBUG: RemoveActiveDevice - Removing device 1
17:04:09 T:2918280048 M:1997037568 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
17:04:09 T:2918280048 M:1997135872 DEBUG: RemoveActiveDevice - Removing device 0
17:04:09 T:2918280048 M:1997107200 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
17:04:09 T:2918280048 M:1997156352 DEBUG: CDVDPlayerAudio:: Discontinuty - was:291123.464000, should be:13.600343, error:-291109.863657
17:04:09 T:2918280048 M:1997127680 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 0)
17:04:09 T:2926672752 M:1996419072 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 8). Creating a new one. TRY #1
17:04:09 T:3029284752 M:1994080256 DEBUG: ------ Window Init (DialogKaiToast.xml) ------
17:04:09 T:3029284752 M:1993953280 DEBUG: Alloc resources: 0.00ms (0.00 ms skin load)
17:04:09 T:2926672752 M:1992937472 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 9). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1988108288 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 10). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1983098880 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 11). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1973108736 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 12). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1968631808 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 13). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1964265472 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 14). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1960054784 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 15). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1956380672 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 16). Creating a new one. TRY #1
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 ERROR: ffmpeg[AE717B70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:04:09 T:2926672752 M:1951936512 NOTICE: (VDPAU) FiniVDPAUOutput
17:04:09 T:2926672752 M:1951936512 NOTICE: (VDPAU) screenWidth:1920 vidWidth:1920
17:04:09 T:2926672752 M:1951936512 NOTICE: (VDPAU) screenHeight:1009 vidHeight:1080
17:04:09 T:2926672752 M:1868189696 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4)
17:04:09 T:2926672752 M:1868189696 NOTICE: VDPAU Decoder capabilities:
17:04:09 T:2926672752 M:1868189696 NOTICE: name level macbs width height
17:04:09 T:2926672752 M:1868189696 NOTICE: ------------------------------------
17:04:09 T:2926672752 M:1868189696 NOTICE: MPEG1 0 8192 2048 2048
17:04:09 T:2926672752 M:1868189696 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048
17:04:09 T:2926672752 M:1868189696 NOTICE: MPEG2_MAIN 3 8192 2048 2048
17:04:09 T:2926672752 M:1868189696 NOTICE: H264_MAIN 41 8190 2032 2048
17:04:09 T:2926672752 M:1868189696 NOTICE: H264_HIGH 41 8190 2032 2048
17:04:09 T:2926672752 M:1868189696 NOTICE: VC1_SIMPLE 1 8190 2048 2048
17:04:09 T:2926672752 M:1868189696 NOTICE: VC1_MAIN 2 8190 2048 2048
17:04:09 T:2926672752 M:1868189696 NOTICE: VC1_ADVANCED 4 8190 2048 2048
17:04:09 T:2926672752 M:1868062720 NOTICE: (VDPAU) Creating the video mixer
17:04:09 T:2926672752 M:1854386176 NOTICE: fps: 47.916668, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
17:04:09 T:2926672752 M:1854459904 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 47.92
17:04:09 T:2926672752 M:1854459904 NOTICE: Display resolution DESKTOP : 1920x1080 @ 50.00 - Full Screen (12)
17:04:09 T:3029284752 M:1854406656 DEBUG: Activating window ID: 12005
17:04:09 T:3029284752 M:1854406656 DEBUG: Checking if window ID 12005 is locked.
17:04:09 T:3029284752 M:1854533632 DEBUG: ------ Window Deinit (MyVideo.xml) ------
17:04:09 T:3029284752 M:1854533632 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
17:04:09 T:3029284752 M:1854533632 INFO: Loading skin file: VideoFullScreen.xml
17:04:09 T:3029284752 M:1854533632 DEBUG: Load VideoFullScreen.xml: 1.40ms
17:04:09 T:3029284752 M:1854533632 DEBUG: Alloc resources: 1.57ms (1.57 ms skin load)
17:04:09 T:3029284752 M:1854533632 INFO: Loading skin file: VideoOSD.xml
17:04:09 T:3029284752 M:1854533632 DEBUG: Load VideoOSD.xml: 5.76ms
17:04:09 T:3029284752 M:1854533632 DEBUG: Alloc resources: 10.67ms (0.00 ms skin load)
17:04:09 T:3029284752 M:1854533632 INFO: Loading skin file: VideoOSDSettings.xml
17:04:09 T:3029284752 M:1854533632 DEBUG: Load VideoOSDSettings.xml: 2.89ms
17:04:09 T:3029284752 M:1854533632 DEBUG: Alloc resources: 2.60ms (0.00 ms skin load)
17:04:09 T:3029284752 M:1854533632 INFO: Loading skin file: VideoOSDSettings.xml
17:04:09 T:3029284752 M:1854533632 DEBUG: Load VideoOSDSettings.xml: 2.55ms
17:04:09 T:3029284752 M:1854533632 DEBUG: Alloc resources: 0.12ms (0.00 ms skin load)
17:04:09 T:3029284752 M:1854533632 NOTICE: Using GL_TEXTURE_2D
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Requested render method: 0
17:04:09 T:3029284752 M:1854533632 NOTICE: GL: Using VDPAU render method
17:04:09 T:3029284752 M:1854533632 NOTICE: GL: NPOT texture support detected
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 1080
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1854533632 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 1080
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1854406656 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:04:09 T:3029284752 M:1865834496 NOTICE: Created m_glPixmapTexture (1)
17:04:11 T:2926672752 M:1863032832 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 17). Creating a new one. TRY #1
17:04:12 T:3029284752 M:1858334720 DEBUG: SDLKeyboard: scancode: 65, sym: 32, unicode: 32, modifier: 0
17:04:12 T:3029284752 M:1858334720 DEBUG: OnKey: 61472 pressed, action is 12
17:04:12 T:3029284752 M:1858334720 DEBUG: OnPlayBackPaused - Playback was paused
17:04:12 T:3029284752 M:1858334720 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
17:04:12 T:3029284752 M:1858334720 DEBUG: Alloc resources: 0.03ms (0.00 ms skin load)
17:04:13 T:3029284752 M:1858265088 DEBUG: SDLKeyboard: scancode: 64, sym: 308, unicode: 0, modifier: 0
17:04:14 T:3029284752 M:1857765376 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
17:04:20 T:3029284752 M:1857740800 DEBUG: DeviceKit.Disks: DeviceChanged (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:04:20 T:3029284752 M:1857740800 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sr0 with interface org.freedesktop.DBus.Properties and method Get
17:04:20 T:3029284752 M:1857740800 DEBUG: DeviceKit.Disks: DeviceChanged - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:04:20 T:3029284752 M:1857789952 DEBUG: DeviceKit.Disks: DeviceChanged (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:04:20 T:3029284752 M:1857789952 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks/devices/sr0 with interface org.freedesktop.DBus.Properties and method Get
17:04:20 T:3029284752 M:1857789952 DEBUG: DeviceKit.Disks: DeviceChanged - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false