rendered paste body16:08:05 T:139969865979968 M:610910208 NOTICE: -----------------------------------------------------------------------
16:08:05 T:139969865979968 M:610910208 NOTICE: Starting XBMC, Platform: GNU/Linux. Built on Nov 14 2009 (SVN:23041M)
16:08:05 T:139969865979968 M:610910208 NOTICE: special://xbmc/ is mapped to: /usr/local/share/xbmc
16:08:05 T:139969865979968 M:610910208 NOTICE: special://masterprofile/ is mapped to: /home/jmeans/.xbmc/userdata
16:08:05 T:139969865979968 M:610910208 NOTICE: special://home/ is mapped to: /home/jmeans/.xbmc
16:08:05 T:139969865979968 M:610910208 NOTICE: special://temp/ is mapped to: /home/jmeans/.xbmc/temp
16:08:05 T:139969865979968 M:610910208 NOTICE: The executable running is: /usr/local/share/xbmc/xbmc.bin
16:08:05 T:139969865979968 M:610910208 NOTICE: Log File is located: /home/jmeans/.xbmc/temp/xbmc.log
16:08:05 T:139969865979968 M:610910208 NOTICE: -----------------------------------------------------------------------
16:08:05 T:139969865979968 M:610512896 NOTICE: Setup SDL
16:08:05 T:139969865979968 M:609878016 INFO: Available videomodes (xrandr):
16:08:05 T:139969865979968 M:609878016 INFO: Number of connected outputs: 1
16:08:05 T:139969865979968 M:609878016 INFO: Output 'default' has 14 modes
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x146 Name:1280x720 Refresh:50.000000 Width:1280 Height:720
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x147 Name:1280x720 Refresh:51.000000 Width:1280 Height:720
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x148 Name:800x600 Refresh:52.000000 Width:800 Height:600
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x149 Name:720x480 Refresh:53.000000 Width:720 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x14a Name:720x480 Refresh:54.000000 Width:720 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x14b Name:720x480 Refresh:55.000000 Width:720 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x14c Name:640x480 Refresh:56.000000 Width:640 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x14d Name:640x480 Refresh:57.000000 Width:640 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x14e Name:576x480 Refresh:58.000000 Width:576 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x14f Name:411x480 Refresh:59.000000 Width:411 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x150 Name:400x300 Refresh:60.000000 Width:400 Height:300
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x151 Name:360x480 Refresh:61.000000 Width:360 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x152 Name:320x240 Refresh:62.000000 Width:320 Height:240
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: ID:0x153 Name:288x480 Refresh:63.000000 Width:288 Height:480
16:08:05 T:139969865979968 M:609878016 INFO: Pixel Ratio: 1.000000
16:08:05 T:139969865979968 M:609878016 INFO: LIRC Initialize: sucessfully started on: /dev/lircd
16:08:05 T:139969865979968 M:609878016 INFO: Drives are mapped
16:08:05 T:139969865979968 M:609878016 NOTICE: load settings...
16:08:05 T:139969865979968 M:609878016 NOTICE: special://profile/ is mapped to: special://masterprofile/
16:08:05 T:139969865979968 M:609878016 NOTICE: loading special://masterprofile/guisettings.xml
16:08:05 T:139969865979968 M:609497088 NOTICE: Getting hardware information now...
16:08:05 T:139969865979968 M:609497088 INFO: Using digital output
16:08:05 T:139969865979968 M:609497088 INFO: AC3 pass through is enabled
16:08:05 T:139969865979968 M:609497088 INFO: DTS pass through is enabled
16:08:05 T:139969865979968 M:609497088 NOTICE: Checking resolution 12
16:08:05 T:139969865979968 M:609497088 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/rtmp/mms
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: flv/aacp/sdp
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdfile
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
16:08:05 T:139969865979968 M:609497088 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
16:08:05 T:139969865979968 M:609497088 NOTICE: Loaded playercorefactory configuration
16:08:05 T:139969865979968 M:609497088 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
16:08:05 T:139969865979968 M:609497088 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
16:08:05 T:139969865979968 M:609497088 NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml)
16:08:05 T:139969865979968 M:609497088 NOTICE: Default DVD Player: dvdplayer
16:08:05 T:139969865979968 M:609497088 NOTICE: Default Video Player: dvdplayer
16:08:05 T:139969865979968 M:609497088 NOTICE: Default Audio Player: paplayer
16:08:05 T:139969865979968 M:609497088 NOTICE: special://masterprofile/sources.xml
16:08:05 T:139969865979968 M:609497088 INFO: XRANDR: /usr/local/share/xbmc/xbmc-xrandr --output default --mode 0x146
16:08:05 T:139969865979968 M:605257728 NOTICE: Using fbConfig[0]
16:08:05 T:139969865979968 M:604512256 INFO: GL: Maximum texture width: 8192
16:08:05 T:139969865979968 M:604512256 DEBUG: SECTION:LoadDLL(special://xbmc/system/ImageLib-x86_64-linux.so)
16:08:05 T:139969865979968 M:604512256 DEBUG: Loading: /usr/local/share/xbmc/system/ImageLib-x86_64-linux.so
16:08:05 T:139969865979968 M:603607040 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_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_EXT_framebuffer_sRGB GLX_ARB_get_proc_address
16:08:05 T:139969865979968 M:603414528 NOTICE: GL_VENDOR = NVIDIA Corporation
16:08:05 T:139969865979968 M:603414528 NOTICE: GL_RENDERER = ION LE/PCI/SSE2
16:08:05 T:139969865979968 M:603414528 NOTICE: GL_VERSION = 3.0.0 NVIDIA 185.18.36
16:08:05 T:139969865979968 M:603414528 NOTICE: GL_EXTENSIONS = GL_ARB_color_buffer_float GL_ARB_depth_buffer_float GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_instanced GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_framebuffer_object GL_ARB_geometry_shader4 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_shadow GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_compression GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirrored_repeat GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_transpose_matrix 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_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_bindable_uniform 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_EXT_framebuffer_object GL_EXTX_framebuffer_mixed_formats 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_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_sRGB GL_EXT_texture_swizzle GL_EXT_texture_shared_exponent GL_EXT_timer_query 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_copy_depth_to_color GL_NV_depth_buffer_float GL_NV_conditional_render 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_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_texgen_reflection 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_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NV_vertex_buffer_unified_memory GL_NV_shader_buffer_load GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
16:08:05 T:139969865979968 M:603414528 ERROR: Error: Requested setting (videoscreen.displayblanking) was not found. It must be case-sensitive
16:08:05 T:139969865979968 M:603004928 ERROR: GLX: Same window as before, refreshing context
16:08:05 T:139969865979968 M:603004928 INFO: GL: Maximum texture width: 8192
16:08:05 T:139969865979968 M:603004928 INFO: load language info file: special://xbmc/language/English/langinfo.xml
16:08:05 T:139969865979968 M:601575424 INFO: load language file:special://xbmc/language/English/strings.xml
16:08:05 T:139969865979968 M:601194496 INFO: load keymapping
16:08:05 T:139969865979968 M:601194496 DEBUG: Sort, sorting took 0 millis
16:08:05 T:139969865979968 M:601194496 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
16:08:05 T:139969865979968 M:601194496 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
16:08:05 T:139969865979968 M:601194496 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
16:08:05 T:139969865979968 M:601194496 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
16:08:05 T:139969865979968 M:601067520 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
16:08:05 T:139969865979968 M:601067520 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
16:08:05 T:139969865979968 M:601067520 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
16:08:05 T:139969865979968 M:601067520 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
16:08:05 T:139969865979968 M:601194496 INFO: Loading special://xbmc/system/keymaps/remote.xml
16:08:06 T:139969865979968 M:601194496 DEBUG: Sort, sorting took 0 millis
16:08:06 T:139969865979968 M:601194496 INFO: Loading special://xbmc/system/Lircmap.xml
16:08:06 T:139969865979968 M:601194496 INFO: Loading special://profile/Lircmap.xml
16:08:06 T:139969865979968 M:601194496 INFO: Checking skin version of: PM3.HD
16:08:06 T:139969865979968 M:601194496 INFO: Skin version is: 2.11 (2.110000)
16:08:06 T:139969865979968 M:601194496 INFO: GUI format 1280x720 720p50 (Full Screen)
16:08:06 T:139969865979968 M:601194496 INFO: creating subdirectories
16:08:06 T:139969865979968 M:601194496 INFO: userdata folder: special://masterprofile/
16:08:06 T:139969865979968 M:601194496 INFO: recording folder:
16:08:06 T:139969865979968 M:601194496 INFO: screenshots folder:
16:08:06 T:139969865979968 M:601194496 INFO: thumbnails folder: special://masterprofile/Thumbnails
16:08:06 T:139969865979968 M:601194496 NOTICE: start dvd mediatype detection
16:08:06 T:139969865979968 M:601194496 NOTICE: initializing playlistplayer
16:08:06 T:139969615898896 M:601194496 DEBUG: thread start, auto delete: 0
16:08:06 T:139969865979968 M:601194496 NOTICE: DONE initializing playlistplayer
16:08:06 T:139969615898896 M:601194496 DEBUG: Compiled with libcdio Version 0.78
16:08:06 T:139969865979968 M:601194496 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
16:08:06 T:139969615898896 M:601194496 DEBUG: Thread 139969615898896 terminating
16:08:06 T:139969865979968 M:601194496 NOTICE: load default skin:[PM3.HD]
16:08:06 T:139969865979968 M:601194496 INFO: load skin from:special://xbmc/skin/PM3.HD
16:08:06 T:139969865979968 M:601194496 INFO: delete old skin...
16:08:06 T:139969865979968 M:601194496 DEBUG: ------ Window Deinit (Pointer.xml) ------
16:08:06 T:139969865979968 M:601194496 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
16:08:06 T:139969865979968 M:601194496 INFO: Default 4:3 resolution directory is special://xbmc/skin/PM3.HD/720p
16:08:06 T:139969865979968 M:601194496 INFO: Default 16:9 resolution directory is special://xbmc/skin/PM3.HD/720p
16:08:06 T:139969865979968 M:601194496 INFO: Skin version is: 2.11
16:08:06 T:139969865979968 M:601194496 INFO: Loading skin includes from /usr/local/share/xbmc/skin/PM3.HD/720p/includes.xml
16:08:06 T:139969865979968 M:599322624 INFO: load fonts for skin...
16:08:06 T:139969865979968 M:599322624 INFO: Loading fonts from special://xbmc/skin/PM3.HD/720p/Font.xml
16:08:06 T:139969865979968 M:599195648 INFO: load new skin...
16:08:06 T:139969865979968 M:599195648 INFO: Skin version is: 2.11 (2.110000)
16:08:06 T:139969865979968 M:599195648 INFO: Loading skin file: Home.xml
16:08:06 T:139969597606160 M:599068672 DEBUG: thread start, auto delete: 1
16:08:06 T:139969597606160 M:599068672 INFO: WEATHER: Downloading weather
16:08:06 T:139969597606160 M:599068672 DEBUG: SECTION:LoadDLL(/usr/lib64/libcurl.so.4)
16:08:06 T:139969597606160 M:599068672 DEBUG: Loading: /usr/lib64/libcurl.so.4
16:08:06 T:139969597606160 M:599068672 DEBUG: FileCurl::Open(0x7f4d3624ed40) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
16:08:06 T:139969597606160 M:599068672 INFO: easy_aquire - Created session to http://xoap.weather.com
16:08:06 T:139969597606160 M:593297408 DEBUG: FileCurl::Close(0x7f4d3624ed40) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
16:08:06 T:139969597606160 M:593297408 INFO: WEATHER: Weather download successful
16:08:06 T:139969865979968 M:594059264 DEBUG: Load Home.xml: 543.70ms
16:08:06 T:139969865979968 M:594059264 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
16:08:06 T:139969865979968 M:594059264 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
16:08:06 T:139969865979968 M:593932288 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
16:08:06 T:139969865979968 M:593932288 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
16:08:06 T:139969865979968 M:593932288 DEBUG: Load Skin XML: 625.10ms
16:08:06 T:139969865979968 M:593932288 INFO: initialize new skin...
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: Pointer.xml
16:08:06 T:139969865979968 M:593932288 DEBUG: Load Pointer.xml: 3.17ms
16:08:06 T:139969865979968 M:593932288 DEBUG: Alloc resources: 8.30ms (3.61 ms skin load)
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: DialogVolumeBar.xml
16:08:06 T:139969865979968 M:593932288 DEBUG: Load DialogVolumeBar.xml: 4.98ms
16:08:06 T:139969865979968 M:593932288 DEBUG: Alloc resources: 5.55ms (5.32 ms skin load)
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: DialogSeekBar.xml
16:08:06 T:139969865979968 M:593932288 DEBUG: Load DialogSeekBar.xml: 15.08ms
16:08:06 T:139969865979968 M:593932288 DEBUG: Alloc resources: 16.05ms (15.63 ms skin load)
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: DialogKaiToast.xml
16:08:06 T:139969865979968 M:593932288 DEBUG: Load DialogKaiToast.xml: 10.69ms
16:08:06 T:139969865979968 M:593932288 DEBUG: Alloc resources: 11.02ms (11.02 ms skin load)
16:08:06 T:139969865979968 M:593932288 INFO: Loading skin file: DialogMuteBug.xml
16:08:06 T:139969865979968 M:593932288 DEBUG: Load DialogMuteBug.xml: 3.49ms
16:08:06 T:139969865979968 M:593932288 DEBUG: Alloc resources: 3.92ms (3.91 ms skin load)
16:08:06 T:139969865979968 M:593932288 DEBUG: CGUIAudioManager::Initialize
16:08:06 T:139969865979968 M:593932288 INFO: Loading special://xbmc/skin/PM3.HD/sounds/sounds.xml
16:08:06 T:139969865979968 M:593932288 INFO: skin loaded...
16:08:06 T:139969865979968 M:593797120 DEBUG: Activating window ID: 12999
16:08:06 T:139969865979968 M:593797120 DEBUG: Checking if window ID 12999 is locked.
16:08:06 T:139969865979968 M:593797120 DEBUG: ------ Window Init (Startup.xml) ------
16:08:06 T:139969865979968 M:593797120 INFO: Loading skin file: Startup.xml
16:08:06 T:139969865979968 M:593797120 DEBUG: Load Startup.xml: 3.04ms
16:08:06 T:139969865979968 M:593797120 DEBUG: Alloc resources: 3.46ms (3.45 ms skin load)
16:08:06 T:139969572350224 M:593797120 DEBUG: thread start, auto delete: 1
16:08:06 T:139969572350224 M:593797120 INFO: easy_aquire - Created session to http://www.google.com
16:08:06 T:139969865979968 M:593018880 INFO: removing tempfiles
16:08:06 T:139969865979968 M:592891904 INFO: HAL: Starting initializing
16:08:06 T:139969865979968 M:593383424 DEBUG: HAL: Clearing old global device list, if any
16:08:06 T:139969865979968 M:593383424 NOTICE: HAL: Generating global device list
16:08:06 T:139969865979968 M:592240640 DEBUG: HAL: Added - disk | HotPlugged NO | Type 1 |Approved NO
16:08:06 T:139969865979968 M:592240640 NOTICE: LFS: Added - disk | Volume
16:08:06 T:139969865979968 M:592240640 DEBUG: HAL: Added - disk | UUID dc96e300-0d0f-4e4e-b33a-340696629af4 | FileSystem swap | HotPlugged NO | Type 1 |Approved NO
16:08:06 T:139969865979968 M:592875520 NOTICE: LFS: Added - disk | Volume (swap)
16:08:06 T:139969865979968 M:595795968 DEBUG: HAL: Added - disk | UUID 64756280-0378-4d54-a043-5ba33658e68c | FileSystem ext4 | Mounted on / | HotPlugged NO | Type 1 |Approved NO
16:08:06 T:139969865979968 M:595795968 NOTICE: LFS: Added - disk | Volume (ext4)
16:08:06 T:139969865979968 M:595795968 DEBUG: HAL: Added - disk | UUID 60A64587A6455F22 | FileSystem ntfs-3g | HotPlugged NO | Type 1 |Approved YES
16:08:06 T:139969865979968 M:595795968 NOTICE: LFS: Added - disk | ACER
16:08:06 T:139969597606160 M:595542016 DEBUG: Thread 139969597606160 terminating (autodelete)
16:08:07 T:139969865979968 M:595034112 DEBUG: HAL: Added - disk | UUID 063A47C23A47AD8D | FileSystem ntfs-3g | HotPlugged NO | Type 1 |Approved YES
16:08:07 T:139969865979968 M:595034112 NOTICE: LFS: Added - disk | DATA
16:08:07 T:139969865979968 M:594907136 DEBUG: HAL: Added - disk | UUID 307835637835294C | FileSystem ntfs-3g | HotPlugged NO | Type 1 |Approved YES
16:08:07 T:139969865979968 M:594907136 NOTICE: LFS: Added - disk | PQSERVICE
16:08:07 T:139969865979968 M:597159936 INFO: HAL: Generated global device list, found 82
16:08:07 T:139969865979968 M:597159936 INFO: HAL: Sucessfully initialized
16:08:07 T:139969865979968 M:597159936 NOTICE: initialize done
16:08:07 T:139969865979968 M:597159936 NOTICE: Running the application...
16:08:07 T:139969865979968 M:597159936 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
16:08:07 T:139969865979968 M:597159936 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
16:08:07 T:139969865979968 M:597159936 DEBUG: Activating window ID: 10000
16:08:07 T:139969865979968 M:597159936 DEBUG: Checking if window ID 10000 is locked.
16:08:07 T:139969865979968 M:597159936 DEBUG: ------ Window Deinit (Startup.xml) ------
16:08:07 T:139969865979968 M:597159936 DEBUG: ------ Window Init (Home.xml) ------
16:08:07 T:139969865979968 M:597159936 DEBUG: Alloc resources: 1.73ms (0.00 ms skin load)
16:08:07 T:139969865979968 M:597159936 ERROR: Python script "special://home/scripts/autoexec.py" does not exist
16:08:07 T:139969865979968 M:597159936 INFO: initializing python engine.
16:08:07 T:139969865979968 M:597159936 DEBUG: Loading: /usr/local/share/xbmc/system/python/python24-x86_64-linux.so
16:08:07 T:139969865979968 M:597159936 DEBUG: Python wrapper library linked with internal Python library
16:08:07 T:139969572350224 M:597073920 DEBUG: FileCurl::Close(0x7f4d34a38cc0) http://www.google.com/
16:08:07 T:139969572350224 M:597073920 DEBUG: Thread 139969572350224 terminating (autodelete)
16:08:07 T:139969865979968 M:596439040 DEBUG: GetZipList - Processing zip://%2fusr%2flocal%2fshare%2fxbmc%2fsystem%2fpython%2fpython24%2ezip/sitecustomize
16:08:07 T:139969865979968 M:593653760 DEBUG: new python thread created. id=1
16:08:07 T:139969865979968 M:593653760 DEBUG: NetworkMessage - Starting network services
16:08:07 T:139969865979968 M:593653760 NOTICE: ES: Starting event server
16:08:07 T:139969597606160 M:593653760 DEBUG: thread start, auto delete: 0
16:08:07 T:139969597606160 M:593653760 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
16:08:07 T:139969865979968 M:593653760 NOTICE: DS: Starting dbus server
16:08:07 T:139969597606160 M:593510400 NOTICE: UDP: Listening on port 9777
16:08:07 T:139969572350224 M:593510400 DEBUG: thread start, auto delete: 0
16:08:07 T:139969572350224 M:593510400 DEBUG: Python thread: start processing
16:08:07 T:139969494513936 M:593383424 DEBUG: thread start, auto delete: 0
16:08:07 T:139969494513936 M:593383424 NOTICE: DS: Starting DBUS server in Run Application aka thread
16:08:07 T:139969865979968 M:593383424 NOTICE: starting zeroconf publishing
16:08:07 T:139969572350224 M:593256448 NOTICE: -->Python Interpreter Initialized<--
16:08:07 T:139969572350224 M:593256448 DEBUG: Process - The source file to load is special://xbmc/scripts/autoexec.py
16:08:07 T:139969572350224 M:593256448 DEBUG: Process - Setting the Python path to /usr/local/share/xbmc/scripts:/usr/local/share/xbmc/system/python/python24.zip:/usr/local/share/xbmc/system/python/lib/python24.zip:/usr/local/share/xbmc/system/python/lib/python2.4/:/usr/local/share/xbmc/system/python/lib/python2.4/plat-linux2:/usr/local/share/xbmc/system/python/lib/python2.4/lib-tk:/usr/local/share/xbmc/system/python/lib/python2.4/lib-dynload
16:08:07 T:139969572350224 M:593256448 DEBUG: Process - Entering source directory /usr/local/share/xbmc/scripts
16:08:07 T:139969572350224 M:593256448 INFO: Scriptresult: Success
16:08:07 T:139969597606160 M:593256448 INFO: CZeroconfAvahi::clientCallback: avahi server not available. But may become later...
16:08:07 T:139969865979968 M:593256448 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
16:08:07 T:139969865979968 M:593256448 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
16:08:07 T:139969865979968 M:593256448 DEBUG: CLastfmScrobbler: Clearing session.
16:08:07 T:139969865979968 M:593256448 DEBUG: CLibrefmScrobbler: Clearing session.
16:08:07 T:139969477728528 M:593256448 DEBUG: thread start, auto delete: 0
16:08:08 T:139969572350224 M:574570496 DEBUG: xbp_dlopen loading python lib /usr/local/share/xbmc/system/python/python24.zip/time.so. flags: 2
16:08:08 T:139969572350224 M:574570496 DEBUG: Loading: /home/jmeans/.xbmc/temp/time.so
16:08:08 T:139969572350224 M:574443520 DEBUG: RegisterExtensionLib, adding time.so (0x7f4d301f8d90)
16:08:08 T:139969572350224 M:574443520 DEBUG: xbp_dlsym - load symbol inittime
16:08:08 T:139969572350224 M:574316544 DEBUG: xbp_dlopen loading python lib /usr/local/share/xbmc/system/python/python24.zip/collections.so. flags: 2
16:08:08 T:139969572350224 M:574316544 DEBUG: Loading: /home/jmeans/.xbmc/temp/collections.so
16:08:08 T:139969572350224 M:574316544 DEBUG: RegisterExtensionLib, adding collections.so (0x7f4d302062c0)
16:08:08 T:139969572350224 M:574316544 DEBUG: xbp_dlsym - load symbol initcollections
16:08:08 T:139969572350224 M:574316544 INFO: Python script stopped
16:08:08 T:139969572350224 M:574316544 DEBUG: Thread 139969572350224 terminating
16:08:08 T:139969601366288 M:579514368 DEBUG: thread start, auto delete: 0
16:08:08 T:139969865979968 M:580640768 DEBUG: python thread 1 destructed
16:08:08 T:139969865979968 M:580640768 INFO: Python, unloading python24.dll because no scripts are running anymore
16:08:08 T:139969601366288 M:580640768 DEBUG: FileCurl::Open(0x7f4d365e4d50) http://feeds.feedburner.com/xbmc
16:08:08 T:139969601366288 M:580640768 INFO: easy_aquire - Created session to http://feeds.feedburner.com
16:08:08 T:139969865979968 M:580640768 DEBUG: UnloadExtensionLibs, clearing python extension libraries
16:08:08 T:139969865979968 M:580640768 DEBUG: Unloading: time.so
16:08:08 T:139969865979968 M:580640768 DEBUG: Unloading: collections.so
16:08:08 T:139969865979968 M:580640768 DEBUG: Unloading: python24-x86_64-linux.so
16:08:08 T:139969601366288 M:579371008 DEBUG: FileCurl::Close(0x7f4d365e4d50) http://feeds.feedburner.com/xbmc
16:08:08 T:139969601366288 M:579371008 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc
16:08:08 T:139969601366288 M:579371008 DEBUG: RSS feed encoding: ISO-8859-1
16:08:08 T:139969601366288 M:579371008 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc
16:08:09 T:139969601366288 M:579297280 DEBUG: Thread 139969601366288 terminating
16:08:13 T:139969477728528 M:579186688 DEBUG: Thread 139969477728528 terminating
16:08:37 T:139969865979968 M:579346432 INFO: CheckIdle - Closing session to http://xoap.weather.com (easy=0x2e3a6a0, multi=0x2b98fe0)
16:08:37 T:139969865979968 M:579219456 INFO: CheckIdle - Closing session to http://www.google.com (easy=0x2e8c420, multi=(nil))
16:08:38 T:139969865979968 M:579219456 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
16:08:38 T:139969865979968 M:579219456 DEBUG: OnKey: 61453 pressed, action is 7
16:08:38 T:139969865979968 M:579219456 DEBUG: Load home-button-focus.gif: 5.6ms (bundled)
16:08:39 T:139969865979968 M:579219456 INFO: CheckIdle - Closing session to http://feeds.feedburner.com (easy=0x2e650f0, multi=0x2ee0da0)
16:08:39 T:139969865979968 M:579219456 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
16:08:39 T:139969865979968 M:579219456 DEBUG: OnKey: 61453 pressed, action is 7
16:08:39 T:139969865979968 M:579219456 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Videos)
16:08:39 T:139969865979968 M:579219456 DEBUG: ExecuteXBMCAction : To ActivateWindow(Videos)
16:08:39 T:139969865979968 M:579219456 DEBUG: Activating window ID: 10025
16:08:39 T:139969865979968 M:579219456 DEBUG: Checking if window ID 10024 is locked.
16:08:39 T:139969865979968 M:579219456 DEBUG: ------ Window Deinit (Home.xml) ------
16:08:39 T:139969865979968 M:579219456 INFO: Attempting to default to:
16:08:39 T:139969865979968 M:579219456 DEBUG: ------ Window Init (MyVideoNav.xml) ------
16:08:39 T:139969865979968 M:579219456 INFO: Loading skin file: MyVideoNav.xml
16:08:39 T:139969865979968 M:579219456 DEBUG: Load MyVideoNav.xml: 152.31ms
16:08:39 T:139969865979968 M:579219456 DEBUG: Alloc resources: 161.33ms (156.47 ms skin load)
16:08:39 T:139969865979968 M:579219456 DEBUG: CGUIMediaWindow::GetDirectory ()
16:08:39 T:139969865979968 M:579219456 DEBUG: ParentPath = []
16:08:39 T:139969572350224 M:579219456 DEBUG: thread start, auto delete: 0
16:08:39 T:139969572350224 M:578449408 DEBUG: Thread 139969572350224 terminating
16:08:41 T:139969865979968 M:571854848 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
16:08:41 T:139969865979968 M:571854848 DEBUG: OnKey: 61453 pressed, action is 7
16:08:41 T:139969865979968 M:571854848 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/)
16:08:41 T:139969865979968 M:571854848 DEBUG: ParentPath = []
16:08:41 T:139969865979968 M:571854848 DEBUG: Saving fileitems [videodb://1/]
16:08:41 T:139969865979968 M:571854848 DEBUG: -- items: 7, sort method: 0, ascending: false
16:08:41 T:139969572350224 M:571854848 DEBUG: thread start, auto delete: 0
16:08:41 T:139969469335824 M:571854848 DEBUG: thread start, auto delete: 0
16:08:41 T:139969469335824 M:571854848 DEBUG: Thread 139969469335824 terminating
16:08:41 T:139969572350224 M:571854848 DEBUG: Thread 139969572350224 terminating
16:08:42 T:139969865979968 M:571858944 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
16:08:42 T:139969865979968 M:571858944 DEBUG: OnKey: 61480 pressed, action is 4
16:08:42 T:139969865979968 M:571858944 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
16:08:42 T:139969865979968 M:571858944 DEBUG: OnKey: 61480 pressed, action is 4
16:08:43 T:139969865979968 M:571858944 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
16:08:43 T:139969865979968 M:571858944 DEBUG: OnKey: 61453 pressed, action is 7
16:08:43 T:139969865979968 M:571858944 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
16:08:43 T:139969865979968 M:571858944 DEBUG: ParentPath = [videodb://1/]
16:08:43 T:139969865979968 M:571858944 DEBUG: GetMoviesByWhere query: select * from movieview
16:08:43 T:139969865979968 M:571858944 DEBUG: Time for actual SQL query = 1
16:08:43 T:139969865979968 M:571858944 DEBUG: Time to retrieve movies from dataset = 1
16:08:43 T:139969865979968 M:571858944 DEBUG: Sort, sorting took 0 millis
16:08:43 T:139969865979968 M:571858944 DEBUG: Saving fileitems [videodb://1/2/]
16:08:43 T:139969865979968 M:571858944 DEBUG: -- items: 2, sort method: 23, ascending: true
16:08:43 T:139969469335824 M:571858944 DEBUG: thread start, auto delete: 0
16:08:43 T:139969477728528 M:571858944 DEBUG: thread start, auto delete: 0
16:08:43 T:139969469335824 M:571842560 DEBUG: Thread 139969469335824 terminating
16:08:44 T:139969865979968 M:571731968 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
16:08:44 T:139969865979968 M:571731968 DEBUG: OnKey: 61480 pressed, action is 4
16:08:45 T:139969865979968 M:565510144 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
16:08:45 T:139969865979968 M:565510144 DEBUG: OnKey: 61453 pressed, action is 7
16:08:45 T:139969865979968 M:565510144 INFO: Loading skin file: DialogContextMenu.xml
16:08:45 T:139969865979968 M:565510144 DEBUG: Load DialogContextMenu.xml: 3.39ms
16:08:45 T:139969865979968 M:565383168 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
16:08:45 T:139969865979968 M:565383168 DEBUG: Alloc resources: 0.32ms (0.00 ms skin load)
16:08:47 T:139969865979968 M:569700352 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0
16:08:47 T:139969865979968 M:569700352 DEBUG: OnKey: 61480 pressed, action is 4
16:08:47 T:139969865979968 M:569573376 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
16:08:47 T:139969865979968 M:569573376 DEBUG: OnKey: 61453 pressed, action is 7
16:08:47 T:139969865979968 M:569573376 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
16:08:47 T:139969865979968 M:569573376 DEBUG: new file set audiostream:0
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/videos/Movies/39_STEPS/39_STEPS.mkv)
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
16:08:47 T:139969865979968 M:569573376 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
16:08:47 T:139969865979968 M:569573376 NOTICE: DVDPlayer: Opening: /var/media/videos/Movies/39_STEPS/39_STEPS.mkv
16:08:47 T:139969572350224 M:569573376 DEBUG: thread start, auto delete: 1
16:08:47 T:139969865979968 M:569573376 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
16:08:47 T:139969460943120 M:569573376 DEBUG: thread start, auto delete: 0
16:08:47 T:139969460943120 M:569573376 NOTICE: Creating InputStream
16:08:47 T:139969460943120 M:569446400 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
16:08:47 T:139969460943120 M:569446400 DEBUG: CacheSubtitles: START
16:08:47 T:139969460943120 M:569446400 DEBUG: CacheSubtitles: Checking for common subirs...
16:08:47 T:139969460943120 M:569446400 DEBUG: CacheSubtitles: Done (time: 46 ms)
16:08:47 T:139969460943120 M:569446400 DEBUG: CacheSubtitles: Searching for subtitles...
16:08:47 T:139969460943120 M:569446400 INFO: cached subtitle 39_STEPS.chaps.txt->special://temp/subtitle.chaps.txt
16:08:47 T:139969460943120 M:569446400 DEBUG: CacheSubtitles: Done (time: 15 ms)
16:08:47 T:139969460943120 M:569446400 DEBUG: CacheSubtitles: END (total time: 61 ms)
16:08:47 T:139969460943120 M:569446400 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
16:08:47 T:139969460943120 M:569446400 NOTICE: Creating Demuxer
16:08:47 T:139969460943120 M:569446400 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/avutil-50-x86_64-linux.so)
16:08:47 T:139969460943120 M:569446400 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/avutil-50-x86_64-linux.so
16:08:47 T:139969460943120 M:569446400 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/avcodec-52-x86_64-linux.so)
16:08:47 T:139969460943120 M:569446400 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/avcodec-52-x86_64-linux.so
16:08:47 T:139969460943120 M:569303040 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/avformat-52-x86_64-linux.so)
16:08:47 T:139969460943120 M:569303040 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/avformat-52-x86_64-linux.so
16:08:47 T:139969460943120 M:569303040 DEBUG: Open - probing detected format [matroska]
16:08:47 T:139969460943120 M:569303040 DEBUG: Open - av_find_stream_info starting
16:08:47 T:139969460943120 M:569303040 DEBUG: Open - av_find_stream_info finished
16:08:47 T:139969460943120 M:569303040 INFO: ffmpeg[2DFFB910]: Input #0, matroska, from '/var/media/videos/Movies/39_STEPS/39_STEPS.mkv':
16:08:47 T:139969460943120 M:569303040 INFO: ffmpeg[2DFFB910]: Duration: 01:22:36.11, start: 0.000000, bitrate: 3001 kb/s
16:08:47 T:139969460943120 M:569303040 INFO: ffmpeg[2DFFB910]: Stream #0.0(eng): Video: mpeg2video, yuv420p, 720x480 [PAR 8:9 DAR 4:3], 3001 kb/s, 29.97 tbr, 1k tbn, 59.94 tbc
16:08:47 T:139969460943120 M:569303040 INFO: ffmpeg[2DFFB910]: Stream #0.1(eng): Audio: ac3, 48000 Hz, 2 channels, s16
16:08:47 T:139969460943120 M:569303040 NOTICE: Opening video stream: 0 source: 256
16:08:47 T:139969460943120 M:569303040 NOTICE: Creating video codec with codec id: 2
16:08:47 T:139969460943120 M:569303040 DEBUG: FactoryCodec - Video: libmpeg2 - Opening
16:08:47 T:139969460943120 M:569303040 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/libmpeg2-x86_64-linux.so)
16:08:47 T:139969460943120 M:569303040 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/libmpeg2-x86_64-linux.so
16:08:47 T:139969460943120 M:569303040 DEBUG: FactoryCodec - Video: libmpeg2 - Opened
16:08:47 T:139969460943120 M:569303040 NOTICE: Creating video thread
16:08:47 T:139969460943120 M:569303040 NOTICE: Opening audio stream: 1 source: 256
16:08:47 T:139969435212048 M:569303040 DEBUG: thread start, auto delete: 0
16:08:47 T:139969460943120 M:569303040 NOTICE: Finding audio codec for: 86020
16:08:47 T:139969460943120 M:569303040 DEBUG: FactoryCodec - Audio: passthrough - Opening
16:08:47 T:139969460943120 M:569303040 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/liba52-x86_64-linux.so)
16:08:47 T:139969460943120 M:569303040 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/liba52-x86_64-linux.so
16:08:47 T:139969435212048 M:569303040 DEBUG: LinuxRendererGL: Cleaning up GL resources
16:08:47 T:139969435212048 M:569303040 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/swscale-0.6.1-x86_64-linux.so)
16:08:47 T:139969435212048 M:569303040 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/swscale-0.6.1-x86_64-linux.so
16:08:47 T:139969435212048 M:569303040 NOTICE: running thread: video_thread
16:08:47 T:139969460943120 M:569303040 DEBUG: FactoryCodec - Audio: passthrough - Opened
16:08:47 T:139969460943120 M:569303040 NOTICE: Creating audio thread
16:08:47 T:139969422342416 M:569303040 DEBUG: thread start, auto delete: 0
16:08:47 T:139969422342416 M:569303040 NOTICE: running thread: CDVDPlayerAudio::Process()
16:08:47 T:139969460943120 M:569303040 NOTICE: Opening Subtitle stream: 0 source: 1024
16:08:47 T:139969460943120 M:569303040 ERROR: OpenStream - Unable to create subtitle parser
16:08:47 T:139969460943120 M:569303040 WARNING: OpenSubtitleStream - Unsupported stream 0. Stream disabled.
16:08:47 T:139969460943120 M:569303040 INFO: Loading skin file: DialogProgress.xml
16:08:47 T:139969460943120 M:569303040 DEBUG: Load DialogProgress.xml: 4.78ms
16:08:47 T:139969572350224 M:569303040 DEBUG: Thread 139969572350224 terminating (autodelete)
16:08:47 T:139969865979968 M:569303040 DEBUG: CGUIAudioManager::DeInitialize
16:08:47 T:139969422342416 M:569303040 DEBUG: ParseFrame - source flags changed flags:2 sr:48000 br:192000
16:08:47 T:139969435212048 M:569303040 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
16:08:47 T:139969435212048 M:569303040 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 0)
16:08:47 T:139969422342416 M:569303040 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, pass-through
16:08:47 T:139969422342416 M:569303040 DEBUG: PulseAudio: Opening Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec AC3 - IsMusic false - IsPassthrough true - audioHost: default - audioDevice: default
16:08:47 T:139969435212048 M:569303040 NOTICE: fps: 29.970030, pwidth: 720, pheight: 480, dwidth: 640, dheight: 480
16:08:47 T:139969435212048 M:569303040 DEBUG: OutputPicture - change configuration. 720x480. framerate: 29.97
16:08:47 T:139969435212048 M:569303040 NOTICE: Display resolution DESKTOP : 720p50 (Full Screen) (12)
16:08:47 T:139969435212048 M:569303040 WARNING: Upscale: possible given resolution increase.
16:08:47 T:139969435212048 M:569303040 WARNING: Upscale: selected algorithm 10
16:08:47 T:139969865979968 M:568905728 DEBUG: OnPlayBackStarted - Playback has started
16:08:47 T:139969422342416 M:568905728 DEBUG: CGUIAudioManager::DeInitialize
16:08:47 T:139969422342416 M:568905728 ERROR: PulseAudio: Passthrough not possible
16:08:47 T:139969422342416 M:568905728 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec AC3 - IsMusic false - IsPassthrough true - audioDevice: default
16:08:47 T:139969422342416 M:568905728 DEBUG: Initialize - using alsa device default:AES0=0x6,AES1=0x82,AES2=0x0,AES3=0x2
16:08:47 T:139969422342416 M:568905728 ERROR: Initialize - failed to open custom device default:AES0=0x6,AES1=0x82,AES2=0x0,AES3=0x2, retry with default default
16:08:47 T:139969422342416 M:568905728 DEBUG: CALSADirectSound::Initialize - packet size:2048, packet count:16, buffer size:8192
16:08:47 T:139969422342416 M:568778752 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
16:08:47 T:139969422342416 M:568651776 DEBUG: CDVDPlayerAudio:: Discontinuty - was:90169.019000, should be:18.574480, error:-90150.444520
16:08:47 T:139969422342416 M:568651776 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1)
16:08:47 T:139969865979968 M:568651776 DEBUG: CGUIInfoManager::SetCurrentMovie(/var/media/videos/Movies/39_STEPS/39_STEPS.mkv)
16:08:47 T:139969865979968 M:568651776 DEBUG: Activating window ID: 12005
16:08:47 T:139969865979968 M:568651776 DEBUG: Checking if window ID 12005 is locked.
16:08:47 T:139969865979968 M:568651776 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
16:08:47 T:139969865979968 M:568651776 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
16:08:47 T:139969865979968 M:568651776 INFO: Loading skin file: VideoFullScreen.xml
16:08:47 T:139969865979968 M:568651776 DEBUG: Load VideoFullScreen.xml: 4.08ms
16:08:47 T:139969865979968 M:568651776 DEBUG: Alloc resources: 4.51ms (4.50 ms skin load)
16:08:47 T:139969865979968 M:568651776 INFO: Loading skin file: VideoOSD.xml
16:08:48 T:139969865979968 M:568754176 DEBUG: Load VideoOSD.xml: 23.55ms
16:08:48 T:139969865979968 M:567103488 DEBUG: Alloc resources: 17.65ms (0.00 ms skin load)
16:08:48 T:139969865979968 M:567103488 INFO: Loading skin file: VideoOSDSettings.xml
16:08:48 T:139969865979968 M:566992896 DEBUG: Load VideoOSDSettings.xml: 7.49ms
16:08:48 T:139969865979968 M:563691520 DEBUG: Alloc resources: 12.00ms (0.00 ms skin load)
16:08:48 T:139969865979968 M:563691520 INFO: Loading skin file: VideoOSDSettings.xml
16:08:48 T:139969865979968 M:563564544 DEBUG: Load VideoOSDSettings.xml: 9.53ms
16:08:48 T:139969865979968 M:563564544 DEBUG: Alloc resources: 0.63ms (0.00 ms skin load)
16:08:48 T:139969865979968 M:562442240 NOTICE: Using GL_TEXTURE_2D
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Requested render method: 4
16:08:48 T:139969865979968 M:562442240 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Shader compilation log:
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Shader compilation log:
16:08:48 T:139969865979968 M:562442240 NOTICE: GL: NPOT texture support detected
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 963 x 684
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 481 x 342
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 481 x 342
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 963 x 342
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 963 x 342
16:08:48 T:139969865979968 M:562442240 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 963 x 684
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 481 x 342
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 481 x 342
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 963 x 342
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 963 x 342
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:562315264 DEBUG: GL: Creating YUV NPOT texture of size 481 x 171
16:08:48 T:139969865979968 M:561807360 ERROR: ffmpeg[46241840]: swScaler: Exactly one scaler algorithm must be chosen