18:13:55 T:3006991104 NOTICE: ----------------------------------------------------------------------- 18:13:55 T:3006991104 NOTICE: Starting XBMC (11.0 Git:Unknown), Platform: Linux (Ubuntu 12.04 LTS, 3.2.0-23-generic-pae i686). Built on Sep 14 2012 18:13:55 T:3006991104 NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc 18:13:55 T:3006991104 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc 18:13:55 T:3006991104 NOTICE: special://masterprofile/ is mapped to: /home/xbmc/.xbmc/userdata 18:13:55 T:3006991104 NOTICE: special://home/ is mapped to: /home/xbmc/.xbmc 18:13:55 T:3006991104 NOTICE: special://temp/ is mapped to: /home/xbmc/.xbmc/temp 18:13:55 T:3006991104 NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin 18:13:55 T:3006991104 NOTICE: Local hostname: SCHALLEXBMC02 18:13:55 T:3006991104 NOTICE: Log File is located: /home/xbmc/.xbmc/temp/xbmc.log 18:13:55 T:3006991104 NOTICE: ----------------------------------------------------------------------- 18:13:55 T:3006991104 NOTICE: Setup SDL 18:13:56 T:3006991104 NOTICE: load settings... 18:13:56 T:3006991104 NOTICE: special://profile/ is mapped to: special://masterprofile/ 18:13:56 T:3006991104 NOTICE: loading special://masterprofile/guisettings.xml 18:13:56 T:3006991104 NOTICE: Getting hardware information now... 18:13:56 T:3006991104 NOTICE: Checking resolution 12 18:13:56 T:3006991104 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 18:13:56 T:3006991104 NOTICE: Loaded playercorefactory configuration 18:13:56 T:3006991104 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 18:13:56 T:3006991104 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 18:13:56 T:3006991104 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 18:13:56 T:3006991104 NOTICE: Loaded settings file from special://profile/advancedsettings.xml 18:13:56 T:3006991104 NOTICE: Contents of special://profile/advancedsettings.xml are... true sensors | tail -n 21 | sed -ne "s/temp1: \+[-+]$[0-9]\+$.*/\1 C/p" echo "$(nvidia-settings -tq gpuCoreTemp) C" (.*?)(-trailer)(\.[^.]+)$ true true mysql 192.168.1.200 3306 xbmc xbmc xbmc_video mysql 192.168.1.200 3306 xbmc xbmc xbmc_music 18:13:56 T:3006991104 WARNING: VIDEO database configuration is experimental. 18:13:56 T:3006991104 NOTICE: Getting hardware information now... 18:13:56 T:3006991104 NOTICE: Checking resolution 12 18:13:56 T:3006991104 NOTICE: Default DVD Player: dvdplayer 18:13:56 T:3006991104 NOTICE: Default Video Player: dvdplayer 18:13:56 T:3006991104 NOTICE: Default Audio Player: paplayer 18:13:56 T:3006991104 NOTICE: Disabled debug logging due to GUI setting. Level 0. 18:13:56 T:3006991104 NOTICE: Log level changed to 0 18:13:56 T:3006991104 NOTICE: Loading media sources from special://masterprofile/sources.xml 18:13:56 T:3006991104 ERROR: Missing or invalid and/or in source 18:13:56 T:3006991104 ERROR: Previous line repeats 1 times. 18:13:56 T:3006991104 NOTICE: Register - new nic device registered on usb->/sys/devices/pci0000:00/0000:00:12.1/usb4/4-3: Pulse-Eight CEC Adapter (2548:1001) 18:13:56 T:3006991104 NOTICE: Using visual 0x43 18:13:56 T:3006991104 NOTICE: GL_VENDOR = NVIDIA Corporation 18:13:56 T:3006991104 NOTICE: GL_RENDERER = GeForce GT 520/PCIe/SSE2/3DNOW! 18:13:56 T:3006991104 NOTICE: GL_VERSION = 4.2.0 NVIDIA 295.71 18:13:56 T:3006991104 NOTICE: GL_SHADING_LANGUAGE_VERSION = 4.20 NVIDIA via Cg compiler 18:13:56 T:3006991104 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 1048576 18:13:56 T:3006991104 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 1048576 18:13:56 T:3006991104 NOTICE: GL_EXTENSIONS = GL_ARB_base_instance GL_ARB_blend_func_extended GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_buffers_blend GL_ARB_draw_indirect GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_ES2_compatibility GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_gpu_shader5 GL_ARB_gpu_shader_fp64 GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_provoking_vertex GL_ARB_robustness GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_atomic_counters GL_ARB_shader_bit_encoding GL_ARB_shader_image_load_store GL_ARB_shader_objects GL_ARB_shader_precision GL_ARB_shader_subroutine GL_ARB_shading_language_100 GL_ARB_shading_language_420pack GL_ARB_shading_language_include GL_ARB_shading_language_packing GL_ARB_shadow GL_ARB_sync GL_ARB_tessellation_shader GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_object_rgb32 GL_ARB_texture_compression GL_ARB_texture_compression_bptc GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_cube_map_array GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_gather GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_lod GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_storage GL_ARB_texture_swizzle GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ARB_transform_feedback_instanced GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_attrib_64bit GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_bindable_uniform GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shader_image_load_store GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_format_BGRA8888 GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent GL_EXT_texture_sRGB GL_EXT_texture_sRGB_decode GL_EXT_texture_storage GL_EXT_texture_swizzle GL_EXT_texture_type_2_10_10_10_REV GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_EXT_vertex_attrib_64bit GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KTX_buffer_region GL_NV_alpha_test GL_NV_blend_minmax GL_NV_blend_square GL_NV_complex_primitives GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_explicit_multisample GL_NV_fbo_color_attachments GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragdepth GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_gpu_program4_1 GL_NV_gpu_program5 GL_NV_gpu_program_fp64 GL_NV_gpu_shader5 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_path_rendering GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_atomic_counters GL_NV_shader_buffer_load GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_lod_clamp GL_NV_texture_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_attrib_integer_64bit GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_OES_depth24 GL_OES_depth32 GL_OES_depth_texture GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_get_program_binary GL_OES_mapbuffer GL_OES_packed_depth_stencil GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_float_linear GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_array_object GL_OES_vertex_half_float GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum 18:13:56 T:3006991104 ERROR: GLX: Same window as before, refreshing context 18:13:56 T:3006991104 ERROR: Keymapping error: no such action 'language' defined 18:13:56 T:3006991104 NOTICE: start dvd mediatype detection 18:13:56 T:3006991104 NOTICE: initializing playlistplayer 18:13:56 T:3006991104 NOTICE: DONE initializing playlistplayer 18:13:57 T:3006991104 NOTICE: Start - EPG thread started 18:13:57 T:3006991104 NOTICE: PVRManager - starting up 18:13:57 T:3006991104 ERROR: Python script "script.tv.show.next.aired" does not exist 18:13:57 T:3006991104 NOTICE: UpdateLibraries - Starting video library startup scan 18:13:57 T:2859989824 NOTICE: -->Python Interpreter Initialized<-- 18:13:57 T:3006991104 NOTICE: initialize done 18:13:57 T:3006991104 NOTICE: Running the application... 18:13:57 T:2849909568 NOTICE: VideoInfoScanner: Starting scan .. 18:13:57 T:3006991104 NOTICE: Webserver: Starting... 18:13:57 T:3006991104 NOTICE: WebServer: Started the webserver 18:13:57 T:3006991104 NOTICE: ES: Starting event server 18:13:57 T:2816338752 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 18:13:57 T:2816338752 NOTICE: UDP: Listening on port 9777 18:13:57 T:3006991104 ERROR: JSONRPC Server: Failed to connect to sdpd 18:13:57 T:3006991104 NOTICE: starting zeroconf publishing 18:13:58 T:2841516864 NOTICE: -->Python Interpreter Initialized<-- 18:13:58 T:2841516864 NOTICE: StorageServer Module loaded RUN 18:13:58 T:2841516864 NOTICE: StorageClient-1.1.0 Starting server 18:13:58 T:2841516864 NOTICE: StorageServer-1.1.0 Storage Server starting /home/xbmc/.xbmc/userdata/Database/commoncache.db 18:13:58 T:2868382528 NOTICE: Start - EPG thread started 18:14:01 T:2849909568 NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:04 18:14:08 T:2744113984 NOTICE: -->Python Interpreter Initialized<-- 18:14:16 T:2799553344 WARNING: JpegIO: Error 55: Not a JPEG file: starts with 0x%02x 0x%02x 18:14:32 T:3006991104 NOTICE: Log level changed to 2 18:14:32 T:3006991104 NOTICE: Enabled debug logging due to GUI setting. Level 2. 18:14:32 T:3006991104 DEBUG: ------ Window Init () ------ 18:14:32 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:32 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:33 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:33 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:33 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":0, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:33 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:33 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":-1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:33 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:33 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:33 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":-1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:33 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:33 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:33 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:33 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:33 T:3006991104 DEBUG: OnKey: backspace (f008) pressed, action is Back 18:14:33 T:3006991104 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:14:33 T:3006991104 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 18:14:33 T:3006991104 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:14:33 T:3006991104 DEBUG: ------ Window Init (Settings.xml) ------ 18:14:33 T:3006991104 INFO: Loading skin file: Settings.xml 18:14:34 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:34 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:34 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":0, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:34 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:34 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":-1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:34 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:34 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:34 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":-1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:34 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:34 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:34 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:34 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:34 T:3006991104 DEBUG: OnKey: backspace (f008) pressed, action is Back 18:14:34 T:3006991104 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:14:35 T:3006991104 DEBUG: ------ Window Deinit (Settings.xml) ------ 18:14:35 T:3006991104 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 18:14:35 T:3006991104 DEBUG: ------ Window Init (Home.xml) ------ 18:14:35 T:3006991104 INFO: Loading skin file: Home.xml 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating ClearProperty(DontRunTVShowScripts,Home) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To ClearProperty(DontRunTVShowScripts,Home) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(BGMove,Control.Move(4324,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(BGMove,Control.Move(4324,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: bgmove 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(WidgetMove,Control.Move(8000,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(WidgetMove,Control.Move(8000,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: widgetmove 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(Widget2Move,Control.Move(7000,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(Widget2Move,Control.Move(7000,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: widget2move 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(HomeTileLeft,Control.Move(5001,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(HomeTileLeft,Control.Move(5001,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: hometileleft 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(HomeTileRight,Control.Move(5003,1),00:10,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(HomeTileRight,Control.Move(5003,1),00:10,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: hometileright 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(HomeTileMid,Control.Move(5002,1),00:15,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(HomeTileMid,Control.Move(5002,1),00:15,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: hometilemid 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(HomeTile2Left,Control.Move(6001,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(HomeTile2Left,Control.Move(6001,1),00:07,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: hometile2left 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(HomeTile2Right,Control.Move(6003,1),00:10,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(HomeTile2Right,Control.Move(6003,1),00:10,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: hometile2right 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : Translating AlarmClock(HomeTile2Mid,Control.Move(6002,1),00:15,silent,loop) 18:14:35 T:3006991104 DEBUG: ExecuteXBMCAction : To AlarmClock(HomeTile2Mid,Control.Move(6002,1),00:15,silent,loop) 18:14:35 T:3006991104 DEBUG: started alarm with name: hometile2mid 18:14:35 T:2735721280 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 18:14:35 T:2735721280 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum 18:14:35 T:2735721280 DEBUG: GetRecentlyAddedAlbums query: select * from albumview order by idAlbum desc limit 10 18:14:35 T:2735721280 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 18:14:35 T:2735721280 DEBUG: RunQuery took 1 ms for 10 items query: select * from movieview order by idMovie desc limit 10 18:14:35 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:35 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:35 T:2735721280 DEBUG: RunQuery took 6 ms for 10 items query: select * from episodeview order by idEpisode desc limit 10 18:14:35 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":0, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:35 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:35 T:2735721280 DEBUG: GetMusicVideosByWhere query = select * from musicvideoview order by idMVideo desc limit 10 18:14:35 T:2735721280 DEBUG: GetMusicVideosByWhere time for actual SQL query = 1 18:14:35 T:2735721280 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 18:14:35 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":-1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:35 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:35 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:35 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":-1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:35 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:35 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:35 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:35 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:36 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:36 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:36 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":0, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:36 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:36 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":-1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:36 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:36 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:36 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":-1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:36 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:36 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:36 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:36 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:36 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.getMovieSets", "params": {"properties": ["fanart", "thumbnail", "playcount","title"], "sort": { "method": "sorttitle", "order": "ascending", "ignorearticle": true }}, "id": 1} 18:14:36 T:2833124160 DEBUG: JSONRPC: Calling videolibrary.getmoviesets 18:14:36 T:2833124160 DEBUG: RunQuery took 1 ms for 0 items query: SELECT sets.idSet,sets.strSet,COUNT(1) AS c,count(files.playCount) FROM sets JOIN setlinkmovie ON sets.idSet=setlinkmovie.idSet JOIN movie ON setlinkmovie.idMovie=movie.idMovie JOIN files ON files.idFile=movie.idFile GROUP BY sets.idSet HAVING c>1 18:14:36 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetMovies", "params": {"properties": ["playcount", "year", "rating", "genre", "trailer", "tagline", "plot", "fanart", "thumbnail","streamdetails","sorttitle","title"] }, "id": 1} 18:14:36 T:2833124160 DEBUG: JSONRPC: Calling videolibrary.getmovies 18:14:36 T:2833124160 DEBUG: RunQuery took 6 ms for 99 items query: select * from movieview 18:14:37 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetRecentlyAddedMovies", "id": 1} 18:14:37 T:2833124160 DEBUG: JSONRPC: Calling videolibrary.getrecentlyaddedmovies 18:14:37 T:2833124160 DEBUG: RunQuery took 2 ms for 25 items query: select * from movieview order by idMovie desc limit 25 18:14:37 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "XBMC.GetInfoLabels", "params": {"labels": ["System.ProfileName"]}, "id": 1} 18:14:37 T:2833124160 DEBUG: JSONRPC: Calling xbmc.getinfolabels 18:14:37 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:37 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:37 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:37 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:38 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:38 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:38 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:38 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:40 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:40 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:40 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:40 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:40 T:2939337536 DEBUG: CecLogMessage - sending ping 18:14:40 T:2983197504 DEBUG: CecLogMessage - command 'PING' sent 18:14:40 T:2974804800 DEBUG: CecLogMessage - PING - command accepted 18:14:41 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:41 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:41 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:41 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:43 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:43 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:43 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "XBMC.GetInfoLabels", "params": {"labels": ["System.ProfileName"]}, "id": 1} 18:14:43 T:2833124160 DEBUG: JSONRPC: Calling xbmc.getinfolabels 18:14:43 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:43 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:43 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:43 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:43 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetMovieDetails", "params": {"movieid": 453, "properties": ["imdbnumber", "top250","resume","runtime","director","streamdetails" ,"tagline"] }, "id": 1} 18:14:43 T:2833124160 DEBUG: JSONRPC: Calling videolibrary.getmoviedetails 18:14:45 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["position"]}, "id": 1} 18:14:45 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:45 T:3006991104 DEBUG: OnKey: at (f040) pressed, action is 18:14:45 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Playlist.Clear", "params": {"playlistid": 1}, "id": 1} 18:14:45 T:2833124160 DEBUG: JSONRPC: Calling playlist.clear 18:14:45 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:45 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:45 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:45 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:45 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Playlist.Add", "params": {"playlistid": 1, "item": {"movieid": 453} }, "id": 1} 18:14:45 T:2833124160 DEBUG: JSONRPC: Calling playlist.add 18:14:45 T:2833124160 DEBUG: JSONRPC: Missing property "file" in type 18:14:45 T:2833124160 DEBUG: JSONRPC: Missing property "directory" in type 18:14:45 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.Open", "params": {"item":{"playlistid":1, "position":0} }, "id": 1} 18:14:45 T:2833124160 DEBUG: JSONRPC: Calling player.open 18:14:45 T:3006991104 DEBUG: CPlayerCoreFactory::GetPlayers(ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/X-Men.mkv) 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 18:14:45 T:3006991104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 18:14:45 T:3006991104 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 18:14:45 T:3006991104 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 18:14:45 T:3006991104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 18:14:45 T:3006991104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 18:14:45 T:3006991104 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 18:14:45 T:3006991104 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 18:14:45 T:3006991104 DEBUG: CGUIAudioManager::DeInitialize 18:14:45 T:3006991104 NOTICE: DVDPlayer: Opening: ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/X-Men.mkv 18:14:45 T:3006991104 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 18:14:45 T:3006991104 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 18:14:45 T:3006991104 DEBUG: LinuxRendererGL: Cleaning up GL resources 18:14:45 T:3006991104 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-50-i486-linux.so) 18:14:45 T:3006991104 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avutil-50-i486-linux.so 18:14:45 T:3006991104 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-0-i486-linux.so) 18:14:45 T:3006991104 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swscale-0-i486-linux.so 18:14:45 T:2885176128 DEBUG: Thread CDVDPlayer start, auto delete: 0 18:14:45 T:2885176128 NOTICE: Creating InputStream 18:14:45 T:2885176128 DEBUG: CFileCache::Open - opening using cache 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xa4b03a48) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/X%2dMen%2emkv 18:14:45 T:2885176128 INFO: easy_aquire - Created session to ftp://192.168.1.200 18:14:45 T:2744113984 DEBUG: Thread XFILE::CFileCache start, auto delete: 0 18:14:45 T:2885176128 INFO: easy_aquire - Created session to ftp://192.168.1.200 18:14:45 T:2885176128 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd1/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd1/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd2/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd2/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd3/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd3/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd4/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd4/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd5/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd5/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd6/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd6/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd7/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd7/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd8/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd8/ 18:14:45 T:2885176128 DEBUG: FileCurl::Open(0xabf83b10) ftp://administrator:**Password**@192.168.1.200:21/DATA%5f02/X%2dMen/cd9/ 18:14:45 T:2885176128 WARNING: FillBuffer: curl failed with code 9 18:14:45 T:2885176128 ERROR: CFileCurl::CReadState::Open, didn't get any data from stream. 18:14:45 T:2885176128 ERROR: GetDirectory - Error getting ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/cd9/ 18:14:45 T:2885176128 DEBUG: ScanForExternalSubtitles: END (total time: 39 ms) 18:14:45 T:2885176128 NOTICE: Creating Demuxer 18:14:45 T:2885176128 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcore-0-i486-linux.so) 18:14:45 T:2885176128 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcore-0-i486-linux.so 18:14:45 T:2885176128 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-52-i486-linux.so) 18:14:45 T:2885176128 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so 18:14:45 T:3006991104 DEBUG: ------ Window Init (DialogBusy.xml) ------ 18:14:45 T:2885176128 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-52-i486-linux.so) 18:14:45 T:2885176128 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avformat-52-i486-linux.so 18:14:45 T:2885176128 DEBUG: Open - probing detected format [matroska,webm] 18:14:45 T:2744113984 DEBUG: Process, request seek on source to 10053653828 18:14:45 T:2744113984 DEBUG: Process, request seek on source to 7849 18:14:45 T:2885176128 DEBUG: Open - av_find_stream_info starting 18:14:45 T:2885176128 DEBUG: Open - av_find_stream_info finished 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Input #0, matroska,webm, from 'ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/X-Men.mkv': 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : X-Men@PHD 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Duration: 01:44:20.30, start: 0.000000, bitrate: 12847 kb/s 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.0: start 0.000000, end 79.288000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:00:00.000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.1: start 79.288000, end 258.133000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:01:19.288 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.2: start 258.133000, end 356.022000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:04:18.133 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.3: start 356.022000, end 476.059000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:05:56.022 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.4: start 476.059000, end 563.688000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:07:56.059 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.5: start 563.688000, end 697.697000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:09:23.688 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.6: start 697.697000, end 878.503000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:11:37.697 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.7: start 878.503000, end 1081.664000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:14:38.503 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.8: start 1081.664000, end 1249.665000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:18:01.664 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.9: start 1249.665000, end 1329.119000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:20:49.665 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.10: start 1329.119000, end 1438.646000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:22:09.119 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.11: start 1438.646000, end 1725.849000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:23:58.646 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.12: start 1725.849000, end 1795.168000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:28:45.849 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.13: start 1795.168000, end 1926.508000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:29:55.168 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.14: start 1926.508000, end 2017.682000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:32:06.508 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.15: start 2017.682000, end 2197.237000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:33:37.682 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.16: start 2197.237000, end 2305.178000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:36:37.237 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.17: start 2305.178000, end 2471.886000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:38:25.178 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.18: start 2471.886000, end 2653.860000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:41:11.886 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.19: start 2653.860000, end 2750.456000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:44:13.860 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.20: start 2750.456000, end 2921.544000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:45:50.456 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.21: start 2921.544000, end 3022.436000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:48:41.544 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.22: start 3022.436000, end 3125.831000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:50:22.436 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.23: start 3125.831000, end 3298.212000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:52:05.831 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.24: start 3298.212000, end 3491.154000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:54:58.212 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.25: start 3491.154000, end 3559.765000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:58:11.154 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.26: start 3559.765000, end 3714.961000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 00:59:19.765 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.27: start 3714.961000, end 3797.627000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:01:54.961 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.28: start 3797.627000, end 3962.375000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:03:17.627 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.29: start 3962.375000, end 4119.782000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:06:02.375 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.30: start 4119.782000, end 4341.379000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:08:39.782 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.31: start 4341.379000, end 4683.804000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:12:21.379 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.32: start 4683.804000, end 4853.223000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:18:03.804 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.33: start 4853.223000, end 5068.063000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:20:53.223 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.34: start 5068.063000, end 5218.422000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:24:28.063 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.35: start 5218.422000, end 5348.510000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:26:58.422 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.36: start 5348.510000, end 5507.210000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:29:08.510 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.37: start 5507.210000, end 5592.879000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:31:47.210 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.38: start 5592.879000, end 5712.123000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:33:12.879 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Chapter #0.39: start 5712.123000, end 6260.306000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : 01:35:12.123 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Stream #0.0(eng): Video: h264 (High), yuv420p, 1920x818 [PAR 1:1 DAR 960:409], 23.98 fps, 24 tbr, 1k tbn, 47.95 tbc 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : x264-crf18@PHD 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Stream #0.1(ger): Audio: dca (DTS), 48000 Hz, 5.1, s16, 768 kb/s 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : GER - DTS 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Stream #0.2(eng): Audio: dca (DTS), 48000 Hz, 5.1, s16, 1536 kb/s 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : ENG - DTS 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Stream #0.3(ger): Subtitle: [0][0][0][0] / 0x0000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Metadata: 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: title : forced 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Stream #0.4(ger): Subtitle: [0][0][0][0] / 0x0000 18:14:45 T:2885176128 INFO: ffmpeg[ABF84B40]: Stream #0.5(eng): Subtitle: [0][0][0][0] / 0x0000 18:14:45 T:2885176128 DEBUG: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 1737031 bytes per second 18:14:45 T:2885176128 NOTICE: Opening video stream: 0 source: 256 18:14:45 T:2885176128 NOTICE: Creating video codec with codec id: 28 18:14:45 T:2885176128 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:yes VAAPI:yes 18:14:45 T:2885176128 DEBUG: FactoryCodec - Video: - Opening 18:14:45 T:2885176128 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avfilter-1-i486-linux.so) 18:14:45 T:2885176128 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avfilter-1-i486-linux.so 18:14:45 T:2885176128 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x818, 28) 18:14:45 T:3006991104 DEBUG: ------ Window Init (VideoOverlay.xml) ------ 18:14:45 T:3006991104 INFO: Loading skin file: VideoOverlay.xml 18:14:46 T:2885176128 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000 18:14:46 T:2885176128 NOTICE: VDPAU Decoder capabilities: 18:14:46 T:2885176128 NOTICE: name level macbs width height 18:14:46 T:2885176128 NOTICE: ------------------------------------ 18:14:46 T:2885176128 NOTICE: MPEG1 0 8192 2048 2048 18:14:46 T:2885176128 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048 18:14:46 T:2885176128 NOTICE: MPEG2_MAIN 3 8192 2048 2048 18:14:46 T:2885176128 NOTICE: H264_MAIN 41 8192 2048 2048 18:14:46 T:2885176128 NOTICE: H264_HIGH 41 8192 2048 2048 18:14:46 T:2885176128 NOTICE: VC1_SIMPLE 1 8190 2048 2048 18:14:46 T:2885176128 NOTICE: VC1_MAIN 2 8190 2048 2048 18:14:46 T:2885176128 NOTICE: VC1_ADVANCED 4 8190 2048 2048 18:14:46 T:2885176128 NOTICE: MPEG4_PART2_ASP 5 8192 2048 2048 18:14:46 T:2885176128 NOTICE: ------------------------------------ 18:14:46 T:2885176128 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION 18:14:46 T:2885176128 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS 18:14:46 T:2885176128 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL 18:14:46 T:2885176128 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL 18:14:46 T:2885176128 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE 18:14:46 T:2885176128 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_HIGH_QUALITY_SCALING_L1 18:14:46 T:2885176128 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration) 18:14:46 T:2885176128 DEBUG: FactoryCodec - Video: ff-h264_vdpau-vdpau - Opened 18:14:46 T:2885176128 NOTICE: Creating video thread 18:14:46 T:2885176128 NOTICE: Opening audio stream: 1 source: 256 18:14:46 T:2885176128 NOTICE: Finding audio codec for: 86020 18:14:46 T:2885176128 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opening 18:14:46 T:2885176128 INFO: CDVDAudioCodecPassthroughFFmpeg::Open - Won't transcode for HDMI 18:14:46 T:2885176128 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Failed 18:14:46 T:2885176128 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 18:14:46 T:2885176128 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 18:14:46 T:2885176128 NOTICE: Creating audio thread 18:14:46 T:2885176128 NOTICE: Opening Subtitle stream: 3 source: 256 18:14:46 T:2885176128 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening 18:14:46 T:2885176128 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened 18:14:46 T:2885176128 DEBUG: CDVDPlayer::SetCaching - caching state 1 18:14:46 T:2885176128 DEBUG: CDVDPlayer::SetCaching - caching state 3 18:14:46 T:2649750336 DEBUG: Thread CDVDPlayerAudio start, auto delete: 0 18:14:46 T:2649750336 NOTICE: running thread: CDVDPlayerAudio::Process() 18:14:46 T:2649750336 NOTICE: Creating audio device with codec id: 86020, channels: 6, sample rate: 48000, no pass-through 18:14:46 T:2658143040 DEBUG: Thread CDVDPlayerVideo start, auto delete: 0 18:14:46 T:2658143040 NOTICE: running thread: video_thread 18:14:46 T:2658143040 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 18:14:46 T:2658143040 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 18:14:46 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.getRecentlyAddedMovies", "id": 1} 18:14:46 T:2833124160 DEBUG: JSONRPC: Calling videolibrary.getrecentlyaddedmovies 18:14:46 T:2649750336 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 18:14:46 T:2649750336 INFO: CPCMRemap: Configured speaker layout: 2.0 18:14:46 T:2649750336 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 18:14:46 T:2649750336 DEBUG: CPCMRemap: Forcing side channel map to back channels 18:14:46 T:2649750336 INFO: CPCMRemap: O channel map: FL,FR 18:14:46 T:2649750336 DEBUG: CPCMRemap: Downmix normalization is disabled 18:14:46 T:2649750336 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 18:14:46 T:2649750336 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 18:14:46 T:2649750336 DEBUG: CPulseAudioDirectSound::CPulseAudioDirectSound - Requested channels changed from 6 to 2 18:14:46 T:2649750336 DEBUG: RemoveActiveDevice - Removing device 1 18:14:46 T:2649750336 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 18:14:46 T:2649750336 DEBUG: RemoveActiveDevice - Removing device 0 18:14:46 T:2649750336 ERROR: PulseAudio: Failed to connect context 18:14:46 T:2649750336 ERROR: PulseAudio: Failed to create context 18:14:46 T:2649750336 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 18:14:46 T:2649750336 DEBUG: RemoveActiveDevice - Removing device 2 18:14:46 T:2649750336 DEBUG: CGUIAudioManager::Initialize 18:14:46 T:2833124160 DEBUG: RunQuery took 3 ms for 25 items query: select * from movieview order by idMovie desc limit 25 18:14:46 T:3006991104 DEBUG: CGUIInfoManager::SetCurrentMovie(ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/X-Men.mkv) 18:14:46 T:3006991104 DEBUG: GetMovieId (ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/X-Men.mkv), query = select idMovie from movie where idFile=144 18:14:46 T:2658143040 NOTICE: (VDPAU) screenWidth:0 vidWidth:1920 surfaceWidth:1920 18:14:46 T:2658143040 NOTICE: (VDPAU) screenHeight:0 vidHeight:818 surfaceHeight:832 18:14:46 T:3006991104 DEBUG: LoadVideoInfo, got movie info! 18:14:46 T:3006991104 DEBUG: Title = X-Men 18:14:46 T:3006991104 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 18:14:46 T:3006991104 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 18:14:46 T:2752506688 DEBUG: GetImageHash - unable to stat url ftp://administrator:**Password**@192.168.1.200:21/DATA_02/X-Men/disc.png 18:14:46 T:2658143040 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(42000.000000, 0) 18:14:46 T:2658143040 NOTICE: Creating 1920x818 pixmap 18:14:46 T:2658143040 DEBUG: Found 6 fbconfigs. 18:14:46 T:2658143040 DEBUG: Using fbconfig index 0. 18:14:46 T:2658143040 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4) 18:14:46 T:2658143040 NOTICE: (VDPAU) Creating the video mixer 18:14:46 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:5105000.000000, curr:5099000.000000, diff:-6000.000000 18:14:46 T:2658143040 NOTICE: fps: 23.976024, pwidth: 1920, pheight: 818, dwidth: 1920, dheight: 818 18:14:46 T:2658143040 DEBUG: OutputPicture - change configuration. 1920x818. framerate: 23.98. format: VDPAU 18:14:46 T:2658143040 NOTICE: Display resolution DESKTOP : 1920x1080 @ 50.00 - Full Screen (12) 18:14:46 T:3006991104 DEBUG: Activating window ID: 12005 18:14:46 T:3006991104 DEBUG: ------ Window Deinit (Home.xml) ------ 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(HomeTileLeft,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(HomeTileLeft,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(HomeTileRight,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(HomeTileRight,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(HomeTileMid,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(HomeTileMid,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(HomeTile2Left,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(HomeTile2Left,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(HomeTile2Right,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(HomeTile2Right,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(HomeTile2Mid,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(HomeTile2Mid,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(WidgetMove,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(WidgetMove,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(Widget2Move,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(Widget2Move,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating CancelAlarm(BGMove,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To CancelAlarm(BGMove,true) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating ClearProperty(PanelWidgetInfo,Home) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To ClearProperty(PanelWidgetInfo,Home) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : Translating ClearProperty(PanelWidget2Info,Home) 18:14:46 T:3006991104 DEBUG: ExecuteXBMCAction : To ClearProperty(PanelWidget2Info,Home) 18:14:46 T:3006991104 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 18:14:46 T:3006991104 INFO: Loading skin file: VideoFullScreen.xml 18:14:46 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:7814000.000000, curr:7744000.000000, diff:-70000.000000 18:14:46 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:7899000.000000, curr:7851000.000000, diff:-48000.000000 18:14:46 T:2885176128 DEBUG: CDVDPlayer::SetCaching - caching state 0 18:14:46 T:2649750336 INFO: CPCMRemap: Configured speaker layout: 2.0 18:14:46 T:2649750336 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 18:14:46 T:2649750336 DEBUG: CPCMRemap: Forcing side channel map to back channels 18:14:46 T:2649750336 INFO: CPCMRemap: O channel map: FL,FR 18:14:46 T:2649750336 DEBUG: CPCMRemap: Downmix normalization is disabled 18:14:46 T:2649750336 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 18:14:46 T:2649750336 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 18:14:46 T:2649750336 DEBUG: CALSADirectSound::CALSADirectSound - Requested channels changed from 6 to 2 18:14:46 T:2649750336 DEBUG: RemoveActiveDevice - Removing device 1 18:14:46 T:2649750336 DEBUG: CGUIAudioManager::DeInitialize 18:14:46 T:3006991104 DEBUG: ------ Window Deinit (VideoOverlay.xml) ------ 18:14:46 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.getRecentlyAddedEpisodes", "id": 1} 18:14:46 T:2833124160 DEBUG: JSONRPC: Calling videolibrary.getrecentlyaddedepisodes 18:14:46 T:2833124160 DEBUG: RunQuery took 6 ms for 25 items query: select * from episodeview order by idEpisode desc limit 25 18:14:46 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:46 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:46 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":0, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:46 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:46 T:3006991104 NOTICE: Using GL_TEXTURE_2D 18:14:46 T:3006991104 NOTICE: GL: Using VDPAU render method 18:14:46 T:3006991104 NOTICE: GL: NPOT texture support detected 18:14:46 T:3006991104 NOTICE: GL: Using GL_ARB_pixel_buffer_object 18:14:46 T:2885176128 DEBUG: CDVDPlayer::HandleMessages - player started 2 18:14:46 T:3006991104 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 18:14:46 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":-1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:46 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:46 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:46 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":-1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:46 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:46 T:2833124160 DEBUG: JSONRPC: Value does not lay between minimum and maximum in type playerid 18:14:46 T:2649750336 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 18:14:46 T:2649750336 DEBUG: RemoveActiveDevice - Removing device 0 18:14:46 T:2649750336 DEBUG: Initialize - using alsa device plughw:0,7 18:14:46 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:46 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:46 T:2649750336 DEBUG: CALSADirectSound::Initialize - frame count:512, packet count:16, buffer size:8192 18:14:46 T:2649750336 DEBUG: CALSADirectSound::Initialize - Channels: 6 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough 0 - audioDevice: plughw:0,7 18:14:46 T:2649750336 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 18:14:46 T:2649750336 DEBUG: CPCMRemap:: max gain: 3.414214, enabling limiter 18:14:46 T:2649750336 DEBUG: CDVDPlayerAudio:: Discontinuity - was:440479.021000, should be:0.000000, error:-440479.021000 18:14:46 T:2649750336 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(10000.000000, 1) 18:14:46 T:2885176128 DEBUG: CDVDPlayer::HandleMessages - player started 1 18:14:47 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:47 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:47 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "XBMC.GetInfoLabels", "params": {"labels": ["System.ProfileName"]}, "id": 1} 18:14:47 T:2833124160 DEBUG: JSONRPC: Calling xbmc.getinfolabels 18:14:47 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":0, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:47 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:47 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:47 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:47 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:47 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:47 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:47 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:48 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:48 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:48 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:48 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:48 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:48 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:48 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:48 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:48 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:48 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:49 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:49 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:49 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:49 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:49 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:49 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:49 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:49 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:49 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:49 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:50 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:50 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:50 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:50 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:50 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:50 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:50 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:50 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:50 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:50 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:50 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:11739000.000000, curr:11680000.000000, diff:-59000.000000 18:14:51 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:51 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:51 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:51 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:51 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:51 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:51 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:51 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:51 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:51 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:51 T:2658143040 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333 18:14:52 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:52 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:52 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:52 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:52 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "XBMC.GetInfoLabels", "params": {"labels": ["System.ProfileName"]}, "id": 1} 18:14:52 T:2833124160 DEBUG: JSONRPC: Calling xbmc.getinfolabels 18:14:52 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:52 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:52 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:52 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:52 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:52 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:52 T:2649750336 DEBUG: CDVDPlayerAudio:: Discontinuity - was:6044648.108000, should be:6023601.541377, error:-21046.566623 18:14:53 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:53 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:53 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:53 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:53 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:53 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:53 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:53 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:53 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:53 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:53 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:14822000.000000, curr:14816000.000000, diff:-6000.000000 18:14:53 T:2649750336 DEBUG: CDVDPlayerAudio:: Discontinuity - was:7031465.196377, should be:7042210.171608, error:10744.975231 18:14:54 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:54 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:54 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:54 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:54 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:54 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:54 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:54 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:54 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:54 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:54 T:2649750336 DEBUG: CDVDPlayerAudio:: Discontinuity - was:8002149.603608, should be:7861596.829243, error:-140552.774365 18:14:55 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:55 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:55 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:16337000.000000, curr:16267000.000000, diff:-70000.000000 18:14:55 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:16337000.000000, curr:16309000.000000, diff:-28000.000000 18:14:55 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:55 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:55 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:55 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:55 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:55 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:55 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:55 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:55 T:2649750336 DEBUG: CDVDPlayerAudio:: Discontinuity - was:8869703.619243, should be:8897682.015443, error:27978.396200 18:14:55 T:2939337536 DEBUG: CecLogMessage - sending ping 18:14:55 T:2983197504 DEBUG: CecLogMessage - command 'PING' sent 18:14:55 T:2974804800 DEBUG: CecLogMessage - PING - command accepted 18:14:56 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:56 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:56 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:56 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:56 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:56 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:56 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:56 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:56 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:56 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:56 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:17915000.000000, curr:17877000.000000, diff:-38000.000000 18:14:56 T:2649750336 DEBUG: CDVDPlayerAudio:: Discontinuity - was:9852944.808443, should be:9973708.362989, error:120763.554546 18:14:57 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:57 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:57 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:57 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:57 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "XBMC.GetInfoLabels", "params": {"labels": ["System.ProfileName"]}, "id": 1} 18:14:57 T:2833124160 DEBUG: JSONRPC: Calling xbmc.getinfolabels 18:14:57 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:57 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:57 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:57 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:57 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:57 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:57 T:2885176128 WARNING: CDVDPlayer::CheckContinuity - wrapback of stream:1, prev:18971000.000000, curr:18901000.000000, diff:-70000.000000 18:14:58 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:58 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:58 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "PlayList.GetItems", "params": {"playlistid":1, "properties":["artist", "duration", "album", "thumbnail", "fanart", "showtitle", "season", "episode", "tagline", "file"]}, "id": 1} 18:14:58 T:2833124160 DEBUG: JSONRPC: Calling playlist.getitems 18:14:58 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getProperties", "params": {"playerid":1, "properties":["partymode","percentage","playlistid", "position","time","totaltime","shuffled"]}, "id": 1} 18:14:58 T:2833124160 DEBUG: JSONRPC: Calling player.getproperties 18:14:58 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.getItem", "params": {"playerid":1, "properties":["thumbnail", "fanart", "title", "artist", "album", "season", "episode", "showtitle", "duration", "runtime", "tagline"]}, "id": 1} 18:14:58 T:2833124160 DEBUG: JSONRPC: Calling player.getitem 18:14:58 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": {"properties": ["volume"]}, "id": 1} 18:14:58 T:2833124160 DEBUG: JSONRPC: Calling application.getproperties 18:14:58 T:3006991104 DEBUG: HttpApi Start command: ExecBuiltIn paras: ExecBuiltIn; ShutDown 18:14:58 T:3006991104 DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc 18:14:58 T:3006991104 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnQuit 18:14:58 T:2735721280 DEBUG: Thread Jobworker 2735721280 terminating (autodelete) 18:14:58 T:2752506688 DEBUG: Thread Jobworker 2752506688 terminating (autodelete) 18:14:58 T:2799553344 DEBUG: Thread Jobworker 2799553344 terminating (autodelete) 18:14:58 T:2774375232 DEBUG: Thread CAlarmClock 2774375232 terminating 18:14:58 T:3006991104 NOTICE: Storing total System Uptime 18:14:58 T:3006991104 NOTICE: Saving settings 18:14:58 T:2893568832 DEBUG: Thread CEC Adapter Update Thread 2893568832 terminating 18:14:58 T:3003624256 DEBUG: Process - sending standby commands 18:14:58 T:3003624256 DEBUG: CecLogMessage - << putting 'TV' (0) in standby mode 18:14:58 T:3003624256 DEBUG: CecLogMessage - << 10:36 18:14:58 T:2983197504 DEBUG: CecLogMessage - command 'CEC transmission' sent 18:14:58 T:2974804800 DEBUG: CecLogMessage - CEC transmission - command accepted - waiting for 2 more 18:14:58 T:2974804800 DEBUG: CecLogMessage - CEC transmission - command accepted - waiting for 1 more 18:14:58 T:2974804800 DEBUG: CecLogMessage - CEC transmission - command accepted 18:14:58 T:3006991104 NOTICE: stop all 18:14:58 T:3006991104 INFO: stopping PVRManager 18:14:58 T:3006991104 NOTICE: CDVDPlayer::CloseFile() 18:14:58 T:3006991104 NOTICE: DVDPlayer: waiting for threads to exit 18:14:58 T:2885176128 NOTICE: CDVDPlayer::OnExit() 18:14:58 T:2885176128 NOTICE: DVDPlayer: closing audio stream 18:14:58 T:2885176128 NOTICE: Closing audio stream 18:14:58 T:2885176128 NOTICE: Waiting for audio thread to exit 18:14:58 T:2649750336 NOTICE: thread end: CDVDPlayerAudio::OnExit() 18:14:58 T:2649750336 DEBUG: Thread CDVDPlayerAudio 2649750336 terminating 18:14:58 T:2885176128 NOTICE: Closing audio device 18:14:58 T:2974804800 DEBUG: CecLogMessage - CEC transmission - transmit succeeded 18:14:58 T:3003624256 DEBUG: CecLogMessage - command transmitted 18:14:58 T:3003624256 DEBUG: CecLogMessage - unregistering all CEC clients 18:14:58 T:3003624256 DEBUG: CecLogMessage - unregistering client: libCEC version = 1.9.0, client version = 1.7.1, firmware version = 2, firmware build date: Thu Aug 2 09:40:28 2012 +0000, logical address(es) = Recorder 1 (1) , base device: TV (0), HDMI port number: 1, host: i686-pc-linux-gnu, features: 'P8 USB' 'P8 USB detect', compiled on: Tue Sep 11 12:54:06 UTC 2012 by pbuilder@packaging0 on Linux 3.2.0-24-generic (x86_64) 18:14:58 T:3003624256 DEBUG: CecLogMessage - Recorder 1 (1): power status changed from 'on' to 'unknown' 18:14:58 T:3003624256 DEBUG: CecLogMessage - Recorder 1 (1): CEC version unknown 18:14:58 T:3003624256 DEBUG: CecLogMessage - Recorder 1 (1): osd name set to 'Recorder 1' 18:14:58 T:3003624256 DEBUG: CecLogMessage - marking Recorder 1 (1) as inactive source 18:14:58 T:3003624256 DEBUG: CecLogMessage - Recorder 1 (1): device status changed into 'unknown' 18:14:58 T:3003624256 DEBUG: CecLogMessage - setting ackmask to 0 18:14:58 T:2983197504 DEBUG: CecLogMessage - command 'SET_ACK_MASK' sent 18:14:58 T:2974804800 DEBUG: CecLogMessage - SET_ACK_MASK - command accepted 18:14:58 T:3003624256 DEBUG: CecLogMessage - unregistering all CEC clients 18:14:58 T:2885176128 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 18:14:58 T:2885176128 DEBUG: RemoveActiveDevice - Removing device 2 18:14:58 T:2885176128 DEBUG: CGUIAudioManager::Initialize 18:14:58 T:2885176128 NOTICE: Deleting audio codec 18:14:58 T:2885176128 NOTICE: DVDPlayer: closing video stream 18:14:58 T:2885176128 NOTICE: Closing video stream 18:14:58 T:2885176128 NOTICE: waiting for video thread to exit 18:14:58 T:2658143040 NOTICE: thread end: video_thread 18:14:58 T:2658143040 DEBUG: Thread CDVDPlayerVideo 2658143040 terminating 18:14:58 T:2885176128 NOTICE: deleting video codec 18:14:58 T:2885176128 NOTICE: DVDPlayer: closing subtitle stream 18:14:58 T:2885176128 NOTICE: Closing subtitle stream 18:14:58 T:2885176128 NOTICE: CDVDPlayer::OnExit() deleting demuxer 18:14:58 T:2885176128 NOTICE: CDVDPlayer::OnExit() deleting input stream 18:14:58 T:2744113984 DEBUG: Thread XFILE::CFileCache 2744113984 terminating 18:14:58 T:2885176128 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 18:14:58 T:2885176128 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 18:14:58 T:2885176128 DEBUG: Thread CDVDPlayer 2885176128 terminating 18:14:58 T:3006991104 NOTICE: DVDPlayer: finished waiting 18:14:58 T:3006991104 DEBUG: LinuxRendererGL: Cleaning up GL resources 18:14:58 T:3006991104 NOTICE: (VDPAU) Close 18:14:58 T:3006991104 DEBUG: GLX: Destroying glPixmap 18:14:58 T:3006991104 DEBUG: GLX: Destroying XPixmap 18:14:58 T:3006991104 NOTICE: (VDPAU) FiniVDPAUOutput 18:14:58 T:3006991104 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 18:14:59 T:2974804800 DEBUG: CecLogMessage - communication thread ended 18:14:59 T:2859989824 DEBUG: Thread PVR GUI info updater 2859989824 terminating 18:14:59 T:2833124160 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1} 18:14:59 T:2833124160 DEBUG: JSONRPC: Calling player.getactiveplayers 18:14:59 T:2760899392 DEBUG: Thread EPG updater 2760899392 terminating 18:14:59 T:2876775232 DEBUG: Thread PVR add-on updater 2876775232 terminating 18:14:59 T:3003624256 DEBUG: Process - CEC adapter processor thread ended 18:14:59 T:3003624256 DEBUG: Thread CEC Adapter 3003624256 terminating 18:15:46 T:2816338752 NOTICE: ES: Client from 192.168.1.9 timed out