here are the contents of the log 16:37:30 T:3053791104 M:1809752064 NOTICE: ----------------------------------------------------------------------- 16:37:30 T:3053791104 M:1809932288 NOTICE: Starting XBMC, Platform: Linux (Ubuntu 10.10, 2.6.35-22-generic i686). Built on Dec 17 2010 (SVN:35648) 16:37:30 T:3053791104 M:1809932288 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc 16:37:30 T:3053791104 M:1809932288 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc 16:37:30 T:3053791104 M:1809932288 NOTICE: special://masterprofile/ is mapped to: /home/djones/.xbmc/userdata 16:37:30 T:3053791104 M:1809932288 NOTICE: special://home/ is mapped to: /home/djones/.xbmc 16:37:30 T:3053791104 M:1809932288 NOTICE: special://temp/ is mapped to: /home/djones/.xbmc/temp 16:37:30 T:3053791104 M:1809932288 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin 16:37:30 T:3053791104 M:1809932288 NOTICE: Log File is located: /home/djones/.xbmc/temp/xbmc.log 16:37:30 T:3053791104 M:1809932288 NOTICE: ----------------------------------------------------------------------- 16:37:30 T:3053791104 M:1810132992 NOTICE: Setup SDL 16:37:30 T:3053791104 M:1809498112 NOTICE: load settings... 16:37:30 T:3053791104 M:1809498112 ERROR: Unable to load libcrystalhd.so.3, reason: libcrystalhd.so.3: cannot open shared object file: No such file or directory 16:37:30 T:3053791104 M:1809498112 NOTICE: special://profile/ is mapped to: special://masterprofile/ 16:37:30 T:3053791104 M:1809498112 NOTICE: loading special://masterprofile/guisettings.xml 16:37:30 T:3053791104 M:1809498112 NOTICE: Getting hardware information now... 16:37:30 T:3053791104 M:1809498112 NOTICE: Checking resolution 12 16:37:30 T:3053791104 M:1809498112 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 16:37:30 T:3053791104 M:1809498112 NOTICE: Loaded playercorefactory configuration 16:37:30 T:3053791104 M:1809498112 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 16:37:30 T:3053791104 M:1809498112 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 16:37:30 T:3053791104 M:1809498112 NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml) 16:37:30 T:3053791104 M:1809498112 NOTICE: Default DVD Player: dvdplayer 16:37:30 T:3053791104 M:1809498112 NOTICE: Default Video Player: dvdplayer 16:37:30 T:3053791104 M:1809498112 NOTICE: Default Audio Player: paplayer 16:37:30 T:3053791104 M:1809498112 NOTICE: Loading media sources from special://masterprofile/sources.xml 16:37:31 T:3053791104 M:1803829248 NOTICE: Using visual 0x43 16:37:31 T:3053791104 M:1802477568 NOTICE: GL_VENDOR = NVIDIA Corporation 16:37:31 T:3053791104 M:1802477568 NOTICE: GL_RENDERER = GeForce 8400 GS/PCI/SSE2 16:37:31 T:3053791104 M:1802477568 NOTICE: GL_VERSION = 3.3.0 NVIDIA 260.19.06 16:37:31 T:3053791104 M:1802477568 NOTICE: GL_SHADING_LANGUAGE_VERSION = 3.30 NVIDIA via Cg compiler 16:37:31 T:3053791104 M:1802477568 NOTICE: GL_EXTENSIONS = GL_ARB_blend_func_extended GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_ES2_compatibility GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_provoking_vertex GL_ARB_robustness GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_shadow GL_ARB_sync GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object 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_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_swizzle GL_ARB_timer_query GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object 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_vertex_type_2_10_10_10_rev GL_ARB_viewport_array 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_bindable_uniform 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_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_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_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent 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_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_buffer_load 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_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_buffer_unified_memory 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_NVX_gpu_memory_info GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum 16:37:31 T:3053791104 M:1802559488 ERROR: GLX: Same window as before, refreshing context 16:37:31 T:3053791104 M:1801416704 NOTICE: start dvd mediatype detection 16:37:31 T:3053791104 M:1801416704 NOTICE: initializing playlistplayer 16:37:31 T:3053791104 M:1801416704 NOTICE: DONE initializing playlistplayer 16:37:31 T:3053791104 M:1796210688 NOTICE: initialize done 16:37:31 T:3053791104 M:1796210688 NOTICE: Running the application... 16:37:31 T:3053791104 M:1796210688 NOTICE: Webserver: Starting... 16:37:31 T:3053791104 M:1796136960 NOTICE: WebServer: Started the webserver 16:37:31 T:3053791104 M:1796136960 NOTICE: starting upnp server 16:37:31 T:3053791104 M:1796063232 NOTICE: starting upnp renderer 16:37:31 T:3053791104 M:1796063232 NOTICE: ES: Starting event server 16:37:31 T:3053791104 M:1796063232 NOTICE: DS: Starting dbus server 16:37:31 T:2840632176 M:1796063232 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 16:37:31 T:2840632176 M:1796063232 NOTICE: UDP: Listening on port 9777 16:37:31 T:3053791104 M:1796063232 NOTICE: starting zeroconf publishing 16:37:31 T:2832239472 M:1796063232 NOTICE: DS: Starting DBUS server in Run Application aka thread 16:37:31 T:3053791104 M:1796063232 ERROR: CZeroconfAvahi::addService(): failed to add service named:XBMC JSONRPC@$(HOSTNAME) type:_xbmc-jsonrpc._tcp port:8080. Error:Invalid Error Code :/ FIXME! 16:37:42 T:2802789232 M:1783308288 WARNING: FillBuffer: curl failed with code 28 16:37:42 T:2802789232 M:1783308288 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 16:37:42 T:2802789232 M:1783308288 ERROR: WEATHER: Weather download failed! 16:38:31 T:3053791104 M:1756004352 NOTICE: DVDPlayer: Opening: smb://KRATOS/media/Video/Movies/LowResMovies/9 (2009)/9.avi 16:38:31 T:3053791104 M:1756004352 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:38:31 T:2651900784 M:1756004352 NOTICE: Creating InputStream 16:38:31 T:2651900784 M:1756004352 NOTICE: Creating Demuxer 16:38:31 T:2651900784 M:1745973248 NOTICE: Opening video stream: 0 source: 256 16:38:31 T:2651900784 M:1745973248 NOTICE: Creating video codec with codec id: 13 16:38:31 T:2651900784 M:1745973248 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2 16:38:32 T:2651900784 M:1743511552 NOTICE: Creating video thread 16:38:32 T:2651900784 M:1743511552 NOTICE: Opening audio stream: 1 source: 256 16:38:32 T:2651900784 M:1743511552 NOTICE: Finding audio codec for: 86020 16:38:32 T:2651900784 M:1743511552 NOTICE: Creating audio thread 16:38:32 T:2603809648 M:1743511552 NOTICE: running thread: CDVDPlayerAudio::Process() 16:38:32 T:2603809648 M:1743511552 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, pass-through 16:38:32 T:2612202352 M:1743511552 NOTICE: running thread: video_thread 16:38:32 T:2612202352 M:1740972032 NOTICE: fps: 23.976044, pwidth: 640, pheight: 352, dwidth: 640, dheight: 352 16:38:32 T:2612202352 M:1740972032 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (12) 16:38:32 T:3053791104 M:1738940416 NOTICE: Using GL_TEXTURE_2D 16:38:32 T:3053791104 M:1738940416 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 16:38:32 T:3053791104 M:1738940416 NOTICE: GL: NPOT texture support detected 16:38:32 T:3053791104 M:1738940416 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:38:40 T:2612202352 M:1726050304 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete 16:38:40 T:2612202352 M:1726050304 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 16:38:47 T:3053791104 M:1702948864 WARNING: Previous line repeats 70 times. 16:38:47 T:3053791104 M:1702948864 NOTICE: DVDPlayer: Opening: smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi 16:38:47 T:3053791104 M:1702948864 NOTICE: CDVDPlayer::CloseFile() 16:38:47 T:3053791104 M:1702948864 NOTICE: DVDPlayer: waiting for threads to exit 16:38:47 T:2651900784 M:1702948864 NOTICE: CDVDPlayer::OnExit() 16:38:47 T:2651900784 M:1702948864 NOTICE: DVDPlayer: closing audio stream 16:38:47 T:2651900784 M:1702948864 NOTICE: Closing audio stream 16:38:47 T:2651900784 M:1702948864 NOTICE: Waiting for audio thread to exit 16:38:47 T:2603809648 M:1702948864 NOTICE: thread end: CDVDPlayerAudio::OnExit() 16:38:47 T:2651900784 M:1702948864 NOTICE: Closing audio device 16:38:47 T:2651900784 M:1702821888 NOTICE: Deleting audio codec 16:38:47 T:2651900784 M:1702948864 NOTICE: DVDPlayer: closing video stream 16:38:47 T:2651900784 M:1702948864 NOTICE: Closing video stream 16:38:47 T:2651900784 M:1702948864 NOTICE: waiting for video thread to exit 16:38:47 T:2612202352 M:1702948864 NOTICE: thread end: video_thread 16:38:47 T:2651900784 M:1702948864 NOTICE: deleting video codec 16:38:47 T:2651900784 M:1702948864 NOTICE: CDVDPlayer::OnExit() deleting demuxer 16:38:47 T:2651900784 M:1707393024 NOTICE: CDVDPlayer::OnExit() deleting input stream 16:38:47 T:3053791104 M:1707393024 NOTICE: DVDPlayer: finished waiting 16:38:47 T:3053791104 M:1707646976 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:38:47 T:2651900784 M:1707646976 NOTICE: Creating InputStream 16:38:47 T:2651900784 M:1707646976 NOTICE: Creating Demuxer 16:38:48 T:2651900784 M:1699053568 NOTICE: Opening video stream: 0 source: 256 16:38:48 T:2651900784 M:1699053568 NOTICE: Creating video codec with codec id: 13 16:38:48 T:2651900784 M:1699053568 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2 16:38:48 T:2651900784 M:1699053568 NOTICE: Creating video thread 16:38:48 T:2651900784 M:1699053568 NOTICE: Opening audio stream: 1 source: 256 16:38:48 T:2612202352 M:1699053568 NOTICE: running thread: video_thread 16:38:48 T:2651900784 M:1699053568 NOTICE: Finding audio codec for: 86017 16:38:48 T:2651900784 M:1699053568 ERROR: CDVDAudioCodecPassthroughFFmpeg::Open - FFmpeg SPDIF muxer does not support this codec 16:38:48 T:2651900784 M:1699053568 NOTICE: Creating audio thread 16:38:48 T:2924497776 M:1699053568 NOTICE: running thread: CDVDPlayerAudio::Process() 16:38:48 T:2612202352 M:1699053568 NOTICE: fps: 25.000000, pwidth: 668, pheight: 352, dwidth: 668, dheight: 352 16:38:48 T:2924497776 M:1699053568 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through 16:38:48 T:2612202352 M:1699053568 WARNING: CRenderManager::Configure - timeout waiting for previous frame 16:38:48 T:2612202352 M:1699053568 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (12) 16:38:48 T:2924497776 M:1698926592 ERROR: PulseAudio: Waited for the stream but it failed 16:38:49 T:2924497776 M:1698963456 ERROR: Initialize - pcm_open_lconf, alsa error: -2 - No such file or directory 16:38:49 T:2924497776 M:1698963456 ERROR: Creating a Null Audio Renderer, Check your audio settings as this should not happen 16:38:49 T:3053791104 M:1698455552 NOTICE: Using GL_TEXTURE_2D 16:38:49 T:3053791104 M:1698455552 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 16:38:49 T:3053791104 M:1698455552 NOTICE: GL: NPOT texture support detected 16:38:49 T:3053791104 M:1698455552 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:38:56 T:2612202352 M:1695760384 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete 16:38:56 T:2612202352 M:1695760384 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 16:38:57 T:2612202352 M:1691561984 WARNING: Previous line repeats 8 times. 16:38:57 T:2612202352 M:1691561984 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete 16:39:11 T:3053791104 M:1693753344 NOTICE: Enabled debug logging due to GUI setting. Level 2. 16:39:12 T:3053791104 M:1693761536 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 16:39:12 T:3053791104 M:1693761536 DEBUG: OnKey: 61480 pressed, action is Down 16:39:20 T:3053791104 M:1693794304 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0 16:39:20 T:3053791104 M:1693794304 DEBUG: OnKey: 61467 pressed, action is PreviousMenu 16:39:20 T:3053791104 M:1693921280 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:39:20 T:3053791104 M:1693921280 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 16:39:21 T:3053791104 M:1693937664 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:39:21 T:3053791104 M:1693937664 DEBUG: ------ Window Init (Settings.xml) ------ 16:39:21 T:3053791104 M:1693937664 INFO: Loading skin file: Settings.xml 16:39:21 T:3053791104 M:1692794880 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0 16:39:21 T:3053791104 M:1692794880 DEBUG: OnKey: 61467 pressed, action is PreviousMenu 16:39:21 T:3053791104 M:1692794880 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:39:21 T:3053791104 M:1692794880 DEBUG: ------ Window Deinit (Settings.xml) ------ 16:39:21 T:3053791104 M:1692794880 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:39:21 T:3053791104 M:1692794880 DEBUG: ------ Window Init (Home.xml) ------ 16:39:21 T:3053791104 M:1692794880 INFO: Loading skin file: Home.xml 16:39:27 T:2802789232 M:1692663808 DEBUG: Thread 2802789232 terminating (autodelete) 16:39:27 T:2786003824 M:1692663808 DEBUG: Thread 2786003824 terminating (autodelete) 16:39:27 T:2685377392 M:1692663808 DEBUG: Thread 2685377392 terminating (autodelete) 16:39:27 T:2735647600 M:1692663808 DEBUG: Thread 2735647600 terminating (autodelete) 16:39:27 T:3053791104 M:1692790784 DEBUG: Activating window ID: 12005 16:39:27 T:3053791104 M:1692790784 DEBUG: Checking if window ID 12005 is locked. 16:39:27 T:3053791104 M:1692790784 DEBUG: ------ Window Deinit (Home.xml) ------ 16:39:27 T:3053791104 M:1692790784 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 16:39:27 T:3053791104 M:1692790784 INFO: Loading skin file: VideoFullScreen.xml 16:39:28 T:3053791104 M:1699807232 DEBUG: ------ Window Init (VideoOSD.xml) ------ 16:39:28 T:3053791104 M:1699807232 INFO: Loading skin file: VideoOSD.xml 16:39:29 T:3053791104 M:1698443264 DEBUG: UPower: Recieved an unkown signal DeviceChanged 16:39:30 T:3053791104 M:1698410496 DEBUG: ExecuteXBMCAction : Translating XBMC.PlayerControl(Stop) 16:39:30 T:3053791104 M:1698410496 DEBUG: ExecuteXBMCAction : To XBMC.PlayerControl(Stop) 16:39:30 T:3053791104 M:1698410496 NOTICE: CDVDPlayer::CloseFile() 16:39:30 T:2651900784 M:1698410496 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc 16:39:30 T:3053791104 M:1698410496 NOTICE: DVDPlayer: waiting for threads to exit 16:39:30 T:2651900784 M:1698410496 NOTICE: CDVDPlayer::OnExit() 16:39:30 T:2651900784 M:1698410496 NOTICE: DVDPlayer: closing audio stream 16:39:30 T:2651900784 M:1698410496 NOTICE: Closing audio stream 16:39:30 T:2651900784 M:1698410496 NOTICE: Waiting for audio thread to exit 16:39:30 T:2924497776 M:1698410496 NOTICE: thread end: CDVDPlayerAudio::OnExit() 16:39:30 T:2924497776 M:1698410496 DEBUG: Thread 2924497776 terminating 16:39:30 T:2651900784 M:1698410496 NOTICE: Closing audio device 16:39:30 T:2651900784 M:1698410496 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 16:39:30 T:2651900784 M:1698410496 DEBUG: RemoveActiveDevice - Removing device 2 16:39:30 T:2651900784 M:1698410496 DEBUG: CGUIAudioManager::Initialize 16:39:30 T:2651900784 M:1698410496 NOTICE: Deleting audio codec 16:39:30 T:2651900784 M:1698410496 NOTICE: DVDPlayer: closing video stream 16:39:30 T:2651900784 M:1698410496 NOTICE: Closing video stream 16:39:30 T:2651900784 M:1698410496 NOTICE: waiting for video thread to exit 16:39:30 T:2612202352 M:1698410496 NOTICE: thread end: video_thread 16:39:30 T:2612202352 M:1698410496 DEBUG: Thread 2612202352 terminating 16:39:30 T:2651900784 M:1698410496 NOTICE: deleting video codec 16:39:30 T:2651900784 M:1698410496 NOTICE: CDVDPlayer::OnExit() deleting demuxer 16:39:30 T:2651900784 M:1705394176 NOTICE: CDVDPlayer::OnExit() deleting input stream 16:39:30 T:2651900784 M:1705394176 DEBUG: CFileSMB::Close closing fd 10000 16:39:30 T:2651900784 M:1705394176 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc 16:39:30 T:2651900784 M:1705394176 DEBUG: Thread 2651900784 terminating 16:39:30 T:3053791104 M:1705394176 NOTICE: DVDPlayer: finished waiting 16:39:30 T:3053791104 M:1705394176 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:39:30 T:3053791104 M:1705394176 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:39:30 T:3053791104 M:1705394176 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 16:39:30 T:3053791104 M:1705394176 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 16:39:30 T:3053791104 M:1706029056 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:39:30 T:3053791104 M:1706029056 DEBUG: ------ Window Init (Home.xml) ------ 16:39:30 T:3053791104 M:1706029056 INFO: Loading skin file: Home.xml 16:39:30 T:2651900784 M:1706029056 DEBUG: thread start, auto delete: 1 16:39:30 T:2651900784 M:1699045376 DEBUG: DoWork - took 111 ms to load special://skin/backgrounds/settings.jpg 16:39:30 T:3053791104 M:1699045376 NOTICE: CDVDPlayer::CloseFile() 16:39:30 T:3053791104 M:1699045376 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:39:30 T:3053791104 M:1699045376 NOTICE: DVDPlayer: waiting for threads to exit 16:39:30 T:3053791104 M:1699045376 NOTICE: DVDPlayer: finished waiting 16:39:30 T:3053791104 M:1699045376 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:39:30 T:2815454064 M:1699045376 DEBUG: CVideoReferenceClock: Cleaning up GLX 16:39:30 T:2815454064 M:1699299328 DEBUG: Thread 2815454064 terminating 16:39:30 T:2651900784 M:1701220352 DEBUG: DoWork - Saving file state for video item smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi 16:39:31 T:2815454064 M:1728327680 DEBUG: thread start, auto delete: 1 16:39:31 T:2651900784 M:1729343488 DEBUG: DoWork - took 125 ms to load special://skin/backgrounds/weather.jpg 16:39:32 T:2815454064 M:1729495040 DEBUG: DoWork - took 105 ms to load special://skin/backgrounds/programs.jpg 16:39:32 T:2651900784 M:1727336448 DEBUG: DoWork - took 110 ms to load special://skin/backgrounds/pictures.jpg 16:39:32 T:2815454064 M:1723908096 DEBUG: DoWork - took 141 ms to load special://skin/backgrounds/tvshow.jpg 16:39:33 T:3053791104 M:1723142144 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoFiles) 16:39:33 T:3053791104 M:1723142144 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoFiles) 16:39:33 T:3053791104 M:1723142144 DEBUG: Activating window ID: 10024 16:39:33 T:3053791104 M:1723142144 DEBUG: Checking if window ID 10024 is locked. 16:39:33 T:3053791104 M:1723142144 DEBUG: ------ Window Deinit (Home.xml) ------ 16:39:33 T:3053791104 M:1723269120 DEBUG: ------ Window Init (MyVideo.xml) ------ 16:39:33 T:3053791104 M:1723269120 INFO: Loading skin file: MyVideo.xml 16:39:34 T:3053791104 M:1723269120 DEBUG: CGUIMediaWindow::GetDirectory () 16:39:34 T:3053791104 M:1723269120 DEBUG: ParentPath = [] 16:39:34 T:2700073840 M:1723269120 DEBUG: thread start, auto delete: 0 16:39:34 T:2700073840 M:1723269120 DEBUG: Thread 2700073840 terminating 16:39:37 T:3053791104 M:1736347648 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:39:37 T:3053791104 M:1736347648 DEBUG: ------ Window Deinit (MyVideo.xml) ------ 16:39:37 T:3053791104 M:1736347648 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:39:37 T:3053791104 M:1736347648 DEBUG: ------ Window Init (Home.xml) ------ 16:39:37 T:3053791104 M:1736347648 INFO: Loading skin file: Home.xml 16:39:37 T:2651900784 M:1729871872 DEBUG: DoWork - took 163 ms to load special://skin/backgrounds/tvshow.jpg 16:39:38 T:3053791104 M:1727848448 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoLibrary,MovieTitles,return) 16:39:38 T:3053791104 M:1727848448 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoLibrary,MovieTitles,return) 16:39:38 T:3053791104 M:1727848448 DEBUG: Activating window ID: 10025 16:39:38 T:3053791104 M:1727848448 DEBUG: Checking if window ID 10024 is locked. 16:39:38 T:3053791104 M:1727848448 DEBUG: ------ Window Deinit (Home.xml) ------ 16:39:38 T:3053791104 M:1727848448 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 16:39:38 T:3053791104 M:1727848448 INFO: Loading skin file: MyVideoNav.xml 16:39:38 T:3053791104 M:1727848448 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/) 16:39:38 T:3053791104 M:1727848448 DEBUG: ParentPath = [videodb://1/2/] 16:39:38 T:3053791104 M:1727848448 DEBUG: GetSetsNav query: SELECT sets.idSet,sets.strSet,COUNT(1) AS c,count(files.playCount) FROM sets JOIN setlinkmovie ON sets.idSet=setlinkmovie.idSet JOIN movie ON setlinkmovie.idMovie=movie.idMovie JOIN files ON files.idFile=movie.idFile GROUP BY sets.idSet HAVING c>1 16:39:38 T:3053791104 M:1727848448 DEBUG: GetMoviesByWhere query: select * from movieview WHERE movieview.idMovie NOT IN (SELECT idMovie FROM setlinkmovie s1 JOIN(SELECT idSet, COUNT(1) AS c FROM setlinkmovie GROUP BY idSet HAVING c>1) s2 ON s2.idSet=s1.idSet) 16:39:38 T:3053791104 M:1721499648 DEBUG: Time for actual SQL query = 53 16:39:39 T:3053791104 M:1720107008 DEBUG: Time to retrieve movies from dataset = 311 16:39:39 T:3053791104 M:1719980032 DEBUG: Saving fileitems [videodb://1/2/] 16:39:39 T:3053791104 M:1719980032 DEBUG: -- items: 707, sort method: 25, ascending: true 16:39:39 T:2716859248 M:1719980032 DEBUG: thread start, auto delete: 0 16:39:39 T:2612202352 M:1715916800 DEBUG: thread start, auto delete: 1 16:39:39 T:2651900784 M:1716551680 DEBUG: DoWork - took 263 ms to load special://masterprofile/Thumbnails/Video/Fanart/ef8940c6.tbn 16:39:39 T:2612202352 M:1716551680 DEBUG: DoWork - trying to extract filestream details from video file smb://KRATOS/media/Video/Movies/HighResMovies/The.Expendables.2010.Multi.Subbed.AVCHD.1080P/The.Expendables.2010.Multi.Subbed.AVCHD.1080P.iso 16:39:40 T:2612202352 M:1719709696 DEBUG: DoWork - trying to extract filestream details from video file smb://KRATOS/media/Video/Movies/HighResMovies/Tinker Bell and the Lost Treasure (2009) 1080p avchd dvd9 Pioen 2Lions-Team/Tinker Bell (2008) 1080p avchd dvd9/Tinker Bell (2008) 1080p avchd dvd9.iso 16:39:41 T:2815454064 M:1713872896 DEBUG: DoWork - took 520 ms to load special://masterprofile/Thumbnails/Video/Fanart/044c04cd.tbn 16:39:41 T:2716859248 M:1722126336 DEBUG: Thread 2716859248 terminating 16:39:42 T:3053791104 M:1722130432 INFO: Loading skin file: DialogContextMenu.xml 16:39:42 T:3053791104 M:1722003456 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 16:39:44 T:3053791104 M:1721249792 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 16:39:44 T:3053791104 M:1721630720 DEBUG: new file set audiostream:0 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerCoreFactory::GetPlayers(smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi) 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 16:39:44 T:3053791104 M:1721630720 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 16:39:44 T:3053791104 M:1720995840 NOTICE: DVDPlayer: Opening: smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi 16:39:44 T:3053791104 M:1720995840 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:39:44 T:2955389808 M:1720995840 DEBUG: thread start, auto delete: 0 16:39:44 T:2955389808 M:1720995840 NOTICE: Creating InputStream 16:39:44 T:2955389808 M:1720995840 DEBUG: CFileSMB::Open - opened media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi, fd=10000 16:39:44 T:2955389808 M:1720995840 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 16:39:44 T:2955389808 M:1720995840 DEBUG: CacheSubtitles: START 16:39:44 T:2955389808 M:1720995840 DEBUG: CacheSubtitles: Checking for common subdirs... 16:39:44 T:2955389808 M:1720995840 DEBUG: CacheSubtitles: Done (time: 9 ms) 16:39:44 T:2955389808 M:1720995840 DEBUG: CacheSubtitles: Searching for subtitles... 16:39:44 T:2955389808 M:1720995840 DEBUG: OpenDir - Using authentication url smb://KRATOS/media/Video/Movies/LowResMovies/28%20Weeks%20Later%20%282007%29 16:39:44 T:2955389808 M:1720995840 DEBUG: CacheSubtitles: Done (time: 7 ms) 16:39:44 T:2955389808 M:1720995840 DEBUG: CacheSubtitles: END (total time: 18 ms) 16:39:44 T:2955389808 M:1720995840 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 16:39:44 T:2955389808 M:1720995840 NOTICE: Creating Demuxer 16:39:44 T:2955389808 M:1720995840 DEBUG: Open - probing detected format [avi] 16:39:44 T:3053791104 M:1717186560 DEBUG: ------ Window Init (DialogBusy.xml) ------ 16:39:44 T:2955389808 M:1713504256 DEBUG: Open - av_find_stream_info starting 16:39:44 T:2955389808 M:1713504256 DEBUG: Open - av_find_stream_info finished 16:39:44 T:2955389808 M:1713504256 INFO: ffmpeg[B027AB70]: Input #0, avi, from 'smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi': 16:39:44 T:2955389808 M:1713504256 INFO: ffmpeg[B027AB70]: Metadata: 16:39:44 T:2955389808 M:1713504256 INFO: ffmpeg[B027AB70]: ISFT : VirtualDubMod 1.5.10.1 (build 2366/release) 16:39:44 T:2955389808 M:1713504256 INFO: ffmpeg[B027AB70]: Duration: 01:36:50.76, start: 0.000000, bitrate: 1012 kb/s 16:39:44 T:2955389808 M:1713504256 INFO: ffmpeg[B027AB70]: Stream #0.0: Video: mpeg4, yuv420p, 668x352 [PAR 1:1 DAR 167:88], 25 tbr, 25 tbn, 25 tbc 16:39:44 T:2955389808 M:1713504256 INFO: ffmpeg[B027AB70]: Stream #0.1: Audio: mp3, 48000 Hz, 2 channels, s16, 128 kb/s 16:39:44 T:2955389808 M:1713504256 NOTICE: Opening video stream: 0 source: 256 16:39:44 T:2955389808 M:1713504256 NOTICE: Creating video codec with codec id: 13 16:39:44 T:2955389808 M:1713504256 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:yes VAAPI:no 16:39:44 T:2955389808 M:1713504256 DEBUG: FactoryCodec - Video: - Opening 16:39:44 T:2955389808 M:1713504256 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2 16:39:44 T:2955389808 M:1713504256 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened 16:39:44 T:2788178800 M:1713504256 DEBUG: thread start, auto delete: 0 16:39:44 T:2788178800 M:1713504256 DEBUG: CVideoReferenceClock: Setting up GLX 16:39:44 T:2788178800 M:1711534080 DEBUG: CVideoReferenceClock: output of nvidia-settings -nt -q RefreshRate3: 60.000 Hz 16:39:44 T:2788178800 M:1711534080 DEBUG: CVideoReferenceClock: Detected refreshrate by nvidia-settings: 60.000000 hertz, rounding to 60 hertz 16:39:44 T:2955389808 M:1711534080 NOTICE: Creating video thread 16:39:44 T:2955389808 M:1711534080 NOTICE: Opening audio stream: 1 source: 256 16:39:44 T:2735647600 M:1711534080 DEBUG: thread start, auto delete: 0 16:39:44 T:2955389808 M:1711534080 NOTICE: Finding audio codec for: 86017 16:39:44 T:2955389808 M:1711534080 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opening 16:39:44 T:2955389808 M:1711534080 ERROR: CDVDAudioCodecPassthroughFFmpeg::Open - FFmpeg SPDIF muxer does not support this codec 16:39:44 T:2955389808 M:1711534080 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Failed 16:39:44 T:2955389808 M:1711407104 DEBUG: FactoryCodec - Audio: libmad - Opening 16:39:44 T:2955389808 M:1711407104 DEBUG: FactoryCodec - Audio: libmad - Opened 16:39:44 T:2955389808 M:1711407104 NOTICE: Creating audio thread 16:39:44 T:2708466544 M:1711407104 DEBUG: thread start, auto delete: 0 16:39:44 T:2708466544 M:1711407104 NOTICE: running thread: CDVDPlayerAudio::Process() 16:39:44 T:2955389808 M:1711407104 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi 16:39:44 T:2955389808 M:1711407104 DEBUG: CDVDPlayer::SetCaching - caching state 2 16:39:44 T:2708466544 M:1711407104 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(24000.000000, 1) 16:39:44 T:2708466544 M:1711407104 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through 16:39:44 T:2708466544 M:1711407104 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 16:39:44 T:2708466544 M:1711407104 INFO: CPCMRemap: Configured speaker layout: 7.1 16:39:44 T:2708466544 M:1711407104 INFO: CPCMRemap: I channel map: FL,FR 16:39:44 T:2708466544 M:1711407104 INFO: CPCMRemap: O channel map: FL,FR 16:39:44 T:2708466544 M:1711407104 DEBUG: CPCMRemap: Downmix normalization is disabled 16:39:44 T:2708466544 M:1711407104 DEBUG: CPCMRemap: FL = FL(1.000000*) 16:39:44 T:2708466544 M:1711407104 DEBUG: CPCMRemap: FR = FR(1.000000*) 16:39:44 T:2708466544 M:1711407104 DEBUG: RemoveActiveDevice - Removing device 1 16:39:44 T:2708466544 M:1711407104 DEBUG: CGUIAudioManager::DeInitialize 16:39:44 T:2735647600 M:1711407104 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:39:44 T:2735647600 M:1711407104 NOTICE: running thread: video_thread 16:39:44 T:2735647600 M:1711407104 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 16:39:44 T:2735647600 M:1711407104 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(40000.000000, 0) 16:39:44 T:2735647600 M:1709756416 NOTICE: fps: 25.000000, pwidth: 668, pheight: 352, dwidth: 668, dheight: 352 16:39:44 T:2735647600 M:1709756416 DEBUG: OutputPicture - change configuration. 668x352. framerate: 25.00. format: YV12 16:39:45 T:2735647600 M:1709756416 WARNING: CRenderManager::Configure - timeout waiting for previous frame 16:39:45 T:2735647600 M:1709756416 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (12) 16:39:45 T:2955389808 M:1709748224 DEBUG: CDVDPlayer::HandleMessages - player started 2 16:39:45 T:2708466544 M:1709748224 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 16:39:45 T:2708466544 M:1709748224 DEBUG: RemoveActiveDevice - Removing device 0 16:39:45 T:3053791104 M:1709621248 DEBUG: Activating window ID: 12005 16:39:45 T:3053791104 M:1709621248 DEBUG: Checking if window ID 12005 is locked. 16:39:45 T:3053791104 M:1709621248 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 16:39:45 T:2708466544 M:1709621248 DEBUG: PulseAudio: Context authorizing 16:39:45 T:2708466544 M:1709613056 DEBUG: PulseAudio: Context setting name 16:39:45 T:2708466544 M:1709613056 DEBUG: PulseAudio: Context ready 16:39:45 T:2708466544 M:1709613056 DEBUG: PulseAudio: Stream failed 16:39:45 T:2708466544 M:1709613056 ERROR: PulseAudio: Waited for the stream but it failed 16:39:45 T:2708466544 M:1709613056 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 16:39:45 T:2708466544 M:1709613056 DEBUG: RemoveActiveDevice - Removing device 2 16:39:45 T:2708466544 M:1709613056 DEBUG: CGUIAudioManager::Initialize 16:39:45 T:2708466544 M:1709613056 INFO: CPCMRemap: Configured speaker layout: 7.1 16:39:45 T:2708466544 M:1709613056 INFO: CPCMRemap: I channel map: FL,FR 16:39:45 T:2708466544 M:1709613056 INFO: CPCMRemap: O channel map: FL,FR 16:39:45 T:2708466544 M:1709613056 DEBUG: CPCMRemap: Downmix normalization is disabled 16:39:45 T:2708466544 M:1709613056 DEBUG: CPCMRemap: FL = FL(1.000000*) 16:39:45 T:2708466544 M:1709613056 DEBUG: CPCMRemap: FR = FR(1.000000*) 16:39:45 T:2708466544 M:1709613056 DEBUG: RemoveActiveDevice - Removing device 1 16:39:45 T:2708466544 M:1709613056 DEBUG: CGUIAudioManager::DeInitialize 16:39:45 T:3053791104 M:1709613056 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 16:39:45 T:3053791104 M:1709613056 INFO: Loading skin file: VideoFullScreen.xml 16:39:45 T:2708466544 M:1708470272 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 16:39:45 T:2708466544 M:1708470272 DEBUG: RemoveActiveDevice - Removing device 0 16:39:45 T:2708466544 M:1708470272 DEBUG: Initialize - using alsa device plug: dmixer 16:39:45 T:3053791104 M:1708470272 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc 16:39:45 T:2708466544 M:1708470272 ERROR: Initialize - pcm_open_lconf, alsa error: -2 - No such file or directory 16:39:45 T:2708466544 M:1708470272 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 16:39:45 T:2708466544 M:1708470272 DEBUG: RemoveActiveDevice - Removing device 2 16:39:45 T:2708466544 M:1708470272 DEBUG: CGUIAudioManager::Initialize 16:39:45 T:2708466544 M:1708470272 ERROR: Creating a Null Audio Renderer, Check your audio settings as this should not happen 16:39:45 T:2708466544 M:1708470272 DEBUG: RemoveActiveDevice - Removing device 1 16:39:45 T:2708466544 M:1708470272 DEBUG: CGUIAudioManager::DeInitialize 16:39:45 T:2708466544 M:1708470272 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 16:39:45 T:2708466544 M:1708470272 DEBUG: RemoveActiveDevice - Removing device 0 16:39:45 T:2708466544 M:1708470272 DEBUG: CNullDirectSound::Initialize - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough false - audioDevice: plug: dmixer 16:39:45 T:2708466544 M:1708470272 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 16:39:45 T:2955389808 M:1708470272 DEBUG: CDVDPlayer::HandleMessages - player started 1 16:39:45 T:2955389808 M:1708470272 DEBUG: CDVDPlayer::SetCaching - caching state 3 16:39:45 T:2955389808 M:1708470272 DEBUG: CDVDPlayer::SetCaching - caching state 0 16:39:45 T:2708466544 M:1708470272 DEBUG: CDVDPlayerAudio:: Discontinuity - was:24000.000000, should be:24000.000000, error:0.000000 16:39:45 T:3053791104 M:1708470272 NOTICE: Using GL_TEXTURE_2D 16:39:45 T:3053791104 M:1708470272 DEBUG: GL: Requested render method: 0 16:39:45 T:3053791104 M:1708470272 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_YV12 16:39:45 T:3053791104 M:1708470272 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 16:39:45 T:3053791104 M:1708470272 DEBUG: GL: Vertex Shader compilation log: 16:39:45 T:3053791104 M:1708470272 DEBUG: GL: Vertex Shader compiled successfully 16:39:45 T:3053791104 M:1708470272 DEBUG: GL: Pixel Shader compilation log: 16:39:45 T:3053791104 M:1708470272 DEBUG: GL: Fragment Shader compiled successfully 16:39:45 T:3053791104 M:1708470272 NOTICE: GL: NPOT texture support detected 16:39:45 T:3053791104 M:1708470272 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:39:45 T:3053791104 M:1712660480 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi) 16:39:45 T:3053791104 M:1712660480 DEBUG: ------ Window Init (DialogKaiToast.xml) ------ 16:39:46 T:3053791104 M:1712660480 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 16:39:46 T:3053791104 M:1712660480 DEBUG: ------ Window Init (VideoOSD.xml) ------ 16:39:46 T:3053791104 M:1712660480 INFO: Loading skin file: VideoOSD.xml 16:39:46 T:2708466544 M:1712660480 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1023999.960000, should be:995294.552579, error:-28705.407421 16:39:47 T:2735647600 M:1712627712 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00 16:39:49 T:3053791104 M:1712640000 DEBUG: ExecuteXBMCAction : Translating XBMC.PlayerControl(Stop) 16:39:49 T:3053791104 M:1712640000 DEBUG: ExecuteXBMCAction : To XBMC.PlayerControl(Stop) 16:39:49 T:3053791104 M:1712640000 NOTICE: CDVDPlayer::CloseFile() 16:39:49 T:2955389808 M:1712640000 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc 16:39:49 T:3053791104 M:1712640000 NOTICE: DVDPlayer: waiting for threads to exit 16:39:49 T:2955389808 M:1712640000 NOTICE: CDVDPlayer::OnExit() 16:39:49 T:2955389808 M:1712640000 NOTICE: DVDPlayer: closing audio stream 16:39:49 T:2955389808 M:1712640000 NOTICE: Closing audio stream 16:39:49 T:2955389808 M:1712640000 NOTICE: Waiting for audio thread to exit 16:39:49 T:2708466544 M:1712640000 NOTICE: thread end: CDVDPlayerAudio::OnExit() 16:39:49 T:2708466544 M:1712640000 DEBUG: Thread 2708466544 terminating 16:39:49 T:2955389808 M:1712640000 NOTICE: Closing audio device 16:39:49 T:2955389808 M:1712640000 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 16:39:49 T:2955389808 M:1712640000 DEBUG: RemoveActiveDevice - Removing device 2 16:39:49 T:2955389808 M:1712640000 DEBUG: CGUIAudioManager::Initialize 16:39:49 T:2955389808 M:1712640000 NOTICE: Deleting audio codec 16:39:49 T:2955389808 M:1712640000 NOTICE: DVDPlayer: closing video stream 16:39:49 T:2955389808 M:1712640000 NOTICE: Closing video stream 16:39:49 T:2955389808 M:1712640000 NOTICE: waiting for video thread to exit 16:39:49 T:2735647600 M:1713274880 NOTICE: thread end: video_thread 16:39:49 T:2735647600 M:1713274880 DEBUG: Thread 2735647600 terminating 16:39:49 T:2955389808 M:1713274880 NOTICE: deleting video codec 16:39:49 T:2955389808 M:1714417664 NOTICE: CDVDPlayer::OnExit() deleting demuxer 16:39:49 T:2955389808 M:1721401344 NOTICE: CDVDPlayer::OnExit() deleting input stream 16:39:49 T:2955389808 M:1721401344 DEBUG: CFileSMB::Close closing fd 10000 16:39:49 T:2955389808 M:1721401344 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc 16:39:49 T:2955389808 M:1721401344 DEBUG: Thread 2955389808 terminating 16:39:49 T:3053791104 M:1721401344 NOTICE: DVDPlayer: finished waiting 16:39:49 T:3053791104 M:1721401344 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:39:49 T:3053791104 M:1721401344 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:39:49 T:3053791104 M:1721401344 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 16:39:49 T:3053791104 M:1721401344 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 16:39:49 T:3053791104 M:1722417152 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:39:49 T:3053791104 M:1722417152 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 16:39:49 T:3053791104 M:1722417152 INFO: Loading skin file: MyVideoNav.xml 16:39:49 T:3053791104 M:1722544128 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/) 16:39:49 T:3053791104 M:1722544128 DEBUG: ParentPath = [videodb://1/2/] 16:39:49 T:3053791104 M:1722544128 DEBUG: GetSetsNav query: SELECT sets.idSet,sets.strSet,COUNT(1) AS c,count(files.playCount) FROM sets JOIN setlinkmovie ON sets.idSet=setlinkmovie.idSet JOIN movie ON setlinkmovie.idMovie=movie.idMovie JOIN files ON files.idFile=movie.idFile GROUP BY sets.idSet HAVING c>1 16:39:49 T:3053791104 M:1722544128 DEBUG: GetMoviesByWhere query: select * from movieview WHERE movieview.idMovie NOT IN (SELECT idMovie FROM setlinkmovie s1 JOIN(SELECT idSet, COUNT(1) AS c FROM setlinkmovie GROUP BY idSet HAVING c>1) s2 ON s2.idSet=s1.idSet) 16:39:49 T:3053791104 M:1722544128 DEBUG: Time for actual SQL query = 52 16:39:50 T:3053791104 M:1722417152 DEBUG: Time to retrieve movies from dataset = 319 16:39:50 T:3053791104 M:1722494976 DEBUG: Saving fileitems [videodb://1/2/] 16:39:50 T:3053791104 M:1722368000 DEBUG: -- items: 707, sort method: 25, ascending: true 16:39:50 T:2716859248 M:1722368000 DEBUG: thread start, auto delete: 0 16:39:50 T:3053791104 M:1717161984 NOTICE: CDVDPlayer::CloseFile() 16:39:50 T:3053791104 M:1717161984 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:39:50 T:3053791104 M:1717161984 NOTICE: DVDPlayer: waiting for threads to exit 16:39:50 T:3053791104 M:1717161984 NOTICE: DVDPlayer: finished waiting 16:39:50 T:3053791104 M:1717161984 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:39:50 T:2788178800 M:1717161984 DEBUG: CVideoReferenceClock: Cleaning up GLX 16:39:50 T:2788178800 M:1717542912 DEBUG: Thread 2788178800 terminating 16:39:50 T:2815454064 M:1721344000 DEBUG: DoWork - Saving file state for video item smb://KRATOS/media/Video/Movies/LowResMovies/28 Weeks Later (2007)/28 Weeks Later.avi 16:39:50 T:2612202352 M:1721352192 DEBUG: DoWork - trying to extract filestream details from video file smb://KRATOS/media/Video/Movies/HighResMovies/The.Expendables.2010.Multi.Subbed.AVCHD.1080P/The.Expendables.2010.Multi.Subbed.AVCHD.1080P.iso 16:39:51 T:3053791104 M:1721393152 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------ 16:39:51 T:2612202352 M:1721393152 DEBUG: DoWork - trying to extract filestream details from video file smb://KRATOS/media/Video/Movies/HighResMovies/Tinker Bell and the Lost Treasure (2009) 1080p avchd dvd9 Pioen 2Lions-Team/Tinker Bell (2008) 1080p avchd dvd9/Tinker Bell (2008) 1080p avchd dvd9.iso 16:39:52 T:2716859248 M:1721393152 DEBUG: Thread 2716859248 terminating 16:39:55 T:2651900784 M:1717854208 DEBUG: DoWork - took 134 ms to load special://skin/backgrounds/videos.jpg 16:39:55 T:2815454064 M:1715568640 DEBUG: DoWork - took 192 ms to load special://masterprofile/Thumbnails/Video/Fanart/c45a0eb5.tbn 16:39:57 T:3053791104 M:1719259136 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Home) 16:39:57 T:3053791104 M:1719259136 DEBUG: ExecuteXBMCAction : To ActivateWindow(Home) 16:39:57 T:3053791104 M:1719259136 DEBUG: Activating window ID: 10000 16:39:57 T:3053791104 M:1719259136 DEBUG: Checking if window ID 10000 is locked. 16:39:57 T:3053791104 M:1719259136 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 16:39:58 T:3053791104 M:1719259136 DEBUG: ------ Window Init (Home.xml) ------ 16:39:58 T:3053791104 M:1719259136 INFO: Loading skin file: Home.xml 16:39:59 T:2815454064 M:1718374400 DEBUG: DoWork - took 119 ms to load special://skin/backgrounds/programs.jpg 16:39:59 T:2651900784 M:1719644160 DEBUG: DoWork - took 174 ms to load special://skin/backgrounds/pictures.jpg 16:39:59 T:3053791104 M:1721802752 DEBUG: UPower: Recieved an unkown signal DeviceChanged 16:40:01 T:3053791104 M:1724956672 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(ShutdownMenu) 16:40:01 T:3053791104 M:1724956672 DEBUG: ExecuteXBMCAction : To ActivateWindow(ShutdownMenu) 16:40:01 T:3053791104 M:1724956672 DEBUG: Activating window ID: 10111 16:40:01 T:3053791104 M:1724956672 DEBUG: Checking if window ID 10111 is locked. 16:40:01 T:3053791104 M:1724956672 DEBUG: ------ Window Init (DialogButtonMenu.xml) ------ 16:40:01 T:3053791104 M:1724956672 INFO: Loading skin file: DialogButtonMenu.xml 16:40:03 T:3053791104 M:1712775168 DEBUG: ExecuteXBMCAction : Translating dialog.close(all,true) 16:40:03 T:3053791104 M:1712775168 DEBUG: ExecuteXBMCAction : To dialog.close(all,true) 16:40:03 T:3053791104 M:1712775168 DEBUG: ------ Window Deinit (DialogButtonMenu.xml) ------ 16:40:03 T:3053791104 M:1712775168 DEBUG: ExecuteXBMCAction : Translating XBMC.Quit() 16:40:03 T:3053791104 M:1712775168 DEBUG: ExecuteXBMCAction : To XBMC.Quit() 16:40:03 T:3053791104 M:1724837888 DEBUG: CAnnouncementManager - Announcement: ApplicationStop from xbmc 16:40:03 T:2612202352 M:1724837888 DEBUG: Thread 2612202352 terminating (autodelete) 16:40:03 T:2815454064 M:1724837888 DEBUG: Thread 2815454064 terminating (autodelete) 16:40:03 T:2651900784 M:1724837888 DEBUG: Thread 2651900784 terminating (autodelete) 16:40:03 T:3053791104 M:1724837888 NOTICE: Storing total System Uptime 16:40:03 T:3053791104 M:1724837888 NOTICE: Saving settings 16:40:03 T:3053791104 M:1724837888 NOTICE: stop all 16:40:03 T:3053791104 M:1724837888 DEBUG: NetworkMessage - Signaling network services to stop 16:40:03 T:3053791104 M:1724837888 NOTICE: ES: Stopping event server 16:40:03 T:3053791104 M:1724837888 DEBUG: CZeroconfAvahi::doRemoveService named: servers.jsonrpc 16:40:03 T:3053791104 M:1724837888 DEBUG: NetworkMessage - Waiting for network services to stop 16:40:03 T:3053791104 M:1724837888 NOTICE: stopping upnp 16:40:03 T:2832239472 M:1724837888 NOTICE: DS: DBUS server stopped 16:40:03 T:2832239472 M:1724837888 DEBUG: Thread 2832239472 terminating 16:40:03 T:2823846768 M:1724837888 DEBUG: Thread 2823846768 terminating 16:40:03 T:2840632176 M:1724837888 NOTICE: ES: UDP Event server stopped 16:40:03 T:2840632176 M:1724837888 DEBUG: Thread 2840632176 terminating 16:40:04 T:3053791104 M:1725169664 NOTICE: stopping zeroconf publishing 16:40:04 T:3053791104 M:1725169664 NOTICE: Webserver: Stopping... 16:40:04 T:3053791104 M:1725296640 NOTICE: WebServer: Stopped the webserver 16:40:04 T:3053791104 M:1725296640 NOTICE: Webserver: Stopped... 16:40:04 T:3053791104 M:1725296640 NOTICE: stop dvd detect media 16:40:04 T:3050769264 M:1725296640 DEBUG: Thread 3050769264 terminating 16:40:04 T:3053791104 M:1725296640 NOTICE: stop sap announcement listener 16:40:04 T:3053791104 M:1725296640 NOTICE: clean cached files! 16:40:04 T:3053791104 M:1725370368 NOTICE: unload skin 16:40:04 T:3053791104 M:1725370368 DEBUG: CGUIAudioManager::DeInitialize 16:40:05 T:3053791104 M:1725435904 DEBUG: ------ Window Deinit (Home.xml) ------ 16:40:05 T:3053791104 M:1725435904 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:40:05 T:3053791104 M:1725435904 DEBUG: ------ Window Deinit (DialogMuteBug.xml) ------ 16:40:05 T:3053791104 M:1728610304 NOTICE: stop python 16:40:05 T:3053791104 M:1732546560 NOTICE: stopped 16:40:05 T:3053791104 M:1732546560 NOTICE: destroy 16:40:05 T:3053791104 M:1732546560 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x936cf00 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 16:40:05 T:3053791104 M:1732546560 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x9347410 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 16:40:05 T:3053791104 M:1732546560 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x936e820 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 16:40:05 T:3053791104 M:1732546560 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x936d820 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 16:40:05 T:3053791104 M:1732546560 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x934a2a0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 16:40:05 T:3053791104 M:1732546560 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x9356e68 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 16:40:05 T:3053791104 M:1732546560 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x936d230 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 16:40:05 T:3053791104 M:1732546560 NOTICE: unload sections 16:40:05 T:3053791104 M:1732546560 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/libcpluff-i486-linux.so) 16:40:05 T:3053791104 M:1732546560 DEBUG: Unloading: libcpluff-i486-linux.so 16:40:05 T:3053791104 M:1732546560 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/ImageLib-i486-linux.so) 16:40:05 T:3053791104 M:1732546560 DEBUG: Unloading: ImageLib-i486-linux.so 16:40:05 T:3053791104 M:1732673536 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/avutil-50-i486-linux.so) 16:40:05 T:3053791104 M:1732673536 DEBUG: Unloading: avutil-50-i486-linux.so 16:40:05 T:3053791104 M:1732673536 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/avcodec-52-i486-linux.so) 16:40:05 T:3053791104 M:1732673536 DEBUG: Unloading: avcodec-52-i486-linux.so 16:40:05 T:3053791104 M:1732673536 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/avformat-52-i486-linux.so) 16:40:05 T:3053791104 M:1732673536 DEBUG: Unloading: avformat-52-i486-linux.so 16:40:05 T:3053791104 M:1737625600 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/swscale-0.6.1-i486-linux.so) 16:40:05 T:3053791104 M:1737625600 DEBUG: Unloading: swscale-0.6.1-i486-linux.so 16:40:05 T:3053791104 M:1737879552 DEBUG: SECTION:UnloadAll(DLL: special://xbmcbin/system/players/dvdplayer/postproc-51-i486-linux.so) 16:40:05 T:3053791104 M:1737879552 DEBUG: Unloading: postproc-51-i486-linux.so 16:40:05 T:3053791104 M:1738006528 DEBUG: SECTION:UnloadAll(DLL: libmad.so.0) 16:40:05 T:3053791104 M:1738006528 DEBUG: Unloading: libmad.so.0 16:40:05 T:3053791104 M:1738006528 INFO: lastfm destroyed 16:40:05 T:3053791104 M:1738006528 DEBUG: object 0 --> 0 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 1 --> 0 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 2 --> 19 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 3 --> 0 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 4 --> 32 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 5 --> 0 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 6 --> 0 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 7 --> 0 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 8 --> 0 instances 16:40:05 T:3053791104 M:1738006528 DEBUG: object 9 --> 0 instances 16:40:05 T:3053791104 M:1739149312 DEBUG: LinuxRendererGL: Cleaning up GL resources