10:15:35 T:4761536 M:357834752 NOTICE: Mapping drive Q to /usr/local/share/xbmc 10:15:35 T:4761536 M:357834752 NOTICE: Mapping drive U to /home/xbmc/.xbmc 10:15:35 T:4761536 M:357834752 NOTICE: Mapping drive T to /home/xbmc/.xbmc/userdata 10:15:35 T:4761536 M:357834752 NOTICE: ----------------------------------------------------------------------- 10:15:35 T:4761536 M:357834752 NOTICE: Starting XBMC, Platform: GNU/Linux. Built on Jan 4 2009 (SVN:16877M) 10:15:35 T:4761536 M:357834752 NOTICE: Q is mapped to: /usr/local/share/xbmc 10:15:35 T:4761536 M:357834752 NOTICE: The executable running is: /usr/local/share/xbmc/xbmc.bin 10:15:35 T:4761536 M:357834752 NOTICE: Log File is located: /var/tmp/xbmc-xbmc.log 10:15:35 T:4761536 M:357834752 NOTICE: ----------------------------------------------------------------------- 10:15:35 T:4761536 M:357670912 NOTICE: Setup SDL 10:15:35 T:4761536 M:355622912 INFO: Available videomodes (xrandr): 10:15:35 T:4761536 M:355622912 INFO: Number of connected outputs: 1 10:15:35 T:4761536 M:355622912 INFO: Output 'default' has 38 modes 10:15:35 T:4761536 M:355622912 INFO: ID:0x92 Name:1920x1200 Refresh:60.000000 Width:1920 Height:1200 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x93 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x94 Name:1680x1050 Refresh:60.000000 Width:1680 Height:1050 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x95 Name:1600x1200 Refresh:60.000000 Width:1600 Height:1200 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x96 Name:1440x900 Refresh:60.000000 Width:1440 Height:900 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x97 Name:1400x1050 Refresh:60.000000 Width:1400 Height:1050 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x98 Name:1280x1024 Refresh:75.000000 Width:1280 Height:1024 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x99 Name:1280x1024 Refresh:70.000000 Width:1280 Height:1024 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x9a Name:1280x1024 Refresh:60.000000 Width:1280 Height:1024 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x9b Name:1280x960 Refresh:60.000000 Width:1280 Height:960 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x9c Name:1280x768 Refresh:60.000000 Width:1280 Height:768 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x9d Name:1280x720 Refresh:60.000000 Width:1280 Height:720 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x9e Name:1152x864 Refresh:75.000000 Width:1152 Height:864 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0x9f Name:1152x864 Refresh:70.000000 Width:1152 Height:864 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa0 Name:1152x864 Refresh:60.000000 Width:1152 Height:864 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa1 Name:1024x768 Refresh:75.000000 Width:1024 Height:768 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa2 Name:1024x768 Refresh:72.000000 Width:1024 Height:768 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa3 Name:1024x768 Refresh:70.000000 Width:1024 Height:768 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa4 Name:1024x768 Refresh:60.000000 Width:1024 Height:768 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa5 Name:800x600 Refresh:75.000000 Width:800 Height:600 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa6 Name:800x600 Refresh:72.000000 Width:800 Height:600 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa7 Name:800x600 Refresh:70.000000 Width:800 Height:600 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa8 Name:800x600 Refresh:60.000000 Width:800 Height:600 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xa9 Name:800x600 Refresh:56.000000 Width:800 Height:600 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xaa Name:720x480 Refresh:60.000000 Width:720 Height:480 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xab Name:640x480 Refresh:75.000000 Width:640 Height:480 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xac Name:640x480 Refresh:72.000000 Width:640 Height:480 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xad Name:640x480 Refresh:60.000000 Width:640 Height:480 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xae Name:640x400 Refresh:75.000000 Width:640 Height:400 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xaf Name:640x400 Refresh:60.000000 Width:640 Height:400 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb0 Name:512x384 Refresh:75.000000 Width:512 Height:384 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb1 Name:512x384 Refresh:60.000000 Width:512 Height:384 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb2 Name:400x300 Refresh:75.000000 Width:400 Height:300 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb3 Name:400x300 Refresh:60.000000 Width:400 Height:300 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb4 Name:320x240 Refresh:75.000000 Width:320 Height:240 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb5 Name:320x240 Refresh:60.000000 Width:320 Height:240 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb6 Name:320x200 Refresh:75.000000 Width:320 Height:200 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: ID:0xb7 Name:320x200 Refresh:60.000000 Width:320 Height:200 10:15:35 T:4761536 M:355622912 INFO: Pixel Ratio: 1.000000 10:15:35 T:4761536 M:355622912 INFO: Drives are mapped 10:15:35 T:4761536 M:355622912 NOTICE: load settings... 10:15:35 T:4761536 M:355622912 NOTICE: Mapping drive P to /home/xbmc/.xbmc/userdata 10:15:35 T:4761536 M:355622912 NOTICE: loading /home/xbmc/.xbmc/userdata/guisettings.xml 10:15:35 T:4761536 M:355622912 NOTICE: Enabled debug logging due to GUI setting 10:15:35 T:4761536 M:355622912 NOTICE: Getting hardware information now... 10:15:35 T:4761536 M:355622912 INFO: Using analog output 10:15:35 T:4761536 M:355622912 INFO: AC3 pass through is enabled 10:15:35 T:4761536 M:355622912 INFO: DTS pass through is enabled 10:15:35 T:4761536 M:355622912 NOTICE: Checking resolution 12 10:15:35 T:4761536 M:355622912 NOTICE: No advancedsettings.xml to load (/home/xbmc/.xbmc/userdata/advancedsettings.xml) 10:15:35 T:4761536 M:355622912 NOTICE: /home/xbmc/.xbmc/userdata/sources.xml 10:15:35 T:4761536 M:355622912 DEBUG: tag is missing or sources.xml is malformed 10:15:35 T:4761536 M:355622912 INFO: Checking skinpath existence, and existence of keymap.xml:Q:\skin... 10:15:35 T:4761536 M:355622912 WARNING: CXRandR::SetMode: found alternative mode (same hz): default mode: 0x92. 10:15:35 T:4761536 M:355622912 INFO: XRANDR: /usr/local/share/xbmc/xbmc-xrandr --output default --mode 0x92 10:15:36 T:4761536 M:352550912 DEBUG: Constructing surface 1920x1200, shared=(nil), fullscreen=0 10:15:36 T:4761536 M:352550912 INFO: GLX Info: NOT Using destination window 10:15:36 T:4761536 M:317366272 INFO: GLX Info: Using parent window 10:15:36 T:4761536 M:317366272 INFO: GLX Info: Creating unshared context 10:15:36 T:4761536 M:315215872 INFO: GL_VENDOR = ATI Technologies Inc. 10:15:36 T:4761536 M:315215872 INFO: GL_RENDERER = ATI Radeon HD 3200 Graphics 10:15:36 T:4761536 M:315215872 INFO: GL_VERSION = 2.1.8388 Release 10:15:36 T:4761536 M:315215872 INFO: GL_EXTENSIONS = GL_AMD_performance_monitor GL_ARB_color_buffer_float GL_ARB_depth_buffer_float GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_instanced GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_instanced_arrays 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_shader_objects GL_ARB_shader_texture_lod GL_ARB_shading_language_100 GL_ARB_shadow GL_ARB_shadow_ambient GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirrored_repeat GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_transpose_matrix GL_ARB_vertex_array_object GL_ARB_vertex_buffer_ 10:15:36 T:4761536 M:315215872 DEBUG: Asking to resize surface to 1920 x 1200 10:15:36 T:4761536 M:315215872 INFO: GL: Maximum texture width: 8192 10:15:36 T:4761536 M:315215872 INFO: load language info file: /usr/local/share/xbmc/language/English/langinfo.xml 10:15:36 T:4761536 M:315215872 DEBUG: SECTION:LoadDLL(Q:\system\ImageLib-i486-linux.so) 10:15:36 T:4761536 M:315215872 DEBUG: Loading: /usr/local/share/xbmc/system/ImageLib-i486-linux.so 10:15:36 T:4761536 M:314601472 WARNING: CreateFile, successfuly opened instead of 10:15:36 T:4761536 M:312123392 DEBUG: PICTURE: loaded image and created texture. height: 401, width: 721 10:15:36 T:4761536 M:312532992 DEBUG: Load /usr/local/share/xbmc/media/splash.png: 180.3ms 10:15:36 T:4761536 M:309153792 INFO: load language file:/usr/local/share/xbmc/language/English/strings.xml 10:15:37 T:4761536 M:308744192 INFO: load keymapping 10:15:37 T:4761536 M:308744192 INFO: Loading /usr/local/share/xbmc/system/Keymap.xml 10:15:37 T:4761536 M:308641792 DEBUG: CButtonTranslator::Load - no userdata keymap found, skipping 10:15:37 T:4761536 M:308641792 INFO: Loading /usr/local/share/xbmc/system/Lircmap.xml 10:15:37 T:4761536 M:308641792 INFO: Loading /home/xbmc/.xbmc/userdata/Lircmap.xml 10:15:37 T:4761536 M:308744192 INFO: Checking skin version of: PM3.HD 10:15:37 T:4761536 M:308539392 INFO: Skin version is: 2.1 10:15:37 T:4761536 M:308539392 INFO: GUI format 1920x1200 1920x1200 @ 60.00Hz (Full Screen) 10:15:37 T:4761536 M:308539392 INFO: install unhandled exception filter 10:15:37 T:4761536 M:308539392 INFO: creating subdirectories 10:15:37 T:4761536 M:308539392 INFO: userdata folder: /home/xbmc/.xbmc/userdata/ 10:15:37 T:4761536 M:308539392 INFO: recording folder: 10:15:37 T:4761536 M:308539392 INFO: screenshots folder: 10:15:37 T:4761536 M:308539392 INFO: thumbnails folder:/home/xbmc/.xbmc/userdata/Thumbnails 10:15:37 T:4761536 M:308539392 WARNING: CreateDirectory, cant create dir . trying lower case. 10:15:37 T:4761536 M:308539392 WARNING: CreateDirectory, cant create dir . trying lower case. 10:15:37 T:4761536 M:308539392 WARNING: CreateDirectory, cant create dir . trying lower case. 10:15:37 T:4761536 M:308539392 NOTICE: start dvd mediatype detection 10:15:37 T:27437968 M:308539392 DEBUG: Running thread 27437968 10:15:37 T:27437968 M:308539392 DEBUG: thread start, auto delete: 0 10:15:37 T:27437968 M:308539392 DEBUG: Compiled with libcdio Version 0.80 10:15:37 T:4761536 M:308539392 NOTICE: initializing playlistplayer 10:15:37 T:4761536 M:308539392 NOTICE: DONE initializing playlistplayer 10:15:37 T:4761536 M:308539392 NOTICE: load default skin:[PM3.HD] 10:15:37 T:4761536 M:308539392 INFO: load skin from:/usr/local/share/xbmc/skin/PM3.HD 10:15:37 T:4761536 M:308539392 INFO: delete old skin... 10:15:37 T:4761536 M:308539392 DEBUG: ------------------- GUI_MSG_WINDOW_DEINIT 10:15:37 T:4761536 M:308539392 DEBUG: Sort by: Size 10:15:37 T:4761536 M:308539392 DEBUG: ------------------- 10:15:37 T:4761536 M:308539392 DEBUG: ------------------- GUI_MSG_WINDOW_DEINIT 10:15:37 T:4761536 M:308539392 DEBUG: 10:15:37 T:4761536 M:308539392 DEBUG: ------------------- 10:15:37 T:4761536 M:308539392 INFO: Default 4:3 resolution directory is /usr/local/share/xbmc/skin/PM3.HD/720p 10:15:37 T:4761536 M:308539392 INFO: Default 16:9 resolution directory is /usr/local/share/xbmc/skin/PM3.HD/720p 10:15:37 T:4761536 M:308539392 INFO: Skin version is: 2.1 10:15:37 T:4761536 M:308539392 INFO: Loading skin includes from /usr/local/share/xbmc/skin/PM3.HD/720p/includes.xml 10:15:37 T:4761536 M:307515392 INFO: load fonts for skin... 10:15:37 T:4761536 M:307515392 INFO: Loading fonts from /usr/local/share/xbmc/skin/PM3.HD/720p/Font.xml 10:15:37 T:4761536 M:305774592 INFO: load new skin... 10:15:37 T:4761536 M:305672192 INFO: Skin version is: 2.1 10:15:37 T:4761536 M:305672192 INFO: Loading skin file: Home.xml 10:15:37 T:99359632 M:305672192 DEBUG: Running thread 99359632 10:15:37 T:99359632 M:305672192 DEBUG: thread start, auto delete: 1 10:15:37 T:99359632 M:305672192 INFO: WEATHER: Downloading weather 10:15:37 T:99359632 M:305672192 INFO: Get URL: http://xoap.weather.com/weather/local/USNY0524?cc=*&unit=m&dayf=4&prod=xoap&link=xoap&par=1004124588&key=079f24145f208494 10:15:37 T:4761536 M:305512448 DEBUG: Load Home.xml: 44.24ms 10:15:37 T:4761536 M:305512448 INFO: Loading user windows, path /usr/local/share/xbmc/skin/PM3.HD/720p 10:15:37 T:4761536 M:305512448 INFO: Loading skin file: /usr/local/share/xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml 10:15:37 T:4761536 M:305315840 INFO: Loading user windows, path /usr/local/share/xbmc/skin/PM3.HD/720p 10:15:37 T:4761536 M:305315840 INFO: Loading skin file: /usr/local/share/xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml 10:15:37 T:4761536 M:305315840 INFO: Loading user windows, path /usr/local/share/xbmc/skin/PM3.HD/720p 10:15:37 T:4761536 M:305315840 INFO: Loading skin file: /usr/local/share/xbmc/skin/PM3.HD/720p/custom_SkinSetting_1111.xml 10:15:37 T:4761536 M:305315840 DEBUG: Load Skin XML: 88.45ms 10:15:37 T:4761536 M:305315840 INFO: initialize new skin... 10:15:37 T:4761536 M:305315840 INFO: Loading skin file: Pointer.xml 10:15:37 T:4761536 M:305315840 DEBUG: Load Pointer.xml: 2.04ms 10:15:37 T:4761536 M:305213440 DEBUG: Load pointer-nofocus.png: 0.1ms (bundled) 10:15:37 T:4761536 M:305213440 DEBUG: Load pointer-focus.png: 0.1ms (bundled) 10:15:37 T:4761536 M:305111040 DEBUG: Load pointer-focus-drag.png: 0.1ms (bundled) 10:15:37 T:4761536 M:305111040 DEBUG: Load pointer-focus-click.png: 0.1ms (bundled) 10:15:37 T:4761536 M:305111040 DEBUG: Alloc resources: 22.72ms (2.15 ms skin load, 19.53 ms preload) 10:15:37 T:4761536 M:305111040 INFO: Loading skin file: DialogVolumeBar.xml 10:15:37 T:4761536 M:305111040 DEBUG: Load DialogVolumeBar.xml: 11.91ms 10:15:37 T:4761536 M:305111040 DEBUG: Load ProgressBack.png: 0.1ms (bundled) 10:15:37 T:4761536 M:305111040 DEBUG: Load ProgressFront.png: 0.0ms (bundled) 10:15:37 T:4761536 M:305111040 DEBUG: Alloc resources: 42.05ms (12.04 ms skin load, 29.68 ms preload) 10:15:37 T:4761536 M:305111040 INFO: Loading skin file: DialogSeekBar.xml 10:15:37 T:4761536 M:305111040 DEBUG: Load DialogSeekBar.xml: 16.85ms 10:15:37 T:4761536 M:305111040 DEBUG: Load seekslider.png: 0.1ms (bundled) 10:15:37 T:4761536 M:305111040 DEBUG: Load slider_nibFO.png: 0.0ms (bundled) 10:15:37 T:4761536 M:305111040 DEBUG: Alloc resources: 17.83ms (17.04 ms skin load, 0.36 ms preload) 10:15:37 T:4761536 M:305111040 INFO: Loading skin file: DialogKaiToast.xml 10:15:37 T:4761536 M:305111040 DEBUG: Load DialogKaiToast.xml: 10.76ms 10:15:37 T:4761536 M:305111040 DEBUG: Alloc resources: 10.88ms (10.87 ms skin load, 0.00 ms preload) 10:15:37 T:4761536 M:305111040 INFO: Loading skin file: DialogMuteBug.xml 10:15:37 T:4761536 M:305111040 DEBUG: Load DialogMuteBug.xml: 7.97ms 10:15:37 T:4761536 M:305111040 DEBUG: Alloc resources: 8.07ms (8.06 ms skin load, 0.00 ms preload) 10:15:37 T:4761536 M:304906240 INFO: Loading /usr/local/share/xbmc/skin/PM3.HD/sounds/sounds.xml 10:15:37 T:4761536 M:304803840 INFO: skin loaded... 10:15:37 T:109849488 M:304803840 DEBUG: Running thread 109849488 10:15:37 T:109849488 M:304803840 DEBUG: thread start, auto delete: 0 10:15:37 T:109849488 M:304803840 DEBUG: staticThread, deleting thread graphic context 10:15:37 T:109849488 M:304803840 DEBUG: Thread 109849488 terminating 10:15:37 T:4761536 M:304599040 DEBUG: Activating window ID: 10000 10:15:37 T:4761536 M:304599040 DEBUG: Checking if window ID 10000 is locked. 10:15:37 T:4761536 M:304599040 DEBUG: ------------------- GUI_MSG_WINDOW_INIT 10:15:37 T:4761536 M:304599040 DEBUG: Home 10:15:37 T:4761536 M:304599040 DEBUG: ------------------- 10:15:37 T:4761536 M:304496640 DEBUG: Load home-button.png: 0.2ms (bundled) 10:15:37 T:4761536 M:304394240 DEBUG: Load home-playmedia.png: 0.8ms (bundled) 10:15:37 T:4761536 M:304291840 DEBUG: Load home-favourites.png: 0.7ms (bundled) 10:15:37 T:4761536 M:304291840 DEBUG: Load home-power.png: 0.8ms (bundled) 10:15:37 T:4761536 M:304291840 DEBUG: Alloc resources: 31.54ms (0.02 ms skin load, 16.48 ms preload) 10:15:37 T:3052403600 M:304291840 DEBUG: Running thread 3052403600 10:15:37 T:3052403600 M:304291840 DEBUG: thread start, auto delete: 1 10:15:37 T:4761536 M:304291840 INFO: removing tempfiles 10:15:37 T:4761536 M:304087040 DEBUG: ------------------- GUI_MSG_WINDOW_INIT 10:15:37 T:4761536 M:304087040 DEBUG: 10:15:37 T:4761536 M:304087040 DEBUG: ------------------- 10:15:37 T:4761536 M:304087040 DEBUG: Alloc resources: 0.01ms (0.00 ms skin load, 0.01 ms preload) 10:15:37 T:4761536 M:304087040 NOTICE: Updating video library on startup 10:15:37 T:4761536 M:304087040 DEBUG: ------------------- GUI_MSG_WINDOW_INIT 10:15:37 T:4761536 M:304087040 DEBUG: 10:15:37 T:4761536 M:304087040 DEBUG: ------------------- 10:15:37 T:4761536 M:304087040 INFO: Loading skin file: DialogVideoScan.xml 10:15:37 T:4761536 M:304087040 DEBUG: Load DialogVideoScan.xml: 10.99ms 10:15:37 T:4761536 M:304087040 DEBUG: Alloc resources: 11.28ms (11.14 ms skin load, 0.12 ms preload) 10:15:37 T:4761536 M:304087040 ERROR: Control 402 in window 10133 has been asked to focus, but it can't 10:15:37 T:3039820688 M:303575040 DEBUG: Running thread 3039820688 10:15:37 T:3039820688 M:303575040 DEBUG: thread start, auto delete: 0 10:15:37 T:4761536 M:303575040 INFO: HAL: Starting initializing 10:15:37 T:3039820688 M:303575040 DEBUG: Process - Starting scan 10:15:37 T:3029330832 M:303575040 DEBUG: Running thread 3029330832 10:15:37 T:3029330832 M:303575040 DEBUG: thread start, auto delete: 0 10:15:37 T:3029330832 M:303575040 DEBUG: staticThread, deleting thread graphic context 10:15:37 T:3029330832 M:303575040 DEBUG: Thread 3029330832 terminating 10:15:37 T:99359632 M:303575040 INFO: WEATHER: Weather download successful 10:15:37 T:4761536 M:303575040 DEBUG: HAL: Clearing old global device list, if any 10:15:37 T:4761536 M:303575040 NOTICE: HAL: Generating global device list 10:15:37 T:4761536 M:303575040 DEBUG: HAL: Added - disk | UUID 543C9D013C9CDEF4 | FileSystem ntfs | HotPlugged YES | Type 1 |Approved YES 10:15:37 T:4761536 M:303575040 NOTICE: LFS: Added - disk | Volume (ntfs) 10:15:37 T:4761536 M:298762240 DEBUG: HAL: Added - disk | UUID 65208573-0669-410f-83ad-256678781cc9 | FileSystem ext3 | Mounted on /boot | HotPlugged NO | Type 1 |Approved YES 10:15:37 T:4761536 M:298762240 NOTICE: LFS: Added - disk | /boot 10:15:37 T:4761536 M:298659840 DEBUG: HAL: Added - disk | UUID e2a7a62d:5e9c63e0:b9726843:bfaa4052 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO 10:15:37 T:4761536 M:298659840 NOTICE: LFS: Added - disk | Volume (linux_raid_member) 10:15:37 T:3039820688 M:298455040 DEBUG: Sort, sorting took 0 millis 10:15:37 T:4761536 M:298455040 DEBUG: HAL: Added - disk | UUID e2a7a62d:5e9c63e0:b9726843:bfaa4052 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO 10:15:37 T:4761536 M:298455040 NOTICE: LFS: Updated - disk | Volume (linux_raid_member) 10:15:37 T:4761536 M:298455040 DEBUG: HAL: Added - disk | UUID e2a7a62d:5e9c63e0:b9726843:bfaa4052 | FileSystem linux_raid_member | HotPlugged NO | Type 1 |Approved NO 10:15:37 T:4761536 M:298455040 NOTICE: LFS: Updated - disk | Volume (linux_raid_member) 10:15:37 T:4761536 M:298455040 DEBUG: HAL: Added - disk | UUID 1v6CeL-kKa1-7C7F-mPUC-Oyc4-Oiws-djydr1 | FileSystem LVM2_member | HotPlugged NO | Type 1 |Approved NO 10:15:37 T:4761536 M:298455040 NOTICE: LFS: Added - disk | Volume (LVM2_member) 10:15:37 T:3039820688 M:298147840 DEBUG: DoScan Skipping dir '/home/jeffutter/Videos/Movies/' due to no change 10:15:37 T:3039820688 M:298147840 DEBUG: Hash[movies,/home/jeffutter/Videos/Movies/]:DB=[25BA7C8CB88AD9CC4A2A82CBA0F1AFD7],Computed=[25BA7C8CB88AD9CC4A2A82CBA0F1AFD7] 10:15:37 T:3039820688 M:298147840 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/Movies/ 10:15:37 T:3052403600 M:298147840 DEBUG: staticThread, deleting thread object 10:15:37 T:3052403600 M:298147840 DEBUG: staticThread, deleting thread graphic context 10:15:37 T:3052403600 M:298147840 DEBUG: Thread 3052403600 terminating 10:15:37 T:3039820688 M:298147840 DEBUG: Sort, sorting took 0 millis 10:15:37 T:3039820688 M:298147840 DEBUG: DoScan Skipping dir '/home/jeffutter/Videos/Movies/Ray.DVDRip.XviD-2005/' due to no change 10:15:37 T:3039820688 M:298147840 DEBUG: Hash[movies,/home/jeffutter/Videos/Movies/Ray.DVDRip.XviD-2005/]:DB=[F85D7CED2A3DCBEEFF8C84072BAF6BF3],Computed=[F85D7CED2A3DCBEEFF8C84072BAF6BF3] 10:15:37 T:3039820688 M:298147840 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/Movies/Ray.DVDRip.XviD-2005/ 10:15:37 T:3039820688 M:298147840 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/]:DB=[2DCC2492FE6FC13CE9270B5E9D382ABA],Computed=[2DCC2492FE6FC13CE9270B5E9D382ABA] 10:15:37 T:3039820688 M:298147840 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/ 10:15:37 T:3039820688 M:298147840 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/4400/]:DB=[],Computed=[] 10:15:37 T:4761536 M:297738240 INFO: HAL: Generated global device list, found 151 10:15:37 T:4761536 M:297738240 INFO: HAL: Sucessfully initialized 10:15:37 T:4761536 M:297738240 NOTICE: Updating music library on startup 10:15:37 T:4761536 M:297738240 DEBUG: ------------------- GUI_MSG_WINDOW_INIT 10:15:37 T:4761536 M:297738240 DEBUG: 10:15:37 T:4761536 M:297738240 DEBUG: ------------------- 10:15:37 T:4761536 M:297738240 INFO: Loading skin file: DialogMusicScan.xml 10:15:37 T:4761536 M:297328640 DEBUG: Load DialogMusicScan.xml: 5.84ms 10:15:37 T:4761536 M:297328640 DEBUG: Alloc resources: 6.03ms (5.96 ms skin load, 0.05 ms preload) 10:15:37 T:4761536 M:297328640 ERROR: Control 402 in window 10112 has been asked to focus, but it can't 10:15:37 T:4761536 M:297328640 INFO: Music scan has started ... enabling Tag Reading, and Remote Thumbs 10:15:37 T:3016752016 M:296202240 DEBUG: Running thread 3016752016 10:15:37 T:3016752016 M:296202240 DEBUG: thread start, auto delete: 0 10:15:37 T:3016752016 M:296202240 DEBUG: Process - Starting scan 10:15:37 T:3006262160 M:296202240 DEBUG: Running thread 3006262160 10:15:37 T:3006262160 M:295997440 DEBUG: thread start, auto delete: 0 10:15:37 T:3006262160 M:295997440 DEBUG: staticThread, deleting thread graphic context 10:15:37 T:3006262160 M:295997440 DEBUG: Thread 3006262160 terminating 10:15:37 T:3016752016 M:295997440 DEBUG: Process - Finished scan 10:15:37 T:3016752016 M:295997440 NOTICE: My Music: Scanning for music info using worker thread, operation took 00:00 10:15:37 T:3016752016 M:295997440 INFO: Music scan was stopped or finished ... restoring FindRemoteThumbs 10:15:37 T:4761536 M:295997440 NOTICE: initialize done 10:15:37 T:4761536 M:295997440 NOTICE: Running the application... 10:15:37 T:4761536 M:295997440 INFO: initializing python engine. 10:15:37 T:4761536 M:295997440 DEBUG: Loading: /usr/local/share/xbmc/system/python/python24-i486-linux.so 10:15:37 T:3016752016 M:295895040 DEBUG: staticThread, deleting thread graphic context 10:15:37 T:3016752016 M:295895040 DEBUG: Thread 3016752016 terminating 10:15:38 T:99359632 M:298352640 DEBUG: staticThread, deleting thread object 10:15:38 T:99359632 M:298352640 DEBUG: staticThread, deleting thread graphic context 10:15:38 T:99359632 M:298352640 DEBUG: Thread 99359632 terminating 10:15:38 T:3039820688 M:296202240 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/4400/ 10:15:38 T:3039820688 M:296202240 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Desperate Housewives/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:295690240 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Desperate Housewives/ 10:15:38 T:3039820688 M:295690240 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Futurama/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:294682624 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Futurama/ 10:15:38 T:3039820688 M:294682624 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Get Smart/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:291610624 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Get Smart/ 10:15:38 T:3039820688 M:291610624 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Grey's Anatomy/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:287309824 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Grey's Anatomy/ 10:15:38 T:3039820688 M:287309824 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/House/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:284135424 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/House/ 10:15:38 T:3039820688 M:284135424 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Law & Order: Special Victims Unit/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:281157632 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Law & Order: Special Victims Unit/ 10:15:38 T:3039820688 M:281055232 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Mister Sterling/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:280952832 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Mister Sterling/ 10:15:38 T:3039820688 M:280952832 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Psych/]:DB=[],Computed=[] 10:15:38 T:3039820688 M:280952832 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Psych/ 10:15:38 T:3039820688 M:280952832 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Scrubs/]:DB=[],Computed=[] 10:15:39 T:3039820688 M:280952832 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Scrubs/ 10:15:39 T:3039820688 M:280952832 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Sex and The City/]:DB=[],Computed=[] 10:15:39 T:4761536 M:280952832 DEBUG: new python thread created. id=1 10:15:39 T:3006262160 M:280850432 DEBUG: Running thread 3006262160 10:15:39 T:3006262160 M:280850432 DEBUG: thread start, auto delete: 0 10:15:39 T:3006262160 M:280850432 DEBUG: Python thread: start processing 10:15:39 T:4761536 M:280850432 DEBUG: NetworkMessage - Starting network services 10:15:39 T:4761536 M:280850432 NOTICE: Webserver: Starting... 10:15:39 T:4761536 M:280850432 DEBUG: xbmcHttpShim starts 10:15:39 T:2991586192 M:280850432 DEBUG: Running thread 2991586192 10:15:39 T:2991586192 M:280850432 DEBUG: thread start, auto delete: 0 10:15:39 T:2991586192 M:280850432 INFO: WebServer: Server starting using /usr/local/share/xbmc/web on 192.168.0.2:8080 10:15:39 T:2991586192 M:280850432 DEBUG: DB: Registering database table 10:15:39 T:2991586192 M:280850432 DEBUG: DB: Registering database table 10:15:39 T:2991586192 M:280850432 DEBUG: DB: Registering database table 10:15:39 T:2991586192 M:280850432 DEBUG: UM: Loading User Configuration from file 10:15:39 T:2991586192 M:280850432 DEBUG: DB: About to read data file 10:15:39 T:2991586192 M:280850432 DEBUG: DB: Failed to stat persistent data file. 10:15:39 T:2991586192 M:280850432 DEBUG: webs: Listening for HTTP requests at address 192.168.0.2:8080 10:15:39 T:2991586192 M:280850432 NOTICE: Webserver: Started 10:15:39 T:3006262160 M:280850432 NOTICE: -->Python Interpreter Initialized<-- 10:15:39 T:3006262160 M:280850432 NOTICE: 10:15:39 T:3006262160 M:280850432 INFO: Scriptresult: Succes 10:15:39 T:4761536 M:280850432 DEBUG: UM: Writing User Configuration to file 10:15:39 T:4761536 M:280850432 DEBUG: DB: About to save database to file 10:15:39 T:4761536 M:280850432 DEBUG: WARNING: Failed to open file /usr/local/share/xbmc/web/data.tmp 10:15:39 T:4761536 M:280850432 NOTICE: ES: Starting event server 10:15:39 T:2981096336 M:280850432 DEBUG: Running thread 2981096336 10:15:39 T:2981096336 M:280850432 DEBUG: thread start, auto delete: 0 10:15:39 T:2970606480 M:280850432 DEBUG: Running thread 2970606480 10:15:39 T:2981096336 M:280850432 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777 10:15:39 T:2981096336 M:280850432 NOTICE: UDP: Listening on port 9777 10:15:39 T:4761536 M:280850432 INFO: GL: Disabling VSYNC 10:15:39 T:3039820688 M:280850432 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Sex and The City/ 10:15:39 T:3039820688 M:280850432 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/That '70s Show/]:DB=[],Computed=[] 10:15:39 T:4761536 M:278085632 DEBUG: Load background-videos.jpg: 61.0ms (bundled) 10:15:39 T:3039820688 M:278085632 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/That '70s Show/ 10:15:39 T:3039820688 M:278085632 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/The IT Crowd/]:DB=[],Computed=[] 10:15:39 T:3039820688 M:270405632 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/The IT Crowd/ 10:15:39 T:3039820688 M:270405632 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/The Office (UK)/]:DB=[],Computed=[] 10:15:39 T:4761536 M:273375232 DEBUG: Load Background-Home-2.png: 80.3ms (bundled) 10:15:39 T:3039820688 M:269279232 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/The Office (UK)/ 10:15:39 T:3039820688 M:269279232 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/The Office (US)/]:DB=[],Computed=[] 10:15:39 T:4761536 M:271429632 DEBUG: Load Home-Menu-Back.png: 12.4ms (bundled) 10:15:39 T:3039820688 M:272515072 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/The Office (US)/ 10:15:39 T:3039820688 M:272515072 DEBUG: Hash[tvshows,/home/jeffutter/Videos/TV/Whose Line Is It Anyway? (US)/]:DB=[],Computed=[] 10:15:39 T:4761536 M:272515072 DEBUG: Load home-button-focus.gif: 12.0ms (bundled) 10:15:39 T:4761536 M:269262848 DEBUG: Load Header.png: 4.9ms (bundled) 10:15:39 T:4761536 M:269570048 DEBUG: Load xbmc-logo.png: 2.8ms (bundled) 10:15:39 T:4761536 M:268955648 DEBUG: Load Footer.png: 4.8ms (bundled) 10:15:39 T:3039820688 M:268955648 DEBUG: DoScan - Finished dir: /home/jeffutter/Videos/TV/Whose Line Is It Anyway? (US)/ 10:15:39 T:4761536 M:269877248 DEBUG: Load NoCover_1.png: 4.8ms (bundled) 10:15:39 T:4761536 M:269877248 DEBUG: Load NoCover_1.png: 0.5ms (bundled) 10:15:39 T:4761536 M:269877248 DEBUG: Load black-back.png: 10.6ms (bundled) 10:15:39 T:4761536 M:269877248 DEBUG: Load VolumeIcon-Mute.png: 7.2ms (bundled) 10:15:39 T:4761536 M:269877248 DEBUG: ------------------- GUI_MSG_WINDOW_DEINIT 10:15:39 T:4761536 M:269877248 DEBUG: 10:15:39 T:4761536 M:269877248 DEBUG: ------------------- 10:15:39 T:3039820688 M:269877248 DEBUG: Process - Finished scan 10:15:39 T:3039820688 M:269877248 NOTICE: My Videos: Scanning for video info using worker thread, operation took 00:01 10:15:39 T:3039820688 M:269877248 INFO: Video scan was stopped or finished ... restoring FindRemoteThumbs 10:15:39 T:3039820688 M:269877248 DEBUG: staticThread, deleting thread graphic context 10:15:39 T:3039820688 M:269877248 DEBUG: Thread 3039820688 terminating 10:15:39 T:4761536 M:269877248 DEBUG: ------------------- GUI_MSG_WINDOW_DEINIT 10:15:39 T:4761536 M:269877248 DEBUG: 10:15:39 T:4761536 M:269877248 DEBUG: ------------------- 10:15:39 T:3006262160 M:269877248 DEBUG: xbp_dlopen loading python lib /usr/local/share/xbmc/system/python/python24.zip/time.so. flags: 2 10:15:39 T:3006262160 M:269877248 DEBUG: Loading: /tmp/xbmc-xbmc/time.so 10:15:39 T:3006262160 M:269877248 DEBUG: RegisterExtensionLib, adding Z:/time.so (0x9df8f78) 10:15:39 T:3006262160 M:269877248 DEBUG: xbp_dlsym - load symbol inittime 10:15:39 T:3006262160 M:269877248 DEBUG: xbp_dlopen loading python lib /usr/local/share/xbmc/system/python/python24.zip/collections.so. flags: 2 10:15:39 T:3006262160 M:269877248 DEBUG: Loading: /tmp/xbmc-xbmc/collections.so 10:15:39 T:3006262160 M:269877248 DEBUG: RegisterExtensionLib, adding Z:/collections.so (0x9d25268) 10:15:39 T:3006262160 M:269877248 DEBUG: xbp_dlsym - load symbol initcollections 10:15:39 T:3006262160 M:269877248 INFO: Python script stopped 10:15:39 T:3006262160 M:269877248 DEBUG: staticThread, deleting thread graphic context 10:15:39 T:3006262160 M:269877248 DEBUG: Thread 3006262160 terminating 10:15:39 T:4761536 M:269877248 DEBUG: python thread 1 destructed 10:15:39 T:4761536 M:269877248 INFO: Python, unloading python24.dll because no scripts are running anymore 10:15:39 T:4761536 M:269877248 DEBUG: UnloadExtensionLibs, clearing python extension libraries 10:15:39 T:4761536 M:269877248 DEBUG: Unloading: Z:/time.so 10:15:39 T:4761536 M:269877248 DEBUG: Unloading: Z:/collections.so 10:15:39 T:4761536 M:269877248 DEBUG: Unloading: python24-i486-linux.so 10:15:40 T:2991586192 M:266022912 DEBUG: webs: accept request 10:15:40 T:2991586192 M:266022912 DEBUG: webs: accept request 10:15:42 T:2991586192 M:268431360 DEBUG: webs: accept request 10:15:42 T:2991586192 M:268431360 DEBUG: Sort, sorting took 0 millis 10:15:44 T:2991586192 M:268644352 DEBUG: webs: accept request 10:15:44 T:2991586192 M:268644352 DEBUG: Sort, sorting took 0 millis 10:15:46 T:2991586192 M:268398592 DEBUG: webs: accept request 10:15:46 T:2991586192 M:268398592 DEBUG: Sort, sorting took 0 millis 10:15:48 T:2991586192 M:268128256 DEBUG: webs: accept request 10:15:48 T:2991586192 M:268128256 DEBUG: Sort, sorting took 0 millis 10:15:49 T:2991586192 M:264839168 DEBUG: webs: accept request 10:15:49 T:3006262160 M:264839168 DEBUG: Running thread 3006262160 10:15:49 T:3006262160 M:264839168 DEBUG: thread start, auto delete: 1 10:15:49 T:3006262160 M:264839168 DEBUG: new file set audiostream:0 10:15:49 T:3006262160 M:264839168 DEBUG: CPlayerCoreFactor::GetPlayers(/home/jeffutter/Videos/Movies/Elizabethtown.DVDRip.XviD-DiAMOND.[www.descargasweb.net].avi) 10:15:49 T:3029330832 M:264839168 DEBUG: Running thread 3029330832 10:15:49 T:3029330832 M:264839168 DEBUG: thread start, auto delete: 1 10:15:49 T:3006262160 M:264839168 NOTICE: DVDPlayer: Opening: /home/jeffutter/Videos/Movies/Elizabethtown.DVDRip.XviD-DiAMOND.[www.descargasweb.net].avi 10:15:49 T:3006262160 M:264839168 WARNING: CDVDMessageQueue::Put MSGQ_NOT_INITIALIZED 10:15:49 T:2960116624 M:264839168 DEBUG: Running thread 2960116624 10:15:49 T:2960116624 M:264839168 DEBUG: thread start, auto delete: 0 10:15:49 T:2960116624 M:264839168 NOTICE: Creating InputStream 10:15:49 T:2960116624 M:264839168 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 10:15:49 T:2960116624 M:264839168 DEBUG: CacheSubtitles: START 10:15:49 T:2960116624 M:264839168 DEBUG: CacheSubtitles: Checking for common subirs... 10:15:49 T:2960116624 M:264839168 DEBUG: CacheSubtitles: Done (time: 3 ms) 10:15:49 T:2960116624 M:264839168 DEBUG: CacheSubtitles: Searching for subtitles... 10:15:49 T:2960116624 M:264839168 DEBUG: CacheSubtitles: Done (time: 3 ms) 10:15:49 T:2960116624 M:264839168 DEBUG: CacheSubtitles: END (total time: 6 ms) 10:15:49 T:2960116624 M:264839168 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 10:15:49 T:2960116624 M:264839168 NOTICE: Creating Demuxer 10:15:49 T:2960116624 M:264839168 DEBUG: SECTION:LoadDLL(Q:\system\players\dvdplayer\avutil-49-i486-linux.so) 10:15:49 T:2960116624 M:264839168 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/avutil-49-i486-linux.so 10:15:49 T:2960116624 M:264839168 DEBUG: SECTION:LoadDLL(Q:\system\players\dvdplayer\avcodec-52-i486-linux.so) 10:15:49 T:2960116624 M:264839168 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so 10:15:49 T:2960116624 M:262688768 DEBUG: SECTION:LoadDLL(Q:\system\players\dvdplayer\avformat-52-i486-linux.so) 10:15:49 T:2960116624 M:262688768 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/avformat-52-i486-linux.so 10:15:50 T:2960116624 M:242909184 DEBUG: Open - av_find_stream_info starting 10:15:50 T:2960116624 M:242806784 INFO: ffmpeg: [NULL] Invalid and inefficient vfw-avi packed B frames detected 10:15:50 T:2960116624 M:242294784 DEBUG: Open - av_find_stream_info finished 10:15:50 T:2960116624 M:242294784 INFO: ffmpeg: Input #0, avi, from '/home/jeffutter/Videos/Movies/Elizabethtown.DVDRip.XviD-DiAMOND.[www.descargasweb.net].avi': 10:15:50 T:2960116624 M:242294784 INFO: ffmpeg: Duration: 02:03:41.37, start: 0.000000, bitrate: 791 kb/s 10:15:50 T:2960116624 M:242294784 INFO: ffmpeg: Stream #0.0: Video: mpeg4, yuv420p, 608x336 [PAR 1:1 DAR 38:21], 23.98 tb(r) 10:15:50 T:2960116624 M:242294784 INFO: ffmpeg: Stream #0.1: Audio: mp3, 48000 Hz, stereo, s16, 32 kb/s 10:15:50 T:2960116624 M:242294784 NOTICE: Opening video stream: 0 source: 256 10:15:50 T:2960116624 M:242294784 NOTICE: Creating video codec with codec id: 13 10:15:50 T:2960116624 M:242294784 DEBUG: FactoryCodec - Video: FFmpeg - Opening 10:15:50 T:2960116624 M:242294784 DEBUG: SECTION:LoadDLL(Q:\system\players\dvdplayer\swscale-0.6.1-i486-linux.so) 10:15:50 T:2960116624 M:242294784 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/swscale-0.6.1-i486-linux.so 10:15:50 T:2960116624 M:241987584 DEBUG: FactoryCodec - Video: FFmpeg - Opened 10:15:50 T:2960116624 M:241987584 NOTICE: Creating video thread 10:15:50 T:2907667344 M:241987584 DEBUG: Running thread 2907667344 10:15:50 T:2960116624 M:241987584 NOTICE: Opening audio stream: 1 source: 256 10:15:50 T:2907667344 M:241987584 DEBUG: thread start, auto delete: 0 10:15:50 T:2960116624 M:241987584 NOTICE: Finding audio codec for: 86017 10:15:50 T:2960116624 M:241987584 DEBUG: FactoryCodec - Audio: passthrough - Opening 10:15:50 T:2960116624 M:241987584 DEBUG: FactoryCodec - Audio: passthrough - Failed 10:15:50 T:2960116624 M:241987584 DEBUG: FactoryCodec - Audio: libmad - Opening 10:15:50 T:2960116624 M:241987584 DEBUG: SECTION:LoadDLL(Q:\system\players\dvdplayer\libmad-i486-linux.so) 10:15:50 T:2960116624 M:241987584 DEBUG: Loading: /usr/local/share/xbmc/system/players/dvdplayer/libmad-i486-linux.so 10:15:50 T:2960116624 M:241987584 DEBUG: FactoryCodec - Audio: libmad - Opened 10:15:50 T:2960116624 M:241987584 NOTICE: Creating audio thread 10:15:50 T:2960116624 M:241987584 DEBUG: Playback has started 10:15:50 T:2960116624 M:241987584 INFO: Loading skin file: DialogProgress.xml 10:15:50 T:2907667344 M:241987584 NOTICE: running thread: video_thread 10:15:50 T:2897177488 M:241987584 DEBUG: Running thread 2897177488 10:15:50 T:2897177488 M:241987584 DEBUG: thread start, auto delete: 0 10:15:50 T:2897177488 M:241987584 NOTICE: running thread: CDVDPlayerAudio::Process() 10:15:50 T:4761536 M:241987584 DEBUG: Load gradient.png: 0.3ms (bundled) 10:15:50 T:4761536 M:241373184 DEBUG: Load defaultVideoCover.png: 13.8ms (bundled) 10:15:50 T:4761536 M:241373184 DEBUG: CGUIInfoManager::SetCurrentMovie(/home/jeffutter/Videos/Movies/Elizabethtown.DVDRip.XviD-DiAMOND.[www.descargasweb.net].avi) 10:15:50 T:2960116624 M:241373184 DEBUG: Load DialogProgress.xml: 39.63ms 10:15:50 T:2960116624 M:241373184 WARNING: CDVDPlayer::CheckContinuity - stream wrapback detected (2) 10:15:50 T:2960116624 M:241373184 WARNING: CDVDPlayer::CheckContinuity - resyncing due to stream wrapback (2) 10:15:50 T:4761536 M:241373184 DEBUG: GetMovieId (/home/jeffutter/Videos/Movies/Elizabethtown.DVDRip.XviD-DiAMOND.[www.descargasweb.net].avi), query = select idMovie from movie where idFile=7 10:15:50 T:4761536 M:241373184 DEBUG: GetMovieId (/home/jeffutter/Videos/Movies/Elizabethtown.DVDRip.XviD-DiAMOND.[www.descargasweb.net].avi), query = select idMovie from movie where idFile=7 10:15:50 T:4761536 M:241270784 DEBUG: SetCurrentMovie, got movie info! 10:15:50 T:4761536 M:241270784 DEBUG: Title = Elizabethtown 10:15:50 T:4761536 M:241168384 DEBUG: ------------------- GUI_MSG_WINDOW_INIT 10:15:50 T:4761536 M:241168384 DEBUG: 10:15:50 T:4761536 M:241168384 DEBUG: ------------------- 10:15:50 T:4761536 M:241168384 DEBUG: Load seekslider.png: 0.1ms (bundled) 10:15:50 T:4761536 M:241168384 DEBUG: Load slider_nibFO.png: 0.0ms (bundled) 10:15:50 T:4761536 M:241168384 DEBUG: Alloc resources: 1.84ms (0.00 ms skin load, 1.47 ms preload) 10:15:50 T:4761536 M:240861184 DEBUG: Load /home/xbmc/.xbmc/userdata/Thumbnails/Video/d/d1974545.tbn: 22.9ms 10:15:50 T:2907667344 M:234307584 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 10:15:50 T:2907667344 M:234205184 INFO: ffmpeg: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected 10:15:50 T:2907667344 M:234000384 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(41708.375042, 1) 10:15:50 T:3029330832 M:233590784 DEBUG: staticThread, deleting thread object 10:15:50 T:3029330832 M:233590784 DEBUG: staticThread, deleting thread graphic context 10:15:50 T:3029330832 M:233590784 DEBUG: Thread 3029330832 terminating 10:15:50 T:2907667344 M:233590784 NOTICE: fps: 23.976000, pwidth: 608, pheight: 336, dwidth: 608, dheight: 336 10:15:50 T:2907667344 M:233590784 DEBUG: OutputPicture - change configuration. 608x336. framerate: 23.98 10:15:50 T:2907667344 M:233590784 NOTICE: Display resolution AUTO : 1920x1200 @ 60.00Hz (Full Screen) (12) 10:15:50 T:2907667344 M:233590784 WARNING: Upscale: selected algorithm 1 10:15:50 T:2897177488 M:233385984 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(24000.000000, 1) 10:15:50 T:4761536 M:233385984 DEBUG: Activating window ID: 12005 10:15:50 T:4761536 M:233385984 DEBUG: Checking if window ID 12005 is locked. 10:15:50 T:4761536 M:233385984 DEBUG: ------------------- GUI_MSG_WINDOW_DEINIT 10:15:50 T:4761536 M:233385984 DEBUG: Home 10:15:50 T:4761536 M:233385984 DEBUG: ------------------- 10:15:50 T:2897177488 M:233385984 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through 10:15:50 T:2897177488 M:233385984 DEBUG: CALSADirectSound::CALSADirectSound - opening alsa device 10:15:50 T:3006262160 M:233385984 DEBUG: staticThread, deleting thread object 10:15:50 T:3006262160 M:233385984 DEBUG: staticThread, deleting thread graphic context 10:15:50 T:3006262160 M:233385984 DEBUG: Thread 3006262160 terminating 10:15:50 T:4761536 M:233283584 DEBUG: ------------------- GUI_MSG_WINDOW_INIT 10:15:50 T:4761536 M:233283584 DEBUG: Fullscreen video 10:15:50 T:4761536 M:233283584 DEBUG: ------------------- 10:15:50 T:4761536 M:233283584 INFO: Loading skin file: VideoFullScreen.xml 10:15:50 T:2897177488 M:233283584 DEBUG: Initialize - using alsa device default 10:15:50 T:4761536 M:233177088 DEBUG: Load VideoFullScreen.xml: 11.99ms 10:15:50 T:4761536 M:233177088 DEBUG: Alloc resources: 12.14ms (12.13 ms skin load, 0.01 ms preload) 10:15:50 T:4761536 M:233177088 INFO: Loading skin file: VideoOSD.xml 10:15:50 T:2897177488 M:232972288 DEBUG: CDVDPlayerAudio:: Discontinuty - was:97940.737000, should be:24000.000000, error:-73940.737000 10:15:50 T:4761536 M:232824832 DEBUG: Load VideoOSD.xml: 22.98ms 10:15:50 T:4761536 M:232722432 DEBUG: Load black-back2.png: 0.9ms (bundled) 10:15:50 T:4761536 M:232722432 DEBUG: Load PlayerControls-FullScreenFO.png: 0.1ms (bundled) 10:15:50 T:4761536 M:232620032 DEBUG: Load PlayerControls-FullScreenNF.png: 0.1ms (bundled) 10:15:50 T:4761536 M:232620032 DEBUG: Load osd_button_rec.png: 0.4ms (bundled) 10:15:50 T:4761536 M:232620032 DEBUG: Load osd_button_recNF.png: 0.4ms (bundled) 10:15:50 T:4761536 M:232517632 DEBUG: Load osd_button_rec2.png: 0.3ms (bundled) 10:15:50 T:4761536 M:232517632 DEBUG: Load osd_button_dvdmenu.png: 0.4ms (bundled) 10:15:50 T:4761536 M:232415232 DEBUG: Load osd_button_dvdmenuNF.png: 0.4ms (bundled) 10:15:50 T:4761536 M:231997440 DEBUG: Load osd_button_dvdmenu2.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_panel.png: 6.6ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_skip_minusFO.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_rewFO.png: 0.3ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_rewSELNF.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_pauseFO.png: 0.5ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_pauseNF.png: 0.5ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_playFO.png: 0.5ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_playNF.png: 0.6ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_ffFO.png: 0.2ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_ffSELNF.png: 0.2ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_skip_plusFO.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_audioFO.png: 6.1ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_audioSELNF.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_videoFO.png: 0.3ms (bundled) 10:15:50 T:4761536 M:230871040 DEBUG: Load osd_button_videoSELNF.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230666240 DEBUG: Load osd_button_stopFO.png: 0.3ms (bundled) 10:15:50 T:4761536 M:230563840 DEBUG: Load osd_button_bookmarkFO.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230461440 DEBUG: Load osd_button_bookmarkSELNF.png: 0.4ms (bundled) 10:15:50 T:4761536 M:230461440 DEBUG: Alloc resources: 49.01ms (0.01 ms skin load, 1.44 ms preload) 10:15:50 T:4761536 M:230461440 INFO: Loading skin file: VideoOSDSettings.xml 10:15:50 T:4761536 M:230461440 DEBUG: Load VideoOSDSettings.xml: 9.32ms 10:15:50 T:4761536 M:228823040 DEBUG: Load DialogBack.png: 8.2ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load DialogFront.png: 8.1ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load close-windowFO.png: 0.1ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load close-window.png: 0.1ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load separator.png: 0.1ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load osd_slider_bg.png: 0.1ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load osd_slider_nibNF.png: 0.0ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load osd_slider_nib.png: 0.0ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load button-focus.png: 0.2ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load button-nofocus.png: 0.2ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load radiobutton-focus.png: 0.1ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load radiobutton-nofocus.png: 0.1ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load scroll-up-2.png: 0.2ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load scroll-up-focus-2.png: 0.2ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load scroll-down-2.png: 0.2ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Load scroll-down-focus-2.png: 0.2ms (bundled) 10:15:50 T:4761536 M:227696640 DEBUG: Alloc resources: 22.22ms (0.01 ms skin load, 0.53 ms preload) 10:15:50 T:4761536 M:227696640 INFO: Loading skin file: VideoOSDSettings.xml 10:15:50 T:4761536 M:227696640 DEBUG: Load VideoOSDSettings.xml: 5.25ms 10:15:50 T:4761536 M:227696640 DEBUG: Alloc resources: 0.39ms (0.01 ms skin load, 0.12 ms preload) 10:15:50 T:2897177488 M:227696640 DEBUG: CDVDPlayerAudio:: Discontinuty - was:215110.150000, should be:182377.721945, error:-32732.428055 10:15:50 T:4761536 M:227696640 DEBUG: GL: Requested render method: 0 10:15:50 T:4761536 M:227696640 NOTICE: GL: Selecting YUV 2 RGB Progressive Shader 10:15:50 T:4761536 M:227082240 DEBUG: GL: Shader compilation log: 10:15:50 T:4761536 M:227082240 DEBUG: Vertex shader was successfully compiled to run on hardware. 10:15:50 T:4761536 M:226160640 DEBUG: GL: Shader compilation log: 10:15:50 T:4761536 M:226160640 DEBUG: Fragment shader was successfully compiled to run on hardware. 10:15:50 T:4761536 M:225341440 NOTICE: GL: NPOT texture support detected 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating Y NPOT texture of size 608 x 336 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating U NPOT texture of size 304 x 168 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating V NPOT texture of size 304 x 168 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating Y NPOT texture of size 608 x 336 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating U NPOT texture of size 304 x 84 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating V NPOT texture of size 304 x 84 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating Y NPOT texture of size 608 x 336 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating U NPOT texture of size 304 x 84 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating V NPOT texture of size 304 x 84 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating Y NPOT texture of size 608 x 336 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating U NPOT texture of size 304 x 168 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating V NPOT texture of size 304 x 168 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating Y NPOT texture of size 608 x 336 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating U NPOT texture of size 304 x 84 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating V NPOT texture of size 304 x 84 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating Y NPOT texture of size 608 x 336 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating U NPOT texture of size 304 x 84 10:15:50 T:4761536 M:225341440 DEBUG: GL: Creating V NPOT texture of size 304 x 84 10:15:50 T:4761536 M:224829440 DEBUG: ------------------- GUI_MSG_WINDOW_DEINIT 10:15:50 T:4761536 M:224829440 DEBUG: 10:15:50 T:4761536 M:224829440 DEBUG: ------------------- 10:15:50 T:2897177488 M:224829440 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_DELAY(510291.624958) 10:15:51 T:2897177488 M:224808960 ERROR: GetDelay - snd_pcm_delay, alsa error: -32 - Broken pipe 10:15:51 T:2897177488 M:224808960 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(552000.000000, 0) 10:15:51 T:2897177488 M:224808960 DEBUG: CDVDPlayerAudio:: Discontinuty - was:860873.817945, should be:528000.000000, error:-332873.817945 10:15:51 T:2897177488 M:224702464 DEBUG: CDVDPlayerAudio:: Discontinuty - was:721063.926000, should be:710117.091318, error:-10946.834682 10:15:52 T:2991586192 M:222969856 DEBUG: webs: accept request 10:15:52 T:4761536 M:222969856 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 10:15:52 T:4761536 M:222969856 DEBUG: ------------------- GUI_MSG_WINDOW_DEINIT 10:15:52 T:4761536 M:222969856 DEBUG: Fullscreen video 10:15:52 T:4761536 M:222969856 DEBUG: ------------------- 10:15:52 T:4761536 M:225222656 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 10:15:52 T:4761536 M:225222656 DEBUG: ------------------- GUI_MSG_WINDOW_INIT 10:15:52 T:4761536 M:225222656 DEBUG: Home 10:15:52 T:4761536 M:225222656 DEBUG: ------------------- 10:15:52 T:4761536 M:225222656 INFO: Loading skin file: Home.xml 10:15:52 T:4761536 M:225222656 DEBUG: Load Home.xml: 19.20ms 10:15:52 T:4761536 M:225222656 DEBUG: Load home-button.png: 0.1ms (bundled) 10:15:52 T:4761536 M:225222656 DEBUG: Load home-playmedia.png: 0.3ms (bundled) 10:15:52 T:4761536 M:225222656 DEBUG: Load home-favourites.png: 0.3ms (bundled) 10:15:52 T:4761536 M:225222656 DEBUG: Load home-power.png: 0.3ms (bundled) 10:15:52 T:4761536 M:225222656 DEBUG: Alloc resources: 20.90ms (19.54 ms skin load, 0.18 ms preload) 10:15:52 T:4761536 M:225222656 NOTICE: CDVDPlayer::CloseFile() 10:15:52 T:4761536 M:225222656 NOTICE: DVDPlayer: waiting for threads to exit 10:15:52 T:2960116624 M:225222656 NOTICE: CDVDPlayer::OnExit() 10:15:52 T:2960116624 M:225222656 NOTICE: DVDPlayer: closing audio stream 10:15:52 T:2960116624 M:225222656 NOTICE: Closing audio stream 10:15:52 T:2960116624 M:225222656 NOTICE: waiting for audio thread to exit 10:15:52 T:2897177488 M:225222656 NOTICE: Closing audio device 10:15:52 T:2897177488 M:224403456 NOTICE: thread end: CDVDPlayerAudio::OnExit() 10:15:52 T:2897177488 M:224403456 DEBUG: staticThread, deleting thread graphic context 10:15:52 T:2897177488 M:224403456 DEBUG: Thread 2897177488 terminating