rendered paste body19:42:34 T:139658049763392 M:1066209280 NOTICE: -----------------------------------------------------------------------
19:42:34 T:139658049763392 M:1066209280 NOTICE: Starting XBMC, Platform: GNU/Linux. Built on Dec 17 2009 (SVN:25783)
19:42:34 T:139658049763392 M:1066209280 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
19:42:34 T:139658049763392 M:1066209280 NOTICE: special://masterprofile/ is mapped to: /home/dorphell/.xbmc/userdata
19:42:34 T:139658049763392 M:1066209280 NOTICE: special://home/ is mapped to: /home/dorphell/.xbmc
19:42:34 T:139658049763392 M:1066209280 NOTICE: special://temp/ is mapped to: /home/dorphell/.xbmc/temp
19:42:34 T:139658049763392 M:1066209280 NOTICE: The executable running is: /usr/share/xbmc/xbmc.bin
19:42:34 T:139658049763392 M:1066209280 NOTICE: Log File is located: /home/dorphell/.xbmc/temp/xbmc.log
19:42:34 T:139658049763392 M:1066209280 NOTICE: -----------------------------------------------------------------------
19:42:34 T:139658049763392 M:1065828352 NOTICE: Setup SDL
19:42:34 T:139658049763392 M:1065066496 NOTICE: load settings...
19:42:34 T:139658049763392 M:1065066496 NOTICE: special://profile/ is mapped to: special://masterprofile/
19:42:34 T:139658049763392 M:1065066496 NOTICE: loading special://masterprofile/guisettings.xml
19:42:34 T:139658049763392 M:1065066496 NOTICE: Enabled debug logging due to GUI setting (2)
19:42:34 T:139658049763392 M:1065066496 NOTICE: Getting hardware information now...
19:42:34 T:139658049763392 M:1065066496 INFO: Using analog output
19:42:34 T:139658049763392 M:1065066496 INFO: AC3 pass through is enabled
19:42:34 T:139658049763392 M:1065066496 INFO: DTS pass through is enabled
19:42:34 T:139658049763392 M:1065066496 NOTICE: Checking resolution 11
19:42:34 T:139658049763392 M:1065066496 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/rtmp/mms/udp
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: flv/aacp/sdp
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdfile
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
19:42:34 T:139658049763392 M:1065066496 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
19:42:34 T:139658049763392 M:1065066496 NOTICE: Loaded playercorefactory configuration
19:42:34 T:139658049763392 M:1065066496 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
19:42:34 T:139658049763392 M:1065066496 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
19:42:34 T:139658049763392 M:1065066496 NOTICE: Loaded advancedsettings.xml from special://profile/advancedsettings.xml
19:42:34 T:139658049763392 M:1065066496 NOTICE: Contents of special://profile/advancedsettings.xml are...
<advancedsettings>
<tvshowmatching>
<regexp>[0]*([0-9]+)x[0]*([0-9]+)[^\\/]*</regexp>
</tvshowmatching>
<cputempcommand>sed -e 's/\([0-9]*\)[0-9]\{3\}.*/\1 C/' /sys/bus/acpi/drivers/ATK0110/ATK0110*/hwmon/hwmon?/temp1_input</cputempcommand>
<gputempcommand>echo "$(nvidia-settings -tq gpuCoreTemp) C"</gputempcommand>
</advancedsettings>
19:42:34 T:139658049763392 M:1065066496 NOTICE: Enabled debug logging due to GUI setting (2)
19:42:34 T:139658049763392 M:1065066496 NOTICE: Getting hardware information now...
19:42:34 T:139658049763392 M:1065066496 INFO: Using analog output
19:42:34 T:139658049763392 M:1065066496 INFO: AC3 pass through is enabled
19:42:34 T:139658049763392 M:1065066496 INFO: DTS pass through is enabled
19:42:34 T:139658049763392 M:1065066496 NOTICE: Checking resolution 11
19:42:34 T:139658049763392 M:1065066496 NOTICE: Default DVD Player: dvdplayer
19:42:34 T:139658049763392 M:1065066496 NOTICE: Default Video Player: dvdplayer
19:42:34 T:139658049763392 M:1065066496 NOTICE: Default Audio Player: paplayer
19:42:34 T:139658049763392 M:1065066496 NOTICE: special://masterprofile/sources.xml
19:42:34 T:139658049763392 M:1065066496 INFO: XRANDR: /usr/share/xbmc/xbmc-xrandr --output default --mode 0x1b6
19:42:34 T:139658049763392 M:1061781504 NOTICE: Using fbConfig[0]
19:42:34 T:139658049763392 M:1060958208 INFO: GL: Maximum texture width: 4096
19:42:34 T:139658049763392 M:1060958208 DEBUG: Loading: /usr/share/xbmc/system/ImageLib-x86_64-linux.so
19:42:34 T:139658049763392 M:1060704256 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_ARB_get_proc_address
19:42:34 T:139658049763392 M:1060704256 NOTICE: GL_VENDOR = NVIDIA Corporation
19:42:34 T:139658049763392 M:1060704256 NOTICE: GL_RENDERER = GeForce 7600 GT/PCI/SSE2
19:42:34 T:139658049763392 M:1060704256 NOTICE: GL_VERSION = 2.1.2 NVIDIA 190.42
19:42:34 T:139658049763392 M:1060704256 NOTICE: GL_EXTENSIONS = GL_ARB_color_buffer_float GL_ARB_copy_buffer GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_object 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_shader_objects GL_ARB_shading_language_100 GL_ARB_shadow GL_ARB_texture_border_clamp GL_ARB_texture_compression 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_non_power_of_two GL_ARB_texture_rectangle GL_ARB_transpose_matrix 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_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_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_framebuffer_object GL_EXT_gpu_program_parameters GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil 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_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_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_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_clamp 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_half_float GL_NV_light_max_exponent GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil 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_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_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_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
19:42:34 T:139658049763392 M:1060704256 INFO: GL: Maximum texture width: 4096
19:42:34 T:139658049763392 M:1060704256 INFO: load language info file: special://xbmc/language/English/langinfo.xml
19:42:34 T:139658049763392 M:1057402880 INFO: load language file:special://xbmc/language/English/strings.xml
19:42:34 T:139658049763392 M:1056387072 INFO: load keymapping
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/keymaps/remote.xml
19:42:34 T:139658049763392 M:1056387072 INFO: Loading special://xbmc/system/Lircmap.xml
19:42:34 T:139658049763392 M:1056387072 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
19:42:34 T:139658049763392 M:1056387072 INFO: Checking skin version of: Confluence
19:42:34 T:139658049763392 M:1056387072 INFO: Skin version is: 2.11 (2.110000)
19:42:34 T:139658049763392 M:1056387072 INFO: GUI format 1280x720 Windowed
19:42:34 T:139658049763392 M:1056387072 DEBUG: Selected HAL as storage provider
19:42:34 T:139658049763392 M:1056387072 INFO: HAL: Starting initializing
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Clearing old global device list, if any
19:42:34 T:139658049763392 M:1056288768 NOTICE: HAL: Generating global device list
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID 4d87e433-2f9e-54d1-b65f-9fee54559b93 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID 1da5e7a1-db08-e795-fa16-e7f8196d6eb1 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID ac735b4d-02bb-9c56-3487-c336ce80ed28 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID aeccdb00-3ed2-a677-5fcf-5d0a431d7d27 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID 1da5e7a1-db08-e795-fa16-e7f8196d6eb1 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID ac735b4d-02bb-9c56-3487-c336ce80ed28 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID 4d87e433-2f9e-54d1-b65f-9fee54559b93 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID aeccdb00-3ed2-a677-5fcf-5d0a431d7d27 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID 1da5e7a1-db08-e795-fa16-e7f8196d6eb1 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID ac735b4d-02bb-9c56-3487-c336ce80ed28 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID 4d87e433-2f9e-54d1-b65f-9fee54559b93 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 DEBUG: HAL: Added - disk | UUID aeccdb00-3ed2-a677-5fcf-5d0a431d7d27 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
19:42:34 T:139658049763392 M:1056288768 INFO: HAL: Generated global device list, found 191
19:42:34 T:139658049763392 M:1056288768 INFO: HAL: Sucessfully initialized
19:42:34 T:139658049763392 M:1056288768 INFO: creating subdirectories
19:42:34 T:139658049763392 M:1056288768 INFO: userdata folder: special://masterprofile/
19:42:34 T:139658049763392 M:1056288768 INFO: recording folder:
19:42:34 T:139658049763392 M:1056288768 INFO: screenshots folder:/home/dorphell/tmp/
19:42:34 T:139658049763392 M:1056288768 INFO: thumbnails folder: special://masterprofile/Thumbnails
19:42:34 T:139658049763392 M:1056288768 NOTICE: start dvd mediatype detection
19:42:34 T:139658049763392 M:1056288768 NOTICE: initializing playlistplayer
19:42:34 T:139658049763392 M:1056288768 NOTICE: DONE initializing playlistplayer
19:42:34 T:139657782626064 M:1056288768 DEBUG: thread start, auto delete: 0
19:42:34 T:139657782626064 M:1056288768 DEBUG: Compiled with libcdio Version 0.82
19:42:34 T:139658049763392 M:1056288768 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
19:42:34 T:139658049763392 M:1056288768 NOTICE: load default skin:[Confluence]
19:42:34 T:139658049763392 M:1056288768 INFO: load skin from:special://xbmc/skin/Confluence
19:42:34 T:139658049763392 M:1056288768 INFO: delete old skin...
19:42:34 T:139658049763392 M:1056288768 DEBUG: ------ Window Deinit (Pointer.xml) ------
19:42:34 T:139658049763392 M:1056288768 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
19:42:34 T:139657782626064 M:1056288768 DEBUG: Thread 139657782626064 terminating
19:42:34 T:139658049763392 M:1056288768 INFO: Default 4:3 resolution directory is special://xbmc/skin/Confluence/720p
19:42:34 T:139658049763392 M:1056288768 INFO: Default 16:9 resolution directory is special://xbmc/skin/Confluence/720p
19:42:34 T:139658049763392 M:1056288768 INFO: Skin version is: 2.11
19:42:34 T:139658049763392 M:1056288768 INFO: Loading skin includes from /usr/share/xbmc/skin/Confluence/720p/includes.xml
19:42:34 T:139658049763392 M:1053741056 INFO: load fonts for skin...
19:42:34 T:139658049763392 M:1053741056 INFO: Loading fonts from special://xbmc/skin/Confluence/720p/Font.xml
19:42:34 T:139658049763392 M:1051074560 INFO: load new skin...
19:42:34 T:139658049763392 M:1051074560 INFO: Skin version is: 2.11 (2.110000)
19:42:34 T:139658049763392 M:1051074560 INFO: Loading skin file: Home.xml
19:42:34 T:139658049763392 M:1050058752 DEBUG: Load Home.xml: 19.45ms
19:42:34 T:139658049763392 M:1050058752 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
19:42:34 T:139658049763392 M:1050058752 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
19:42:34 T:139658049763392 M:1050058752 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
19:42:34 T:139658049763392 M:1050058752 DEBUG: Load Skin XML: 35.29ms
19:42:34 T:139658049763392 M:1050058752 INFO: initialize new skin...
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: Pointer.xml
19:42:34 T:139658049763392 M:1050058752 DEBUG: Load Pointer.xml: 0.58ms
19:42:34 T:139658049763392 M:1050058752 DEBUG: Alloc resources: 1.46ms (0.63 ms skin load)
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: DialogVolumeBar.xml
19:42:34 T:139658049763392 M:1050058752 DEBUG: Load DialogVolumeBar.xml: 0.88ms
19:42:34 T:139658049763392 M:1050058752 DEBUG: Alloc resources: 0.98ms (0.94 ms skin load)
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: DialogSeekBar.xml
19:42:34 T:139658049763392 M:1050058752 DEBUG: Load DialogSeekBar.xml: 2.84ms
19:42:34 T:139658049763392 M:1050058752 DEBUG: Alloc resources: 2.97ms (2.93 ms skin load)
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: DialogKaiToast.xml
19:42:34 T:139658049763392 M:1050058752 DEBUG: Load DialogKaiToast.xml: 0.58ms
19:42:34 T:139658049763392 M:1050058752 DEBUG: Alloc resources: 0.64ms (0.63 ms skin load)
19:42:34 T:139658049763392 M:1050058752 INFO: Loading skin file: DialogMuteBug.xml
19:42:34 T:139658049763392 M:1050058752 DEBUG: Load DialogMuteBug.xml: 0.43ms
19:42:34 T:139658049763392 M:1050058752 DEBUG: Alloc resources: 0.48ms (0.48 ms skin load)
19:42:34 T:139658049763392 M:1050058752 DEBUG: CGUIAudioManager::Initialize
19:42:34 T:139658049763392 M:1049931776 INFO: Loading special://xbmc/skin/Confluence/sounds/sounds.xml
19:42:34 T:139658049763392 M:1049931776 INFO: skin loaded...
19:42:34 T:139658049763392 M:1049931776 DEBUG: Loading: libcurl.so.4
19:42:34 T:139658049763392 M:1049931776 DEBUG: Activating window ID: 12999
19:42:34 T:139658049763392 M:1049931776 DEBUG: Checking if window ID 12999 is locked.
19:42:34 T:139658049763392 M:1049931776 DEBUG: ------ Window Init (Startup.xml) ------
19:42:34 T:139658049763392 M:1049931776 INFO: Loading skin file: Startup.xml
19:42:34 T:139658049763392 M:1049931776 DEBUG: Load Startup.xml: 1.11ms
19:42:34 T:139658049763392 M:1049931776 DEBUG: Alloc resources: 1.20ms (1.20 ms skin load)
19:42:34 T:139658049763392 M:1049931776 INFO: removing tempfiles
19:42:34 T:139658049763392 M:1049931776 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
19:42:34 T:139658049763392 M:1049931776 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
19:42:34 T:139658049763392 M:1049931776 DEBUG: DeviceKit.Power: org.freedesktop.DBus.Error.AccessDenied - Rejected send message, 1 matched rules; type="method_call", sender=":1.1988" (uid=1000 pid=4023 comm="/usr/share/xbmc/xbmc.bin) interface="org.freedesktop.DeviceKit.Disks" member="EnumerateDevices" error name="(unset)" requested_reply=0 destination="org.freedesktop.DeviceKit.Disks" (uid=0 pid=11306 comm="/usr/lib/DeviceKit/devkit-disks-daemon))
19:42:34 T:139658049763392 M:1049931776 NOTICE: initialize done
19:42:34 T:139658049763392 M:1049931776 NOTICE: Running the application...
19:42:34 T:139658049763392 M:1049931776 DEBUG: ExecuteXBMCAction : Translating SetFocus(10)
19:42:34 T:139658049763392 M:1049931776 DEBUG: ExecuteXBMCAction : To SetFocus(10)
19:42:34 T:139658049763392 M:1049931776 DEBUG: Process - no user autoexec.py (/home/dorphell/.xbmc/scripts/autoexec.py) found, skipping
19:42:34 T:139658049763392 M:1049931776 INFO: initializing python engine.
19:42:34 T:139658049763392 M:1049931776 DEBUG: Loading: /usr/share/xbmc/system/python/python24-x86_64-linux.so
19:42:34 T:139658049763392 M:1049669632 DEBUG: Python wrapper library linked with internal Python library
19:42:34 T:139658049763392 M:1048653824 DEBUG: GetZipList - Processing zip://%2fusr%2fshare%2fxbmc%2fsystem%2fpython%2fpython24%2ezip/sitecustomize
19:42:34 T:139658049763392 M:1045860352 DEBUG: new python thread created. id=1
19:42:34 T:139658049763392 M:1045860352 DEBUG: NetworkMessage - Starting network services
19:42:34 T:139657684846352 M:1045860352 DEBUG: thread start, auto delete: 0
19:42:34 T:139657684846352 M:1045860352 DEBUG: Python thread: start processing
19:42:34 T:139658049763392 M:1045860352 NOTICE: ES: Starting event server
19:42:34 T:139657676453648 M:1045725184 DEBUG: thread start, auto delete: 0
19:42:34 T:139657676453648 M:1045725184 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
19:42:34 T:139657676453648 M:1045725184 NOTICE: UDP: Listening on port 9777
19:42:34 T:139658049763392 M:1045725184 NOTICE: DS: Starting dbus server
19:42:34 T:139657668060944 M:1045590016 DEBUG: thread start, auto delete: 0
19:42:34 T:139657668060944 M:1045590016 NOTICE: DS: Starting DBUS server in Run Application aka thread
19:42:34 T:139658049763392 M:1045590016 NOTICE: starting zeroconf publishing
19:42:34 T:139657676453648 M:1045590016 INFO: CZeroconfAvahi::clientCallback: avahi server not available. But may become later...
19:42:34 T:139657684846352 M:1045106688 NOTICE: -->Python Interpreter Initialized<--
19:42:34 T:139657684846352 M:1045106688 DEBUG: Process - The source file to load is /usr/share/xbmc/scripts/autoexec.py
19:42:34 T:139657684846352 M:1045106688 DEBUG: Process - Setting the Python path to /usr/share/xbmc/scripts:/usr/share/xbmc/system/python/python24.zip:/usr/share/xbmc/system/python/lib/python24.zip:/usr/share/xbmc/system/python/lib/python2.4/:/usr/share/xbmc/system/python/lib/python2.4/plat-linux2:/usr/share/xbmc/system/python/lib/python2.4/lib-tk:/usr/share/xbmc/system/python/lib/python2.4/lib-dynload
19:42:34 T:139657684846352 M:1045106688 DEBUG: Process - Entering source directory /usr/share/xbmc/scripts
19:42:34 T:139657684846352 M:1045106688 INFO: Scriptresult: Success
19:42:34 T:139658049763392 M:1045106688 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
19:42:34 T:139658049763392 M:1045106688 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
19:42:34 T:139658049763392 M:1045106688 DEBUG: CLastfmScrobbler: Clearing session.
19:42:34 T:139658049763392 M:1045106688 DEBUG: CLibrefmScrobbler: Clearing session.
19:42:34 T:139658049763392 M:1045106688 INFO: GL: Disabling VSYNC
19:42:34 T:139657651275536 M:1045106688 DEBUG: thread start, auto delete: 1
19:42:34 T:139658049763392 M:1045860352 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
19:42:34 T:139658049763392 M:1045860352 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
19:42:34 T:139658049763392 M:1045860352 DEBUG: Activating window ID: 10000
19:42:34 T:139658049763392 M:1045860352 DEBUG: Checking if window ID 10000 is locked.
19:42:34 T:139658049763392 M:1045860352 DEBUG: ------ Window Deinit (Startup.xml) ------
19:42:34 T:139658049763392 M:1045860352 DEBUG: ------ Window Init (Home.xml) ------
19:42:34 T:139658049763392 M:1045860352 DEBUG: Alloc resources: 0.55ms (0.00 ms skin load)
19:42:34 T:139657631819536 M:1045860352 DEBUG: thread start, auto delete: 1
19:42:34 T:139657631819536 M:1045860352 INFO: WEATHER: Downloading weather
19:42:34 T:139657631819536 M:1045860352 DEBUG: FileCurl::Open(0x7f049388ca60) http://xoap.weather.com/weather/local/02461?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
19:42:34 T:139657631819536 M:1045860352 INFO: easy_aquire - Created session to http://xoap.weather.com
19:42:34 T:139657623426832 M:1045057536 DEBUG: thread start, auto delete: 1
19:42:34 T:139658049189648 M:1021693952 DEBUG: thread start, auto delete: 0
19:42:35 T:139658049189648 M:1021566976 DEBUG: FileCurl::Open(0x7f04ac695a40) http://feeds.feedburner.com/xbmc
19:42:35 T:139658049189648 M:1021566976 INFO: easy_aquire - Created session to http://feeds.feedburner.com
19:42:35 T:139657631819536 M:1021566976 DEBUG: FileCurl::Close(0x7f049388ca60) http://xoap.weather.com/weather/local/02461?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
19:42:35 T:139657631819536 M:1021566976 INFO: WEATHER: Weather download successful
19:42:35 T:139658049763392 M:1018519552 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000)
19:42:35 T:139658049763392 M:1018519552 DEBUG: ExecuteXBMCAction : To SetFocus(9000)
19:42:35 T:139658049763392 M:1018900480 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139657684846352 M:1018089472 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/time.so. flags: 2
19:42:35 T:139657684846352 M:1018089472 DEBUG: Loading: /home/dorphell/.xbmc/temp/time.so
19:42:35 T:139657684846352 M:1018089472 DEBUG: RegisterExtensionLib, adding time.so (0x7f04987ab970)
19:42:35 T:139657684846352 M:1018089472 DEBUG: xbp_dlsym - load symbol inittime
19:42:35 T:139657684846352 M:1018089472 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/collections.so. flags: 2
19:42:35 T:139657684846352 M:1018089472 DEBUG: Loading: /home/dorphell/.xbmc/temp/collections.so
19:42:35 T:139657684846352 M:1018089472 DEBUG: RegisterExtensionLib, adding collections.so (0x7f04987b3af0)
19:42:35 T:139657684846352 M:1018089472 DEBUG: xbp_dlsym - load symbol initcollections
19:42:35 T:139657684846352 M:1017962496 INFO: Python script stopped
19:42:35 T:139657684846352 M:1017962496 DEBUG: Thread 139657684846352 terminating
19:42:35 T:139658049763392 M:1017962496 DEBUG: python thread 1 destructed
19:42:35 T:139658049763392 M:1017962496 INFO: Python, unloading python24.dll because no scripts are running anymore
19:42:35 T:139658049763392 M:1017962496 DEBUG: UnloadExtensionLibs, clearing python extension libraries
19:42:35 T:139658049763392 M:1017962496 DEBUG: Unloading: time.so
19:42:35 T:139658049763392 M:1017962496 DEBUG: Unloading: collections.so
19:42:35 T:139658049763392 M:1017962496 DEBUG: Unloading: python24-x86_64-linux.so
19:42:35 T:139658049189648 M:1017962496 DEBUG: FileCurl::Close(0x7f04ac695a40) http://feeds.feedburner.com/xbmc
19:42:35 T:139658049189648 M:1017962496 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc
19:42:35 T:139658049189648 M:1017962496 DEBUG: RSS feed encoding: ISO-8859-1
19:42:35 T:139658049189648 M:1017962496 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc
19:42:35 T:139658049189648 M:1017962496 DEBUG: Thread 139658049189648 terminating
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:35 T:139658049763392 M:1036136448 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:36 T:139658049763392 M:1023950848 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoLibrary,MovieTitles,return)
19:42:36 T:139658049763392 M:1023950848 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoLibrary,MovieTitles,return)
19:42:36 T:139658049763392 M:1023950848 DEBUG: Activating window ID: 10025
19:42:36 T:139658049763392 M:1023950848 DEBUG: Checking if window ID 10024 is locked.
19:42:36 T:139658049763392 M:1023950848 DEBUG: ------ Window Deinit (Home.xml) ------
19:42:36 T:139658049763392 M:1033601024 INFO: Attempting to return to: MovieTitles
19:42:36 T:139658049763392 M:1033601024 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:42:36 T:139658049763392 M:1033601024 INFO: Loading skin file: MyVideoNav.xml
19:42:36 T:139658049763392 M:1031331840 DEBUG: Load MyVideoNav.xml: 49.04ms
19:42:36 T:139658049763392 M:1031331840 DEBUG: Alloc resources: 52.86ms (51.29 ms skin load)
19:42:36 T:139658049763392 M:1031331840 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
19:42:36 T:139658049763392 M:1031331840 DEBUG: ParentPath = [videodb://1/2/]
19:42:36 T:139658049763392 M:1031331840 DEBUG: GetSetsNav query: select sets.idSet,sets.strSet,count(1),count(files.playCount) from sets join setlinkmovie on sets.idSet=setlinkmovie.idSet join movie on setlinkmovie.idMovie=movie.idMovie join files on files.idFile=movie.idFile group by sets.idSet
19:42:36 T:139658049763392 M:1031331840 DEBUG: GetMoviesByWhere query: select * from movieview where movieview.idmovie NOT in (select idmovie from setlinkmovie)
19:42:36 T:139658049763392 M:1031331840 DEBUG: Time for actual SQL query = 4
19:42:36 T:139658049763392 M:1031331840 DEBUG: Time to retrieve movies from dataset = 38
19:42:36 T:139658049763392 M:1031331840 DEBUG: Saving fileitems [videodb://1/2/]
19:42:36 T:139658049763392 M:1031839744 DEBUG: -- items: 102, sort method: 23, ascending: true
19:42:36 T:139657684846352 M:1031839744 DEBUG: thread start, auto delete: 0
19:42:36 T:139658049763392 M:1031331840 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:36 T:139658049763392 M:1031331840 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 4 seconds.
19:42:36 T:139657684846352 M:1031331840 DEBUG: Thread 139657684846352 terminating
19:42:40 T:139658049763392 M:1004904448 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 8 seconds.
19:42:42 T:139657642882832 M:982188032 DEBUG: thread start, auto delete: 1
19:42:44 T:139658049763392 M:1004380160 DEBUG: SDLKeyboard: scancode: 50, sym: 304, unicode: 0, modifier: 0
19:42:44 T:139658049763392 M:1004380160 DEBUG: OnKey: 61600 pressed, action is 0
19:42:44 T:139658049763392 M:1004380160 DEBUG: SDLKeyboard: scancode: 30, sym: 117, unicode: 85, modifier: 1
19:42:44 T:139658049763392 M:995491840 DEBUG: Failed to connect to LIRC. Retry in 20s.
19:42:46 T:139658049763392 M:953925632 INFO: Loading skin file: DialogContextMenu.xml
19:42:46 T:139658049763392 M:953925632 DEBUG: Load DialogContextMenu.xml: 1.04ms
19:42:46 T:139658049763392 M:953925632 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
19:42:46 T:139658049763392 M:953925632 DEBUG: Alloc resources: 0.11ms (0.00 ms skin load)
19:42:48 T:139658049763392 M:980848640 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
19:42:48 T:139658049763392 M:980848640 DEBUG: new file set audiostream:0
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerCoreFactory::GetPlayers(/data/Video/Movies/Up.avi)
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:42:48 T:139658049763392 M:980848640 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:42:48 T:139658049763392 M:980848640 NOTICE: DVDPlayer: Opening: /data/Video/Movies/Up.avi
19:42:48 T:139658049763392 M:980848640 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:42:48 T:139657615034128 M:980848640 DEBUG: thread start, auto delete: 1
19:42:48 T:139657596323600 M:980848640 DEBUG: thread start, auto delete: 0
19:42:48 T:139657596323600 M:980848640 NOTICE: Creating InputStream
19:42:48 T:139657596323600 M:980848640 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
19:42:48 T:139657596323600 M:980848640 DEBUG: CacheSubtitles: START
19:42:48 T:139657596323600 M:980848640 DEBUG: CacheSubtitles: Checking for common subirs...
19:42:48 T:139657596323600 M:980848640 DEBUG: CacheSubtitles: Done (time: 1 ms)
19:42:48 T:139657596323600 M:980848640 DEBUG: CacheSubtitles: Searching for subtitles...
19:42:48 T:139657596323600 M:980848640 DEBUG: CacheSubtitles: Done (time: 3 ms)
19:42:48 T:139657596323600 M:980848640 DEBUG: CacheSubtitles: END (total time: 4 ms)
19:42:48 T:139657596323600 M:980848640 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
19:42:48 T:139657596323600 M:980848640 NOTICE: Creating Demuxer
19:42:48 T:139657596323600 M:980848640 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avutil-50-x86_64-linux.so
19:42:48 T:139657596323600 M:980705280 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avcodec-52-x86_64-linux.so
19:42:48 T:139657596323600 M:980705280 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/avformat-52-x86_64-linux.so
19:42:48 T:139657596323600 M:980705280 DEBUG: Open - probing detected format [avi]
19:42:48 T:139657596323600 M:980705280 DEBUG: Open - av_find_stream_info starting
19:42:48 T:139657596323600 M:980705280 DEBUG: ffmpeg[916B4710]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
19:42:48 T:139657596323600 M:980578304 DEBUG: Open - av_find_stream_info finished
19:42:48 T:139657596323600 M:980578304 INFO: ffmpeg[916B4710]: Input #0, avi, from '/data/Video/Movies/Up.avi':
19:42:48 T:139657596323600 M:980578304 INFO: ffmpeg[916B4710]: Duration: 01:36:37.63, start: 0.000000, bitrate: 1012 kb/s
19:42:48 T:139657596323600 M:980578304 INFO: ffmpeg[916B4710]: Stream #0.0: Video: mpeg4, yuv420p, 624x352 [PAR 1:1 DAR 39:22], 23.98 tbr, 23.98 tbn, 23.98 tbc
19:42:48 T:139657596323600 M:980578304 INFO: ffmpeg[916B4710]: Stream #0.1: Audio: mp3, 48000 Hz, 2 channels, s16, 144 kb/s
19:42:48 T:139657596323600 M:980578304 NOTICE: Opening video stream: 0 source: 256
19:42:48 T:139657596323600 M:980578304 NOTICE: Creating video codec with codec id: 13
19:42:48 T:139657596323600 M:980578304 DEBUG: FactoryCodec - Video: - Opening
19:42:48 T:139657596323600 M:980578304 DEBUG: Loading: /usr/share/xbmc/system/players/dvdplayer/swscale-0.6.1-x86_64-linux.so
19:42:48 T:139657596323600 M:980578304 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2
19:42:48 T:139657596323600 M:980578304 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
19:42:48 T:139657596323600 M:980578304 NOTICE: Creating video thread
19:42:48 T:139657596323600 M:980578304 NOTICE: Opening audio stream: 1 source: 256
19:42:48 T:139657596323600 M:980578304 NOTICE: Finding audio codec for: 86017
19:42:48 T:139657560717072 M:980578304 DEBUG: thread start, auto delete: 0
19:42:48 T:139657596323600 M:980578304 DEBUG: FactoryCodec - Audio: passthrough - Opening
19:42:48 T:139657560717072 M:980578304 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:42:48 T:139657596323600 M:980578304 DEBUG: FactoryCodec - Audio: passthrough - Failed
19:42:48 T:139657560717072 M:980578304 NOTICE: running thread: video_thread
19:42:48 T:139657596323600 M:980578304 DEBUG: FactoryCodec - Audio: libmad - Opening
19:42:48 T:139657560717072 M:980578304 NOTICE: Display resolution DESKTOP : 1680x1050 @ 50.00 - Full Screen (12)
19:42:48 T:139657596323600 M:980578304 DEBUG: Loading: libmad.so.0
19:42:48 T:139657596323600 M:980578304 DEBUG: FactoryCodec - Audio: libmad - Opened
19:42:48 T:139657596323600 M:980578304 NOTICE: Creating audio thread
19:42:48 T:139657552324368 M:980578304 DEBUG: thread start, auto delete: 0
19:42:48 T:139657552324368 M:980578304 NOTICE: running thread: CDVDPlayerAudio::Process()
19:42:48 T:139657596323600 M:980578304 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: /data/Video/Movies/Up.avi
19:42:48 T:139657596323600 M:980578304 DEBUG: SeekTime - unknown position after seek
19:42:48 T:139657596323600 M:980578304 DEBUG: Process - starting demuxer from: 1856000
19:42:48 T:139658049763392 M:980578304 DEBUG: Activating window ID: 12005
19:42:48 T:139658049763392 M:980578304 DEBUG: Checking if window ID 12005 is locked.
19:42:48 T:139658049763392 M:980578304 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:42:48 T:139658049763392 M:980578304 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:42:48 T:139658049763392 M:980578304 INFO: Loading skin file: VideoFullScreen.xml
19:42:48 T:139658049763392 M:980578304 DEBUG: Load VideoFullScreen.xml: 0.64ms
19:42:48 T:139658049763392 M:980578304 DEBUG: Alloc resources: 0.70ms (0.70 ms skin load)
19:42:48 T:139658049763392 M:980578304 INFO: Loading skin file: VideoOSD.xml
19:42:48 T:139658049763392 M:980578304 DEBUG: Load VideoOSD.xml: 2.35ms
19:42:48 T:139658049763392 M:980418560 DEBUG: Alloc resources: 3.09ms (0.00 ms skin load)
19:42:48 T:139658049763392 M:980418560 INFO: Loading skin file: VideoOSDSettings.xml
19:42:48 T:139658049763392 M:980418560 DEBUG: Load VideoOSDSettings.xml: 1.85ms
19:42:48 T:139658049763392 M:980418560 DEBUG: Alloc resources: 1.75ms (0.00 ms skin load)
19:42:48 T:139658049763392 M:980418560 INFO: Loading skin file: VideoOSDSettings.xml
19:42:48 T:139658049763392 M:980418560 DEBUG: Load VideoOSDSettings.xml: 2.02ms
19:42:48 T:139658049763392 M:980418560 DEBUG: Alloc resources: 0.06ms (0.00 ms skin load)
19:42:48 T:139658049763392 M:980418560 DEBUG: CGUIAudioManager::DeInitialize
19:42:48 T:139657596323600 M:980418560 INFO: Loading skin file: DialogProgress.xml
19:42:48 T:139657596323600 M:980418560 DEBUG: Load DialogProgress.xml: 1.16ms
19:42:48 T:139657596323600 M:980418560 DEBUG: ffmpeg[916B4710]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
19:42:48 T:139657560717072 M:980418560 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
19:42:48 T:139657552324368 M:980418560 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_DELAY(519250.000000)
19:42:48 T:139657560717072 M:980418560 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(1860608750.000000, 1)
19:42:48 T:139657560717072 M:980418560 DEBUG: ffmpeg[8F4BF710]: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected
19:42:48 T:139657615034128 M:980418560 DEBUG: Thread 139657615034128 terminating (autodelete)
19:42:48 T:139658049763392 M:979656704 DEBUG: OnPlayBackStarted - Playback has started
19:42:48 T:139658049763392 M:979656704 DEBUG: ------ Window Init (VideoOSD.xml) ------
19:42:48 T:139658049763392 M:979656704 DEBUG: CGUIInfoManager::SetCurrentMovie(/data/Video/Movies/Up.avi)
19:42:48 T:139658049763392 M:979656704 ERROR: XLCDproc::SetBackLight - Unable to write to socket
19:42:48 T:139658049763392 M:979656704 INFO: GL: Enabling VSYNC
19:42:48 T:139658049763392 M:979656704 INFO: GL: Selected vsync mode 2
19:42:48 T:139658049763392 M:979656704 NOTICE: Using GL_TEXTURE_2D
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Requested render method: 0
19:42:48 T:139658049763392 M:979656704 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Shader compilation log:
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Shader compilation log:
19:42:48 T:139658049763392 M:979656704 NOTICE: GL: NPOT texture support detected
19:42:48 T:139658049763392 M:979656704 NOTICE: GL: Using GL_ARB_pixel_buffer_object
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 624 x 352
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 NOTICE: GL: Using GL_ARB_pixel_buffer_object
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 624 x 352
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
19:42:48 T:139658049763392 M:979656704 INFO: GL: ARB Imaging extension supported
19:42:48 T:139657552324368 M:979656704 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(1861128000.000000, 0)
19:42:48 T:139657552324368 M:979656704 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through
19:42:48 T:139657552324368 M:979656704 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec PCM - IsMusic false - IsPassthrough false - audioDevice: default:CARD=NVidia
19:42:48 T:139657552324368 M:979656704 DEBUG: RemoveActiveDevice - Removing device 1
19:42:48 T:139657552324368 M:979656704 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
19:42:48 T:139657552324368 M:979656704 DEBUG: RemoveActiveDevice - Removing device 0
19:42:48 T:139657552324368 M:979656704 DEBUG: Initialize - using alsa device default:CARD=NVidia
19:42:48 T:139657552324368 M:979656704 DEBUG: CALSADirectSound::Initialize - packet size:4096, packet count:8, buffer size:8192
19:42:48 T:139657552324368 M:979656704 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
19:42:48 T:139657552324368 M:979656704 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1861131697.314000, should be:1861152072.779424, error:20375.465424
19:42:49 T:139658049763392 M:979529728 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 16 seconds.
19:42:50 T:139658049763392 M:979558400 DEBUG: SDLKeyboard: scancode: 65, sym: 32, unicode: 32, modifier: 0
19:42:50 T:139658049763392 M:979558400 DEBUG: OnKey: 61472 pressed, action is 12
19:42:50 T:139658049763392 M:979558400 DEBUG: OnPlayBackPaused - Playback was paused
19:42:50 T:139658049763392 M:979558400 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:42:50 T:139658049763392 M:979558400 DEBUG: Alloc resources: 0.01ms (0.00 ms skin load)
19:42:50 T:139657552324368 M:979558400 WARNING: CALSADirectSound::CALSADirectSound - device is not able to pause playback, will flush instead
19:42:50 T:139658049763392 M:979558400 DEBUG: SDLKeyboard: scancode: 65, sym: 32, unicode: 32, modifier: 0
19:42:50 T:139658049763392 M:979558400 DEBUG: OnKey: 61472 pressed, action is 12
19:42:50 T:139657552324368 M:979558400 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1862536332.261424, should be:1862709402.312757, error:173070.051333
19:42:50 T:139658049763392 M:979558400 DEBUG: OnPlayBackResumed - Playback was resumed
19:42:50 T:139658049763392 M:979558400 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:42:51 T:139657552324368 M:979578880 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1863631556.642757, should be:1863711392.259231, error:79835.616473
19:42:52 T:139657560717072 M:979451904 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33
19:42:52 T:139658049763392 M:979451904 DEBUG: ExecuteXBMCAction : Translating RunScript(special://home/scripts/OpenSubtitles_OSD/default.py)
19:42:52 T:139658049763392 M:979451904 DEBUG: ExecuteXBMCAction : To RunScript(special://home/scripts/OpenSubtitles_OSD/default.py)
19:42:52 T:139658049763392 M:979451904 INFO: initializing python engine.
19:42:52 T:139658049763392 M:979451904 DEBUG: Loading: /usr/share/xbmc/system/python/python24-x86_64-linux.so
19:42:52 T:139658049763392 M:979324928 DEBUG: Python wrapper library linked with internal Python library
19:42:52 T:139658049763392 M:979324928 DEBUG: new python thread created. id=2
19:42:52 T:139657615034128 M:979324928 DEBUG: thread start, auto delete: 0
19:42:52 T:139657615034128 M:979324928 DEBUG: Python thread: start processing
19:42:52 T:139657615034128 M:979324928 NOTICE: -->Python Interpreter Initialized<--
19:42:52 T:139657615034128 M:979324928 DEBUG: Process - The source file to load is special://home/scripts/OpenSubtitles_OSD/default.py
19:42:52 T:139657615034128 M:979197952 DEBUG: Process - Setting the Python path to /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD:/usr/share/xbmc/system/python/python24.zip:/usr/share/xbmc/system/python/lib/python24.zip:/usr/share/xbmc/system/python/lib/python2.4/:/usr/share/xbmc/system/python/lib/python2.4/plat-linux2:/usr/share/xbmc/system/python/lib/python2.4/lib-tk:/usr/share/xbmc/system/python/lib/python2.4/lib-dynload
19:42:52 T:139657615034128 M:979197952 DEBUG: Process - Entering source directory /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/strop.so. flags: 2
19:42:52 T:139657615034128 M:979197952 DEBUG: Loading: /home/dorphell/.xbmc/temp/strop.so
19:42:52 T:139657615034128 M:979197952 DEBUG: RegisterExtensionLib, adding strop.so (0x2b02ef0)
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlsym - load symbol initstrop
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/math.so. flags: 2
19:42:52 T:139657615034128 M:979197952 DEBUG: Loading: /home/dorphell/.xbmc/temp/math.so
19:42:52 T:139657615034128 M:979197952 DEBUG: RegisterExtensionLib, adding math.so (0x399a830)
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlsym - load symbol initmath
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/binascii.so. flags: 2
19:42:52 T:139657615034128 M:979197952 DEBUG: Loading: /home/dorphell/.xbmc/temp/binascii.so
19:42:52 T:139657615034128 M:979197952 DEBUG: RegisterExtensionLib, adding binascii.so (0x290b9b0)
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlsym - load symbol initbinascii
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/_random.so. flags: 2
19:42:52 T:139657615034128 M:979197952 DEBUG: Loading: /home/dorphell/.xbmc/temp/_random.so
19:42:52 T:139657615034128 M:979197952 DEBUG: RegisterExtensionLib, adding _random.so (0x290f060)
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlsym - load symbol init_random
19:42:52 T:139657615034128 M:979197952 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/fcntl.so. flags: 2
19:42:52 T:139657615034128 M:979070976 DEBUG: Loading: /home/dorphell/.xbmc/temp/fcntl.so
19:42:52 T:139657615034128 M:979070976 DEBUG: RegisterExtensionLib, adding fcntl.so (0x7f04989dc540)
19:42:52 T:139657615034128 M:979070976 DEBUG: xbp_dlsym - load symbol initfcntl
19:42:52 T:139657615034128 M:979070976 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/_socket.so. flags: 2
19:42:52 T:139657615034128 M:979197952 DEBUG: Loading: /home/dorphell/.xbmc/temp/_socket.so
19:42:52 T:139657615034128 M:979070976 DEBUG: RegisterExtensionLib, adding _socket.so (0x39eb9c0)
19:42:52 T:139657615034128 M:979070976 DEBUG: xbp_dlsym - load symbol init_socket
19:42:52 T:139657615034128 M:979070976 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/time.so. flags: 2
19:42:52 T:139657615034128 M:979070976 DEBUG: Loading: /home/dorphell/.xbmc/temp/time.so
19:42:52 T:139657615034128 M:979070976 DEBUG: RegisterExtensionLib, adding time.so (0x5374ab0)
19:42:52 T:139657615034128 M:979070976 DEBUG: xbp_dlsym - load symbol inittime
19:42:52 T:139657552324368 M:979070976 WARNING: CALSADirectSound::CALSADirectSound - device is not able to pause playback, will flush instead
19:42:52 T:139658049763392 M:979070976 DEBUG: OnPlayBackPaused - Playback was paused
19:42:52 T:139658049763392 M:979070976 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:42:52 T:139658049763392 M:979070976 DEBUG: Alloc resources: 0.07ms (0.00 ms skin load)
19:42:52 T:139657615034128 M:978935808 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/cStringIO.so. flags: 2
19:42:52 T:139657615034128 M:978935808 DEBUG: Loading: /home/dorphell/.xbmc/temp/cStringIO.so
19:42:52 T:139657615034128 M:978935808 DEBUG: RegisterExtensionLib, adding cStringIO.so (0x3930b90)
19:42:52 T:139657615034128 M:978935808 DEBUG: xbp_dlsym - load symbol initcStringIO
19:42:52 T:139657615034128 M:978935808 NOTICE: SVN Latest Version :[ 1.44]
19:42:52 T:139657615034128 M:978935808 NOTICE: False
19:42:52 T:139657615034128 M:978935808 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/operator.so. flags: 2
19:42:52 T:139657615034128 M:978935808 DEBUG: Loading: /home/dorphell/.xbmc/temp/operator.so
19:42:52 T:139657615034128 M:978935808 DEBUG: RegisterExtensionLib, adding operator.so (0x548a000)
19:42:52 T:139657615034128 M:978935808 DEBUG: xbp_dlsym - load symbol initoperator
19:42:52 T:139657615034128 M:978935808 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/struct.so. flags: 2
19:42:52 T:139657615034128 M:978935808 DEBUG: Loading: /home/dorphell/.xbmc/temp/struct.so
19:42:52 T:139657615034128 M:978935808 DEBUG: RegisterExtensionLib, adding struct.so (0x5490280)
19:42:52 T:139657615034128 M:978935808 DEBUG: xbp_dlsym - load symbol initstruct
19:42:53 T:139657615034128 M:978935808 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/pyexpat.so. flags: 2
19:42:53 T:139657615034128 M:978935808 DEBUG: Loading: /home/dorphell/.xbmc/temp/pyexpat.so
19:42:53 T:139657615034128 M:978935808 DEBUG: RegisterExtensionLib, adding pyexpat.so (0x5496dd0)
19:42:53 T:139657615034128 M:978935808 DEBUG: xbp_dlsym - load symbol initpyexpat
19:42:53 T:139657615034128 M:978935808 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/md5.so. flags: 2
19:42:53 T:139657615034128 M:978935808 DEBUG: Loading: /home/dorphell/.xbmc/temp/md5.so
19:42:53 T:139657615034128 M:978935808 DEBUG: RegisterExtensionLib, adding md5.so (0x37835f0)
19:42:53 T:139657615034128 M:978935808 DEBUG: xbp_dlsym - load symbol initmd5
19:42:53 T:139657615034128 M:978935808 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/sha.so. flags: 2
19:42:53 T:139657615034128 M:978935808 DEBUG: Loading: /home/dorphell/.xbmc/temp/sha.so
19:42:53 T:139657615034128 M:978935808 DEBUG: RegisterExtensionLib, adding sha.so (0x37be1c0)
19:42:53 T:139657615034128 M:978935808 DEBUG: xbp_dlsym - load symbol initsha
19:42:53 T:139657615034128 M:978935808 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/_bisect.so. flags: 2
19:42:53 T:139657615034128 M:978935808 DEBUG: Loading: /home/dorphell/.xbmc/temp/_bisect.so
19:42:53 T:139657615034128 M:978808832 DEBUG: RegisterExtensionLib, adding _bisect.so (0x37cce80)
19:42:53 T:139657615034128 M:978808832 DEBUG: xbp_dlsym - load symbol init_bisect
19:42:53 T:139657615034128 M:978808832 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/collections.so. flags: 2
19:42:53 T:139657615034128 M:978808832 DEBUG: Loading: /home/dorphell/.xbmc/temp/collections.so
19:42:53 T:139657615034128 M:978808832 DEBUG: RegisterExtensionLib, adding collections.so (0x3833dc0)
19:42:53 T:139657615034128 M:978808832 DEBUG: xbp_dlsym - load symbol initcollections
19:42:53 T:139657615034128 M:978808832 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/datetime.so. flags: 2
19:42:53 T:139657615034128 M:978808832 DEBUG: Loading: /home/dorphell/.xbmc/temp/datetime.so
19:42:53 T:139657615034128 M:978808832 DEBUG: RegisterExtensionLib, adding datetime.so (0x38403c0)
19:42:53 T:139657615034128 M:978808832 DEBUG: xbp_dlsym - load symbol initdatetime
19:42:53 T:139657615034128 M:978808832 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/zlib.so. flags: 2
19:42:53 T:139657615034128 M:978808832 DEBUG: Loading: /home/dorphell/.xbmc/temp/zlib.so
19:42:53 T:139657615034128 M:978808832 DEBUG: RegisterExtensionLib, adding zlib.so (0x38739f0)
19:42:53 T:139657615034128 M:978808832 DEBUG: xbp_dlsym - load symbol initzlib
19:42:53 T:139657615034128 M:978817024 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/array.so. flags: 2
19:42:53 T:139657615034128 M:978817024 DEBUG: Loading: /home/dorphell/.xbmc/temp/array.so
19:42:53 T:139657615034128 M:978817024 DEBUG: RegisterExtensionLib, adding array.so (0x38de8c0)
19:42:53 T:139657615034128 M:978817024 DEBUG: xbp_dlsym - load symbol initarray
19:42:53 T:139657615034128 M:978817024 DEBUG: xbp_dlopen loading python lib /usr/share/xbmc/system/python/python24.zip/unicodedata.so. flags: 2
19:42:53 T:139657615034128 M:978817024 DEBUG: Loading: /home/dorphell/.xbmc/temp/unicodedata.so
19:42:53 T:139657615034128 M:978817024 DEBUG: RegisterExtensionLib, adding unicodedata.so (0x3959dd0)
19:42:53 T:139657615034128 M:978817024 DEBUG: xbp_dlsym - load symbol initunicodedata
19:42:53 T:139657615034128 M:978817024 INFO: Default 4:3 resolution directory is /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p
19:42:53 T:139657615034128 M:978817024 INFO: Default 16:9 resolution directory is /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p
19:42:53 T:139657615034128 M:978817024 INFO: Skin version is: 2.1
19:42:53 T:139657615034128 M:978817024 INFO: Loading skin includes from /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/includes.xml
19:42:53 T:139658049763392 M:978817024 ERROR: XLCDproc::SetBackLight - Unable to write to socket
19:42:53 T:139658049763392 M:978817024 DEBUG: ------ Window Init (/home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/script-OpenSubtitles_OSD-confluence.xml) ------
19:42:53 T:139658049763392 M:978817024 INFO: Loading skin file: /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/script-OpenSubtitles_OSD-confluence.xml
19:42:53 T:139658049763392 M:978690048 DEBUG: Load /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/script-OpenSubtitles_OSD-confluence.xml: 3.29ms
19:42:53 T:139658049763392 M:978690048 DEBUG: Alloc resources: 3.39ms (3.37 ms skin load)
19:42:53 T:139658049763392 M:978690048 DEBUG: CGUIMediaWindow::GetDirectory (?)
19:42:53 T:139658049763392 M:978690048 DEBUG: ParentPath = []
19:42:53 T:139658049763392 M:978690048 ERROR: GetDirectory - Error getting ?
19:42:53 T:139658049763392 M:978690048 ERROR: CGUIMediaWindow::GetDirectory(?) failed
19:42:53 T:139658049763392 M:977674240 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
19:42:56 T:139658049763392 M:977555456 DEBUG: ------ Window Deinit (VideoOSD.xml) ------
19:43:04 T:139658049763392 M:977555456 DEBUG: Failed to connect to LIRC. Retry in 40s.
19:43:04 T:139658049763392 M:977555456 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0
19:43:04 T:139658049763392 M:977555456 DEBUG: OnKey: 61467 pressed, action is 10
19:43:04 T:139658049763392 M:977555456 DEBUG: ------ Window Deinit (/home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/script-OpenSubtitles_OSD-confluence.xml) ------
19:43:04 T:139658049763392 M:977555456 DEBUG: OnPlayBackResumed - Playback was resumed
19:43:04 T:139657615034128 M:977555456 ERROR: ClearBlock: Trying to clear non existent block /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD
19:43:04 T:139657615034128 M:977555456 INFO: Scriptresult: Success
19:43:04 T:139657552324368 M:977555456 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1865229178.285231, should be:1865400000.000000, error:170821.714769
19:43:05 T:139658049763392 M:977555456 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:43:05 T:139658049763392 M:977555456 INFO: CheckIdle - Closing session to http://xoap.weather.com (easy=0x1e760d0, multi=0x1e4d580)
19:43:05 T:139658049763392 M:977555456 INFO: CheckIdle - Closing session to http://feeds.feedburner.com (easy=0x1e2a7e0, multi=0x1e65a50)
19:43:05 T:139658049763392 M:977555456 ERROR: XLCDproc::SetBackLight - Unable to write to socket
19:43:05 T:139658049763392 M:977555456 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 32 seconds.
19:43:05 T:139657615034128 M:977555456 INFO: Python script stopped
19:43:05 T:139657615034128 M:977555456 DEBUG: Thread 139657615034128 terminating
19:43:05 T:139658049763392 M:977555456 DEBUG: python thread 2 destructed
19:43:05 T:139658049763392 M:977555456 INFO: Python, unloading python24.dll because no scripts are running anymore
19:43:05 T:139658049763392 M:977555456 DEBUG: UnloadExtensionLibs, clearing python extension libraries
19:43:05 T:139658049763392 M:977555456 DEBUG: Unloading: strop.so
19:43:05 T:139658049763392 M:977555456 DEBUG: Unloading: math.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: binascii.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: _random.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: fcntl.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: _socket.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: time.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: cStringIO.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: operator.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: struct.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: pyexpat.so
19:43:05 T:139658049763392 M:977682432 DEBUG: Unloading: md5.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: sha.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: _bisect.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: collections.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: datetime.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: zlib.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: array.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: unicodedata.so
19:43:05 T:139658049763392 M:977809408 DEBUG: Unloading: python24-x86_64-linux.so
19:43:05 T:139658049763392 M:978063360 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0
19:43:05 T:139658049763392 M:978063360 DEBUG: OnKey: 61467 pressed, action is 18
19:43:05 T:139658049763392 M:978063360 DEBUG: Switching to GUI
19:43:05 T:139658049763392 M:978063360 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:43:05 T:139658049763392 M:978063360 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:43:05 T:139658049763392 M:978063360 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:43:05 T:139658049763392 M:978063360 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:43:05 T:139658049763392 M:978063360 INFO: Loading skin file: MyVideoNav.xml
19:43:05 T:139658049763392 M:978063360 DEBUG: Load MyVideoNav.xml: 46.22ms
19:43:05 T:139658049763392 M:978063360 DEBUG: Alloc resources: 51.29ms (48.58 ms skin load)
19:43:05 T:139658049763392 M:978063360 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
19:43:05 T:139658049763392 M:978063360 DEBUG: ParentPath = [videodb://1/2/]
19:43:05 T:139658049763392 M:978063360 DEBUG: GetSetsNav query: select sets.idSet,sets.strSet,count(1),count(files.playCount) from sets join setlinkmovie on sets.idSet=setlinkmovie.idSet join movie on setlinkmovie.idMovie=movie.idMovie join files on files.idFile=movie.idFile group by sets.idSet
19:43:05 T:139658049763392 M:978063360 DEBUG: GetMoviesByWhere query: select * from movieview where movieview.idmovie NOT in (select idmovie from setlinkmovie)
19:43:05 T:139658049763392 M:978063360 DEBUG: Time for actual SQL query = 4
19:43:05 T:139658049763392 M:978063360 DEBUG: Time to retrieve movies from dataset = 13
19:43:05 T:139658049763392 M:978063360 DEBUG: Saving fileitems [videodb://1/2/]
19:43:05 T:139658049763392 M:978571264 DEBUG: -- items: 102, sort method: 23, ascending: true
19:43:05 T:139657684846352 M:978571264 DEBUG: thread start, auto delete: 0
19:43:05 T:139658049763392 M:978579456 DEBUG: Now in GUI
19:43:05 T:139658049763392 M:978579456 INFO: GL: Disabling VSYNC
19:43:05 T:139657684846352 M:973746176 DEBUG: Thread 139657684846352 terminating
19:43:06 T:139658049763392 M:947130368 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0
19:43:06 T:139658049763392 M:947130368 DEBUG: OnKey: 61467 pressed, action is 10
19:43:06 T:139658049763392 M:947130368 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:43:06 T:139658049763392 M:947130368 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:43:06 T:139658049763392 M:947130368 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:43:06 T:139658049763392 M:947130368 DEBUG: ------ Window Init (Home.xml) ------
19:43:06 T:139658049763392 M:947130368 INFO: Loading skin file: Home.xml
19:43:06 T:139658049763392 M:947130368 DEBUG: Load Home.xml: 12.96ms
19:43:06 T:139658049763392 M:947130368 DEBUG: Alloc resources: 13.87ms (13.32 ms skin load)
19:43:06 T:139658049763392 M:929099776 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000)
19:43:06 T:139658049763392 M:929099776 DEBUG: ExecuteXBMCAction : To SetFocus(9000)
19:43:08 T:139658049763392 M:907010048 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(ShutdownMenu)
19:43:08 T:139658049763392 M:907010048 DEBUG: ExecuteXBMCAction : To ActivateWindow(ShutdownMenu)
19:43:08 T:139658049763392 M:907010048 DEBUG: Activating window ID: 10111
19:43:08 T:139658049763392 M:907010048 DEBUG: Checking if window ID 10111 is locked.
19:43:08 T:139658049763392 M:907010048 DEBUG: ------ Window Init (DialogButtonMenu.xml) ------
19:43:08 T:139658049763392 M:907010048 INFO: Loading skin file: DialogButtonMenu.xml
19:43:08 T:139658049763392 M:907010048 DEBUG: Load DialogButtonMenu.xml: 2.78ms
19:43:08 T:139658049763392 M:907010048 DEBUG: Alloc resources: 4.95ms (2.87 ms skin load)
19:43:09 T:139658049763392 M:928468992 DEBUG: ExecuteXBMCAction : Translating XBMC.Quit()
19:43:09 T:139658049763392 M:928468992 DEBUG: ExecuteXBMCAction : To XBMC.Quit()
19:43:09 T:139657623426832 M:928468992 DEBUG: Thread 139657623426832 terminating (autodelete)
19:43:09 T:139657631819536 M:928468992 DEBUG: Thread 139657631819536 terminating (autodelete)
19:43:09 T:139657642882832 M:928595968 DEBUG: Thread 139657642882832 terminating (autodelete)
19:43:09 T:139657651275536 M:928595968 DEBUG: Thread 139657651275536 terminating (autodelete)
19:43:09 T:139658049763392 M:928595968 NOTICE: Storing total System Uptime
19:43:09 T:139658049763392 M:928595968 NOTICE: Saving settings
19:43:09 T:139658049763392 M:928595968 NOTICE: stop all
19:43:09 T:139658049763392 M:928595968 DEBUG: NetworkMessage - Signaling network services to stop
19:43:09 T:139658049763392 M:928595968 NOTICE: ES: Stopping event server
19:43:09 T:139658049763392 M:928595968 DEBUG: NetworkMessage - Waiting for network services to stop
19:43:09 T:139658049763392 M:928595968 NOTICE: stopping zeroconf publishing
19:43:09 T:139658049763392 M:928595968 DEBUG: CLastfmScrobbler: Journal with 0 entries saved to special://masterprofile/LastfmScrobbler.xml
19:43:09 T:139658049763392 M:928595968 DEBUG: CLibrefmScrobbler: Journal with 0 entries saved to special://masterprofile/LibrefmScrobbler.xml
19:43:09 T:139657668060944 M:928595968 NOTICE: DS: DBUS server stopped
19:43:09 T:139657668060944 M:928595968 DEBUG: Thread 139657668060944 terminating
19:43:09 T:139657676453648 M:928595968 NOTICE: ES: UDP Event server stopped
19:43:09 T:139657676453648 M:928595968 DEBUG: Thread 139657676453648 terminating
19:43:09 T:139658049763392 M:928595968 NOTICE: stop dvd detect media
19:43:09 T:139658049763392 M:928595968 NOTICE: stop player
19:43:09 T:139658049763392 M:928595968 NOTICE: CDVDPlayer::CloseFile()
19:43:09 T:139657596323600 M:928595968 DEBUG: OnPlayBackSpeedChanged - Playback speed changed
19:43:09 T:139658049763392 M:928595968 NOTICE: DVDPlayer: waiting for threads to exit
19:43:09 T:139657596323600 M:928595968 NOTICE: CDVDPlayer::OnExit()
19:43:09 T:139657596323600 M:928595968 NOTICE: DVDPlayer: closing audio stream
19:43:09 T:139657596323600 M:928595968 NOTICE: Closing audio stream
19:43:09 T:139657596323600 M:928595968 NOTICE: Waiting for audio thread to exit
19:43:09 T:139657552324368 M:928595968 ERROR: AddPackets - failed to add leftover bytes to render
19:43:09 T:139657552324368 M:928595968 NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:43:09 T:139657552324368 M:928595968 DEBUG: Thread 139657552324368 terminating
19:43:09 T:139657596323600 M:928763904 NOTICE: Closing audio device
19:43:09 T:139657596323600 M:928763904 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
19:43:09 T:139657596323600 M:928763904 DEBUG: RemoveActiveDevice - Removing device 2
19:43:09 T:139657596323600 M:928763904 NOTICE: Deleting audio codec
19:43:09 T:139657596323600 M:928763904 NOTICE: DVDPlayer: closing video stream
19:43:09 T:139657596323600 M:928763904 NOTICE: Closing video stream
19:43:09 T:139657596323600 M:928763904 NOTICE: waiting for video thread to exit
19:43:09 T:139657560717072 M:928763904 NOTICE: thread end: video_thread
19:43:09 T:139657560717072 M:928763904 DEBUG: Thread 139657560717072 terminating
19:43:09 T:139657596323600 M:928763904 NOTICE: deleting video codec
19:43:09 T:139657596323600 M:928763904 NOTICE: CDVDPlayer::OnExit() deleting demuxer
19:43:09 T:139657596323600 M:928763904 NOTICE: CDVDPlayer::OnExit() deleting input stream
19:43:09 T:139657596323600 M:928763904 DEBUG: OnPlayBackStopped - Playback was stopped
19:43:09 T:139657596323600 M:928763904 DEBUG: Thread 139657596323600 terminating
19:43:09 T:139658049763392 M:928763904 NOTICE: DVDPlayer: finished waiting
19:43:09 T:139658049763392 M:928763904 DEBUG: LinuxRendererGL: Cleaning up GL resources
19:43:09 T:139658049763392 M:928763904 DEBUG: Deleted YV12 texture 0
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 1 plane 1
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 1 plane 2
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 1 plane 3
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 2 plane 1
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 2 plane 2
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 2 plane 3
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 3 plane 1
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 3 plane 2
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 3 plane 3
19:43:09 T:139658049763392 M:928763904 DEBUG: Deleted YV12 texture 1
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 1 plane 1
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 1 plane 2
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 1 plane 3
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 2 plane 1
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 2 plane 2
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 2 plane 3
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 3 plane 1
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 3 plane 2
19:43:09 T:139658049763392 M:928763904 DEBUG: GL: Deleting texture field 3 plane 3
19:43:09 T:139658049763392 M:928763904 NOTICE: stop sap announcement listener
19:43:09 T:139658049763392 M:928763904 NOTICE: clean cached files!
19:43:09 T:139658049763392 M:928763904 NOTICE: unload skin
19:43:09 T:139658049763392 M:928763904 DEBUG: ------ Window Deinit (Home.xml) ------
19:43:09 T:139658049763392 M:928763904 DEBUG: ------ Window Deinit (Pointer.xml) ------
19:43:09 T:139658049763392 M:928763904 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
19:43:09 T:139658049763392 M:928763904 WARNING: Cleanup: Having to cleanup texture MenuItemFO.png
19:43:09 T:139658049763392 M:928763904 WARNING: Cleanup: Having to cleanup texture MenuItemNF.png
19:43:09 T:139658049763392 M:928763904 WARNING: Cleanup: Having to cleanup texture icon_sync.png
19:43:09 T:139658049763392 M:928763904 WARNING: Cleanup: Having to cleanup texture flags/en.gif
19:43:09 T:139658049763392 M:928763904 WARNING: Cleanup: Having to cleanup texture info_window_icon_rating_star_10.png
19:43:09 T:139658049763392 M:928763904 WARNING: Cleanup: Having to cleanup texture info_window_icon_rating_star_0.png
19:43:09 T:139658049763392 M:928763904 WARNING: Cleanup: Having to cleanup texture info_window_icon_rating_star_8.png
19:43:09 T:139658049763392 M:928763904 NOTICE: stop python
19:43:09 T:139658049763392 M:928763904 NOTICE: stopped
19:43:09 T:139658049763392 M:928763904 NOTICE: destroy
19:43:09 T:139658049763392 M:928763904 NOTICE: unload sections
19:43:09 T:139658049763392 M:928763904 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/ImageLib-x86_64-linux.so)
19:43:09 T:139658049763392 M:928763904 DEBUG: Unloading: ImageLib-x86_64-linux.so
19:43:09 T:139658049763392 M:928763904 DEBUG: SECTION:UnloadAll(DLL: libcurl.so.4)
19:43:09 T:139658049763392 M:928763904 DEBUG: Unloading: libcurl.so.4
19:43:09 T:139658049763392 M:928763904 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/avutil-50-x86_64-linux.so)
19:43:09 T:139658049763392 M:928763904 DEBUG: Unloading: avutil-50-x86_64-linux.so
19:43:09 T:139658049763392 M:928763904 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/avcodec-52-x86_64-linux.so)
19:43:09 T:139658049763392 M:928763904 DEBUG: Unloading: avcodec-52-x86_64-linux.so
19:43:09 T:139658049763392 M:928763904 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/avformat-52-x86_64-linux.so)
19:43:09 T:139658049763392 M:928763904 DEBUG: Unloading: avformat-52-x86_64-linux.so
19:43:09 T:139658049763392 M:929153024 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/swscale-0.6.1-x86_64-linux.so)
19:43:09 T:139658049763392 M:929153024 DEBUG: Unloading: swscale-0.6.1-x86_64-linux.so
19:43:09 T:139658049763392 M:929153024 DEBUG: SECTION:UnloadAll(DLL: libmad.so.0)
19:43:09 T:139658049763392 M:929153024 DEBUG: Unloading: libmad.so.0
19:43:09 T:139658049763392 M:929153024 DEBUG: CLastfmScrobbler: Journal with 0 entries saved to special://masterprofile/LastfmScrobbler.xml
19:43:09 T:139658049763392 M:929153024 DEBUG: CLibrefmScrobbler: Journal with 0 entries saved to special://masterprofile/LibrefmScrobbler.xml
19:43:09 T:139658049763392 M:929153024 INFO: lastfm destroyed
19:43:09 T:139658049763392 M:929153024 DEBUG: object 0 --> 0 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 1 --> 1 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 2 --> 16 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 3 --> 0 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 4 --> 16 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 5 --> 0 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 6 --> 0 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 7 --> 0 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 8 --> 0 instances
19:43:09 T:139658049763392 M:929153024 DEBUG: object 9 --> 0 instances
19:43:10 T:139658049763392 M:930312192 DEBUG: LinuxRendererGL: Cleaning up GL resources