16:56:54.963 T:139809076500608 NOTICE: special://profile/ is mapped to: special://masterprofile/ 16:56:54.963 T:139809076500608 NOTICE: ----------------------------------------------------------------------- 16:56:54.964 T:139809076500608 NOTICE: Starting Kodi (17.1 Git:fc1619b). Platform: Linux x86 64-bit 16:56:54.964 T:139809076500608 NOTICE: Using Release Kodi x64 build 16:56:54.964 T:139809076500608 NOTICE: Kodi compiled Mar 21 2017 by GCC 6.2.0 for Linux x86 64-bit version 4.9.13 (264461) 16:56:54.964 T:139809076500608 NOTICE: Running on LibreELEC (official) - Version: 8.0.1, kernel: Linux x86 64-bit version 4.9.13 16:56:54.964 T:139809076500608 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi 16:56:54.964 T:139809076500608 NOTICE: Host CPU: Intel(R) Atom(TM) CPU 330 @ 1.60GHz, 4 cores available 16:56:54.964 T:139809076500608 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi/ 16:56:54.964 T:139809076500608 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi 16:56:54.964 T:139809076500608 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons 16:56:54.964 T:139809076500608 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata 16:56:54.964 T:139809076500608 NOTICE: special://envhome/ is mapped to: /storage 16:56:54.964 T:139809076500608 NOTICE: special://home/ is mapped to: /storage/.kodi 16:56:54.964 T:139809076500608 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp 16:56:54.964 T:139809076500608 NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp 16:56:54.964 T:139809076500608 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin 16:56:54.965 T:139809076500608 NOTICE: Local hostname: LibreELEC 16:56:54.965 T:139809076500608 NOTICE: Log File is located: /storage/.kodi/temp//kodi.log 16:56:54.968 T:139809076500608 NOTICE: ----------------------------------------------------------------------- 16:56:55.079 T:139809076500608 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files 16:56:55.089 T:139809076500608 NOTICE: load settings... 16:56:55.382 T:139809076500608 NOTICE: Found 2 Lists of Devices 16:56:55.383 T:139809076500608 NOTICE: Enumerated ALSA devices: 16:56:55.383 T:139809076500608 NOTICE: Device 1 16:56:55.383 T:139809076500608 NOTICE: m_deviceName : @ 16:56:55.383 T:139809076500608 NOTICE: m_displayName : Default (HDA NVidia VT2020 Analog) 16:56:55.383 T:139809076500608 NOTICE: m_displayNameExtra: 16:56:55.383 T:139809076500608 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:56:55.383 T:139809076500608 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE 16:56:55.383 T:139809076500608 NOTICE: m_sampleRates : 48000 16:56:55.383 T:139809076500608 NOTICE: m_dataFormats : AE_FMT_S32NE 16:56:55.383 T:139809076500608 NOTICE: m_streamTypes : No passthrough capabilities 16:56:55.383 T:139809076500608 NOTICE: Device 2 16:56:55.383 T:139809076500608 NOTICE: m_deviceName : @:CARD=NVidia,DEV=0 16:56:55.383 T:139809076500608 NOTICE: m_displayName : HDA NVidia (@:CARD=NVidia,DEV=0) 16:56:55.384 T:139809076500608 NOTICE: m_displayNameExtra: VT2020 Analog 16:56:55.384 T:139809076500608 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:56:55.384 T:139809076500608 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE 16:56:55.384 T:139809076500608 NOTICE: m_sampleRates : 48000 16:56:55.384 T:139809076500608 NOTICE: m_dataFormats : AE_FMT_S32NE 16:56:55.384 T:139809076500608 NOTICE: m_streamTypes : No passthrough capabilities 16:56:55.384 T:139809076500608 NOTICE: Device 3 16:56:55.384 T:139809076500608 NOTICE: m_deviceName : surround21:CARD=NVidia,DEV=0 16:56:55.384 T:139809076500608 NOTICE: m_displayName : HDA NVidia (surround21:CARD=NVidia,DEV=0) 16:56:55.384 T:139809076500608 NOTICE: m_displayNameExtra: VT2020 Analog 16:56:55.384 T:139809076500608 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:56:55.384 T:139809076500608 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR,UNKNOWN1,UNKNOWN2,UNKNOWN3,UNKNOWN4,UNKNOWN5,UNKNOWN6,UNKNOWN7,UNKNOWN8 16:56:55.384 T:139809076500608 NOTICE: m_sampleRates : 44100,48000,88200,96000,192000 16:56:55.384 T:139809076500608 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:56:55.384 T:139809076500608 NOTICE: m_streamTypes : No passthrough capabilities 16:56:55.384 T:139809076500608 NOTICE: Device 4 16:56:55.384 T:139809076500608 NOTICE: m_deviceName : iec958:CARD=NVidia,DEV=0 16:56:55.384 T:139809076500608 NOTICE: m_displayName : HDA NVidia 16:56:55.384 T:139809076500608 NOTICE: m_displayNameExtra: VT2020 Digital S/PDIF 16:56:55.384 T:139809076500608 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 16:56:55.384 T:139809076500608 NOTICE: m_channels : FL,FR 16:56:55.385 T:139809076500608 NOTICE: m_sampleRates : 44100,48000,88200,96000,192000 16:56:55.385 T:139809076500608 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:56:55.385 T:139809076500608 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512 16:56:55.385 T:139809076500608 NOTICE: Device 5 16:56:55.385 T:139809076500608 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0 16:56:55.385 T:139809076500608 NOTICE: m_displayName : HDA NVidia 16:56:55.385 T:139809076500608 NOTICE: m_displayNameExtra: HDMI 16:56:55.385 T:139809076500608 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:56:55.385 T:139809076500608 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR,SL,SR 16:56:55.385 T:139809076500608 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:56:55.385 T:139809076500608 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 16:56:55.385 T:139809076500608 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD 16:56:55.385 T:139809076500608 NOTICE: Enumerated PULSE devices: 16:56:55.385 T:139809076500608 NOTICE: Device 1 16:56:55.385 T:139809076500608 NOTICE: m_deviceName : Default 16:56:55.385 T:139809076500608 NOTICE: m_displayName : Default 16:56:55.385 T:139809076500608 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 16:56:55.385 T:139809076500608 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:56:55.385 T:139809076500608 NOTICE: m_channels : FL,FR 16:56:55.385 T:139809076500608 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 16:56:55.386 T:139809076500608 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 16:56:55.386 T:139809076500608 NOTICE: m_streamTypes : No passthrough capabilities 16:56:55.396 T:139809076500608 WARNING: CViewStateSettings: no tag found 16:56:55.396 T:139809076500608 ERROR: CDisplaySettings: settings file doesn't contain 16:56:55.400 T:139809076500608 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml 16:56:55.400 T:139809076500608 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are... false 1 cputemp gputemp 30 16:56:55.402 T:139809076500608 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 16:56:55.402 T:139809076500608 NOTICE: Default Video Player: VideoPlayer 16:56:55.402 T:139809076500608 NOTICE: Default Audio Player: paplayer 16:56:55.402 T:139809076500608 NOTICE: Disabled debug logging due to GUI setting. Level 0. 16:56:55.402 T:139809076500608 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 16:56:55.402 T:139809076500608 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 16:56:55.403 T:139809076500608 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 16:56:55.405 T:139809076500608 NOTICE: Loaded playercorefactory configuration 16:56:55.405 T:139809076500608 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 16:56:55.405 T:139809076500608 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 16:56:55.408 T:139809076500608 ERROR: CSettings: unable to load settings from special://masterprofile/guisettings.xml, creating new default settings 16:56:55.431 T:139809076500608 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml 16:56:55.431 T:139809076500608 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are... false 1 cputemp gputemp 30 16:56:55.432 T:139809076500608 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 16:56:55.432 T:139809076500608 NOTICE: Default Video Player: VideoPlayer 16:56:55.432 T:139809076500608 NOTICE: Default Audio Player: paplayer 16:56:55.432 T:139809076500608 NOTICE: Disabled debug logging due to GUI setting. Level 0. 16:56:55.433 T:139809076500608 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 16:56:55.433 T:139809076500608 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 16:56:55.433 T:139809076500608 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 16:56:55.434 T:139809076500608 NOTICE: Loaded playercorefactory configuration 16:56:55.435 T:139809076500608 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 16:56:55.435 T:139809076500608 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 16:56:55.670 T:139809076500608 NOTICE: ADDONS: Using repository repository.kodi.game 16:56:55.670 T:139809076500608 NOTICE: ADDONS: Using repository repository.libreelec.tv 16:56:55.670 T:139809076500608 NOTICE: ADDONS: Using repository repository.xbmc.org 16:56:55.852 T:139809076500608 NOTICE: Checking resolution 16 16:56:55.958 T:139809076500608 ERROR: failed to initialize egl display 16:56:55.959 T:139809076500608 NOTICE: Using visual 0x27 16:56:56.124 T:139809076500608 NOTICE: GL_VENDOR = NVIDIA Corporation 16:56:56.124 T:139809076500608 NOTICE: GL_RENDERER = ION/integrated/SSE2 16:56:56.124 T:139809076500608 NOTICE: GL_VERSION = 3.3.0 NVIDIA 340.102 16:56:56.124 T:139809076500608 NOTICE: GL_SHADING_LANGUAGE_VERSION = 3.30 NVIDIA via Cg compiler 16:56:56.124 T:139809076500608 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 262144 16:56:56.124 T:139809076500608 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 262144 16:56:56.125 T:139809076500608 NOTICE: GL_EXTENSIONS = GL_ARB_arrays_of_arrays GL_ARB_base_instance GL_ARB_blend_func_extended GL_ARB_clear_buffer_object GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_copy_buffer GL_ARB_copy_image GL_ARB_debug_output GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_enhanced_layouts GL_ARB_ES2_compatibility GL_ARB_ES3_compatibility GL_ARB_explicit_attrib_location GL_ARB_explicit_uniform_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_layer_viewport GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_no_attachments GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_internalformat_query2 GL_ARB_invalidate_subdata GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multi_bind 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_program_interface_query GL_ARB_provoking_vertex GL_ARB_robust_buffer_access_behavior GL_ARB_robustness GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_objects GL_ARB_shader_texture_lod 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_stencil_texturing GL_ARB_sync GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_range GL_ARB_texture_compression GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirror_clamp_to_edge GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_levels GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_stencil8 GL_ARB_texture_storage GL_ARB_texture_storage_multisample GL_ARB_texture_swizzle GL_ARB_texture_view GL_ARB_timer_query GL_ARB_transform_feedback2 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_binding GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_vertex_type_10f_11f_11f_rev 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_multisample_blit_scaled 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_integer_mix 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_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_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KHR_debug GL_KTX_buffer_region GL_NV_blend_square GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_ES1_1_compatibility GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_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_buffer_load GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum 16:56:56.711 T:139809057568512 NOTICE: Running database version Addons27 16:56:58.002 T:139809076500608 NOTICE: start dvd mediatype detection 16:56:58.080 T:139809076500608 WARNING: CSkinInfo: failed to load skin settings 16:56:58.504 T:139809076500608 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 16:56:58.859 T:139809076500608 NOTICE: initialize done 16:56:58.859 T:139809076500608 NOTICE: Running the application... 16:56:58.876 T:139809076500608 NOTICE: starting zeroconf publishing 16:56:58.877 T:139809076500608 NOTICE: CWebServer[8080]: Started 16:56:58.877 T:139809076500608 NOTICE: starting upnp client 16:56:58.908 T:139807921514240 NOTICE: ES: Starting UDP Event server on port 9777 16:56:58.909 T:139807921514240 NOTICE: UDP: Listening on port 9777 (ipv6 : false) 16:56:59.476 T:139808466777856 WARNING: CSkinInfo: failed to load skin settings 16:56:59.671 T:139808475170560 ERROR: ADDON: Could not locate inputstream.adaptive.so.1.0.8 16:56:59.748 T:139808475170560 ERROR: ADDON: Could not locate inputstream.rtmp.so.1.0.4 16:57:01.355 T:139807571678976 WARNING: CSkinInfo: failed to load skin settings 16:57:01.436 T:139809076500608 ERROR: Control 1500 in window 13001 has been asked to focus, but it can't 16:57:18.386 T:139807571678976 WARNING: CSkinInfo: failed to load skin settings 16:57:25.105 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:57:25.105 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:57:25.145 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:57:25.145 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:57:29.419 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:57:29.419 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:57:29.460 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:57:29.460 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:57:44.649 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:57:44.649 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:57:44.691 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:57:44.691 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:58:08.658 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:58:08.658 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:58:08.704 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:58:08.704 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:58:50.267 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:58:50.267 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:58:50.309 T:139809076500608 ERROR: GetDirectory - Error getting /storage/.kodi/media/Fonts/ 16:58:50.309 T:139809076500608 ERROR: GetDirectory - Error getting special://home/media/Fonts/ 16:59:08.080 T:139809076500608 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 16:59:08.080 T:139809076500608 NOTICE: Enabled debug logging due to GUI setting. Level 2. 16:59:08.081 T:139809076500608 DEBUG: ------ Window Init () ------ 16:59:33.771 T:139809076500608 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0 16:59:33.838 T:139809076500608 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu 16:59:33.849 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:59:34.143 T:139809076500608 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 16:59:34.153 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:59:34.153 T:139809076500608 DEBUG: ------ Window Init (Settings.xml) ------ 16:59:34.155 T:139809076500608 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x0000, modifier: 0x0 16:59:35.355 T:139809076500608 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0 16:59:35.440 T:139809076500608 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu 16:59:35.440 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:59:35.745 T:139809076500608 DEBUG: ------ Window Deinit (Settings.xml) ------ 16:59:35.755 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:59:35.755 T:139809076500608 DEBUG: ------ Window Init (Home.xml) ------ 16:59:35.756 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 16:59:35.756 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 16:59:35.756 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 16:59:35.756 T:139808475170560 DEBUG: Thread JobWorker start, auto delete: true 16:59:35.756 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 16:59:35.756 T:139809057568512 DEBUG: Thread JobWorker start, auto delete: true 16:59:35.765 T:139809076500608 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x0000, modifier: 0x0 16:59:40.239 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 16:59:47.323 T:139808904673024 INFO: Detecting DVD-ROM media filesystem... 16:59:47.335 T:139808904673024 INFO: CD Analysis Report 16:59:47.336 T:139808904673024 INFO: __________________________________ 16:59:47.337 T:139808904673024 INFO: CD-ROM with ISO 9660 filesystem 16:59:47.337 T:139808904673024 INFO: ISO 9660: 0 blocks, label 16:59:47.337 T:139808904673024 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1 16:59:47.761 T:139808904673024 INFO: Using protocol iso9660:// 16:59:47.761 T:139808904673024 INFO: Disc label: 16:59:49.850 T:139809076500608 NOTICE: UDev: Changed / Added /var/media/IKARIE_XB_1 16:59:49.866 T:139809076500608 DEBUG: ------ Window Init (DialogSelect.xml) ------ 16:59:50.004 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:59:51.555 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 16:59:52.387 T:139809076500608 NOTICE: UDev: Changed / Added /var/media/IKARIE_XB_1 16:59:55.773 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:59:55.860 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 16:59:56.107 T:139809076500608 DEBUG: ------ Window Deinit (DialogSelect.xml) ------ 16:59:57.074 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:04.206 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:04.312 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:06.860 T:139809076500608 DEBUG: GetDiskUniqueId: Trying to retrieve ID for path /dev/cdrom 17:00:06.860 T:139809076500608 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/VideoPlayer/libdvdnav-x86_64-linux.so) 17:00:06.860 T:139809076500608 DEBUG: Loading: /usr/lib/kodi/system/players/VideoPlayer/libdvdnav-x86_64-linux.so 17:00:06.867 T:139809076500608 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 17:00:07.818 T:139809076500608 DEBUG: msg: libdvdnav: DVD Title: 17:00:07.818 T:139809076500608 DEBUG: msg: I 17:00:07.819 T:139809076500608 DEBUG: msg: K 17:00:07.819 T:139809076500608 DEBUG: msg: A 17:00:07.819 T:139809076500608 DEBUG: msg: R 17:00:07.819 T:139809076500608 DEBUG: msg: I 17:00:07.819 T:139809076500608 DEBUG: msg: E 17:00:07.819 T:139809076500608 DEBUG: msg: _ 17:00:07.819 T:139809076500608 DEBUG: msg: X 17:00:07.819 T:139809076500608 DEBUG: msg: B 17:00:07.819 T:139809076500608 DEBUG: msg: _ 17:00:07.819 T:139809076500608 DEBUG: msg: 1 17:00:07.819 T:139809076500608 DEBUG: msg: libdvdnav: DVD Serial Number: 17:00:07.819 T:139809076500608 DEBUG: msg: 3 17:00:07.819 T:139809076500608 DEBUG: msg: 4 17:00:07.819 T:139809076500608 DEBUG: Previous line repeats 1 times. 17:00:07.819 T:139809076500608 DEBUG: msg: A 17:00:07.819 T:139809076500608 DEBUG: msg: B 17:00:07.819 T:139809076500608 DEBUG: msg: 8 17:00:07.819 T:139809076500608 DEBUG: msg: 3 17:00:07.819 T:139809076500608 DEBUG: msg: 6 17:00:07.819 T:139809076500608 DEBUG: msg: libdvdnav: DVD Title (Alternative): 17:00:07.820 T:139809076500608 DEBUG: msg: I 17:00:07.820 T:139809076500608 DEBUG: msg: K 17:00:07.820 T:139809076500608 DEBUG: msg: A 17:00:07.820 T:139809076500608 DEBUG: msg: R 17:00:07.820 T:139809076500608 DEBUG: msg: I 17:00:07.820 T:139809076500608 DEBUG: msg: E 17:00:07.820 T:139809076500608 DEBUG: msg: _ 17:00:07.820 T:139809076500608 DEBUG: msg: X 17:00:07.820 T:139809076500608 DEBUG: msg: B 17:00:07.820 T:139809076500608 DEBUG: msg: _ 17:00:07.820 T:139809076500608 DEBUG: msg: 1 17:00:07.820 T:139809076500608 DEBUG: msg: 17:00:07.820 T:139809076500608 INFO: msg: libdvdnav: vm: DVD Title: IKARIE_XB_1 17:00:07.820 T:139809076500608 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 17:00:07.820 T:139809076500608 INFO: msg: 1 17:00:07.820 T:139809076500608 INFO: msg: 2 17:00:07.820 T:139809076500608 INFO: msg: 3 17:00:07.820 T:139809076500608 INFO: msg: 4 17:00:07.820 T:139809076500608 INFO: msg: 5 17:00:07.820 T:139809076500608 INFO: msg: 6 17:00:07.821 T:139809076500608 INFO: msg: 7 17:00:07.821 T:139809076500608 INFO: msg: 8 17:00:07.821 T:139809076500608 DEBUG: msg: 17:00:07.821 T:139809076500608 DEBUG: Previous line repeats 1 times. 17:00:07.821 T:139809076500608 DEBUG: msg: libdvdread: Attempting to retrieve all CSS keys 17:00:07.821 T:139809076500608 DEBUG: msg: libdvdread: This can take a _long_ time, please be patient 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000195 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000022b 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00023128 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000241ff 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002424c 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x001f9a79 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_03_0.VOB (0x001f9a79) 17:00:07.821 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x001f9ac6 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_03_1.VOB (0x001f9ac6)!! 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x001fc1b8 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_04_0.VOB (0x001fc1b8) 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x001fc205 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_04_1.VOB (0x001fc205)!! 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x00244e76 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_05_0.VOB (0x00244e76) 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00244ec3 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_05_1.VOB (0x00244ec3)!! 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x00257e10 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_06_0.VOB (0x00257e10) 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x00257e5d 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_06_1.VOB (0x00257e5d)!! 17:00:07.822 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x0026044b 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_07_0.VOB (0x0026044b) 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x00260498 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_07_1.VOB (0x00260498)!! 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x00260eb1 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_0.VOB (0x00260eb1) 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x00260efe 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_1.VOB (0x00260efe)!! 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_0.VOB at 0x002b6f3a 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_09_0.VOB (0x002b6f3a) 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x002b6f87 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_09_1.VOB (0x002b6f87)!! 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Found 9 VTS's 17:00:07.823 T:139809076500608 INFO: msg: libdvdread: Elapsed time 0 17:00:07.824 T:139809076500608 DEBUG: Open - Setting region mask ff 17:00:07.950 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.950 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.950 T:139809076500608 INFO: msg: cs 17:00:07.950 T:139809076500608 DEBUG: msg: 17:00:07.950 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.950 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.950 T:139809076500608 INFO: msg: cs 17:00:07.950 T:139809076500608 DEBUG: msg: 17:00:07.950 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.950 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.950 T:139809076500608 INFO: msg: cs 17:00:07.950 T:139809076500608 DEBUG: msg: 17:00:07.950 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.950 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.950 T:139809076500608 INFO: msg: cs 17:00:07.950 T:139809076500608 DEBUG: msg: 17:00:07.957 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.957 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.957 T:139809076500608 INFO: msg: cs 17:00:07.957 T:139809076500608 DEBUG: msg: 17:00:07.957 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.957 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.957 T:139809076500608 INFO: msg: cs 17:00:07.957 T:139809076500608 DEBUG: msg: 17:00:07.958 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.958 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.958 T:139809076500608 INFO: msg: cs 17:00:07.958 T:139809076500608 DEBUG: msg: 17:00:07.958 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.958 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.958 T:139809076500608 INFO: msg: cs 17:00:07.958 T:139809076500608 DEBUG: msg: 17:00:07.958 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.958 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.958 T:139809076500608 INFO: msg: cs 17:00:07.958 T:139809076500608 DEBUG: msg: 17:00:07.958 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.958 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.958 T:139809076500608 INFO: msg: cs 17:00:07.958 T:139809076500608 DEBUG: msg: 17:00:07.958 T:139809076500608 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:07.958 T:139809076500608 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:07.958 T:139809076500608 INFO: msg: cs 17:00:07.958 T:139809076500608 DEBUG: msg: 17:00:07.960 T:139809076500608 DEBUG: GetDiskUniqueId: Got ID removable://IKARIE_XB_1_344AB836 for DVD disk 17:00:07.988 T:139809076500608 DEBUG: Loading settings for /var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO 17:00:07.990 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 17:00:07.991 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 17:00:07.999 T:139809076500608 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO) 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 17:00:08.000 T:139809076500608 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 17:00:08.000 T:139809076500608 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 17:00:08.000 T:139809076500608 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 17:00:08.000 T:139809076500608 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 17:00:08.000 T:139809076500608 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 17:00:08.000 T:139809076500608 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 17:00:08.007 T:139809076500608 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 17:00:08.008 T:139809076500608 NOTICE: VideoPlayer: Opening: /var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO 17:00:08.008 T:139809076500608 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 17:00:08.008 T:139809076500608 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:00:08.009 T:139809076500608 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:00:08.009 T:139808483563264 DEBUG: Thread VideoPlayer start, auto delete: false 17:00:08.009 T:139808483563264 NOTICE: Creating InputStream 17:00:08.012 T:139808483563264 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 17:00:08.012 T:139808483563264 INFO: dll_fopen - something opened the mount file, let's hope it knows what it's doing 17:00:08.013 T:139808483563264 INFO: msg: libdvdread: Attempting to use device /dev/sr0 mounted on /var/media/IKARIE_XB_1 for CSS authentication 17:00:08.509 T:139809076500608 DEBUG: ------ Window Init (DialogBusy.xml) ------ 17:00:08.560 T:139808483563264 DEBUG: msg: libdvdnav: Can't read name block. Probably not a DVD-ROM device. 17:00:08.561 T:139808483563264 DEBUG: msg: libdvdnav: vm: dvd_read_name failed 17:00:08.561 T:139808483563264 INFO: msg: libdvdnav: vm: DVD Title: IKARIE_XB_1 17:00:08.561 T:139808483563264 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 17:00:08.561 T:139808483563264 INFO: msg: 1 17:00:08.561 T:139808483563264 INFO: msg: 2 17:00:08.561 T:139808483563264 INFO: msg: 3 17:00:08.561 T:139808483563264 INFO: msg: 4 17:00:08.561 T:139808483563264 INFO: msg: 5 17:00:08.561 T:139808483563264 INFO: msg: 6 17:00:08.561 T:139808483563264 INFO: msg: 7 17:00:08.561 T:139808483563264 INFO: msg: 8 17:00:08.561 T:139808483563264 DEBUG: msg: 17:00:08.561 T:139808483563264 DEBUG: Previous line repeats 1 times. 17:00:08.561 T:139808483563264 DEBUG: msg: libdvdread: Attempting to retrieve all CSS keys 17:00:08.561 T:139808483563264 DEBUG: msg: libdvdread: This can take a _long_ time, please be patient 17:00:08.561 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000195 17:00:08.561 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.561 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000022b 17:00:08.561 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.561 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00023128 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000241ff 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002424c 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x001f9a79 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_03_0.VOB (0x001f9a79) 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x001f9ac6 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_03_1.VOB (0x001f9ac6)!! 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x001fc1b8 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_04_0.VOB (0x001fc1b8) 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x001fc205 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_04_1.VOB (0x001fc205)!! 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x00244e76 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_05_0.VOB (0x00244e76) 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00244ec3 17:00:08.562 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_05_1.VOB (0x00244ec3)!! 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x00257e10 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_06_0.VOB (0x00257e10) 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x00257e5d 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_06_1.VOB (0x00257e5d)!! 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x0026044b 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_07_0.VOB (0x0026044b) 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x00260498 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_07_1.VOB (0x00260498)!! 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x00260eb1 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_0.VOB (0x00260eb1) 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x00260efe 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_1.VOB (0x00260efe)!! 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_0.VOB at 0x002b6f3a 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_09_0.VOB (0x002b6f3a) 17:00:08.563 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.564 T:139808483563264 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x002b6f87 17:00:08.564 T:139808483563264 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_09_1.VOB (0x002b6f87)!! 17:00:08.564 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.564 T:139808483563264 INFO: msg: libdvdread: Found 9 VTS's 17:00:08.564 T:139808483563264 INFO: msg: libdvdread: Elapsed time 0 17:00:08.564 T:139808483563264 DEBUG: Open - Setting region mask ff 17:00:08.571 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.571 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.571 T:139808483563264 INFO: msg: cs 17:00:08.571 T:139808483563264 DEBUG: msg: 17:00:08.571 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.571 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.571 T:139808483563264 INFO: msg: cs 17:00:08.571 T:139808483563264 DEBUG: msg: 17:00:08.571 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.571 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.571 T:139808483563264 INFO: msg: cs 17:00:08.572 T:139808483563264 DEBUG: msg: 17:00:08.572 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.572 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.572 T:139808483563264 INFO: msg: cs 17:00:08.572 T:139808483563264 DEBUG: msg: 17:00:08.578 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.579 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.579 T:139808483563264 INFO: msg: cs 17:00:08.579 T:139808483563264 DEBUG: msg: 17:00:08.579 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.579 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.579 T:139808483563264 INFO: msg: cs 17:00:08.579 T:139808483563264 DEBUG: msg: 17:00:08.579 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.579 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.579 T:139808483563264 INFO: msg: cs 17:00:08.579 T:139808483563264 DEBUG: msg: 17:00:08.579 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.579 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.579 T:139808483563264 INFO: msg: cs 17:00:08.579 T:139808483563264 DEBUG: msg: 17:00:08.579 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.579 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.579 T:139808483563264 INFO: msg: cs 17:00:08.579 T:139808483563264 DEBUG: msg: 17:00:08.579 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.579 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.579 T:139808483563264 INFO: msg: cs 17:00:08.579 T:139808483563264 DEBUG: msg: 17:00:08.579 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:08.579 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:08.579 T:139808483563264 INFO: msg: cs 17:00:08.580 T:139808483563264 DEBUG: msg: 17:00:08.580 T:139808483563264 NOTICE: VideoPlayer: playing a file with menu's 17:00:08.580 T:139808483563264 NOTICE: Creating Demuxer 17:00:08.580 T:139808483563264 DEBUG: DVDNAV_HOP_CHANNEL 17:00:08.580 T:139808483563264 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 17:00:08.580 T:139808483563264 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 17:00:08.580 T:139808483563264 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 17:00:08.580 T:139808483563264 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 17:00:08.581 T:139808483563264 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 17:00:09.580 T:139808483563264 DEBUG: CDVDMsgGeneralSynchronize - global timeout 17:00:09.580 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:00:09.897 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:00:09.898 T:139808483563264 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 17:00:09.899 T:139808483563264 DEBUG: Open - avformat_find_stream_info starting 17:00:09.899 T:139808483563264 WARNING: could not find codec parameters for /var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO 17:00:09.899 T:139808483563264 DEBUG: Open - av_find_stream_info finished 17:00:09.899 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Input #0, mpeg, /var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IF': 17:00:09.899 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Duration: N/A, bitrate: N/A 17:00:09.899 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 17:00:09.899 T:139808483563264 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Cannot get current position) 17:00:09.899 T:139808483563264 NOTICE: Opening stream: 1 source: 512 17:00:09.900 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 17:00:09.900 T:139808483563264 NOTICE: Creating video codec with codec id: 0 17:00:09.900 T:139808483563264 DEBUG: FactoryCodec - Video: - Opening 17:00:09.900 T:139808483563264 DEBUG: CDVDVideoCodecFFmpeg::Open() Unable to find codec 0 17:00:09.900 T:139808483563264 DEBUG: FactoryCodec - Video: - Failed 17:00:09.900 T:139808483563264 ERROR: Unsupported video codec 17:00:09.900 T:139808483563264 NOTICE: Opening stream: 0 source: 512 17:00:09.900 T:139808483563264 NOTICE: Finding audio codec for: 0 17:00:09.900 T:139808483563264 DEBUG: FactoryCodec - Audio: passthrough - Opening 17:00:09.900 T:139808483563264 DEBUG: FactoryCodec - Audio: passthrough - Failed 17:00:09.900 T:139808483563264 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 17:00:09.901 T:139808483563264 DEBUG: CDVDAudioCodecFFmpeg::Open() Unable to find codec 0 17:00:09.901 T:139808483563264 DEBUG: FactoryCodec - Audio: FFmpeg - Failed 17:00:09.901 T:139808483563264 ERROR: Unsupported audio codec 17:00:09.901 T:139808483563264 NOTICE: Opening stream: 0 source: 512 17:00:09.901 T:139808483563264 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 17:00:09.901 T:139808483563264 DEBUG: Open - Unable to find codec 0 17:00:09.901 T:139808483563264 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Failed 17:00:09.901 T:139808483563264 ERROR: OpenStream - Unable to init overlay codec 17:00:09.902 T:139808483563264 DEBUG: OnPlayBackStarted: play state was 1, starting 1 17:00:09.903 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:00:09.903 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:00:09.903 T:139808483563264 NOTICE: VideoPlayer: next stream, wait for old streams to be finished 17:00:09.903 T:139808483563264 NOTICE: Creating Demuxer 17:00:09.903 T:139808483563264 DEBUG: DVDNAV_VTS_CHANGE 17:00:09.903 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 17:00:09.903 T:139808483563264 INFO: Previous line repeats 1 times. 17:00:09.903 T:139808483563264 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Cannot get current position) 17:00:09.904 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:09.904 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:09.904 T:139808483563264 INFO: msg: cs 17:00:09.904 T:139808483563264 DEBUG: msg: 17:00:09.904 T:139808483563264 DEBUG: ProcessBlock - Cell change: Title 0, Chapter 0 17:00:09.904 T:139808483563264 DEBUG: ProcessBlock - At position 0% inside the feature 17:00:09.904 T:139808483563264 DEBUG: DVDNAV_CELL_CHANGE 17:00:09.911 T:139809076500608 DEBUG: PlayFile: OpenFile succeed, play state 2 17:00:09.911 T:139809076500608 DEBUG: OnPlayBackStarted: play state was 2, starting 0 17:00:09.994 T:139808483563264 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:360000 DIFF:360000 17:00:09.999 T:139809057568512 DEBUG: CGUIInfoManager::SetCurrentMovie(/var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IFO) 17:00:10.009 T:139808483563264 DEBUG: Open - avformat_find_stream_info starting 17:00:10.029 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 17:00:10.029 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 17:00:10.168 T:139809076500608 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 17:00:10.391 T:139808483563264 DEBUG: Open - av_find_stream_info finished 17:00:10.391 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Input #0, mpeg, /var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IF': 17:00:10.391 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Duration: N/A, start: 0.360000, bitrate: 6192 kb/s 17:00:10.392 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Stream #0:0[0x1e0]: Video: mpeg2video (Main), yuv420p(tv, fcc/bt470bg/bt470bg), 720x576 [SAR 16:15 DAR 4:3], 6000 kb/s, 25 fps, 25 tbr, 90k tbn, 50 tbc 17:00:10.392 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Stream #0:1[0x20]: Subtitle: dvd_subtitle 17:00:10.392 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Stream #0:2[0x80]: Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s 17:00:10.392 T:139808483563264 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 17:00:10.393 T:139808483563264 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 17:00:10.393 T:139808483563264 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 17:00:10.393 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 17:00:10.393 T:139808483563264 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Couldn't open IFO for chosen title, exit.) 17:00:10.395 T:139808483563264 NOTICE: Opening stream: 1 source: 256 17:00:10.396 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:00:10.397 T:139808483563264 NOTICE: Opening stream: 0 source: 256 17:00:10.397 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 0, Scale permissions: 3 17:00:10.397 T:139808483563264 NOTICE: Creating video codec with codec id: 2 17:00:10.397 T:139808483563264 DEBUG: FactoryCodec - Video: - Opening 17:00:10.397 T:139808483563264 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 17:00:10.398 T:139808483563264 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 17:00:10.398 T:139808483563264 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 17:00:10.398 T:139808483563264 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 17:00:10.398 T:139808483563264 NOTICE: Creating video thread 17:00:10.398 T:139808483563264 NOTICE: Opening stream: 2 source: 256 17:00:10.399 T:139808483563264 NOTICE: Finding audio codec for: 86019 17:00:10.399 T:139807571678976 DEBUG: Thread VideoPlayerVideo start, auto delete: false 17:00:10.399 T:139808483563264 DEBUG: FactoryCodec - Audio: passthrough - Opening 17:00:10.399 T:139807571678976 NOTICE: running thread: video_thread 17:00:10.399 T:139808483563264 DEBUG: FactoryCodec - Audio: passthrough - Failed 17:00:10.399 T:139807571678976 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:00:10.399 T:139808483563264 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 17:00:10.399 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:10.399 T:139807571678976 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(720x576) 17:00:10.400 T:139807571678976 NOTICE: VDPAU::CreateContext - creating decoder context 17:00:10.401 T:139808483563264 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 17:00:10.401 T:139808483563264 NOTICE: Creating audio thread 17:00:10.401 T:139807208494848 DEBUG: Thread VideoPlayerAudio start, auto delete: false 17:00:10.401 T:139807208494848 NOTICE: running thread: CVideoPlayerAudio::Process() 17:00:10.401 T:139808483563264 DEBUG: CVideoPlayer::CheckContinuity - wrapback :1, prev:360000.000000, curr:32000.000000, diff:-328000.000000 17:00:10.402 T:139808483563264 DEBUG: CVideoPlayer::CheckContinuity - wrapback :2, prev:360000.000000, curr:40000.000000, diff:-320000.000000 17:00:10.402 T:139807208494848 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through) 17:00:10.403 T:139807208494848 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 17:00:10.422 T:139808970110720 INFO: CActiveAESink::OpenSink - initialize sink 17:00:10.614 T:139808970110720 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@ 17:00:10.614 T:139808970110720 INFO: CAESinkALSA::Initialize - Attempting to open device "@" 17:00:10.636 T:139808970110720 INFO: CAESinkALSA::Initialize - Opened device "sysdefault" 17:00:10.637 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:00:10.637 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:00:10.637 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600 17:00:10.638 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216 17:00:10.638 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms 17:00:10.638 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:00:10.638 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:00:10.638 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:00:10.638 T:139808970110720 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:00:10.638 T:139808970110720 DEBUG: Output Device : Default (HDA NVidia VT2020 Analog) 17:00:10.638 T:139808970110720 DEBUG: Sample Rate : 48000 17:00:10.638 T:139808970110720 DEBUG: Sample Format : AE_FMT_S32NE 17:00:10.638 T:139808970110720 DEBUG: Channel Count : 2 17:00:10.638 T:139808970110720 DEBUG: Channel Layout: FL,FR 17:00:10.638 T:139808970110720 DEBUG: Frames : 1024 17:00:10.638 T:139808970110720 DEBUG: Frame Size : 8 17:00:10.641 T:139809046943488 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:00:10.831 T:139808483563264 DEBUG: CVideoPlayer::HandleMessages - player started 1 17:00:11.166 T:139807571678976 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000 17:00:11.166 T:139807571678976 NOTICE: VDPAU Decoder capabilities: 17:00:11.166 T:139807571678976 NOTICE: name level macbs width height 17:00:11.166 T:139807571678976 NOTICE: ------------------------------------ 17:00:11.166 T:139807571678976 NOTICE: MPEG1 0 8192 2048 2048 17:00:11.166 T:139807571678976 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048 17:00:11.166 T:139807571678976 NOTICE: MPEG2_MAIN 3 8192 2048 2048 17:00:11.166 T:139807571678976 NOTICE: H264_MAIN 41 8190 2032 2048 17:00:11.166 T:139807571678976 NOTICE: H264_HIGH 41 8190 2032 2048 17:00:11.166 T:139807571678976 NOTICE: VC1_SIMPLE 1 8190 2048 2048 17:00:11.166 T:139807571678976 NOTICE: VC1_MAIN 2 8190 2048 2048 17:00:11.166 T:139807571678976 NOTICE: VC1_ADVANCED 4 8190 2048 2048 17:00:11.166 T:139807571678976 NOTICE: ------------------------------------ 17:00:11.167 T:139807571678976 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION 17:00:11.167 T:139807571678976 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS 17:00:11.167 T:139807571678976 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL 17:00:11.167 T:139807571678976 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL 17:00:11.167 T:139807571678976 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE 17:00:11.240 T:139807571678976 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video-vdpau 17:00:11.251 T:139807571678976 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 720 OutHeight: 576 17:00:11.251 T:139807571678976 NOTICE: (VDPAU) screenWidth:720 vidWidth:720 surfaceWidth:720 17:00:11.251 T:139807571678976 NOTICE: (VDPAU) screenHeight:576 vidHeight:576 surfaceHeight:576 17:00:11.359 T:139807193585408 DEBUG: Thread Vdpau Output start, auto delete: false 17:00:11.359 T:139807193585408 NOTICE: COutput::OnStartup: Output Thread created 17:00:11.421 T:139807193585408 NOTICE: VDPAU::COutput::CreateGlxContext - created context 17:00:11.422 T:139807193585408 NOTICE: VDPAU::COutput: vdpau gl interop initialized 17:00:11.423 T:139807183378176 DEBUG: Thread Vdpau Mixer start, auto delete: false 17:00:11.423 T:139807183378176 NOTICE: CMixer::OnStartup: Output Thread created 17:00:11.423 T:139807183378176 NOTICE: (VDPAU) Creating the video mixer 17:00:11.436 T:139807193585408 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:00:11.440 T:139807571678976 NOTICE: Previous line repeats 3 times. 17:00:11.440 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.458 T:139807193585408 DEBUG: Previous line repeats 3 times. 17:00:11.458 T:139807193585408 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:00:11.468 T:139807193585408 NOTICE: Previous line repeats 2 times. 17:00:11.468 T:139807193585408 NOTICE: VDPAU::COutput registered output surfaces 17:00:11.468 T:139807571678976 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 720x540. framerate: 25.00. format: VDPAU 17:00:11.469 T:139807193585408 NOTICE: VDPAU::COutput registered output surfaces 17:00:11.479 T:139809076500608 NOTICE: Previous line repeats 1 times. 17:00:11.480 T:139809076500608 DEBUG: DeleteRenderer - deleting renderer 17:00:11.480 T:139809076500608 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:00:11.500 T:139809076500608 DEBUG: Previous line repeats 1 times. 17:00:11.501 T:139809076500608 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:00:11.501 T:139809076500608 NOTICE: GL: Using VDPAU render method 17:00:11.501 T:139809076500608 NOTICE: GL: NPOT texture support detected 17:00:11.501 T:139809076500608 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:00:11.501 T:139809076500608 NOTICE: Using GL_TEXTURE_2D 17:00:11.501 T:139809076500608 DEBUG: CRenderManager::Configure - 5 17:00:11.575 T:139809076500608 DEBUG: GL: Vertex Shader compilation log: 17:00:11.575 T:139809076500608 DEBUG: GL: Vertex Shader compiled successfully 17:00:11.576 T:139809076500608 DEBUG: GL: Pixel Shader compilation log: 17:00:11.576 T:139809076500608 DEBUG: GL: Fragment Shader compiled successfully 17:00:11.610 T:139808483563264 DEBUG: CVideoPlayer::HandleMessages - player started 2 17:00:11.610 T:139808483563264 DEBUG: VideoPlayer::Sync - Audio - pts: 96000.000000, cache: 316138.586080, totalcache: 592000.007629 17:00:11.610 T:139808483563264 DEBUG: VideoPlayer::Sync - Video - pts: 360000.000000, cache: 50000.000000, totalcache: 100000.000000 17:00:11.610 T:139807208494848 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-220138.586080) 17:00:11.610 T:139807571678976 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-220138.586080) 17:00:11.610 T:139807208494848 DEBUG: CDVDAudio::Resume - resume audio stream 17:00:11.610 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.615 T:139809046943488 DEBUG: ActiveAE - start sync of audio stream 17:00:11.615 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.615 T:139809076500608 DEBUG: Activating window ID: 12005 17:00:11.615 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.616 T:139809076500608 DEBUG: ------ Window Deinit (Home.xml) ------ 17:00:11.617 T:139809076500608 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 17:00:11.617 T:139809076500608 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 17:00:11.621 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.625 T:139807193585408 DEBUG: Previous line repeats 2 times. 17:00:11.626 T:139807193585408 NOTICE: VDPAU::COutput registered output surfaces 17:00:11.626 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.631 T:139807193585408 DEBUG: Previous line repeats 2 times. 17:00:11.631 T:139807193585408 NOTICE: VDPAU::COutput registered output surfaces 17:00:11.632 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.633 T:139807193585408 DEBUG: Previous line repeats 3 times. 17:00:11.633 T:139807193585408 NOTICE: VDPAU::COutput registered output surfaces 17:00:11.633 T:139807571678976 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:11.634 T:139807193585408 DEBUG: Previous line repeats 4 times. 17:00:11.634 T:139807193585408 NOTICE: VDPAU::COutput registered output surfaces 17:00:11.723 T:139809046943488 DEBUG: ActiveAE::SyncStream - average error of 50.267485, start adjusting 17:00:11.723 T:139809046943488 DEBUG: ActiveAE::SyncStream - average error 29.267485 below threshold of 30.000000 17:00:11.904 T:139807571678976 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 50909 17:00:12.320 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:12.610 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 6 17:00:12.621 T:139807571678976 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: lost diff 17:00:12.748 T:139807208494848 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:16769.107519, adjusted:16769.107519 17:00:13.054 T:139807571678976 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 17:00:13.221 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 17:00:13.886 T:139809076500608 DEBUG: ProcessMouse: trying mouse action rightclick 17:00:13.886 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:00:13.886 T:139809076500608 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 17:00:13.897 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:00:13.897 T:139809076500608 DEBUG: ------ Window Init (Home.xml) ------ 17:00:13.897 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 17:00:13.897 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 17:00:13.897 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 17:00:13.898 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 17:00:13.899 T:139806956844800 DEBUG: Thread JobWorker start, auto delete: true 17:00:13.911 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:15.011 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:16.143 T:139809076500608 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0 17:00:16.226 T:139809076500608 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu 17:00:16.226 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:00:16.226 T:139809076500608 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x0000, modifier: 0x0 17:00:16.228 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:16.460 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:16.994 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:17.075 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:17.076 T:139809076500608 DEBUG: Activating window ID: 12005 17:00:17.076 T:139809076500608 DEBUG: ------ Window Deinit (Home.xml) ------ 17:00:17.076 T:139809076500608 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 17:00:17.252 T:139807571678976 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 17:00:17.301 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:17.944 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 2 17:00:18.336 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 3 17:00:18.418 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 4 17:00:18.460 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 5 17:00:18.544 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 6 17:00:18.575 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 8 17:00:19.857 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 7 17:00:19.888 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 6 17:00:19.972 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 2 17:00:20.177 T:139808483563264 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 17:00:20.734 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:20.866 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:20.871 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:20.871 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:20.871 T:139808483563264 INFO: msg: cs 17:00:20.871 T:139808483563264 DEBUG: msg: 17:00:20.871 T:139808483563264 INFO: msg: libdvdnav: Language 'en' not found, using 'cs' instead 17:00:20.871 T:139808483563264 DEBUG: msg: libdvdnav: Menu Languages available: 17:00:20.871 T:139808483563264 INFO: msg: cs 17:00:20.871 T:139808483563264 DEBUG: msg: 17:00:21.003 T:139808483563264 DEBUG: DVDNAV_HOP_CHANNEL 17:00:21.003 T:139808483563264 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 17:00:21.025 T:139807208494848 DEBUG: CDVDAudio::Flush - flush audio stream 17:00:21.025 T:139807208494848 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 17:00:21.025 T:139807571678976 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 17:00:21.025 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:00:21.025 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:00:21.027 T:139808483563264 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 17:00:21.027 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:00:21.027 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:00:21.027 T:139808483563264 NOTICE: VideoPlayer: next stream, wait for old streams to be finished 17:00:21.027 T:139808483563264 NOTICE: Closing stream player 1 17:00:21.027 T:139808483563264 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty 17:00:21.035 T:139807208494848 DEBUG: CDVDAudio::Pause - pausing audio stream 17:00:21.035 T:139807208494848 DEBUG: CDVDAudio::Resume - resume audio stream 17:00:21.035 T:139807208494848 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 17:00:21.035 T:139808483563264 NOTICE: Waiting for audio thread to exit 17:00:21.035 T:139807208494848 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 17:00:21.036 T:139807208494848 NOTICE: thread end: CVideoPlayerAudio::OnExit() 17:00:21.036 T:139808483563264 NOTICE: Closing audio device 17:00:21.036 T:139807208494848 DEBUG: Thread VideoPlayerAudio 139807208494848 terminating 17:00:21.038 T:139809046943488 DEBUG: CActiveAE::DiscardStream - audio stream deleted 17:00:21.038 T:139809046943488 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:00:21.038 T:139808483563264 DEBUG: Previous line repeats 2 times. 17:00:21.038 T:139808483563264 NOTICE: Deleting audio codec 17:00:21.038 T:139808483563264 NOTICE: Closing stream player 2 17:00:21.038 T:139808483563264 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty 17:00:21.039 T:139807571678976 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 17:00:21.039 T:139808483563264 NOTICE: waiting for video thread to exit 17:00:21.039 T:139807571678976 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 17:00:21.039 T:139807571678976 NOTICE: thread end: video_thread 17:00:21.039 T:139808483563264 NOTICE: deleting video codec 17:00:21.039 T:139807571678976 DEBUG: Thread VideoPlayerVideo 139807571678976 terminating 17:00:21.039 T:139808483563264 NOTICE: CVDPAU::Release pre-cleanup 17:00:21.044 T:139807183378176 NOTICE: CMixer::OnExit: Output Thread terminated 17:00:21.044 T:139807183378176 DEBUG: Thread Vdpau Mixer 139807183378176 terminating 17:00:21.046 T:139807193585408 DEBUG: VDPAU::PreCleanup - released output surface 17:00:21.058 T:139808483563264 DEBUG: Previous line repeats 4 times. 17:00:21.058 T:139808483563264 NOTICE: Creating Demuxer 17:00:21.058 T:139808483563264 DEBUG: DVDNAV_VTS_CHANGE 17:00:21.058 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 17:00:21.059 T:139808483563264 INFO: Previous line repeats 1 times. 17:00:21.059 T:139808483563264 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Couldn't open IFO for chosen title, exit.) 17:00:21.060 T:139808483563264 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 1 17:00:21.060 T:139808483563264 DEBUG: ProcessBlock - At position 0% inside the feature 17:00:21.060 T:139808483563264 DEBUG: DVDNAV_CELL_CHANGE 17:00:21.061 T:139808483563264 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:18280000 TO:360000 DIFF:-17920000 17:00:21.071 T:139808483563264 DEBUG: Open - avformat_find_stream_info starting 17:00:21.238 T:139808970110720 INFO: CActiveAESink::OpenSink - initialize sink 17:00:21.312 T:139808483563264 DEBUG: Open - av_find_stream_info finished 17:00:21.313 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Input #0, mpeg, /var/media/IKARIE_XB_1/VIDEO_TS/VIDEO_TS.IF': 17:00:21.313 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Duration: N/A, start: 0.360000, bitrate: N/A 17:00:21.313 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Stream #0:0[0x1e0]: Video: mpeg2video (Main), yuv420p(tv), 720x576 [SAR 64:45 DAR 16:9], 25 fps, 25 tbr, 90k tbn, 50 tbc 17:00:21.313 T:139808483563264 INFO: ffmpeg[7F27B2FFD700]: Stream #0:1[0x80]: Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s 17:00:21.313 T:139808483563264 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 17:00:21.313 T:139808483563264 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 17:00:21.313 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 17:00:21.313 T:139808483563264 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Couldn't open IFO for chosen title, exit.) 17:00:21.315 T:139808483563264 NOTICE: Opening stream: 0 source: 256 17:00:21.315 T:139808483563264 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 17:00:21.315 T:139808483563264 NOTICE: Creating video codec with codec id: 2 17:00:21.316 T:139808483563264 DEBUG: FactoryCodec - Video: - Opening 17:00:21.316 T:139808483563264 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 17:00:21.316 T:139808483563264 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 17:00:21.316 T:139808483563264 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 17:00:21.316 T:139808483563264 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 17:00:21.316 T:139808483563264 NOTICE: Creating video thread 17:00:21.316 T:139807183378176 DEBUG: Thread VideoPlayerVideo start, auto delete: false 17:00:21.316 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 1 17:00:21.316 T:139807183378176 NOTICE: running thread: video_thread 17:00:21.316 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:00:21.316 T:139807183378176 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:00:21.316 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:21.317 T:139807183378176 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(720x576) 17:00:21.317 T:139808483563264 NOTICE: Opening stream: 1 source: 256 17:00:21.317 T:139808483563264 NOTICE: Finding audio codec for: 86019 17:00:21.317 T:139808483563264 DEBUG: FactoryCodec - Audio: passthrough - Opening 17:00:21.317 T:139808483563264 DEBUG: FactoryCodec - Audio: passthrough - Failed 17:00:21.317 T:139808483563264 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 17:00:21.319 T:139808483563264 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 17:00:21.319 T:139808483563264 NOTICE: Creating audio thread 17:00:21.320 T:139807571678976 DEBUG: Thread VideoPlayerAudio start, auto delete: false 17:00:21.320 T:139807571678976 NOTICE: running thread: CVideoPlayerAudio::Process() 17:00:21.320 T:139807571678976 DEBUG: CDVDAudio::Pause - pausing audio stream 17:00:21.320 T:139807571678976 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through) 17:00:21.430 T:139808970110720 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@ 17:00:21.430 T:139808970110720 INFO: CAESinkALSA::Initialize - Attempting to open device "@" 17:00:21.456 T:139808970110720 INFO: CAESinkALSA::Initialize - Opened device "sysdefault" 17:00:21.457 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:00:21.457 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:00:21.457 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600 17:00:21.458 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216 17:00:21.458 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms 17:00:21.458 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:00:21.458 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:00:21.458 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:00:21.458 T:139808970110720 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:00:21.458 T:139808970110720 DEBUG: Output Device : Default (HDA NVidia VT2020 Analog) 17:00:21.458 T:139808970110720 DEBUG: Sample Rate : 48000 17:00:21.458 T:139808970110720 DEBUG: Sample Format : AE_FMT_S32NE 17:00:21.458 T:139808970110720 DEBUG: Channel Count : 2 17:00:21.458 T:139808970110720 DEBUG: Channel Layout: FL,FR 17:00:21.458 T:139808970110720 DEBUG: Frames : 1024 17:00:21.458 T:139808970110720 DEBUG: Frame Size : 8 17:00:21.460 T:139809046943488 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:00:21.460 T:139807571678976 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 17:00:21.461 T:139808970110720 INFO: CActiveAESink::OpenSink - initialize sink 17:00:21.499 T:139807183378176 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video-vdpau 17:00:21.501 T:139807183378176 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 720 OutHeight: 576 17:00:21.501 T:139807183378176 NOTICE: (VDPAU) screenWidth:720 vidWidth:720 surfaceWidth:720 17:00:21.502 T:139807183378176 NOTICE: (VDPAU) screenHeight:576 vidHeight:576 surfaceHeight:576 17:00:21.520 T:139808970110720 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@ 17:00:21.520 T:139808970110720 INFO: CAESinkALSA::Initialize - Attempting to open device "@" 17:00:21.543 T:139808970110720 INFO: CAESinkALSA::Initialize - Opened device "sysdefault" 17:00:21.544 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:00:21.544 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:00:21.544 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600 17:00:21.545 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216 17:00:21.545 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms 17:00:21.545 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:00:21.545 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:00:21.545 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:00:21.545 T:139808970110720 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:00:21.545 T:139808970110720 DEBUG: Output Device : Default (HDA NVidia VT2020 Analog) 17:00:21.545 T:139808970110720 DEBUG: Sample Rate : 48000 17:00:21.545 T:139808970110720 DEBUG: Sample Format : AE_FMT_S32NE 17:00:21.545 T:139808970110720 DEBUG: Channel Count : 2 17:00:21.545 T:139808970110720 DEBUG: Channel Layout: FL,FR 17:00:21.545 T:139808970110720 DEBUG: Frames : 1024 17:00:21.545 T:139808970110720 DEBUG: Frame Size : 8 17:00:21.547 T:139809046943488 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:00:21.661 T:139807208494848 DEBUG: Thread Vdpau Output start, auto delete: false 17:00:21.661 T:139807208494848 NOTICE: COutput::OnStartup: Output Thread created 17:00:21.662 T:139808483563264 DEBUG: CVideoPlayer::HandleMessages - player started 1 17:00:21.713 T:139807208494848 NOTICE: VDPAU::COutput::CreateGlxContext - created context 17:00:21.714 T:139807208494848 NOTICE: VDPAU::COutput: vdpau gl interop initialized 17:00:21.715 T:139806948452096 DEBUG: Thread Vdpau Mixer start, auto delete: false 17:00:21.715 T:139806948452096 NOTICE: CMixer::OnStartup: Output Thread created 17:00:21.715 T:139806948452096 NOTICE: (VDPAU) Creating the video mixer 17:00:21.728 T:139807208494848 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:00:21.732 T:139807183378176 NOTICE: Previous line repeats 3 times. 17:00:21.732 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:21.752 T:139807208494848 DEBUG: Previous line repeats 3 times. 17:00:21.752 T:139807208494848 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:00:21.752 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:21.754 T:139807208494848 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:00:21.758 T:139807208494848 NOTICE: Previous line repeats 1 times. 17:00:21.759 T:139807208494848 NOTICE: VDPAU::COutput registered output surfaces 17:00:21.759 T:139807183378176 DEBUG: CRenderManager::Configure - change configuration. 720x576. display: 720x405. framerate: 25.00. format: VDPAU 17:00:21.759 T:139807208494848 NOTICE: VDPAU::COutput registered output surfaces 17:00:21.797 T:139809076500608 NOTICE: Previous line repeats 2 times. 17:00:21.797 T:139809076500608 NOTICE: (VDPAU) Close 17:00:21.797 T:139809076500608 NOTICE: (VDPAU) FiniVDPAUOutput 17:00:21.799 T:139807193585408 NOTICE: VDPAU::COutput: vdpau gl interop finished 17:00:21.818 T:139807193585408 NOTICE: COutput::OnExit: Output Thread terminated 17:00:21.818 T:139807193585408 DEBUG: Thread Vdpau Output 139807193585408 terminating 17:00:21.854 T:139809076500608 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 17:00:21.854 T:139809076500608 NOTICE: GL: Using VDPAU render method 17:00:21.854 T:139809076500608 NOTICE: GL: NPOT texture support detected 17:00:21.854 T:139809076500608 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:00:21.854 T:139809076500608 NOTICE: Using GL_TEXTURE_2D 17:00:21.854 T:139809076500608 DEBUG: CRenderManager::Configure - 5 17:00:21.856 T:139809076500608 DEBUG: GL: Vertex Shader compilation log: 17:00:21.856 T:139809076500608 DEBUG: GL: Vertex Shader compiled successfully 17:00:21.858 T:139809076500608 DEBUG: GL: Pixel Shader compilation log: 17:00:21.858 T:139809076500608 DEBUG: GL: Fragment Shader compiled successfully 17:00:21.863 T:139808483563264 DEBUG: CVideoPlayer::HandleMessages - player started 2 17:00:21.863 T:139808483563264 DEBUG: VideoPlayer::Sync - Audio - pts: 616000.000000, cache: 326630.222071, totalcache: 592000.007629 17:00:21.863 T:139808483563264 DEBUG: VideoPlayer::Sync - Video - pts: 360000.000000, cache: 50000.000000, totalcache: 100000.000000 17:00:21.863 T:139807571678976 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(260000.000000) 17:00:21.863 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:00:21.863 T:139807571678976 DEBUG: CDVDAudio::Resume - resume audio stream 17:00:21.863 T:139807183378176 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(260000.000000) 17:00:21.863 T:139809046943488 DEBUG: ActiveAE - start sync of audio stream 17:00:21.864 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:00:21.864 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:00:21.883 T:139807208494848 DEBUG: Previous line repeats 6 times. 17:00:21.883 T:139807208494848 NOTICE: VDPAU::COutput registered output surfaces 17:00:21.969 T:139809046943488 NOTICE: Previous line repeats 2 times. 17:00:21.969 T:139809046943488 DEBUG: ActiveAE::SyncStream - average error of -81.067735, start adjusting 17:00:21.969 T:139809046943488 DEBUG: ActiveAE::SyncStream - average error -18.067735 below threshold of 30.000000 17:00:22.084 T:139807183378176 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 69090 17:00:22.117 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:22.132 T:139809076500608 DEBUG: ------ Window Init (VideoOSD.xml) ------ 17:00:22.132 T:139809076500608 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 17:00:22.174 T:139809076500608 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 17:00:22.176 T:139809076500608 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 17:00:22.176 T:139809076500608 INFO: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: KEEP_IN_MEMORY 17:00:22.602 T:139807183378176 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: lost diff 17:00:22.993 T:139807571678976 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-31836.840375, adjusted:-31836.840375 17:00:23.069 T:139807183378176 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000 17:00:23.724 T:139808483563264 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 17:00:23.724 T:139808483563264 NOTICE: Opening stream: 2 source: 256 17:00:23.724 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:00:23.724 T:139808483563264 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3 17:00:24.033 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:24.132 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:24.133 T:139809076500608 DEBUG: Activating window ID: 11101 17:00:24.133 T:139809076500608 DEBUG: ------ Window Init (Custom_1101_SettingsList.xml) ------ 17:00:24.133 T:139809076500608 INFO: Loading skin file: Custom_1101_SettingsList.xml, load type: KEEP_IN_MEMORY 17:00:24.735 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:25.360 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:00:27.167 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:27.269 T:139807183378176 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 17:00:27.283 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:27.283 T:139809076500608 DEBUG: Activating window ID: 10124 17:00:27.283 T:139809076500608 DEBUG: ------ Window Init (DialogSettings.xml) ------ 17:00:27.283 T:139809076500608 INFO: Loading skin file: DialogSettings.xml, load type: KEEP_IN_MEMORY 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (audio.volume) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (audio.volumeamplification) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (audio.delay) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (audio.stream) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (audio.outputtoallspeakers) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (audio.digitalanalog) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (subtitles.enable) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (subtitles.delay) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (subtitles.stream) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (subtitles.browser) was not found. 17:00:27.302 T:139809076500608 DEBUG: CSettingsManager: requested setting (audio.makedefault) was not found. 17:00:27.834 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:28.934 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:29.034 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:29.034 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:29.034 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:29.101 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:30.034 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:30.150 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:30.150 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:30.150 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:30.357 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:00:31.816 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:32.156 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:00:32.417 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:32.516 T:139809076500608 DEBUG: ProcessMouse: unknown mouse command 57605 17:00:32.566 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.567 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.567 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:32.567 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:32.583 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.583 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.583 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:32.699 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.700 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.700 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:32.716 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.716 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.716 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:32.749 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.749 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.750 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:32.799 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.799 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.799 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:32.866 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.866 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.866 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:32.932 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:32.933 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:32.933 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:33.066 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:33.066 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:33.066 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:33.116 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:33.116 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:33.116 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:33.182 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:33.899 T:139809076500608 DEBUG: ProcessMouse: unknown mouse command 57606 17:00:33.899 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:35.032 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:35.115 T:139809076500608 DEBUG: ProcessMouse: unknown mouse command 57605 17:00:35.116 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:35.165 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.165 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.165 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.166 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:35.198 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.199 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.199 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.215 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.216 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.216 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.282 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.282 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.282 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.315 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.315 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.315 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.415 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.415 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.415 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.865 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.865 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.865 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.898 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.898 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.899 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:35.948 T:139809076500608 DEBUG: ProcessMouse: trying mouse action mousedrag 17:00:35.949 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 17:00:35.949 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 17:00:36.831 T:139809076500608 DEBUG: ProcessMouse: unknown mouse command 57606 17:00:36.832 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:41.480 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:42.130 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:42.279 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:42.541 T:139809076500608 DEBUG: ------ Window Deinit (DialogSettings.xml) ------ 17:00:43.429 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:00:43.917 T:139809057568512 DEBUG: Thread JobWorker 139809057568512 terminating (autodelete) 17:00:43.927 T:139806956844800 DEBUG: Thread JobWorker 139806956844800 terminating (autodelete) 17:00:43.928 T:139808475170560 DEBUG: Thread JobWorker 139808475170560 terminating (autodelete) 17:00:45.678 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:00:45.777 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:00:46.016 T:139809076500608 DEBUG: ------ Window Deinit (Custom_1101_SettingsList.xml) ------ 17:00:48.978 T:139809076500608 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 17:00:48.995 T:139809076500608 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 17:00:48.995 T:139809076500608 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 17:01:13.101 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:01:13.116 T:139809076500608 DEBUG: ------ Window Init (VideoOSD.xml) ------ 17:01:13.133 T:139809076500608 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 17:01:13.135 T:139809076500608 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 17:01:20.298 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:01:22.198 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:01:23.176 T:139807183378176 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 17:01:23.178 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:23.178 T:139807183378176 DEBUG: Previous line repeats 2 times. 17:01:23.178 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 2, Bufferlevel: 1, dropped: 1 17:01:23.197 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 3, Bufferlevel: 2, dropped: 1 17:01:23.208 T:139807183378176 DEBUG: Previous line repeats 1 times. 17:01:23.208 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:23.213 T:139807183378176 DEBUG: Previous line repeats 2 times. 17:01:23.213 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 0, dropped: 1 17:01:23.213 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:23.220 T:139807183378176 DEBUG: Previous line repeats 2 times. 17:01:23.220 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 5, Bufferlevel: 0, dropped: 1 17:01:23.234 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 6, Bufferlevel: 2, dropped: 1 17:01:23.250 T:139807183378176 DEBUG: Previous line repeats 1 times. 17:01:23.250 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:23.250 T:139807183378176 DEBUG: Previous line repeats 2 times. 17:01:23.250 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 6, Bufferlevel: 1, dropped: 1 17:01:23.251 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:23.252 T:139807183378176 DEBUG: Previous line repeats 2 times. 17:01:23.253 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 6, Bufferlevel: 1, dropped: 1 17:01:23.253 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:23.257 T:139807183378176 DEBUG: Previous line repeats 2 times. 17:01:23.258 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 6, Bufferlevel: 1, dropped: 1 17:01:28.431 T:139807183378176 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 17:01:29.112 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:01:29.262 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:01:29.269 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:01:29.269 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:01:29.269 T:139808483563264 DEBUG: demuxer seek to: 885017.000000 17:01:29.269 T:139808483563264 DEBUG: demuxer seek to: 885017.000000, success 17:01:29.269 T:139808483563264 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 17:01:29.276 T:139807571678976 DEBUG: CDVDAudio::Pause - pausing audio stream 17:01:29.318 T:139807571678976 DEBUG: CDVDAudio::Flush - flush audio stream 17:01:29.318 T:139807571678976 DEBUG: CDVDAudio::Pause - pausing audio stream 17:01:29.318 T:139807571678976 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 17:01:29.319 T:139807183378176 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 17:01:29.320 T:139808483563264 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 17:01:29.320 T:139808483563264 DEBUG: DVDNAV_HOP_CHANNEL 17:01:29.320 T:139808483563264 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 3 17:01:29.321 T:139808483563264 DEBUG: ProcessBlock - At position 18% inside the feature 17:01:29.321 T:139808483563264 DEBUG: DVDNAV_CELL_CHANGE 17:01:29.331 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 17:01:29.332 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 17:01:29.438 T:139808483563264 DEBUG: DVDNAV_NAV_PACKET (HOLDING) 17:01:29.540 T:139808483563264 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:76280000 TO:886200000 DIFF:809920000 17:01:29.587 T:139807183378176 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:01:29.587 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:29.591 T:139807571678976 DEBUG: Previous line repeats 5 times. 17:01:29.591 T:139807571678976 ERROR: ffmpeg[7F277CA59700]: [ac3] frame sync error 17:01:29.591 T:139807571678976 ERROR: CVideoPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet (-1094995529) 17:01:29.594 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:29.594 T:139808483563264 DEBUG: CVideoPlayer::HandleMessages - player started 1 17:01:29.597 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:29.666 T:139808483563264 DEBUG: Previous line repeats 3 times. 17:01:29.666 T:139808483563264 DEBUG: CVideoPlayer::HandleMessages - player started 2 17:01:29.666 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 3 17:01:29.666 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:01:29.666 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:01:29.666 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:01:29.666 T:139808483563264 DEBUG: VideoPlayer::Sync - Audio - pts: 886120000.000000, cache: 315859.508325, totalcache: 592000.007629 17:01:29.666 T:139808483563264 DEBUG: VideoPlayer::Sync - Video - pts: 886280000.000000, cache: 50000.000000, totalcache: 100000.000000 17:01:29.666 T:139807571678976 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(885804140.491675) 17:01:29.666 T:139807571678976 DEBUG: CDVDAudio::Resume - resume audio stream 17:01:29.667 T:139809046943488 DEBUG: ActiveAE - start sync of audio stream 17:01:29.667 T:139807183378176 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(885804140.491675) 17:01:29.667 T:139807183378176 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:29.766 T:139809046943488 DEBUG: Previous line repeats 1 times. 17:01:29.766 T:139809046943488 DEBUG: ActiveAE::SyncStream - average error of 17.542281, start adjusting 17:01:29.766 T:139809046943488 DEBUG: ActiveAE::SyncStream - average error 0.542281 below threshold of 30.000000 17:01:30.272 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:01:30.812 T:139807571678976 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-19701.951221, adjusted:-19701.951221 17:01:31.463 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:01:32.414 T:139809076500608 DEBUG: Previous line repeats 1 times. 17:01:32.414 T:139809076500608 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 17:01:32.431 T:139809076500608 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 17:01:32.431 T:139809076500608 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 17:01:34.760 T:139807183378176 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 17:01:37.159 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:01:37.175 T:139809076500608 DEBUG: ------ Window Init (VideoOSD.xml) ------ 17:01:37.178 T:139809076500608 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 17:01:37.179 T:139809076500608 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 17:01:44.246 T:139808483563264 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:01:45.457 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:01:45.556 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:01:45.561 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:01:45.561 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:01:45.561 T:139808483563264 DEBUG: demuxer seek to: 1704047.000000 17:01:45.561 T:139808483563264 DEBUG: demuxer seek to: 1704047.000000, success 17:01:45.561 T:139808483563264 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 17:01:45.574 T:139807571678976 DEBUG: CDVDAudio::Pause - pausing audio stream 17:01:45.617 T:139807571678976 DEBUG: CDVDAudio::Flush - flush audio stream 17:01:45.617 T:139807571678976 DEBUG: CDVDAudio::Pause - pausing audio stream 17:01:45.617 T:139807183378176 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 17:01:45.617 T:139807571678976 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 17:01:45.619 T:139808483563264 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE 17:01:45.619 T:139808483563264 DEBUG: DVDNAV_HOP_CHANNEL 17:01:45.619 T:139808483563264 DEBUG: ProcessBlock - Cell change: Title 2, Chapter 6 17:01:45.619 T:139808483563264 DEBUG: ProcessBlock - At position 33% inside the feature 17:01:45.619 T:139808483563264 DEBUG: DVDNAV_CELL_CHANGE 17:01:45.619 T:139808483563264 INFO: msg: libdvdread: Can't seek to block 790666 17:01:45.620 T:139808483563264 ERROR: Error getting next block: Error reading NAV packet. 17:01:45.620 T:139808483563264 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:01:45.620 T:139808483563264 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:01:45.620 T:139808483563264 NOTICE: CVideoPlayer::OnExit() 17:01:45.620 T:139808483563264 NOTICE: VideoPlayer: eof, waiting for queues to empty 17:01:45.620 T:139808483563264 NOTICE: Closing stream player 1 17:01:45.620 T:139808483563264 NOTICE: Waiting for audio thread to exit 17:01:45.627 T:139807571678976 NOTICE: thread end: CVideoPlayerAudio::OnExit() 17:01:45.628 T:139807571678976 DEBUG: Thread VideoPlayerAudio 139807571678976 terminating 17:01:45.628 T:139808483563264 NOTICE: Closing audio device 17:01:45.628 T:139808483563264 DEBUG: CDVDAudio::Flush - flush audio stream 17:01:45.630 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc 17:01:45.631 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek 17:01:45.638 T:139809046943488 DEBUG: CActiveAE::DiscardStream - audio stream deleted 17:01:45.638 T:139809046943488 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:01:45.639 T:139808483563264 DEBUG: Previous line repeats 2 times. 17:01:45.639 T:139808483563264 NOTICE: Deleting audio codec 17:01:45.639 T:139808483563264 NOTICE: Closing stream player 2 17:01:45.639 T:139808483563264 NOTICE: waiting for video thread to exit 17:01:45.639 T:139807183378176 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 17:01:45.639 T:139807183378176 NOTICE: thread end: video_thread 17:01:45.639 T:139808483563264 NOTICE: deleting video codec 17:01:45.639 T:139807183378176 DEBUG: Thread VideoPlayerVideo 139807183378176 terminating 17:01:45.640 T:139808483563264 NOTICE: CVDPAU::Release pre-cleanup 17:01:45.645 T:139806948452096 NOTICE: CMixer::OnExit: Output Thread terminated 17:01:45.646 T:139806948452096 DEBUG: Thread Vdpau Mixer 139806948452096 terminating 17:01:45.660 T:139808970110720 INFO: CActiveAESink::OpenSink - initialize sink 17:01:45.673 T:139807208494848 DEBUG: VDPAU::PreCleanup - released output surface 17:01:45.684 T:139808483563264 DEBUG: Previous line repeats 4 times. 17:01:45.684 T:139808483563264 NOTICE: Closing stream player 3 17:01:45.685 T:139808483563264 DEBUG: OnPlayBackEnded: play state was 2, starting 0 17:01:45.686 T:139808483563264 DEBUG: Thread VideoPlayer 139808483563264 terminating 17:01:45.690 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:01:45.691 T:139809076500608 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 17:01:45.691 T:139809076500608 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 17:01:45.696 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 17:01:45.696 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 17:01:45.703 T:139809076500608 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:01:45.703 T:139809076500608 DEBUG: ------ Window Init (Home.xml) ------ 17:01:45.705 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing.. 17:01:45.705 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing.. 17:01:45.705 T:139808483563264 DEBUG: Thread JobWorker start, auto delete: true 17:01:45.705 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing.. 17:01:45.706 T:139809076500608 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing.. 17:01:45.706 T:139806948452096 DEBUG: Thread JobWorker start, auto delete: true 17:01:45.852 T:139808970110720 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:@ 17:01:45.852 T:139808970110720 INFO: CAESinkALSA::Initialize - Attempting to open device "@" 17:01:45.869 T:139808970110720 INFO: CAESinkALSA::Initialize - Opened device "sysdefault" 17:01:45.870 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:01:45.870 T:139808970110720 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:01:45.870 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 1024, bufferSize 9600 17:01:45.871 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 9216 17:01:45.871 T:139808970110720 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 192 ms 17:01:45.871 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:01:45.871 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:01:45.871 T:139808970110720 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:01:45.871 T:139808970110720 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:01:45.871 T:139808970110720 DEBUG: Output Device : Default (HDA NVidia VT2020 Analog) 17:01:45.871 T:139808970110720 DEBUG: Sample Rate : 48000 17:01:45.871 T:139808970110720 DEBUG: Sample Format : AE_FMT_S32NE 17:01:45.871 T:139808970110720 DEBUG: Channel Count : 2 17:01:45.872 T:139808970110720 DEBUG: Channel Layout: FL,FR 17:01:45.872 T:139808970110720 DEBUG: Frames : 1024 17:01:45.872 T:139808970110720 DEBUG: Frame Size : 8 17:01:45.873 T:139809046943488 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:01:46.026 T:139808483563264 DEBUG: DoWork - Saving file state for video item removable://IKARIE_XB_1_344AB836/ 17:01:46.037 T:139809076500608 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 17:01:46.037 T:139809076500608 DEBUG: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 17:01:46.038 T:139809076500608 NOTICE: CVideoPlayer::CloseFile() 17:01:46.038 T:139809076500608 NOTICE: VideoPlayer: waiting for threads to exit 17:01:46.038 T:139809076500608 NOTICE: VideoPlayer: finished waiting 17:01:46.038 T:139809076500608 DEBUG: DeleteRenderer - deleting renderer 17:01:46.038 T:139809076500608 NOTICE: (VDPAU) Close 17:01:46.038 T:139809076500608 NOTICE: (VDPAU) FiniVDPAUOutput 17:01:46.045 T:139807208494848 NOTICE: VDPAU::COutput: vdpau gl interop finished 17:01:46.049 T:139807208494848 NOTICE: COutput::OnExit: Output Thread terminated 17:01:46.049 T:139807208494848 DEBUG: Thread Vdpau Output 139807208494848 terminating 17:01:46.084 T:139809076500608 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 17:01:46.084 T:139809076500608 NOTICE: VDPAU::Close - closing decoder context 17:01:46.096 T:139809076500608 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:01:46.098 T:139809076500608 NOTICE: CVideoPlayer::CloseFile() 17:01:46.098 T:139809076500608 NOTICE: VideoPlayer: waiting for threads to exit 17:01:46.098 T:139809076500608 NOTICE: VideoPlayer: finished waiting 17:01:46.102 T:139809076500608 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 17:01:46.651 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 17:01:46.652 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 17:02:07.287 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:02:12.833 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:02:12.914 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:02:12.915 T:139809076500608 DEBUG: Activating window ID: 10111 17:02:12.915 T:139809076500608 DEBUG: ------ Window Init (DialogButtonMenu.xml) ------ 17:02:12.915 T:139809076500608 INFO: Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY 17:02:13.698 T:139809076500608 DEBUG: ------ Window Init (Pointer.xml) ------ 17:02:15.818 T:139809076500608 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/VideoPlayer/libdvdnav-x86_64-linux.so) 17:02:19.046 T:139809076500608 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:02:19.126 T:139809076500608 DEBUG: ProcessMouse: trying mouse action leftclick 17:02:19.185 T:139809076500608 DEBUG: ------ Window Init (DialogBusy.xml) ------ 17:02:19.185 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnRestart from xbmc 17:02:19.185 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnRestart 17:02:19.342 T:139809076500608 INFO: Received SIGTERM... 17:02:19.342 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnQuit from xbmc 17:02:19.342 T:139809076500608 NOTICE: Storing total System Uptime 17:02:19.342 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnQuit 17:02:19.343 T:139809076500608 NOTICE: Saving settings 17:02:19.382 T:139809076500608 NOTICE: Saving skin settings 17:02:19.396 T:139809076500608 NOTICE: stop all 17:02:19.396 T:139808483563264 DEBUG: Thread JobWorker 139808483563264 terminating (autodelete) 17:02:19.397 T:139806948452096 DEBUG: Thread JobWorker 139806948452096 terminating (autodelete) 17:02:19.402 T:139809076500608 NOTICE: stop player 17:02:19.402 T:139809076500608 DEBUG: NetworkMessage - Signaling network services to stop 17:02:19.402 T:139809076500608 NOTICE: ES: Stopping event server 17:02:19.402 T:139809076500608 INFO: AIRPLAY: Cleaning up photoassetcache 17:02:19.405 T:139809076500608 DEBUG: CZeroconfAvahi::doRemoveService named: servers.airplay 17:02:19.819 T:139809076500608 DEBUG: CZeroconfAvahi::doRemoveService named: servers.airtunes 17:02:19.820 T:139809076500608 DEBUG: NetworkMessage - Waiting for network services to stop 17:02:19.820 T:139809076500608 NOTICE: stopping upnp 17:02:19.971 T:139809076500608 NOTICE: stopping zeroconf publishing 17:02:19.976 T:139809076500608 NOTICE: CWebServer[8080]: Stopped 17:02:20.214 T:139807913121536 DEBUG: Thread TCPServer 139807913121536 terminating 17:02:20.229 T:139807921514240 NOTICE: ES: UDP Event server stopped 17:02:20.229 T:139809076500608 INFO: AIRPLAY: Cleaning up photoassetcache 17:02:20.229 T:139807921514240 DEBUG: Thread EventServer 139807921514240 terminating 17:02:20.278 T:139807619540736 DEBUG: Thread AirPlayServer 139807619540736 terminating 17:02:20.278 T:139809076500608 NOTICE: stop dvd detect media 17:02:20.278 T:139808904673024 DEBUG: Thread DetectDVDMedia 139808904673024 terminating 17:02:20.278 T:139808491955968 DEBUG: Thread PeripEventScanner 139808491955968 terminating 17:02:20.313 T:139808923973376 DEBUG: Thread PeripBusUSBUdev 139808923973376 terminating 17:02:20.704 T:139808500348672 DEBUG: Thread PeripBusAddon 139808500348672 terminating 17:02:20.704 T:139808915580672 DEBUG: Thread PeripBusCEC 139808915580672 terminating 17:02:20.705 T:139809076500608 DEBUG: SECTION:UnloadDll(/usr/lib/kodi/addons/peripheral.joystick/peripheral.joystick.so.1.3.1) 17:02:20.706 T:139809076500608 INFO: ADDON: Dll Destroyed - Joystick Support 17:02:20.706 T:139809076500608 NOTICE: clean cached files! 17:02:20.706 T:139809076500608 DEBUG: ADDON: Stopping service addons. 17:02:20.711 T:139807602755328 INFO: ## LibreELEC Addon ## xdbus Monitor stopped. ## 17:02:20.730 T:139808466777856 DEBUG: ## LibreELEC Addon ## STOP SERVICE DONE ! 17:02:20.730 T:139808466777856 INFO: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): script successfully run 17:02:20.730 T:139808466777856 INFO: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): waiting on thread 139807611148032 17:02:20.731 T:139807611148032 INFO: ## LibreELEC Addon ## _service_::run ## MESSAGE:'exit' 17:02:20.747 T:139807594362624 INFO: ## LibreELEC Addon ## system::updateThread ## Stopped 17:02:20.831 T:139809076500608 DEBUG: CPythonInvoker(0, /usr/share/kodi/addons/service.libreelec.settings/service.py): script termination took 121ms 17:02:20.831 T:139809046943488 DEBUG: Thread ActiveAE 139809046943488 terminating 17:02:20.834 T:139808970110720 DEBUG: Thread AESink 139808970110720 terminating 17:02:20.849 T:139808466777856 INFO: Python script interrupted by user 17:02:20.849 T:139808466777856 DEBUG: Thread LanguageInvoker 139808466777856 terminating 17:02:21.026 T:139809076500608 NOTICE: closing down remote control service 17:02:21.026 T:139809076500608 INFO: LIRC SetEnabled: disabled 17:02:21.026 T:139809076500608 NOTICE: stopped 17:02:21.232 T:139809076500608 NOTICE: destroy 17:02:21.233 T:139809076500608 NOTICE: unload skin 17:02:21.233 T:139809076500608 INFO: Unloading old skin ... 17:02:21.235 T:139809076500608 DEBUG: ------ Window Deinit () ------ 17:02:21.235 T:139809076500608 DEBUG: ------ Window Deinit (Home.xml) ------ 17:02:21.249 T:139809076500608 DEBUG: ------ Window Deinit (DialogButtonMenu.xml) ------ 17:02:21.252 T:139809076500608 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 17:02:21.257 T:139809076500608 DEBUG: CloseBundle - Closed bundle 17:02:21.320 T:139809076500608 INFO: XRANDR: /usr/lib/kodi/kodi-xrandr --screen 0 --output DVI-D-0 --mode 0x1c6 17:02:21.376 T:139809076500608 NOTICE: unload sections 17:02:21.383 T:139809076496128 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 17:02:21.383 T:139809076496128 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 17:02:21.390 T:139809076500608 NOTICE: special://profile/ is mapped to: special://masterprofile/ 17:02:21.390 T:139809076500608 DEBUG: object 0 --> 0 instances 17:02:21.390 T:139809076500608 DEBUG: object 1 --> 1 instances 17:02:21.391 T:139809076500608 DEBUG: object 2 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: object 3 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: object 4 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: object 5 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: object 6 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: object 7 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: object 8 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: object 9 --> 0 instances 17:02:21.391 T:139809076500608 DEBUG: PVRManager - destroyed 17:02:21.391 T:139809076500608 DEBUG: ActiveAE DSP - destroyed 17:02:21.393 T:139809076500608 INFO: ADDON: cpluff: 'An unreleased information object was encountered at address 0x7f27c41ab0d0 with reference count 1 when destroying the associated plug-in context. Not releasing the object.' 17:02:21.394 T:139809076496128 DEBUG: Thread Announce 139809076496128 terminating 17:02:21.394 T:139809076500608 NOTICE: application stopped... 17:02:21.411 T:139809076500608 DEBUG: SECTION:UnloadDll(libcurl.so.4) 17:02:21.425 T:139809076500608 DEBUG: LogindUPowerSyscall - delay lock released