rendered paste body17:34:40 T:3029157776 M:3304271872 ERROR: Failed to create common scripts symlink.
17:34:40 T:3029157776 M:3304271872 NOTICE: -----------------------------------------------------------------------
17:34:40 T:3029157776 M:3304271872 NOTICE: Starting XBMC, Platform: GNU/Linux. Built on Dec 24 2009 (SVN:26018)
17:34:40 T:3029157776 M:3304271872 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
17:34:40 T:3029157776 M:3304271872 NOTICE: special://masterprofile/ is mapped to: /home/chris/.xbmc/userdata
17:34:40 T:3029157776 M:3304271872 NOTICE: special://home/ is mapped to: /home/chris/.xbmc
17:34:40 T:3029157776 M:3304271872 NOTICE: special://temp/ is mapped to: /home/chris/.xbmc/temp
17:34:40 T:3029157776 M:3304271872 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
17:34:40 T:3029157776 M:3304271872 NOTICE: Log File is located: /home/chris/.xbmc/temp/xbmc.log
17:34:40 T:3029157776 M:3304271872 NOTICE: -----------------------------------------------------------------------
17:34:40 T:3029157776 M:3304144896 NOTICE: Setup SDL
17:34:40 T:3029157776 M:3303460864 NOTICE: load settings...
17:34:40 T:3029157776 M:3303460864 NOTICE: special://profile/ is mapped to: special://masterprofile/
17:34:40 T:3029157776 M:3303460864 NOTICE: loading special://masterprofile/guisettings.xml
17:34:40 T:3029157776 M:3303460864 NOTICE: Getting hardware information now...
17:34:40 T:3029157776 M:3303460864 NOTICE: Checking resolution 11
17:34:40 T:3029157776 M:3303460864 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
17:34:40 T:3029157776 M:3303460864 NOTICE: Loaded playercorefactory configuration
17:34:40 T:3029157776 M:3303460864 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
17:34:40 T:3029157776 M:3303460864 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
17:34:40 T:3029157776 M:3303460864 NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml)
17:34:40 T:3029157776 M:3303460864 NOTICE: Default DVD Player: dvdplayer
17:34:40 T:3029157776 M:3303460864 NOTICE: Default Video Player: dvdplayer
17:34:40 T:3029157776 M:3303460864 NOTICE: Default Audio Player: paplayer
17:34:40 T:3029157776 M:3303460864 NOTICE: Enabled debug logging due to GUI setting (2)
17:34:40 T:3029157776 M:3303460864 NOTICE: special://masterprofile/sources.xml
17:34:40 T:3029157776 M:3303460864 INFO: XRANDR: /usr/share/xbmc/xbmc-xrandr --output default --mode 0x146
17:34:40 T:3029157776 M:3298816000 NOTICE: Using fbConfig[0]
17:34:40 T:3029157776 M:3298435072 INFO: GL: Maximum texture width: 8192
17:34:40 T:3029157776 M:3298435072 DEBUG: Loading: /usr/share/xbmc/system/ImageLib-i486-linux.so
17:34:40 T:3029157776 M:3297910784 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:34:40 T:3029157776 M:3297783808 NOTICE: GL_VENDOR = NVIDIA Corporation
17:34:40 T:3029157776 M:3297783808 NOTICE: GL_RENDERER = GeForce 8200/PCI/SSE2/3DNOW!
17:34:40 T:3029157776 M:3297783808 NOTICE: GL_VERSION = 3.2.0 NVIDIA 190.53
17:34:40 T:3029157776 M:3297783808 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:34:40 T:3029157776 M:3297783808 INFO: GL: Maximum texture width: 8192
17:34:40 T:3029157776 M:3297783808 INFO: load language info file: special://xbmc/language/English/langinfo.xml
17:34:40 T:3029157776 M:3295879168 INFO: load language file:special://xbmc/language/English/strings.xml
17:34:40 T:3029157776 M:3295879168 INFO: load keymapping
17:34:40 T:3029157776 M:3295879168 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
17:34:40 T:3029157776 M:3295879168 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
17:34:40 T:3029157776 M:3295879168 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
17:34:40 T:3029157776 M:3295879168 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
17:34:40 T:3029157776 M:3295879168 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
17:34:40 T:3029157776 M:3295752192 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
17:34:40 T:3029157776 M:3295752192 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
17:34:40 T:3029157776 M:3295752192 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
17:34:40 T:3029157776 M:3295752192 INFO: Loading special://xbmc/system/keymaps/remote.xml
17:34:40 T:3029157776 M:3295625216 INFO: Loading special://xbmc/system/Lircmap.xml
17:34:40 T:3029157776 M:3295625216 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
17:34:40 T:3029157776 M:3295625216 INFO: Checking skin version of: PM3.HD
17:34:40 T:3029157776 M:3295625216 INFO: Skin version is: 2.11 (2.110000)
17:34:40 T:3029157776 M:3295625216 INFO: GUI format 1920x1009 Windowed
17:34:40 T:3029157776 M:3295625216 DEBUG: Selected DeviceKit.Disks as storage provider
17:34:40 T:3029157776 M:3295625216 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DBus.Properties and method Get
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DaemonVersion 7
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: Querying available devices
17:34:40 T:3029157776 M:3295625216 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda3)
17:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda3: HasFileSystem ext4 IsMounted true IsRemovable false IsPartition true
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda)
17:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda5)
17:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda5: HasFileSystem swap IsMounted false IsRemovable false IsPartition true
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda1)
17:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda1: HasFileSystem ext4 IsMounted true IsRemovable false IsPartition true
17:34:40 T:3029157776 M:3295625216 NOTICE: DeviceKit.Disks: Added /media/hd
17:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded (/org/freedesktop/DeviceKit/Disks/devices/sda2)
17:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 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:34:40 T:3029157776 M:3295625216 DEBUG: DeviceKit.Disks: DeviceAdded - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sda2: HasFileSystem IsMounted false IsRemovable false IsPartition true
17:34:40 T:3029157776 M:3295625216 INFO: creating subdirectories
17:34:40 T:3029157776 M:3295625216 INFO: userdata folder: special://masterprofile/
17:34:40 T:3029157776 M:3295625216 INFO: recording folder:
17:34:40 T:3029157776 M:3295625216 INFO: screenshots folder:
17:34:40 T:3029157776 M:3295625216 INFO: thumbnails folder: special://masterprofile/Thumbnails
17:34:40 T:3029157776 M:3295625216 NOTICE: start dvd mediatype detection
17:34:40 T:3026942832 M:3295625216 DEBUG: thread start, auto delete: 0
17:34:40 T:3026942832 M:3295625216 DEBUG: Compiled with libcdio Version 0.78
17:34:40 T:3029157776 M:3295625216 NOTICE: initializing playlistplayer
17:34:40 T:3029157776 M:3295625216 NOTICE: DONE initializing playlistplayer
17:34:40 T:3029157776 M:3295625216 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
17:34:40 T:3029157776 M:3295625216 NOTICE: load default skin:[PM3.HD]
17:34:40 T:3029157776 M:3295625216 INFO: load skin from:special://xbmc/skin/PM3.HD
17:34:40 T:3029157776 M:3295625216 INFO: delete old skin...
17:34:40 T:3029157776 M:3295625216 DEBUG: ------ Window Deinit (Pointer.xml) ------
17:34:40 T:3029157776 M:3295625216 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
17:34:40 T:3029157776 M:3295625216 INFO: Default 4:3 resolution directory is special://xbmc/skin/PM3.HD/720p
17:34:40 T:3029157776 M:3295625216 INFO: Default 16:9 resolution directory is special://xbmc/skin/PM3.HD/720p
17:34:40 T:3029157776 M:3295625216 INFO: Skin version is: 2.11
17:34:40 T:3026942832 M:3295625216 DEBUG: Drive closed media present, remounting...
17:34:40 T:3029157776 M:3295625216 INFO: Loading skin includes from /usr/share/xbmc/skin/PM3.HD/720p/includes.xml
17:34:40 T:3026942832 M:3295625216 INFO: Detecting DVD-ROM media filesystem...
17:34:40 T:3026942832 M:3295625216 INFO: CD Analysis Report
17:34:40 T:3026942832 M:3295625216 INFO: __________________________________
17:34:40 T:3026942832 M:3295625216 INFO: CD-ROM with ISO 9660 filesystem
17:34:40 T:3026942832 M:3295625216 INFO: ISO 9660: 1151849 blocks, label `'
17:34:40 T:3026942832 M:3295625216 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1
17:34:40 T:3029157776 M:3294609408 INFO: load fonts for skin...
17:34:40 T:3029157776 M:3294609408 INFO: Loading fonts from special://xbmc/skin/PM3.HD/720p/Font.xml
17:34:40 T:3029157776 M:3292704768 INFO: load new skin...
17:34:40 T:3029157776 M:3292704768 INFO: Skin version is: 2.11 (2.110000)
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: Home.xml
17:34:40 T:3029157776 M:3292704768 DEBUG: Load Home.xml: 23.85ms
17:34:40 T:3029157776 M:3292704768 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
17:34:40 T:3029157776 M:3292704768 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
17:34:40 T:3029157776 M:3292704768 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
17:34:40 T:3029157776 M:3292704768 DEBUG: Load Skin XML: 47.63ms
17:34:40 T:3029157776 M:3292704768 INFO: initialize new skin...
17:34:40 T:3029157776 M:3292704768 INFO: Loading skin file: Pointer.xml
17:34:40 T:3029157776 M:3292704768 DEBUG: Load Pointer.xml: 0.94ms
17:34:40 T:3029157776 M:3292577792 DEBUG: Alloc resources: 3.03ms (1.04 ms skin load)
17:34:40 T:3029157776 M:3292577792 INFO: Loading skin file: DialogVolumeBar.xml
17:34:40 T:3029157776 M:3292577792 DEBUG: Load DialogVolumeBar.xml: 1.66ms
17:34:40 T:3029157776 M:3292577792 DEBUG: Alloc resources: 1.88ms (1.79 ms skin load)
17:34:40 T:3029157776 M:3292577792 INFO: Loading skin file: DialogSeekBar.xml
17:34:40 T:3029157776 M:3292450816 DEBUG: Load DialogSeekBar.xml: 4.78ms
17:34:40 T:3029157776 M:3292450816 DEBUG: Alloc resources: 5.14ms (5.01 ms skin load)
17:34:40 T:3029157776 M:3292450816 INFO: Loading skin file: DialogKaiToast.xml
17:34:40 T:3029157776 M:3292450816 DEBUG: Load DialogKaiToast.xml: 1.15ms
17:34:40 T:3029157776 M:3292450816 DEBUG: Alloc resources: 1.26ms (1.26 ms skin load)
17:34:40 T:3029157776 M:3292450816 INFO: Loading skin file: DialogMuteBug.xml
17:34:40 T:3029157776 M:3292450816 DEBUG: Load DialogMuteBug.xml: 0.89ms
17:34:40 T:3029157776 M:3292450816 DEBUG: Alloc resources: 0.99ms (0.99 ms skin load)
17:34:40 T:3029157776 M:3292450816 INFO: skin loaded...
17:34:40 T:3029157776 M:3292450816 DEBUG: Loading: libcurl.so.4
17:34:40 T:3029157776 M:3292450816 DEBUG: Activating window ID: 12999
17:34:40 T:3029157776 M:3292450816 DEBUG: Checking if window ID 12999 is locked.
17:34:40 T:3029157776 M:3292450816 DEBUG: ------ Window Init (Startup.xml) ------
17:34:40 T:3029157776 M:3292450816 INFO: Loading skin file: Startup.xml
17:34:40 T:3029157776 M:3292450816 DEBUG: Load Startup.xml: 0.94ms
17:34:40 T:3029157776 M:3292450816 DEBUG: Alloc resources: 1.04ms (1.04 ms skin load)
17:34:40 T:3029157776 M:3292450816 INFO: removing tempfiles
17:34:40 T:3029157776 M:3292450816 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
17:34:40 T:3029157776 M:3292450816 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
17:34:40 T:3029157776 M:3292450816 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
17:34:40 T:3029157776 M:3292450816 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Power on /org/freedesktop/DeviceKit/Power with interface org.freedesktop.DBus.Properties and method Get
17:34:40 T:3029157776 M:3292450816 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Power on /org/freedesktop/DeviceKit/Power with interface org.freedesktop.DBus.Properties and method Get
17:34:40 T:3029157776 M:3292450816 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanRestart
17:34:40 T:3029157776 M:3292450816 NOTICE: initialize done
17:34:40 T:3029157776 M:3292450816 NOTICE: Running the application...
17:34:40 T:3029157776 M:3292450816 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
17:34:40 T:3029157776 M:3292450816 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
17:34:40 T:3029157776 M:3292450816 DEBUG: Activating window ID: 10000
17:34:40 T:3029157776 M:3292450816 DEBUG: Checking if window ID 10000 is locked.
17:34:40 T:3029157776 M:3292450816 DEBUG: ------ Window Deinit (Startup.xml) ------
17:34:40 T:3029157776 M:3292450816 DEBUG: ------ Window Init (Home.xml) ------
17:34:40 T:3029157776 M:3292450816 DEBUG: Alloc resources: 1.02ms (0.00 ms skin load)
17:34:40 T:3029157776 M:3292450816 DEBUG: Process - no user autoexec.py (/home/chris/.xbmc/scripts/autoexec.py) found, skipping
17:34:40 T:3029157776 M:3292450816 DEBUG: Process - no system autoexec.py (/usr/share/xbmc/scripts/autoexec.py) found, skipping
17:34:40 T:3029157776 M:3292450816 DEBUG: NetworkMessage - Starting network services
17:34:40 T:3029157776 M:3292450816 NOTICE: ES: Starting event server
17:34:40 T:3029157776 M:3292450816 NOTICE: DS: Starting dbus server
17:34:40 T:3011505008 M:3292450816 DEBUG: thread start, auto delete: 0
17:34:40 T:3011505008 M:3292450816 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
17:34:40 T:3011505008 M:3292450816 NOTICE: UDP: Listening on port 9777
17:34:40 T:3003112304 M:3292450816 DEBUG: thread start, auto delete: 0
17:34:40 T:3003112304 M:3292450816 NOTICE: DS: Starting DBUS server in Run Application aka thread
17:34:40 T:3029157776 M:3292450816 NOTICE: starting zeroconf publishing
17:34:40 T:3011505008 M:3292450816 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
17:34:40 T:3029157776 M:3292282880 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
17:34:40 T:3029157776 M:3292282880 DEBUG: CZeroconfAvahi::addService() named: XBMC Event Server type: _xbmc-events._udp port:9777
17:34:40 T:3029157776 M:3292282880 DEBUG: CLastfmScrobbler: Clearing session.
17:34:40 T:3029157776 M:3292282880 DEBUG: CLibrefmScrobbler: Clearing session.
17:34:40 T:2986326896 M:3292282880 DEBUG: thread start, auto delete: 1
17:34:41 T:2986326896 M:3279728640 DEBUG: DoWork - took 94 ms to load special://skin/backgrounds/videos.jpg
17:34:41 T:3026873200 M:3281125376 DEBUG: thread start, auto delete: 0
17:34:41 T:3026873200 M:3281854464 DEBUG: FileCurl::Open(0xb46a5120) http://feeds.feedburner.com/xbmc
17:34:41 T:3026873200 M:3281600512 INFO: easy_aquire - Created session to http://feeds.feedburner.com
17:34:41 T:2986326896 M:3282489344 INFO: WEATHER: Downloading weather
17:34:41 T:2986326896 M:3282489344 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:34:41 T:2986326896 M:3282489344 INFO: easy_aquire - Created session to http://xoap.weather.com
17:34:41 T:2986326896 M:3282542592 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:34:41 T:2986326896 M:3282542592 INFO: WEATHER: Weather download successful
17:34:41 T:3026873200 M:3278225408 DEBUG: FileCurl::Close(0xb46a5120) http://feeds.feedburner.com/xbmc
17:34:41 T:3026873200 M:3278225408 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc
17:34:41 T:3026873200 M:3278225408 DEBUG: RSS feed encoding: ISO-8859-1
17:34:41 T:3026873200 M:3278225408 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc
17:34:41 T:3026873200 M:3281907712 DEBUG: Thread 3026873200 terminating
17:34:41 T:2994719600 M:3281907712 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
17:34:41 T:3029157776 M:3281907712 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
17:34:41 T:3029157776 M:3281907712 DEBUG: OnKey: 61453 pressed, action is 7
17:34:41 T:3029157776 M:3281907712 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoFiles)
17:34:41 T:3029157776 M:3281907712 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoFiles)
17:34:41 T:3029157776 M:3281907712 DEBUG: Activating window ID: 10024
17:34:41 T:3029157776 M:3281907712 DEBUG: Checking if window ID 10024 is locked.
17:34:41 T:3029157776 M:3281907712 DEBUG: ------ Window Deinit (Home.xml) ------
17:34:42 T:3029157776 M:3281932288 INFO: Attempting to default to:
17:34:42 T:3029157776 M:3281932288 DEBUG: ------ Window Init (MyVideo.xml) ------
17:34:42 T:3029157776 M:3281932288 INFO: Loading skin file: MyVideo.xml
17:34:42 T:3029157776 M:3281170432 DEBUG: Load MyVideo.xml: 31.94ms
17:34:42 T:3029157776 M:3281043456 DEBUG: Alloc resources: 35.18ms (32.79 ms skin load)
17:34:42 T:3029157776 M:3281043456 DEBUG: CGUIMediaWindow::GetDirectory ()
17:34:42 T:3029157776 M:3281043456 DEBUG: ParentPath = []
17:34:42 T:2965543792 M:3281043456 DEBUG: thread start, auto delete: 0
17:34:42 T:2965543792 M:3281043456 DEBUG: Thread 2965543792 terminating
17:34:43 T:3026942832 M:3280666624 INFO: Using protocol iso9660://
17:34:43 T:3026942832 M:3280666624 INFO: Disc label:
17:34:43 T:3026942832 M:3280666624 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.jpg]
17:34:43 T:3026942832 M:3280666624 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://Folder.jpg]
17:34:43 T:3026942832 M:3280666624 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.JPG]
17:34:43 T:3026942832 M:3280666624 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://Folder.JPG]
17:34:43 T:3029157776 M:3280666624 DEBUG: CGUIMediaWindow::GetDirectory ()
17:34:43 T:3029157776 M:3280666624 DEBUG: ParentPath = []
17:34:43 T:2965543792 M:3280666624 DEBUG: thread start, auto delete: 0
17:34:43 T:2965543792 M:3280666624 DEBUG: Thread 2965543792 terminating
17:34:43 T:3029157776 M:3280809984 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:34:43 T:3029157776 M:3280809984 DEBUG: OnKey: 61480 pressed, action is 4
17:34:43 T:3029157776 M:3280683008 DEBUG: DeviceKit.Disks: DeviceChanged (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:34:43 T:3029157776 M:3280683008 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:34:43 T:3029157776 M:3280683008 DEBUG: DeviceKit.Disks: DeviceChanged - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:34:43 T:3029157776 M:3280556032 DEBUG: CGUIMediaWindow::GetDirectory ()
17:34:43 T:3029157776 M:3280556032 DEBUG: ParentPath = []
17:34:43 T:2965543792 M:3280556032 DEBUG: thread start, auto delete: 0
17:34:43 T:3029157776 M:3280556032 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
17:34:43 T:3029157776 M:3280556032 DEBUG: OnKey: 61453 pressed, action is 7
17:34:43 T:2965543792 M:3280556032 DEBUG: Thread 2965543792 terminating
17:34:43 T:3029157776 M:3280556032 DEBUG: CGUIMediaWindow::GetDirectory (/home/chris/Videos/)
17:34:43 T:3029157776 M:3280556032 DEBUG: ParentPath = []
17:34:43 T:2965543792 M:3280429056 DEBUG: thread start, auto delete: 0
17:34:44 T:2986326896 M:3280441344 DEBUG: DoWork - trying to extract thumb from video file /home/chris/Videos/Top.Chef.S06E12.HDTV.XviD-LMAO.avi
17:34:44 T:2986326896 M:3280441344 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avutil-50-i486-linux.so
17:34:44 T:2986326896 M:3280441344 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so
17:34:44 T:2986326896 M:3277651968 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avformat-52-i486-linux.so
17:34:44 T:2986326896 M:3277144064 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:34:44 T:2986326896 M:3277144064 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:34:44 T:3029157776 M:3278667776 DEBUG: DeviceKit.Disks: DeviceChanged (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:34:44 T:3029157776 M:3278667776 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:34:44 T:3029157776 M:3278667776 DEBUG: DeviceKit.Disks: DeviceChanged - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:34:44 T:2986326896 M:3278667776 ERROR: Open - error probing input format, /home/chris/Videos/Top.Chef.S06E12.HDTV.XviD-LMAO.avi
17:34:44 T:2986326896 M:3278667776 ERROR: ExtractThumb - Error creating demuxer
17:34:44 T:2986326896 M:3278667776 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:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:34:44 T:2965543792 M:3278667776 DEBUG: Thread 2965543792 terminating
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:34:44 T:2986326896 M:3278667776 ERROR: Open - error probing input format, /home/chris/Videos/Mad.Men.S03E13.Shut.the.Door.Have.a.Seat.HDTV.XviD-FQM.avi
17:34:44 T:2986326896 M:3278667776 ERROR: ExtractThumb - Error creating demuxer
17:34:44 T:2986326896 M:3278667776 DEBUG: DoWork - trying to extract thumb from video file /home/chris/Videos/Curb.Your.Enthusiasm.S07E09.HDTV.XviD-NoTV.avi
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:34:44 T:2986326896 M:3278667776 ERROR: Open - error probing input format, /home/chris/Videos/Curb.Your.Enthusiasm.S07E09.HDTV.XviD-NoTV.avi
17:34:44 T:2986326896 M:3278667776 ERROR: ExtractThumb - Error creating demuxer
17:34:44 T:2986326896 M:3278667776 DEBUG: DoWork - trying to extract thumb from video file /home/chris/Videos/Top.Chef.S06E11.HDTV.XviD-SYS.avi
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [2048]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [4096]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [8192]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [16384]
17:34:44 T:2986326896 M:3278667776 DEBUG: Open - probing failed, re-probing with probe size [32768]
17:34:44 T:2986326896 M:3278667776 ERROR: Open - error probing input format, /home/chris/Videos/Top.Chef.S06E11.HDTV.XviD-SYS.avi
17:34:44 T:2986326896 M:3278667776 ERROR: ExtractThumb - Error creating demuxer
17:34:44 T:3029157776 M:3278667776 DEBUG: SDLKeyboard: scancode: 117, sym: 281, unicode: 0, modifier: 0
17:34:44 T:3029157776 M:3278667776 DEBUG: OnKey: 61474 pressed, action is 6
17:34:44 T:3029157776 M:3278667776 DEBUG: DeviceKit.Disks: DeviceChanged (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:34:44 T:3029157776 M:3278667776 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:34:44 T:3029157776 M:3278667776 DEBUG: DeviceKit.Disks: DeviceChanged - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:34:45 T:3029157776 M:3278716928 DEBUG: DeviceKit.Disks: DeviceChanged (/org/freedesktop/DeviceKit/Disks/devices/sr0)
17:34:45 T:3029157776 M:3278716928 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:34:45 T:3029157776 M:3278716928 DEBUG: DeviceKit.Disks: DeviceChanged - DeviceUDI /org/freedesktop/DeviceKit/Disks/devices/sr0: HasFileSystem IsMounted false IsRemovable false IsPartition false
17:34:45 T:3029157776 M:3278716928 DEBUG: SDLKeyboard: scancode: 117, sym: 281, unicode: 0, modifier: 0
17:34:45 T:3029157776 M:3278716928 DEBUG: OnKey: 61474 pressed, action is 6
17:34:46 T:3029157776 M:3271995392 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:34:46 T:3029157776 M:3271995392 DEBUG: OnKey: 61480 pressed, action is 4
17:34:46 T:3029157776 M:3271995392 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:34:46 T:3029157776 M:3271995392 DEBUG: OnKey: 61480 pressed, action is 4
17:34:46 T:3029157776 M:3271995392 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
17:34:46 T:3029157776 M:3271995392 DEBUG: OnKey: 61480 pressed, action is 4
17:34:47 T:3029157776 M:3271995392 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
17:34:47 T:3029157776 M:3271995392 DEBUG: OnKey: 61453 pressed, action is 7
17:34:47 T:3029157776 M:3271995392 DEBUG: new file set audiostream:0
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerCoreFactory::GetPlayers(/home/chris/Videos/killa.sampla.x264.mkv)
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
17:34:47 T:3029157776 M:3271995392 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
17:34:47 T:3029157776 M:3271229440 NOTICE: DVDPlayer: Opening: /home/chris/Videos/killa.sampla.x264.mkv
17:34:47 T:3029157776 M:3271102464 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
17:34:47 T:2945174384 M:3271102464 DEBUG: thread start, auto delete: 1
17:34:47 T:2936781680 M:3271102464 DEBUG: thread start, auto delete: 0
17:34:47 T:2936781680 M:3271102464 NOTICE: Creating InputStream
17:34:47 T:2936781680 M:3271102464 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
17:34:47 T:2936781680 M:3271102464 DEBUG: CacheSubtitles: START
17:34:47 T:2936781680 M:3271102464 DEBUG: CacheSubtitles: Checking for common subirs...
17:34:47 T:2936781680 M:3271102464 DEBUG: CacheSubtitles: Done (time: 0 ms)
17:34:47 T:2936781680 M:3271102464 DEBUG: CacheSubtitles: Searching for subtitles...
17:34:47 T:2936781680 M:3271102464 DEBUG: CacheSubtitles: Done (time: 6 ms)
17:34:47 T:2936781680 M:3271102464 DEBUG: CacheSubtitles: END (total time: 6 ms)
17:34:47 T:2936781680 M:3271102464 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
17:34:47 T:2936781680 M:3271102464 NOTICE: Creating Demuxer
17:34:47 T:2936781680 M:3271102464 DEBUG: Open - probing detected format [matroska]
17:34:47 T:2936781680 M:3271102464 DEBUG: Open - av_find_stream_info starting
17:34:47 T:2936781680 M:3261071360 DEBUG: Open - av_find_stream_info finished
17:34:47 T:2936781680 M:3261071360 INFO: ffmpeg[AF0BBB70]: Input #0, matroska, from '/home/chris/Videos/killa.sampla.x264.mkv':
17:34:47 T:2936781680 M:3261071360 INFO: ffmpeg[AF0BBB70]: Duration: 00:00:23.04, start: 0.000000, bitrate: N/A
17:34:47 T:2936781680 M:3261071360 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:34:47 T:2936781680 M:3261071360 INFO: ffmpeg[AF0BBB70]: Stream #0.1: Audio: ac3, 48000 Hz, 6 channels, s16
17:34:47 T:2936781680 M:3261071360 INFO: ffmpeg[AF0BBB70]: Metadata
17:34:47 T:2936781680 M:3261071360 INFO: ffmpeg[AF0BBB70]: title : Avidemux
17:34:47 T:2936781680 M:3261071360 NOTICE: Opening video stream: 0 source: 256
17:34:47 T:2936781680 M:3261071360 NOTICE: Creating video codec with codec id: 28
17:34:47 T:2936781680 M:3261071360 DEBUG: FactoryCodec - Video: - Opening
17:34:47 T:2936781680 M:3261071360 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so
17:34:47 T:2936781680 M:3260846080 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080)
17:34:47 T:2936781680 M:3260084224 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000
17:34:47 T:2936781680 M:3260084224 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration)
17:34:47 T:2936781680 M:3260084224 DEBUG: FactoryCodec - Video: ff-h264_vdpau - Opened
17:34:47 T:2936781680 M:3260084224 NOTICE: Creating video thread
17:34:47 T:2936781680 M:3260084224 NOTICE: Opening audio stream: 1 source: 256
17:34:47 T:2936781680 M:3260084224 NOTICE: Finding audio codec for: 86020
17:34:47 T:2928388976 M:3260084224 DEBUG: thread start, auto delete: 0
17:34:47 T:2936781680 M:3260084224 DEBUG: FactoryCodec - Audio: passthrough - Opening
17:34:47 T:2928388976 M:3260084224 DEBUG: LinuxRendererGL: Cleaning up GL resources
17:34:47 T:2928388976 M:3260084224 ERROR: (VDPAU) ReleasePixmap called without valid pixmap
17:34:47 T:2936781680 M:3260084224 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/liba52-i486-linux.so
17:34:47 T:2928388976 M:3260084224 NOTICE: running thread: video_thread
17:34:47 T:2936781680 M:3260084224 DEBUG: FactoryCodec - Audio: passthrough - Opened
17:34:47 T:2936781680 M:3260084224 NOTICE: Creating audio thread
17:34:47 T:2919938928 M:3259957248 DEBUG: thread start, auto delete: 0
17:34:47 T:2919938928 M:3259957248 NOTICE: running thread: CDVDPlayerAudio::Process()
17:34:47 T:2936781680 M:3259957248 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: /home/chris/Videos/killa.sampla.x264.mkv
17:34:47 T:2936781680 M:3259957248 INFO: Loading skin file: DialogProgress.xml
17:34:47 T:2936781680 M:3259957248 DEBUG: Load DialogProgress.xml: 2.14ms
17:34:47 T:2928388976 M:3259957248 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
17:34:47 T:2928388976 M:3259957248 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(83000.000000, 1)
17:34:47 T:3029157776 M:3259957248 DEBUG: OnPlayBackStarted - Playback has started
17:34:47 T:2919938928 M:3259957248 DEBUG: ParseFrame - source flags changed flags:17 sr:48000 br:448000
17:34:47 T:2919938928 M:3259957248 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, pass-through
17:34:47 T:2919938928 M:3259957248 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec AC3 - IsMusic false - IsPassthrough true - audioDevice: hdmi
17:34:47 T:2919938928 M:3259957248 DEBUG: RemoveActiveDevice - Removing device 1
17:34:47 T:2919938928 M:3259957248 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
17:34:47 T:2919938928 M:3259957248 DEBUG: RemoveActiveDevice - Removing device 0
17:34:47 T:2919938928 M:3259957248 DEBUG: Initialize - using alsa device hdmi:AES0=0x6,AES1=0x82,AES2=0x0,AES3=0x2
17:34:47 T:2919938928 M:3259957248 DEBUG: CALSADirectSound::Initialize - packet size:2048, packet count:16, buffer size:8192
17:34:47 T:2919938928 M:3259957248 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
17:34:47 T:2919938928 M:3259957248 DEBUG: CDVDPlayerAudio:: Discontinuty - was:91532.586000, should be:17.158480, error:-91515.427520
17:34:47 T:2919938928 M:3259957248 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 0)
17:34:47 T:2919938928 M:3259957248 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue
17:34:47 T:2928388976 M:3259957248 NOTICE: Creating 1920x1009 pixmap
17:34:47 T:2928388976 M:3259957248 DEBUG: Found 6 fbconfigs.
17:34:47 T:2928388976 M:3259957248 DEBUG: Using fbconfig index 0.
17:34:47 T:2928388976 M:3259957248 INFO: GLX: Creating Pixmap context
17:34:47 T:2945174384 M:3259957248 DEBUG: Thread 2945174384 terminating (autodelete)
17:34:47 T:2928388976 M:3244306432 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 0). Creating a new one. TRY #1
17:34:47 T:3029157776 M:3244306432 DEBUG: CGUIInfoManager::SetCurrentMovie(/home/chris/Videos/killa.sampla.x264.mkv)
17:34:47 T:3029157776 M:3241480192 DEBUG: GetMovieId (/home/chris/Videos/killa.sampla.x264.mkv), query = select idMovie from movie where idFile=51
17:34:47 T:3029157776 M:3241099264 DEBUG: GetEpisodeId (/home/chris/Videos/killa.sampla.x264.mkv), query = select idEpisode from episode where idFile=51
17:34:47 T:3029157776 M:3241050112 DEBUG: GetMusicVideoId (/home/chris/Videos/killa.sampla.x264.mkv), query = select idMVideo from musicvideo where idFile=51
17:34:47 T:2928388976 M:3240034304 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 1). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3235717120 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 2). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3231072256 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 3). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3226755072 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 4). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3222437888 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 5). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3218120704 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 6). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3213770752 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 7). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3209453568 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 8). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3205103616 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 9). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3200786432 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 10). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3196436480 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 11). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3192119296 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 12). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3187769344 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 13). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3183325184 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 14). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3178975232 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 15). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3174658048 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 16). Creating a new one. TRY #1
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 ERROR: ffmpeg[AE8BAB70]: [h264_vdpau] number of reference frames exceeds max (probably corrupt input), discarding one
17:34:47 T:2928388976 M:3170308096 NOTICE: (VDPAU) FiniVDPAUOutput
17:34:47 T:2928388976 M:3170308096 NOTICE: (VDPAU) screenWidth:1920 vidWidth:1920
17:34:47 T:2928388976 M:3170308096 NOTICE: (VDPAU) screenHeight:1009 vidHeight:1080
17:34:47 T:2928388976 M:3084595200 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4)
17:34:47 T:2928388976 M:3084595200 NOTICE: VDPAU Decoder capabilities:
17:34:47 T:2928388976 M:3084595200 NOTICE: name level macbs width height
17:34:47 T:2928388976 M:3084595200 NOTICE: ------------------------------------
17:34:47 T:2928388976 M:3084595200 NOTICE: MPEG1 0 8192 2048 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: MPEG2_MAIN 3 8192 2048 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: H264_MAIN 41 8190 2032 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: H264_HIGH 41 8190 2032 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: VC1_SIMPLE 1 8190 2048 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: VC1_MAIN 2 8190 2048 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: VC1_ADVANCED 4 8190 2048 2048
17:34:47 T:2928388976 M:3084595200 NOTICE: (VDPAU) Creating the video mixer
17:34:47 T:2928388976 M:3070799872 NOTICE: fps: 47.916668, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080
17:34:47 T:2928388976 M:3070799872 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 47.92
17:34:47 T:2928388976 M:3070799872 NOTICE: Display resolution DESKTOP : 1920x1080 @ 50.00 - Full Screen (12)
17:34:47 T:3029157776 M:3070799872 DEBUG: Activating window ID: 12005
17:34:47 T:3029157776 M:3070799872 DEBUG: Checking if window ID 12005 is locked.
17:34:47 T:3029157776 M:3070799872 DEBUG: ------ Window Deinit (MyVideo.xml) ------
17:34:47 T:3029157776 M:3070799872 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
17:34:47 T:3029157776 M:3070799872 INFO: Loading skin file: VideoFullScreen.xml
17:34:47 T:3029157776 M:3070799872 DEBUG: Load VideoFullScreen.xml: 1.03ms
17:34:47 T:3029157776 M:3070799872 DEBUG: Alloc resources: 1.15ms (1.15 ms skin load)
17:34:47 T:3029157776 M:3070799872 INFO: Loading skin file: VideoOSD.xml
17:34:47 T:3029157776 M:3070799872 DEBUG: Load VideoOSD.xml: 4.18ms
17:34:47 T:3029157776 M:3070799872 DEBUG: Alloc resources: 7.58ms (0.00 ms skin load)
17:34:47 T:3029157776 M:3070799872 INFO: Loading skin file: VideoOSDSettings.xml
17:34:47 T:3029157776 M:3070799872 DEBUG: Load VideoOSDSettings.xml: 2.07ms
17:34:47 T:3029157776 M:3070799872 DEBUG: Alloc resources: 1.97ms (0.00 ms skin load)
17:34:47 T:3029157776 M:3070799872 INFO: Loading skin file: VideoOSDSettings.xml
17:34:47 T:3029157776 M:3070799872 DEBUG: Load VideoOSDSettings.xml: 1.93ms
17:34:47 T:3029157776 M:3070799872 DEBUG: Alloc resources: 0.09ms (0.00 ms skin load)
17:34:47 T:3029157776 M:3070799872 NOTICE: Using GL_TEXTURE_2D
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Requested render method: 0
17:34:47 T:3029157776 M:3070799872 NOTICE: GL: Using VDPAU render method
17:34:47 T:3029157776 M:3070799872 NOTICE: GL: NPOT texture support detected
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 1080
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3070799872 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3070672896 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 1080
17:34:47 T:3029157776 M:3070672896 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:34:47 T:3029157776 M:3070672896 DEBUG: GL: Creating YUV NPOT texture of size 960 x 540
17:34:47 T:3029157776 M:3070672896 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:34:47 T:3029157776 M:3070545920 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3070545920 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3070545920 DEBUG: GL: Creating YUV NPOT texture of size 1920 x 540
17:34:47 T:3029157776 M:3070545920 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3070545920 DEBUG: GL: Creating YUV NPOT texture of size 960 x 270
17:34:47 T:3029157776 M:3080323072 NOTICE: Created m_glPixmapTexture (1)
17:34:48 T:2928388976 M:3080273920 NOTICE: (VDPAU) Didnt find a Video Surface Available (Total: 17). Creating a new one. TRY #1
17:34:50 T:3029157776 M:3071258624 DEBUG: SDLKeyboard: scancode: 65, sym: 32, unicode: 32, modifier: 0
17:34:50 T:3029157776 M:3071258624 DEBUG: OnKey: 61472 pressed, action is 12
17:34:50 T:3029157776 M:3071258624 DEBUG: OnPlayBackPaused - Playback was paused
17:34:50 T:3029157776 M:3071258624 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
17:34:50 T:3029157776 M:3071258624 DEBUG: Alloc resources: 0.03ms (0.00 ms skin load)
17:34:51 T:3029157776 M:3071270912 DEBUG: SDLKeyboard: scancode: 64, sym: 308, unicode: 0, modifier: 0