20:04:13 T:140679204898752 M:3059953664 NOTICE: ----------------------------------------------------------------------- 20:04:13 T:140679204898752 M:3059802112 NOTICE: Starting XBMC, Platform: Linux (Gentoo Base System release 2.0.1, 2.6.35-gentoo x86_64). Built on Sep 12 2010 (SVN:33681) 20:04:13 T:140679204898752 M:3059802112 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc 20:04:13 T:140679204898752 M:3059802112 NOTICE: special://xbmcbin/ is mapped to: /usr/lib64/xbmc 20:04:13 T:140679204898752 M:3059802112 NOTICE: special://masterprofile/ is mapped to: /home/freevo/.xbmc/userdata 20:04:13 T:140679204898752 M:3059802112 NOTICE: special://home/ is mapped to: /home/freevo/.xbmc 20:04:13 T:140679204898752 M:3059802112 NOTICE: special://temp/ is mapped to: /home/freevo/.xbmc/temp 20:04:13 T:140679204898752 M:3059802112 NOTICE: The executable running is: /usr/lib64/xbmc/xbmc.bin 20:04:13 T:140679204898752 M:3059802112 NOTICE: Log File is located: /home/freevo/.xbmc/temp/xbmc.log 20:04:13 T:140679204898752 M:3059802112 NOTICE: ----------------------------------------------------------------------- 20:04:13 T:140679204898752 M:3059802112 NOTICE: Setup SDL 20:05:05 T:140679204898752 M:3057844224 NOTICE: load settings... 20:05:07 T:140679204898752 M:2988429312 NOTICE: special://profile/ is mapped to: special://masterprofile/ 20:05:07 T:140679204898752 M:2988429312 NOTICE: loading special://masterprofile/guisettings.xml 20:05:07 T:140679204898752 M:2987794432 NOTICE: Getting hardware information now... 20:05:07 T:140679204898752 M:2987794432 NOTICE: Checking resolution 12 20:05:07 T:140679204898752 M:2987794432 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 20:05:07 T:140679204898752 M:2987667456 NOTICE: Loaded playercorefactory configuration 20:05:07 T:140679204898752 M:2987667456 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 20:05:07 T:140679204898752 M:2987667456 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 20:05:07 T:140679204898752 M:2987819008 NOTICE: Loaded advancedsettings.xml from special://profile/advancedsettings.xml 20:05:07 T:140679204898752 M:2987819008 NOTICE: Contents of special://profile/advancedsettings.xml are... false true false 0 false 0 false false 1 .tod|.TOD true false true --> true false 200 / true false sed -e 's/\([0-9]*\)[0-9]\{3\}.*/\1 C/' /sys/class/thermal/thermal_zone0/temp 20:05:07 T:140679204898752 M:2987819008 NOTICE: Getting hardware information now... 20:05:07 T:140679204898752 M:2987819008 NOTICE: Checking resolution 12 20:05:07 T:140679204898752 M:2987819008 NOTICE: Default DVD Player: dvdplayer 20:05:07 T:140679204898752 M:2987819008 NOTICE: Default Video Player: mplayer 20:05:07 T:140679204898752 M:2987819008 NOTICE: Default Audio Player: mplayer 20:05:07 T:140679204898752 M:2987819008 NOTICE: Loading media sources from special://masterprofile/sources.xml 20:05:10 T:140679204898752 M:2984869888 NOTICE: Using visual 0x22 20:05:10 T:140679204898752 M:2982010880 NOTICE: GL_VENDOR = Tungsten Graphics, Inc 20:05:10 T:140679204898752 M:2982010880 NOTICE: GL_RENDERER = Mesa DRI Intel(R) 945GM GEM 20100328 2010Q1 20:05:10 T:140679204898752 M:2982010880 NOTICE: GL_VERSION = 1.4 Mesa 7.8.2 20:05:10 T:140679204898752 M:2982010880 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.20 20:05:10 T:140679204898752 M:2982010880 NOTICE: GL_EXTENSIONS = GL_ARB_copy_buffer GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_elements_base_vertex GL_ARB_fragment_program GL_ARB_half_float_pixel GL_ARB_map_buffer_range GL_ARB_multisample GL_ARB_multitexture GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_provoking_vertex GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_shading_language_120 GL_ARB_shadow GL_ARB_sync GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_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_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_logic_op GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_cull_vertex GL_EXT_compiled_vertex_array GL_EXT_copy_texture GL_EXT_draw_range_elements GL_EXT_framebuffer_blit GL_EXT_framebuffer_object GL_EXT_fog_coord 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_polygon_offset GL_EXT_provoking_vertex 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_subtexture GL_EXT_texture GL_EXT_texture3D GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_add GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod_bias GL_EXT_texture_object GL_EXT_texture_rectangle GL_EXT_vertex_array GL_3DFX_texture_compression_FXT1 GL_APPLE_client_storage GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_APPLE_object_purgeable GL_ATI_blend_equation_separate GL_ATI_texture_env_combine3 GL_ATI_separate_stencil GL_IBM_multimode_draw_arrays GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_INGR_blend_func_separate GL_MESA_pack_invert GL_MESA_ycbcr_texture GL_MESA_window_pos GL_NV_blend_square GL_NV_light_max_exponent GL_NV_packed_depth_stencil GL_NV_texture_env_combine4 GL_NV_texture_rectangle GL_NV_texgen_reflection GL_NV_vertex_program GL_NV_vertex_program1_1 GL_OES_read_format GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_SUN_multi_draw_arrays 20:05:10 T:140679204898752 M:2982010880 ERROR: GLX: Same window as before, refreshing context 20:05:10 T:140679204898752 M:2979442688 NOTICE: start dvd mediatype detection 20:05:10 T:140679204898752 M:2979442688 NOTICE: initializing playlistplayer 20:05:10 T:140679204898752 M:2979442688 NOTICE: DONE initializing playlistplayer 20:05:12 T:140679204898752 M:2971250688 NOTICE: initialize done 20:05:12 T:140679204898752 M:2971250688 NOTICE: Running the application... 20:05:12 T:140679204898752 M:2971250688 NOTICE: starting upnp server 20:05:12 T:140679204898752 M:2971553792 NOTICE: ES: Starting event server 20:05:12 T:140679204898752 M:2971410432 NOTICE: DS: Starting dbus server 20:05:12 T:140678717576976 M:2971410432 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777 20:05:12 T:140678717576976 M:2971410432 NOTICE: UDP: Listening on port 9777 20:05:12 T:140679204898752 M:2970337280 ERROR: DS: Failed to connect to the D-Bus session daemon: /usr/bin/dbus-launch terminated abnormally with the following error: Failed to waitpid() for babysitter intermediate process: No child processes 20:05:12 T:140679204898752 M:2970464256 NOTICE: starting zeroconf publishing 20:05:16 T:140678660122384 M:2964475904 NOTICE: -->Python Interpreter Initialized<-- 20:05:21 T:140679204898752 M:2960277504 ERROR: Window Translator: Can't find window scriptsdebuginfo 20:05:21 T:140679204898752 M:2960277504 ERROR: Window Translator: Can't find window pluginsettings 20:05:21 T:140679204898752 M:2960277504 ERROR: Error evaluating boolean expression Window.IsActive(MovieInformation) | Window.IsActive(MusicInformation) | Window.IsActive(SongInformation) | Window.IsActive(FileBrowser) | Window.IsActive(ScriptsDebugInfo) | Window.IsActive(PluginSettings) | Window.IsActive(ContentSettings) | Window.IsActive(SelectDialog) | Window.IsActive(FileStackingDialog) | Window.IsActive(MediaSource) | Window.IsActive(PictureInfo) | Window.IsActive(PlayerControls) | Window.IsActive(VirtualKeyboard) | Window.IsActive(NumericInput) | Window.IsActive(ProfileSettings) | Window.IsActive(LockSettings) | Window.IsActive(SmartPlaylistEditor) | Window.IsActive(SmartPlaylistRule) | Window.IsActive(script-Apple_Movie_Trailers-settings.xml) | Window.IsActive(script-Apple_Movie_Trailers-chooser.xml) | Window.IsActive(script-Apple_Movie_Trailers-search.xml) | Window.IsActive(script-Apple_Movie_Trailers-showtimes.xml) | Window.IsActive(script-XBMC_Lyrics-settings.xml) 20:05:31 T:140679204898752 M:2890100736 WARNING: CPlayerCoreFactory::GetPlayerCore(videodefaultplayer): no such core: mplayer 20:05:31 T:140679204898752 M:2887647232 NOTICE: DVDPlayer: Opening: /mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv 20:05:31 T:140679204898752 M:2887647232 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:05:31 T:140678446335760 M:2887647232 NOTICE: Creating InputStream 20:05:32 T:140678446335760 M:2881679360 NOTICE: Creating Demuxer 20:12:14 T:140678660122384 M:776118272 WARNING: could not find codec parameters for /mnt/ssh.drive.0/movies/M-N-O/Operation.Endgame.2010.720p.bluray.x264.dts-wiki.mkv 20:12:16 T:140678660122384 M:2927067136 ERROR: Open - Error, could not open file /mnt/ssh.drive.0/movies/0-9/300.2006.1080p.BluRay.DTS.x264-CtrlHD.mkv 20:12:16 T:140678446335760 M:2923261952 NOTICE: Opening video stream: 0 source: 256 20:12:16 T:140678446335760 M:2923261952 NOTICE: Creating video codec with codec id: 28 20:12:16 T:140678446335760 M:2923335680 NOTICE: Creating video thread 20:12:16 T:140678446335760 M:2923335680 NOTICE: Opening audio stream: 1 source: 256 20:12:16 T:140678446335760 M:2923335680 NOTICE: Finding audio codec for: 86019 20:12:16 T:140678446335760 M:2923343872 NOTICE: Creating audio thread 20:12:16 T:140678446335760 M:2923343872 NOTICE: Opening Subtitle stream: 2 source: 256 20:12:16 T:140678636623632 M:2923343872 NOTICE: running thread: CDVDPlayerAudio::Process() 20:12:16 T:140678636623632 M:2923491328 NOTICE: Creating audio device with codec id: 86019, channels: 6, sample rate: 48000, no pass-through 20:12:17 T:140678499661584 M:2920521728 NOTICE: running thread: video_thread 20:12:18 T:140678499661584 M:2918195200 NOTICE: fps: 23.976025, pwidth: 1280, pheight: 544, dwidth: 1280, dheight: 544 20:12:18 T:140678499661584 M:2918195200 NOTICE: Display resolution DESKTOP : 1920x1080 (12) 20:12:18 T:140679204898752 M:2913443840 NOTICE: Using GL_TEXTURE_2D 20:12:18 T:140679204898752 M:2913443840 NOTICE: GL: ARB shaders support detected 20:12:18 T:140679204898752 M:2910162944 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:12:18 T:140679204898752 M:2910162944 NOTICE: GL: No vertex shader, fixed pipeline in use 20:12:18 T:140679204898752 M:2907766784 NOTICE: GL: NPOT texture support detected 20:12:18 T:140679204898752 M:2907766784 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:12:18 T:140679204898752 M:2886361088 NOTICE: GL: ARB shaders support detected 20:12:18 T:140679204898752 M:2885599232 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:12:18 T:140679204898752 M:2885599232 NOTICE: GL: No vertex shader, fixed pipeline in use 20:12:18 T:140679204898752 M:2885599232 NOTICE: GL: NPOT texture support detected 20:12:18 T:140679204898752 M:2885599232 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:14:09 T:140679204898752 M:2856792064 NOTICE: CDVDPlayer::CloseFile() 20:14:09 T:140679204898752 M:2856792064 NOTICE: DVDPlayer: waiting for threads to exit 20:14:09 T:140678446335760 M:2856792064 NOTICE: CDVDPlayer::OnExit() 20:14:09 T:140678446335760 M:2856792064 NOTICE: DVDPlayer: closing audio stream 20:14:09 T:140678446335760 M:2856792064 NOTICE: Closing audio stream 20:14:09 T:140678446335760 M:2856792064 NOTICE: Waiting for audio thread to exit 20:14:09 T:140678636623632 M:2856792064 ERROR: AddPackets - failed to add leftover bytes to render 20:14:09 T:140678636623632 M:2856792064 NOTICE: thread end: CDVDPlayerAudio::OnExit() 20:14:09 T:140678446335760 M:2856792064 NOTICE: Closing audio device 20:14:09 T:140678446335760 M:2856792064 NOTICE: Deleting audio codec 20:14:09 T:140678446335760 M:2856792064 NOTICE: DVDPlayer: closing video stream 20:14:09 T:140678446335760 M:2856792064 NOTICE: Closing video stream 20:14:09 T:140678446335760 M:2856792064 NOTICE: waiting for video thread to exit 20:14:09 T:140678499661584 M:2856792064 NOTICE: thread end: video_thread 20:14:09 T:140678446335760 M:2856792064 NOTICE: deleting video codec 20:14:09 T:140678446335760 M:2913042432 NOTICE: DVDPlayer: closing subtitle stream 20:14:09 T:140678446335760 M:2913042432 NOTICE: Closing subtitle stream 20:14:09 T:140678446335760 M:2913042432 NOTICE: CDVDPlayer::OnExit() deleting demuxer 20:14:09 T:140678446335760 M:2913042432 NOTICE: CDVDPlayer::OnExit() deleting input stream 20:14:09 T:140679204898752 M:2913193984 NOTICE: DVDPlayer: finished waiting 20:14:11 T:140679204898752 M:2905808896 NOTICE: CDVDPlayer::CloseFile() 20:14:11 T:140679204898752 M:2905808896 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:14:11 T:140679204898752 M:2905808896 NOTICE: DVDPlayer: waiting for threads to exit 20:14:11 T:140679204898752 M:2905808896 NOTICE: DVDPlayer: finished waiting 20:14:12 T:140678587488016 M:2895708160 NOTICE: -->Python Interpreter Initialized<-- 20:14:33 T:140679204898752 M:2896437248 WARNING: Failed to find previously selected audio sink 20:14:35 T:140679204898752 M:2896621568 WARNING: Previous line repeats 1 times. 20:14:35 T:140679204898752 M:2896621568 NOTICE: Enabled debug logging due to GUI setting. Level 2. 20:14:38 T:140679204898752 M:2896646144 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 20:14:38 T:140679204898752 M:2896646144 DEBUG: OnKey: 61480 pressed, action is Down 20:14:41 T:140679204898752 M:2896707584 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0 20:14:41 T:140679204898752 M:2896707584 DEBUG: OnKey: 61467 pressed, action is PreviousMenu 20:14:41 T:140679204898752 M:2896707584 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:14:41 T:140679204898752 M:2896707584 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 20:14:41 T:140679204898752 M:2896707584 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:14:41 T:140679204898752 M:2896707584 DEBUG: ------ Window Init (Settings.xml) ------ 20:14:41 T:140679204898752 M:2896707584 INFO: Loading skin file: Settings.xml 20:14:42 T:140679204898752 M:2896003072 DEBUG: SDLKeyboard: scancode: 9, sym: 27, unicode: 27, modifier: 0 20:14:42 T:140679204898752 M:2896003072 DEBUG: OnKey: 61467 pressed, action is PreviousMenu 20:14:42 T:140679204898752 M:2896003072 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:14:42 T:140679204898752 M:2896003072 DEBUG: ------ Window Deinit (Settings.xml) ------ 20:14:43 T:140679204898752 M:2896105472 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:14:43 T:140679204898752 M:2896105472 DEBUG: ------ Window Init (Home.xml) ------ 20:14:43 T:140679204898752 M:2896105472 INFO: Loading skin file: Home.xml 20:14:43 T:140679204898752 M:2895978496 DEBUG: ExecuteXBMCAction : Translating XBMC.RunScript(special://skin/scripts/RecentlyAdded.py,limit=4) 20:14:43 T:140679204898752 M:2895978496 DEBUG: ExecuteXBMCAction : To XBMC.RunScript(special://skin/scripts/RecentlyAdded.py,limit=4) 20:14:43 T:140679204898752 M:2895978496 INFO: initializing python engine. 20:14:43 T:140679204898752 M:2895978496 DEBUG: Loading: /usr/lib64/xbmc/system/python/python26-x86_64-linux.so 20:14:43 T:140679204898752 M:2895851520 DEBUG: Python wrapper library linked with system Python library 20:14:43 T:140679204898752 M:2895978496 DEBUG: new python thread created. id=3 20:14:43 T:140678601766672 M:2895978496 DEBUG: thread start, auto delete: 0 20:14:43 T:140678601766672 M:2895978496 DEBUG: Python thread: start processing 20:14:43 T:140679204898752 M:2895978496 DEBUG: ExecuteXBMCAction : Translating SetFocus(9000) 20:14:43 T:140679204898752 M:2895978496 DEBUG: ExecuteXBMCAction : To SetFocus(9000) 20:14:43 T:140678601766672 M:2895978496 NOTICE: -->Python Interpreter Initialized<-- 20:14:43 T:140678601766672 M:2895978496 DEBUG: Process - The source file to load is special://skin/scripts/RecentlyAdded.py 20:14:43 T:140678601766672 M:2895978496 DEBUG: Process - Setting the Python path to /home/freevo/.xbmc/addons/skin.mediastream_redux/scripts:/home/freevo/.xbmc/addons/script.module.simplejson/lib:/home/freevo/.xbmc/addons/script.module.beautifulsoup/lib:/usr/lib64/xbmc/addons/script.module.pil/lib:/usr/lib64/xbmc/addons/script.module.pysqlite/lib:/usr/lib64/python26.zip:/usr/lib64/python2.6/:/usr/lib64/python2.6/plat-linux2:/usr/lib64/python2.6/lib-tk:/usr/lib64/python2.6/lib-old:/usr/lib64/python2.6/lib-dynload 20:14:43 T:140678601766672 M:2895978496 DEBUG: Process - Entering source directory /home/freevo/.xbmc/addons/skin.mediastream_redux/scripts 20:14:43 T:140679204898752 M:2895978496 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat 20:14:43 T:140679204898752 M:2895978496 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; OpenRecord; 20:14:43 T:140679204898752 M:2895978496 DEBUG: HttpApi Start command: SetResponseFormat paras: SetResponseFormat; CloseRecord; 20:14:43 T:140679204898752 M:2895978496 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from movieview order by idMovie desc limit 4 20:14:43 T:140679204898752 M:2896150528 DEBUG: HttpApi Start command: QueryVideoDatabase paras: QueryVideoDatabase; select * from episodeview order by idepisode desc limit 4 20:14:43 T:140679204898752 M:2896150528 DEBUG: HttpApi Start command: QueryMusicDatabase paras: QueryMusicDatabase; select * from songview order by idSong desc limit 4 20:14:43 T:140678601766672 M:2896150528 INFO: Scriptresult: Success 20:14:43 T:140678601766672 M:2896150528 INFO: Python script stopped 20:14:43 T:140678601766672 M:2896150528 DEBUG: Thread 140678601766672 terminating 20:14:43 T:140679204898752 M:2896277504 DEBUG: python thread 3 destructed 20:14:44 T:140679204898752 M:2896199680 DEBUG: SDLKeyboard: scancode: 111, sym: 273, unicode: 0, modifier: 0 20:14:44 T:140679204898752 M:2896199680 DEBUG: OnKey: 61478 pressed, action is Up 20:14:44 T:140679204898752 M:2896609280 DEBUG: SDLKeyboard: scancode: 111, sym: 273, unicode: 0, modifier: 0 20:14:44 T:140679204898752 M:2896609280 DEBUG: OnKey: 61478 pressed, action is Up 20:14:44 T:140678499661584 M:2886332416 DEBUG: DoWork - took 164 ms to load special://masterprofile/Thumbnails/9/998af621.jpg 20:14:45 T:140679204898752 M:2895683584 DEBUG: SDLKeyboard: scancode: 111, sym: 273, unicode: 0, modifier: 0 20:14:45 T:140679204898752 M:2895683584 DEBUG: OnKey: 61478 pressed, action is Up 20:14:45 T:140678691350288 M:2885668864 DEBUG: DoWork - took 116 ms to load special://masterprofile/Thumbnails/a/a13fa518.jpg 20:14:46 T:140679204898752 M:2895691776 DEBUG: SDLKeyboard: scancode: 111, sym: 273, unicode: 0, modifier: 0 20:14:46 T:140679204898752 M:2895691776 DEBUG: OnKey: 61478 pressed, action is Up 20:14:46 T:140679204898752 M:2895937536 DEBUG: SDLKeyboard: scancode: 111, sym: 273, unicode: 0, modifier: 0 20:14:46 T:140679204898752 M:2895937536 DEBUG: OnKey: 61478 pressed, action is Up 20:14:47 T:140679204898752 M:2895585280 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:14:47 T:140679204898752 M:2895585280 DEBUG: OnKey: 61479 pressed, action is Right 20:14:48 T:140679204898752 M:2895564800 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 20:14:48 T:140679204898752 M:2895564800 DEBUG: OnKey: 61480 pressed, action is Down 20:14:48 T:140679204898752 M:2895564800 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 20:14:48 T:140679204898752 M:2895564800 DEBUG: OnKey: 61480 pressed, action is Down 20:14:48 T:140679204898752 M:2895564800 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 20:14:48 T:140679204898752 M:2895564800 DEBUG: OnKey: 61480 pressed, action is Down 20:14:49 T:140679204898752 M:2895499264 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 20:14:49 T:140679204898752 M:2895499264 DEBUG: OnKey: 61480 pressed, action is Down 20:14:49 T:140679204898752 M:2895818752 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0 20:14:49 T:140679204898752 M:2895818752 DEBUG: OnKey: 61453 pressed, action is Select 20:14:49 T:140679204898752 M:2895564800 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(VideoLibrary,RecentlyAddedMovies,return) 20:14:49 T:140679204898752 M:2895564800 DEBUG: ExecuteXBMCAction : To ActivateWindow(VideoLibrary,RecentlyAddedMovies,return) 20:14:49 T:140679204898752 M:2895564800 DEBUG: Activating window ID: 10025 20:14:49 T:140679204898752 M:2895564800 DEBUG: Checking if window ID 10024 is locked. 20:14:49 T:140679204898752 M:2895564800 DEBUG: ------ Window Deinit (Home.xml) ------ 20:14:50 T:140679204898752 M:2896101376 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 20:14:50 T:140679204898752 M:2896101376 INFO: Loading skin file: MyVideoNav.xml 20:14:50 T:140679204898752 M:2896101376 DEBUG: CGUIMediaWindow::GetDirectory (videodb://4/) 20:14:50 T:140679204898752 M:2896101376 DEBUG: ParentPath = [videodb://4/] 20:14:50 T:140679204898752 M:2896101376 DEBUG: GetMoviesByWhere query: select * from movieview order by idMovie desc limit 200 20:14:50 T:140679204898752 M:2896101376 DEBUG: Time for actual SQL query = 7 20:14:50 T:140679204898752 M:2896101376 DEBUG: Time to retrieve movies from dataset = 48 20:14:50 T:140679204898752 M:2896101376 DEBUG: Saving fileitems [videodb://4/] 20:14:50 T:140679204898752 M:2895921152 DEBUG: -- items: 201, sort method: 0, ascending: false 20:14:50 T:140678601766672 M:2895921152 DEBUG: thread start, auto delete: 0 20:14:50 T:140678691350288 M:2894831616 DEBUG: DoWork - took 105 ms to load special://skin/backgrounds/videos.jpg 20:14:50 T:140678587488016 M:2892439552 DEBUG: DoWork - took 305 ms to load special://masterprofile/Thumbnails/Video/Fanart/6fcdf294.tbn 20:14:52 T:140679204898752 M:2890817536 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0 20:14:52 T:140679204898752 M:2890817536 DEBUG: OnKey: 61453 pressed, action is Select 20:14:52 T:140679204898752 M:2890817536 INFO: Loading skin file: DialogContextMenu.xml 20:14:52 T:140679204898752 M:2890817536 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 20:14:53 T:140679204898752 M:2890903552 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 20:14:53 T:140679204898752 M:2890903552 DEBUG: OnKey: 61480 pressed, action is Down 20:14:53 T:140679204898752 M:2890350592 INFO: Python, unloading python24.dll because no scripts are running anymore 20:14:53 T:140679204898752 M:2890350592 DEBUG: UnloadExtensionLibs, clearing python extension libraries 20:14:53 T:140679204898752 M:2890350592 DEBUG: Unloading: python26-x86_64-linux.so 20:14:53 T:140679204898752 M:2890223616 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0 20:14:53 T:140679204898752 M:2890223616 DEBUG: OnKey: 61453 pressed, action is Select 20:14:53 T:140679204898752 M:2890223616 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 20:14:53 T:140679204898752 M:2890223616 DEBUG: new file set audiostream:0 20:14:53 T:140678601766672 M:2890223616 DEBUG: Thread 140678601766672 terminating 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerCoreFactory::GetPlayers(/mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv) 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 20:14:53 T:140679204898752 M:2890223616 WARNING: CPlayerCoreFactory::GetPlayerCore(videodefaultplayer): no such core: mplayer 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 20:14:53 T:140679204898752 M:2890223616 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 20:14:53 T:140679204898752 M:2890223616 NOTICE: DVDPlayer: Opening: /mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv 20:14:53 T:140679204898752 M:2890223616 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:14:53 T:140678421165840 M:2890223616 DEBUG: thread start, auto delete: 0 20:14:53 T:140678421165840 M:2890223616 NOTICE: Creating InputStream 20:14:53 T:140678421165840 M:2890223616 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 20:14:53 T:140678421165840 M:2890223616 DEBUG: CacheSubtitles: START 20:14:53 T:140678421165840 M:2890223616 DEBUG: CacheSubtitles: Checking for common subdirs... 20:14:53 T:140678421165840 M:2890223616 DEBUG: CacheSubtitles: Done (time: 20 ms) 20:14:53 T:140678421165840 M:2890223616 DEBUG: CacheSubtitles: Searching for subtitles... 20:14:53 T:140678421165840 M:2890223616 DEBUG: CacheSubtitles: Done (time: 27 ms) 20:14:53 T:140678421165840 M:2890223616 DEBUG: CacheSubtitles: END (total time: 75 ms) 20:14:53 T:140678421165840 M:2890223616 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 20:14:53 T:140678421165840 M:2890223616 NOTICE: Creating Demuxer 20:14:53 T:140678421165840 M:2890223616 DEBUG: DllAvUtilBase: Using libavutil system library 20:14:53 T:140678421165840 M:2890223616 DEBUG: DllAvCodec: Using libavcodec system library 20:14:53 T:140678421165840 M:2890223616 DEBUG: DllAvFormat: Using libavformat system library 20:14:53 T:140678421165840 M:2890223616 DEBUG: Open - probing detected format [matroska] 20:14:53 T:140678421165840 M:2890244096 DEBUG: Open - av_find_stream_info starting 20:14:53 T:140679204898752 M:2890244096 DEBUG: ------ Window Init (DialogBusy.xml) ------ 20:14:53 T:140678421165840 M:2890244096 DEBUG: ffmpeg[3F523710]: [matroska] max_analyze_duration reached 20:14:53 T:140678421165840 M:2890244096 DEBUG: ffmpeg[3F523710]: [matroska] Estimating duration from bitrate, this may be inaccurate 20:14:53 T:140678421165840 M:2890244096 DEBUG: Open - av_find_stream_info finished 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Input #0, matroska, from '/mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv': 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: doctype : matroska 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Duration: 02:04:39.16, start: 0.000000, bitrate: 640 kb/s 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.0: start 0.000000, end 304.304000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:00:00.000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.1: start 304.304000, end 667.584000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:05:04.304 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.2: start 667.584000, end 1079.954000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:11:07.584 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.3: start 1079.954000, end 1553.010000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:17:59.954 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.4: start 1553.010000, end 1826.783000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:25:53.010 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.5: start 1826.783000, end 2253.001000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:30:26.783 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.6: start 2253.001000, end 2639.637000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:37:33.001 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.7: start 2639.637000, end 3099.346000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:43:59.637 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.8: start 3099.346000, end 3610.523000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 00:51:39.346 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.9: start 3610.523000, end 4021.517000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:00:10.523 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.10: start 4021.517000, end 4560.764000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:07:01.517 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.11: start 4560.764000, end 4873.285000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:16:00.764 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.12: start 4873.285000, end 5433.553000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:21:13.285 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.13: start 5433.553000, end 5929.340000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:30:33.553 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.14: start 5929.340000, end 6341.794000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:38:49.340 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.15: start 6341.794000, end 6857.350000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:45:41.794 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Chapter #0.16: start 6857.350000, end 7479.168000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : 01:54:17.350 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.0(eng): Video: h264, yuv420p, 1280x544, PAR 1:1 DAR 40:17, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : x264_L4.1 @ 6810 Kbps 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.1(eng): Audio: ac3, 48000 Hz, 5.1, s16, 640 kb/s 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : English Dolby Digital 5.1 @ 640 Kbps 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.2(eng): Subtitle: 0x0000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : English 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.3(chi): Subtitle: 0x0000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : Chinese Simplified 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.4(chi): Subtitle: 0x0000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : Chinese Traditional 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.5(eng): Audio: vorbis, 48000 Hz, stereo, s16 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : English Commetary Vorbis 2.0 @ 80 Kbps 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.6(eng): Subtitle: 0x0000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: title : English Commentary 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Stream #0.7: Attachment: 0x0000 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: Metadata: 20:14:53 T:140678421165840 M:2890244096 INFO: ffmpeg[3F523710]: filename : MHeiHKS-Medium.otf 20:14:53 T:140678421165840 M:2890244096 NOTICE: Opening video stream: 0 source: 256 20:14:53 T:140678421165840 M:2890244096 NOTICE: Creating video codec with codec id: 28 20:14:53 T:140678421165840 M:2890244096 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:no VAAPI:no 20:14:53 T:140678421165840 M:2890244096 INFO: Trying Broadcom Crystal HD Decoder... 20:14:53 T:140678421165840 M:2890244096 DEBUG: FactoryCodec - Video: - Opening 20:14:53 T:140678421165840 M:2890244096 DEBUG: CrystalHD: profile 640029 20:14:53 T:140678421165840 M:2890244096 DEBUG: CrystalHD: nal size 4 20:14:53 T:140678421165840 M:2890244096 DEBUG: CrystalHD: num sps 1 20:14:53 T:140678421165840 M:2890244096 DEBUG: CrystalHD: data size at end = 0 20:14:54 T:140678421165840 M:2890244096 DEBUG: DllSwScale: Using libswscale system library 20:14:54 T:140678421165840 M:2890244096 INFO: DVDVideoCodecCrystalHD: Opened Broadcom Crystal HD Codec 20:14:54 T:140678421165840 M:2890244096 DEBUG: FactoryCodec - Video: chd-h264 - Opened 20:14:54 T:140678412773136 M:2890244096 DEBUG: thread start, auto delete: 0 20:14:54 T:140678412773136 M:2890244096 DEBUG: CrystalHD: Output Thread Started... 20:14:54 T:140678421165840 M:2890244096 NOTICE: Creating video thread 20:14:54 T:140678421165840 M:2890244096 NOTICE: Opening audio stream: 1 source: 256 20:14:54 T:140678404380432 M:2890244096 DEBUG: thread start, auto delete: 0 20:14:54 T:140678421165840 M:2890244096 NOTICE: Finding audio codec for: 86019 20:14:54 T:140678421165840 M:2890244096 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 20:14:54 T:140678421165840 M:2890244096 DEBUG: DllAvUtilBase: Using libavutil system library 20:14:54 T:140678421165840 M:2890244096 DEBUG: DllAvCodec: Using libavcodec system library 20:14:54 T:140678421165840 M:2890244096 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 20:14:54 T:140678421165840 M:2890244096 NOTICE: Creating audio thread 20:14:54 T:140678421165840 M:2890100736 NOTICE: Opening Subtitle stream: 2 source: 256 20:14:54 T:140678421165840 M:2890100736 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 20:14:54 T:140678421165840 M:2890100736 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 20:14:54 T:140678395987728 M:2890100736 DEBUG: thread start, auto delete: 0 20:14:54 T:140678395987728 M:2890100736 NOTICE: running thread: CDVDPlayerAudio::Process() 20:14:54 T:140678421165840 M:2890100736 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv 20:14:54 T:140678421165840 M:2890100736 DEBUG: CDVDPlayer::SetCaching - caching state 2 20:14:54 T:140678395987728 M:2890100736 NOTICE: Creating audio device with codec id: 86019, channels: 6, sample rate: 48000, no pass-through 20:14:54 T:140678421165840 M:2890100736 DEBUG: CheckPlayerInit - dropping packet type:2 dts:83000.000000 to get to start point at 167000.000000 20:14:54 T:140678395987728 M:2890100736 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 20:14:54 T:140678421165840 M:2890100736 DEBUG: CheckPlayerInit - dropping packet type:2 dts:42000.000000 to get to start point at 167000.000000 20:14:54 T:140678395987728 M:2890100736 INFO: CPCMRemap: Configured speaker layout: 2.0 20:14:54 T:140678421165840 M:2890100736 DEBUG: CheckPlayerInit - dropping packet type:2 dts:125000.000000 to get to start point at 167000.000000 20:14:54 T:140678395987728 M:2890100736 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 20:14:54 T:140678395987728 M:2890100736 DEBUG: CPCMRemap: Forcing side channel map to back channels 20:14:54 T:140678395987728 M:2890100736 INFO: CPCMRemap: O channel map: FL,FR 20:14:54 T:140678395987728 M:2890100736 DEBUG: CPCMRemap: Downmix normalization is disabled 20:14:54 T:140678395987728 M:2890100736 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 20:14:54 T:140678395987728 M:2890100736 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 20:14:54 T:140678395987728 M:2890100736 DEBUG: CALSADirectSound::CALSADirectSound - Requested channels changed from 6 to 2 20:14:54 T:140678395987728 M:2890100736 DEBUG: RemoveActiveDevice - Removing device 1 20:14:54 T:140678395987728 M:2890100736 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 20:14:54 T:140678395987728 M:2890100736 DEBUG: RemoveActiveDevice - Removing device 0 20:14:54 T:140678395987728 M:2890100736 DEBUG: Initialize - using alsa device default 20:14:54 T:140678395987728 M:2887933952 DEBUG: CALSADirectSound::Initialize - packet size:4096, packet count:8, buffer size:8192 20:14:54 T:140678395987728 M:2887933952 DEBUG: CALSADirectSound::Initialize - Channels: 6 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough false - audioDevice: default 20:14:54 T:140678395987728 M:2887933952 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 20:14:54 T:140678421165840 M:2887933952 DEBUG: CDVDPlayer::HandleMessages - player started 1 20:14:54 T:140678404380432 M:2887471104 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:14:54 T:140678404380432 M:2887471104 DEBUG: DllAvUtilBase: Using libavutil system library 20:14:54 T:140678404380432 M:2887471104 DEBUG: DllAvCodec: Using libavcodec system library 20:14:54 T:140678404380432 M:2887471104 DEBUG: DllSwScale: Using libswscale system library 20:14:54 T:140678404380432 M:2887471104 NOTICE: running thread: video_thread 20:14:54 T:140678404380432 M:2887471104 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 20:14:54 T:140679204898752 M:2887471104 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc 20:14:54 T:140678404380432 M:2887606272 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(167000.000000, 0) 20:14:54 T:140678412773136 M:2887479296 DEBUG: CrystalHD: Format Change Detected. Flags: 0x00030000 20:14:54 T:140678412773136 M:2887479296 DEBUG: ---------------------------------- 20:14:54 T:140678412773136 M:2887479296 DEBUG: TimeStamp: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: Picture Number: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: Width: 1280 20:14:54 T:140678412773136 M:2887479296 DEBUG: Height: 544 20:14:54 T:140678412773136 M:2887479296 DEBUG: Chroma: 0x420 20:14:54 T:140678412773136 M:2887479296 DEBUG: Pulldown: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: Flags: 0x80040000 20:14:54 T:140678412773136 M:2887479296 DEBUG: Frame Rate/Res: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: Aspect Ratio: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: Color Primaries: 2 20:14:54 T:140678412773136 M:2887479296 DEBUG: MetaData: 684298 20:14:54 T:140678412773136 M:2887479296 DEBUG: Session Number: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: TimeStamp: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: Custom Aspect: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: Frames to Drop: 0 20:14:54 T:140678412773136 M:2887479296 DEBUG: H264 Valid Fields: 0x00000000 20:14:54 T:140678412773136 M:2887479296 DEBUG: CrystalHD: dec_par x = 1, dec_par y = 1 20:14:54 T:140678412773136 M:2887479296 DEBUG: CrystalHD: resolution = 0 interlace = 0 20:14:54 T:140678404380432 M:2887479296 NOTICE: fps: 23.976025, pwidth: 1280, pheight: 544, dwidth: 1280, dheight: 544 20:14:54 T:140678404380432 M:2887479296 DEBUG: OutputPicture - change configuration. 1280x544. framerate: 23.98. format: NV12 20:14:54 T:140678404380432 M:2887479296 WARNING: CRenderManager::Configure - timeout waiting for previous frame 20:14:54 T:140678404380432 M:2887479296 NOTICE: Display resolution DESKTOP : 1920x1080 (12) 20:14:54 T:140678421165840 M:2887479296 DEBUG: CDVDPlayer::HandleMessages - player started 2 20:14:54 T:140678421165840 M:2887479296 DEBUG: CDVDPlayer::SetCaching - caching state 3 20:14:54 T:140678395987728 M:2887479296 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1) 20:14:54 T:140678395987728 M:2887479296 DEBUG: CDVDPlayerAudio:: Discontinuity - was:32575.290000, should be:32196.648677, error:-378.641323 20:14:54 T:140679204898752 M:2887352320 NOTICE: Using GL_TEXTURE_2D 20:14:54 T:140679204898752 M:2887352320 DEBUG: GL: Requested render method: 0 20:14:54 T:140679204898752 M:2887352320 NOTICE: GL: ARB shaders support detected 20:14:54 T:140679204898752 M:2887192576 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:14:54 T:140679204898752 M:2887192576 NOTICE: GL: No vertex shader, fixed pipeline in use 20:14:54 T:140679204898752 M:2887192576 DEBUG: GL: Disabling non-linear stretch 20:14:54 T:140679204898752 M:2887192576 NOTICE: GL: NPOT texture support detected 20:14:54 T:140679204898752 M:2887192576 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:14:54 T:140678421165840 M:2887798784 DEBUG: CDVDPlayer::SetCaching - caching state 0 20:14:54 T:140679204898752 M:2887839744 DEBUG: CGUIInfoManager::SetCurrentMovie(/mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv) 20:14:54 T:140679204898752 M:2887839744 DEBUG: Activating window ID: 12005 20:14:54 T:140679204898752 M:2887839744 DEBUG: Checking if window ID 12005 is locked. 20:14:54 T:140679204898752 M:2887839744 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 20:14:54 T:140679204898752 M:2887839744 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 20:14:54 T:140679204898752 M:2887839744 INFO: Loading skin file: VideoFullScreen.xml 20:14:54 T:140679204898752 M:2888482816 DEBUG: GL: Requested render method: 0 20:14:54 T:140679204898752 M:2888482816 NOTICE: GL: ARB shaders support detected 20:14:54 T:140679204898752 M:2888355840 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:14:54 T:140679204898752 M:2888355840 NOTICE: GL: No vertex shader, fixed pipeline in use 20:14:54 T:140679204898752 M:2888355840 NOTICE: GL: NPOT texture support detected 20:14:54 T:140679204898752 M:2888355840 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:14:54 T:140679204898752 M:2888237056 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 20:14:56 T:140678404380432 M:2840883200 DEBUG: CPullupCorrection: detected pattern of length 24: 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 20:15:08 T:140679204898752 M:2838425600 DEBUG: SDLKeyboard: scancode: 53, sym: 120, unicode: 120, modifier: 0 20:15:08 T:140679204898752 M:2838425600 DEBUG: OnKey: 61528 pressed, action is Stop 20:15:08 T:140679204898752 M:2838425600 NOTICE: CDVDPlayer::CloseFile() 20:15:08 T:140678421165840 M:2838425600 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc 20:15:08 T:140679204898752 M:2838425600 NOTICE: DVDPlayer: waiting for threads to exit 20:15:08 T:140678421165840 M:2838425600 NOTICE: CDVDPlayer::OnExit() 20:15:08 T:140678421165840 M:2838425600 NOTICE: DVDPlayer: closing audio stream 20:15:08 T:140678421165840 M:2838425600 NOTICE: Closing audio stream 20:15:08 T:140678421165840 M:2838425600 NOTICE: Waiting for audio thread to exit 20:15:08 T:140678395987728 M:2838425600 ERROR: AddPackets - failed to add leftover bytes to render 20:15:08 T:140678395987728 M:2838425600 DEBUG: CDVDPlayerAudio:: Discontinuity - was:-4082962.496323, should be:14363169.209188, error:18446131.705511 20:15:08 T:140678395987728 M:2838425600 NOTICE: thread end: CDVDPlayerAudio::OnExit() 20:15:08 T:140678395987728 M:2838425600 DEBUG: Thread 140678395987728 terminating 20:15:08 T:140678421165840 M:2838425600 NOTICE: Closing audio device 20:15:08 T:140678421165840 M:2838425600 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 20:15:08 T:140678421165840 M:2838425600 DEBUG: RemoveActiveDevice - Removing device 2 20:15:08 T:140678421165840 M:2838425600 NOTICE: Deleting audio codec 20:15:08 T:140678421165840 M:2838425600 NOTICE: DVDPlayer: closing video stream 20:15:08 T:140678421165840 M:2838425600 NOTICE: Closing video stream 20:15:08 T:140678421165840 M:2838425600 NOTICE: waiting for video thread to exit 20:15:08 T:140678404380432 M:2838425600 NOTICE: thread end: video_thread 20:15:08 T:140678404380432 M:2838425600 DEBUG: Thread 140678404380432 terminating 20:15:08 T:140678421165840 M:2838425600 NOTICE: deleting video codec 20:15:08 T:140678412773136 M:2838425600 DEBUG: CrystalHD: Output Thread Stopped... 20:15:08 T:140678412773136 M:2838425600 DEBUG: Thread 140678412773136 terminating 20:15:09 T:140678421165840 M:2838462464 NOTICE: DVDPlayer: closing subtitle stream 20:15:09 T:140678421165840 M:2838462464 NOTICE: Closing subtitle stream 20:15:09 T:140678421165840 M:2838462464 NOTICE: CDVDPlayer::OnExit() deleting demuxer 20:15:09 T:140678421165840 M:2838462464 NOTICE: CDVDPlayer::OnExit() deleting input stream 20:15:09 T:140678421165840 M:2904616960 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc 20:15:09 T:140678421165840 M:2904616960 DEBUG: Thread 140678421165840 terminating 20:15:09 T:140679204898752 M:2904616960 NOTICE: DVDPlayer: finished waiting 20:15:09 T:140679204898752 M:2904616960 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:15:09 T:140679204898752 M:2904616960 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:15:09 T:140679204898752 M:2904616960 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 20:15:09 T:140679204898752 M:2904743936 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:15:09 T:140679204898752 M:2904743936 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 20:15:09 T:140679204898752 M:2904743936 INFO: Loading skin file: MyVideoNav.xml 20:15:09 T:140679204898752 M:2904616960 DEBUG: CGUIMediaWindow::GetDirectory (videodb://4/) 20:15:09 T:140679204898752 M:2904616960 DEBUG: ParentPath = [videodb://4/] 20:15:09 T:140679204898752 M:2904616960 DEBUG: GetMoviesByWhere query: select * from movieview order by idMovie desc limit 200 20:15:09 T:140679204898752 M:2904616960 DEBUG: Time for actual SQL query = 7 20:15:09 T:140679204898752 M:2904616960 DEBUG: Time to retrieve movies from dataset = 48 20:15:09 T:140679204898752 M:2904616960 DEBUG: Saving fileitems [videodb://4/] 20:15:09 T:140679204898752 M:2904969216 DEBUG: -- items: 201, sort method: 0, ascending: false 20:15:09 T:140678601766672 M:2904969216 DEBUG: thread start, auto delete: 0 20:15:09 T:140678499661584 M:2889605120 DEBUG: DoWork - took 222 ms to load special://skin/backgrounds/videos.jpg 20:15:09 T:140679204898752 M:2889650176 NOTICE: CDVDPlayer::CloseFile() 20:15:09 T:140679204898752 M:2889650176 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:15:09 T:140679204898752 M:2889650176 NOTICE: DVDPlayer: waiting for threads to exit 20:15:09 T:140679204898752 M:2889650176 NOTICE: DVDPlayer: finished waiting 20:15:09 T:140679204898752 M:2889650176 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:15:09 T:140678446335760 M:2884169728 DEBUG: DoWork - took 264 ms to load special://masterprofile/Thumbnails/8/89c76e3b.jpg 20:15:09 T:140678691350288 M:2890964992 DEBUG: DoWork - took 380 ms to load special://masterprofile/Thumbnails/Video/Fanart/6fcdf294.tbn 20:15:09 T:140678691350288 M:2890838016 DEBUG: DoWork - Saving file state for video item /mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv 20:15:10 T:140679204898752 M:2892464128 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:15:10 T:140679204898752 M:2892464128 DEBUG: OnKey: 61479 pressed, action is Right 20:15:10 T:140678587488016 M:2892550144 DEBUG: DoWork - took 129 ms to load special://masterprofile/Thumbnails/Video/2/23eba754.tbn 20:15:10 T:140678446335760 M:2891235328 DEBUG: DoWork - took 163 ms to load special://masterprofile/Thumbnails/Video/9/9bef61b8.tbn 20:15:10 T:140678499661584 M:2887233536 DEBUG: DoWork - took 222 ms to load special://masterprofile/Thumbnails/Video/Fanart/4bf19f8a.tbn 20:15:12 T:140679204898752 M:2893557760 DEBUG: SDLKeyboard: scancode: 113, sym: 276, unicode: 0, modifier: 0 20:15:12 T:140679204898752 M:2893557760 DEBUG: OnKey: 61477 pressed, action is Left 20:15:13 T:140679204898752 M:2893942784 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:15:13 T:140679204898752 M:2893942784 DEBUG: OnKey: 61479 pressed, action is Right 20:15:13 T:140678587488016 M:2893942784 DEBUG: DoWork - trying to extract filestream details from video file /mnt/ssh.drive.0/movies/M-N-O/Operation.Endgame.2010.720p.bluray.x264.dts-wiki.mkv 20:15:13 T:140678587488016 M:2893942784 DEBUG: DllAvUtilBase: Using libavutil system library 20:15:13 T:140678587488016 M:2893942784 DEBUG: DllAvCodec: Using libavcodec system library 20:15:13 T:140678587488016 M:2893942784 DEBUG: DllAvFormat: Using libavformat system library 20:15:13 T:140678587488016 M:2893942784 DEBUG: Open - probing detected format [mp3] 20:15:13 T:140678587488016 M:2893942784 DEBUG: Open - av_find_stream_info starting 20:15:13 T:140679204898752 M:2893942784 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:15:13 T:140679204898752 M:2893942784 DEBUG: OnKey: 61479 pressed, action is Right 20:15:13 T:140678499661584 M:2891935744 DEBUG: DoWork - took 155 ms to load special://masterprofile/Thumbnails/Video/b/ba05765c.tbn 20:15:13 T:140679204898752 M:2890760192 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:15:13 T:140679204898752 M:2890760192 DEBUG: OnKey: 61479 pressed, action is Right 20:15:13 T:140678691350288 M:2872909824 DEBUG: DoWork - took 313 ms to load special://masterprofile/Thumbnails/Video/Fanart/55cb26fa.tbn 20:15:14 T:140679204898752 M:2884849664 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:15:14 T:140679204898752 M:2884849664 DEBUG: OnKey: 61479 pressed, action is Right 20:15:14 T:140679204898752 M:2880143360 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:15:14 T:140679204898752 M:2880143360 DEBUG: OnKey: 61479 pressed, action is Right 20:15:14 T:140678499661584 M:2873249792 DEBUG: DoWork - took 416 ms to load special://masterprofile/Thumbnails/Video/Fanart/0b382027.tbn 20:15:14 T:140678499661584 M:2869723136 DEBUG: DoWork - took 102 ms to load special://masterprofile/Thumbnails/Video/9/978dac18.tbn 20:15:14 T:140678691350288 M:2866548736 DEBUG: DoWork - took 178 ms to load special://masterprofile/Thumbnails/Video/Fanart/31056524.tbn 20:15:15 T:140678446335760 M:2867793920 DEBUG: DoWork - took 1843 ms to load special://masterprofile/Thumbnails/Video/Fanart/33fc58b3.tbn 20:15:15 T:140679204898752 M:2867556352 DEBUG: SDLKeyboard: scancode: 114, sym: 275, unicode: 0, modifier: 0 20:15:15 T:140679204898752 M:2867556352 DEBUG: OnKey: 61479 pressed, action is Right 20:15:15 T:140679204898752 M:2858139648 DEBUG: SDLKeyboard: scancode: 113, sym: 276, unicode: 0, modifier: 0 20:15:15 T:140679204898752 M:2858139648 DEBUG: OnKey: 61477 pressed, action is Left 20:15:16 T:140679204898752 M:2852003840 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0 20:15:16 T:140679204898752 M:2852003840 DEBUG: OnKey: 61453 pressed, action is Select 20:15:16 T:140679204898752 M:2851848192 DEBUG: new file set audiostream:0 20:15:16 T:140678601766672 M:2851606528 DEBUG: Thread 140678601766672 terminating 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerCoreFactory::GetPlayers(/mnt/ssh.drive.0/movies/P-Q-R-S/The.Sweetest.Thing.UNRATED.DVDRip.DivX-DcN.avi) 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 20:15:16 T:140679204898752 M:2851250176 WARNING: CPlayerCoreFactory::GetPlayerCore(videodefaultplayer): no such core: mplayer 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 20:15:16 T:140679204898752 M:2851250176 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 20:15:16 T:140679204898752 M:2848837632 NOTICE: DVDPlayer: Opening: /mnt/ssh.drive.0/movies/P-Q-R-S/The.Sweetest.Thing.UNRATED.DVDRip.DivX-DcN.avi 20:15:16 T:140679204898752 M:2848837632 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:15:16 T:140678421165840 M:2848837632 DEBUG: thread start, auto delete: 0 20:15:16 T:140678421165840 M:2848837632 NOTICE: Creating InputStream 20:15:16 T:140678421165840 M:2848141312 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 20:15:16 T:140678421165840 M:2848141312 DEBUG: CacheSubtitles: START 20:15:16 T:140678421165840 M:2848141312 DEBUG: CacheSubtitles: Checking for common subdirs... 20:15:16 T:140679204898752 M:2847653888 DEBUG: ------ Window Init (DialogBusy.xml) ------ 20:15:17 T:140678421165840 M:2842316800 DEBUG: CacheSubtitles: Done (time: 517 ms) 20:15:17 T:140678421165840 M:2842316800 DEBUG: CacheSubtitles: Searching for subtitles... 20:15:17 T:140678421165840 M:2839474176 DEBUG: CacheSubtitles: Done (time: 216 ms) 20:15:17 T:140678421165840 M:2839130112 DEBUG: CacheSubtitles: END (total time: 772 ms) 20:15:17 T:140678421165840 M:2839130112 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 20:15:17 T:140678421165840 M:2839130112 NOTICE: Creating Demuxer 20:15:17 T:140678421165840 M:2839130112 DEBUG: DllAvUtilBase: Using libavutil system library 20:15:17 T:140678421165840 M:2839130112 DEBUG: DllAvCodec: Using libavcodec system library 20:15:17 T:140678421165840 M:2839130112 DEBUG: DllAvFormat: Using libavformat system library 20:15:44 T:140678499661584 M:2523017216 DEBUG: Thread 140678499661584 terminating (autodelete) 20:15:44 T:140678691350288 M:2522812416 DEBUG: Thread 140678691350288 terminating (autodelete) 20:15:45 T:140678446335760 M:2517434368 DEBUG: Thread 140678446335760 terminating (autodelete) 20:21:59 T:140678587488016 M:737677312 WARNING: could not find codec parameters for /mnt/ssh.drive.0/movies/M-N-O/Operation.Endgame.2010.720p.bluray.x264.dts-wiki.mkv 20:21:59 T:140678421165840 M:2889207808 DEBUG: Open - probing detected format [avi] 20:22:00 T:140678587488016 M:2889453568 DEBUG: DoWork - trying to extract filestream details from video file /mnt/ssh.drive.0/movies/0-9/300.2006.1080p.BluRay.DTS.x264-CtrlHD.mkv 20:22:00 T:140678587488016 M:2889211904 DEBUG: DllAvUtilBase: Using libavutil system library 20:22:00 T:140678587488016 M:2889211904 DEBUG: DllAvCodec: Using libavcodec system library 20:22:00 T:140678587488016 M:2889076736 DEBUG: DllAvFormat: Using libavformat system library 20:22:00 T:140678587488016 M:2888982528 DEBUG: Open - probing detected format [matroska] 20:22:00 T:140678587488016 M:2889392128 ERROR: Open - Error, could not open file /mnt/ssh.drive.0/movies/0-9/300.2006.1080p.BluRay.DTS.x264-CtrlHD.mkv 20:22:00 T:140678421165840 M:2889416704 DEBUG: Open - av_find_stream_info starting 20:22:01 T:140678421165840 M:2888491008 DEBUG: Open - av_find_stream_info finished 20:22:01 T:140678421165840 M:2888491008 INFO: ffmpeg[3F523710]: Input #0, avi, from '/mnt/ssh.drive.0/movies/P-Q-R-S/The.Sweetest.Thing.UNRATED.DVDRip.DivX-DcN.avi': 20:22:01 T:140678421165840 M:2888491008 INFO: ffmpeg[3F523710]: Metadata: 20:22:01 T:140678421165840 M:2888491008 INFO: ffmpeg[3F523710]: ISFT : Nandub v1.0rc2 20:22:01 T:140678421165840 M:2888491008 INFO: ffmpeg[3F523710]: Duration: 01:29:30.95, start: 0.000000, bitrate: 1092 kb/s 20:22:01 T:140678421165840 M:2888364032 INFO: ffmpeg[3F523710]: Stream #0.0: Video: msmpeg4, yuv420p, 576x320, 23.98 tbr, 23.98 tbn, 23.98 tbc 20:22:01 T:140678421165840 M:2888515584 INFO: ffmpeg[3F523710]: Stream #0.1: Audio: mp3, 48000 Hz, 2 channels, s16, 128 kb/s 20:22:01 T:140678421165840 M:2888388608 NOTICE: Opening video stream: 0 source: 256 20:22:01 T:140678421165840 M:2888388608 NOTICE: Creating video codec with codec id: 17 20:22:01 T:140678421165840 M:2888388608 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:no VAAPI:no 20:22:01 T:140678421165840 M:2888388608 DEBUG: FactoryCodec - Video: - Opening 20:22:01 T:140678421165840 M:2888388608 DEBUG: DllAvUtilBase: Using libavutil system library 20:22:01 T:140678421165840 M:2888388608 DEBUG: DllAvCodec: Using libavcodec system library 20:22:01 T:140678421165840 M:2888388608 DEBUG: DllSwScale: Using libswscale system library 20:22:01 T:140678421165840 M:2888540160 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2 Microsoft variant version 3 20:22:01 T:140678421165840 M:2888486912 DEBUG: FactoryCodec - Video: ff-msmpeg4 - Opened 20:22:01 T:140678421165840 M:2888486912 NOTICE: Creating video thread 20:22:01 T:140678421165840 M:2888384512 NOTICE: Opening audio stream: 1 source: 256 20:22:01 T:140678446335760 M:2888384512 DEBUG: thread start, auto delete: 0 20:22:01 T:140678421165840 M:2888384512 NOTICE: Finding audio codec for: 86017 20:22:01 T:140678421165840 M:2888536064 DEBUG: FactoryCodec - Audio: libmad - Opening 20:22:01 T:140678421165840 M:2888560640 DEBUG: Loading: libmad.so.0 20:22:02 T:140678446335760 M:2887548928 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:22:02 T:140678446335760 M:2887548928 DEBUG: DllAvUtilBase: Using libavutil system library 20:22:02 T:140678446335760 M:2887548928 DEBUG: DllAvCodec: Using libavcodec system library 20:22:02 T:140678446335760 M:2887548928 DEBUG: DllSwScale: Using libswscale system library 20:22:02 T:140678446335760 M:2887548928 NOTICE: running thread: video_thread 20:22:02 T:140678421165840 M:2887548928 DEBUG: FactoryCodec - Audio: libmad - Opened 20:22:02 T:140678421165840 M:2887700480 NOTICE: Creating audio thread 20:22:02 T:140678691350288 M:2887700480 DEBUG: thread start, auto delete: 0 20:22:02 T:140678691350288 M:2887700480 NOTICE: running thread: CDVDPlayerAudio::Process() 20:22:02 T:140678421165840 M:2887700480 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /mnt/ssh.drive.0/movies/P-Q-R-S/The.Sweetest.Thing.UNRATED.DVDRip.DivX-DcN.avi 20:22:02 T:140678421165840 M:2887700480 DEBUG: CDVDPlayer::SetCaching - caching state 2 20:22:02 T:140678446335760 M:2887565312 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 20:22:02 T:140678691350288 M:2887581696 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(24000.000000, 1) 20:22:02 T:140678691350288 M:2887581696 NOTICE: Creating audio device with codec id: 86017, channels: 2, sample rate: 48000, no pass-through 20:22:02 T:140678446335760 M:2887180288 DEBUG: DllPostProc: Using libpostproc system library 20:22:02 T:140678446335760 M:2887168000 NOTICE: fps: 23.976000, pwidth: 576, pheight: 320, dwidth: 576, dheight: 320 20:22:02 T:140678446335760 M:2887168000 DEBUG: OutputPicture - change configuration. 576x320. framerate: 23.98. format: YV12 20:22:02 T:140678691350288 M:2887168000 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 20:22:02 T:140678691350288 M:2887319552 INFO: CPCMRemap: Configured speaker layout: 2.0 20:22:02 T:140678691350288 M:2887319552 INFO: CPCMRemap: I channel map: FL,FR 20:22:02 T:140678691350288 M:2887319552 INFO: CPCMRemap: O channel map: FL,FR 20:22:02 T:140678691350288 M:2887319552 DEBUG: CPCMRemap: Downmix normalization is disabled 20:22:02 T:140678691350288 M:2887319552 DEBUG: CPCMRemap: FL = FL(1.000000*) 20:22:02 T:140678691350288 M:2887319552 DEBUG: CPCMRemap: FR = FR(1.000000*) 20:22:02 T:140678691350288 M:2887319552 DEBUG: RemoveActiveDevice - Removing device 1 20:22:02 T:140678691350288 M:2887319552 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 20:22:02 T:140678691350288 M:2887176192 DEBUG: RemoveActiveDevice - Removing device 0 20:22:02 T:140679204898752 M:2887217152 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc 20:22:02 T:140678446335760 M:2887290880 WARNING: CRenderManager::Configure - timeout waiting for previous frame 20:22:02 T:140678446335760 M:2887290880 NOTICE: Display resolution DESKTOP : 1920x1080 (12) 20:22:02 T:140678421165840 M:2887290880 DEBUG: CDVDPlayer::HandleMessages - player started 2 20:22:02 T:140678691350288 M:2887413760 DEBUG: Initialize - using alsa device default 20:22:02 T:140679204898752 M:2887331840 NOTICE: Using GL_TEXTURE_2D 20:22:02 T:140679204898752 M:2887331840 DEBUG: GL: Requested render method: 0 20:22:02 T:140679204898752 M:2887356416 NOTICE: GL: ARB shaders support detected 20:22:02 T:140679204898752 M:2887286784 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:22:02 T:140679204898752 M:2887286784 NOTICE: GL: No vertex shader, fixed pipeline in use 20:22:02 T:140678691350288 M:2887286784 DEBUG: CALSADirectSound::Initialize - packet size:4096, packet count:8, buffer size:8192 20:22:02 T:140678691350288 M:2887286784 DEBUG: CALSADirectSound::Initialize - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough false - audioDevice: default 20:22:02 T:140678691350288 M:2887286784 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 20:22:02 T:140678421165840 M:2887311360 DEBUG: CDVDPlayer::HandleMessages - player started 1 20:22:02 T:140678421165840 M:2887311360 DEBUG: CDVDPlayer::SetCaching - caching state 3 20:22:02 T:140678421165840 M:2887311360 DEBUG: CDVDPlayer::SetCaching - caching state 0 20:22:02 T:140678446335760 M:2887311360 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(41708.375042, 0) 20:22:02 T:140678691350288 M:2887335936 DEBUG: CDVDPlayerAudio:: Discontinuity - was:22194.092000, should be:24000.000000, error:1805.908000 20:22:03 T:140679204898752 M:2887385088 DEBUG: GL: Disabling non-linear stretch 20:22:03 T:140679204898752 M:2887385088 NOTICE: GL: NPOT texture support detected 20:22:03 T:140679204898752 M:2887385088 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:22:03 T:140678446335760 M:2887155712 DEBUG: DllPostProc: Using libpostproc system library 20:22:03 T:140679204898752 M:2887069696 DEBUG: CGUIInfoManager::SetCurrentMovie(/mnt/ssh.drive.0/movies/P-Q-R-S/The.Sweetest.Thing.UNRATED.DVDRip.DivX-DcN.avi) 20:22:03 T:140679204898752 M:2887086080 DEBUG: Activating window ID: 12005 20:22:03 T:140679204898752 M:2887086080 DEBUG: Checking if window ID 12005 is locked. 20:22:03 T:140679204898752 M:2887008256 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 20:22:03 T:140679204898752 M:2887008256 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 20:22:03 T:140679204898752 M:2887008256 INFO: Loading skin file: VideoFullScreen.xml 20:22:03 T:140679204898752 M:2887004160 DEBUG: SECTION:UnloadDelayed(SECTION: LCODE) 20:22:03 T:140679204898752 M:2887004160 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 20:22:03 T:140679204898752 M:2887004160 DEBUG: Unloading: ImageLib-x86_64-linux.so 20:22:03 T:140678446335760 M:2887028736 DEBUG: DllPostProc: Using libpostproc system library 20:22:03 T:140679204898752 M:2887163904 DEBUG: GL: Requested render method: 0 20:22:03 T:140679204898752 M:2887163904 NOTICE: GL: ARB shaders support detected 20:22:03 T:140679204898752 M:2887163904 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:22:03 T:140679204898752 M:2887163904 NOTICE: GL: No vertex shader, fixed pipeline in use 20:22:03 T:140679204898752 M:2887163904 NOTICE: GL: NPOT texture support detected 20:22:03 T:140679204898752 M:2887163904 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:22:03 T:140679204898752 M:2887012352 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 20:22:03 T:140678446335760 M:2887012352 DEBUG: DllPostProc: Using libpostproc system library 20:22:04 T:140678691350288 M:2887065600 DEBUG: Previous line repeats 24 times. 20:22:04 T:140678691350288 M:2887065600 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1043180.124000, should be:1026664.195864, error:-16515.928136 20:22:04 T:140678446335760 M:2887065600 DEBUG: DllPostProc: Using libpostproc system library 20:22:05 T:140678446335760 M:2887344128 DEBUG: Previous line repeats 41 times. 20:22:05 T:140678446335760 M:2887344128 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.38 20:22:05 T:140678446335760 M:2887344128 DEBUG: DllPostProc: Using libpostproc system library 20:22:30 T:140678587488016 M:2888364032 DEBUG: Previous line repeats 594 times. 20:22:30 T:140678587488016 M:2888364032 DEBUG: Thread 140678587488016 terminating (autodelete) 20:22:30 T:140678446335760 M:2888364032 DEBUG: DllPostProc: Using libpostproc system library 20:23:15 T:140679204898752 M:2888228864 DEBUG: Previous line repeats 1073 times. 20:23:15 T:140679204898752 M:2888228864 DEBUG: SDLKeyboard: scancode: 53, sym: 120, unicode: 120, modifier: 0 20:23:15 T:140679204898752 M:2888228864 DEBUG: OnKey: 61528 pressed, action is Stop 20:23:15 T:140679204898752 M:2888228864 NOTICE: CDVDPlayer::CloseFile() 20:23:15 T:140678421165840 M:2888228864 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc 20:23:15 T:140679204898752 M:2888228864 NOTICE: DVDPlayer: waiting for threads to exit 20:23:15 T:140678691350288 M:2888228864 DEBUG: CDVDPlayerAudio:: Discontinuity - was:-1141122.057136, should be:72640798.008918, error:73781920.066055 20:23:15 T:140678421165840 M:2888228864 NOTICE: CDVDPlayer::OnExit() 20:23:15 T:140678421165840 M:2888228864 NOTICE: DVDPlayer: closing audio stream 20:23:15 T:140678421165840 M:2888228864 NOTICE: Closing audio stream 20:23:15 T:140678421165840 M:2888228864 NOTICE: Waiting for audio thread to exit 20:23:15 T:140678691350288 M:2888228864 ERROR: AddPackets - failed to add leftover bytes to render 20:23:15 T:140678691350288 M:2888228864 NOTICE: thread end: CDVDPlayerAudio::OnExit() 20:23:15 T:140678691350288 M:2888228864 DEBUG: Thread 140678691350288 terminating 20:23:15 T:140678421165840 M:2888228864 NOTICE: Closing audio device 20:23:15 T:140678421165840 M:2888355840 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 20:23:15 T:140678421165840 M:2888355840 DEBUG: RemoveActiveDevice - Removing device 2 20:23:15 T:140678421165840 M:2888355840 NOTICE: Deleting audio codec 20:23:15 T:140678421165840 M:2888355840 NOTICE: DVDPlayer: closing video stream 20:23:15 T:140678421165840 M:2888355840 NOTICE: Closing video stream 20:23:15 T:140678421165840 M:2888355840 NOTICE: waiting for video thread to exit 20:23:15 T:140678446335760 M:2888355840 NOTICE: thread end: video_thread 20:23:15 T:140678446335760 M:2888355840 DEBUG: Thread 140678446335760 terminating 20:23:15 T:140678421165840 M:2888355840 NOTICE: deleting video codec 20:23:15 T:140678421165840 M:2888355840 NOTICE: CDVDPlayer::OnExit() deleting demuxer 20:23:15 T:140678421165840 M:2888355840 NOTICE: CDVDPlayer::OnExit() deleting input stream 20:23:15 T:140678421165840 M:2888355840 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc 20:23:15 T:140678421165840 M:2888388608 DEBUG: Thread 140678421165840 terminating 20:23:15 T:140679204898752 M:2888523776 NOTICE: DVDPlayer: finished waiting 20:23:15 T:140679204898752 M:2888523776 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:23:15 T:140679204898752 M:2888523776 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:23:15 T:140679204898752 M:2888523776 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 20:23:15 T:140679204898752 M:2888429568 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:23:15 T:140679204898752 M:2888351744 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 20:23:15 T:140679204898752 M:2888351744 INFO: Loading skin file: MyVideoNav.xml 20:23:16 T:140679204898752 M:2888343552 DEBUG: CGUIMediaWindow::GetDirectory (videodb://4/) 20:23:16 T:140679204898752 M:2888343552 DEBUG: ParentPath = [videodb://4/] 20:23:16 T:140679204898752 M:2888343552 DEBUG: GetMoviesByWhere query: select * from movieview order by idMovie desc limit 200 20:23:16 T:140679204898752 M:2888343552 DEBUG: Time for actual SQL query = 7 20:23:16 T:140679204898752 M:2888392704 DEBUG: Time to retrieve movies from dataset = 211 20:23:16 T:140679204898752 M:2888417280 DEBUG: Saving fileitems [videodb://4/] 20:23:16 T:140679204898752 M:2888220672 DEBUG: -- items: 201, sort method: 0, ascending: false 20:23:16 T:140678601766672 M:2888220672 DEBUG: thread start, auto delete: 0 20:23:16 T:140678421165840 M:2887061504 DEBUG: thread start, auto delete: 1 20:23:16 T:140678421165840 M:2887163904 DEBUG: Loading: /usr/lib64/xbmc/system/ImageLib-x86_64-linux.so 20:23:16 T:140678446335760 M:2887864320 DEBUG: thread start, auto delete: 1 20:23:16 T:140679204898752 M:2887995392 NOTICE: CDVDPlayer::CloseFile() 20:23:16 T:140679204898752 M:2888298496 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:23:16 T:140679204898752 M:2888298496 NOTICE: DVDPlayer: waiting for threads to exit 20:23:16 T:140679204898752 M:2888298496 NOTICE: DVDPlayer: finished waiting 20:23:16 T:140679204898752 M:2888298496 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:23:16 T:140678691350288 M:2890076160 DEBUG: thread start, auto delete: 1 20:23:16 T:140678421165840 M:2882416640 DEBUG: DoWork - took 518 ms to load special://skin/backgrounds/videos.jpg 20:23:17 T:140678691350288 M:2882768896 DEBUG: DoWork - took 368 ms to load special://masterprofile/Thumbnails/Video/Fanart/31056524.tbn 20:23:17 T:140678446335760 M:2883657728 DEBUG: DoWork - took 137 ms to load special://masterprofile/Thumbnails/8/89c76e3b.jpg 20:23:17 T:140678446335760 M:2889486336 DEBUG: DoWork - Saving file state for video item /mnt/ssh.drive.0/movies/P-Q-R-S/The.Sweetest.Thing.UNRATED.DVDRip.DivX-DcN.avi 20:23:18 T:140679204898752 M:2889543680 DEBUG: SDLKeyboard: scancode: 113, sym: 276, unicode: 0, modifier: 0 20:23:18 T:140679204898752 M:2889543680 DEBUG: OnKey: 61477 pressed, action is Left 20:23:18 T:140678691350288 M:2888880128 DEBUG: DoWork - took 136 ms to load special://masterprofile/Thumbnails/Video/Fanart/0b382027.tbn 20:23:18 T:140679204898752 M:2888949760 DEBUG: SDLKeyboard: scancode: 113, sym: 276, unicode: 0, modifier: 0 20:23:18 T:140679204898752 M:2888949760 DEBUG: OnKey: 61477 pressed, action is Left 20:23:18 T:140678446335760 M:2874933248 DEBUG: DoWork - took 142 ms to load special://masterprofile/Thumbnails/Video/Fanart/55cb26fa.tbn 20:23:19 T:140679204898752 M:2888159232 DEBUG: SDLKeyboard: scancode: 113, sym: 276, unicode: 0, modifier: 0 20:23:19 T:140679204898752 M:2888159232 DEBUG: OnKey: 61477 pressed, action is Left 20:23:20 T:140679204898752 M:2878242816 DEBUG: SDLKeyboard: scancode: 113, sym: 276, unicode: 0, modifier: 0 20:23:20 T:140679204898752 M:2878242816 DEBUG: OnKey: 61477 pressed, action is Left 20:23:20 T:140678421165840 M:2875588608 DEBUG: DoWork - trying to extract filestream details from video file /mnt/ssh.drive.0/movies/M-N-O/Operation.Endgame.2010.720p.bluray.x264.dts-wiki.mkv 20:23:20 T:140678421165840 M:2875588608 DEBUG: DllAvUtilBase: Using libavutil system library 20:23:20 T:140678421165840 M:2875588608 DEBUG: DllAvCodec: Using libavcodec system library 20:23:20 T:140678421165840 M:2875588608 DEBUG: DllAvFormat: Using libavformat system library 20:23:20 T:140678421165840 M:2875461632 DEBUG: Open - probing detected format [mp3] 20:23:20 T:140678421165840 M:2872795136 DEBUG: Open - av_find_stream_info starting 20:23:20 T:140678446335760 M:2871652352 DEBUG: DoWork - took 132 ms to load special://masterprofile/Thumbnails/Video/Fanart/4bf19f8a.tbn 20:23:20 T:140678691350288 M:2860072960 DEBUG: DoWork - took 1616 ms to load special://masterprofile/Thumbnails/Video/Fanart/33fc58b3.tbn 20:23:21 T:140679204898752 M:2874056704 DEBUG: SDLKeyboard: scancode: 113, sym: 276, unicode: 0, modifier: 0 20:23:21 T:140679204898752 M:2874056704 DEBUG: OnKey: 61477 pressed, action is Left 20:23:21 T:140678446335760 M:2857091072 DEBUG: DoWork - took 251 ms to load special://masterprofile/Thumbnails/Video/Fanart/6fcdf294.tbn 20:23:22 T:140679204898752 M:2862862336 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0 20:23:22 T:140679204898752 M:2862862336 DEBUG: OnKey: 61453 pressed, action is Select 20:23:22 T:140679204898752 M:2862641152 INFO: Loading skin file: DialogContextMenu.xml 20:23:22 T:140679204898752 M:2862395392 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 20:23:23 T:140679204898752 M:2850615296 DEBUG: SDLKeyboard: scancode: 116, sym: 274, unicode: 0, modifier: 0 20:23:23 T:140679204898752 M:2850615296 DEBUG: OnKey: 61480 pressed, action is Down 20:23:23 T:140678601766672 M:2849501184 DEBUG: Thread 140678601766672 terminating 20:23:23 T:140679204898752 M:2846466048 DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0 20:23:23 T:140679204898752 M:2846466048 DEBUG: OnKey: 61453 pressed, action is Select 20:23:24 T:140679204898752 M:2842873856 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 20:23:24 T:140679204898752 M:2842632192 DEBUG: new file set audiostream:0 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerCoreFactory::GetPlayers(/mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv) 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 20:23:24 T:140679204898752 M:2841862144 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 20:23:24 T:140679204898752 M:2841735168 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 20:23:24 T:140679204898752 M:2841886720 WARNING: CPlayerCoreFactory::GetPlayerCore(videodefaultplayer): no such core: mplayer 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 20:23:24 T:140679204898752 M:2841886720 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 20:23:24 T:140679204898752 M:2839556096 NOTICE: DVDPlayer: Opening: /mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv 20:23:24 T:140679204898752 M:2839556096 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:23:24 T:140678412773136 M:2839556096 DEBUG: thread start, auto delete: 0 20:23:24 T:140678412773136 M:2839420928 NOTICE: Creating InputStream 20:23:24 T:140678412773136 M:2838970368 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 20:23:24 T:140678412773136 M:2838970368 DEBUG: CacheSubtitles: START 20:23:24 T:140678412773136 M:2838970368 DEBUG: CacheSubtitles: Checking for common subdirs... 20:23:24 T:140679204898752 M:2838249472 DEBUG: ------ Window Init (DialogBusy.xml) ------ 20:23:24 T:140678412773136 M:2832396288 DEBUG: CacheSubtitles: Done (time: 584 ms) 20:23:24 T:140678412773136 M:2832269312 DEBUG: CacheSubtitles: Searching for subtitles... 20:23:25 T:140678412773136 M:2829983744 DEBUG: CacheSubtitles: Done (time: 162 ms) 20:23:25 T:140678412773136 M:2829594624 DEBUG: CacheSubtitles: END (total time: 781 ms) 20:23:25 T:140678412773136 M:2829594624 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 20:23:25 T:140678412773136 M:2829594624 NOTICE: Creating Demuxer 20:23:25 T:140678412773136 M:2829594624 DEBUG: DllAvUtilBase: Using libavutil system library 20:23:25 T:140678412773136 M:2829594624 DEBUG: DllAvCodec: Using libavcodec system library 20:23:25 T:140678412773136 M:2829594624 DEBUG: DllAvFormat: Using libavformat system library 20:23:51 T:140678691350288 M:2522947584 DEBUG: Thread 140678691350288 terminating (autodelete) 20:23:51 T:140678446335760 M:2520797184 DEBUG: Thread 140678446335760 terminating (autodelete) 20:30:05 T:140678421165840 M:735997952 WARNING: could not find codec parameters for /mnt/ssh.drive.0/movies/M-N-O/Operation.Endgame.2010.720p.bluray.x264.dts-wiki.mkv 20:30:05 T:140678412773136 M:2887548928 DEBUG: Open - probing detected format [matroska] 20:30:06 T:140678421165840 M:2887655424 DEBUG: DoWork - trying to extract filestream details from video file /mnt/ssh.drive.0/movies/0-9/300.2006.1080p.BluRay.DTS.x264-CtrlHD.mkv 20:30:06 T:140678421165840 M:2887659520 DEBUG: DllAvUtilBase: Using libavutil system library 20:30:06 T:140678421165840 M:2887659520 DEBUG: DllAvCodec: Using libavcodec system library 20:30:06 T:140678421165840 M:2887548928 DEBUG: DllAvFormat: Using libavformat system library 20:30:06 T:140678421165840 M:2887548928 DEBUG: Open - probing detected format [matroska] 20:30:06 T:140678421165840 M:2887712768 ERROR: Open - Error, could not open file /mnt/ssh.drive.0/movies/0-9/300.2006.1080p.BluRay.DTS.x264-CtrlHD.mkv 20:30:06 T:140678412773136 M:2888163328 DEBUG: Open - av_find_stream_info starting 20:30:07 T:140678412773136 M:2888019968 DEBUG: ffmpeg[3ED22710]: [matroska] max_analyze_duration reached 20:30:07 T:140678412773136 M:2888019968 DEBUG: ffmpeg[3ED22710]: [matroska] Estimating duration from bitrate, this may be inaccurate 20:30:07 T:140678412773136 M:2888019968 DEBUG: Open - av_find_stream_info finished 20:30:07 T:140678412773136 M:2888019968 INFO: ffmpeg[3ED22710]: Input #0, matroska, from '/mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv': 20:30:07 T:140678412773136 M:2888019968 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888019968 INFO: ffmpeg[3ED22710]: doctype : matroska 20:30:07 T:140678412773136 M:2888019968 INFO: ffmpeg[3ED22710]: Duration: 02:04:39.16, start: 0.000000, bitrate: 640 kb/s 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Chapter #0.0: start 0.000000, end 304.304000 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: title : 00:00:00.000 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Chapter #0.1: start 304.304000, end 667.584000 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: title : 00:05:04.304 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Chapter #0.2: start 667.584000, end 1079.954000 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: title : 00:11:07.584 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Chapter #0.3: start 1079.954000, end 1553.010000 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: title : 00:17:59.954 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Chapter #0.4: start 1553.010000, end 1826.783000 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: title : 00:25:53.010 20:30:07 T:140678412773136 M:2888007680 INFO: ffmpeg[3ED22710]: Chapter #0.5: start 1826.783000, end 2253.001000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 00:30:26.783 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.6: start 2253.001000, end 2639.637000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 00:37:33.001 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.7: start 2639.637000, end 3099.346000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 00:43:59.637 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.8: start 3099.346000, end 3610.523000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 00:51:39.346 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.9: start 3610.523000, end 4021.517000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:00:10.523 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.10: start 4021.517000, end 4560.764000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:07:01.517 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.11: start 4560.764000, end 4873.285000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:16:00.764 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.12: start 4873.285000, end 5433.553000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:21:13.285 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.13: start 5433.553000, end 5929.340000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:30:33.553 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.14: start 5929.340000, end 6341.794000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:38:49.340 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.15: start 6341.794000, end 6857.350000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:45:41.794 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Chapter #0.16: start 6857.350000, end 7479.168000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : 01:54:17.350 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.0(eng): Video: h264, yuv420p, 1280x544, PAR 1:1 DAR 40:17, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : x264_L4.1 @ 6810 Kbps 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.1(eng): Audio: ac3, 48000 Hz, 5.1, s16, 640 kb/s 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : English Dolby Digital 5.1 @ 640 Kbps 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.2(eng): Subtitle: 0x0000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : English 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.3(chi): Subtitle: 0x0000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : Chinese Simplified 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.4(chi): Subtitle: 0x0000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : Chinese Traditional 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.5(eng): Audio: vorbis, 48000 Hz, stereo, s16 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : English Commetary Vorbis 2.0 @ 80 Kbps 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.6(eng): Subtitle: 0x0000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: title : English Commentary 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Stream #0.7: Attachment: 0x0000 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: Metadata: 20:30:07 T:140678412773136 M:2888159232 INFO: ffmpeg[3ED22710]: filename : MHeiHKS-Medium.otf 20:30:07 T:140678412773136 M:2887921664 NOTICE: Opening video stream: 0 source: 256 20:30:07 T:140678412773136 M:2887921664 NOTICE: Creating video codec with codec id: 28 20:30:07 T:140678412773136 M:2887921664 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:no VAAPI:no 20:30:07 T:140678412773136 M:2887921664 INFO: Trying Broadcom Crystal HD Decoder... 20:30:07 T:140678412773136 M:2887921664 DEBUG: FactoryCodec - Video: - Opening 20:30:07 T:140678412773136 M:2887921664 DEBUG: CrystalHD: profile 640029 20:30:07 T:140678412773136 M:2887921664 DEBUG: CrystalHD: nal size 4 20:30:07 T:140678412773136 M:2887921664 DEBUG: CrystalHD: num sps 1 20:30:07 T:140678412773136 M:2887921664 DEBUG: CrystalHD: data size at end = 0 20:30:07 T:140678412773136 M:2888073216 DEBUG: DllSwScale: Using libswscale system library 20:30:07 T:140678412773136 M:2888073216 INFO: DVDVideoCodecCrystalHD: Opened Broadcom Crystal HD Codec 20:30:07 T:140678412773136 M:2888073216 DEBUG: FactoryCodec - Video: chd-h264 - Opened 20:30:07 T:140678446335760 M:2888073216 DEBUG: thread start, auto delete: 0 20:30:07 T:140678446335760 M:2888073216 DEBUG: CrystalHD: Output Thread Started... 20:30:07 T:140678412773136 M:2888073216 NOTICE: Creating video thread 20:30:07 T:140678412773136 M:2888073216 NOTICE: Opening audio stream: 1 source: 256 20:30:07 T:140678691350288 M:2888073216 DEBUG: thread start, auto delete: 0 20:30:07 T:140678412773136 M:2888073216 NOTICE: Finding audio codec for: 86019 20:30:07 T:140678412773136 M:2888224768 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 20:30:07 T:140678412773136 M:2888224768 DEBUG: DllAvUtilBase: Using libavutil system library 20:30:07 T:140678412773136 M:2888224768 DEBUG: DllAvCodec: Using libavcodec system library 20:30:07 T:140678412773136 M:2888028160 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 20:30:07 T:140678412773136 M:2888052736 NOTICE: Creating audio thread 20:30:07 T:140678412773136 M:2887925760 NOTICE: Opening Subtitle stream: 2 source: 256 20:30:07 T:140678437943056 M:2887925760 DEBUG: thread start, auto delete: 0 20:30:07 T:140678437943056 M:2887925760 NOTICE: running thread: CDVDPlayerAudio::Process() 20:30:07 T:140678412773136 M:2887925760 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 20:30:07 T:140678412773136 M:2887925760 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 20:30:07 T:140678412773136 M:2887925760 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv 20:30:07 T:140678412773136 M:2888040448 DEBUG: CDVDPlayer::SetCaching - caching state 2 20:30:07 T:140678691350288 M:2888040448 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:30:07 T:140678691350288 M:2888040448 DEBUG: DllAvUtilBase: Using libavutil system library 20:30:07 T:140678691350288 M:2888040448 DEBUG: DllAvCodec: Using libavcodec system library 20:30:07 T:140678691350288 M:2888040448 DEBUG: DllSwScale: Using libswscale system library 20:30:07 T:140678691350288 M:2888040448 NOTICE: running thread: video_thread 20:30:07 T:140678691350288 M:2888040448 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 20:30:07 T:140678437943056 M:2888040448 NOTICE: Creating audio device with codec id: 86019, channels: 6, sample rate: 48000, no pass-through 20:30:07 T:140678412773136 M:2888065024 DEBUG: CheckPlayerInit - dropping packet type:2 dts:83000.000000 to get to start point at 167000.000000 20:30:07 T:140678412773136 M:2888065024 DEBUG: CheckPlayerInit - dropping packet type:2 dts:42000.000000 to get to start point at 167000.000000 20:30:07 T:140678412773136 M:2888065024 DEBUG: CheckPlayerInit - dropping packet type:2 dts:125000.000000 to get to start point at 167000.000000 20:30:07 T:140678691350288 M:2888065024 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(167000.000000, 0) 20:30:07 T:140679204898752 M:2888028160 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc 20:30:07 T:140678446335760 M:2887995392 DEBUG: CrystalHD: Format Change Detected. Flags: 0x00030000 20:30:07 T:140678446335760 M:2887995392 DEBUG: ---------------------------------- 20:30:07 T:140678446335760 M:2887995392 DEBUG: TimeStamp: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: Picture Number: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: Width: 1280 20:30:07 T:140678446335760 M:2887995392 DEBUG: Height: 544 20:30:07 T:140678446335760 M:2887995392 DEBUG: Chroma: 0x420 20:30:07 T:140678446335760 M:2887995392 DEBUG: Pulldown: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: Flags: 0x80040000 20:30:07 T:140678446335760 M:2887995392 DEBUG: Frame Rate/Res: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: Aspect Ratio: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: Color Primaries: 2 20:30:07 T:140678446335760 M:2887995392 DEBUG: MetaData: 709131 20:30:07 T:140678446335760 M:2887995392 DEBUG: Session Number: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: TimeStamp: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: Custom Aspect: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: Frames to Drop: 0 20:30:07 T:140678446335760 M:2887995392 DEBUG: H264 Valid Fields: 0x00000000 20:30:07 T:140678446335760 M:2887995392 DEBUG: CrystalHD: dec_par x = 1, dec_par y = 1 20:30:07 T:140678446335760 M:2887995392 DEBUG: CrystalHD: resolution = 0 interlace = 0 20:30:07 T:140678691350288 M:2887995392 NOTICE: fps: 23.976025, pwidth: 1280, pheight: 544, dwidth: 1280, dheight: 544 20:30:07 T:140678691350288 M:2887995392 DEBUG: OutputPicture - change configuration. 1280x544. framerate: 23.98. format: NV12 20:30:08 T:140678437943056 M:2887995392 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 20:30:08 T:140678437943056 M:2888146944 INFO: CPCMRemap: Configured speaker layout: 2.0 20:30:08 T:140678437943056 M:2887938048 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 20:30:08 T:140678437943056 M:2887938048 DEBUG: CPCMRemap: Forcing side channel map to back channels 20:30:08 T:140678437943056 M:2887938048 INFO: CPCMRemap: O channel map: FL,FR 20:30:08 T:140678437943056 M:2887938048 DEBUG: CPCMRemap: Downmix normalization is disabled 20:30:08 T:140678437943056 M:2887938048 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 20:30:08 T:140678437943056 M:2887938048 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 20:30:08 T:140678437943056 M:2887938048 DEBUG: CALSADirectSound::CALSADirectSound - Requested channels changed from 6 to 2 20:30:08 T:140678437943056 M:2887938048 DEBUG: RemoveActiveDevice - Removing device 1 20:30:08 T:140678437943056 M:2887938048 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 20:30:08 T:140678437943056 M:2887938048 DEBUG: RemoveActiveDevice - Removing device 0 20:30:08 T:140678437943056 M:2887987200 DEBUG: Initialize - using alsa device default 20:30:08 T:140678691350288 M:2888036352 WARNING: CRenderManager::Configure - timeout waiting for previous frame 20:30:08 T:140678691350288 M:2888036352 NOTICE: Display resolution DESKTOP : 1920x1080 (12) 20:30:08 T:140678412773136 M:2888060928 DEBUG: CDVDPlayer::HandleMessages - player started 2 20:30:08 T:140679204898752 M:2885783552 NOTICE: Using GL_TEXTURE_2D 20:30:08 T:140679204898752 M:2885656576 DEBUG: GL: Requested render method: 0 20:30:08 T:140679204898752 M:2883547136 NOTICE: GL: ARB shaders support detected 20:30:08 T:140679204898752 M:2883489792 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:30:08 T:140679204898752 M:2883489792 NOTICE: GL: No vertex shader, fixed pipeline in use 20:30:09 T:140679204898752 M:2881056768 DEBUG: GL: Disabling non-linear stretch 20:30:09 T:140679204898752 M:2880929792 NOTICE: GL: NPOT texture support detected 20:30:09 T:140679204898752 M:2880929792 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:30:09 T:140678437943056 M:2880544768 DEBUG: CALSADirectSound::Initialize - packet size:4096, packet count:8, buffer size:8192 20:30:09 T:140678437943056 M:2880544768 DEBUG: CALSADirectSound::Initialize - Channels: 6 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough false - audioDevice: default 20:30:09 T:140678437943056 M:2880544768 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 20:30:09 T:140678412773136 M:2878836736 DEBUG: CDVDPlayer::HandleMessages - player started 1 20:30:09 T:140678412773136 M:2878709760 DEBUG: CDVDPlayer::SetCaching - caching state 3 20:30:09 T:140678412773136 M:2878709760 DEBUG: CDVDPlayer::SetCaching - caching state 0 20:30:09 T:140678437943056 M:2878758912 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1) 20:30:09 T:140678437943056 M:2878758912 DEBUG: CDVDPlayerAudio:: Discontinuity - was:888.462000, should be:0.000000, error:-888.462000 20:30:09 T:140679204898752 M:2878656512 DEBUG: CGUIInfoManager::SetCurrentMovie(/mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv) 20:30:09 T:140679204898752 M:2878402560 DEBUG: Activating window ID: 12005 20:30:09 T:140679204898752 M:2878275584 DEBUG: Checking if window ID 12005 is locked. 20:30:09 T:140679204898752 M:2875478016 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 20:30:09 T:140679204898752 M:2870415360 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 20:30:09 T:140679204898752 M:2870288384 INFO: Loading skin file: VideoFullScreen.xml 20:30:09 T:140679204898752 M:2853953536 DEBUG: SECTION:UnloadDelayed(DLL: libmad.so.0) 20:30:09 T:140679204898752 M:2853826560 DEBUG: Unloading: libmad.so.0 20:30:09 T:140679204898752 M:2853707776 DEBUG: GL: Requested render method: 0 20:30:09 T:140679204898752 M:2853707776 NOTICE: GL: ARB shaders support detected 20:30:09 T:140679204898752 M:2853707776 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader 20:30:09 T:140679204898752 M:2853707776 NOTICE: GL: No vertex shader, fixed pipeline in use 20:30:09 T:140679204898752 M:2853707776 NOTICE: GL: NPOT texture support detected 20:30:09 T:140679204898752 M:2853707776 NOTICE: GL: Using GL_ARB_pixel_buffer_object 20:30:09 T:140679204898752 M:2853683200 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 20:30:09 T:140679204898752 M:2853564416 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 20:30:09 T:140679204898752 M:2853564416 DEBUG: Unloading: ImageLib-x86_64-linux.so 20:30:16 T:140678691350288 M:2850312192 DEBUG: CPullupCorrection: detected pattern of length 24: 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 20:30:36 T:140678421165840 M:2841726976 DEBUG: Thread 140678421165840 terminating (autodelete) 20:30:37 T:140679204898752 M:2841907200 DEBUG: SECTION:UnloadDelayed(SECTION: LCODE) 20:31:01 T:140678691350288 M:2841874432 DEBUG: CPullupCorrection: pattern lost 20:31:03 T:140678691350288 M:2840330240 DEBUG: CPullupCorrection: detected pattern of length 24: 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 41000.00 42000.00 42000.00 42000.00 41000.00 42000.00 42000.00 20:31:13 T:140679204898752 M:2841862144 DEBUG: SDLKeyboard: scancode: 53, sym: 120, unicode: 120, modifier: 0 20:31:13 T:140679204898752 M:2841886720 DEBUG: OnKey: 61528 pressed, action is Stop 20:31:13 T:140679204898752 M:2841886720 NOTICE: CDVDPlayer::CloseFile() 20:31:13 T:140678412773136 M:2841886720 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc 20:31:13 T:140679204898752 M:2841886720 NOTICE: DVDPlayer: waiting for threads to exit 20:31:13 T:140678412773136 M:2841886720 NOTICE: CDVDPlayer::OnExit() 20:31:13 T:140678412773136 M:2841886720 NOTICE: DVDPlayer: closing audio stream 20:31:13 T:140678412773136 M:2841886720 NOTICE: Closing audio stream 20:31:13 T:140678412773136 M:2841886720 NOTICE: Waiting for audio thread to exit 20:31:13 T:140678437943056 M:2841886720 DEBUG: CDVDPlayerAudio:: Discontinuity - was:-9003436.903000, should be:64786850.805302, error:73790287.708302 20:31:13 T:140678437943056 M:2841886720 NOTICE: thread end: CDVDPlayerAudio::OnExit() 20:31:13 T:140678437943056 M:2841886720 DEBUG: Thread 140678437943056 terminating 20:31:13 T:140678412773136 M:2841886720 NOTICE: Closing audio device 20:31:13 T:140678412773136 M:2841886720 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 20:31:13 T:140678412773136 M:2841886720 DEBUG: RemoveActiveDevice - Removing device 2 20:31:13 T:140678412773136 M:2841886720 NOTICE: Deleting audio codec 20:31:13 T:140678412773136 M:2841886720 NOTICE: DVDPlayer: closing video stream 20:31:13 T:140678412773136 M:2841886720 NOTICE: Closing video stream 20:31:13 T:140678412773136 M:2841886720 NOTICE: waiting for video thread to exit 20:31:13 T:140678691350288 M:2841886720 NOTICE: thread end: video_thread 20:31:13 T:140678691350288 M:2841886720 DEBUG: Thread 140678691350288 terminating 20:31:13 T:140678412773136 M:2841886720 NOTICE: deleting video codec 20:31:13 T:140678446335760 M:2841886720 DEBUG: CrystalHD: Output Thread Stopped... 20:31:13 T:140678446335760 M:2841886720 DEBUG: Thread 140678446335760 terminating 20:31:14 T:140678412773136 M:2859683840 NOTICE: DVDPlayer: closing subtitle stream 20:31:14 T:140678412773136 M:2859683840 NOTICE: Closing subtitle stream 20:31:14 T:140678412773136 M:2859683840 NOTICE: CDVDPlayer::OnExit() deleting demuxer 20:31:14 T:140678412773136 M:2859683840 NOTICE: CDVDPlayer::OnExit() deleting input stream 20:31:14 T:140678412773136 M:2859683840 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc 20:31:14 T:140678412773136 M:2859708416 DEBUG: Thread 140678412773136 terminating 20:31:14 T:140679204898752 M:2859835392 NOTICE: DVDPlayer: finished waiting 20:31:14 T:140679204898752 M:2859835392 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:31:14 T:140679204898752 M:2859835392 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:31:14 T:140679204898752 M:2859835392 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 20:31:14 T:140679204898752 M:2859732992 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:31:14 T:140679204898752 M:2860060672 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 20:31:14 T:140679204898752 M:2860060672 INFO: Loading skin file: MyVideoNav.xml 20:31:14 T:140679204898752 M:2859888640 DEBUG: CGUIMediaWindow::GetDirectory (videodb://4/) 20:31:14 T:140679204898752 M:2859888640 DEBUG: ParentPath = [videodb://4/] 20:31:14 T:140679204898752 M:2859888640 DEBUG: GetMoviesByWhere query: select * from movieview order by idMovie desc limit 200 20:31:14 T:140679204898752 M:2859888640 DEBUG: Time for actual SQL query = 7 20:31:14 T:140679204898752 M:2859888640 DEBUG: Time to retrieve movies from dataset = 50 20:31:14 T:140679204898752 M:2859888640 DEBUG: Saving fileitems [videodb://4/] 20:31:14 T:140679204898752 M:2860240896 DEBUG: -- items: 201, sort method: 0, ascending: false 20:31:14 T:140678601766672 M:2860240896 DEBUG: thread start, auto delete: 0 20:31:14 T:140678412773136 M:2855780352 DEBUG: thread start, auto delete: 1 20:31:14 T:140678412773136 M:2855780352 DEBUG: Loading: /usr/lib64/xbmc/system/ImageLib-x86_64-linux.so 20:31:14 T:140678446335760 M:2857172992 DEBUG: thread start, auto delete: 1 20:31:14 T:140679204898752 M:2856521728 NOTICE: CDVDPlayer::CloseFile() 20:31:14 T:140679204898752 M:2856849408 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 20:31:14 T:140679204898752 M:2856849408 NOTICE: DVDPlayer: waiting for threads to exit 20:31:14 T:140679204898752 M:2856849408 NOTICE: DVDPlayer: finished waiting 20:31:14 T:140679204898752 M:2856849408 DEBUG: LinuxRendererGL: Cleaning up GL resources 20:31:14 T:140678691350288 M:2856849408 DEBUG: thread start, auto delete: 1 20:31:15 T:140678412773136 M:2856808448 DEBUG: DoWork - took 653 ms to load special://skin/backgrounds/videos.jpg 20:31:15 T:140678446335760 M:2856853504 DEBUG: DoWork - took 224 ms to load special://masterprofile/Thumbnails/8/89c76e3b.jpg 20:31:15 T:140678691350288 M:2856554496 DEBUG: DoWork - took 392 ms to load special://masterprofile/Thumbnails/Video/Fanart/6fcdf294.tbn 20:31:15 T:140678446335760 M:2851328000 DEBUG: DoWork - Saving file state for video item /mnt/ssh.drive.0/movies/G-H-I/Iron.Man.2.2010.720p.BluRay.x264-WiKi.mkv 20:31:18 T:140678691350288 M:2855964672 DEBUG: DoWork - trying to extract filestream details from video file /mnt/ssh.drive.0/movies/M-N-O/Operation.Endgame.2010.720p.bluray.x264.dts-wiki.mkv 20:31:18 T:140678691350288 M:2855964672 DEBUG: DllAvUtilBase: Using libavutil system library 20:31:18 T:140678691350288 M:2855964672 DEBUG: DllAvCodec: Using libavcodec system library 20:31:18 T:140678691350288 M:2855964672 DEBUG: DllAvFormat: Using libavformat system library 20:31:18 T:140678691350288 M:2855964672 DEBUG: Open - probing detected format [mp3] 20:31:18 T:140678691350288 M:2855964672 DEBUG: Open - av_find_stream_info starting 20:31:21 T:140678601766672 M:2821607424 DEBUG: Thread 140678601766672 terminating 20:31:45 T:140678412773136 M:2543800320 DEBUG: Thread 140678412773136 terminating (autodelete) 20:31:45 T:140678446335760 M:2542575616 DEBUG: Thread 140678446335760 terminating (autodelete) 20:31:45 T:140679204898752 M:2541342720 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 20:31:45 T:140679204898752 M:2541342720 DEBUG: Unloading: ImageLib-x86_64-linux.so 20:34:13 T:140679204898752 M:820199424 DEBUG: Activating window ID: 12900 20:34:13 T:140679204898752 M:820064256 DEBUG: Checking if window ID 12900 is locked. 20:34:14 T:140679204898752 M:819761152 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 20:34:14 T:140679204898752 M:816730112 DEBUG: ------ Window Init () ------ 20:34:14 T:140679204898752 M:816828416 DEBUG: ADDON: Dll Initializing - Euphoria 20:34:14 T:140679204898752 M:815439872 DEBUG: Loading: /usr/lib64/xbmc/addons/screensaver.rsxs.euphoria/Euphoria.xbs 20:36:14 T:140679204898752 M:706068480 INFO: DPMS: enabled power-saving mode SUSPEND 20:36:14 T:140679204898752 M:705708032 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 20:36:14 T:140679204898752 M:705765376 INFO: ADDON: Dll Stopped - Euphoria 20:36:14 T:140679204898752 M:705765376 DEBUG: SECTION:UnloadDll(/usr/lib64/xbmc/addons/screensaver.rsxs.euphoria/Euphoria.xbs) 20:36:14 T:140679204898752 M:705728512 DEBUG: Unloading: Euphoria.xbs 20:36:14 T:140679204898752 M:705912832 INFO: ADDON: Dll Destroyed - Euphoria 20:36:14 T:140679204898752 M:705515520 DEBUG: ------ Window Deinit () ------ 20:36:14 T:140679204898752 M:705515520 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 20:36:15 T:140679204898752 M:705875968 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 20:36:15 T:140679204898752 M:705875968 INFO: Loading skin file: MyVideoNav.xml 20:36:15 T:140679204898752 M:706052096 DEBUG: CGUIMediaWindow::GetDirectory (videodb://4/) 20:36:15 T:140679204898752 M:706052096 DEBUG: ParentPath = [videodb://4/] 20:36:15 T:140679204898752 M:706052096 DEBUG: GetMoviesByWhere query: select * from movieview order by idMovie desc limit 200 20:36:15 T:140679204898752 M:705425408 DEBUG: Time for actual SQL query = 8 20:36:15 T:140679204898752 M:705773568 DEBUG: Time to retrieve movies from dataset = 67 20:36:15 T:140679204898752 M:705445888 DEBUG: Saving fileitems [videodb://4/] 20:36:15 T:140679204898752 M:705945600 DEBUG: -- items: 201, sort method: 0, ascending: false 20:36:15 T:140678601766672 M:705843200 DEBUG: thread start, auto delete: 0 20:36:16 T:140678446335760 M:705486848 DEBUG: thread start, auto delete: 1 20:36:16 T:140678446335760 M:705040384 DEBUG: Loading: /usr/lib64/xbmc/system/ImageLib-x86_64-linux.so 20:36:16 T:140678412773136 M:705593344 DEBUG: thread start, auto delete: 1 20:36:17 T:140678446335760 M:699760640 DEBUG: Previous line repeats 1 times. 20:36:17 T:140678446335760 M:699760640 DEBUG: DoWork - took 1200 ms to load special://skin/backgrounds/videos.jpg 20:36:17 T:140678412773136 M:697970688 DEBUG: DoWork - took 217 ms to load special://masterprofile/Thumbnails/8/89c76e3b.jpg 20:36:17 T:140678652086032 M:684036096 DEBUG: DoWork - took 1076 ms to load special://masterprofile/Thumbnails/Video/Fanart/6fcdf294.tbn 20:36:23 T:140678601766672 M:704380928 DEBUG: Thread 140678601766672 terminating 20:36:47 T:140678652086032 M:704016384 DEBUG: Thread 140678652086032 terminating (autodelete) 20:36:47 T:140678446335760 M:704114688 DEBUG: Thread 140678446335760 terminating (autodelete) 20:36:47 T:140678412773136 M:703987712 DEBUG: Thread 140678412773136 terminating (autodelete) 20:36:48 T:140679204898752 M:704028672 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 20:36:48 T:140679204898752 M:704028672 DEBUG: Unloading: ImageLib-x86_64-linux.so