17:32:09.955 T:139971559856256 NOTICE: special://profile/ is mapped to: special://masterprofile/ 17:32:09.955 T:139971559856256 NOTICE: ----------------------------------------------------------------------- 17:32:09.955 T:139971559856256 NOTICE: Starting Kodi (17.1 Git:fc1619b). Platform: Linux x86 64-bit 17:32:09.955 T:139971559856256 NOTICE: Using Release Kodi x64 build 17:32:09.955 T:139971559856256 NOTICE: Kodi compiled Mar 21 2017 by GCC 6.2.0 for Linux x86 64-bit version 4.9.13 (264461) 17:32:09.955 T:139971559856256 NOTICE: Running on LibreELEC (official) - Version: 8.0.1, kernel: Linux x86 64-bit version 4.9.13 17:32:09.955 T:139971559856256 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi 17:32:09.955 T:139971559856256 NOTICE: Host CPU: Pentium(R) Dual-Core CPU E5300 @ 2.60GHz, 2 cores available 17:32:09.955 T:139971559856256 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi/ 17:32:09.955 T:139971559856256 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi 17:32:09.955 T:139971559856256 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons 17:32:09.955 T:139971559856256 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata 17:32:09.955 T:139971559856256 NOTICE: special://envhome/ is mapped to: /storage 17:32:09.955 T:139971559856256 NOTICE: special://home/ is mapped to: /storage/.kodi 17:32:09.955 T:139971559856256 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp 17:32:09.955 T:139971559856256 NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp 17:32:09.955 T:139971559856256 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin 17:32:09.955 T:139971559856256 NOTICE: Local hostname: HTPC 17:32:09.955 T:139971559856256 NOTICE: Log File is located: /storage/.kodi/temp//kodi.log 17:32:09.957 T:139971559856256 NOTICE: ----------------------------------------------------------------------- 17:32:09.992 T:139971559856256 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files 17:32:09.995 T:139971559856256 NOTICE: load settings... 17:32:11.627 T:139971559856256 NOTICE: Found 2 Lists of Devices 17:32:11.627 T:139971559856256 NOTICE: Enumerated ALSA devices: 17:32:11.627 T:139971559856256 NOTICE: Device 1 17:32:11.627 T:139971559856256 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0 17:32:11.627 T:139971559856256 NOTICE: m_displayName : HDA NVidia 17:32:11.627 T:139971559856256 NOTICE: m_displayNameExtra: HDMI #0 17:32:11.627 T:139971559856256 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 17:32:11.627 T:139971559856256 NOTICE: m_channels : FL,FR 17:32:11.627 T:139971559856256 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 17:32:11.627 T:139971559856256 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 17:32:11.627 T:139971559856256 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 17:32:11.627 T:139971559856256 NOTICE: Device 2 17:32:11.627 T:139971559856256 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=1 17:32:11.627 T:139971559856256 NOTICE: m_displayName : HDA NVidia 17:32:11.627 T:139971559856256 NOTICE: m_displayNameExtra: HDMI #1 17:32:11.627 T:139971559856256 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 17:32:11.627 T:139971559856256 NOTICE: m_channels : FL,FR 17:32:11.627 T:139971559856256 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 17:32:11.627 T:139971559856256 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 17:32:11.628 T:139971559856256 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 17:32:11.628 T:139971559856256 NOTICE: Device 3 17:32:11.628 T:139971559856256 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=2 17:32:11.628 T:139971559856256 NOTICE: m_displayName : HDA NVidia 17:32:11.628 T:139971559856256 NOTICE: m_displayNameExtra: HDMI #2 17:32:11.628 T:139971559856256 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 17:32:11.628 T:139971559856256 NOTICE: m_channels : FL,FR 17:32:11.628 T:139971559856256 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 17:32:11.628 T:139971559856256 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 17:32:11.628 T:139971559856256 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 17:32:11.628 T:139971559856256 NOTICE: Device 4 17:32:11.628 T:139971559856256 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=3 17:32:11.628 T:139971559856256 NOTICE: m_displayName : HDA NVidia 17:32:11.628 T:139971559856256 NOTICE: m_displayNameExtra: PHL PHILIPS on HDMI #3 17:32:11.628 T:139971559856256 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 17:32:11.628 T:139971559856256 NOTICE: m_channels : FL,FR 17:32:11.628 T:139971559856256 NOTICE: m_sampleRates : 32000,44100,48000 17:32:11.628 T:139971559856256 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 17:32:11.628 T:139971559856256 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 17:32:11.628 T:139971559856256 NOTICE: Enumerated PULSE devices: 17:32:11.628 T:139971559856256 NOTICE: Device 1 17:32:11.628 T:139971559856256 NOTICE: m_deviceName : Default 17:32:11.628 T:139971559856256 NOTICE: m_displayName : Default 17:32:11.628 T:139971559856256 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 17:32:11.628 T:139971559856256 NOTICE: m_deviceType : AE_DEVTYPE_PCM 17:32:11.628 T:139971559856256 NOTICE: m_channels : FL,FR 17:32:11.628 T:139971559856256 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 17:32:11.628 T:139971559856256 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 17:32:11.628 T:139971559856256 NOTICE: m_streamTypes : No passthrough capabilities 17:32:11.635 T:139971559856256 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml 17:32:11.635 T:139971559856256 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are... false 1 cputemp gputemp 30 17:32:11.635 T:139971559856256 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 17:32:11.635 T:139971559856256 NOTICE: Default Video Player: VideoPlayer 17:32:11.635 T:139971559856256 NOTICE: Default Audio Player: paplayer 17:32:11.635 T:139971559856256 NOTICE: Disabled debug logging due to GUI setting. Level 0. 17:32:11.635 T:139971559856256 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 17:32:11.635 T:139971559856256 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 17:32:11.648 T:139971559856256 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 17:32:11.648 T:139971559856256 NOTICE: Loaded playercorefactory configuration 17:32:11.648 T:139971559856256 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 17:32:11.648 T:139971559856256 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 17:32:11.763 T:139971559856256 NOTICE: Running database version Addons27 17:32:11.927 T:139971559856256 NOTICE: ADDONS: Using repository repository.kodi.game 17:32:11.927 T:139971559856256 NOTICE: ADDONS: Using repository repository.libreelec.tv 17:32:11.927 T:139971559856256 NOTICE: ADDONS: Using repository repository.xbmc.org 17:32:11.927 T:139971559856256 NOTICE: ADDONS: Using repository repository.gomioa 17:32:11.927 T:139971559856256 NOTICE: ADDONS: Using repository repository.alelec 17:32:12.243 T:139971559856256 NOTICE: Checking resolution 22 17:32:12.283 T:139971559856256 ERROR: failed to initialize egl display 17:32:12.283 T:139971559856256 NOTICE: Using visual 0x27 17:32:12.343 T:139971559856256 NOTICE: GL_VENDOR = NVIDIA Corporation 17:32:12.343 T:139971559856256 NOTICE: GL_RENDERER = GeForce GT 430/PCIe/SSE2 17:32:12.343 T:139971559856256 NOTICE: GL_VERSION = 4.5.0 NVIDIA 375.39 17:32:12.343 T:139971559856256 NOTICE: GL_SHADING_LANGUAGE_VERSION = 4.50 NVIDIA 17:32:12.343 T:139971559856256 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 1048576 17:32:12.343 T:139971559856256 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 1048576 17:32:12.343 T:139971559856256 NOTICE: GL_EXTENSIONS = GL_AMD_multi_draw_indirect GL_ARB_arrays_of_arrays GL_ARB_base_instance GL_ARB_blend_func_extended GL_ARB_buffer_storage GL_ARB_clear_buffer_object GL_ARB_clear_texture GL_ARB_clip_control GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_compute_shader GL_ARB_compute_variable_group_size GL_ARB_conditional_render_inverted GL_ARB_copy_buffer GL_ARB_copy_image GL_ARB_cull_distance GL_ARB_debug_output GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_derivative_control GL_ARB_direct_state_access GL_ARB_draw_buffers GL_ARB_draw_buffers_blend GL_ARB_draw_indirect GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_enhanced_layouts GL_ARB_ES2_compatibility GL_ARB_ES3_compatibility GL_ARB_ES3_1_compatibility GL_ARB_ES3_2_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_get_texture_sub_image GL_ARB_gl_spirv GL_ARB_gpu_shader5 GL_ARB_gpu_shader_fp64 GL_ARB_gpu_shader_int64 GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_indirect_parameters 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_multi_draw_indirect GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_parallel_shader_compile GL_ARB_pipeline_statistics_query GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_program_interface_query GL_ARB_provoking_vertex GL_ARB_query_buffer_object GL_ARB_robust_buffer_access_behavior GL_ARB_robustness GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_atomic_counter_ops GL_ARB_shader_atomic_counters GL_ARB_shader_bit_encoding GL_ARB_shader_draw_parameters GL_ARB_shader_group_vote GL_ARB_shader_image_load_store GL_ARB_shader_image_size GL_ARB_shader_objects GL_ARB_shader_precision GL_ARB_shader_storage_buffer_object GL_ARB_shader_subroutine GL_ARB_shader_texture_image_samples 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_sparse_buffer GL_ARB_sparse_texture GL_ARB_stencil_texturing GL_ARB_sync GL_ARB_tessellation_shader GL_ARB_texture_barrier GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_object_rgb32 GL_ARB_texture_buffer_range GL_ARB_texture_compression GL_ARB_texture_compression_bptc GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_cube_map_array GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_gather GL_ARB_texture_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_query_lod 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_feedback3 GL_ARB_transform_feedback_instanced GL_ARB_transform_feedback_overflow_query GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_attrib_64bit GL_ARB_vertex_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_polygon_offset_clamp GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shader_image_load_store GL_EXT_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_vertex_attrib_64bit GL_EXT_window_rectangles GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_NV_robustness_video_memory_purge GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KHR_context_flush_control GL_KHR_debug GL_KHR_no_error GL_KHR_robust_buffer_access_behavior GL_KHR_robustness GL_KTX_buffer_region GL_NV_alpha_to_coverage_dither_control GL_NV_bindless_multi_draw_indirect GL_NV_bindless_multi_draw_indirect_count GL_NV_blend_equation_advanced GL_NV_blend_square GL_NV_command_list GL_NV_compute_program5 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_draw_texture GL_NV_ES1_1_compatibility GL_NV_ES3_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_internalformat_sample_query GL_NV_gpu_program4_1 GL_NV_gpu_program5 GL_NV_gpu_program5_mem_extended GL_NV_gpu_program_fp64 GL_NV_gpu_shader5 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_path_rendering GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_atomic_counters GL_NV_shader_atomic_float GL_NV_shader_buffer_load GL_NV_shader_storage_buffer_object GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 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_uniform_buffer_unified_memory GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_attrib_integer_64bit GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_NV_shader_thread_group GL_KHR_blend_equation_advanced GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum 17:32:12.343 T:139971559856256 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 17:32:13.165 T:139971540924160 NOTICE: Running database version Addons27 17:32:13.166 T:139971540924160 NOTICE: Running database version ViewModes6 17:32:13.263 T:139971540924160 NOTICE: Running database version Textures13 17:32:13.287 T:139971540924160 NOTICE: Running database version MyMusic60 17:32:13.738 T:139971540924160 NOTICE: Running database version MyVideos107 17:32:13.740 T:139971540924160 NOTICE: Running database version TV29 17:32:13.741 T:139971540924160 NOTICE: Running database version Epg11 17:32:13.745 T:139971559856256 NOTICE: start dvd mediatype detection 17:32:14.104 T:139971559856256 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 17:32:14.212 T:139971559856256 NOTICE: initialize done 17:32:14.212 T:139971559856256 NOTICE: Running the application... 17:32:14.216 T:139971559856256 NOTICE: starting zeroconf publishing 17:32:14.216 T:139971559856256 NOTICE: CWebServer[8080]: Started 17:32:14.216 T:139971559856256 NOTICE: starting upnp client 17:32:14.237 T:139970450790144 NOTICE: ES: Starting UDP Event server on port 9777 17:32:14.239 T:139970450790144 NOTICE: UDP: Listening on port 9777 (ipv6 : false) 17:32:14.500 T:139970954123008 WARNING: CSkinInfo: failed to load skin settings 17:32:39.926 T:139971559856256 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 17:32:39.926 T:139971559856256 NOTICE: Enabled debug logging due to GUI setting. Level 2. 17:32:39.926 T:139971559856256 DEBUG: ------ Window Init () ------ 17:32:45.144 T:139971559856256 INFO: CheckIdle - Closing session to https://raw.githubusercontent.com (easy=0x7f4d98045c60, multi=0x7f4d98035fa0) 17:32:45.147 T:139971559856256 INFO: CheckIdle - Closing session to http://addons.libreelec.tv (easy=0x7f4d70085e00, multi=0x7f4d70085590) 17:32:45.147 T:139971559856256 INFO: CheckIdle - Closing session to http://mirrors.kodi.tv (easy=0x7f4d780eb9b0, multi=0x7f4d78204fe0) 17:32:46.177 T:139971559856256 INFO: CheckIdle - Closing session to http://kodi.alelec.net (easy=0x7f4d78136690, multi=0x7f4d7820b760) 17:32:46.694 T:139971559856256 INFO: CheckIdle - Closing session to http://raw.github.com (easy=0x7f4d700a9450, multi=0x7f4d700022d0) 17:33:00.556 T:139971540924160 DEBUG: Thread JobWorker 139971540924160 terminating (autodelete) 17:33:00.556 T:139970962515712 DEBUG: Thread JobWorker 139970962515712 terminating (autodelete) 17:33:00.615 T:139970492753664 DEBUG: Thread JobWorker 139970492753664 terminating (autodelete) 17:33:04.186 T:139971374946048 INFO: Detecting DVD-ROM media filesystem... 17:33:04.192 T:139971374946048 INFO: CD Analysis Report 17:33:04.192 T:139971374946048 INFO: __________________________________ 17:33:04.194 T:139971374946048 INFO: CD-ROM with ISO 9660 filesystem 17:33:04.194 T:139971374946048 INFO: ISO 9660: 1151849 blocks, label 17:33:04.194 T:139971374946048 INFO: Tracks overall:1; Audio tracks:0; Data tracks:1 17:33:04.539 T:139971559856256 DEBUG: LIRC: Update - NEW at 54474:ae 0 KEY_EXIT devinput (KEY_EXIT) 17:33:04.539 T:139971559856256 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 17:33:04.542 T:139971559856256 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:33:04.622 T:139971374946048 INFO: Using protocol iso9660:// 17:33:04.622 T:139971374946048 INFO: Disc label: 17:33:04.773 T:139971559856256 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 17:33:04.788 T:139971559856256 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:33:04.788 T:139971559856256 DEBUG: ------ Window Init (Settings.xml) ------ 17:33:04.789 T:139970492753664 DEBUG: Thread JobWorker start, auto delete: true 17:33:05.972 T:139971559856256 DEBUG: LIRC: Update - NEW at 55908:ae 0 KEY_EXIT devinput (KEY_EXIT) 17:33:05.972 T:139971559856256 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 17:33:05.973 T:139971559856256 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:33:06.223 T:139971559856256 DEBUG: ------ Window Deinit (Settings.xml) ------ 17:33:06.238 T:139971559856256 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:33:06.238 T:139971559856256 DEBUG: ------ Window Init (Home.xml) ------ 17:33:06.337 T:139971559856256 NOTICE: UDev: Changed / Added /var/media/SING 17:33:06.337 T:139971559856256 DEBUG: ------ Window Init (DialogNotification.xml) ------ 17:33:08.519 T:139971559856256 DEBUG: LIRC: Update - NEW at 58455:160 0 KEY_OK devinput (KEY_OK) 17:33:08.519 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:33:08.546 T:139971559856256 DEBUG: GetDiskUniqueId: Trying to retrieve ID for path /dev/cdrom 17:33:08.546 T:139971559856256 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/VideoPlayer/libdvdnav-x86_64-linux.so) 17:33:08.546 T:139971559856256 DEBUG: Loading: /usr/lib/kodi/system/players/VideoPlayer/libdvdnav-x86_64-linux.so 17:33:08.549 T:139971559856256 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 17:33:08.947 T:139971559856256 DEBUG: msg: libdvdnav: DVD Title: 17:33:08.947 T:139971559856256 DEBUG: msg: S 17:33:08.947 T:139971559856256 DEBUG: msg: I 17:33:08.947 T:139971559856256 DEBUG: msg: N 17:33:08.947 T:139971559856256 DEBUG: msg: G 17:33:08.947 T:139971559856256 DEBUG: msg: libdvdnav: DVD Serial Number: 17:33:08.947 T:139971559856256 DEBUG: msg: 4 17:33:08.947 T:139971559856256 DEBUG: msg: a 17:33:08.947 T:139971559856256 DEBUG: msg: 3 17:33:08.947 T:139971559856256 DEBUG: msg: e 17:33:08.947 T:139971559856256 DEBUG: msg: a 17:33:08.948 T:139971559856256 DEBUG: msg: c 17:33:08.948 T:139971559856256 DEBUG: msg: 1 17:33:08.948 T:139971559856256 DEBUG: Previous line repeats 1 times. 17:33:08.948 T:139971559856256 DEBUG: msg: libdvdnav: DVD Title (Alternative): 17:33:08.948 T:139971559856256 DEBUG: msg: D 17:33:08.948 T:139971559856256 DEBUG: msg: O 17:33:08.948 T:139971559856256 DEBUG: msg: M 17:33:08.948 T:139971559856256 DEBUG: msg: _ 17:33:08.948 T:139971559856256 DEBUG: msg: R 17:33:08.948 T:139971559856256 DEBUG: msg: 0 17:33:08.948 T:139971559856256 DEBUG: msg: 17:33:08.948 T:139971559856256 INFO: msg: libdvdnav: vm: DVD Title: SING 17:33:08.948 T:139971559856256 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 17:33:08.948 T:139971559856256 INFO: msg: 1 17:33:08.948 T:139971559856256 DEBUG: msg: 17:33:08.948 T:139971559856256 DEBUG: Previous line repeats 1 times. 17:33:08.948 T:139971559856256 DEBUG: msg: libdvdread: Attempting to retrieve all CSS keys 17:33:08.948 T:139971559856256 DEBUG: msg: libdvdread: This can take a _long_ time, please be patient 17:33:08.948 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x0000015c 17:33:08.955 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.955 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x0000043e 17:33:08.955 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.955 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x000004b8 17:33:08.955 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.955 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x00002a22 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x00012a6f 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00042971 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x000753f9 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.956 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x000cd369 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_07_1.VOB (0x000cd369)!! 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x000d7624 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_0.VOB (0x000d7624) 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x000dd523 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_1.VOB (0x000dd523)!! 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.957 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x0034e98e 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_09_1.VOB (0x0034e98e)!! 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_10_1.VOB at 0x0038b514 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_10_1.VOB (0x0038b514)!! 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_11_1.VOB at 0x0038f5c1 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_11_1.VOB (0x0038f5c1)!! 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.958 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_12_1.VOB at 0x0039018e 17:33:08.977 T:139971559856256 WARNING: dll_ioctl - 21394 request failed with error [5] Input/output error 17:33:08.990 T:139971559856256 WARNING: Previous line repeats 1 times. 17:33:08.990 T:139971559856256 WARNING: dll_ioctl - 21394 request failed with error [25] Inappropriate ioctl for device 17:33:08.996 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_12_1.VOB (0x0039018e)!! 17:33:08.996 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:08.996 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_13_1.VOB at 0x003901b0 17:33:09.013 T:139971559856256 WARNING: dll_ioctl - 21394 request failed with error [5] Input/output error 17:33:09.027 T:139971559856256 WARNING: Previous line repeats 1 times. 17:33:09.027 T:139971559856256 WARNING: dll_ioctl - 21394 request failed with error [25] Inappropriate ioctl for device 17:33:09.033 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_13_1.VOB (0x003901b0)!! 17:33:09.033 T:139971559856256 INFO: msg: libdvdread: Elapsed time 1 17:33:09.033 T:139971559856256 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_14_1.VOB at 0x003901d9 17:33:09.033 T:139971559856256 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_14_1.VOB (0x003901d9)!! 17:33:09.033 T:139971559856256 INFO: msg: libdvdread: Elapsed time 0 17:33:09.033 T:139971559856256 INFO: msg: libdvdread: Found 14 VTS's 17:33:09.033 T:139971559856256 INFO: msg: libdvdread: Elapsed time 1 17:33:09.033 T:139971559856256 DEBUG: Open - Setting region mask 01 17:33:09.033 T:139971559856256 DEBUG: GetDiskUniqueId: Got ID removable://SING_4a3eac11 for DVD disk 17:33:09.037 T:139971559851776 DEBUG: GetMovieId (removable://SING_4a3eac11/), query = select idMovie from movie where idFile=598 17:33:09.037 T:139971559851776 DEBUG: GetEpisodeId (removable://SING_4a3eac11/), query = select idEpisode from episode where idFile=598 17:33:09.037 T:139971559851776 DEBUG: GetMusicVideoId (removable://SING_4a3eac11/), query = select idMVideo from musicvideo where idFile=598 17:33:09.038 T:139971559851776 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 17:33:09.038 T:139971559851776 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 17:33:09.041 T:139971559856256 DEBUG: Loading settings for /var/media/SING/VIDEO_TS/VIDEO_TS.IFO 17:33:09.044 T:139971559856256 DEBUG: CPlayerCoreFactory::GetPlayers(/var/media/SING/VIDEO_TS/VIDEO_TS.IFO) 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 17:33:09.044 T:139971559856256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 17:33:09.044 T:139971559856256 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 17:33:09.044 T:139971559856256 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 17:33:09.044 T:139971559856256 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 17:33:09.044 T:139971559856256 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 17:33:09.044 T:139971559856256 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 17:33:09.047 T:139971559856256 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 17:33:09.048 T:139971559856256 NOTICE: VideoPlayer: Opening: /var/media/SING/VIDEO_TS/VIDEO_TS.IFO 17:33:09.048 T:139971559856256 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 17:33:09.048 T:139971559856256 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:33:09.048 T:139971559856256 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:33:09.049 T:139970962515712 DEBUG: Thread VideoPlayer start, auto delete: false 17:33:09.049 T:139970962515712 NOTICE: Creating InputStream 17:33:09.049 T:139970962515712 INFO: msg: libdvdnav: Using dvdnav version 5.0.4 17:33:09.049 T:139970962515712 INFO: dll_fopen - something opened the mount file, let's hope it knows what it's doing 17:33:09.049 T:139970962515712 INFO: msg: libdvdread: Attempting to use device /dev/sr0 mounted on /var/media/SING for CSS authentication 17:33:09.074 T:139970962515712 DEBUG: msg: libdvdnav: Can't read name block. Probably not a DVD-ROM device. 17:33:09.074 T:139970962515712 DEBUG: msg: libdvdnav: vm: dvd_read_name failed 17:33:09.074 T:139970962515712 INFO: msg: libdvdnav: vm: DVD Title: SING 17:33:09.074 T:139970962515712 INFO: msg: libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 17:33:09.074 T:139970962515712 INFO: msg: 1 17:33:09.074 T:139970962515712 DEBUG: msg: 17:33:09.074 T:139970962515712 DEBUG: Previous line repeats 1 times. 17:33:09.074 T:139970962515712 DEBUG: msg: libdvdread: Attempting to retrieve all CSS keys 17:33:09.074 T:139970962515712 DEBUG: msg: libdvdread: This can take a _long_ time, please be patient 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x0000015c 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x0000043e 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x000004b8 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x00002a22 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x00012a6f 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00042971 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.074 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x000753f9 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x000cd369 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_07_1.VOB (0x000cd369)!! 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x000d7624 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_0.VOB (0x000d7624) 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x000dd523 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_08_1.VOB (0x000dd523)!! 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x0034e98e 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_09_1.VOB (0x0034e98e)!! 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_10_1.VOB at 0x0038b514 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_10_1.VOB (0x0038b514)!! 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_11_1.VOB at 0x0038f5c1 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_11_1.VOB (0x0038f5c1)!! 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.075 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_12_1.VOB at 0x0039018e 17:33:09.093 T:139970962515712 WARNING: dll_ioctl - 21394 request failed with error [5] Input/output error 17:33:09.107 T:139970962515712 WARNING: Previous line repeats 1 times. 17:33:09.107 T:139970962515712 WARNING: dll_ioctl - 21394 request failed with error [25] Inappropriate ioctl for device 17:33:09.112 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_12_1.VOB (0x0039018e)!! 17:33:09.112 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.113 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_13_1.VOB at 0x003901b0 17:33:09.130 T:139970962515712 WARNING: dll_ioctl - 21394 request failed with error [5] Input/output error 17:33:09.143 T:139970962515712 WARNING: Previous line repeats 1 times. 17:33:09.143 T:139970962515712 WARNING: dll_ioctl - 21394 request failed with error [25] Inappropriate ioctl for device 17:33:09.149 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_13_1.VOB (0x003901b0)!! 17:33:09.149 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.149 T:139970962515712 INFO: msg: libdvdread: Get key for /VIDEO_TS/VTS_14_1.VOB at 0x003901d9 17:33:09.149 T:139970962515712 INFO: msg: libdvdread: Error cracking CSS key for /VIDEO_TS/VTS_14_1.VOB (0x003901d9)!! 17:33:09.149 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.149 T:139970962515712 INFO: msg: libdvdread: Found 14 VTS's 17:33:09.149 T:139970962515712 INFO: msg: libdvdread: Elapsed time 0 17:33:09.149 T:139970962515712 DEBUG: Open - Setting region mask 01 17:33:09.149 T:139970962515712 NOTICE: VideoPlayer: playing a file with menu's 17:33:09.149 T:139970962515712 NOTICE: Creating Demuxer 17:33:09.150 T:139970962515712 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 1 17:33:09.150 T:139970962515712 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 17:33:09.150 T:139970962515712 DEBUG: Open - avformat_find_stream_info starting 17:33:09.150 T:139970962515712 WARNING: could not find codec parameters for /var/media/SING/VIDEO_TS/VIDEO_TS.IFO 17:33:09.150 T:139970962515712 DEBUG: Open - av_find_stream_info finished 17:33:09.150 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Input #0, mpeg, /var/media/SING/VIDEO_TS/VIDEO_TS.IF': 17:33:09.150 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Duration: N/A, bitrate: N/A 17:33:09.150 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 17:33:09.150 T:139970962515712 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution () 17:33:09.150 T:139970962515712 NOTICE: Opening stream: 1 source: 512 17:33:09.150 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 17:33:09.150 T:139970962515712 NOTICE: Creating video codec with codec id: 0 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Video: - Opening 17:33:09.150 T:139970962515712 DEBUG: CDVDVideoCodecFFmpeg::Open() Unable to find codec 0 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Video: - Failed 17:33:09.150 T:139970962515712 ERROR: Unsupported video codec 17:33:09.150 T:139970962515712 NOTICE: Opening stream: 0 source: 512 17:33:09.150 T:139970962515712 NOTICE: Finding audio codec for: 0 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Audio: passthrough - Opening 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Audio: passthrough - Failed 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 17:33:09.150 T:139970962515712 DEBUG: CDVDAudioCodecFFmpeg::Open() Unable to find codec 0 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Audio: FFmpeg - Failed 17:33:09.150 T:139970962515712 ERROR: Unsupported audio codec 17:33:09.150 T:139970962515712 NOTICE: Opening stream: 0 source: 512 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 17:33:09.150 T:139970962515712 DEBUG: Open - Unable to find codec 0 17:33:09.150 T:139970962515712 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Failed 17:33:09.150 T:139970962515712 ERROR: OpenStream - Unable to init overlay codec 17:33:09.151 T:139970962515712 DEBUG: OnPlayBackStarted: play state was 1, starting 1 17:33:09.151 T:139970962515712 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:33:09.151 T:139971559856256 DEBUG: PlayFile: OpenFile succeed, play state 2 17:33:09.151 T:139970962515712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:33:09.151 T:139971559856256 DEBUG: OnPlayBackStarted: play state was 2, starting 0 17:33:09.151 T:139970962515712 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:33:09.151 T:139970962515712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:33:09.151 T:139970962515712 NOTICE: VideoPlayer: next stream, wait for old streams to be finished 17:33:09.151 T:139970962515712 NOTICE: Creating Demuxer 17:33:09.151 T:139970962515712 DEBUG: DVDNAV_VTS_CHANGE 17:33:09.151 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 17:33:09.151 T:139970962515712 INFO: Previous line repeats 1 times. 17:33:09.151 T:139970962515712 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution () 17:33:09.151 T:139970962515712 DEBUG: ProcessBlock - Cell change: Title 0, Chapter 0 17:33:09.151 T:139970962515712 DEBUG: ProcessBlock - At position 0% inside the feature 17:33:09.151 T:139970962515712 DEBUG: DVDNAV_CELL_CHANGE 17:33:09.203 T:139970962515712 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:0 TO:330077 DIFF:330077 17:33:09.204 T:139970962515712 DEBUG: Open - avformat_find_stream_info starting 17:33:09.206 T:139970492753664 DEBUG: CGUIInfoManager::SetCurrentMovie(/var/media/SING/VIDEO_TS/VIDEO_TS.IFO) 17:33:09.209 T:139971559851776 DEBUG: GetMovieId (removable://SING_4a3eac11/), query = select idMovie from movie where idFile=598 17:33:09.209 T:139971559851776 DEBUG: GetEpisodeId (removable://SING_4a3eac11/), query = select idEpisode from episode where idFile=598 17:33:09.209 T:139971559851776 DEBUG: GetMusicVideoId (removable://SING_4a3eac11/), query = select idMVideo from musicvideo where idFile=598 17:33:09.210 T:139971559851776 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 17:33:09.210 T:139971559851776 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 17:33:09.211 T:139970492753664 DEBUG: GetMovieId (removable://SING_4a3eac11/), query = select idMovie from movie where idFile=598 17:33:09.212 T:139970492753664 DEBUG: GetEpisodeId (removable://SING_4a3eac11/), query = select idEpisode from episode where idFile=598 17:33:09.212 T:139970492753664 DEBUG: GetMusicVideoId (removable://SING_4a3eac11/), query = select idMVideo from musicvideo where idFile=598 17:33:09.221 T:139970962515712 DEBUG: - DVDNAV_WAIT (HOLDING) 17:33:09.221 T:139970962515712 DEBUG: Open - av_find_stream_info finished 17:33:09.221 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Input #0, mpeg, /var/media/SING/VIDEO_TS/VIDEO_TS.IF': 17:33:09.221 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Duration: N/A, start: 0.330078, bitrate: 8000 kb/s 17:33:09.221 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Stream #0:0[0x1e0]: Video: mpeg2video (Main), yuv420p(tv, bt470bg/smpte170m/smpte170m), 720x480 [SAR 32:27 DAR 16:9], 8000 kb/s, 29.97 tbr, 90k tbn, 59.94 tbc 17:33:09.221 T:139970962515712 DEBUG: AddStream - fps may be unreliable since ffmpeg decoded only 1 frame(s) 17:33:09.221 T:139970962515712 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 17:33:09.221 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 17:33:09.221 T:139970962515712 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Couldn't open IFO for chosen title, exit.) 17:33:09.222 T:139970962515712 NOTICE: Opening stream: 0 source: 256 17:33:09.222 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 1 17:33:09.222 T:139970962515712 NOTICE: Creating video codec with codec id: 2 17:33:09.222 T:139970962515712 DEBUG: FactoryCodec - Video: - Opening 17:33:09.222 T:139970962515712 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 17:33:09.222 T:139970962515712 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 17:33:09.222 T:139970962515712 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 17:33:09.222 T:139970962515712 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 17:33:09.222 T:139970962515712 NOTICE: Creating video thread 17:33:09.227 T:139970220922624 DEBUG: Thread JobWorker start, auto delete: true 17:33:09.227 T:139971540924160 DEBUG: Thread VideoPlayerVideo start, auto delete: false 17:33:09.227 T:139971540924160 NOTICE: running thread: video_thread 17:33:09.227 T:139971540924160 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:33:09.227 T:139971540924160 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:33:09.227 T:139971540924160 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(720x480) 17:33:09.227 T:139971540924160 NOTICE: VDPAU::CreateContext - creating decoder context 17:33:09.322 T:139970962515712 DEBUG: DVDNAV_STILL_FRAME - waiting 3 sec, with delay of 0 sec 17:33:09.717 T:139971540924160 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000 17:33:09.717 T:139971540924160 NOTICE: VDPAU Decoder capabilities: 17:33:09.717 T:139971540924160 NOTICE: name level macbs width height 17:33:09.717 T:139971540924160 NOTICE: ------------------------------------ 17:33:09.717 T:139971540924160 NOTICE: MPEG1 0 8192 2048 2048 17:33:09.717 T:139971540924160 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048 17:33:09.717 T:139971540924160 NOTICE: MPEG2_MAIN 3 8192 2048 2048 17:33:09.717 T:139971540924160 NOTICE: H264_BASELINE 41 8192 2048 2048 17:33:09.717 T:139971540924160 NOTICE: H264_MAIN 41 8192 2048 2048 17:33:09.717 T:139971540924160 NOTICE: H264_HIGH 41 8192 2048 2048 17:33:09.717 T:139971540924160 NOTICE: VC1_SIMPLE 1 8190 2048 2048 17:33:09.717 T:139971540924160 NOTICE: VC1_MAIN 2 8190 2048 2048 17:33:09.717 T:139971540924160 NOTICE: VC1_ADVANCED 4 8190 2048 2048 17:33:09.717 T:139971540924160 NOTICE: MPEG4_PART2_ASP 5 8192 2048 2048 17:33:09.717 T:139971540924160 NOTICE: ------------------------------------ 17:33:09.717 T:139971540924160 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION 17:33:09.717 T:139971540924160 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS 17:33:09.717 T:139971540924160 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL 17:33:09.717 T:139971540924160 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL 17:33:09.717 T:139971540924160 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE 17:33:09.717 T:139971540924160 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_HIGH_QUALITY_SCALING_L1 17:33:09.747 T:139971540924160 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video-vdpau 17:33:09.750 T:139971540924160 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 720 OutHeight: 480 17:33:09.751 T:139971540924160 NOTICE: (VDPAU) screenWidth:720 vidWidth:720 surfaceWidth:720 17:33:09.751 T:139971540924160 NOTICE: (VDPAU) screenHeight:480 vidHeight:480 surfaceHeight:480 17:33:09.835 T:139970197022464 DEBUG: Thread Vdpau Output start, auto delete: false 17:33:09.835 T:139970197022464 NOTICE: COutput::OnStartup: Output Thread created 17:33:09.881 T:139970197022464 NOTICE: VDPAU::COutput::CreateGlxContext - created context 17:33:09.882 T:139970197022464 NOTICE: VDPAU::COutput: vdpau gl interop initialized 17:33:09.883 T:139970188629760 DEBUG: Thread Vdpau Mixer start, auto delete: false 17:33:09.883 T:139970188629760 NOTICE: CMixer::OnStartup: Output Thread created 17:33:09.883 T:139970188629760 NOTICE: (VDPAU) Creating the video mixer 17:33:09.885 T:139970197022464 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:33:10.287 T:139971540924160 NOTICE: Previous line repeats 3 times. 17:33:10.287 T:139971540924160 INFO: CVideoPlayerVideo - Stillframe detected, switching to forced 25.000000 fps 17:33:10.289 T:139970197022464 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:33:10.291 T:139970197022464 NOTICE: Previous line repeats 2 times. 17:33:10.291 T:139970197022464 NOTICE: VDPAU::COutput registered output surfaces 17:33:10.327 T:139971540924160 DEBUG: CRenderManager::Configure - change configuration. 720x480. display: 720x405. framerate: 0.00. format: VDPAU 17:33:10.342 T:139971559856256 DEBUG: DeleteRenderer - deleting renderer 17:33:10.342 T:139971559856256 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:33:10.343 T:139971559856256 DEBUG: Previous line repeats 1 times. 17:33:10.343 T:139971559856256 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:33:10.343 T:139971559856256 NOTICE: GL: Using VDPAU render method 17:33:10.343 T:139971559856256 NOTICE: GL: NPOT texture support detected 17:33:10.343 T:139971559856256 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:33:10.343 T:139971559856256 NOTICE: Using GL_TEXTURE_2D 17:33:10.343 T:139971559856256 DEBUG: CRenderManager::Configure - 5 17:33:10.393 T:139971559856256 DEBUG: GL: Vertex Shader compilation log: 17:33:10.393 T:139971559856256 DEBUG: GL: Vertex Shader compiled successfully 17:33:10.395 T:139971559856256 DEBUG: GL: Pixel Shader compilation log: 17:33:10.395 T:139971559856256 DEBUG: GL: Fragment Shader compiled successfully 17:33:10.426 T:139970962515712 DEBUG: CVideoPlayer::HandleMessages - player started 2 17:33:10.426 T:139970962515712 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 17:33:10.426 T:139970962515712 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 17:33:10.426 T:139971540924160 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-100000.000000) 17:33:10.432 T:139971559856256 DEBUG: Activating window ID: 12005 17:33:10.433 T:139971559856256 DEBUG: ------ Window Deinit (Home.xml) ------ 17:33:10.433 T:139971559856256 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 17:33:10.433 T:139971559856256 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 17:33:11.684 T:139971559856256 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 17:33:12.430 T:139970962515712 DEBUG: DVDNAV_HIGHLIGHT: Highlight button 2 17:33:12.430 T:139970962515712 DEBUG: - DVDNAV_VTS_CHANGE (HOLDING) 17:33:12.430 T:139970962515712 NOTICE: VideoPlayer: next stream, wait for old streams to be finished 17:33:12.430 T:139970962515712 NOTICE: Closing stream player 2 17:33:12.430 T:139970962515712 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty 17:33:12.430 T:139971540924160 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 17:33:12.430 T:139970962515712 NOTICE: waiting for video thread to exit 17:33:12.430 T:139971540924160 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 17:33:12.430 T:139971540924160 NOTICE: thread end: video_thread 17:33:12.430 T:139970962515712 NOTICE: deleting video codec 17:33:12.430 T:139971540924160 DEBUG: Thread VideoPlayerVideo 139971540924160 terminating 17:33:12.430 T:139970962515712 NOTICE: CVDPAU::Release pre-cleanup 17:33:12.431 T:139970188629760 NOTICE: CMixer::OnExit: Output Thread terminated 17:33:12.431 T:139970188629760 DEBUG: Thread Vdpau Mixer 139970188629760 terminating 17:33:12.431 T:139970197022464 ERROR: PreCleanup - could not find gl surface 17:33:12.432 T:139970962515712 ERROR: Previous line repeats 5 times. 17:33:12.432 T:139970962515712 NOTICE: Creating Demuxer 17:33:12.432 T:139970962515712 DEBUG: DVDNAV_VTS_CHANGE 17:33:12.432 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 17:33:12.432 T:139970962515712 INFO: Previous line repeats 1 times. 17:33:12.432 T:139970962515712 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Couldn't open IFO for chosen title, exit.) 17:33:12.432 T:139970962515712 DEBUG: ProcessBlock - Cell change: Title 3, Chapter 1 17:33:12.432 T:139970962515712 DEBUG: ProcessBlock - At position 0% inside the feature 17:33:12.432 T:139970962515712 DEBUG: DVDNAV_CELL_CHANGE 17:33:12.432 T:139970962515712 INFO: ProcessBlock - none or invalid subtitle stream selected, defaulting to first 17:33:12.432 T:139970962515712 DEBUG: SetActiveSubtitleStream - id: 0, stream: 0 17:33:12.432 T:139970962515712 INFO: ProcessBlock - none or invalid audio stream selected, defaulting to first 17:33:12.432 T:139970962515712 DEBUG: SetActiveAudioStream - id: 0, stream: 0 17:33:12.433 T:139970962515712 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:3333077 TO:280633 DIFF:-3052444 17:33:12.433 T:139970962515712 DEBUG: Open - avformat_find_stream_info starting 17:33:12.472 T:139970962515712 DEBUG: Open - av_find_stream_info finished 17:33:12.472 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Input #0, mpeg, /var/media/SING/VIDEO_TS/VIDEO_TS.IF': 17:33:12.472 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Duration: N/A, start: 0.280633, bitrate: N/A 17:33:12.472 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Stream #0:0[0x1e0]: Video: mpeg2video (Main), yuv420p(tv), 720x480 [SAR 32:27 DAR 16:9], 32 fps, 59.94 tbr, 90k tbn, 59.94 tbc 17:33:12.473 T:139970962515712 INFO: ffmpeg[7F4D877FE700]: Stream #0:1[0x80]: Audio: ac3, 48000 Hz, 5.1(side), fltp, 448 kb/s 17:33:12.473 T:139970962515712 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 17:33:12.473 T:139970962515712 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 17:33:12.473 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 17:33:12.473 T:139970962515712 WARNING: CDVDInputStreamNavigator::GetVideoResolution - Failed to get resolution (Couldn't open IFO for chosen title, exit.) 17:33:12.473 T:139970962515712 NOTICE: Opening stream: 0 source: 256 17:33:12.473 T:139970962515712 INFO: GetVideoAspectRatio - Aspect wanted: 3, Scale permissions: 2 17:33:12.473 T:139970962515712 NOTICE: Creating video codec with codec id: 2 17:33:12.473 T:139970962515712 DEBUG: FactoryCodec - Video: - Opening 17:33:12.473 T:139970962515712 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video 17:33:12.473 T:139970962515712 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video 17:33:12.473 T:139970962515712 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened 17:33:12.473 T:139970962515712 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 2 17:33:12.473 T:139970962515712 NOTICE: Creating video thread 17:33:12.473 T:139970188629760 DEBUG: Thread VideoPlayerVideo start, auto delete: false 17:33:12.473 T:139970188629760 NOTICE: running thread: video_thread 17:33:12.473 T:139970188629760 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:33:12.473 T:139970188629760 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:33:12.473 T:139970188629760 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(720x480) 17:33:12.473 T:139970962515712 NOTICE: Opening stream: 1 source: 256 17:33:12.474 T:139970962515712 NOTICE: Finding audio codec for: 86019 17:33:12.474 T:139970962515712 DEBUG: FactoryCodec - Audio: passthrough - Opening 17:33:12.474 T:139970962515712 DEBUG: FactoryCodec - Audio: passthrough - Failed 17:33:12.474 T:139970962515712 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 17:33:12.474 T:139970962515712 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 17:33:12.474 T:139970962515712 NOTICE: Creating audio thread 17:33:12.475 T:139971540924160 DEBUG: Thread VideoPlayerAudio start, auto delete: false 17:33:12.475 T:139971540924160 NOTICE: running thread: CVideoPlayerAudio::Process() 17:33:12.476 T:139971540924160 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through) 17:33:12.476 T:139971540924160 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 17:33:12.491 T:139971520714496 INFO: CActiveAESink::OpenSink - initialize sink 17:33:12.527 T:139970188629760 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video-vdpau 17:33:12.528 T:139970188629760 DEBUG: CVDPAU::SetWidthHeight Setting OutWidth: 720 OutHeight: 480 17:33:12.528 T:139970188629760 NOTICE: (VDPAU) screenWidth:720 vidWidth:720 surfaceWidth:720 17:33:12.528 T:139970188629760 NOTICE: (VDPAU) screenHeight:480 vidHeight:480 surfaceHeight:480 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:3920555.555556, diff:2722366.666667 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:3970633.333333, diff:2755766.666667 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4004000.000000, diff:2789133.333333 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4054055.555556, diff:2839188.888889 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4087411.111111, diff:2889222.222222 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4137455.555556, diff:2939266.666667 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4170822.222222, diff:2972633.333333 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4220866.666667, diff:3006000.000000 17:33:12.528 T:139970962515712 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 17:33:12.528 T:139970962515712 NOTICE: Opening stream: 2 source: 256 17:33:12.528 T:139970962515712 DEBUG: CVideoPlayer::ProcessSubData: Got complete SPU packet 17:33:12.529 T:139970962515712 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3 17:33:12.529 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4254233.333333, diff:3039366.666667 17:33:12.529 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4304277.777778, diff:3089411.111111 17:33:12.529 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4337644.444444, diff:3139455.555556 17:33:12.529 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :2, prev:1164822.222222, curr:4387688.888889, diff:3189500.000000 17:33:12.529 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync forward :1, prev:1016633.333333, curr:3771000.000000, diff:2572811.111111 17:33:12.529 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - update correction: 2572811.111111 17:33:12.584 T:139970179184384 DEBUG: Thread Vdpau Output start, auto delete: false 17:33:12.584 T:139970179184384 NOTICE: COutput::OnStartup: Output Thread created 17:33:12.600 T:139970179184384 NOTICE: VDPAU::COutput::CreateGlxContext - created context 17:33:12.601 T:139970179184384 NOTICE: VDPAU::COutput: vdpau gl interop initialized 17:33:12.601 T:139970168796928 DEBUG: Thread Vdpau Mixer start, auto delete: false 17:33:12.601 T:139970168796928 NOTICE: CMixer::OnStartup: Output Thread created 17:33:12.601 T:139970168796928 NOTICE: (VDPAU) Creating the video mixer 17:33:12.604 T:139970179184384 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:33:12.605 T:139970188629760 NOTICE: Previous line repeats 3 times. 17:33:12.605 T:139970188629760 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:33:12.613 T:139970179184384 DEBUG: Previous line repeats 2 times. 17:33:12.613 T:139970179184384 NOTICE: VDPAU::COutput::InitBufferPool - Output Surface created 17:33:12.614 T:139970179184384 NOTICE: Previous line repeats 2 times. 17:33:12.614 T:139970179184384 NOTICE: VDPAU::COutput registered output surfaces 17:33:12.614 T:139970188629760 DEBUG: CRenderManager::Configure - change configuration. 720x480. display: 720x405. framerate: 59.94. format: VDPAU 17:33:12.615 T:139970179184384 NOTICE: VDPAU::COutput registered output surfaces 17:33:12.648 T:139971559856256 NOTICE: (VDPAU) Close 17:33:12.648 T:139971559856256 NOTICE: (VDPAU) FiniVDPAUOutput 17:33:12.650 T:139970197022464 NOTICE: VDPAU::COutput: vdpau gl interop finished 17:33:12.659 T:139970197022464 NOTICE: COutput::OnExit: Output Thread terminated 17:33:12.660 T:139970197022464 DEBUG: Thread Vdpau Output 139970197022464 terminating 17:33:12.671 T:139971559856256 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 17:33:12.671 T:139971559856256 NOTICE: GL: Using VDPAU render method 17:33:12.671 T:139971559856256 NOTICE: GL: NPOT texture support detected 17:33:12.671 T:139971559856256 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:33:12.671 T:139971559856256 NOTICE: Using GL_TEXTURE_2D 17:33:12.671 T:139971559856256 DEBUG: CRenderManager::Configure - 5 17:33:12.672 T:139971559856256 DEBUG: GL: Vertex Shader compilation log: 17:33:12.672 T:139971559856256 DEBUG: GL: Vertex Shader compiled successfully 17:33:12.672 T:139971559856256 DEBUG: GL: Pixel Shader compilation log: 17:33:12.672 T:139971559856256 DEBUG: GL: Fragment Shader compiled successfully 17:33:12.696 T:139970962515712 DEBUG: CVideoPlayer::HandleMessages - player started 2 17:33:12.819 T:139971520714496 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=3 17:33:12.820 T:139971520714496 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=3" 17:33:12.822 T:139971520714496 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=3,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 17:33:12.823 T:139971520714496 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:33:12.823 T:139971520714496 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:33:12.823 T:139971520714496 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 17:33:12.993 T:139971520714496 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 17:33:12.993 T:139971520714496 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:33:13.123 T:139971520714496 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:33:13.123 T:139971520714496 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:33:13.123 T:139971520714496 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:33:13.123 T:139971520714496 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:33:13.123 T:139971520714496 DEBUG: Output Device : HDA NVidia 17:33:13.123 T:139971520714496 DEBUG: Sample Rate : 48000 17:33:13.123 T:139971520714496 DEBUG: Sample Format : AE_FMT_S32NE 17:33:13.123 T:139971520714496 DEBUG: Channel Count : 2 17:33:13.123 T:139971520714496 DEBUG: Channel Layout: FL,FR 17:33:13.123 T:139971520714496 DEBUG: Frames : 2400 17:33:13.123 T:139971520714496 DEBUG: Frame Size : 8 17:33:13.125 T:139971529107200 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:33:13.254 T:139970962515712 DEBUG: Previous line repeats 1 times. 17:33:13.254 T:139970962515712 DEBUG: CVideoPlayer::HandleMessages - player started 1 17:33:13.254 T:139970962515712 DEBUG: VideoPlayer::Sync - Audio - pts: 376633.000000, cache: 326398.683413, totalcache: 600000.023842 17:33:13.254 T:139970962515712 DEBUG: VideoPlayer::Sync - Video - pts: 280633.000000, cache: 50000.000000, totalcache: 100000.000000 17:33:13.254 T:139970188629760 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(50234.316587) 17:33:13.254 T:139970188629760 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:33:13.254 T:139971540924160 DEBUG: Previous line repeats 2 times. 17:33:13.254 T:139971540924160 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(50234.316587) 17:33:13.254 T:139971540924160 DEBUG: CDVDAudio::Resume - resume audio stream 17:33:13.256 T:139970188629760 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:33:13.256 T:139971529107200 DEBUG: Previous line repeats 2 times. 17:33:13.256 T:139971529107200 DEBUG: ActiveAE - start sync of audio stream 17:33:13.256 T:139970179184384 NOTICE: VDPAU::COutput registered output surfaces 17:33:13.383 T:139971529107200 NOTICE: Previous line repeats 4 times. 17:33:13.383 T:139971529107200 DEBUG: ActiveAE::SyncStream - average error of 40.179423, start adjusting 17:33:13.383 T:139971529107200 DEBUG: ActiveAE::SyncStream - average error 0.179423 below threshold of 30.000000 17:33:13.483 T:139970962515712 DEBUG: - DVDNAV_WAIT (HOLDING) 17:33:13.583 T:139970962515712 DEBUG: ProcessBlock - Cell change: Title 3, Chapter 1 17:33:13.583 T:139970962515712 DEBUG: ProcessBlock - At position 2% inside the feature 17:33:13.583 T:139970962515712 DEBUG: DVDNAV_CELL_CHANGE 17:33:13.700 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync backward :1, prev:8494188.888889, curr:5803822.222222, diff:-2690366.666667 17:33:13.701 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync backward :1, prev:8494188.888889, curr:5835822.222222, diff:-2658366.666667 17:33:13.701 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - resync backward :2, prev:8655055.555556, curr:5966066.666667, diff:-2688988.888889 17:33:13.701 T:139970962515712 DEBUG: CVideoPlayer::CheckContinuity - update correction: -2722355.555556 17:33:14.433 T:139971540924160 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:45018.821863, adjusted:45018.821863 17:33:14.450 T:139970188629760 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 87966 17:33:14.969 T:139970188629760 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: lost diff 17:33:15.432 T:139970188629760 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 42466 17:33:15.434 T:139971540924160 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:77263.908793, adjusted:77263.908793 17:33:20.597 T:139971559856256 DEBUG: LIRC: Update - NEW at 70533:160 0 KEY_OK devinput (KEY_OK) 17:33:20.597 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is OSD 17:33:20.597 T:139971559856256 DEBUG: ------ Window Init (VideoOSD.xml) ------ 17:33:20.597 T:139971559856256 INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY 17:33:21.997 T:139971559856256 DEBUG: LIRC: Update - NEW at 71933:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:21.997 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:22.613 T:139971559856256 DEBUG: LIRC: Update - NEW at 72549:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:22.613 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:23.480 T:139971559856256 DEBUG: LIRC: Update - NEW at 73415:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:23.480 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:24.380 T:139971559856256 DEBUG: LIRC: Update - NEW at 74315:160 0 KEY_OK devinput (KEY_OK) 17:33:24.380 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:33:24.382 T:139970962515712 DEBUG: - go to menu 17:33:24.447 T:139970188629760 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.24, frameduration: 41708.333333 17:33:24.478 T:139970962515712 INFO: msg: libdvdread: Can't seek to block 882170 17:33:24.478 T:139970962515712 INFO: Previous line repeats 1 times. 17:33:24.478 T:139970962515712 INFO: msg: libdvdread: Invalid IFO for title 8 (VTS_08_0.IFO). 17:33:24.478 T:139970962515712 DEBUG: msg: libdvdnav: ifoOpenVTSI failed 17:33:24.680 T:139971559856256 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 17:33:28.478 T:139971559856256 DEBUG: LIRC: Update - NEW at 78414:160 0 KEY_OK devinput (KEY_OK) 17:33:28.478 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is OSD 17:33:28.478 T:139971559856256 DEBUG: ------ Window Init (VideoOSD.xml) ------ 17:33:29.345 T:139971559856256 DEBUG: LIRC: Update - NEW at 79281:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:29.345 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:29.961 T:139971559856256 DEBUG: LIRC: Update - NEW at 79897:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:29.961 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:30.578 T:139971559856256 DEBUG: LIRC: Update - NEW at 80514:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:30.578 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:31.911 T:139971559856256 DEBUG: LIRC: Update - NEW at 81847:160 0 KEY_OK devinput (KEY_OK) 17:33:31.911 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:33:31.914 T:139970962515712 DEBUG: - go to menu 17:33:31.914 T:139970962515712 INFO: msg: libdvdread: Can't seek to block 882170 17:33:31.914 T:139970962515712 INFO: Previous line repeats 1 times. 17:33:31.914 T:139970962515712 INFO: msg: libdvdread: Invalid IFO for title 8 (VTS_08_0.IFO). 17:33:31.914 T:139970962515712 DEBUG: msg: libdvdnav: ifoOpenVTSI failed 17:33:32.145 T:139971559856256 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 17:33:39.227 T:139970220922624 DEBUG: Thread JobWorker 139970220922624 terminating (autodelete) 17:33:39.227 T:139970492753664 DEBUG: Thread JobWorker 139970492753664 terminating (autodelete) 17:33:43.408 T:139971559856256 DEBUG: LIRC: Update - NEW at 93343:16d 0 KEY_EPG devinput (KEY_EPG) 17:33:43.408 T:139971559856256 DEBUG: OnKey: yellow (0xe5) pressed, action is PlayerProcessInfo 17:33:43.408 T:139971559856256 DEBUG: Activating window ID: 10116 17:33:43.408 T:139971559856256 DEBUG: ------ Window Init (DialogPlayerProcessInfo.xml) ------ 17:33:43.408 T:139971559856256 INFO: Loading skin file: DialogPlayerProcessInfo.xml, load type: KEEP_IN_MEMORY 17:33:45.958 T:139971559856256 DEBUG: LIRC: Update - NEW at 95893:16d 0 KEY_EPG devinput (KEY_EPG) 17:33:45.958 T:139971559856256 DEBUG: OnKey: yellow (0xe5) pressed, action is PlayerProcessInfo 17:33:46.177 T:139971559856256 DEBUG: ------ Window Deinit (DialogPlayerProcessInfo.xml) ------ 17:33:47.590 T:139971559856256 DEBUG: LIRC: Update - NEW at 97526:160 0 KEY_OK devinput (KEY_OK) 17:33:47.590 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is OSD 17:33:47.590 T:139971559856256 DEBUG: ------ Window Init (VideoOSD.xml) ------ 17:33:49.340 T:139971559856256 DEBUG: LIRC: Update - NEW at 99276:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:49.341 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:49.940 T:139971559856256 DEBUG: LIRC: Update - NEW at 99876:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:49.940 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:50.607 T:139971559856256 DEBUG: LIRC: Update - NEW at 100542:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:50.607 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:51.340 T:139971559856256 DEBUG: LIRC: Update - NEW at 101276:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:51.340 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:52.773 T:139971559856256 DEBUG: LIRC: Update - NEW at 102708:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:52.773 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:53.373 T:139971559856256 DEBUG: LIRC: Update - NEW at 103308:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:53.373 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:54.106 T:139971559856256 DEBUG: LIRC: Update - NEW at 104041:69 0 KEY_LEFT devinput (KEY_LEFT) 17:33:54.106 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:33:55.722 T:139971559856256 DEBUG: LIRC: Update - NEW at 105658:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 17:33:55.722 T:139971559856256 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 17:33:57.205 T:139971559856256 DEBUG: LIRC: Update - NEW at 107141:160 0 KEY_OK devinput (KEY_OK) 17:33:57.205 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:33:57.205 T:139971559856256 DEBUG: Activating window ID: 10123 17:33:57.205 T:139971559856256 DEBUG: ------ Window Init (DialogSettings.xml) ------ 17:33:57.205 T:139971559856256 INFO: Loading skin file: DialogSettings.xml, load type: KEEP_IN_MEMORY 17:33:57.218 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.interlacemethod) was not found. 17:33:57.218 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.scalingmethod) was not found. 17:33:57.218 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.stream) was not found. 17:33:57.218 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.viewmode) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.zoom) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.verticalshift) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.pixelratio) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.postprocess) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.brightness) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.contrast) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (vdpau.noise) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (vdpau.sharpness) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.nonlinearstretch) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.stereoscopicmode) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.stereoscopicinvert) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.save) was not found. 17:33:57.219 T:139971559856256 DEBUG: CSettingsManager: requested setting (video.calibration) was not found. 17:34:00.737 T:139971559856256 DEBUG: LIRC: Update - NEW at 110673:ae 0 KEY_EXIT devinput (KEY_EXIT) 17:34:00.738 T:139971559856256 DEBUG: OnKey: menu (0xd8) pressed, action is Back 17:34:00.957 T:139971559856256 DEBUG: ------ Window Deinit (DialogSettings.xml) ------ 17:34:02.137 T:139971559856256 DEBUG: LIRC: Update - NEW at 112073:69 0 KEY_LEFT devinput (KEY_LEFT) 17:34:02.137 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:34:02.787 T:139971559856256 DEBUG: LIRC: Update - NEW at 112722:69 0 KEY_LEFT devinput (KEY_LEFT) 17:34:02.787 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:34:03.670 T:139971559856256 DEBUG: LIRC: Update - NEW at 113605:69 0 KEY_LEFT devinput (KEY_LEFT) 17:34:03.670 T:139971559856256 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is Left 17:34:04.553 T:139971559856256 DEBUG: LIRC: Update - NEW at 114489:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 17:34:04.553 T:139971559856256 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 17:34:05.286 T:139971559856256 DEBUG: LIRC: Update - NEW at 115222:160 0 KEY_OK devinput (KEY_OK) 17:34:05.286 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:34:05.287 T:139971559856256 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 17:34:05.296 T:139970962515712 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:34:05.296 T:139970962515712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:34:05.296 T:139970962515712 DEBUG: SeekChapter - chapter seeking using input stream 17:34:05.333 T:139971540924160 DEBUG: CDVDAudio::Pause - pausing audio stream 17:34:05.395 T:139970962515712 DEBUG: SetActiveSubtitleStream - id: 0, stream: 0 17:34:05.395 T:139970962515712 DEBUG: SetActiveAudioStream - id: 0, stream: 0 17:34:05.395 T:139970962515712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 17:34:05.433 T:139971540924160 DEBUG: CDVDAudio::Flush - flush audio stream 17:34:05.433 T:139971540924160 DEBUG: CDVDAudio::Pause - pausing audio stream 17:34:05.433 T:139971540924160 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 17:34:05.433 T:139970188629760 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 17:34:05.434 T:139970962515712 DEBUG: DVDNAV_HOP_CHANNEL 17:34:05.434 T:139970962515712 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers 17:34:05.443 T:139971540924160 DEBUG: CDVDAudio::Flush - flush audio stream 17:34:05.443 T:139971540924160 DEBUG: CDVDAudio::Pause - pausing audio stream 17:34:05.443 T:139971540924160 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 17:34:05.443 T:139970188629760 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 17:34:05.444 T:139970962515712 DEBUG: ProcessBlock - Cell change: Title 3, Chapter 2 17:34:05.444 T:139970962515712 DEBUG: ProcessBlock - At position 100% inside the feature 17:34:05.444 T:139970962515712 DEBUG: DVDNAV_CELL_CHANGE 17:34:05.528 T:139970962515712 DEBUG: DVDNAV_NAV_PACKET (HOLDING) 17:34:05.628 T:139970962515712 DEBUG: DVDNAV_NAV_PACKET - DISCONTINUITY FROM:60557511 TO:330077 DIFF:-60227433 17:34:05.630 T:139970188629760 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:34:05.630 T:139970188629760 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:34:05.631 T:139970962515712 INFO: msg: libdvdread: Can't seek to block 840546 17:34:05.631 T:139970962515712 INFO: Previous line repeats 1 times. 17:34:05.631 T:139970962515712 INFO: msg: libdvdread: Invalid IFO for title 7 (VTS_07_0.IFO). 17:34:05.631 T:139970962515712 DEBUG: msg: libdvdnav: ifoOpenVTSI failed 17:34:05.631 T:139970962515712 DEBUG: DVDNAV_STOP 17:34:05.631 T:139970962515712 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:34:05.631 T:139970962515712 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000 17:34:05.631 T:139970962515712 NOTICE: CVideoPlayer::OnExit() 17:34:05.631 T:139970962515712 NOTICE: VideoPlayer: eof, waiting for queues to empty 17:34:05.631 T:139970962515712 NOTICE: Closing stream player 1 17:34:05.631 T:139970962515712 NOTICE: Waiting for audio thread to exit 17:34:05.635 T:139971540924160 NOTICE: thread end: CVideoPlayerAudio::OnExit() 17:34:05.635 T:139970962515712 NOTICE: Closing audio device 17:34:05.635 T:139971540924160 DEBUG: Thread VideoPlayerAudio 139971540924160 terminating 17:34:05.635 T:139970962515712 DEBUG: CDVDAudio::Flush - flush audio stream 17:34:05.635 T:139971529107200 DEBUG: CActiveAE::DiscardStream - audio stream deleted 17:34:05.635 T:139971529107200 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:34:05.635 T:139970962515712 DEBUG: Previous line repeats 2 times. 17:34:05.635 T:139970962515712 NOTICE: Deleting audio codec 17:34:05.635 T:139970962515712 NOTICE: Closing stream player 2 17:34:05.635 T:139970962515712 NOTICE: waiting for video thread to exit 17:34:05.635 T:139970188629760 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true 17:34:05.635 T:139970188629760 NOTICE: thread end: video_thread 17:34:05.635 T:139970962515712 NOTICE: deleting video codec 17:34:05.635 T:139970188629760 DEBUG: Thread VideoPlayerVideo 139970188629760 terminating 17:34:05.635 T:139970962515712 NOTICE: CVDPAU::Release pre-cleanup 17:34:05.636 T:139971559856256 DEBUG: ------ Window Init (DialogNotification.xml) ------ 17:34:05.637 T:139970168796928 NOTICE: CMixer::OnExit: Output Thread terminated 17:34:05.637 T:139970168796928 DEBUG: Thread Vdpau Mixer 139970168796928 terminating 17:34:05.653 T:139970179184384 DEBUG: VDPAU::PreCleanup - released output surface 17:34:05.657 T:139970962515712 DEBUG: Previous line repeats 4 times. 17:34:05.657 T:139970962515712 NOTICE: Closing stream player 3 17:34:05.657 T:139970962515712 DEBUG: OnPlayBackEnded: play state was 2, starting 0 17:34:05.657 T:139970962515712 DEBUG: Thread VideoPlayer 139970962515712 terminating 17:34:05.658 T:139971559856256 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:34:05.658 T:139971559856256 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 17:34:05.658 T:139971559856256 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 17:34:05.660 T:139971559851776 DEBUG: GetMovieId (removable://SING_4a3eac11/), query = select idMovie from movie where idFile=598 17:34:05.660 T:139971559851776 DEBUG: GetEpisodeId (removable://SING_4a3eac11/), query = select idEpisode from episode where idFile=598 17:34:05.660 T:139971559851776 DEBUG: GetMusicVideoId (removable://SING_4a3eac11/), query = select idMVideo from musicvideo where idFile=598 17:34:05.661 T:139971559856256 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:34:05.661 T:139971559856256 DEBUG: ------ Window Init (Home.xml) ------ 17:34:05.661 T:139971559851776 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 17:34:05.661 T:139971559851776 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 17:34:05.714 T:139971559856256 NOTICE: CVideoPlayer::CloseFile() 17:34:05.714 T:139971559856256 NOTICE: VideoPlayer: waiting for threads to exit 17:34:05.714 T:139971559856256 NOTICE: VideoPlayer: finished waiting 17:34:05.714 T:139971559856256 DEBUG: DeleteRenderer - deleting renderer 17:34:05.714 T:139971559856256 NOTICE: (VDPAU) Close 17:34:05.714 T:139971559856256 NOTICE: (VDPAU) FiniVDPAUOutput 17:34:05.716 T:139970179184384 NOTICE: VDPAU::COutput: vdpau gl interop finished 17:34:05.722 T:139970179184384 NOTICE: COutput::OnExit: Output Thread terminated 17:34:05.722 T:139970179184384 DEBUG: Thread Vdpau Output 139970179184384 terminating 17:34:05.748 T:139971559856256 DEBUG: CVDPAU::FiniVDPAUOutput destroying 0 video surfaces 17:34:05.748 T:139971559856256 NOTICE: VDPAU::Close - closing decoder context 17:34:05.753 T:139971559856256 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:34:05.755 T:139971559856256 NOTICE: CVideoPlayer::CloseFile() 17:34:05.755 T:139971559856256 NOTICE: VideoPlayer: waiting for threads to exit 17:34:05.755 T:139971559856256 NOTICE: VideoPlayer: finished waiting 17:34:05.757 T:139971559856256 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 17:34:05.758 T:139971559856256 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 17:34:05.833 T:139971520714496 INFO: CActiveAESink::OpenSink - initialize sink 17:34:05.971 T:139970179184384 DEBUG: Thread JobWorker start, auto delete: true 17:34:05.971 T:139970179184384 DEBUG: DoWork - Saving file state for video item removable://SING_4a3eac11/ 17:34:05.974 T:139970179184384 DEBUG: DoWork - Marking video item removable://SING_4a3eac11/ as watched 17:34:06.040 T:139971559851776 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 17:34:06.040 T:139971559851776 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 17:34:06.173 T:139971520714496 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=3 17:34:06.173 T:139971520714496 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=3" 17:34:06.177 T:139971520714496 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=3,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 17:34:06.178 T:139971520714496 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:34:06.178 T:139971520714496 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:34:06.178 T:139971520714496 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 17:34:06.336 T:139971520714496 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 17:34:06.336 T:139971520714496 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:34:06.466 T:139971520714496 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:34:06.466 T:139971520714496 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:34:06.466 T:139971520714496 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:34:06.466 T:139971520714496 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:34:06.466 T:139971520714496 DEBUG: Output Device : HDA NVidia 17:34:06.466 T:139971520714496 DEBUG: Sample Rate : 44100 17:34:06.466 T:139971520714496 DEBUG: Sample Format : AE_FMT_S32NE 17:34:06.466 T:139971520714496 DEBUG: Channel Count : 2 17:34:06.466 T:139971520714496 DEBUG: Channel Layout: FL,FR 17:34:06.466 T:139971520714496 DEBUG: Frames : 464 17:34:06.466 T:139971520714496 DEBUG: Frame Size : 8 17:34:06.467 T:139971529107200 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:34:11.038 T:139971559856256 DEBUG: Previous line repeats 1 times. 17:34:11.038 T:139971559856256 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 17:34:12.029 T:139971559856256 DEBUG: LIRC: Update - NEW at 121965:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 17:34:12.029 T:139971559856256 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 17:34:12.998 T:139971559856256 DEBUG: LIRC: Update - NEW at 122933:6c 0 KEY_DOWN devinput (KEY_DOWN) 17:34:12.998 T:139971559856256 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down 17:34:13.787 T:139971559856256 DEBUG: LIRC: Update - NEW at 123722:160 0 KEY_OK devinput (KEY_OK) 17:34:13.787 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:34:13.787 T:139971559856256 DEBUG: Activating window ID: 10004 17:34:14.021 T:139971559856256 DEBUG: ------ Window Deinit (Home.xml) ------ 17:34:14.035 T:139971559856256 DEBUG: ------ Window Init (Settings.xml) ------ 17:34:15.333 T:139971559856256 DEBUG: LIRC: Update - NEW at 125269:160 0 KEY_OK devinput (KEY_OK) 17:34:15.333 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:34:15.333 T:139971559856256 DEBUG: Activating window ID: 10016 17:34:15.572 T:139971559856256 DEBUG: ------ Window Deinit (Settings.xml) ------ 17:34:15.586 T:139971559856256 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 17:34:17.877 T:139971559856256 DEBUG: LIRC: Update - NEW at 127812:67 0 KEY_UP devinput (KEY_UP) 17:34:17.877 T:139971559856256 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up 17:34:18.705 T:139971559856256 DEBUG: LIRC: Update - NEW at 128641:6a 0 KEY_RIGHT devinput (KEY_RIGHT) 17:34:18.705 T:139971559856256 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right 17:34:19.469 T:139971559856256 DEBUG: LIRC: Update - NEW at 129405:160 0 KEY_OK devinput (KEY_OK) 17:34:19.469 T:139971559856256 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select 17:34:19.469 T:139971559856256 NOTICE: Disabled debug logging due to GUI setting. Level 0. 17:34:19.469 T:139971559856256 NOTICE: Log level changed to "LOG_LEVEL_NORMAL"