rendered paste body12:35:07 T:140737352747072 M:1118633984 NOTICE: -----------------------------------------------------------------------
12:35:07 T:140737352747072 M:1118633984 NOTICE: Starting XBMC, Platform: GNU/Linux. Built on Dec 17 2009 (SVN:25783)
12:35:07 T:140737352747072 M:1118633984 NOTICE: special://xbmc/ is mapped to: /home/dorphell/tmp/xbmc-svn/src/XBMC/
12:35:07 T:140737352747072 M:1118633984 NOTICE: special://masterprofile/ is mapped to: /home/dorphell/.xbmc/userdata
12:35:07 T:140737352747072 M:1118633984 NOTICE: special://home/ is mapped to: /home/dorphell/.xbmc
12:35:07 T:140737352747072 M:1118633984 NOTICE: special://temp/ is mapped to: /home/dorphell/.xbmc/temp
12:35:07 T:140737352747072 M:1118633984 NOTICE: The executable running is: /usr/share/xbmc/xbmc.bin
12:35:07 T:140737352747072 M:1118633984 NOTICE: Log File is located: /home/dorphell/.xbmc/temp/xbmc.log
12:35:07 T:140737352747072 M:1118633984 NOTICE: -----------------------------------------------------------------------
12:35:07 T:140737352747072 M:1117872128 NOTICE: Setup SDL
12:35:07 T:140737352747072 M:1116602368 NOTICE: load settings...
12:35:07 T:140737352747072 M:1116602368 NOTICE: special://profile/ is mapped to: special://masterprofile/
12:35:07 T:140737352747072 M:1116602368 NOTICE: loading special://masterprofile/guisettings.xml
12:35:07 T:140737352747072 M:1116221440 NOTICE: Enabled debug logging due to GUI setting (2)
12:35:07 T:140737352747072 M:1116221440 NOTICE: Getting hardware information now...
12:35:07 T:140737352747072 M:1116221440 INFO: Using analog output
12:35:07 T:140737352747072 M:1116221440 INFO: AC3 pass through is enabled
12:35:07 T:140737352747072 M:1116221440 INFO: DTS pass through is enabled
12:35:07 T:140737352747072 M:1116221440 NOTICE: Checking resolution 11
12:35:07 T:140737352747072 M:1116094464 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/rtmp/mms/udp
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: flv/aacp/sdp
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdfile
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
12:35:07 T:140737352747072 M:1116094464 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
12:35:07 T:140737352747072 M:1116094464 NOTICE: Loaded playercorefactory configuration
12:35:07 T:140737352747072 M:1116094464 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
12:35:07 T:140737352747072 M:1116094464 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
12:35:07 T:140737352747072 M:1116094464 NOTICE: Loaded advancedsettings.xml from special://profile/advancedsettings.xml
12:35:07 T:140737352747072 M:1116094464 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>
12:35:07 T:140737352747072 M:1116094464 NOTICE: Enabled debug logging due to GUI setting (2)
12:35:07 T:140737352747072 M:1116094464 NOTICE: Getting hardware information now...
12:35:07 T:140737352747072 M:1116094464 INFO: Using analog output
12:35:07 T:140737352747072 M:1116094464 INFO: AC3 pass through is enabled
12:35:07 T:140737352747072 M:1116094464 INFO: DTS pass through is enabled
12:35:07 T:140737352747072 M:1116094464 NOTICE: Checking resolution 11
12:35:07 T:140737352747072 M:1116094464 NOTICE: Default DVD Player: dvdplayer
12:35:07 T:140737352747072 M:1116094464 NOTICE: Default Video Player: dvdplayer
12:35:07 T:140737352747072 M:1116094464 NOTICE: Default Audio Player: paplayer
12:35:07 T:140737352747072 M:1116094464 NOTICE: special://masterprofile/sources.xml
12:35:07 T:140737352747072 M:1116094464 INFO: XRANDR: /home/dorphell/tmp/xbmc-svn/src/XBMC//xbmc-xrandr --output default --mode 0x1b6
12:35:07 T:140737352747072 M:1111228416 NOTICE: Using fbConfig[0]
12:35:07 T:140737352747072 M:1110593536 INFO: GL: Maximum texture width: 4096
12:35:07 T:140737352747072 M:1110593536 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/ImageLib-x86_64-linux.so
12:35:07 T:140737352747072 M:1111220224 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
12:35:07 T:140737352747072 M:1111220224 NOTICE: GL_VENDOR = NVIDIA Corporation
12:35:07 T:140737352747072 M:1111220224 NOTICE: GL_RENDERER = GeForce 7600 GT/PCI/SSE2
12:35:07 T:140737352747072 M:1111220224 NOTICE: GL_VERSION = 2.1.2 NVIDIA 190.42
12:35:07 T:140737352747072 M:1111220224 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
12:35:07 T:140737352747072 M:1111220224 INFO: GL: Maximum texture width: 4096
12:35:07 T:140737352747072 M:1111220224 INFO: load language info file: special://xbmc/language/English/langinfo.xml
12:35:07 T:140737352747072 M:1107791872 INFO: load language file:special://xbmc/language/English/strings.xml
12:35:07 T:140737352747072 M:1106903040 INFO: load keymapping
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/.svn/
12:35:07 T:140737352747072 M:1106903040 ERROR: Error loading keymap: special://xbmc/system/keymaps/.svn/, Line 0
Error document empty.
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/keymaps/remote.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://masterprofile/keymaps/keyboard.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://profile/keymaps/keyboard.xml
12:35:07 T:140737352747072 M:1106903040 INFO: Loading special://xbmc/system/Lircmap.xml
12:35:07 T:140737352747072 M:1106903040 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
12:35:07 T:140737352747072 M:1106903040 INFO: Checking skin version of: PM3.HD
12:35:07 T:140737352747072 M:1106903040 INFO: Skin version is: 2.11 (2.110000)
12:35:07 T:140737352747072 M:1106903040 INFO: GUI format 1280x720 Windowed
12:35:07 T:140737352747072 M:1106903040 DEBUG: Selected HAL as storage provider
12:35:07 T:140737352747072 M:1106903040 INFO: HAL: Starting initializing
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Clearing old global device list, if any
12:35:07 T:140737352747072 M:1106903040 NOTICE: HAL: Generating global device list
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID 4d87e433-2f9e-54d1-b65f-9fee54559b93 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID 1da5e7a1-db08-e795-fa16-e7f8196d6eb1 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID ac735b4d-02bb-9c56-3487-c336ce80ed28 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID aeccdb00-3ed2-a677-5fcf-5d0a431d7d27 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID 1da5e7a1-db08-e795-fa16-e7f8196d6eb1 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID ac735b4d-02bb-9c56-3487-c336ce80ed28 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID 4d87e433-2f9e-54d1-b65f-9fee54559b93 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID aeccdb00-3ed2-a677-5fcf-5d0a431d7d27 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID 1da5e7a1-db08-e795-fa16-e7f8196d6eb1 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID ac735b4d-02bb-9c56-3487-c336ce80ed28 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID 4d87e433-2f9e-54d1-b65f-9fee54559b93 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 DEBUG: HAL: Added - disk | UUID aeccdb00-3ed2-a677-5fcf-5d0a431d7d27 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO
12:35:07 T:140737352747072 M:1106903040 INFO: HAL: Generated global device list, found 191
12:35:07 T:140737352747072 M:1106903040 INFO: HAL: Sucessfully initialized
12:35:07 T:140737352747072 M:1106903040 INFO: creating subdirectories
12:35:07 T:140737352747072 M:1106903040 INFO: userdata folder: special://masterprofile/
12:35:07 T:140737352747072 M:1106903040 INFO: recording folder:
12:35:07 T:140737352747072 M:1106903040 INFO: screenshots folder:/home/dorphell/tmp/
12:35:07 T:140737352747072 M:1106903040 INFO: thumbnails folder: special://masterprofile/Thumbnails
12:35:07 T:140737352747072 M:1106903040 NOTICE: start dvd mediatype detection
12:35:07 T:140737085613840 M:1106903040 DEBUG: thread start, auto delete: 0
12:35:07 T:140737352747072 M:1106903040 NOTICE: initializing playlistplayer
12:35:07 T:140737352747072 M:1106903040 NOTICE: DONE initializing playlistplayer
12:35:07 T:140737085613840 M:1106903040 DEBUG: Compiled with libcdio Version 0.82
12:35:07 T:140737352747072 M:1106903040 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
12:35:07 T:140737352747072 M:1106903040 NOTICE: load default skin:[PM3.HD]
12:35:07 T:140737352747072 M:1106903040 INFO: load skin from:special://xbmc/skin/PM3.HD
12:35:07 T:140737352747072 M:1106903040 INFO: delete old skin...
12:35:07 T:140737352747072 M:1106903040 DEBUG: ------ Window Deinit (Pointer.xml) ------
12:35:07 T:140737352747072 M:1106903040 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
12:35:07 T:140737085613840 M:1106903040 DEBUG: Thread 140737085613840 terminating
12:35:07 T:140737352747072 M:1106903040 INFO: Default 4:3 resolution directory is special://xbmc/skin/PM3.HD/720p
12:35:07 T:140737352747072 M:1106903040 INFO: Default 16:9 resolution directory is special://xbmc/skin/PM3.HD/720p
12:35:07 T:140737352747072 M:1106903040 INFO: Skin version is: 2.11
12:35:07 T:140737352747072 M:1106903040 INFO: Loading skin includes from /home/dorphell/tmp/xbmc-svn/src/XBMC/skin/PM3.HD/720p/includes.xml
12:35:07 T:140737352747072 M:1104125952 INFO: load fonts for skin...
12:35:07 T:140737352747072 M:1104125952 INFO: Loading fonts from special://xbmc/skin/PM3.HD/720p/Font.xml
12:35:07 T:140737352747072 M:1103482880 INFO: load new skin...
12:35:07 T:140737352747072 M:1103482880 INFO: Skin version is: 2.11 (2.110000)
12:35:07 T:140737352747072 M:1103482880 INFO: Loading skin file: Home.xml
12:35:07 T:140737352747072 M:1102848000 DEBUG: Load Home.xml: 15.33ms
12:35:07 T:140737352747072 M:1102848000 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
12:35:07 T:140737352747072 M:1102848000 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
12:35:07 T:140737352747072 M:1102848000 INFO: Loading user windows, path special://xbmc/skin/PM3.HD/720p
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: special://xbmc/skin/PM3.HD/720p/custom_Credits_1112.xml
12:35:07 T:140737352747072 M:1102848000 DEBUG: Load Skin XML: 31.47ms
12:35:07 T:140737352747072 M:1102848000 INFO: initialize new skin...
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: Pointer.xml
12:35:07 T:140737352747072 M:1102848000 DEBUG: Load Pointer.xml: 0.49ms
12:35:07 T:140737352747072 M:1102848000 DEBUG: Alloc resources: 1.59ms (0.54 ms skin load)
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: DialogVolumeBar.xml
12:35:07 T:140737352747072 M:1102848000 DEBUG: Load DialogVolumeBar.xml: 0.91ms
12:35:07 T:140737352747072 M:1102848000 DEBUG: Alloc resources: 1.02ms (0.98 ms skin load)
12:35:07 T:140737352747072 M:1102848000 INFO: Loading skin file: DialogSeekBar.xml
12:35:07 T:140737352747072 M:1102721024 DEBUG: Load DialogSeekBar.xml: 3.02ms
12:35:07 T:140737352747072 M:1102721024 DEBUG: Alloc resources: 3.20ms (3.14 ms skin load)
12:35:07 T:140737352747072 M:1102721024 INFO: Loading skin file: DialogKaiToast.xml
12:35:07 T:140737352747072 M:1102721024 DEBUG: Load DialogKaiToast.xml: 0.66ms
12:35:07 T:140737352747072 M:1102721024 DEBUG: Alloc resources: 0.72ms (0.72 ms skin load)
12:35:07 T:140737352747072 M:1102721024 INFO: Loading skin file: DialogMuteBug.xml
12:35:07 T:140737352747072 M:1102721024 DEBUG: Load DialogMuteBug.xml: 0.41ms
12:35:07 T:140737352747072 M:1102721024 DEBUG: Alloc resources: 0.47ms (0.47 ms skin load)
12:35:07 T:140737352747072 M:1102721024 DEBUG: CGUIAudioManager::Initialize
12:35:07 T:140737352747072 M:1102204928 INFO: Loading special://xbmc/skin/PM3.HD/sounds/sounds.xml
12:35:07 T:140737352747072 M:1102204928 INFO: skin loaded...
12:35:07 T:140737352747072 M:1102077952 DEBUG: Loading: libcurl.so.4
12:35:07 T:140737352747072 M:1101443072 DEBUG: Activating window ID: 12999
12:35:07 T:140737352747072 M:1101443072 DEBUG: Checking if window ID 12999 is locked.
12:35:07 T:140737352747072 M:1101443072 DEBUG: ------ Window Init (Startup.xml) ------
12:35:07 T:140737352747072 M:1101443072 INFO: Loading skin file: Startup.xml
12:35:07 T:140737352747072 M:1101443072 DEBUG: Load Startup.xml: 0.55ms
12:35:07 T:140737352747072 M:1101443072 DEBUG: Alloc resources: 0.60ms (0.60 ms skin load)
12:35:07 T:140737352747072 M:1101443072 INFO: removing tempfiles
12:35:07 T:140737352747072 M:1101443072 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
12:35:07 T:140737352747072 M:1101443072 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
12:35:07 T:140737352747072 M:1101443072 DEBUG: DeviceKit.Power: org.freedesktop.DBus.Error.AccessDenied - Rejected send message, 1 matched rules; type="method_call", sender=":1.2081" (uid=1000 pid=14074 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))
12:35:07 T:140737352747072 M:1101443072 NOTICE: initialize done
12:35:07 T:140737352747072 M:1101443072 NOTICE: Running the application...
12:35:07 T:140737352747072 M:1101443072 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
12:35:07 T:140737352747072 M:1101443072 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
12:35:07 T:140737352747072 M:1101443072 DEBUG: Activating window ID: 10000
12:35:07 T:140737352747072 M:1101443072 DEBUG: Checking if window ID 10000 is locked.
12:35:07 T:140737352747072 M:1101443072 DEBUG: ------ Window Deinit (Startup.xml) ------
12:35:07 T:140737352747072 M:1101443072 DEBUG: ------ Window Init (Home.xml) ------
12:35:07 T:140737352747072 M:1101443072 DEBUG: Alloc resources: 0.51ms (0.00 ms skin load)
12:35:07 T:140737352747072 M:1101443072 DEBUG: Process - no user autoexec.py (/home/dorphell/.xbmc/scripts/autoexec.py) found, skipping
12:35:07 T:140737352747072 M:1101443072 INFO: initializing python engine.
12:35:07 T:140737352747072 M:1101443072 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24-x86_64-linux.so
12:35:08 T:140737352747072 M:1098903552 DEBUG: Python wrapper library linked with internal Python library
12:35:08 T:140737352747072 M:1097740288 DEBUG: GetZipList - Processing zip://%2fhome%2fdorphell%2ftmp%2fxbmc%2dsvn%2fsrc%2fXBMC%2fsystem%2fpython%2fpython24%2ezip/sitecustomize
12:35:08 T:140737352747072 M:1095311360 DEBUG: new python thread created. id=1
12:35:08 T:140737067136784 M:1095184384 DEBUG: thread start, auto delete: 0
12:35:08 T:140737067136784 M:1095057408 DEBUG: Python thread: start processing
12:35:08 T:140737352747072 M:1095057408 DEBUG: NetworkMessage - Starting network services
12:35:08 T:140737352747072 M:1095057408 NOTICE: ES: Starting event server
12:35:08 T:140737058744080 M:1094914048 DEBUG: thread start, auto delete: 0
12:35:08 T:140737058744080 M:1094914048 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
12:35:08 T:140737058744080 M:1094914048 NOTICE: UDP: Listening on port 9777
12:35:08 T:140737352747072 M:1094914048 NOTICE: DS: Starting dbus server
12:35:08 T:140737058744080 M:1094914048 INFO: CZeroconfAvahi::clientCallback: avahi server not available. But may become later...
12:35:08 T:140737352747072 M:1095327744 NOTICE: starting zeroconf publishing
12:35:08 T:140737352747072 M:1095327744 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
12:35:08 T:140737352747072 M:1095327744 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
12:35:08 T:140737352747072 M:1095327744 DEBUG: CLastfmScrobbler: Clearing session.
12:35:08 T:140737352747072 M:1095327744 DEBUG: CLibrefmScrobbler: Clearing session.
12:35:08 T:140737041958672 M:1095327744 DEBUG: thread start, auto delete: 0
12:35:08 T:140737041958672 M:1095327744 NOTICE: DS: Starting DBUS server in Run Application aka thread
12:35:08 T:140737352747072 M:1095327744 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
12:35:08 T:140737352747072 M:1095327744 INFO: GL: Disabling VSYNC
12:35:08 T:140737067136784 M:1095200768 NOTICE: -->Python Interpreter Initialized<--
12:35:08 T:140737067136784 M:1095200768 DEBUG: Process - The source file to load is /home/dorphell/tmp/xbmc-svn/src/XBMC/scripts/autoexec.py
12:35:08 T:140737067136784 M:1095200768 DEBUG: Process - Setting the Python path to /home/dorphell/tmp/xbmc-svn/src/XBMC/scripts:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python24.zip:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/plat-linux2:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/lib-tk:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/lib-dynload
12:35:08 T:140737067136784 M:1095200768 DEBUG: Process - Entering source directory /home/dorphell/tmp/xbmc-svn/src/XBMC/scripts
12:35:08 T:140737067136784 M:1095200768 INFO: Scriptresult: Success
12:35:08 T:140737033565968 M:1095200768 DEBUG: thread start, auto delete: 1
12:35:08 T:140736951482128 M:1079226368 DEBUG: thread start, auto delete: 0
12:35:08 T:140736951482128 M:1079226368 DEBUG: FileCurl::Open(0x7fffdfffda40) http://feeds.feedburner.com/xbmc
12:35:08 T:140736951482128 M:1079226368 INFO: easy_aquire - Created session to http://feeds.feedburner.com
12:35:08 T:140736949131024 M:1079242752 DEBUG: thread start, auto delete: 1
12:35:08 T:140736949131024 M:1079242752 INFO: WEATHER: Downloading weather
12:35:08 T:140736949131024 M:1079242752 DEBUG: FileCurl::Open(0x7fffdfdbfa60) http://xoap.weather.com/weather/local/02461?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
12:35:08 T:140736949131024 M:1079242752 INFO: easy_aquire - Created session to http://xoap.weather.com
12:35:08 T:140737067136784 M:1081155584 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/time.so. flags: 2
12:35:08 T:140737067136784 M:1081028608 DEBUG: Loading: /home/dorphell/.xbmc/temp/time.so
12:35:08 T:140737067136784 M:1081028608 DEBUG: RegisterExtensionLib, adding time.so (0x7fffe01f8910)
12:35:08 T:140737067136784 M:1081028608 DEBUG: xbp_dlsym - load symbol inittime
12:35:08 T:140737067136784 M:1080901632 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/collections.so. flags: 2
12:35:08 T:140737067136784 M:1080774656 DEBUG: Loading: /home/dorphell/.xbmc/temp/collections.so
12:35:08 T:140737352747072 M:1080647680 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
12:35:08 T:140737067136784 M:1080647680 DEBUG: RegisterExtensionLib, adding collections.so (0x7fffe014c830)
12:35:08 T:140737067136784 M:1080647680 DEBUG: xbp_dlsym - load symbol initcollections
12:35:08 T:140737067136784 M:1080647680 INFO: Python script stopped
12:35:08 T:140737067136784 M:1080647680 DEBUG: Thread 140737067136784 terminating
12:35:08 T:140737352747072 M:1080647680 DEBUG: python thread 1 destructed
12:35:08 T:140737352747072 M:1080647680 INFO: Python, unloading python24.dll because no scripts are running anymore
12:35:08 T:140737352747072 M:1080647680 DEBUG: UnloadExtensionLibs, clearing python extension libraries
12:35:08 T:140737352747072 M:1080647680 DEBUG: Unloading: time.so
12:35:08 T:140736951482128 M:1080647680 DEBUG: FileCurl::Close(0x7fffdfffda40) http://feeds.feedburner.com/xbmc
12:35:08 T:140736951482128 M:1080647680 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc
12:35:08 T:140736951482128 M:1080647680 DEBUG: RSS feed encoding: ISO-8859-1
12:35:08 T:140736951482128 M:1080647680 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc
12:35:08 T:140736951482128 M:1080647680 DEBUG: Thread 140736951482128 terminating
12:35:08 T:140737352747072 M:1080647680 DEBUG: Unloading: collections.so
12:35:08 T:140737352747072 M:1080528896 DEBUG: Unloading: python24-x86_64-linux.so
12:35:08 T:140737352747072 M:1080655872 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
12:35:08 T:140737352747072 M:1080655872 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
12:35:08 T:140736949131024 M:1080537088 DEBUG: FileCurl::Close(0x7fffdfdbfa60) http://xoap.weather.com/weather/local/02461?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
12:35:08 T:140736949131024 M:1080537088 INFO: WEATHER: Weather download successful
12:35:08 T:140737352747072 M:1076600832 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
12:35:08 T:140737352747072 M:1076600832 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
12:35:09 T:140737352747072 M:1078616064 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 4 seconds.
12:35:11 T:140737352747072 M:1061785600 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Videos)
12:35:11 T:140737352747072 M:1061785600 DEBUG: ExecuteXBMCAction : To ActivateWindow(Videos)
12:35:11 T:140737352747072 M:1061785600 DEBUG: Activating window ID: 10025
12:35:11 T:140737352747072 M:1061785600 DEBUG: Checking if window ID 10024 is locked.
12:35:11 T:140737352747072 M:1061785600 DEBUG: ------ Window Deinit (Home.xml) ------
12:35:11 T:140737352747072 M:1068007424 INFO: Attempting to default to: MovieTitles
12:35:11 T:140737352747072 M:1068007424 DEBUG: ------ Window Init (MyVideoNav.xml) ------
12:35:11 T:140737352747072 M:1068007424 INFO: Loading skin file: MyVideoNav.xml
12:35:11 T:140737352747072 M:1068007424 DEBUG: Load MyVideoNav.xml: 31.57ms
12:35:11 T:140737352747072 M:1068007424 DEBUG: Alloc resources: 33.99ms (32.73 ms skin load)
12:35:11 T:140737352747072 M:1068007424 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
12:35:11 T:140737352747072 M:1068007424 DEBUG: ParentPath = [videodb://1/2/]
12:35:11 T:140737352747072 M:1068007424 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
12:35:11 T:140737352747072 M:1068007424 DEBUG: GetMoviesByWhere query: select * from movieview where movieview.idmovie NOT in (select idmovie from setlinkmovie)
12:35:11 T:140737352747072 M:1068007424 DEBUG: Time for actual SQL query = 3
12:35:11 T:140737352747072 M:1068007424 DEBUG: Time to retrieve movies from dataset = 14
12:35:11 T:140737352747072 M:1068007424 DEBUG: Saving fileitems [videodb://1/2/]
12:35:11 T:140737352747072 M:1068515328 DEBUG: -- items: 103, sort method: 23, ascending: true
12:35:11 T:140737067136784 M:1068380160 DEBUG: thread start, auto delete: 0
12:35:11 T:140737067136784 M:1067999232 DEBUG: Thread 140737067136784 terminating
12:35:13 T:140737352747072 M:1055277056 DEBUG: SDLKeyboard: scancode: 50, sym: 304, unicode: 0, modifier: 0
12:35:13 T:140737352747072 M:1055277056 DEBUG: OnKey: 61600 pressed, action is 0
12:35:13 T:140737352747072 M:1055260672 DEBUG: SDLKeyboard: scancode: 30, sym: 117, unicode: 85, modifier: 1
12:35:13 T:140737352747072 M:1047977984 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 8 seconds.
12:35:14 T:140737352747072 M:1060315136 INFO: Loading skin file: DialogContextMenu.xml
12:35:14 T:140737352747072 M:1060315136 DEBUG: Load DialogContextMenu.xml: 0.68ms
12:35:14 T:140737352747072 M:1060315136 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
12:35:14 T:140737352747072 M:1060315136 DEBUG: Alloc resources: 0.09ms (0.00 ms skin load)
12:35:16 T:140737352747072 M:1060691968 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
12:35:16 T:140737352747072 M:1060691968 DEBUG: new file set audiostream:0
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerCoreFactory::GetPlayers(/data/Video/Movies/Up.avi)
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:35:16 T:140737352747072 M:1060691968 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
12:35:16 T:140737352747072 M:1060691968 NOTICE: DVDPlayer: Opening: /data/Video/Movies/Up.avi
12:35:16 T:140736940738320 M:1060691968 DEBUG: thread start, auto delete: 1
12:35:16 T:140737352747072 M:1060691968 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
12:35:16 T:140736932345616 M:1060691968 DEBUG: thread start, auto delete: 0
12:35:16 T:140736932345616 M:1060691968 NOTICE: Creating InputStream
12:35:16 T:140736932345616 M:1060691968 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
12:35:16 T:140736932345616 M:1060691968 DEBUG: CacheSubtitles: START
12:35:16 T:140736932345616 M:1060691968 DEBUG: CacheSubtitles: Checking for common subirs...
12:35:16 T:140736932345616 M:1060691968 DEBUG: CacheSubtitles: Done (time: 1 ms)
12:35:16 T:140736932345616 M:1060691968 DEBUG: CacheSubtitles: Searching for subtitles...
12:35:16 T:140736932345616 M:1060691968 DEBUG: CacheSubtitles: Done (time: 3 ms)
12:35:16 T:140736932345616 M:1060691968 DEBUG: CacheSubtitles: END (total time: 4 ms)
12:35:16 T:140736932345616 M:1060691968 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
12:35:16 T:140736932345616 M:1060691968 NOTICE: Creating Demuxer
12:35:16 T:140736932345616 M:1060691968 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/players/dvdplayer/avutil-50-x86_64-linux.so
12:35:16 T:140736932345616 M:1060691968 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/players/dvdplayer/avcodec-52-x86_64-linux.so
12:35:16 T:140736932345616 M:1060564992 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/players/dvdplayer/avformat-52-x86_64-linux.so
12:35:16 T:140736932345616 M:1059913728 DEBUG: Open - probing detected format [avi]
12:35:16 T:140736932345616 M:1059913728 DEBUG: Open - av_find_stream_info starting
12:35:16 T:140736932345616 M:1059913728 DEBUG: ffmpeg[DEDBF710]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
12:35:16 T:140736932345616 M:1059405824 DEBUG: Open - av_find_stream_info finished
12:35:16 T:140736932345616 M:1059405824 INFO: ffmpeg[DEDBF710]: Input #0, avi, from '/data/Video/Movies/Up.avi':
12:35:16 T:140736932345616 M:1059405824 INFO: ffmpeg[DEDBF710]: Duration: 01:36:37.63, start: 0.000000, bitrate: 1012 kb/s
12:35:16 T:140736932345616 M:1059405824 INFO: ffmpeg[DEDBF710]: Stream #0.0: Video: mpeg4, yuv420p, 624x352 [PAR 1:1 DAR 39:22], 23.98 tbr, 23.98 tbn, 23.98 tbc
12:35:16 T:140736932345616 M:1059405824 INFO: ffmpeg[DEDBF710]: Stream #0.1: Audio: mp3, 48000 Hz, 2 channels, s16, 144 kb/s
12:35:16 T:140736932345616 M:1059405824 NOTICE: Opening video stream: 0 source: 256
12:35:16 T:140736932345616 M:1059405824 NOTICE: Creating video codec with codec id: 13
12:35:16 T:140736932345616 M:1059405824 DEBUG: FactoryCodec - Video: - Opening
12:35:16 T:140736932345616 M:1059405824 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/players/dvdplayer/swscale-0.6.1-x86_64-linux.so
12:35:16 T:140736932345616 M:1059151872 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2
12:35:16 T:140736932345616 M:1059151872 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
12:35:16 T:140736932345616 M:1059151872 NOTICE: Creating video thread
12:35:16 T:140736891619088 M:1059151872 DEBUG: thread start, auto delete: 0
12:35:16 T:140736891619088 M:1059151872 DEBUG: LinuxRendererGL: Cleaning up GL resources
12:35:16 T:140736891619088 M:1059151872 NOTICE: running thread: video_thread
12:35:16 T:140736891619088 M:1059151872 NOTICE: Display resolution DESKTOP : 1680x1050 @ 50.00 - Full Screen (12)
12:35:16 T:140736932345616 M:1059151872 NOTICE: Opening audio stream: 1 source: 256
12:35:16 T:140736932345616 M:1059151872 NOTICE: Finding audio codec for: 86017
12:35:16 T:140736932345616 M:1059151872 DEBUG: FactoryCodec - Audio: passthrough - Opening
12:35:16 T:140736932345616 M:1059151872 DEBUG: FactoryCodec - Audio: passthrough - Failed
12:35:16 T:140736932345616 M:1059151872 DEBUG: FactoryCodec - Audio: libmad - Opening
12:35:16 T:140736932345616 M:1059151872 DEBUG: Loading: libmad.so.0
12:35:16 T:140736932345616 M:1059151872 DEBUG: FactoryCodec - Audio: libmad - Opened
12:35:16 T:140736932345616 M:1059151872 NOTICE: Creating audio thread
12:35:16 T:140736883226384 M:1059151872 DEBUG: thread start, auto delete: 0
12:35:16 T:140736883226384 M:1059151872 NOTICE: running thread: CDVDPlayerAudio::Process()
12:35:16 T:140736932345616 M:1059151872 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: /data/Video/Movies/Up.avi
12:35:16 T:140736932345616 M:1059151872 DEBUG: SeekTime - unknown position after seek
12:35:16 T:140736932345616 M:1059151872 DEBUG: Process - starting demuxer from: 100000
12:35:16 T:140737352747072 M:1059151872 DEBUG: Activating window ID: 12005
12:35:16 T:140737352747072 M:1059151872 DEBUG: Checking if window ID 12005 is locked.
12:35:16 T:140737352747072 M:1059151872 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
12:35:16 T:140737352747072 M:1059151872 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
12:35:16 T:140737352747072 M:1059151872 INFO: Loading skin file: VideoFullScreen.xml
12:35:16 T:140737352747072 M:1059151872 DEBUG: Load VideoFullScreen.xml: 0.64ms
12:35:16 T:140737352747072 M:1059151872 DEBUG: Alloc resources: 0.71ms (0.70 ms skin load)
12:35:16 T:140737352747072 M:1059151872 INFO: Loading skin file: VideoOSD.xml
12:35:16 T:140737352747072 M:1059151872 DEBUG: Load VideoOSD.xml: 3.42ms
12:35:16 T:140737352747072 M:1059151872 DEBUG: Alloc resources: 4.33ms (0.00 ms skin load)
12:35:16 T:140737352747072 M:1059151872 INFO: Loading skin file: VideoOSDSettings.xml
12:35:16 T:140737352747072 M:1059151872 DEBUG: Load VideoOSDSettings.xml: 1.55ms
12:35:16 T:140737352747072 M:1059151872 DEBUG: Alloc resources: 0.88ms (0.00 ms skin load)
12:35:16 T:140737352747072 M:1059151872 INFO: Loading skin file: VideoOSDSettings.xml
12:35:16 T:140737352747072 M:1059151872 DEBUG: Load VideoOSDSettings.xml: 1.54ms
12:35:16 T:140737352747072 M:1059151872 DEBUG: Alloc resources: 0.05ms (0.00 ms skin load)
12:35:16 T:140737352747072 M:1059151872 DEBUG: CGUIAudioManager::DeInitialize
12:35:16 T:140736932345616 M:1058992128 INFO: Loading skin file: DialogProgress.xml
12:35:16 T:140736932345616 M:1058992128 DEBUG: Load DialogProgress.xml: 1.28ms
12:35:16 T:140736932345616 M:1058992128 DEBUG: ffmpeg[DEDBF710]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
12:35:16 T:140736891619088 M:1058992128 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
12:35:16 T:140736891619088 M:1058992128 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(102477375.000000, 1)
12:35:16 T:140736891619088 M:1058992128 DEBUG: ffmpeg[DC6E8710]: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected
12:35:16 T:140736883226384 M:1058992128 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_DELAY(506625.000000)
12:35:16 T:140736940738320 M:1058992128 DEBUG: Thread 140736940738320 terminating (autodelete)
12:35:16 T:140737352747072 M:1058992128 DEBUG: OnPlayBackStarted - Playback has started
12:35:16 T:140737352747072 M:1058992128 INFO: GL: Enabling VSYNC
12:35:16 T:140737352747072 M:1058992128 INFO: GL: Selected vsync mode 2
12:35:16 T:140737352747072 M:1058992128 NOTICE: Using GL_TEXTURE_2D
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Requested render method: 0
12:35:16 T:140737352747072 M:1058992128 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Shader compilation log:
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Shader compilation log:
12:35:16 T:140737352747072 M:1058992128 NOTICE: GL: NPOT texture support detected
12:35:16 T:140737352747072 M:1058992128 NOTICE: GL: Using GL_ARB_pixel_buffer_object
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 624 x 352
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 NOTICE: GL: Using GL_ARB_pixel_buffer_object
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 624 x 352
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:35:16 T:140737352747072 M:1058992128 DEBUG: CGUIInfoManager::SetCurrentMovie(/data/Video/Movies/Up.avi)
12:35:16 T:140737352747072 M:1058992128 ERROR: XLCDproc::SetBackLight - Unable to write to socket
12:35:16 T:140737352747072 M:1058992128 INFO: GL: ARB Imaging extension supported
12:35:17 T:140737352747072 M:1058992128 DEBUG: ------ Window Init (VideoOSD.xml) ------
12:35:17 T:140736883226384 M:1059028992 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(102984000.000000, 0)
12:35:17 T:140736883226384 M:1059028992 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through
12:35:17 T:140736883226384 M:1059028992 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec PCM - IsMusic false - IsPassthrough false - audioDevice: default:CARD=NVidia
12:35:17 T:140736883226384 M:1059028992 DEBUG: RemoveActiveDevice - Removing device 1
12:35:17 T:140736883226384 M:1059028992 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
12:35:17 T:140736883226384 M:1059028992 DEBUG: RemoveActiveDevice - Removing device 0
12:35:17 T:140736883226384 M:1059028992 DEBUG: Initialize - using alsa device default:CARD=NVidia
12:35:17 T:140736883226384 M:1059028992 DEBUG: CALSADirectSound::Initialize - packet size:4096, packet count:8, buffer size:8192
12:35:17 T:140736883226384 M:1059028992 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
12:35:17 T:140736883226384 M:1059028992 DEBUG: CDVDPlayerAudio:: Discontinuty - was:102991730.530000, should be:103008072.778424, error:16342.248424
12:35:17 T:140737352747072 M:1059028992 DEBUG: SDLKeyboard: scancode: 65, sym: 32, unicode: 32, modifier: 0
12:35:17 T:140737352747072 M:1059028992 DEBUG: OnKey: 61472 pressed, action is 12
12:35:17 T:140737352747072 M:1059028992 DEBUG: OnPlayBackPaused - Playback was paused
12:35:17 T:140737352747072 M:1059028992 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
12:35:17 T:140737352747072 M:1059028992 DEBUG: Alloc resources: 0.02ms (0.00 ms skin load)
12:35:17 T:140736883226384 M:1059028992 WARNING: CALSADirectSound::CALSADirectSound - device is not able to pause playback, will flush instead
12:35:17 T:140737352747072 M:1059028992 ERROR: XLCDproc::SetBackLight - Unable to write to socket
12:35:17 T:140737352747072 M:1059028992 DEBUG: Failed to connect to LIRC. Retry in 20s.
12:35:18 T:140737352747072 M:1058942976 DEBUG: SDLKeyboard: scancode: 29, sym: 121, unicode: 121, modifier: 0
12:35:18 T:140737352747072 M:1058942976 DEBUG: OnKey: 61529 pressed, action is 0
12:35:21 T:140737352747072 M:1059074048 DEBUG: SDLKeyboard: scancode: 50, sym: 304, unicode: 0, modifier: 0
12:35:21 T:140737352747072 M:1059074048 DEBUG: OnKey: 61600 pressed, action is 0
12:35:22 T:140737352747072 M:1059074048 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 16 seconds.
12:35:22 T:140737352747072 M:1059074048 DEBUG: SDLKeyboard: scancode: 29, sym: 121, unicode: 89, modifier: 1
12:35:22 T:140737352747072 M:1059074048 DEBUG: OnKey: 61529 pressed, action is 0
12:35:24 T:140737352747072 M:1059123200 DEBUG: SDLKeyboard: scancode: 51, sym: 92, unicode: 92, modifier: 0
12:35:24 T:140737352747072 M:1059123200 DEBUG: OnKey: 61676 pressed, action is 199
12:35:24 T:140737352747072 M:1059123200 ERROR: GLX: Same window as before, refreshing context
12:35:24 T:140737352747072 M:1059123200 INFO: GL: Maximum texture width: 4096
12:35:25 T:140737352747072 M:1059020800 DEBUG: SDLKeyboard: scancode: 29, sym: 121, unicode: 121, modifier: 0
12:35:25 T:140737352747072 M:1059020800 DEBUG: OnKey: 61529 pressed, action is 0
12:35:28 T:140737352747072 M:1059135488 DEBUG: SDLKeyboard: scancode: 50, sym: 304, unicode: 0, modifier: 0
12:35:28 T:140737352747072 M:1059135488 DEBUG: OnKey: 61600 pressed, action is 0
12:35:28 T:140737352747072 M:1058500608 DEBUG: SDLKeyboard: scancode: 29, sym: 121, unicode: 89, modifier: 1
12:35:28 T:140737352747072 M:1058500608 DEBUG: OnKey: 61529 pressed, action is 0
12:35:31 T:140737352747072 M:1058881536 DEBUG: SDLKeyboard: scancode: 50, sym: 304, unicode: 0, modifier: 0
12:35:31 T:140737352747072 M:1058881536 DEBUG: OnKey: 61600 pressed, action is 0
12:35:31 T:140737352747072 M:1058881536 DEBUG: SDLKeyboard: scancode: 29, sym: 121, unicode: 89, modifier: 1
12:35:31 T:140737352747072 M:1058881536 DEBUG: OnKey: 61529 pressed, action is 0
12:35:32 T:140737352747072 M:1058902016 DEBUG: SDLKeyboard: scancode: 51, sym: 92, unicode: 92, modifier: 0
12:35:32 T:140737352747072 M:1058902016 DEBUG: OnKey: 61676 pressed, action is 199
12:35:32 T:140737352747072 M:1058897920 ERROR: GLX: Same window as before, refreshing context
12:35:32 T:140737352747072 M:1058897920 INFO: GL: Maximum texture width: 4096
12:35:33 T:140737352747072 M:1059188736 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0
12:35:33 T:140737352747072 M:1059188736 DEBUG: OnKey: 61467 pressed, action is 51
12:35:33 T:140737352747072 M:1059188736 DEBUG: ------ Window Deinit (VideoOSD.xml) ------
12:35:34 T:140737352747072 M:1059188736 DEBUG: ------ Window Init (VideoOSD.xml) ------
12:35:35 T:140737352747072 M:1058713600 DEBUG: ExecuteXBMCAction : Translating XBMC.PlayerControl(Stop)
12:35:35 T:140737352747072 M:1058713600 DEBUG: ExecuteXBMCAction : To XBMC.PlayerControl(Stop)
12:35:35 T:140737352747072 M:1058713600 NOTICE: CDVDPlayer::CloseFile()
12:35:35 T:140737352747072 M:1058713600 NOTICE: DVDPlayer: waiting for threads to exit
12:35:35 T:140736932345616 M:1058713600 NOTICE: CDVDPlayer::OnExit()
12:35:35 T:140736932345616 M:1058713600 NOTICE: DVDPlayer: closing audio stream
12:35:35 T:140736932345616 M:1058713600 NOTICE: Closing audio stream
12:35:35 T:140736932345616 M:1058713600 NOTICE: Waiting for audio thread to exit
12:35:35 T:140736883226384 M:1058713600 DEBUG: CDVDPlayerAudio:: Discontinuty - was:103240826.593424, should be:103437341.893333, error:196515.299910
12:35:35 T:140736883226384 M:1058713600 NOTICE: thread end: CDVDPlayerAudio::OnExit()
12:35:35 T:140736883226384 M:1058713600 DEBUG: Thread 140736883226384 terminating
12:35:35 T:140736932345616 M:1058713600 NOTICE: Closing audio device
12:35:35 T:140736932345616 M:1058713600 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
12:35:35 T:140736932345616 M:1058713600 DEBUG: RemoveActiveDevice - Removing device 2
12:35:35 T:140736932345616 M:1058713600 DEBUG: CGUIAudioManager::Initialize
12:35:35 T:140736932345616 M:1058713600 NOTICE: Deleting audio codec
12:35:35 T:140736932345616 M:1058713600 NOTICE: DVDPlayer: closing video stream
12:35:35 T:140736932345616 M:1058713600 NOTICE: Closing video stream
12:35:35 T:140736932345616 M:1058713600 NOTICE: waiting for video thread to exit
12:35:35 T:140736891619088 M:1058713600 NOTICE: thread end: video_thread
12:35:35 T:140736891619088 M:1058713600 DEBUG: Thread 140736891619088 terminating
12:35:35 T:140736932345616 M:1058713600 NOTICE: deleting video codec
12:35:35 T:140736932345616 M:1058713600 NOTICE: CDVDPlayer::OnExit() deleting demuxer
12:35:35 T:140736932345616 M:1058713600 NOTICE: CDVDPlayer::OnExit() deleting input stream
12:35:35 T:140736932345616 M:1058713600 DEBUG: OnPlayBackStopped - Playback was stopped
12:35:35 T:140736932345616 M:1058713600 DEBUG: Thread 140736932345616 terminating
12:35:35 T:140737352747072 M:1058713600 NOTICE: DVDPlayer: finished waiting
12:35:35 T:140737352747072 M:1058713600 DEBUG: LinuxRendererGL: Cleaning up GL resources
12:35:35 T:140737352747072 M:1058713600 DEBUG: Deleted YV12 texture 0
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 1 plane 1
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 1 plane 2
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 1 plane 3
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 2 plane 1
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 2 plane 2
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 2 plane 3
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 3 plane 1
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 3 plane 2
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 3 plane 3
12:35:35 T:140737352747072 M:1058713600 DEBUG: Deleted YV12 texture 1
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 1 plane 1
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 1 plane 2
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 1 plane 3
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 2 plane 1
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 2 plane 2
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 2 plane 3
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 3 plane 1
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 3 plane 2
12:35:35 T:140737352747072 M:1058713600 DEBUG: GL: Deleting texture field 3 plane 3
12:35:35 T:140737352747072 M:1058713600 INFO: GL: Disabling VSYNC
12:35:35 T:140737352747072 M:1058713600 NOTICE: CDVDPlayer::CloseFile()
12:35:35 T:140737352747072 M:1058713600 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
12:35:35 T:140737352747072 M:1058713600 NOTICE: DVDPlayer: waiting for threads to exit
12:35:35 T:140737352747072 M:1058713600 NOTICE: DVDPlayer: finished waiting
12:35:35 T:140737352747072 M:1058713600 DEBUG: LinuxRendererGL: Cleaning up GL resources
12:35:35 T:140737352747072 M:1058713600 ERROR: XLCDproc::SetBackLight - Unable to write to socket
12:35:35 T:140737352747072 M:1058713600 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
12:35:35 T:140737352747072 M:1058713600 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
12:35:35 T:140737352747072 M:1058713600 DEBUG: ------ Window Deinit (VideoOSD.xml) ------
12:35:35 T:140737352747072 M:1058713600 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
12:35:35 T:140737352747072 M:1058713600 DEBUG: ------ Window Init (MyVideoNav.xml) ------
12:35:35 T:140737352747072 M:1058713600 INFO: Loading skin file: MyVideoNav.xml
12:35:35 T:140737352747072 M:1058713600 DEBUG: Load MyVideoNav.xml: 31.01ms
12:35:35 T:140737352747072 M:1058713600 DEBUG: Alloc resources: 33.60ms (32.28 ms skin load)
12:35:35 T:140737352747072 M:1058713600 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/)
12:35:35 T:140737352747072 M:1058713600 DEBUG: ParentPath = [videodb://1/2/]
12:35:35 T:140737352747072 M:1058713600 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
12:35:35 T:140737352747072 M:1058713600 DEBUG: GetMoviesByWhere query: select * from movieview where movieview.idmovie NOT in (select idmovie from setlinkmovie)
12:35:35 T:140737352747072 M:1058713600 DEBUG: Time for actual SQL query = 4
12:35:35 T:140737352747072 M:1058713600 DEBUG: Time to retrieve movies from dataset = 12
12:35:35 T:140737352747072 M:1058713600 DEBUG: Saving fileitems [videodb://1/2/]
12:35:35 T:140737352747072 M:1058713600 DEBUG: -- items: 103, sort method: 23, ascending: true
12:35:35 T:140737067136784 M:1058713600 DEBUG: thread start, auto delete: 0
12:35:35 T:140737067136784 M:1058746368 DEBUG: Thread 140737067136784 terminating
12:35:35 T:140737352747072 M:1058746368 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
12:35:35 T:140737352747072 M:1058746368 ERROR: XLCDproc::SetBackLight - Unable to write to socket
12:35:36 T:140737352747072 M:1058746368 DEBUG: SaveFileState - Saving file state for video item /data/Video/Movies/Up.avi
12:35:36 T:140737352747072 M:1058693120 DEBUG: SDLKeyboard: scancode: 133, sym: 311, unicode: 0, modifier: 0
12:35:37 T:140737352747072 M:1058983936 DEBUG: Failed to connect to LIRC. Retry in 40s.
12:35:38 T:140737352747072 M:1058975744 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 32 seconds.
12:35:39 T:140737352747072 M:1058758656 INFO: CheckIdle - Closing session to http://feeds.feedburner.com (easy=0x142fcd0, multi=0x16bd1a0)
12:35:39 T:140737352747072 M:1058758656 INFO: CheckIdle - Closing session to http://xoap.weather.com (easy=0x16beca0, multi=0x138ed00)
12:35:40 T:140736949131024 M:1057771520 DEBUG: Thread 140736949131024 terminating (autodelete)
12:35:41 T:140737033565968 M:1057865728 DEBUG: Thread 140737033565968 terminating (autodelete)
12:36:05 T:140737352747072 M:1056419840 DEBUG: SECTION:UnloadDelayed(DLL: libmad.so.0)
12:36:05 T:140737352747072 M:1056419840 DEBUG: Unloading: libmad.so.0
12:36:06 T:140737352747072 M:1056714752 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/ImageLib-x86_64-linux.so)
12:36:06 T:140737352747072 M:1056714752 DEBUG: Unloading: ImageLib-x86_64-linux.so
12:36:06 T:140737352747072 M:1056595968 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/players/dvdplayer/avformat-52-x86_64-linux.so)
12:36:06 T:140737352747072 M:1056595968 DEBUG: Unloading: avformat-52-x86_64-linux.so
12:36:10 T:140737352747072 M:1057443840 ERROR: XLCDproc::Initialize - Unable to connect to host. Retry in 64 seconds.
12:36:17 T:140737352747072 M:1058304000 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/ImageLib-x86_64-linux.so
12:36:17 T:140737352747072 M:1049743360 DEBUG: Failed to connect to LIRC. Giving up.
12:36:19 T:140737352747072 M:1057931264 INFO: Loading skin file: DialogContextMenu.xml
12:36:19 T:140737352747072 M:1057931264 DEBUG: Load DialogContextMenu.xml: 0.70ms
12:36:19 T:140737352747072 M:1057931264 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
12:36:19 T:140737352747072 M:1057931264 DEBUG: Alloc resources: 0.10ms (0.00 ms skin load)
12:36:20 T:140737352747072 M:1058250752 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
12:36:20 T:140737352747072 M:1058250752 DEBUG: new file set audiostream:0
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerCoreFactory::GetPlayers(/data/Video/Movies/Up.avi)
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
12:36:20 T:140737352747072 M:1058250752 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
12:36:20 T:140737352747072 M:1058250752 NOTICE: DVDPlayer: Opening: /data/Video/Movies/Up.avi
12:36:20 T:140737033565968 M:1058250752 DEBUG: thread start, auto delete: 1
12:36:20 T:140737352747072 M:1058250752 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
12:36:20 T:140736949131024 M:1058250752 DEBUG: thread start, auto delete: 0
12:36:20 T:140736949131024 M:1058250752 NOTICE: Creating InputStream
12:36:20 T:140736949131024 M:1058250752 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
12:36:20 T:140736949131024 M:1058250752 DEBUG: CacheSubtitles: START
12:36:20 T:140736949131024 M:1058250752 DEBUG: CacheSubtitles: Checking for common subirs...
12:36:20 T:140736949131024 M:1058250752 DEBUG: CacheSubtitles: Done (time: 1 ms)
12:36:20 T:140736949131024 M:1058250752 DEBUG: CacheSubtitles: Searching for subtitles...
12:36:20 T:140736949131024 M:1058250752 DEBUG: CacheSubtitles: Done (time: 3 ms)
12:36:20 T:140736949131024 M:1058250752 DEBUG: CacheSubtitles: END (total time: 4 ms)
12:36:20 T:140736949131024 M:1058250752 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
12:36:20 T:140736949131024 M:1058250752 NOTICE: Creating Demuxer
12:36:20 T:140736949131024 M:1058250752 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/players/dvdplayer/avformat-52-x86_64-linux.so
12:36:20 T:140736949131024 M:1058107392 DEBUG: Open - probing detected format [avi]
12:36:20 T:140736949131024 M:1056456704 DEBUG: Open - av_find_stream_info starting
12:36:20 T:140736949131024 M:1056456704 DEBUG: ffmpeg[DFDC1710]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
12:36:20 T:140736949131024 M:1056456704 DEBUG: Open - av_find_stream_info finished
12:36:20 T:140736949131024 M:1056456704 INFO: ffmpeg[DFDC1710]: Input #0, avi, from '/data/Video/Movies/Up.avi':
12:36:20 T:140736949131024 M:1056456704 INFO: ffmpeg[DFDC1710]: Duration: 01:36:37.63, start: 0.000000, bitrate: 1012 kb/s
12:36:20 T:140736949131024 M:1056456704 INFO: ffmpeg[DFDC1710]: Stream #0.0: Video: mpeg4, yuv420p, 624x352 [PAR 1:1 DAR 39:22], 23.98 tbr, 23.98 tbn, 23.98 tbc
12:36:20 T:140736949131024 M:1056456704 INFO: ffmpeg[DFDC1710]: Stream #0.1: Audio: mp3, 48000 Hz, 2 channels, s16, 144 kb/s
12:36:20 T:140736949131024 M:1056456704 NOTICE: Opening video stream: 0 source: 256
12:36:20 T:140736949131024 M:1056456704 NOTICE: Creating video codec with codec id: 13
12:36:20 T:140736949131024 M:1056456704 DEBUG: FactoryCodec - Video: - Opening
12:36:20 T:140736949131024 M:1056456704 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2
12:36:20 T:140736949131024 M:1056456704 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
12:36:20 T:140736949131024 M:1056456704 NOTICE: Creating video thread
12:36:20 T:140736940738320 M:1056456704 DEBUG: thread start, auto delete: 0
12:36:20 T:140736940738320 M:1056456704 DEBUG: LinuxRendererGL: Cleaning up GL resources
12:36:20 T:140736940738320 M:1056456704 NOTICE: running thread: video_thread
12:36:20 T:140736940738320 M:1056456704 NOTICE: Display resolution DESKTOP : 1680x1050 @ 50.00 - Full Screen (12)
12:36:20 T:140736949131024 M:1056456704 NOTICE: Opening audio stream: 1 source: 256
12:36:20 T:140736949131024 M:1056456704 NOTICE: Finding audio codec for: 86017
12:36:20 T:140736949131024 M:1056456704 DEBUG: FactoryCodec - Audio: passthrough - Opening
12:36:20 T:140736949131024 M:1056456704 DEBUG: FactoryCodec - Audio: passthrough - Failed
12:36:20 T:140736949131024 M:1056456704 DEBUG: FactoryCodec - Audio: libmad - Opening
12:36:20 T:140736949131024 M:1056456704 DEBUG: Loading: libmad.so.0
12:36:20 T:140736949131024 M:1056456704 DEBUG: FactoryCodec - Audio: libmad - Opened
12:36:20 T:140736949131024 M:1056456704 NOTICE: Creating audio thread
12:36:20 T:140736908404496 M:1056456704 DEBUG: thread start, auto delete: 0
12:36:20 T:140736908404496 M:1056456704 NOTICE: running thread: CDVDPlayerAudio::Process()
12:36:20 T:140736949131024 M:1056456704 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: /data/Video/Movies/Up.avi
12:36:20 T:140736949131024 M:1056456704 DEBUG: SeekTime - unknown position after seek
12:36:20 T:140736949131024 M:1056456704 DEBUG: Process - starting demuxer from: 103000
12:36:20 T:140736949131024 M:1056456704 DEBUG: ffmpeg[DFDC1710]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
12:36:20 T:140736908404496 M:1056456704 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_DELAY(516916.666667)
12:36:20 T:140737352747072 M:1056456704 DEBUG: Activating window ID: 12005
12:36:20 T:140737352747072 M:1056456704 DEBUG: Checking if window ID 12005 is locked.
12:36:20 T:140737352747072 M:1056456704 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
12:36:20 T:140736940738320 M:1056456704 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
12:36:20 T:140736940738320 M:1056456704 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(106523083.333333, 1)
12:36:20 T:140736940738320 M:1056456704 DEBUG: ffmpeg[DF5C0710]: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected
12:36:20 T:140737033565968 M:1055948800 DEBUG: Thread 140737033565968 terminating (autodelete)
12:36:20 T:140737352747072 M:1055948800 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
12:36:20 T:140737352747072 M:1055948800 INFO: Loading skin file: VideoFullScreen.xml
12:36:20 T:140737352747072 M:1055789056 DEBUG: Load VideoFullScreen.xml: 0.71ms
12:36:20 T:140737352747072 M:1055789056 DEBUG: Alloc resources: 0.78ms (0.77 ms skin load)
12:36:20 T:140737352747072 M:1055789056 INFO: Loading skin file: VideoOSD.xml
12:36:20 T:140737352747072 M:1055281152 DEBUG: Load VideoOSD.xml: 3.42ms
12:36:20 T:140737352747072 M:1054519296 DEBUG: Alloc resources: 4.09ms (0.00 ms skin load)
12:36:20 T:140737352747072 M:1054519296 INFO: Loading skin file: VideoOSDSettings.xml
12:36:20 T:140737352747072 M:1054392320 DEBUG: Load VideoOSDSettings.xml: 1.83ms
12:36:20 T:140737352747072 M:1054392320 DEBUG: Alloc resources: 0.97ms (0.00 ms skin load)
12:36:20 T:140737352747072 M:1054392320 INFO: Loading skin file: VideoOSDSettings.xml
12:36:20 T:140737352747072 M:1054392320 DEBUG: Load VideoOSDSettings.xml: 1.62ms
12:36:20 T:140737352747072 M:1054392320 DEBUG: Alloc resources: 0.06ms (0.00 ms skin load)
12:36:20 T:140737352747072 M:1054392320 DEBUG: CGUIAudioManager::DeInitialize
12:36:20 T:140737352747072 M:1054392320 DEBUG: OnPlayBackStarted - Playback has started
12:36:20 T:140737352747072 M:1054392320 INFO: GL: Enabling VSYNC
12:36:20 T:140737352747072 M:1054392320 INFO: GL: Selected vsync mode 2
12:36:20 T:140737352747072 M:1054392320 NOTICE: Using GL_TEXTURE_2D
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Requested render method: 0
12:36:20 T:140737352747072 M:1054392320 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Shader compilation log:
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Shader compilation log:
12:36:20 T:140737352747072 M:1054392320 NOTICE: GL: NPOT texture support detected
12:36:20 T:140737352747072 M:1054392320 NOTICE: GL: Using GL_ARB_pixel_buffer_object
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 624 x 352
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 NOTICE: GL: Using GL_ARB_pixel_buffer_object
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 624 x 352
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 624 x 176
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 DEBUG: GL: Creating YUV NPOT texture of size 312 x 88
12:36:20 T:140737352747072 M:1054392320 DEBUG: CGUIInfoManager::SetCurrentMovie(/data/Video/Movies/Up.avi)
12:36:20 T:140737352747072 M:1054392320 ERROR: XLCDproc::SetBackLight - Unable to write to socket
12:36:20 T:140736908404496 M:1054527488 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(107040000.000000, 0)
12:36:20 T:140736908404496 M:1054527488 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through
12:36:20 T:140736908404496 M:1054527488 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec PCM - IsMusic false - IsPassthrough false - audioDevice: default:CARD=NVidia
12:36:20 T:140736908404496 M:1054527488 DEBUG: RemoveActiveDevice - Removing device 1
12:36:20 T:140736908404496 M:1054527488 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
12:36:20 T:140736908404496 M:1054527488 DEBUG: RemoveActiveDevice - Removing device 0
12:36:20 T:140736908404496 M:1054527488 DEBUG: Initialize - using alsa device default:CARD=NVidia
12:36:20 T:140736908404496 M:1054392320 DEBUG: CALSADirectSound::Initialize - packet size:4096, packet count:8, buffer size:8192
12:36:20 T:140736908404496 M:1054392320 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
12:36:20 T:140736908404496 M:1054392320 DEBUG: CDVDPlayerAudio:: Discontinuty - was:107037686.587333, should be:107088072.858424, error:50386.271091
12:36:21 T:140737352747072 M:1054416896 DEBUG: SDLKeyboard: scancode: 29, sym: 121, unicode: 121, modifier: 0
12:36:21 T:140737352747072 M:1054416896 DEBUG: OnKey: 61529 pressed, action is 122
12:36:21 T:140737352747072 M:1054416896 INFO: initializing python engine.
12:36:21 T:140737352747072 M:1054416896 DEBUG: Loading: /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24-x86_64-linux.so
12:36:21 T:140737352747072 M:1052012544 DEBUG: Python wrapper library linked with internal Python library
12:36:22 T:140737352747072 M:1052012544 DEBUG: new python thread created. id=2
12:36:22 T:140736883226384 M:1052012544 DEBUG: thread start, auto delete: 0
12:36:22 T:140736883226384 M:1052012544 DEBUG: Python thread: start processing
12:36:22 T:140736883226384 M:1051877376 NOTICE: -->Python Interpreter Initialized<--
12:36:22 T:140736883226384 M:1051877376 DEBUG: Process - The source file to load is special://home/scripts/OpenSubtitles_OSD/default.py
12:36:22 T:140736883226384 M:1051877376 DEBUG: Process - Setting the Python path to /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python24.zip:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/plat-linux2:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/lib-tk:/home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/lib/python2.4/lib-dynload
12:36:22 T:140736883226384 M:1051877376 DEBUG: Process - Entering source directory /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD
12:36:22 T:140736883226384 M:1051242496 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/strop.so. flags: 2
12:36:22 T:140736883226384 M:1051242496 DEBUG: Loading: /home/dorphell/.xbmc/temp/strop.so
12:36:22 T:140736883226384 M:1050988544 DEBUG: RegisterExtensionLib, adding strop.so (0x7fffe01ec600)
12:36:22 T:140736883226384 M:1050988544 DEBUG: xbp_dlsym - load symbol initstrop
12:36:22 T:140736883226384 M:1050861568 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/math.so. flags: 2
12:36:22 T:140736883226384 M:1050861568 DEBUG: Loading: /home/dorphell/.xbmc/temp/math.so
12:36:22 T:140736883226384 M:1050607616 DEBUG: RegisterExtensionLib, adding math.so (0x7fffe0666d70)
12:36:22 T:140736883226384 M:1050607616 DEBUG: xbp_dlsym - load symbol initmath
12:36:22 T:140736883226384 M:1050607616 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/binascii.so. flags: 2
12:36:22 T:140736883226384 M:1050607616 DEBUG: Loading: /home/dorphell/.xbmc/temp/binascii.so
12:36:22 T:140736883226384 M:1050480640 DEBUG: RegisterExtensionLib, adding binascii.so (0x2c3aec0)
12:36:22 T:140736883226384 M:1050480640 DEBUG: xbp_dlsym - load symbol initbinascii
12:36:22 T:140736883226384 M:1050480640 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/_random.so. flags: 2
12:36:22 T:140736883226384 M:1050480640 DEBUG: Loading: /home/dorphell/.xbmc/temp/_random.so
12:36:22 T:140736883226384 M:2074759168 DEBUG: RegisterExtensionLib, adding _random.so (0x33694e0)
12:36:22 T:140736883226384 M:1050353664 DEBUG: xbp_dlsym - load symbol init_random
12:36:22 T:140736883226384 M:1050353664 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/fcntl.so. flags: 2
12:36:22 T:140736883226384 M:1050226688 DEBUG: Loading: /home/dorphell/.xbmc/temp/fcntl.so
12:36:22 T:140736883226384 M:1050099712 DEBUG: RegisterExtensionLib, adding fcntl.so (0x2a90d70)
12:36:22 T:140736883226384 M:1050099712 DEBUG: xbp_dlsym - load symbol initfcntl
12:36:22 T:140736883226384 M:1050226688 NOTICE: OpenSubtitles_OSD version [1.44]
12:36:22 T:140736883226384 M:1050226688 NOTICE: Skin Folder: [ pm3 hd ]
12:36:22 T:140736883226384 M:1050226688 NOTICE: OpenSubtitles_OSD skin XML: [ main ]
12:36:22 T:140736883226384 M:1050226688 NOTICE: False
12:36:22 T:140736883226384 M:1050271744 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/time.so. flags: 2
12:36:22 T:140736883226384 M:1050271744 DEBUG: Loading: /home/dorphell/.xbmc/temp/time.so
12:36:22 T:140736883226384 M:1050144768 DEBUG: RegisterExtensionLib, adding time.so (0x1caad40)
12:36:22 T:140736883226384 M:1050144768 DEBUG: xbp_dlsym - load symbol inittime
12:36:22 T:140736883226384 M:1050144768 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/operator.so. flags: 2
12:36:22 T:140736883226384 M:1050144768 DEBUG: Loading: /home/dorphell/.xbmc/temp/operator.so
12:36:22 T:140736883226384 M:1050017792 DEBUG: RegisterExtensionLib, adding operator.so (0x1cc07c0)
12:36:22 T:140736883226384 M:1050017792 DEBUG: xbp_dlsym - load symbol initoperator
12:36:22 T:140736883226384 M:1050017792 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/struct.so. flags: 2
12:36:22 T:140736883226384 M:1050017792 DEBUG: Loading: /home/dorphell/.xbmc/temp/struct.so
12:36:22 T:140736883226384 M:1049763840 DEBUG: RegisterExtensionLib, adding struct.so (0x1cb7ab0)
12:36:22 T:140736883226384 M:1049763840 DEBUG: xbp_dlsym - load symbol initstruct
12:36:22 T:140736883226384 M:1049763840 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/cStringIO.so. flags: 2
12:36:22 T:140736883226384 M:1049763840 DEBUG: Loading: /home/dorphell/.xbmc/temp/cStringIO.so
12:36:22 T:140736883226384 M:1049354240 DEBUG: RegisterExtensionLib, adding cStringIO.so (0x1cb0710)
12:36:22 T:140736883226384 M:1049354240 DEBUG: xbp_dlsym - load symbol initcStringIO
12:36:22 T:140736883226384 M:1049354240 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/pyexpat.so. flags: 2
12:36:22 T:140736883226384 M:1049608192 DEBUG: Loading: /home/dorphell/.xbmc/temp/pyexpat.so
12:36:22 T:140736883226384 M:1049227264 DEBUG: RegisterExtensionLib, adding pyexpat.so (0x1cbd720)
12:36:22 T:140736883226384 M:1049227264 DEBUG: xbp_dlsym - load symbol initpyexpat
12:36:22 T:140736883226384 M:1049227264 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/_socket.so. flags: 2
12:36:22 T:140736883226384 M:1049227264 DEBUG: Loading: /home/dorphell/.xbmc/temp/_socket.so
12:36:22 T:140736883226384 M:1048973312 DEBUG: RegisterExtensionLib, adding _socket.so (0x2c03a20)
12:36:22 T:140736883226384 M:1048973312 DEBUG: xbp_dlsym - load symbol init_socket
12:36:22 T:140736883226384 M:1048973312 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/md5.so. flags: 2
12:36:22 T:140736883226384 M:1048973312 DEBUG: Loading: /home/dorphell/.xbmc/temp/md5.so
12:36:22 T:140736883226384 M:1048719360 DEBUG: RegisterExtensionLib, adding md5.so (0x1e7dbc0)
12:36:22 T:140736883226384 M:1048719360 DEBUG: xbp_dlsym - load symbol initmd5
12:36:22 T:140736883226384 M:1048719360 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/sha.so. flags: 2
12:36:22 T:140736883226384 M:1048719360 DEBUG: Loading: /home/dorphell/.xbmc/temp/sha.so
12:36:22 T:140736883226384 M:1048592384 DEBUG: RegisterExtensionLib, adding sha.so (0x1e718c0)
12:36:22 T:140736883226384 M:1048592384 DEBUG: xbp_dlsym - load symbol initsha
12:36:22 T:140736883226384 M:1048592384 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/_bisect.so. flags: 2
12:36:22 T:140736883226384 M:1048592384 DEBUG: Loading: /home/dorphell/.xbmc/temp/_bisect.so
12:36:22 T:140736883226384 M:1048465408 DEBUG: RegisterExtensionLib, adding _bisect.so (0x1eb9740)
12:36:22 T:140736883226384 M:1048465408 DEBUG: xbp_dlsym - load symbol init_bisect
12:36:22 T:140736883226384 M:1048338432 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/collections.so. flags: 2
12:36:22 T:140736883226384 M:1048338432 DEBUG: Loading: /home/dorphell/.xbmc/temp/collections.so
12:36:22 T:140736883226384 M:1048211456 DEBUG: RegisterExtensionLib, adding collections.so (0x1f209c0)
12:36:22 T:140736883226384 M:1048211456 DEBUG: xbp_dlsym - load symbol initcollections
12:36:22 T:140736883226384 M:1048211456 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/datetime.so. flags: 2
12:36:22 T:140736883226384 M:1048211456 DEBUG: Loading: /home/dorphell/.xbmc/temp/datetime.so
12:36:22 T:140736883226384 M:1047830528 DEBUG: RegisterExtensionLib, adding datetime.so (0x1f31d20)
12:36:22 T:140736883226384 M:1047830528 DEBUG: xbp_dlsym - load symbol initdatetime
12:36:22 T:140736883226384 M:1047830528 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/zlib.so. flags: 2
12:36:22 T:140736883226384 M:1047830528 DEBUG: Loading: /home/dorphell/.xbmc/temp/zlib.so
12:36:22 T:140736883226384 M:1047703552 DEBUG: RegisterExtensionLib, adding zlib.so (0x1f5f5a0)
12:36:22 T:140736883226384 M:1047703552 DEBUG: xbp_dlsym - load symbol initzlib
12:36:22 T:140736883226384 M:1047703552 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/array.so. flags: 2
12:36:22 T:140736883226384 M:1047703552 DEBUG: Loading: /home/dorphell/.xbmc/temp/array.so
12:36:22 T:140736883226384 M:1047449600 DEBUG: RegisterExtensionLib, adding array.so (0x1fca880)
12:36:22 T:140736883226384 M:1047449600 DEBUG: xbp_dlsym - load symbol initarray
12:36:22 T:140736883226384 M:1047449600 DEBUG: xbp_dlopen loading python lib /home/dorphell/tmp/xbmc-svn/src/XBMC/system/python/python24.zip/unicodedata.so. flags: 2
12:36:22 T:140736883226384 M:1047449600 DEBUG: Loading: /home/dorphell/.xbmc/temp/unicodedata.so
12:36:22 T:140736883226384 M:1047195648 DEBUG: RegisterExtensionLib, adding unicodedata.so (0x1ff2480)
12:36:22 T:140736883226384 M:1047195648 DEBUG: xbp_dlsym - load symbol initunicodedata
12:36:22 T:140737352747072 M:1047195648 DEBUG: OnPlayBackPaused - Playback was paused
12:36:22 T:140736883226384 M:1047195648 INFO: Default 4:3 resolution directory is /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p
12:36:22 T:140736883226384 M:1047195648 INFO: Default 16:9 resolution directory is /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p
12:36:22 T:140736883226384 M:1047195648 INFO: Skin version is: 2.1
12:36:22 T:140736883226384 M:1047195648 INFO: Loading skin includes from /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/includes.xml
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Manual Search : [False]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Service : [Podnapisi]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: SL Service : [True]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: PN Service : [True]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: OS Service : [True]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Search String: [Up]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Temp?: [False]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: File Path: [/data/Video/Movies/Up.avi]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Year: [2009]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Subtitle Folder: [/data/Video/Movies/Subtitles/]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Language 1: [eng]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Language 2: [eng]
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Language 3: [eng]
12:36:22 T:140737352747072 M:1047195648 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
12:36:22 T:140737352747072 M:1047195648 DEBUG: Alloc resources: 0.10ms (0.00 ms skin load)
12:36:22 T:140737352747072 M:1047195648 DEBUG: ------ Window Init (/home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/script-OpenSubtitles_OSD-main.xml) ------
12:36:22 T:140737352747072 M:1047195648 INFO: Loading skin file: /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/script-OpenSubtitles_OSD-main.xml
12:36:22 T:140736908404496 M:1047195648 WARNING: CALSADirectSound::CALSADirectSound - device is not able to pause playback, will flush instead
12:36:22 T:140737352747072 M:1047195648 DEBUG: Load /home/dorphell/.xbmc/scripts/OpenSubtitles_OSD/resources/skins/Default/720p/script-OpenSubtitles_OSD-main.xml: 56.18ms
12:36:22 T:140737352747072 M:1047195648 DEBUG: Alloc resources: 57.21ms (56.29 ms skin load)
12:36:22 T:140737352747072 M:1047195648 DEBUG: CGUIMediaWindow::GetDirectory (?)
12:36:22 T:140737352747072 M:1047195648 DEBUG: ParentPath = []
12:36:22 T:140737352747072 M:1047195648 ERROR: GetDirectory - Error getting ?
12:36:22 T:140737352747072 M:1047195648 ERROR: CGUIMediaWindow::GetDirectory(?) failed
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: onInit
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: XBOX System: []
12:36:22 T:140736883226384 M:1047195648 NOTICE: INFO: Search by hash_pod [Up.avi]
12:36:23 T:140737033565968 M:1047097344 NOTICE: INFO: File Hash: [cf5230d456588246]
12:36:23 T:140736883226384 M:1047097344 NOTICE: INFO: Languages : [['2']]
12:36:23 T:140736883226384 M:1047097344 NOTICE: INFO: Hash : [['cf5230d456588246']]
12:36:23 T:140737352747072 M:1047097344 ERROR: XLCDproc::SetBackLight - Unable to write to socket
12:36:24 T:140736883226384 M:1047048192 NOTICE: INFO: Filter : [{'status': 200, 'search_restrict': True, 'search_langs': ['2']}]
12:36:24 T:140736883226384 M:1047048192 NOTICE: INFO: Auth : [{'status': 200, 'search_restrict': False, 'search_langs': []}]
12:36:24 T:140736883226384 M:1047048192 NOTICE: INFO: No subtitles found Podnapisi_hash
12:36:24 T:140736883226384 M:1047048192 NOTICE: INFO: Hash Search_pod: [No subtitles found Podnapisi_hash]
12:36:24 T:140736883226384 M:1047048192 NOTICE: INFO: Search by name_pod [Up]
12:36:25 T:140736883226384 M:1047048192 NOTICE: INFO: http://www.podnapisi.net/ppodnapisi/search?tbsl=1&sK=Up&sJ=2&sY=2009&sTS=&sTE=&sXML=1
12:36:25 T:140736883226384 M:1047048192 NOTICE: INFO: Searching subtitles by name_pod [Up]
12:36:25 T:140736883226384 M:1047076864 NOTICE: INFO: 24 subtitles found
12:36:25 T:140736883226384 M:1047076864 NOTICE: INFO: Name Search_pod: [24 subtitles found]