rendered paste body04:46:46 T:3038914368 M:1788444672 NOTICE: -----------------------------------------------------------------------
04:46:46 T:3038914368 M:1787891712 WARNING: Unable to determine Linux distribution
04:46:46 T:3038914368 M:1787891712 NOTICE: Starting XBMC, Platform: Linux (Unknown, 2.6.30-bpo.1-686 i686). Built on Feb 27 2010 (SVN:28150)
04:46:46 T:3038914368 M:1787891712 NOTICE: special://xbmc/ is mapped to: /home/gnif/xbmc
04:46:46 T:3038914368 M:1787891712 NOTICE: special://masterprofile/ is mapped to: /home/gnif/.xbmc/userdata
04:46:46 T:3038914368 M:1787891712 NOTICE: special://home/ is mapped to: /home/gnif/.xbmc
04:46:46 T:3038914368 M:1787891712 NOTICE: special://temp/ is mapped to: /home/gnif/.xbmc/temp
04:46:46 T:3038914368 M:1787891712 NOTICE: The executable running is: /home/gnif/xbmc/xbmc.bin
04:46:46 T:3038914368 M:1787891712 NOTICE: Log File is located: /home/gnif/.xbmc/temp/xbmc.log
04:46:46 T:3038914368 M:1787891712 NOTICE: -----------------------------------------------------------------------
04:46:46 T:3038914368 M:1788317696 NOTICE: Setup SDL
04:46:47 T:3038914368 M:1787641856 INFO: Available videomodes (xrandr):
04:46:47 T:3038914368 M:1787641856 INFO: Number of connected outputs: 1
04:46:47 T:3038914368 M:1787641856 INFO: Output 'default' has 1 modes
04:46:47 T:3038914368 M:1787641856 INFO: ID:0x1b1 Name:1400x1050 Refresh:50.000000 Width:1400 Height:1050
04:46:47 T:3038914368 M:1787641856 INFO: Pixel Ratio: 1.000000
04:46:47 T:3038914368 M:1787641856 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop
04:46:47 T:3038914368 M:1787641856 DEBUG: ConsoleKit.Manager: org.freedesktop.DBus.Error.AccessDenied - Rejected send message, 2 matched rules; type="method_call", sender=":1.4340" (uid=1000 pid=29489 comm="/home/gnif/xbmc/xbmc.bin ") interface="org.freedesktop.ConsoleKit.Manager" member="CanStop" error name="(unset)" requested_reply=0 destination="org.freedesktop.ConsoleKit" (uid=0 pid=3166 comm="/usr/sbin/console-kit-daemon "))
04:46:47 T:3038914368 M:1787641856 DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
04:46:47 T:3038914368 M:1787641856 DEBUG: DeviceKit.Power: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
04:46:47 T:3038914368 M:1787641856 NOTICE: load settings...
04:46:47 T:3038914368 M:1787572224 NOTICE: special://profile/ is mapped to: special://masterprofile/
04:46:47 T:3038914368 M:1787572224 NOTICE: loading special://masterprofile/guisettings.xml
04:46:47 T:3038914368 M:1787432960 NOTICE: Getting hardware information now...
04:46:47 T:3038914368 M:1787432960 INFO: Using analog output
04:46:47 T:3038914368 M:1787432960 INFO: AC3 pass through is enabled
04:46:47 T:3038914368 M:1787432960 INFO: DTS pass through is enabled
04:46:47 T:3038914368 M:1787432960 INFO: AAC pass through is enabled
04:46:47 T:3038914368 M:1787432960 INFO: MP1 pass through is disabled
04:46:47 T:3038914368 M:1787432960 INFO: MP2 pass through is disabled
04:46:47 T:3038914368 M:1787432960 INFO: MP3 pass through is disabled
04:46:47 T:3038914368 M:1787432960 NOTICE: Checking resolution 11
04:46:47 T:3038914368 M:1787211776 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/rtmp/mms/udp
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: flv/aacp/sdp
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdfile
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
04:46:47 T:3038914368 M:1787211776 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
04:46:47 T:3038914368 M:1787211776 NOTICE: Loaded playercorefactory configuration
04:46:47 T:3038914368 M:1787211776 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
04:46:47 T:3038914368 M:1787211776 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
04:46:47 T:3038914368 M:1787211776 NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml)
04:46:47 T:3038914368 M:1787211776 NOTICE: Default DVD Player: dvdplayer
04:46:47 T:3038914368 M:1787211776 NOTICE: Default Video Player: dvdplayer
04:46:47 T:3038914368 M:1787211776 NOTICE: Default Audio Player: paplayer
04:46:47 T:3038914368 M:1787211776 NOTICE: Loading media sources from special://masterprofile/sources.xml
04:46:47 T:3038914368 M:1786675200 INFO: LIRC Initialize: connect failed: No such file or directory
04:46:47 T:3038914368 M:1786675200 DEBUG: Failed to connect to LIRC. Retry in 10s.
04:46:47 T:3038914368 M:1786675200 INFO: XRANDR: /home/gnif/xbmc/xbmc-xrandr --output default --mode 0x1b1
04:46:47 T:3038914368 M:1783697408 NOTICE: Using fbConfig[0]
04:46:47 T:3038914368 M:1783209984 INFO: GL: Maximum texture width: 4096
04:46:47 T:3038914368 M:1783209984 DEBUG: SECTION:LoadDLL(special://xbmc/system/ImageLib-i486-linux.so)
04:46:47 T:3038914368 M:1783209984 DEBUG: Loading: /home/gnif/xbmc/system/ImageLib-i486-linux.so
04:46:47 T:3038914368 M:1780637696 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
04:46:47 T:3038914368 M:1780637696 NOTICE: GL_VENDOR = NVIDIA Corporation
04:46:47 T:3038914368 M:1780637696 NOTICE: GL_RENDERER = GeForce Go 7400/PCI/SSE2
04:46:47 T:3038914368 M:1780637696 NOTICE: GL_VERSION = 2.1.2 NVIDIA 190.53
04:46:47 T:3038914368 M:1780637696 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
04:46:47 T:3038914368 M:1780637696 INFO: GL: Maximum texture width: 4096
04:46:47 T:3038914368 M:1780637696 INFO: load language info file: special://xbmc/language/English/langinfo.xml
04:46:47 T:3038914368 M:1780637696 INFO: load default splash image: /home/gnif/xbmc/media/Splash.png
04:46:47 T:3038914368 M:1777356800 INFO: load language file:special://xbmc/language/English/strings.xml
04:46:47 T:3038914368 M:1776840704 INFO: load keymapping
04:46:47 T:3038914368 M:1776840704 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/keymaps/remote.xml
04:46:47 T:3038914368 M:1776730112 INFO: Loading special://xbmc/system/Lircmap.xml
04:46:47 T:3038914368 M:1776730112 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
04:46:47 T:3038914368 M:1776730112 INFO: Checking skin version of: Confluence
04:46:47 T:3038914368 M:1776730112 INFO: Skin version is: 2.11 (2.110000)
04:46:47 T:3038914368 M:1776730112 INFO: GUI format 720x480 Windowed
04:46:47 T:3038914368 M:1776730112 DEBUG: Selected HAL as storage provider
04:46:47 T:3038914368 M:1776730112 INFO: HAL: Starting initializing
04:46:47 T:3038914368 M:1776730112 DEBUG: HAL: Clearing old global device list, if any
04:46:47 T:3038914368 M:1776730112 NOTICE: HAL: Generating global device list
04:46:47 T:3038914368 M:1776730112 DEBUG: HAL: Added - cdrom | FileSystem udf | HotPlugged NO | Type 2 |Approved YES
04:46:47 T:3038914368 M:1776730112 DEBUG: HAL: Added - disk | UUID AC00DC6000DC32D6 | FileSystem ntfs | HotPlugged NO | Type 1 |Approved YES
04:46:47 T:3038914368 M:1776730112 DEBUG: HAL: Added - disk | UUID 95c926fb-61cc-4ca5-9c6d-da0e8acf3448 | FileSystem ext4 | Mounted on /home/ | HotPlugged NO | Type 1 |Approved NO
04:46:47 T:3038914368 M:1776730112 DEBUG: HAL: Added - disk | UUID 5fdbda06-d25f-499f-9be8-9f139b78ee73 | FileSystem ext4 | Mounted on / | HotPlugged NO | Type 1 |Approved NO
04:46:47 T:3038914368 M:1776730112 DEBUG: HAL: Added - disk | UUID b977f73a-113c-4f89-8f40-e5677c2da4bf | FileSystem swap | HotPlugged NO | Type 1 |Approved NO
04:46:47 T:3038914368 M:1776730112 INFO: HAL: Generated global device list, found 116
04:46:47 T:3038914368 M:1776730112 INFO: HAL: Sucessfully initialized
04:46:47 T:3038914368 M:1776730112 INFO: creating subdirectories
04:46:47 T:3038914368 M:1776730112 INFO: userdata folder: special://masterprofile/
04:46:47 T:3038914368 M:1776730112 INFO: recording folder:
04:46:47 T:3038914368 M:1776730112 INFO: screenshots folder:
04:46:47 T:3038914368 M:1776730112 INFO: thumbnails folder: special://masterprofile/Thumbnails
04:46:47 T:3038914368 M:1776730112 NOTICE: start dvd mediatype detection
04:46:47 T:3038914368 M:1776730112 NOTICE: initializing playlistplayer
04:46:47 T:3038914368 M:1776730112 NOTICE: DONE initializing playlistplayer
04:46:47 T:3022048144 M:1776730112 DEBUG: thread start, auto delete: 0
04:46:47 T:3022048144 M:1776730112 DEBUG: Compiled with libcdio Version 0.78
04:46:47 T:3038914368 M:1776730112 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
04:46:47 T:3038914368 M:1776730112 NOTICE: load default skin:[Confluence]
04:46:47 T:3038914368 M:1776730112 INFO: load skin from:special://xbmc/skin/Confluence
04:46:47 T:3038914368 M:1776730112 INFO: delete old skin...
04:46:47 T:3038914368 M:1776730112 DEBUG: ------ Window Deinit (Pointer.xml) ------
04:46:47 T:3038914368 M:1776730112 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------
04:46:47 T:3038914368 M:1776730112 INFO: Default 4:3 resolution directory is special://xbmc/skin/Confluence/720p
04:46:47 T:3022048144 M:1776730112 DEBUG: Drive closed media present, remounting...
04:46:47 T:3038914368 M:1776730112 INFO: Default 16:9 resolution directory is special://xbmc/skin/Confluence/720p
04:46:47 T:3022048144 M:1776730112 INFO: Detecting DVD-ROM media filesystem...
04:46:47 T:3038914368 M:1776730112 INFO: Skin version is: 2.11
04:46:47 T:3038914368 M:1776730112 INFO: Loading skin includes from /home/gnif/xbmc/skin/Confluence/720p/includes.xml
04:46:47 T:3022048144 M:1776730112 INFO: CD Analysis Report
04:46:47 T:3022048144 M:1776730112 INFO: __________________________________
04:46:49 T:3022048144 M:1776328704 INFO: CD-ROM with both UDF and ISO 9660 filesystem
04:46:49 T:3022048144 M:1776328704 INFO: ISO 9660: 383253 blocks, label `'
04:46:49 T:3022048144 M:1776328704 INFO: UDF: version 0.00
04:46:49 T:3022048144 M:1776328704 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1
04:46:49 T:3022048144 M:1775214592 INFO: Using protocol iso9660://
04:46:49 T:3022048144 M:1775214592 INFO: Disc label: DVD1
04:46:49 T:3022048144 M:1775214592 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.jpg]
04:46:49 T:3022048144 M:1775214592 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://Folder.jpg]
04:46:49 T:3022048144 M:1775214592 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://folder.JPG]
04:46:49 T:3022048144 M:1775214592 DEBUG: SetNewDVDShareUrl: looking for disc thumb:[iso9660://Folder.JPG]
04:46:49 T:3038914368 M:1775214592 INFO: load fonts for skin...
04:46:49 T:3038914368 M:1775214592 INFO: Loading fonts from special://xbmc/skin/Confluence/720p/Font.xml
04:46:49 T:3038914368 M:1773821952 INFO: load new skin...
04:46:49 T:3038914368 M:1773821952 INFO: Skin version is: 2.11 (2.110000)
04:46:49 T:3038914368 M:1773821952 INFO: Loading skin file: Home.xml
04:46:49 T:3038914368 M:1773056000 DEBUG: Load Home.xml: 40.40ms
04:46:49 T:3038914368 M:1773056000 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
04:46:49 T:3038914368 M:1773056000 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
04:46:49 T:3038914368 M:1773056000 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
04:46:49 T:3038914368 M:1773056000 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
04:46:49 T:3038914368 M:1773056000 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
04:46:49 T:3038914368 M:1773056000 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
04:46:49 T:3038914368 M:1773056000 INFO: Loading user windows, path special://xbmc/skin/Confluence/720p
04:46:49 T:3038914368 M:1773056000 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_SkinSetting_1111.xml
04:46:49 T:3038914368 M:1773056000 INFO: Loading skin file: special://xbmc/skin/Confluence/720p/custom_DiscDialog_1113.xml
04:46:49 T:3038914368 M:1773056000 DEBUG: Load Skin XML: 77.69ms
04:46:49 T:3038914368 M:1773056000 INFO: initialize new skin...
04:46:49 T:3038914368 M:1773056000 INFO: Loading skin file: Pointer.xml
04:46:49 T:3038914368 M:1773056000 DEBUG: Load Pointer.xml: 0.84ms
04:46:49 T:3038914368 M:1772916736 DEBUG: Alloc resources: 3.01ms (0.96 ms skin load)
04:46:49 T:3038914368 M:1772916736 INFO: Loading skin file: DialogVolumeBar.xml
04:46:49 T:3038914368 M:1772916736 DEBUG: Load DialogVolumeBar.xml: 1.43ms
04:46:49 T:3038914368 M:1772916736 DEBUG: Alloc resources: 1.64ms (1.56 ms skin load)
04:46:49 T:3038914368 M:1772916736 INFO: Loading skin file: DialogSeekBar.xml
04:46:49 T:3038914368 M:1772916736 DEBUG: Load DialogSeekBar.xml: 4.71ms
04:46:49 T:3038914368 M:1772916736 DEBUG: Alloc resources: 4.96ms (4.89 ms skin load)
04:46:49 T:3038914368 M:1772916736 INFO: Loading skin file: DialogKaiToast.xml
04:46:49 T:3038914368 M:1772916736 DEBUG: Load DialogKaiToast.xml: 1.06ms
04:46:49 T:3038914368 M:1772916736 DEBUG: Alloc resources: 1.18ms (1.18 ms skin load)
04:46:49 T:3038914368 M:1772916736 INFO: Loading skin file: DialogMuteBug.xml
04:46:49 T:3038914368 M:1772916736 DEBUG: Load DialogMuteBug.xml: 0.80ms
04:46:49 T:3038914368 M:1772916736 DEBUG: Alloc resources: 0.91ms (0.91 ms skin load)
04:46:49 T:3038914368 M:1772916736 DEBUG: CGUIAudioManager::Initialize
04:46:49 T:3038914368 M:1772969984 INFO: Loading special://xbmc/skin/Confluence/sounds/sounds.xml
04:46:49 T:3038914368 M:1772969984 INFO: skin loaded...
04:46:49 T:3038914368 M:1772969984 DEBUG: SECTION:LoadDLL(libcurl.so.4)
04:46:49 T:3038914368 M:1772969984 DEBUG: Loading: libcurl.so.4
04:46:49 T:3038914368 M:1772969984 DEBUG: Activating window ID: 12999
04:46:49 T:3038914368 M:1772969984 DEBUG: Checking if window ID 12999 is locked.
04:46:49 T:3038914368 M:1772969984 DEBUG: ------ Window Init (Startup.xml) ------
04:46:49 T:3038914368 M:1772969984 INFO: Loading skin file: Startup.xml
04:46:49 T:3038914368 M:1772969984 DEBUG: Load Startup.xml: 2.02ms
04:46:49 T:3038914368 M:1772969984 DEBUG: Alloc resources: 2.15ms (2.14 ms skin load)
04:46:49 T:3038914368 M:1772969984 INFO: removing tempfiles
04:46:49 T:3038914368 M:1772969984 NOTICE: initialize done
04:46:49 T:3038914368 M:1772969984 NOTICE: Running the application...
04:46:49 T:3038914368 M:1772969984 DEBUG: ExecuteXBMCAction : Translating SetFocus(10)
04:46:49 T:3038914368 M:1772969984 DEBUG: ExecuteXBMCAction : To SetFocus(10)
04:46:49 T:3038914368 M:1772969984 DEBUG: Process - no user autoexec.py (/home/gnif/.xbmc/scripts/autoexec.py) found, skipping
04:46:49 T:3038914368 M:1772969984 INFO: initializing python engine.
04:46:49 T:3038914368 M:1772969984 DEBUG: Loading: /home/gnif/xbmc/system/python/python24-i486-linux.so
04:46:50 T:3038914368 M:1770217472 DEBUG: Python wrapper library linked with internal Python library
04:46:50 T:3038914368 M:1769533440 DEBUG: GetZipList - Processing zip://%2fhome%2fgnif%2fxbmc%2fsystem%2fpython%2fpython24%2ezip/sitecustomize
04:46:50 T:3038914368 M:1768194048 DEBUG: new python thread created. id=1
04:46:50 T:3038914368 M:1768194048 DEBUG: NetworkMessage - Starting network services
04:46:50 T:2990898064 M:1768194048 DEBUG: thread start, auto delete: 0
04:46:50 T:3038914368 M:1768194048 NOTICE: ES: Starting event server
04:46:50 T:2990898064 M:1768194048 DEBUG: Python thread: start processing
04:46:50 T:2982505360 M:1768124416 DEBUG: thread start, auto delete: 0
04:46:50 T:2982505360 M:1768124416 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
04:46:50 T:2982505360 M:1768124416 NOTICE: UDP: Listening on port 9777
04:46:50 T:3038914368 M:1768124416 NOTICE: DS: Starting dbus server
04:46:50 T:2982505360 M:1767874560 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
04:46:50 T:3038914368 M:1767469056 ERROR: DS: Failed to connect to the D-Bus session daemon: Failed to execute dbus-launch to autolaunch D-Bus session
04:46:50 T:2965719952 M:1767469056 DEBUG: thread start, auto delete: 0
04:46:50 T:2965719952 M:1767469056 INFO: JSONRPC Server: Successfully initialized
04:46:50 T:3038914368 M:1767837696 NOTICE: starting zeroconf publishing
04:46:50 T:3038914368 M:1767837696 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC Event Server port:9777
04:46:50 T:3038914368 M:1767837696 DEBUG: CZeroconfAvahi::addService() named: XBMC Event Server type: _xbmc-events._udp port:9777
04:46:50 T:3038914368 M:1767837696 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc type: _xbmc-jsonrpc._tcp name:XBMC JSONRPC port:9090
04:46:50 T:3038914368 M:1767837696 DEBUG: CZeroconfAvahi::addService() named: XBMC JSONRPC type: _xbmc-jsonrpc._tcp port:9090
04:46:50 T:3038914368 M:1767837696 DEBUG: CLastfmScrobbler: Clearing session.
04:46:50 T:3038914368 M:1767837696 DEBUG: CLibrefmScrobbler: Clearing session.
04:46:50 T:2957327248 M:1767837696 DEBUG: thread start, auto delete: 1
04:46:50 T:2990898064 M:1767837696 NOTICE: -->Python Interpreter Initialized<--
04:46:50 T:2990898064 M:1767837696 DEBUG: Process - The source file to load is /home/gnif/xbmc/scripts/autoexec.py
04:46:50 T:2990898064 M:1767837696 DEBUG: Process - Setting the Python path to /home/gnif/xbmc/scripts:/home/gnif/xbmc/system/python/python24.zip:/home/gnif/xbmc/system/python/lib/python24.zip:/home/gnif/xbmc/system/python/lib/python2.4/:/home/gnif/xbmc/system/python/lib/python2.4/plat-linux2:/home/gnif/xbmc/system/python/lib/python2.4/lib-tk:/home/gnif/xbmc/system/python/lib/python2.4/lib-dynload
04:46:50 T:2990898064 M:1767837696 DEBUG: Process - Entering source directory /home/gnif/xbmc/scripts
04:46:50 T:2990898064 M:1767837696 INFO: Scriptresult: Success
04:46:50 T:3038914368 M:1768255488 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
04:46:50 T:3038914368 M:1768255488 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
04:46:50 T:3038914368 M:1768255488 DEBUG: Activating window ID: 10000
04:46:50 T:3038914368 M:1768255488 DEBUG: Checking if window ID 10000 is locked.
04:46:50 T:3038914368 M:1768255488 DEBUG: ------ Window Deinit (Startup.xml) ------
04:46:50 T:3038914368 M:1768255488 DEBUG: ------ Window Init (Home.xml) ------
04:46:50 T:3038914368 M:1768255488 DEBUG: Alloc resources: 1.40ms (0.00 ms skin load)
04:46:50 T:2946165648 M:1768255488 DEBUG: thread start, auto delete: 1
04:46:50 T:2946165648 M:1768255488 INFO: WEATHER: Downloading weather
04:46:50 T:2946165648 M:1768255488 DEBUG: FileCurl::Open(0xaf9ad12c) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
04:46:50 T:2946165648 M:1768255488 INFO: easy_aquire - Created session to http://xoap.weather.com
04:46:50 T:2937772944 M:1766723584 DEBUG: thread start, auto delete: 1
04:46:50 T:3025316752 M:1763471360 DEBUG: thread start, auto delete: 0
04:46:50 T:3025316752 M:1763471360 DEBUG: FileCurl::Open(0xb4529118) http://feeds.feedburner.com/xbmc
04:46:50 T:3025316752 M:1763471360 INFO: easy_aquire - Created session to http://feeds.feedburner.com
04:46:50 T:3038914368 M:1763360768 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000)
04:46:50 T:3038914368 M:1763360768 DEBUG: ExecuteXBMCAction : To SetFocus(9000)
04:46:50 T:2957327248 M:1762656256 DEBUG: DoWork - took 283 ms to load special://skin/backgrounds/videos.jpg
04:46:50 T:2946165648 M:1762885632 DEBUG: FileCurl::Close(0xaf9ad12c) http://xoap.weather.com/weather/local/USNY0996?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494
04:46:50 T:2946165648 M:1762885632 INFO: WEATHER: Weather download successful
04:46:50 T:2946165648 M:1762885632 DEBUG: GetZipList - Processing zip://special%3a%2f%2fxbmc%2fmedia%2fweather%2ezip/
04:46:50 T:2937772944 M:1764876288 DEBUG: DoWork - took 261 ms to load special://skin/backgrounds/videos.jpg
04:46:50 T:2990898064 M:1764528128 DEBUG: xbp_dlopen loading python lib /home/gnif/xbmc/system/python/python24.zip/time.so. flags: 2
04:46:50 T:2990898064 M:1764528128 DEBUG: Loading: /home/gnif/.xbmc/temp/time.so
04:46:50 T:2990898064 M:1764388864 DEBUG: RegisterExtensionLib, adding time.so (0x923a958)
04:46:50 T:2990898064 M:1764388864 DEBUG: xbp_dlsym - load symbol inittime
04:46:50 T:2990898064 M:1764388864 DEBUG: xbp_dlopen loading python lib /home/gnif/xbmc/system/python/python24.zip/collections.so. flags: 2
04:46:50 T:2990898064 M:1764388864 DEBUG: Loading: /home/gnif/.xbmc/temp/collections.so
04:46:50 T:2990898064 M:1764249600 DEBUG: RegisterExtensionLib, adding collections.so (0x942f0a8)
04:46:50 T:2990898064 M:1764249600 DEBUG: xbp_dlsym - load symbol initcollections
04:46:50 T:2990898064 M:1764249600 INFO: Python script stopped
04:46:50 T:2990898064 M:1764249600 DEBUG: Thread 2990898064 terminating
04:46:50 T:3038914368 M:1764249600 DEBUG: python thread 1 destructed
04:46:51 T:3025316752 M:1764126720 DEBUG: FileCurl::Close(0xb4529118) http://feeds.feedburner.com/xbmc
04:46:51 T:3025316752 M:1764126720 DEBUG: Got rss feed: http://feeds.feedburner.com/xbmc
04:46:51 T:3025316752 M:1764126720 DEBUG: RSS feed encoding: UTF-8
04:46:51 T:3025316752 M:1764126720 DEBUG: Parsed rss feed: http://feeds.feedburner.com/xbmc
04:46:51 T:3025316752 M:1764605952 DEBUG: Thread 3025316752 terminating
04:46:51 T:2974112656 M:1764642816 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
04:46:51 T:2974112656 M:1764642816 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
04:46:55 T:3038914368 M:1764737024 DEBUG: HAL: Device (/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT0) Property battery.voltage.current modified
04:46:57 T:3038914368 M:1764683776 DEBUG: Failed to connect to LIRC. Retry in 20s.
04:47:00 T:3038914368 M:1764749312 INFO: Python, unloading python24.dll because no scripts are running anymore
04:47:00 T:3038914368 M:1764749312 DEBUG: UnloadExtensionLibs, clearing python extension libraries
04:47:00 T:3038914368 M:1764749312 DEBUG: Unloading: time.so
04:47:00 T:3038914368 M:1764749312 DEBUG: Unloading: collections.so
04:47:00 T:3038914368 M:1764749312 DEBUG: Unloading: python24-i486-linux.so
04:47:07 T:3038914368 M:1766432768 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:07 T:3038914368 M:1766432768 DEBUG: OnKey: 61480 pressed, action is Down
04:47:07 T:2937772944 M:1764884480 DEBUG: DoWork - took 267 ms to load special://skin/backgrounds/music.jpg
04:47:07 T:3038914368 M:1764814848 DEBUG: SDLKeyboard: scancode: 98, sym: 273, unicode: 0, modifier: 0
04:47:07 T:3038914368 M:1764814848 DEBUG: OnKey: 61478 pressed, action is Up
04:47:08 T:3038914368 M:1764859904 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
04:47:08 T:3038914368 M:1764859904 DEBUG: OnKey: 61453 pressed, action is Select
04:47:08 T:3038914368 M:1764859904 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Videos)
04:47:08 T:3038914368 M:1764859904 DEBUG: ExecuteXBMCAction : To ActivateWindow(Videos)
04:47:08 T:3038914368 M:1764859904 DEBUG: Activating window ID: 10024
04:47:08 T:3038914368 M:1764859904 DEBUG: Checking if window ID 10024 is locked.
04:47:08 T:3038914368 M:1764859904 DEBUG: ------ Window Deinit (Home.xml) ------
04:47:08 T:3038914368 M:1764859904 INFO: Attempting to default to:
04:47:08 T:3038914368 M:1764859904 DEBUG: ------ Window Init (MyVideo.xml) ------
04:47:08 T:3038914368 M:1764859904 INFO: Loading skin file: MyVideo.xml
04:47:08 T:3038914368 M:1764859904 DEBUG: Load MyVideo.xml: 70.14ms
04:47:08 T:3038914368 M:1764749312 DEBUG: Alloc resources: 74.53ms (71.37 ms skin load)
04:47:08 T:3038914368 M:1764749312 DEBUG: CGUIMediaWindow::GetDirectory ()
04:47:08 T:3038914368 M:1764749312 DEBUG: ParentPath = []
04:47:08 T:3038914368 M:1764749312 WARNING: CRITSEC[0xb31886b4]: Trying to initialze initialized section.
04:47:08 T:2990898064 M:1764749312 DEBUG: thread start, auto delete: 0
04:47:08 T:2990898064 M:1764749312 DEBUG: Thread 2990898064 terminating
04:47:09 T:3038914368 M:1763336192 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
04:47:09 T:3038914368 M:1763336192 DEBUG: OnKey: 61453 pressed, action is Select
04:47:09 T:3038914368 M:1763336192 DEBUG: CGUIMediaWindow::GetDirectory (/home/gnif/Videos/)
04:47:09 T:3038914368 M:1763336192 DEBUG: ParentPath = []
04:47:09 T:3038914368 M:1763336192 WARNING: CRITSEC[0xbf9e7438]: Trying to initialze initialized section.
04:47:09 T:2957327248 M:1763336192 DEBUG: GetZipList - Processing zip://%2fhome%2fgnif%2fVideos%2fsimpsons%5fmovie%5ftrailer%2ezip/
04:47:09 T:3038914368 M:1763336192 WARNING: CRITSEC[0xbf9e6b80]: Trying to initialze initialized section.
04:47:09 T:3038914368 M:1763336192 WARNING: CRITSEC[0xb31886b4]: Trying to initialze initialized section.
04:47:09 T:2990898064 M:1763336192 DEBUG: thread start, auto delete: 0
04:47:09 T:2937772944 M:1761935360 DEBUG: DoWork - took 39 ms to load special://masterprofile/Thumbnails/Video/f/auto-fffdf3da.tbn
04:47:09 T:2990898064 M:1761935360 DEBUG: Thread 2990898064 terminating
04:47:09 T:2957327248 M:1761566720 DEBUG: DoWork - took 27 ms to load special://masterprofile/Thumbnails/Video/2/auto-253f35af.tbn
04:47:09 T:2946165648 M:1760145408 DEBUG: DoWork - took 27 ms to load special://masterprofile/Thumbnails/Video/f/auto-f750cebd.tbn
04:47:10 T:2946165648 M:1759776768 DEBUG: DoWork - took 16 ms to load special://masterprofile/Thumbnails/Video/c/auto-cb6201d3.tbn
04:47:10 T:2957327248 M:1758482432 DEBUG: DoWork - took 50 ms to load special://masterprofile/Thumbnails/Video/2/auto-2eba97e8.tbn
04:47:10 T:2937772944 M:1758035968 DEBUG: DoWork - took 39 ms to load special://masterprofile/Thumbnails/Video/6/auto-6b13e502.tbn
04:47:10 T:2946165648 M:1757577216 DEBUG: DoWork - took 45 ms to load special://masterprofile/Thumbnails/Video/a/auto-ac73e92a.tbn
04:47:10 T:2957327248 M:1756622848 DEBUG: DoWork - took 38 ms to load special://masterprofile/Thumbnails/Video/1/auto-101a7119.tbn
04:47:10 T:3038914368 M:1756585984 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:10 T:3038914368 M:1756585984 DEBUG: OnKey: 61480 pressed, action is Down
04:47:10 T:3038914368 M:1757728768 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:10 T:3038914368 M:1757728768 DEBUG: OnKey: 61480 pressed, action is Down
04:47:10 T:3038914368 M:1757728768 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:10 T:3038914368 M:1757728768 DEBUG: OnKey: 61480 pressed, action is Down
04:47:10 T:3038914368 M:1757728768 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:10 T:3038914368 M:1757728768 DEBUG: OnKey: 61480 pressed, action is Down
04:47:10 T:3038914368 M:1757728768 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:10 T:3038914368 M:1757728768 DEBUG: OnKey: 61480 pressed, action is Down
04:47:11 T:3038914368 M:1757786112 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:11 T:3038914368 M:1757786112 DEBUG: OnKey: 61480 pressed, action is Down
04:47:12 T:3038914368 M:1757786112 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:12 T:3038914368 M:1757786112 DEBUG: OnKey: 61480 pressed, action is Down
04:47:12 T:3038914368 M:1757786112 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:12 T:3038914368 M:1757786112 DEBUG: OnKey: 61480 pressed, action is Down
04:47:14 T:3038914368 M:1758175232 DEBUG: SDLKeyboard: scancode: 98, sym: 273, unicode: 0, modifier: 0
04:47:14 T:3038914368 M:1758175232 DEBUG: OnKey: 61478 pressed, action is Up
04:47:14 T:3038914368 M:1758175232 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
04:47:14 T:3038914368 M:1758175232 DEBUG: OnKey: 61453 pressed, action is Select
04:47:14 T:3038914368 M:1758175232 DEBUG: new file set audiostream:0
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerCoreFactory::GetPlayers(/home/gnif/Videos/The Simpsons Movie - Trailer.mp4)
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
04:47:14 T:3038914368 M:1758175232 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
04:47:14 T:3038914368 M:1757032448 NOTICE: DVDPlayer: Opening: /home/gnif/Videos/The Simpsons Movie - Trailer.mp4
04:47:14 T:3038914368 M:1757032448 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
04:47:14 T:2919721872 M:1757032448 DEBUG: thread start, auto delete: 0
04:47:14 T:2919721872 M:1757032448 NOTICE: Creating InputStream
04:47:14 T:2919721872 M:1757032448 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
04:47:14 T:2919721872 M:1757032448 DEBUG: CacheSubtitles: START
04:47:14 T:2919721872 M:1757032448 DEBUG: CacheSubtitles: Checking for common subirs...
04:47:14 T:2919721872 M:1757032448 DEBUG: CacheSubtitles: Done (time: 2 ms)
04:47:14 T:2919721872 M:1757032448 DEBUG: CacheSubtitles: Searching for subtitles...
04:47:14 T:2919721872 M:1757032448 DEBUG: CacheSubtitles: Done (time: 1 ms)
04:47:14 T:2919721872 M:1757032448 DEBUG: CacheSubtitles: END (total time: 4 ms)
04:47:14 T:2919721872 M:1757032448 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
04:47:14 T:2919721872 M:1757032448 NOTICE: Creating Demuxer
04:47:14 T:2919721872 M:1757032448 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/avutil-50-i486-linux.so)
04:47:14 T:2919721872 M:1757032448 DEBUG: Loading: /home/gnif/xbmc/system/players/dvdplayer/avutil-50-i486-linux.so
04:47:14 T:2919721872 M:1757032448 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so)
04:47:14 T:2919721872 M:1757032448 DEBUG: Loading: /home/gnif/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so
04:47:15 T:3038914368 M:1747804160 DEBUG: ------ Window Init (DialogBusy.xml) ------
04:47:15 T:3038914368 M:1747664896 INFO: Loading skin file: DialogBusy.xml
04:47:15 T:3038914368 M:1746690048 DEBUG: Load DialogBusy.xml: 1.42ms
04:47:15 T:3038914368 M:1746550784 DEBUG: Alloc resources: 1.70ms (1.70 ms skin load)
04:47:15 T:2919721872 M:1745367040 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/avformat-52-i486-linux.so)
04:47:15 T:2919721872 M:1745367040 DEBUG: Loading: /home/gnif/xbmc/system/players/dvdplayer/avformat-52-i486-linux.so
04:47:15 T:2919721872 M:1741705216 DEBUG: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
04:47:15 T:2919721872 M:1741635584 DEBUG: Open - av_find_stream_info starting
04:47:15 T:2919721872 M:1740869632 DEBUG: Open - av_find_stream_info finished
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, from '/home/gnif/Videos/The Simpsons Movie - Trailer.mp4':
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: Metadata:
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: major_brand : isom
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: minor_version : 1
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: compatible_brands: isomavc1
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: Duration: 00:02:17.30, start: 0.000000, bitrate: 4283 kb/s
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: Stream #0.0(und): Video: h264, yuv420p, 1280x544, 4221 kb/s, 47.90 fps, 23.98 tbr, 24k tbn, 47.95 tbc
04:47:15 T:2919721872 M:1740869632 INFO: ffmpeg[AE076B90]: Stream #0.1(und): Audio: aac, 24000 Hz, stereo, s16, 64 kb/s
04:47:15 T:2919721872 M:1740869632 NOTICE: Opening video stream: 0 source: 256
04:47:15 T:2919721872 M:1740869632 NOTICE: Creating video codec with codec id: 28
04:47:15 T:2919721872 M:1740869632 DEBUG: FactoryCodec - Video: - Opening
04:47:15 T:2919721872 M:1740869632 DEBUG: SECTION:LoadDLL(special://xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so)
04:47:15 T:2919721872 M:1740869632 DEBUG: Loading: /home/gnif/xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so
04:47:15 T:2919721872 M:1740730368 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1280x544, 28)
04:47:15 T:2919721872 M:1740169216 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000001
04:47:15 T:2919721872 M:1740169216 ERROR: (VDPAU) unable to init VDPAU - vdp_st = 0x1. Falling back.
04:47:15 T:2919721872 M:1740169216 NOTICE: CDVDVideoCodecFFmpeg::Open() Failed to get VDPAU device
04:47:15 T:2919721872 M:1740169216 NOTICE: (VDPAU) Close
04:47:15 T:2919721872 M:1740169216 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
04:47:15 T:2919721872 M:1740058624 DEBUG: FactoryCodec - Video: ff-h264 - Opened
04:47:15 T:2919721872 M:1740058624 NOTICE: Creating video thread
04:47:15 T:2884504464 M:1740058624 DEBUG: thread start, auto delete: 0
04:47:15 T:2919721872 M:1740058624 NOTICE: Opening audio stream: 1 source: 256
04:47:15 T:2919721872 M:1740058624 NOTICE: Creating audio thread
04:47:15 T:2876111760 M:1740058624 DEBUG: thread start, auto delete: 0
04:47:15 T:2876111760 M:1740058624 NOTICE: running thread: CDVDPlayerAudio::Process()
04:47:15 T:2919721872 M:1740058624 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: /home/gnif/Videos/The Simpsons Movie - Trailer.mp4
04:47:15 T:2919721872 M:1740058624 DEBUG: CDVDPlayer::SetCaching - caching state 2
04:47:15 T:2876111760 M:1740058624 DEBUG: FactoryCodec - Audio: libfaad - Opening
04:47:15 T:2876111760 M:1740058624 DEBUG: SECTION:LoadDLL(libfaad.so.0)
04:47:15 T:2876111760 M:1740058624 DEBUG: Loading: libfaad.so.0
04:47:15 T:2876111760 M:1740058624 DEBUG: FactoryCodec - Audio: libfaad - Opened
04:47:15 T:2876111760 M:1740058624 NOTICE: Creating audio device with codec id: 86018, channels: 2, sample rate: 1536000
04:47:15 T:2884504464 M:1740058624 DEBUG: LinuxRendererGL: Cleaning up GL resources
04:47:15 T:2884504464 M:1740058624 NOTICE: running thread: video_thread
04:47:15 T:2884504464 M:1740058624 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
04:47:15 T:3038914368 M:1740058624 DEBUG: CGUIAudioManager::DeInitialize
04:47:15 T:2884504464 M:1740058624 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(41708.333333, 1)
04:47:15 T:2876111760 M:1740058624 INFO: AudioRendererFactory: not a explicit device, trying to autodetect.
04:47:15 T:2876111760 M:1740058624 DEBUG: PulseAudio: Opening Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec AAC - IsMusic false - IsPassthrough false - device: default
04:47:15 T:2876111760 M:1740058624 DEBUG: RemoveActiveDevice - Removing device 1
04:47:15 T:2884504464 M:1735843840 NOTICE: fps: 23.976025, pwidth: 1280, pheight: 544, dwidth: 1280, dheight: 544
04:47:15 T:2884504464 M:1735843840 DEBUG: OutputPicture - change configuration. 1280x544. framerate: 23.98
04:47:15 T:2884504464 M:1735843840 NOTICE: Display resolution DESKTOP : 1400x1050 @ 50.00 - Full Screen (12)
04:47:15 T:2919721872 M:1735843840 DEBUG: CDVDPlayer::HandleMessages - player started 2
04:47:15 T:2876111760 M:1734832128 DEBUG: CGUIAudioManager::DeInitialize
04:47:15 T:2876111760 M:1734832128 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
04:47:15 T:2876111760 M:1734832128 DEBUG: RemoveActiveDevice - Removing device 0
04:47:15 T:3038914368 M:1734832128 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc
04:47:15 T:3038914368 M:1734832128 DEBUG: OnPlayBackStarted - Playback has started
04:47:15 T:2876111760 M:1733201920 DEBUG: PulseAudio: Context failed
04:47:15 T:2876111760 M:1733201920 ERROR: PulseAudio: Waited for the Context but it failed
04:47:15 T:2876111760 M:1733201920 ERROR: PulseAudio: Failed to create context
04:47:15 T:2876111760 M:1733201920 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
04:47:15 T:2876111760 M:1733201920 DEBUG: RemoveActiveDevice - Removing device 2
04:47:15 T:2876111760 M:1733201920 DEBUG: CGUIAudioManager::Initialize
04:47:15 T:2876111760 M:1732902912 DEBUG: CALSADirectSound::CALSADirectSound - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - Codec AAC - IsMusic false - IsPassthrough false - audioDevice: default
04:47:15 T:2876111760 M:1732902912 DEBUG: RemoveActiveDevice - Removing device 1
04:47:15 T:2876111760 M:1732902912 DEBUG: CGUIAudioManager::DeInitialize
04:47:15 T:2876111760 M:1730969600 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
04:47:15 T:2876111760 M:1730969600 DEBUG: RemoveActiveDevice - Removing device 0
04:47:15 T:2876111760 M:1730969600 DEBUG: Initialize - using alsa device default
04:47:15 T:2876111760 M:1730711552 DEBUG: CALSADirectSound::Initialize - packet size:4096, packet count:16, buffer size:16384
04:47:15 T:2876111760 M:1730711552 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(42666.666667, 0)
04:47:15 T:2876111760 M:1730600960 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
04:47:15 T:2919721872 M:1730600960 DEBUG: CDVDPlayer::HandleMessages - player started 1
04:47:15 T:2919721872 M:1730600960 DEBUG: CDVDPlayer::SetCaching - caching state 3
04:47:15 T:2919721872 M:1730600960 DEBUG: CDVDPlayer::SetCaching - caching state 0
04:47:15 T:2884504464 M:1729052672 INFO: ffmpeg[ABEE0B90]: [h264] Cannot parallelize deblocking type 1, decoding such frames in sequential order
04:47:15 T:2876111760 M:1729105920 DEBUG: CDVDPlayerAudio:: Discontinuity - was:36606.489333, should be:42666.666667, error:6060.177333
04:47:15 T:3038914368 M:1728385024 NOTICE: Using GL_TEXTURE_2D
04:47:15 T:3038914368 M:1728385024 DEBUG: GL: Requested render method: 0
04:47:15 T:3038914368 M:1728385024 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
04:47:15 T:3038914368 M:1728385024 DEBUG: GL: Shader compilation log:
04:47:15 T:3038914368 M:1728385024 DEBUG: GL: Shader compilation log:
04:47:15 T:3038914368 M:1728385024 NOTICE: GL: NPOT texture support detected
04:47:15 T:3038914368 M:1727578112 DEBUG: CGUIInfoManager::SetCurrentMovie(/home/gnif/Videos/The Simpsons Movie - Trailer.mp4)
04:47:15 T:3038914368 M:1727578112 DEBUG: GetMovieId (/home/gnif/Videos/The Simpsons Movie - Trailer.mp4), query = select idMovie from movie where idFile=5
04:47:15 T:3038914368 M:1727578112 DEBUG: GetEpisodeId (/home/gnif/Videos/The Simpsons Movie - Trailer.mp4), query = select idEpisode from episode where idFile=5
04:47:15 T:3038914368 M:1727578112 DEBUG: GetMusicVideoId (/home/gnif/Videos/The Simpsons Movie - Trailer.mp4), query = select idMVideo from musicvideo where idFile=5
04:47:15 T:3038914368 M:1727578112 DEBUG: Activating window ID: 12005
04:47:15 T:3038914368 M:1727578112 DEBUG: Checking if window ID 12005 is locked.
04:47:15 T:3038914368 M:1727578112 DEBUG: ------ Window Deinit (MyVideo.xml) ------
04:47:15 T:3038914368 M:1727578112 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
04:47:15 T:3038914368 M:1727578112 INFO: Loading skin file: VideoFullScreen.xml
04:47:15 T:3038914368 M:1727578112 DEBUG: Load VideoFullScreen.xml: 1.14ms
04:47:15 T:3038914368 M:1727578112 DEBUG: Alloc resources: 1.28ms (1.28 ms skin load)
04:47:15 T:3038914368 M:1727578112 INFO: Loading skin file: VideoOSD.xml
04:47:15 T:3038914368 M:1727578112 DEBUG: Load VideoOSD.xml: 5.60ms
04:47:15 T:3038914368 M:1727062016 DEBUG: Alloc resources: 3.31ms (0.00 ms skin load)
04:47:15 T:3038914368 M:1727062016 INFO: Loading skin file: VideoOSDSettings.xml
04:47:15 T:3038914368 M:1727062016 DEBUG: Load VideoOSDSettings.xml: 3.31ms
04:47:15 T:3038914368 M:1726066688 DEBUG: Alloc resources: 3.93ms (0.00 ms skin load)
04:47:15 T:3038914368 M:1726066688 INFO: Loading skin file: VideoOSDSettings.xml
04:47:15 T:3038914368 M:1726066688 DEBUG: Load VideoOSDSettings.xml: 4.27ms
04:47:15 T:3038914368 M:1726066688 DEBUG: Alloc resources: 0.13ms (0.00 ms skin load)
04:47:15 T:3038914368 M:1726066688 INFO: Loading skin file: DialogFullScreenInfo.xml
04:47:15 T:3038914368 M:1726066688 DEBUG: Load DialogFullScreenInfo.xml: 5.45ms
04:47:15 T:3038914368 M:1726066688 DEBUG: Alloc resources: 0.44ms (0.00 ms skin load)
04:47:15 T:3038914368 M:1726066688 INFO: GL: ARB Imaging extension supported
04:47:15 T:3038914368 M:1723502592 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
04:47:16 T:3038914368 M:1721978880 DEBUG: SDLKeyboard: scancode: 53, sym: 120, unicode: 120, modifier: 0
04:47:16 T:3038914368 M:1721978880 DEBUG: OnKey: 61528 pressed, action is Stop
04:47:16 T:3038914368 M:1721978880 NOTICE: CDVDPlayer::CloseFile()
04:47:16 T:2919721872 M:1721978880 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc
04:47:16 T:2919721872 M:1721978880 DEBUG: OnPlayBackSpeedChanged - Playback speed changed
04:47:16 T:3038914368 M:1721978880 NOTICE: DVDPlayer: waiting for threads to exit
04:47:16 T:2919721872 M:1721978880 NOTICE: CDVDPlayer::OnExit()
04:47:16 T:2919721872 M:1721978880 NOTICE: DVDPlayer: closing audio stream
04:47:16 T:2919721872 M:1721978880 NOTICE: Closing audio stream
04:47:16 T:2919721872 M:1721978880 NOTICE: Waiting for audio thread to exit
04:47:16 T:2876111760 M:1721978880 NOTICE: thread end: CDVDPlayerAudio::OnExit()
04:47:16 T:2876111760 M:1721978880 DEBUG: Thread 2876111760 terminating
04:47:16 T:2919721872 M:1721868288 NOTICE: Closing audio device
04:47:16 T:2919721872 M:1721761792 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1
04:47:16 T:2919721872 M:1721761792 DEBUG: RemoveActiveDevice - Removing device 2
04:47:16 T:2919721872 M:1721761792 DEBUG: CGUIAudioManager::Initialize
04:47:16 T:2919721872 M:1721761792 NOTICE: DVDPlayer: closing video stream
04:47:16 T:2919721872 M:1721761792 NOTICE: Closing video stream
04:47:16 T:2919721872 M:1721761792 NOTICE: waiting for video thread to exit
04:47:16 T:2884504464 M:1721761792 NOTICE: thread end: video_thread
04:47:16 T:2884504464 M:1721761792 DEBUG: Thread 2884504464 terminating
04:47:16 T:2919721872 M:1721761792 NOTICE: deleting video codec
04:47:16 T:2919721872 M:1729355776 NOTICE: CDVDPlayer::OnExit() deleting demuxer
04:47:16 T:2919721872 M:1729355776 NOTICE: CDVDPlayer::OnExit() deleting input stream
04:47:16 T:2919721872 M:1729355776 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc
04:47:16 T:2919721872 M:1729355776 DEBUG: OnPlayBackStopped - Playback was stopped
04:47:16 T:2919721872 M:1729355776 DEBUG: Thread 2919721872 terminating
04:47:16 T:3038914368 M:1729466368 NOTICE: DVDPlayer: finished waiting
04:47:16 T:3038914368 M:1729466368 DEBUG: LinuxRendererGL: Cleaning up GL resources
04:47:16 T:3038914368 M:1734959104 NOTICE: CDVDPlayer::CloseFile()
04:47:16 T:3038914368 M:1734959104 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
04:47:16 T:3038914368 M:1734959104 NOTICE: DVDPlayer: waiting for threads to exit
04:47:16 T:3038914368 M:1734959104 NOTICE: DVDPlayer: finished waiting
04:47:16 T:3038914368 M:1734959104 DEBUG: LinuxRendererGL: Cleaning up GL resources
04:47:16 T:3038914368 M:1736212480 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
04:47:16 T:3038914368 M:1736212480 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
04:47:16 T:3038914368 M:1736364032 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
04:47:16 T:3038914368 M:1736364032 DEBUG: ------ Window Init (MyVideo.xml) ------
04:47:16 T:3038914368 M:1736364032 INFO: Loading skin file: MyVideo.xml
04:47:16 T:3038914368 M:1736253440 DEBUG: Load MyVideo.xml: 57.54ms
04:47:16 T:3038914368 M:1736253440 DEBUG: Alloc resources: 60.81ms (58.22 ms skin load)
04:47:16 T:3038914368 M:1736253440 DEBUG: CGUIMediaWindow::GetDirectory (/home/gnif/Videos/)
04:47:16 T:3038914368 M:1736253440 DEBUG: ParentPath = [/home/gnif/Videos/]
04:47:16 T:3038914368 M:1736253440 WARNING: CRITSEC[0xbf9e7cb8]: Trying to initialze initialized section.
04:47:16 T:3038914368 M:1736253440 WARNING: CRITSEC[0xb31886b4]: Trying to initialze initialized section.
04:47:16 T:2990898064 M:1736364032 DEBUG: thread start, auto delete: 0
04:47:16 T:2990898064 M:1736011776 DEBUG: Thread 2990898064 terminating
04:47:17 T:3038914368 M:1736101888 DEBUG: SaveFileState - Saving file state for video item /home/gnif/Videos/The Simpsons Movie - Trailer.mp4
04:47:17 T:3038914368 M:1736101888 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:17 T:3038914368 M:1736101888 DEBUG: OnKey: 61480 pressed, action is Down
04:47:17 T:3038914368 M:1736101888 DEBUG: Failed to connect to LIRC. Retry in 40s.
04:47:17 T:3038914368 M:1736101888 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
04:47:17 T:3038914368 M:1736101888 DEBUG: OnKey: 61453 pressed, action is Select
04:47:17 T:3038914368 M:1736101888 INFO: Loading skin file: DialogContextMenu.xml
04:47:17 T:3038914368 M:1736101888 DEBUG: Load DialogContextMenu.xml: 2.95ms
04:47:17 T:3038914368 M:1736101888 DEBUG: ------ Window Init (DialogContextMenu.xml) ------
04:47:17 T:3038914368 M:1736101888 DEBUG: Alloc resources: 0.35ms (0.01 ms skin load)
04:47:18 T:3038914368 M:1736122368 DEBUG: SDLKeyboard: scancode: 104, sym: 274, unicode: 0, modifier: 0
04:47:18 T:3038914368 M:1736122368 DEBUG: OnKey: 61480 pressed, action is Down
04:47:18 T:3038914368 M:1736122368 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
04:47:18 T:3038914368 M:1736122368 DEBUG: OnKey: 61453 pressed, action is Select
04:47:18 T:3038914368 M:1736122368 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
04:47:18 T:3038914368 M:1736122368 DEBUG: new file set audiostream:0
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerCoreFactory::GetPlayers(/home/gnif/Videos/test.avi)
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
04:47:18 T:3038914368 M:1736122368 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
04:47:18 T:3038914368 M:1735237632 NOTICE: DVDPlayer: Opening: /home/gnif/Videos/test.avi
04:47:18 T:3038914368 M:1735237632 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
04:47:18 T:2919721872 M:1735237632 DEBUG: thread start, auto delete: 0
04:47:18 T:2919721872 M:1735237632 NOTICE: Creating InputStream
04:47:18 T:2919721872 M:1735237632 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
04:47:18 T:2919721872 M:1735237632 DEBUG: CacheSubtitles: START
04:47:18 T:2919721872 M:1735237632 DEBUG: CacheSubtitles: Checking for common subirs...
04:47:18 T:2919721872 M:1735237632 DEBUG: CacheSubtitles: Done (time: 2 ms)
04:47:18 T:2919721872 M:1735237632 DEBUG: CacheSubtitles: Searching for subtitles...
04:47:18 T:2919721872 M:1735237632 DEBUG: CacheSubtitles: Done (time: 1 ms)
04:47:18 T:2919721872 M:1735237632 DEBUG: CacheSubtitles: END (total time: 4 ms)
04:47:18 T:2919721872 M:1735237632 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
04:47:18 T:2919721872 M:1735237632 NOTICE: Creating Demuxer
04:47:18 T:2919721872 M:1735237632 DEBUG: Open - probing detected format [avi]
04:47:18 T:2919721872 M:1735237632 DEBUG: Open - av_find_stream_info starting
04:47:18 T:2919721872 M:1735028736 DEBUG: ffmpeg[AE076B90]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
04:47:18 T:2919721872 M:1734541312 DEBUG: Open - av_find_stream_info finished
04:47:18 T:2919721872 M:1734541312 INFO: ffmpeg[AE076B90]: Input #0, avi, from '/home/gnif/Videos/test.avi':
04:47:18 T:2919721872 M:1734541312 INFO: ffmpeg[AE076B90]: Duration: 00:49:07.96, start: 0.000000, bitrate: 15 kb/s
04:47:18 T:2919721872 M:1734541312 INFO: ffmpeg[AE076B90]: Stream #0.0: Video: mpeg4, yuv420p, 720x544 [PAR 1:1 DAR 45:34], 25 tbr, 25 tbn, 25 tbc
04:47:18 T:2919721872 M:1734541312 INFO: ffmpeg[AE076B90]: Stream #0.1: Audio: mp3, 48000 Hz, 1 channels, s16, 96 kb/s
04:47:18 T:2919721872 M:1734541312 NOTICE: Opening video stream: 0 source: 256
04:47:18 T:2919721872 M:1734541312 NOTICE: Creating video codec with codec id: 13
04:47:18 T:2919721872 M:1734541312 DEBUG: FactoryCodec - Video: - Opening
04:47:18 T:2919721872 M:1734541312 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(720x544, 13)
04:47:18 T:2919721872 M:1734402048 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000001
04:47:18 T:2919721872 M:1734402048 ERROR: (VDPAU) unable to init VDPAU - vdp_st = 0x1. Falling back.
04:47:18 T:2919721872 M:1734402048 NOTICE: CDVDVideoCodecFFmpeg::Open() Failed to get VDPAU device
04:47:18 T:2919721872 M:1734402048 NOTICE: (VDPAU) Close
04:47:18 T:3038914368 M:1734402048 DEBUG: ------ Window Init (DialogBusy.xml) ------
04:47:18 T:2919721872 M:1734402048 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2
04:47:18 T:3038914368 M:1734402048 DEBUG: Alloc resources: 0.01ms (0.01 ms skin load)
04:47:18 T:2919721872 M:1734332416 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened
04:47:18 T:2919721872 M:1734332416 NOTICE: Creating video thread
04:47:18 T:2884504464 M:1734332416 DEBUG: thread start, auto delete: 0
04:47:18 T:2919721872 M:1734332416 NOTICE: Opening audio stream: 1 source: 256
04:47:18 T:2919721872 M:1734332416 NOTICE: Creating audio thread
04:47:19 T:2853174160 M:1734332416 DEBUG: thread start, auto delete: 0
04:47:19 T:2853174160 M:1734332416 NOTICE: running thread: CDVDPlayerAudio::Process()
04:47:19 T:2919721872 M:1734332416 DEBUG: ReadEditDecisionLists - checking for any edit decision lists (EDL) on local drive or remote share for: /home/gnif/Videos/test.avi
04:47:19 T:2919721872 M:1734332416 DEBUG: CDVDPlayer::SetCaching - caching state 2
04:47:19 T:2853174160 M:1734184960 DEBUG: FactoryCodec - Audio: libmad - Opening
04:47:19 T:2853174160 M:1734184960 DEBUG: SECTION:LoadDLL(libmad.so.0)
04:47:19 T:2853174160 M:1734184960 DEBUG: Loading: libmad.so.0
04:47:19 T:2853174160 M:1734062080 DEBUG: FactoryCodec - Audio: libmad - Opened
04:47:19 T:2853174160 M:1734062080 NOTICE: Creating audio device with codec id: 86017, channels: 0, sample rate: 0