20:30:07 T:3548 M:1822765056 NOTICE: ----------------------------------------------------------------------- 20:30:07 T:3548 M:1822728192 NOTICE: Starting XBMC, Platform: Windows XP Professional Service Pack 3 build 2600. Built on Jun 2 2009 (SVN:20672, compiler 1500) 20:30:07 T:3548 M:1822728192 NOTICE: Intel(R) Xeon(TM) CPU 2.66GHz 20:30:07 T:3548 M:1822728192 NOTICE: Desktop Resolution: 1864x1048 32Bit at 60Hz 20:30:07 T:3548 M:1822728192 NOTICE: special://xbmc/ is mapped to: C:\Program Files\XBMC 20:30:07 T:3548 M:1822728192 NOTICE: special://masterprofile/ is mapped to: C:\Documents and Settings\Administrator\Application Data\XBMC\userdata 20:30:07 T:3548 M:1822728192 NOTICE: special://home/ is mapped to: C:\Documents and Settings\Administrator\Application Data\XBMC 20:30:07 T:3548 M:1822728192 NOTICE: special://temp/ is mapped to: C:\Documents and Settings\Administrator\Application Data\XBMC\cache 20:30:07 T:3548 M:1822728192 NOTICE: The executable running is: C:\Program Files\XBMC\XBMC.exe 20:30:07 T:3548 M:1822728192 NOTICE: Log File is located: C:\Documents and Settings\Administrator\Application Data\XBMC\xbmc.log 20:30:07 T:3548 M:1822728192 NOTICE: ----------------------------------------------------------------------- 20:30:07 T:3548 M:1822707712 NOTICE: Setup SDL 20:30:07 T:3548 M:1821999104 NOTICE: load settings... 20:30:07 T:3548 M:1821966336 NOTICE: special://profile/ is mapped to: special://masterprofile/ 20:30:07 T:3548 M:1821958144 NOTICE: loading special://masterprofile/guisettings.xml 20:30:07 T:3548 M:1821536256 NOTICE: Getting hardware information now... 20:30:07 T:3548 M:1821532160 NOTICE: Checking resolution 10 20:30:07 T:3548 M:1821532160 NOTICE: Setting autoresolution mode 12 20:30:07 T:3548 M:1821507584 NOTICE: No advancedsettings.xml to load (special://masterprofile/advancedsettings.xml) 20:30:07 T:3548 M:1821503488 NOTICE: Default DVD Player: dvdplayer 20:30:07 T:3548 M:1821503488 NOTICE: Default Video Player: dvdplayer 20:30:07 T:3548 M:1821503488 NOTICE: Default Audio Player: paplayer 20:30:07 T:3548 M:1821548544 NOTICE: special://masterprofile/sources.xml 20:30:07 T:3548 M:1808535552 NOTICE: GL_VENDOR = NVIDIA Corporation 20:30:07 T:3548 M:1808535552 NOTICE: GL_RENDERER = GeForce 6200/AGP/SSE2 20:30:07 T:3548 M:1808535552 NOTICE: GL_VERSION = 2.1.2 20:30:07 T:3548 M:1808535552 NOTICE: GL_EXTENSIONS = GL_ARB_color_buffer_float GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_framebuffer_object GL_ARB_imaging 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_shadow GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirrored_repeat GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_transpose_matrix GL_ARB_vertex_array_object GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_framebuffer_object GL_EXT_gpu_program_parameters GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_specular_color GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_sRGB GL_EXT_timer_query GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KTX_buffer_region GL_NV_blend_square GL_NV_copy_depth_to_color GL_NV_depth_clamp GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_texgen_reflection GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum GL_WIN_swap_hint WGL_EXT_swap_control 20:30:08 T:3548 M:1805385728 NOTICE: start dvd mediatype detection 20:30:08 T:3548 M:1805369344 NOTICE: initializing playlistplayer 20:30:08 T:3548 M:1805361152 NOTICE: DONE initializing playlistplayer 20:30:08 T:3548 M:1805324288 NOTICE: load default skin:[MediaStream] 20:30:08 T:3548 M:1790578688 ERROR: Window Translator: Can't find window settingscategory 20:30:08 T:3548 M:1790554112 ERROR: Error evaluating boolean expression !Window.Previous(0) + !Window.Previous(SettingsCategory) + !Window.Previous(1001) + !Window.Previous(130) + !Window.Previous(34) 20:30:09 T:3548 M:1779789824 NOTICE: initialize done 20:30:09 T:3548 M:1780240384 NOTICE: Running the application... 20:30:09 T:3548 M:1780023296 NOTICE: Webserver: Starting... 20:30:09 T:3656 M:1779646464 NOTICE: Webserver: Started 20:30:09 T:3548 M:1779593216 NOTICE: ES: Starting event server 20:30:09 T:3664 M:1779499008 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777 20:30:09 T:3664 M:1779478528 NOTICE: UDP: Listening on port 9777 20:30:36 T:3548 M:1770971136 NOTICE: Enabled debug logging due to GUI setting 20:30:39 T:3548 M:1771806720 DEBUG: SDLKeyboard: scancode: 0, sym: 8, unicode: 8, modifier: 0 20:30:39 T:3548 M:1771806720 DEBUG: CApplication::OnKey: 61448 pressed, action is 10 20:30:39 T:3548 M:1773088768 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:30:39 T:3548 M:1779363840 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:30:39 T:3548 M:1779363840 INFO: Loading skin file: Home.xml 20:30:39 T:3548 M:1779351552 DEBUG: Load Home.xml: 82.47ms 20:30:39 T:3548 M:1779343360 DEBUG: Alloc resources: 82.84ms (82.71 ms skin load, 0.09 ms preload) 20:30:40 T:3548 M:1768374272 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://xoap.weather.com (easy=034C3B90, multi=034C2540) 20:30:40 T:3548 M:1768366080 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://www.google.com (easy=05233630, multi=00000000) 20:30:40 T:3548 M:1768054784 INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://feeds.feedburner.com (easy=0523E978, multi=05232390) 20:30:42 T:3548 M:1768529920 DEBUG: SDLKeyboard: scancode: 0, sym: 273, unicode: 0, modifier: 0 20:30:42 T:3548 M:1768529920 DEBUG: CApplication::OnKey: 61478 pressed, action is 3 20:30:43 T:3548 M:1765720064 DEBUG: SDLKeyboard: scancode: 0, sym: 273, unicode: 0, modifier: 0 20:30:43 T:3548 M:1765720064 DEBUG: CApplication::OnKey: 61478 pressed, action is 3 20:30:43 T:3548 M:1763037184 DEBUG: SDLKeyboard: scancode: 0, sym: 273, unicode: 0, modifier: 0 20:30:43 T:3548 M:1763037184 DEBUG: CApplication::OnKey: 61478 pressed, action is 3 20:30:44 T:3548 M:1759862784 DEBUG: SDLKeyboard: scancode: 0, sym: 273, unicode: 0, modifier: 0 20:30:44 T:3548 M:1759862784 DEBUG: CApplication::OnKey: 61478 pressed, action is 3 20:30:45 T:3548 M:1755385856 DEBUG: SDLKeyboard: scancode: 0, sym: 273, unicode: 0, modifier: 0 20:30:45 T:3548 M:1755385856 DEBUG: CApplication::OnKey: 61478 pressed, action is 3 20:30:46 T:3548 M:1752379392 DEBUG: SDLKeyboard: scancode: 0, sym: 273, unicode: 0, modifier: 0 20:30:46 T:3548 M:1752379392 DEBUG: CApplication::OnKey: 61478 pressed, action is 3 20:30:48 T:3548 M:1747787776 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:30:48 T:3548 M:1747787776 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:30:48 T:3548 M:1747951616 DEBUG: CApplication::OnMessage : Translating XBMC.ActivateWindow(MyVideoLibrary,movietitles,return) 20:30:48 T:3548 M:1747951616 DEBUG: CApplication::OnMessage : To XBMC.ActivateWindow(MyVideoLibrary,movietitles,return) 20:30:48 T:3548 M:1747951616 DEBUG: Activating window ID: 10025 20:30:48 T:3548 M:1747951616 DEBUG: Checking if window ID 10024 is locked. 20:30:49 T:3548 M:1775767552 INFO: Attempting to return to: movietitles 20:30:49 T:3548 M:1775173632 INFO: Loading skin file: MyVideoNav.xml 20:30:49 T:3548 M:1767972864 DEBUG: Load MyVideoNav.xml: 716.68ms 20:30:49 T:3548 M:1767919616 DEBUG: Alloc resources: 779.21ms (716.82 ms skin load, 3.03 ms preload) 20:30:49 T:3548 M:1767911424 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/) 20:30:49 T:3548 M:1767911424 DEBUG: ParentPath = [videodb://1/2/] 20:30:49 T:3548 M:1767899136 DEBUG: CVideoDatabase::GetMoviesByWhere query: select * from movieview 20:30:49 T:3548 M:1767612416 DEBUG: Time for actual SQL query = 10 20:30:50 T:3548 M:1767591936 DEBUG: Time to retrieve movies from dataset = 102 20:30:50 T:3548 M:1767546880 DEBUG: CFileItemList::Sort, sorting took 0 millis 20:30:50 T:3548 M:1767546880 DEBUG: Saving fileitems [videodb://1/2/] 20:30:50 T:3548 M:1767288832 DEBUG: -- items: 144, sort method: 2, ascending: true 20:30:50 T:3740 M:1766981632 DEBUG: thread start, auto delete: 0 20:30:50 T:3748 M:1766948864 DEBUG: thread start, auto delete: 0 20:30:50 T:3736 M:1766936576 DEBUG: thread start, auto delete: 0 20:30:50 T:3744 M:1766924288 DEBUG: thread start, auto delete: 0 20:30:50 T:3752 M:1766895616 DEBUG: thread start, auto delete: 0 20:30:50 T:3740 M:1762267136 DEBUG: Thread 3740 terminating 20:30:50 T:3748 M:1761796096 DEBUG: Thread 3748 terminating 20:30:50 T:3752 M:1763127296 DEBUG: Thread 3752 terminating 20:30:50 T:3736 M:1763352576 DEBUG: Thread 3736 terminating 20:30:50 T:3744 M:1763385344 DEBUG: Thread 3744 terminating 20:30:50 T:3764 M:1757765632 DEBUG: thread start, auto delete: 0 20:30:50 T:3764 M:1757761536 DEBUG: Win32DllLoader::Load(special://xbmc/system/ImageLib.dll) 20:30:50 T:3764 M:1756831744 DEBUG: LoadLibraryA('MSVCR71.dll') 20:30:50 T:3764 M:1756831744 DEBUG: LoadLibrary('MSVCR71.dll') returning: 04A990E0 20:30:50 T:3764 M:1756831744 DEBUG: LoadLibraryA('MSVCP71.dll') 20:30:50 T:3764 M:1756753920 DEBUG: Win32DllLoader::Load(MSVCP71.dll) 20:30:50 T:3764 M:1756753920 DEBUG: LoadLibraryA('MSVCR71.dll') 20:30:50 T:3764 M:1756749824 DEBUG: LoadLibrary('MSVCR71.dll') returning: 04A990E0 20:30:50 T:3764 M:1756749824 DEBUG: LoadLibrary('MSVCP71.dll') returning: 04AC9ED0 20:30:51 T:3764 M:1747415040 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:51 T:3764 M:1753280512 DEBUG: Thread 3764 terminating 20:30:52 T:3548 M:1753780224 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:52 T:3548 M:1753780224 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:52 T:3772 M:1753743360 DEBUG: thread start, auto delete: 0 20:30:52 T:3548 M:1746452480 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:52 T:3548 M:1746452480 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:53 T:3772 M:1740431360 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:53 T:3548 M:1749643264 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:53 T:3548 M:1749643264 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:54 T:3772 M:1740357632 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:54 T:3772 M:1747443712 DEBUG: PICTURE: loaded image and created texture. height: 720, width: 1280 20:30:54 T:3548 M:1746583552 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:54 T:3548 M:1746583552 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:54 T:3772 M:1746558976 DEBUG: Thread 3772 terminating 20:30:54 T:3548 M:1747484672 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:54 T:3548 M:1747484672 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:55 T:3776 M:1747472384 DEBUG: thread start, auto delete: 0 20:30:55 T:3548 M:1744756736 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:55 T:3548 M:1744752640 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:55 T:3776 M:1740959744 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:56 T:3548 M:1747730432 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:56 T:3548 M:1747722240 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:56 T:3776 M:1741680640 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:57 T:3548 M:1747787776 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:57 T:3548 M:1747787776 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:57 T:3776 M:1745457152 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:57 T:3548 M:1750257664 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:57 T:3548 M:1750253568 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:58 T:3776 M:1745362944 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:58 T:3776 M:1745805312 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:30:58 T:3548 M:1744850944 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:58 T:3548 M:1744437248 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:58 T:3776 M:1751646208 DEBUG: Thread 3776 terminating 20:30:58 T:3784 M:1751756800 DEBUG: thread start, auto delete: 0 20:30:59 T:3548 M:1741668352 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:30:59 T:3548 M:1741668352 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:30:59 T:3784 M:1738399744 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:31:00 T:3548 M:1746173952 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:00 T:3548 M:1746173952 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:00 T:3784 M:1742856192 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:31:00 T:3548 M:1751044096 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:00 T:3548 M:1751044096 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:01 T:3784 M:1744105472 DEBUG: PICTURE: loaded image and created texture. height: 1048, width: 1864 20:31:01 T:3784 M:1749962752 DEBUG: Thread 3784 terminating 20:31:01 T:3548 M:1757908992 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:01 T:3548 M:1757908992 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:01 T:3788 M:1757900800 DEBUG: thread start, auto delete: 0 20:31:01 T:3788 M:1751527424 DEBUG: PICTURE: loaded image and created texture. height: 719, width: 1280 20:31:01 T:3788 M:1754292224 DEBUG: Thread 3788 terminating 20:31:02 T:3548 M:1753858048 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:02 T:3548 M:1753858048 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:02 T:3800 M:1754030080 DEBUG: thread start, auto delete: 0 20:31:02 T:3800 M:1747394560 DEBUG: PICTURE: loaded image and created texture. height: 720, width: 1280 20:31:02 T:3800 M:1750085632 DEBUG: Thread 3800 terminating 20:31:02 T:3548 M:1748451328 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:02 T:3548 M:1748451328 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:02 T:3804 M:1748414464 DEBUG: thread start, auto delete: 0 20:31:03 T:3804 M:1741545472 DEBUG: PICTURE: loaded image and created texture. height: 720, width: 1280 20:31:03 T:3804 M:1744396288 DEBUG: Thread 3804 terminating 20:31:03 T:3548 M:1750487040 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:03 T:3548 M:1750487040 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:03 T:3808 M:1750454272 DEBUG: thread start, auto delete: 0 20:31:03 T:3808 M:1743437824 DEBUG: PICTURE: loaded image and created texture. height: 720, width: 1280 20:31:03 T:3808 M:1746210816 DEBUG: Thread 3808 terminating 20:31:05 T:3548 M:1753219072 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:31:05 T:3548 M:1753219072 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:31:05 T:3548 M:1753042944 INFO: Loading skin file: DialogContextMenu.xml 20:31:05 T:3548 M:1753038848 DEBUG: Load DialogContextMenu.xml: 8.37ms 20:31:05 T:3548 M:1753038848 DEBUG: Alloc resources: 0.26ms (0.00 ms skin load, 0.20 ms preload) 20:31:05 T:3548 M:1753038848 DEBUG: CApplication::OnMessage : Translating - 20:31:05 T:3548 M:1753038848 DEBUG: CApplication::OnMessage : To - 20:31:05 T:3548 M:1753038848 ERROR: Keymapping error: no such action '-' defined 20:31:07 T:3548 M:1756893184 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:31:07 T:3548 M:1756893184 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:31:07 T:3548 M:1756786688 DEBUG: CPlayerCoreFactory::GetPlayers(smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img) 20:31:07 T:3548 M:1756729344 NOTICE: DVDPlayer: Opening: smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img 20:31:07 T:3548 M:1756708864 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:31:07 T:3816 M:1756708864 DEBUG: thread start, auto delete: 1 20:31:07 T:3820 M:1756696576 DEBUG: thread start, auto delete: 0 20:31:07 T:3820 M:1756688384 NOTICE: Creating InputStream 20:31:07 T:3820 M:1756676096 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/libdvdnav.dll) 20:31:07 T:3820 M:1756602368 DEBUG: LoadLibraryA('libdvdcss-2.dll') 20:31:07 T:3820 M:1756602368 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/libdvdcss-2.dll) 20:31:07 T:3820 M:1756598272 DEBUG: LoadLibrary('libdvdcss-2.dll') returning: 04DCEA68 20:31:07 T:3820 M:1756581888 INFO: msg: libdvdnav: Using dvdnav version SVN-r19116 20:31:07 T:3820 M:1756565504 INFO: msg: libdvdread: Using libdvdcss version 1.2.10 for DVD access 20:31:07 T:3820 M:1756536832 WARNING: WARNING: File is larger than 32bit stat can handle, file size will be reported as 0 bytes 20:31:07 T:3820 M:1756508160 DEBUG: libdvdcss debug: 20:31:07 T:3820 M:1756508160 INFO: msg: opening target `smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img' 20:31:07 T:3820 M:1756508160 DEBUG: msg: 20:31:07 T:3820 M:1756508160 DEBUG: libdvdcss debug: 20:31:07 T:3820 M:1756508160 DEBUG: using libc for access 20:31:07 T:3820 M:1756508160 DEBUG: msg: 20:31:07 T:3820 M:1756696576 INFO: msg: libdvdcss error: failed creating cache directory 20:31:07 T:3820 M:1756688384 INFO: msg: libdvdnav: vm: DVD Title: BATMAN_THE_DARK_KNIGHT 20:31:07 T:3820 M:1756684288 DEBUG: libdvdnav: Unable to find home directory 20:31:07 T:3820 M:1756590080 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 20:31:07 T:3820 M:1756590080 INFO: msg: 1 20:31:07 T:3820 M:1756585984 INFO: msg: 2 20:31:07 T:3820 M:1756585984 INFO: msg: 3 20:31:07 T:3820 M:1756585984 INFO: msg: 4 20:31:07 T:3820 M:1756585984 INFO: msg: 5 20:31:07 T:3820 M:1756585984 INFO: msg: 6 20:31:07 T:3820 M:1756585984 INFO: msg: 7 20:31:07 T:3820 M:1756585984 INFO: msg: 8 20:31:07 T:3820 M:1756585984 DEBUG: msg: 20:31:07 T:3820 M:1756561408 DEBUG: CDVDInputStreamNavigator::Open - Setting region mask ff 20:31:07 T:3820 M:1756561408 NOTICE: DVDPlayer: playing a dvd with menu's 20:31:07 T:3820 M:1756553216 WARNING: CDVDInputStreamNavigator::SetNavigatorState - Failed to set state (Virtual DVD machine not started.), retrying after read 20:31:07 T:3820 M:1756418048 INFO: msg: libdvdnav: Language 'en' not found, using 'ÿÿ' instead 20:31:07 T:3820 M:1756418048 DEBUG: libdvdnav: Menu Languages available: 20:31:07 T:3820 M:1756418048 INFO: msg: ÿÿ 20:31:07 T:3820 M:1756418048 DEBUG: msg: 20:31:07 T:3820 M:1756176384 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 20:31:07 T:3820 M:1756176384 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 20:31:07 T:3820 M:1756172288 NOTICE: Creating Demuxer 20:31:07 T:3820 M:1756160000 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/avutil-49.dll) 20:31:07 T:3820 M:1756241920 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/avcodec-52.dll) 20:31:07 T:3820 M:1755897856 DEBUG: LoadLibraryA('avutil-49.dll') 20:31:07 T:3820 M:1755897856 DEBUG: LoadLibrary('avutil-49.dll') returning: 04CA7918 20:31:07 T:3820 M:1755885568 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/avformat-52.dll) 20:31:07 T:3820 M:1755787264 DEBUG: LoadLibraryA('avutil-49.dll') 20:31:07 T:3820 M:1755787264 DEBUG: LoadLibrary('avutil-49.dll') returning: 04CA7918 20:31:07 T:3820 M:1755787264 DEBUG: LoadLibraryA('avcodec-52.dll') 20:31:07 T:3820 M:1755787264 DEBUG: LoadLibrary('avcodec-52.dll') returning: 04CB5640 20:31:07 T:3820 M:1755619328 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:07 T:3820 M:1755619328 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:07 T:3820 M:1755607040 NOTICE: Opening audio stream: 0 source: 512 20:31:07 T:3820 M:1755594752 INFO: Loading skin file: DialogProgress.xml 20:31:07 T:3548 M:1755594752 DEBUG: Playback has started 20:31:07 T:3548 M:1755594752 INFO: Loading skin file: VideoOverlay.xml 20:31:07 T:3548 M:1755586560 DEBUG: Load VideoOverlay.xml: 1.49ms 20:31:07 T:3548 M:1755586560 DEBUG: Alloc resources: 1.61ms (1.61 ms skin load, 0.00 ms preload) 20:31:07 T:3548 M:1755586560 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img) 20:31:07 T:3820 M:1755586560 DEBUG: Load DialogProgress.xml: 10.79ms 20:31:07 T:3820 M:1755574272 DEBUG: DVDNAV_VTS_CHANGE 20:31:07 T:3820 M:1755574272 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:07 T:3816 M:1755570176 DEBUG: Thread 3816 terminating (autodelete) 20:31:07 T:3820 M:1755602944 DEBUG: DVDNAV_HOP_CHANNEL 20:31:07 T:3820 M:1755602944 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:07 T:3820 M:1755602944 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:07 T:3820 M:1755602944 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 20:31:07 T:3820 M:1755602944 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 20:31:07 T:3820 M:1755602944 DEBUG: DVDNAV_VTS_CHANGE 20:31:07 T:3820 M:1755602944 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:07 T:3820 M:1755602944 DEBUG: CDVDInputStreamNavigator::ProcessBlock - Cell change: Title 1, Chapter 7 20:31:07 T:3820 M:1755602944 DEBUG: CDVDInputStreamNavigator::ProcessBlock - At position 17% inside the feature 20:31:07 T:3820 M:1755602944 DEBUG: DVDNAV_CELL_CHANGE 20:31:07 T:3820 M:1755598848 DEBUG: DVDNAV_SPU_CLUT_CHANGE 20:31:07 T:3820 M:1755598848 DEBUG: DVDNAV_SPU_STREAM_CHANGE 20:31:07 T:3820 M:1755598848 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 20:31:07 T:3820 M:1755586560 DEBUG: libdvdcss debug: 20:31:07 T:3820 M:1755586560 INFO: msg: cracking title key at block 44460 20:31:07 T:3820 M:1755582464 DEBUG: msg: 20:31:09 T:3820 M:1755414528 DEBUG: libdvdcss debug: 20:31:09 T:3820 M:1755414528 DEBUG: end of title reached 20:31:09 T:3820 M:1755414528 DEBUG: msg: 20:31:09 T:3820 M:1755414528 DEBUG: libdvdcss debug: 20:31:09 T:3820 M:1755414528 INFO: msg: successful attempts 0/0, scrambled blocks 0/2000 20:31:09 T:3820 M:1755414528 DEBUG: msg: 20:31:09 T:3820 M:1755414528 DEBUG: libdvdcss debug: 20:31:09 T:3820 M:1755414528 DEBUG: no scrambled sectors found 20:31:09 T:3820 M:1755414528 DEBUG: msg: 20:31:09 T:3820 M:1755414528 DEBUG: libdvdcss debug: 20:31:09 T:3820 M:1755414528 INFO: msg: title key is 00:00:00:00:00 20:31:09 T:3820 M:1755414528 DEBUG: msg: 20:31:09 T:3820 M:1755414528 DEBUG: libdvdcss debug: 20:31:09 T:3820 M:1755414528 DEBUG: unencrypted title 20:31:09 T:3820 M:1755414528 DEBUG: msg: 20:31:09 T:3820 M:1755574272 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:1596717333 DIFF:1596717333 20:31:09 T:3820 M:1755549696 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:09 T:3820 M:1755549696 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:09 T:3820 M:1754808320 NOTICE: Opening audio stream: 1 source: 256 20:31:09 T:3820 M:1754804224 NOTICE: Finding audio codec for: 86019 20:31:09 T:3820 M:1754796032 DEBUG: FactoryCodec - Audio: passthrough - Opening 20:31:09 T:3820 M:1754796032 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/liba52.dll) 20:31:09 T:3820 M:1754836992 DEBUG: LoadLibraryA('MSVCR71.dll') 20:31:09 T:3820 M:1754836992 DEBUG: LoadLibrary('MSVCR71.dll') returning: 04A990E0 20:31:09 T:3820 M:1754828800 DEBUG: FactoryCodec - Audio: passthrough - Opened 20:31:09 T:3820 M:1754824704 NOTICE: Creating audio thread 20:31:09 T:3824 M:1754775552 DEBUG: thread start, auto delete: 0 20:31:09 T:3820 M:1754767360 NOTICE: Opening video stream: 0 source: 256 20:31:09 T:3824 M:1754767360 NOTICE: running thread: CDVDPlayerAudio::Process() 20:31:09 T:3820 M:1754767360 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:09 T:3824 M:1754767360 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(-44688.888889, 1) 20:31:09 T:3820 M:1754767360 NOTICE: Creating video codec with codec id: 2 20:31:09 T:3820 M:1754755072 DEBUG: FactoryCodec - Video: libmpeg2 - Opening 20:31:09 T:3820 M:1754755072 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/libmpeg2.dll) 20:31:09 T:3820 M:1754693632 DEBUG: FactoryCodec - Video: libmpeg2 - Opened 20:31:09 T:3820 M:1754693632 NOTICE: Creating video thread 20:31:09 T:3828 M:1754685440 DEBUG: thread start, auto delete: 0 20:31:09 T:3824 M:1754673152 DEBUG: CDVDAudioCodecPassthrough::ParseFrame - source flags changed flags:17 sr:48000 br:384000 20:31:09 T:3824 M:1754673152 NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, pass-through 20:31:09 T:3824 M:1754570752 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Primary Sound Driver 20:31:09 T:3824 M:1754632192 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Turtle Beach Riviera Wave 20:31:09 T:3824 M:1754632192 DEBUG: CAudioContext::SetActiveDevice - selecting Turtle Beach Riviera Wave as output devices 20:31:09 T:3828 M:1754554368 DEBUG: Win32DllLoader::Load(special://xbmc/system/players/dvdplayer/swscale-0.6.1.dll) 20:31:09 T:3828 M:1754517504 DEBUG: LoadLibraryA('avutil-49.dll') 20:31:09 T:3828 M:1754517504 DEBUG: LoadLibrary('avutil-49.dll') returning: 04CA7918 20:31:09 T:3828 M:1754509312 NOTICE: running thread: video_thread 20:31:09 T:3828 M:1754509312 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_DELAY(11311.111111) 20:31:09 T:3824 M:1754476544 DEBUG: CWin32DirectSound::Initialize: Packet Size = 12384. Avg Bytes Per Second = 192000. 20:31:09 T:3824 M:1754726400 DEBUG: CWin32DirectSound::Initialize: Couldn't create secondary buffer (DSERR_UNSUPPORTED). Trying without LOCHARDWARE. 20:31:09 T:3824 M:1754726400 DEBUG: CWin32DirectSound::Initialize: Couldn't create secondary buffer (DSERR_INVALIDPARAM). Trying without CTRLVOLUME. 20:31:09 T:3828 M:1754537984 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-33377.777778, 0) 20:31:09 T:3824 M:1754718208 ERROR: CWin32DirectSound::Initialize: cannot create secondary buffer (DSERR_INVALIDPARAM) 20:31:09 T:3824 M:1754607616 ERROR: Creating a Null Audio Renderer, Check your audio settings as this should not happen 20:31:09 T:3824 M:1753665536 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Primary Sound Driver 20:31:09 T:3824 M:1753657344 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Turtle Beach Riviera Wave 20:31:09 T:3824 M:1753657344 DEBUG: CAudioContext::SetActiveDevice - selecting Turtle Beach Riviera Wave as output devices 20:31:09 T:3820 M:1753640960 NOTICE: Opening Subtitle stream: 7 source: 256 20:31:09 T:3820 M:1753628672 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:09 T:3824 M:1752895488 DEBUG: CDVDPlayerAudio:: Discontinuty - was:5311.111111, should be:-12688.886683, error:-17999.997795 20:31:09 T:3824 M:1752895488 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3548 M:1752895488 DEBUG: Alloc resources: 0.01ms (0.00 ms skin load, 0.00 ms preload) 20:31:09 T:3828 M:1752895488 NOTICE: fps: 25.000000, pwidth: 720, pheight: 480, dwidth: 853, dheight: 480 20:31:09 T:3828 M:1752895488 DEBUG: CDVDPlayerVideo::OutputPicture - change configuration. 720x480. framerate: 25.00 20:31:09 T:3828 M:1752895488 NOTICE: Display resolution AUTO : 1864x1048 @ 60.00Hz (Full Screen) (12) 20:31:09 T:3828 M:1752891392 WARNING: Upscale: selected algorithm 1 20:31:09 T:3548 M:1752825856 NOTICE: Using GL_TEXTURE_2D 20:31:09 T:3548 M:1752825856 DEBUG: GL: Requested render method: 0 20:31:09 T:3548 M:1752813568 NOTICE: GL: Selecting YUV 2 RGB Progressive Shader 20:31:09 T:3548 M:1752178688 DEBUG: GL: Shader compilation log: 20:31:09 T:3548 M:1752170496 DEBUG: 20:31:09 T:3548 M:1752141824 DEBUG: GL: Shader compilation log: 20:31:09 T:3548 M:1752141824 DEBUG: 20:31:09 T:3548 M:1751969792 NOTICE: GL: NPOT texture support detected 20:31:09 T:3548 M:1751941120 DEBUG: GL: Creating Y NPOT texture of size 720 x 480 20:31:09 T:3548 M:1751941120 DEBUG: GL: Creating U NPOT texture of size 360 x 240 20:31:09 T:3548 M:1751941120 DEBUG: GL: Creating V NPOT texture of size 360 x 240 20:31:09 T:3548 M:1751941120 DEBUG: GL: Creating Y NPOT texture of size 720 x 480 20:31:09 T:3548 M:1751941120 DEBUG: GL: Creating U NPOT texture of size 360 x 120 20:31:09 T:3548 M:1751932928 DEBUG: GL: Creating V NPOT texture of size 360 x 120 20:31:09 T:3548 M:1751924736 DEBUG: GL: Creating Y NPOT texture of size 720 x 480 20:31:09 T:3548 M:1751924736 DEBUG: GL: Creating U NPOT texture of size 360 x 120 20:31:09 T:3548 M:1751916544 DEBUG: GL: Creating V NPOT texture of size 360 x 120 20:31:09 T:3548 M:1751887872 DEBUG: GL: Creating Y NPOT texture of size 720 x 480 20:31:09 T:3548 M:1751814144 DEBUG: GL: Creating U NPOT texture of size 360 x 240 20:31:09 T:3548 M:1751814144 DEBUG: GL: Creating V NPOT texture of size 360 x 240 20:31:09 T:3548 M:1751814144 DEBUG: GL: Creating Y NPOT texture of size 720 x 480 20:31:09 T:3548 M:1751814144 DEBUG: GL: Creating U NPOT texture of size 360 x 120 20:31:09 T:3548 M:1751805952 DEBUG: GL: Creating V NPOT texture of size 360 x 120 20:31:09 T:3548 M:1751797760 DEBUG: GL: Creating Y NPOT texture of size 720 x 480 20:31:09 T:3548 M:1751793664 DEBUG: GL: Creating U NPOT texture of size 360 x 120 20:31:09 T:3548 M:1751785472 DEBUG: GL: Creating V NPOT texture of size 360 x 120 20:31:09 T:3548 M:1750355968 DEBUG: Activating window ID: 12005 20:31:09 T:3548 M:1750351872 DEBUG: Checking if window ID 12005 is locked. 20:31:09 T:3824 M:1751359488 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3824 M:1751486464 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3548 M:1753186304 INFO: Loading skin file: VideoFullScreen.xml 20:31:09 T:3548 M:1753915392 DEBUG: Load VideoFullScreen.xml: 22.91ms 20:31:09 T:3548 M:1753845760 DEBUG: Alloc resources: 29.43ms (29.40 ms skin load, 0.02 ms preload) 20:31:09 T:3548 M:1753845760 INFO: Loading skin file: VideoOSD.xml 20:31:09 T:3548 M:1754345472 DEBUG: Load VideoOSD.xml: 6.49ms 20:31:09 T:3820 M:1754275840 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:09 T:3824 M:1753862144 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3548 M:1753534464 DEBUG: Alloc resources: 47.53ms (0.00 ms skin load, 0.89 ms preload) 20:31:09 T:3548 M:1753624576 INFO: Loading skin file: VideoOSDSettings.xml 20:31:09 T:3548 M:1753456640 DEBUG: Load VideoOSDSettings.xml: 4.33ms 20:31:09 T:3824 M:1752948736 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3824 M:1752944640 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3548 M:1752514560 DEBUG: Alloc resources: 83.76ms (0.00 ms skin load, 0.91 ms preload) 20:31:09 T:3548 M:1752514560 INFO: Loading skin file: VideoOSDSettings.xml 20:31:09 T:3548 M:1752440832 DEBUG: Load VideoOSDSettings.xml: 4.10ms 20:31:09 T:3548 M:1752440832 DEBUG: Alloc resources: 0.52ms (0.00 ms skin load, 0.21 ms preload) 20:31:09 T:3548 M:1752018944 INFO: GL: ARB Imaging extension supported 20:31:09 T:3824 M:1751519232 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3820 M:1751482368 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:09 T:3824 M:1750859776 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3828 M:1750577152 DEBUG: CDVDVideoCodecLibMpeg2::m_bFilm = true 20:31:09 T:3824 M:1750192128 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3824 M:1750528000 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3824 M:1750315008 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3824 M:1750069248 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:09 T:3820 M:1749053440 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:10 T:3820 M:1748553728 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:10 T:3824 M:1748979712 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:11 T:3824 M:1749196800 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:11 T:3824 M:1749237760 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:11 T:3824 M:1752395776 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:11 T:3824 M:1752412160 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:13 T:3824 M:1752178688 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:14 T:3824 M:1752543232 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:14 T:3824 M:1752510464 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:14 T:3824 M:1752403968 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:14 T:3820 M:1752395776 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:14 T:3824 M:1752416256 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:15 T:3824 M:1752514560 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:16 T:3824 M:1752350720 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:16 T:3824 M:1752739840 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:16 T:3824 M:1752739840 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:17 T:3824 M:1752653824 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:17 T:3820 M:1752584192 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:17 T:3824 M:1752580096 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:17 T:3824 M:1752514560 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:17 T:3824 M:1752350720 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:18 T:3824 M:1752395776 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:18 T:3548 M:1752739840 DEBUG: SDLKeyboard: scancode: 0, sym: 308, unicode: 0, modifier: 0 20:31:18 T:3548 M:1752764416 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 100 20:31:18 T:3548 M:1752764416 DEBUG: CApplication::OnKey: 61453 pressed, action is 24 20:31:18 T:3548 M:1752764416 DEBUG: Alloc resources: 24.88ms (0.00 ms skin load, 0.80 ms preload) 20:31:18 T:3820 M:1750249472 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:18 T:3824 M:1750249472 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:19 T:3548 M:1754267648 DEBUG: SDLKeyboard: scancode: 0, sym: 275, unicode: 0, modifier: 0 20:31:19 T:3548 M:1754267648 DEBUG: CApplication::OnKey: 61479 pressed, action is 2 20:31:19 T:3824 M:1754243072 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:19 T:3824 M:1758969856 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:19 T:3824 M:1758900224 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:19 T:3548 M:1758822400 DEBUG: SDLKeyboard: scancode: 0, sym: 275, unicode: 0, modifier: 0 20:31:19 T:3548 M:1758822400 DEBUG: CApplication::OnKey: 61479 pressed, action is 2 20:31:20 T:3824 M:1758658560 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:20 T:3820 M:1758658560 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:20 T:3824 M:1758654464 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:20 T:3824 M:1758584832 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:20 T:3824 M:1759039488 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:20 T:3548 M:1759031296 DEBUG: SDLKeyboard: scancode: 0, sym: 276, unicode: 0, modifier: 0 20:31:20 T:3548 M:1759031296 DEBUG: CApplication::OnKey: 61477 pressed, action is 1 20:31:21 T:3824 M:1759031296 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:21 T:3820 M:1758859264 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:21 T:3824 M:1758756864 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:21 T:3548 M:1758752768 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:31:21 T:3548 M:1758752768 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:31:21 T:3548 M:1758752768 DEBUG: CApplication::OnMessage : Translating XBMC.ActivateWindow(124) 20:31:21 T:3548 M:1758752768 DEBUG: CApplication::OnMessage : To XBMC.ActivateWindow(124) 20:31:21 T:3548 M:1758752768 DEBUG: Activating window ID: 10124 20:31:21 T:3548 M:1758752768 DEBUG: Checking if window ID 10124 is locked. 20:31:21 T:3824 M:1758707712 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:22 T:3824 M:1758875648 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:22 T:3824 M:1758715904 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:22 T:3824 M:1758646272 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:23 T:3548 M:1758642176 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:23 T:3548 M:1758642176 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:23 T:3820 M:1759219712 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:23 T:3824 M:1759219712 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:23 T:3548 M:1759023104 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:23 T:3548 M:1759023104 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:23 T:3824 M:1759129600 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:24 T:3824 M:1758969856 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:24 T:3548 M:1759330304 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:31:24 T:3548 M:1759330304 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:31:25 T:3824 M:1759125504 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:25 T:3824 M:1759465472 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:25 T:3824 M:1759506432 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:25 T:3824 M:1759346688 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:26 T:3824 M:1759346688 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:26 T:3820 M:1759305728 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:26 T:3824 M:1759784960 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:27 T:3548 M:1759748096 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:27 T:3548 M:1759748096 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:27 T:3824 M:1759772672 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:27 T:3820 M:1759809536 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:27 T:3548 M:1759809536 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:31:27 T:3548 M:1759809536 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:31:27 T:3824 M:1759809536 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:28 T:3548 M:1759326208 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:31:28 T:3548 M:1759326208 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:31:28 T:3548 M:1759322112 NOTICE: DVDPlayer: Opening: smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img 20:31:28 T:3548 M:1759322112 NOTICE: CDVDPlayer::CloseFile() 20:31:28 T:3548 M:1759322112 NOTICE: DVDPlayer: waiting for threads to exit 20:31:28 T:3820 M:1759322112 NOTICE: CDVDPlayer::OnExit() 20:31:28 T:3820 M:1759322112 NOTICE: DVDPlayer: closing audio stream 20:31:28 T:3820 M:1759322112 NOTICE: Closing audio stream 20:31:28 T:3820 M:1759322112 NOTICE: Waiting for audio thread to exit 20:31:28 T:3824 M:1759322112 DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread 20:31:28 T:3824 M:1759322112 NOTICE: thread end: CDVDPlayerAudio::OnExit() 20:31:28 T:3824 M:1759322112 DEBUG: Thread 3824 terminating 20:31:28 T:3820 M:1759363072 NOTICE: Closing audio device 20:31:28 T:3820 M:1759358976 NOTICE: Deleting audio codec 20:31:28 T:3820 M:1759358976 NOTICE: DVDPlayer: closing video stream 20:31:28 T:3820 M:1759358976 NOTICE: Closing video stream 20:31:28 T:3820 M:1759358976 NOTICE: waiting for video thread to exit 20:31:29 T:3828 M:1759367168 NOTICE: thread end: video_thread 20:31:29 T:3828 M:1759367168 DEBUG: Thread 3828 terminating 20:31:29 T:3820 M:1763139584 NOTICE: deleting video codec 20:31:29 T:3820 M:1765257216 NOTICE: DVDPlayer: closing video stream 20:31:29 T:3820 M:1765257216 NOTICE: Closing subtitle stream 20:31:29 T:3820 M:1765646336 NOTICE: CDVDPlayer::OnExit() deleting demuxer 20:31:29 T:3820 M:1765748736 NOTICE: CDVDPlayer::OnExit() deleting input stream 20:31:29 T:3820 M:1767268352 DEBUG: Thread 3820 terminating 20:31:29 T:3548 M:1767321600 NOTICE: DVDPlayer: finished waiting 20:31:29 T:3548 M:1767317504 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:31:29 T:3884 M:1767317504 DEBUG: thread start, auto delete: 1 20:31:29 T:3888 M:1767305216 DEBUG: thread start, auto delete: 0 20:31:29 T:3888 M:1767301120 NOTICE: Creating InputStream 20:31:29 T:3888 M:1767301120 INFO: msg: libdvdnav: Using dvdnav version SVN-r19116 20:31:29 T:3888 M:1767297024 INFO: msg: libdvdread: Using libdvdcss version 1.2.10 for DVD access 20:31:29 T:3888 M:1767264256 WARNING: WARNING: File is larger than 32bit stat can handle, file size will be reported as 0 bytes 20:31:29 T:3888 M:1767239680 DEBUG: libdvdcss debug: 20:31:29 T:3888 M:1767239680 INFO: msg: opening target `smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img' 20:31:29 T:3888 M:1767239680 DEBUG: msg: 20:31:29 T:3888 M:1767239680 DEBUG: libdvdcss debug: 20:31:29 T:3888 M:1767239680 DEBUG: using libc for access 20:31:29 T:3888 M:1767239680 DEBUG: msg: 20:31:29 T:3888 M:1767223296 INFO: msg: libdvdcss error: failed creating cache directory 20:31:29 T:3888 M:1767223296 INFO: msg: libdvdnav: vm: DVD Title: BATMAN_THE_DARK_KNIGHT 20:31:29 T:3888 M:1767215104 DEBUG: libdvdnav: Unable to find home directory 20:31:29 T:3888 M:1767129088 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 20:31:29 T:3888 M:1767129088 INFO: msg: 1 20:31:29 T:3888 M:1767129088 INFO: msg: 2 20:31:29 T:3888 M:1767129088 INFO: msg: 3 20:31:29 T:3888 M:1767129088 INFO: msg: 4 20:31:29 T:3888 M:1767129088 INFO: msg: 5 20:31:29 T:3888 M:1767129088 INFO: msg: 6 20:31:29 T:3888 M:1767129088 INFO: msg: 7 20:31:29 T:3888 M:1767129088 INFO: msg: 8 20:31:29 T:3888 M:1767129088 DEBUG: msg: 20:31:29 T:3888 M:1767124992 DEBUG: CDVDInputStreamNavigator::Open - Setting region mask ff 20:31:29 T:3888 M:1767124992 NOTICE: DVDPlayer: playing a dvd with menu's 20:31:29 T:3888 M:1767124992 NOTICE: Creating Demuxer 20:31:29 T:3888 M:1767092224 INFO: msg: libdvdnav: Language 'en' not found, using 'ÿÿ' instead 20:31:29 T:3888 M:1767092224 DEBUG: libdvdnav: Menu Languages available: 20:31:29 T:3888 M:1767092224 INFO: msg: ÿÿ 20:31:29 T:3888 M:1767092224 DEBUG: msg: 20:31:29 T:3888 M:1766920192 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 20:31:29 T:3888 M:1766920192 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 20:31:29 T:3888 M:1766920192 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:29 T:3888 M:1766920192 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:29 T:3888 M:1766920192 NOTICE: Opening audio stream: 0 source: 512 20:31:29 T:3888 M:1766920192 DEBUG: DVDNAV_VTS_CHANGE 20:31:29 T:3548 M:1766920192 DEBUG: Playback has started 20:31:29 T:3888 M:1766920192 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 20:31:29 T:3884 M:1766920192 DEBUG: Thread 3884 terminating (autodelete) 20:31:29 T:3548 M:1766932480 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img) 20:31:29 T:3888 M:1766961152 DEBUG: CDVDInputStreamNavigator::ProcessBlock - Cell change: Title 0, Chapter 3 20:31:29 T:3888 M:1766961152 DEBUG: CDVDInputStreamNavigator::ProcessBlock - At position 0% inside the feature 20:31:29 T:3888 M:1766961152 DEBUG: DVDNAV_CELL_CHANGE 20:31:29 T:3888 M:1766961152 DEBUG: DVDNAV_SPU_CLUT_CHANGE 20:31:29 T:3888 M:1766961152 DEBUG: DVDNAV_SPU_STREAM_CHANGE 20:31:29 T:3888 M:1766961152 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 20:31:29 T:3888 M:1766957056 DEBUG: libdvdcss debug: 20:31:29 T:3888 M:1766957056 INFO: msg: cracking title key at block 378 20:31:29 T:3888 M:1766957056 DEBUG: msg: 20:31:30 T:3888 M:1767018496 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767018496 DEBUG: end of title reached 20:31:30 T:3888 M:1767018496 DEBUG: msg: 20:31:30 T:3888 M:1767018496 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767018496 INFO: msg: successful attempts 0/0, scrambled blocks 0/2000 20:31:30 T:3888 M:1767018496 DEBUG: msg: 20:31:30 T:3888 M:1767018496 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767018496 DEBUG: no scrambled sectors found 20:31:30 T:3888 M:1767018496 DEBUG: msg: 20:31:30 T:3888 M:1767018496 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767018496 INFO: msg: title key is 00:00:00:00:00 20:31:30 T:3888 M:1767018496 DEBUG: msg: 20:31:30 T:3888 M:1767018496 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767018496 DEBUG: unencrypted title 20:31:30 T:3888 M:1767018496 DEBUG: msg: 20:31:30 T:3888 M:1767010304 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:45500 DIFF:45500 20:31:30 T:3888 M:1766993920 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:30 T:3888 M:1766993920 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:30 T:3888 M:1766993920 DEBUG: DVDNAV_HOP_CHANNEL 20:31:30 T:3888 M:1766993920 NOTICE: Opening video stream: 0 source: 256 20:31:30 T:3888 M:1766993920 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:30 T:3888 M:1766993920 NOTICE: Creating video codec with codec id: 2 20:31:30 T:3888 M:1766993920 DEBUG: FactoryCodec - Video: libmpeg2 - Opening 20:31:30 T:3888 M:1766981632 DEBUG: FactoryCodec - Video: libmpeg2 - Opened 20:31:30 T:3888 M:1766981632 NOTICE: Creating video thread 20:31:30 T:3888 M:1766973440 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 20:31:30 T:3888 M:1766973440 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 20:31:30 T:3892 M:1766973440 DEBUG: thread start, auto delete: 0 20:31:30 T:3888 M:1766969344 DEBUG: DVDNAV_VTS_CHANGE 20:31:30 T:3892 M:1766969344 NOTICE: running thread: video_thread 20:31:30 T:3888 M:1766969344 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:30 T:3892 M:1766969344 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-33366.666667, 1) 20:31:30 T:3892 M:1766969344 DEBUG: (CDVDVideoCodecLibMpeg2::Reset) 20:31:30 T:3888 M:1766903808 DEBUG: CDVDInputStreamNavigator::ProcessBlock - Cell change: Title 1, Chapter 7 20:31:30 T:3888 M:1766903808 DEBUG: CDVDInputStreamNavigator::ProcessBlock - At position 17% inside the feature 20:31:30 T:3888 M:1766903808 DEBUG: DVDNAV_CELL_CHANGE 20:31:30 T:3888 M:1766903808 DEBUG: DVDNAV_SPU_CLUT_CHANGE 20:31:30 T:3888 M:1767309312 DEBUG: DVDNAV_SPU_STREAM_CHANGE 20:31:30 T:3888 M:1767309312 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 20:31:30 T:3888 M:1767309312 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767309312 INFO: msg: cracking title key at block 44460 20:31:30 T:3888 M:1767309312 DEBUG: msg: 20:31:30 T:3888 M:1767063552 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767055360 DEBUG: end of title reached 20:31:30 T:3888 M:1767055360 DEBUG: msg: 20:31:30 T:3888 M:1767055360 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767055360 INFO: msg: successful attempts 0/0, scrambled blocks 0/2000 20:31:30 T:3888 M:1767055360 DEBUG: msg: 20:31:30 T:3888 M:1767059456 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767059456 DEBUG: no scrambled sectors found 20:31:30 T:3888 M:1767055360 DEBUG: msg: 20:31:30 T:3888 M:1767055360 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767055360 INFO: msg: title key is 00:00:00:00:00 20:31:30 T:3888 M:1767059456 DEBUG: msg: 20:31:30 T:3888 M:1767059456 DEBUG: libdvdcss debug: 20:31:30 T:3888 M:1767055360 DEBUG: unencrypted title 20:31:30 T:3888 M:1767055360 DEBUG: msg: 20:31:30 T:3888 M:1767051264 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:546000 TO:1635205777 DIFF:1634659777 20:31:30 T:3888 M:1767043072 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:30 T:3888 M:1767043072 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:30 T:3888 M:1766678528 NOTICE: Closing video stream 20:31:30 T:3888 M:1766678528 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty 20:31:30 T:3892 M:1766678528 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 20:31:30 T:3888 M:1766760448 NOTICE: waiting for video thread to exit 20:31:30 T:3892 M:1766760448 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 20:31:30 T:3892 M:1766760448 NOTICE: thread end: video_thread 20:31:30 T:3892 M:1766760448 DEBUG: Thread 3892 terminating 20:31:30 T:3888 M:1766768640 NOTICE: deleting video codec 20:31:30 T:3888 M:1766780928 NOTICE: Opening audio stream: 1 source: 256 20:31:30 T:3888 M:1766780928 NOTICE: Finding audio codec for: 86019 20:31:30 T:3888 M:1766768640 DEBUG: FactoryCodec - Audio: passthrough - Opening 20:31:30 T:3888 M:1766768640 DEBUG: FactoryCodec - Audio: passthrough - Failed 20:31:30 T:3888 M:1766768640 DEBUG: FactoryCodec - Audio: liba52 - Opening 20:31:30 T:3888 M:1766768640 DEBUG: FactoryCodec - Audio: liba52 - Opened 20:31:30 T:3888 M:1766768640 NOTICE: Creating audio thread 20:31:30 T:3896 M:1766731776 DEBUG: thread start, auto delete: 0 20:31:30 T:3896 M:1766744064 NOTICE: running thread: CDVDPlayerAudio::Process() 20:31:30 T:3896 M:1766727680 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(463366.666667, 1) 20:31:30 T:3888 M:1766641664 NOTICE: Opening video stream: 0 source: 256 20:31:30 T:3888 M:1766633472 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:30 T:3888 M:1766633472 NOTICE: Creating video codec with codec id: 2 20:31:30 T:3888 M:1766633472 DEBUG: FactoryCodec - Video: libmpeg2 - Opening 20:31:30 T:3888 M:1766617088 DEBUG: FactoryCodec - Video: libmpeg2 - Opened 20:31:30 T:3896 M:1766612992 NOTICE: Creating audio device with codec id: 86019, channels: 6, sample rate: 48000, no pass-through 20:31:30 T:3888 M:1766612992 NOTICE: Creating video thread 20:31:30 T:3896 M:1766584320 DEBUG: CWin32DirectSound::Initialize: Packet Size = 37152. Avg Bytes Per Second = 576000. 20:31:30 T:3896 M:1765986304 DEBUG: CWin32DirectSound::Initialize: secondary buffer created 20:31:30 T:3888 M:1765961728 NOTICE: Opening Subtitle stream: 5 source: 256 20:31:30 T:3888 M:1765924864 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:30 T:3896 M:1765896192 DEBUG: CWin32DirectSound::CheckPlayStatus: Resuming Playback 20:31:30 T:3900 M:1764843520 DEBUG: thread start, auto delete: 0 20:31:30 T:3900 M:1764831232 NOTICE: running thread: video_thread 20:31:30 T:3900 M:1764831232 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_DELAY(3766.666667) 20:31:30 T:3900 M:1764831232 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(467133.333333, 0) 20:31:30 T:3896 M:1762766848 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:30 T:3888 M:1762025472 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:30 T:3900 M:1761734656 DEBUG: CDVDVideoCodecLibMpeg2::m_bFilm = true 20:31:30 T:3888 M:1760186368 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:30 T:3888 M:1759485952 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:31 T:3896 M:1758814208 DEBUG: CDVDPlayerAudio:: Discontinuty - was:1569366.666667, should be:1584116.660945, error:14749.994278 20:31:33 T:3888 M:1758670848 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:33 T:3548 M:1758666752 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmc/system/ImageLib.dll) 20:31:33 T:3548 M:1758666752 DEBUG: Win32DllLoader::Unload ImageLib.dll 20:31:33 T:3548 M:1758662656 DEBUG: FreeLibrary(MSVCR71.dll) -> 04A990E0 20:31:33 T:3548 M:1758662656 DEBUG: FreeLibrary(MSVCP71.dll) -> 04AC9ED0 20:31:33 T:3548 M:1758658560 DEBUG: Win32DllLoader::Unload MSVCP71.dll 20:31:33 T:3548 M:1758658560 DEBUG: FreeLibrary(MSVCR71.dll) -> 04A990E0 20:31:36 T:3548 M:1759105024 DEBUG: SDLKeyboard: scancode: 0, sym: 275, unicode: 0, modifier: 0 20:31:36 T:3548 M:1759105024 DEBUG: CApplication::OnKey: 61479 pressed, action is 2 20:31:38 T:3548 M:1759391744 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:31:38 T:3548 M:1759391744 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:31:38 T:3548 M:1759391744 NOTICE: DVDPlayer: Opening: smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img 20:31:38 T:3548 M:1759391744 NOTICE: CDVDPlayer::CloseFile() 20:31:38 T:3548 M:1759391744 NOTICE: DVDPlayer: waiting for threads to exit 20:31:38 T:3888 M:1759391744 NOTICE: CDVDPlayer::OnExit() 20:31:38 T:3888 M:1759391744 NOTICE: DVDPlayer: closing audio stream 20:31:38 T:3888 M:1759391744 NOTICE: Closing audio stream 20:31:38 T:3888 M:1759391744 NOTICE: Waiting for audio thread to exit 20:31:38 T:3896 M:1759391744 ERROR: CDVDAudio::AddPackets - failed to add leftover bytes to render 20:31:38 T:3896 M:1759391744 NOTICE: thread end: CDVDPlayerAudio::OnExit() 20:31:38 T:3896 M:1759391744 DEBUG: Thread 3896 terminating 20:31:38 T:3888 M:1759453184 NOTICE: Closing audio device 20:31:38 T:3888 M:1759453184 DEBUG: CWin32DirectSound::Deinitialize: Cleaning up 20:31:38 T:3888 M:1760063488 NOTICE: Deleting audio codec 20:31:38 T:3888 M:1760063488 NOTICE: DVDPlayer: closing video stream 20:31:38 T:3888 M:1760063488 NOTICE: Closing video stream 20:31:38 T:3888 M:1760063488 NOTICE: waiting for video thread to exit 20:31:38 T:3900 M:1760063488 NOTICE: thread end: video_thread 20:31:38 T:3900 M:1760063488 DEBUG: Thread 3900 terminating 20:31:38 T:3888 M:1764315136 NOTICE: deleting video codec 20:31:38 T:3888 M:1766309888 NOTICE: DVDPlayer: closing video stream 20:31:38 T:3888 M:1766309888 NOTICE: Closing subtitle stream 20:31:38 T:3888 M:1766375424 NOTICE: CDVDPlayer::OnExit() deleting demuxer 20:31:38 T:3888 M:1766445056 NOTICE: CDVDPlayer::OnExit() deleting input stream 20:31:38 T:3888 M:1767895040 DEBUG: Thread 3888 terminating 20:31:38 T:3548 M:1767976960 NOTICE: DVDPlayer: finished waiting 20:31:38 T:3548 M:1767972864 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:31:38 T:3912 M:1767968768 DEBUG: thread start, auto delete: 1 20:31:38 T:3916 M:1767956480 DEBUG: thread start, auto delete: 0 20:31:38 T:3916 M:1767952384 NOTICE: Creating InputStream 20:31:38 T:3916 M:1767952384 INFO: msg: libdvdnav: Using dvdnav version SVN-r19116 20:31:38 T:3916 M:1767948288 INFO: msg: libdvdread: Using libdvdcss version 1.2.10 for DVD access 20:31:38 T:3916 M:1767919616 WARNING: WARNING: File is larger than 32bit stat can handle, file size will be reported as 0 bytes 20:31:38 T:3916 M:1767895040 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767895040 INFO: msg: opening target `smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img' 20:31:38 T:3916 M:1767895040 DEBUG: msg: 20:31:38 T:3916 M:1767895040 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767895040 DEBUG: using libc for access 20:31:38 T:3916 M:1767895040 DEBUG: msg: 20:31:38 T:3916 M:1767907328 INFO: msg: libdvdcss error: failed creating cache directory 20:31:38 T:3916 M:1767907328 INFO: msg: libdvdnav: vm: DVD Title: BATMAN_THE_DARK_KNIGHT 20:31:38 T:3916 M:1767899136 DEBUG: libdvdnav: Unable to find home directory 20:31:38 T:3916 M:1767813120 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 20:31:38 T:3916 M:1767813120 INFO: msg: 1 20:31:38 T:3916 M:1767813120 INFO: msg: 2 20:31:38 T:3916 M:1767813120 INFO: msg: 3 20:31:38 T:3916 M:1767813120 INFO: msg: 4 20:31:38 T:3916 M:1767813120 INFO: msg: 5 20:31:38 T:3916 M:1767813120 INFO: msg: 6 20:31:38 T:3916 M:1767813120 INFO: msg: 7 20:31:38 T:3916 M:1767809024 INFO: msg: 8 20:31:38 T:3916 M:1767809024 DEBUG: msg: 20:31:38 T:3916 M:1767804928 DEBUG: CDVDInputStreamNavigator::Open - Setting region mask ff 20:31:38 T:3916 M:1767804928 NOTICE: DVDPlayer: playing a dvd with menu's 20:31:38 T:3916 M:1767804928 NOTICE: Creating Demuxer 20:31:38 T:3916 M:1767772160 INFO: msg: libdvdnav: Language 'en' not found, using 'ÿÿ' instead 20:31:38 T:3916 M:1767772160 DEBUG: libdvdnav: Menu Languages available: 20:31:38 T:3916 M:1767772160 INFO: msg: ÿÿ 20:31:38 T:3916 M:1767772160 DEBUG: msg: 20:31:38 T:3916 M:1767604224 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 20:31:38 T:3916 M:1767600128 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 20:31:38 T:3916 M:1767600128 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:38 T:3916 M:1767600128 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:38 T:3916 M:1767600128 NOTICE: Opening audio stream: 0 source: 512 20:31:38 T:3916 M:1767600128 DEBUG: DVDNAV_VTS_CHANGE 20:31:38 T:3916 M:1767600128 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 20:31:38 T:3548 M:1767600128 DEBUG: Playback has started 20:31:38 T:3912 M:1767600128 DEBUG: Thread 3912 terminating (autodelete) 20:31:38 T:3548 M:1767612416 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img) 20:31:38 T:3916 M:1767612416 DEBUG: CDVDInputStreamNavigator::ProcessBlock - Cell change: Title 0, Chapter 3 20:31:38 T:3916 M:1767612416 DEBUG: CDVDInputStreamNavigator::ProcessBlock - At position 0% inside the feature 20:31:38 T:3916 M:1767612416 DEBUG: DVDNAV_CELL_CHANGE 20:31:38 T:3916 M:1767612416 DEBUG: DVDNAV_SPU_CLUT_CHANGE 20:31:38 T:3916 M:1767612416 DEBUG: DVDNAV_SPU_STREAM_CHANGE 20:31:38 T:3916 M:1767612416 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 20:31:38 T:3916 M:1767612416 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767612416 INFO: msg: cracking title key at block 378 20:31:38 T:3916 M:1767612416 DEBUG: msg: 20:31:38 T:3916 M:1767649280 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767649280 DEBUG: end of title reached 20:31:38 T:3916 M:1767649280 DEBUG: msg: 20:31:38 T:3916 M:1767649280 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767649280 INFO: msg: successful attempts 0/0, scrambled blocks 0/2000 20:31:38 T:3916 M:1767649280 DEBUG: msg: 20:31:38 T:3916 M:1767649280 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767649280 DEBUG: no scrambled sectors found 20:31:38 T:3916 M:1767649280 DEBUG: msg: 20:31:38 T:3916 M:1767649280 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767649280 INFO: msg: title key is 00:00:00:00:00 20:31:38 T:3916 M:1767649280 DEBUG: msg: 20:31:38 T:3916 M:1767649280 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767649280 DEBUG: unencrypted title 20:31:38 T:3916 M:1767649280 DEBUG: msg: 20:31:38 T:3916 M:1767641088 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:45500 DIFF:45500 20:31:38 T:3916 M:1767624704 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:38 T:3916 M:1767624704 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:38 T:3916 M:1767624704 DEBUG: DVDNAV_HOP_CHANNEL 20:31:38 T:3916 M:1767624704 NOTICE: Opening video stream: 0 source: 256 20:31:38 T:3916 M:1767624704 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:38 T:3916 M:1767624704 NOTICE: Creating video codec with codec id: 2 20:31:38 T:3916 M:1767624704 DEBUG: FactoryCodec - Video: libmpeg2 - Opening 20:31:38 T:3916 M:1767612416 DEBUG: FactoryCodec - Video: libmpeg2 - Opened 20:31:38 T:3916 M:1767612416 NOTICE: Creating video thread 20:31:38 T:3916 M:1767604224 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 20:31:38 T:3916 M:1767604224 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 20:31:38 T:3928 M:1767600128 DEBUG: thread start, auto delete: 0 20:31:38 T:3916 M:1767596032 DEBUG: DVDNAV_VTS_CHANGE 20:31:38 T:3928 M:1767596032 NOTICE: running thread: video_thread 20:31:38 T:3916 M:1767596032 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:38 T:3928 M:1767596032 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-33366.666667, 1) 20:31:38 T:3928 M:1767596032 DEBUG: (CDVDVideoCodecLibMpeg2::Reset) 20:31:38 T:3916 M:1767596032 DEBUG: CDVDInputStreamNavigator::ProcessBlock - Cell change: Title 1, Chapter 7 20:31:38 T:3916 M:1767596032 DEBUG: CDVDInputStreamNavigator::ProcessBlock - At position 17% inside the feature 20:31:38 T:3916 M:1767596032 DEBUG: DVDNAV_CELL_CHANGE 20:31:38 T:3916 M:1767596032 DEBUG: DVDNAV_SPU_CLUT_CHANGE 20:31:38 T:3916 M:1767596032 DEBUG: DVDNAV_SPU_STREAM_CHANGE 20:31:38 T:3916 M:1767596032 DEBUG: DVDNAV_AUDIO_STREAM_CHANGE 20:31:38 T:3916 M:1767596032 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767596032 INFO: msg: cracking title key at block 44460 20:31:38 T:3916 M:1767596032 DEBUG: msg: 20:31:38 T:3916 M:1767342080 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767342080 DEBUG: end of title reached 20:31:38 T:3916 M:1767342080 DEBUG: msg: 20:31:38 T:3916 M:1767342080 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767342080 INFO: msg: successful attempts 0/0, scrambled blocks 0/2000 20:31:38 T:3916 M:1767342080 DEBUG: msg: 20:31:38 T:3916 M:1767342080 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767342080 DEBUG: no scrambled sectors found 20:31:38 T:3916 M:1767342080 DEBUG: msg: 20:31:38 T:3916 M:1767342080 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767342080 INFO: msg: title key is 00:00:00:00:00 20:31:38 T:3916 M:1767342080 DEBUG: msg: 20:31:38 T:3916 M:1767342080 DEBUG: libdvdcss debug: 20:31:38 T:3916 M:1767342080 DEBUG: unencrypted title 20:31:38 T:3916 M:1767342080 DEBUG: msg: 20:31:38 T:3916 M:1767337984 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:546000 TO:1653941155 DIFF:1653395155 20:31:38 T:3916 M:1767329792 INFO: ffmpeg: Input #0, mpeg, from 'smb://192.168.0.4/f$/Video/Movies/The Dark Knight (2008)/bd-tdknight-dvdr.img': 20:31:38 T:3916 M:1767329792 INFO: ffmpeg: Duration: N/A, bitrate: N/A 20:31:38 T:3916 M:1766871040 NOTICE: Closing video stream 20:31:38 T:3916 M:1766871040 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty 20:31:38 T:3928 M:1766871040 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 20:31:38 T:3916 M:1766871040 NOTICE: waiting for video thread to exit 20:31:38 T:3928 M:1766871040 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 20:31:38 T:3928 M:1766871040 NOTICE: thread end: video_thread 20:31:38 T:3928 M:1766871040 DEBUG: Thread 3928 terminating 20:31:38 T:3916 M:1766883328 NOTICE: deleting video codec 20:31:38 T:3916 M:1766887424 NOTICE: Opening audio stream: 1 source: 256 20:31:38 T:3916 M:1766887424 NOTICE: Finding audio codec for: 86019 20:31:38 T:3916 M:1766875136 DEBUG: FactoryCodec - Audio: passthrough - Opening 20:31:38 T:3916 M:1766875136 DEBUG: FactoryCodec - Audio: passthrough - Opened 20:31:38 T:3916 M:1766875136 NOTICE: Creating audio thread 20:31:38 T:3932 M:1766789120 DEBUG: thread start, auto delete: 0 20:31:38 T:3932 M:1766723584 NOTICE: running thread: CDVDPlayerAudio::Process() 20:31:38 T:3932 M:1766727680 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(447988.888889, 1) 20:31:38 T:3932 M:1766735872 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:38 T:3932 M:1766707200 DEBUG: CDVDAudioCodecPassthrough::ParseFrame - source flags changed flags:17 sr:48000 br:384000 20:31:38 T:3932 M:1766715392 NOTICE: Creating audio device with codec id: 86019, channels: 2, sample rate: 48000, pass-through 20:31:38 T:3916 M:1766711296 NOTICE: Opening video stream: 0 source: 256 20:31:38 T:3916 M:1766711296 INFO: CDVDInputStreamNavigator::GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 20:31:38 T:3916 M:1766707200 NOTICE: Creating video codec with codec id: 2 20:31:38 T:3916 M:1766707200 DEBUG: FactoryCodec - Video: libmpeg2 - Opening 20:31:38 T:3916 M:1766703104 DEBUG: FactoryCodec - Video: libmpeg2 - Opened 20:31:38 T:3916 M:1766703104 NOTICE: Creating video thread 20:31:38 T:3940 M:1766338560 DEBUG: thread start, auto delete: 0 20:31:38 T:3940 M:1766326272 NOTICE: running thread: video_thread 20:31:38 T:3940 M:1766326272 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_DELAY(19155.555556) 20:31:38 T:3932 M:1766301696 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Primary Sound Driver 20:31:38 T:3932 M:1766297600 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Turtle Beach Riviera Wave 20:31:38 T:3932 M:1766297600 DEBUG: CAudioContext::SetActiveDevice - selecting Turtle Beach Riviera Wave as output devices 20:31:38 T:3916 M:1766281216 NOTICE: Opening Subtitle stream: 7 source: 256 20:31:38 T:3916 M:1766268928 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:38 T:3932 M:1765969920 DEBUG: CWin32DirectSound::Initialize: Packet Size = 12384. Avg Bytes Per Second = 192000. 20:31:38 T:3932 M:1765969920 DEBUG: CWin32DirectSound::Initialize: Couldn't create secondary buffer (DSERR_UNSUPPORTED). Trying without LOCHARDWARE. 20:31:38 T:3932 M:1765965824 DEBUG: CWin32DirectSound::Initialize: Couldn't create secondary buffer (DSERR_INVALIDPARAM). Trying without CTRLVOLUME. 20:31:38 T:3932 M:1765900288 ERROR: CWin32DirectSound::Initialize: cannot create secondary buffer (DSERR_INVALIDPARAM) 20:31:38 T:3932 M:1765904384 ERROR: Creating a Null Audio Renderer, Check your audio settings as this should not happen 20:31:38 T:3940 M:1765867520 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(467144.444444, 0) 20:31:38 T:3932 M:1764311040 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Primary Sound Driver 20:31:38 T:3932 M:1764360192 DEBUG: CWDSound::direct_sound_enumerator_member_callback - found Device: Turtle Beach Riviera Wave 20:31:38 T:3932 M:1764360192 DEBUG: CAudioContext::SetActiveDevice - selecting Turtle Beach Riviera Wave as output devices 20:31:38 T:3932 M:1764327424 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:38 T:3932 M:1763684352 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:38 T:3548 M:1763446784 DEBUG: Alloc resources: 0.01ms (0.00 ms skin load, 0.01 ms preload) 20:31:38 T:3916 M:1763180544 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:39 T:3940 M:1762803712 DEBUG: CDVDVideoCodecLibMpeg2::m_bFilm = true 20:31:39 T:3916 M:1761693696 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:39 T:3932 M:1761320960 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:39 T:3916 M:1761333248 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:39 T:3932 M:1760616448 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:39 T:3932 M:1760657408 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:39 T:3932 M:1760571392 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:40 T:3916 M:1760587776 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:40 T:3932 M:1760509952 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:40 T:3932 M:1760337920 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:41 T:3932 M:1760346112 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:42 T:3932 M:1760108544 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:42 T:3932 M:1760481280 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:42 T:3916 M:1760301056 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:43 T:3932 M:1760387072 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:44 T:3548 M:1760268288 DEBUG: SDLKeyboard: scancode: 0, sym: 8, unicode: 8, modifier: 0 20:31:44 T:3548 M:1760268288 DEBUG: CApplication::OnKey: 61448 pressed, action is 51 20:31:44 T:3916 M:1760022528 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:44 T:3932 M:1760104448 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:45 T:3932 M:1760358400 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:45 T:3548 M:1760198656 DEBUG: SDLKeyboard: scancode: 0, sym: 8, unicode: 8, modifier: 0 20:31:45 T:3548 M:1760198656 DEBUG: CApplication::OnKey: 61448 pressed, action is 51 20:31:45 T:3932 M:1760178176 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:45 T:3932 M:1760210944 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:46 T:3932 M:1760329728 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:47 T:3932 M:1760112640 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:47 T:3916 M:1760620544 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:47 T:3932 M:1760620544 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:47 T:3932 M:1760411648 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:47 T:3548 M:1760411648 DEBUG: SDLKeyboard: scancode: 0, sym: 308, unicode: 0, modifier: 0 20:31:48 T:3548 M:1760411648 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 100 20:31:48 T:3548 M:1760411648 DEBUG: CApplication::OnKey: 61453 pressed, action is 24 20:31:48 T:3548 M:1760149504 DEBUG: Alloc resources: 23.71ms (0.00 ms skin load, 0.36 ms preload) 20:31:48 T:3932 M:1760149504 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:48 T:3932 M:1760075776 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:49 T:3932 M:1760010240 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:49 T:3548 M:1760292864 DEBUG: SDLKeyboard: scancode: 0, sym: 276, unicode: 0, modifier: 0 20:31:49 T:3548 M:1760317440 DEBUG: CApplication::OnKey: 61477 pressed, action is 1 20:31:50 T:3932 M:1760256000 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:50 T:3932 M:1760268288 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:50 T:3932 M:1760194560 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:50 T:3548 M:1760034816 DEBUG: SDLKeyboard: scancode: 0, sym: 276, unicode: 0, modifier: 0 20:31:50 T:3548 M:1760034816 DEBUG: CApplication::OnKey: 61477 pressed, action is 1 20:31:50 T:3916 M:1760034816 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:51 T:3548 M:1760358400 DEBUG: SDLKeyboard: scancode: 0, sym: 276, unicode: 0, modifier: 0 20:31:51 T:3548 M:1760354304 DEBUG: CApplication::OnKey: 61477 pressed, action is 1 20:31:52 T:3932 M:1759895552 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:52 T:3932 M:1760219136 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:52 T:3548 M:1760219136 DEBUG: SDLKeyboard: scancode: 0, sym: 276, unicode: 0, modifier: 0 20:31:52 T:3548 M:1760219136 DEBUG: CApplication::OnKey: 61477 pressed, action is 1 20:31:52 T:3932 M:1760223232 WARNING: CDVDMessageQueue(audio)::Get - retrieved last data packet of queue 20:31:53 T:3548 M:1759961088 DEBUG: SDLKeyboard: scancode: 0, sym: 276, unicode: 0, modifier: 0 20:31:53 T:3548 M:1759961088 DEBUG: CApplication::OnKey: 61477 pressed, action is 1 20:31:54 T:3916 M:1760235520 DEBUG: CDVDPlayer::ProcessSubData: Got complete SPU packet 20:31:54 T:3932 M:1760010240 DEBUG: CDVDPlayerAudio:: Discontinuty - was:15896988.888889, should be:15886988.797336, error:-10000.091553 20:31:54 T:3548 M:1760010240 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:31:54 T:3548 M:1760006144 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:31:54 T:3548 M:1760006144 DEBUG: CApplication::OnMessage : Translating XBMC.PlayerControl(Stop) 20:31:54 T:3548 M:1760006144 DEBUG: CApplication::OnMessage : To XBMC.PlayerControl(Stop) 20:31:54 T:3548 M:1759776768 NOTICE: CDVDPlayer::CloseFile() 20:31:54 T:3548 M:1759776768 NOTICE: DVDPlayer: waiting for threads to exit 20:31:54 T:3916 M:1759776768 NOTICE: CDVDPlayer::OnExit() 20:31:54 T:3916 M:1759776768 NOTICE: DVDPlayer: closing audio stream 20:31:54 T:3916 M:1759776768 NOTICE: Closing audio stream 20:31:54 T:3916 M:1759776768 NOTICE: Waiting for audio thread to exit 20:31:54 T:3932 M:1759776768 DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread 20:31:54 T:3932 M:1759776768 NOTICE: thread end: CDVDPlayerAudio::OnExit() 20:31:54 T:3932 M:1759776768 DEBUG: Thread 3932 terminating 20:31:54 T:3916 M:1759821824 NOTICE: Closing audio device 20:31:54 T:3916 M:1759825920 NOTICE: Deleting audio codec 20:31:54 T:3916 M:1759825920 NOTICE: DVDPlayer: closing video stream 20:31:54 T:3916 M:1759825920 NOTICE: Closing video stream 20:31:54 T:3916 M:1759825920 NOTICE: waiting for video thread to exit 20:31:54 T:3940 M:1759825920 NOTICE: thread end: video_thread 20:31:54 T:3940 M:1759825920 DEBUG: Thread 3940 terminating 20:31:54 T:3916 M:1764114432 NOTICE: deleting video codec 20:31:54 T:3916 M:1766199296 NOTICE: DVDPlayer: closing video stream 20:31:54 T:3916 M:1766195200 NOTICE: Closing subtitle stream 20:31:54 T:3916 M:1766416384 NOTICE: CDVDPlayer::OnExit() deleting demuxer 20:31:54 T:3916 M:1766477824 NOTICE: CDVDPlayer::OnExit() deleting input stream 20:31:54 T:3916 M:1768071168 DEBUG: Playback was stopped 20:31:54 T:3916 M:1768071168 DEBUG: Thread 3916 terminating 20:31:54 T:3548 M:1768140800 NOTICE: DVDPlayer: finished waiting 20:31:54 T:3548 M:1768148992 NOTICE: CDVDPlayer::CloseFile() 20:31:54 T:3548 M:1768148992 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:31:54 T:3548 M:1768148992 NOTICE: DVDPlayer: waiting for threads to exit 20:31:54 T:3548 M:1768148992 NOTICE: DVDPlayer: finished waiting 20:31:54 T:3548 M:1768288256 NOTICE: CDVDPlayerVideo::~CDVDPlayerVideo - uninitting video device 20:31:54 T:3548 M:1768296448 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:31:54 T:3548 M:1770962944 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:31:54 T:3548 M:1770962944 INFO: Loading skin file: MyVideoNav.xml 20:31:55 T:3548 M:1767755776 DEBUG: Load MyVideoNav.xml: 1090.72ms 20:31:55 T:3548 M:1767673856 DEBUG: Alloc resources: 1153.00ms (1090.87 ms skin load, 2.78 ms preload) 20:31:55 T:3548 M:1767673856 DEBUG: CGUIMediaWindow::GetDirectory (videodb://1/2/) 20:31:55 T:3548 M:1767673856 DEBUG: ParentPath = [videodb://1/2/] 20:31:55 T:3548 M:1767673856 DEBUG: Loading fileitems [videodb://1/2/] 20:31:55 T:3548 M:1767419904 DEBUG: -- items: 144, directory: videodb://1/2/ sort method: 2, ascending: true 20:31:55 T:3548 M:1767358464 DEBUG: CFileItemList::Sort, sorting took 0 millis 20:31:55 T:3548 M:1767424000 DEBUG: Saving fileitems [videodb://1/2/] 20:31:55 T:3548 M:1767424000 DEBUG: -- items: 144, sort method: 2, ascending: true 20:31:55 T:3980 M:1767411712 DEBUG: thread start, auto delete: 0 20:31:55 T:3984 M:1767399424 DEBUG: thread start, auto delete: 0 20:31:55 T:3988 M:1767387136 DEBUG: thread start, auto delete: 0 20:31:55 T:3992 M:1767362560 DEBUG: thread start, auto delete: 0 20:31:55 T:3996 M:1767346176 DEBUG: thread start, auto delete: 0 20:31:56 T:4000 M:1768300544 DEBUG: thread start, auto delete: 0 20:31:56 T:4000 M:1768226816 DEBUG: Win32DllLoader::Load(special://xbmc/system/ImageLib.dll) 20:31:56 T:4000 M:1767346176 DEBUG: LoadLibraryA('MSVCR71.dll') 20:31:56 T:4000 M:1767346176 DEBUG: LoadLibrary('MSVCR71.dll') returning: 04A990E0 20:31:56 T:4000 M:1767346176 DEBUG: LoadLibraryA('MSVCP71.dll') 20:31:56 T:4000 M:1766219776 DEBUG: Win32DllLoader::Load(MSVCP71.dll) 20:31:56 T:4000 M:1766047744 DEBUG: LoadLibraryA('MSVCR71.dll') 20:31:56 T:4000 M:1765986304 DEBUG: LoadLibrary('MSVCR71.dll') returning: 04A990E0 20:31:56 T:4000 M:1765810176 DEBUG: LoadLibrary('MSVCP71.dll') returning: 04AC8A90 20:31:56 T:3980 M:1759039488 DEBUG: Thread 3980 terminating 20:31:56 T:3996 M:1757720576 DEBUG: Thread 3996 terminating 20:31:56 T:3984 M:1757589504 DEBUG: Thread 3984 terminating 20:31:56 T:3988 M:1757556736 DEBUG: Thread 3988 terminating 20:31:56 T:3992 M:1756418048 DEBUG: Thread 3992 terminating 20:31:56 T:4000 M:1755152384 DEBUG: PICTURE: loaded image and created texture. height: 720, width: 1280 20:31:56 T:4000 M:1757667328 DEBUG: Thread 4000 terminating 20:31:57 T:3548 M:1759416320 DEBUG: SDLKeyboard: scancode: 0, sym: 8, unicode: 8, modifier: 0 20:31:57 T:3548 M:1759416320 DEBUG: CApplication::OnKey: 61448 pressed, action is 9 20:31:57 T:3548 M:1759399936 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:31:58 T:3548 M:1767694336 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:31:58 T:3548 M:1767694336 INFO: Loading skin file: Home.xml 20:31:58 T:3548 M:1767641088 DEBUG: Load Home.xml: 82.81ms 20:31:58 T:3548 M:1767641088 DEBUG: Alloc resources: 83.60ms (83.31 ms skin load, 0.18 ms preload) 20:32:01 T:3548 M:1760129024 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:32:01 T:3548 M:1760129024 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:32:01 T:3548 M:1755193344 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:32:01 T:3548 M:1755193344 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:32:02 T:3548 M:1752068096 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:32:02 T:3548 M:1752068096 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:32:03 T:3548 M:1747099648 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:32:03 T:3548 M:1747099648 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:32:03 T:3548 M:1744113664 DEBUG: SDLKeyboard: scancode: 0, sym: 274, unicode: 0, modifier: 0 20:32:03 T:3548 M:1744113664 DEBUG: CApplication::OnKey: 61480 pressed, action is 4 20:32:05 T:3548 M:1739730944 DEBUG: SDLKeyboard: scancode: 0, sym: 275, unicode: 0, modifier: 0 20:32:05 T:3548 M:1739730944 DEBUG: CApplication::OnKey: 61479 pressed, action is 2 20:32:06 T:3548 M:1740201984 DEBUG: SDLKeyboard: scancode: 0, sym: 13, unicode: 13, modifier: 0 20:32:06 T:3548 M:1740201984 DEBUG: CApplication::OnKey: 61453 pressed, action is 7 20:32:06 T:3548 M:1740189696 DEBUG: CApplication::OnMessage : Translating XBMC.Shutdown() 20:32:06 T:3548 M:1740189696 DEBUG: CApplication::OnMessage : To XBMC.Shutdown() 20:32:06 T:3548 M:1740201984 NOTICE: Storing total System Uptime 20:32:06 T:3548 M:1740201984 NOTICE: Saving settings 20:32:06 T:3548 M:1740144640 NOTICE: stop all 20:32:06 T:3548 M:1740144640 DEBUG: CNetwork::NetworkMessage - Stopping network services 20:32:06 T:3548 M:1740144640 NOTICE: Webserver: Stopping... 20:32:06 T:3656 M:1740144640 DEBUG: WebServer:Exiting thread sockReady=0, sockSelect=0. 20:32:06 T:3656 M:1740144640 DEBUG: WebServer:OnExit - Exit web server. 20:32:06 T:3656 M:1740156928 DEBUG: Thread 3656 terminating 20:32:06 T:3548 M:1740169216 DEBUG: xbmcHttpShim ends 20:32:06 T:3548 M:1740169216 DEBUG: xbmcHttp ends 20:32:06 T:3548 M:1740169216 NOTICE: Webserver: Stopped... 20:32:06 T:3548 M:1740169216 NOTICE: ES: Stopping event server 20:32:07 T:3664 M:1741119488 NOTICE: ES: UDP Event server stopped 20:32:07 T:3664 M:1741119488 DEBUG: Thread 3664 terminating 20:32:07 T:3548 M:1741139968 NOTICE: stop dvd detect media 20:32:07 T:3604 M:1741139968 DEBUG: Thread 3604 terminating 20:32:07 T:3548 M:1741164544 NOTICE: stop sap announcement listener 20:32:07 T:3548 M:1741164544 NOTICE: clean cached files! 20:32:07 T:3548 M:1741160448 NOTICE: unload skin 20:32:07 T:3548 M:1773142016 NOTICE: stop python 20:32:07 T:3548 M:1773142016 NOTICE: stopped 20:32:07 T:3548 M:1773142016 NOTICE: destroy 20:32:07 T:3548 M:1773146112 NOTICE: unload sections 20:32:07 T:3548 M:1773146112 DEBUG: SECTION:UnloadAll(SECTION: LIBHTTP) 20:32:07 T:3548 M:1773146112 DEBUG: SECTION:UnloadAll(SECTION: LCODE) 20:32:07 T:3548 M:1773146112 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/libcurl.dll) 20:32:07 T:3548 M:1773146112 DEBUG: Win32DllLoader::Unload libcurl.dll 20:32:07 T:3548 M:1773146112 DEBUG: FreeLibrary(MSVCR71.dll) -> 04A990E0 20:32:07 T:3548 M:1773711360 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/libdvdnav.dll) 20:32:07 T:3548 M:1773711360 DEBUG: Win32DllLoader::Unload libdvdnav.dll 20:32:07 T:3548 M:1773715456 DEBUG: FreeLibrary(libdvdcss-2.dll) -> 04DCEA68 20:32:07 T:3548 M:1773711360 DEBUG: Win32DllLoader::Unload libdvdcss-2.dll 20:32:07 T:3548 M:1773948928 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/avutil-49.dll) 20:32:07 T:3548 M:1773948928 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/avcodec-52.dll) 20:32:07 T:3548 M:1773948928 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/avformat-52.dll) 20:32:07 T:3548 M:1773948928 DEBUG: Win32DllLoader::Unload avformat-52.dll 20:32:07 T:3548 M:1773953024 DEBUG: FreeLibrary(avutil-49.dll) -> 04CA7918 20:32:07 T:3548 M:1773948928 DEBUG: FreeLibrary(avcodec-52.dll) -> 04CB5640 20:32:07 T:3548 M:1773948928 DEBUG: Win32DllLoader::Unload avcodec-52.dll 20:32:07 T:3548 M:1773948928 DEBUG: FreeLibrary(avutil-49.dll) -> 04CA7918 20:32:07 T:3548 M:1774837760 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/liba52.dll) 20:32:07 T:3548 M:1774837760 DEBUG: Win32DllLoader::Unload liba52.dll 20:32:07 T:3548 M:1774837760 DEBUG: FreeLibrary(MSVCR71.dll) -> 04A990E0 20:32:07 T:3548 M:1774907392 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/libmpeg2.dll) 20:32:07 T:3548 M:1774907392 DEBUG: Win32DllLoader::Unload libmpeg2.dll 20:32:07 T:3548 M:1775001600 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/players/dvdplayer/swscale-0.6.1.dll) 20:32:07 T:3548 M:1775001600 DEBUG: Win32DllLoader::Unload swscale-0.6.1.dll 20:32:07 T:3548 M:1775001600 DEBUG: FreeLibrary(avutil-49.dll) -> 04CA7918 20:32:07 T:3548 M:1775001600 DEBUG: Win32DllLoader::Unload avutil-49.dll 20:32:07 T:3548 M:1775099904 DEBUG: SECTION:UnloadAll(DLL: special://xbmc/system/ImageLib.dll) 20:32:07 T:3548 M:1775099904 DEBUG: Win32DllLoader::Unload ImageLib.dll 20:32:07 T:3548 M:1775099904 DEBUG: FreeLibrary(MSVCR71.dll) -> 04A990E0 20:32:07 T:3548 M:1775099904 DEBUG: FreeLibrary(MSVCP71.dll) -> 04AC8A90 20:32:07 T:3548 M:1775099904 DEBUG: Win32DllLoader::Unload MSVCP71.dll 20:32:07 T:3548 M:1775099904 DEBUG: FreeLibrary(MSVCR71.dll) -> 04A990E0 20:32:07 T:3548 M:1775099904 DEBUG: Win32DllLoader::Unload MSVCR71.dll 20:32:07 T:3668 M:1777463296 DEBUG: Audioscrobbler worker thread stopping 20:32:07 T:3668 M:1777451008 DEBUG: Thread 3668 terminating 20:32:07 T:3548 M:1777475584 INFO: lastfm destroyed