16:58:19 19.297455 T:140492205930560 NOTICE: special://profile/ is mapped to: special://masterprofile/ 16:58:19 19.297531 T:140492205930560 NOTICE: ----------------------------------------------------------------------- 16:58:19 19.297560 T:140492205930560 NOTICE: Starting Kodi (17.0-ALPHA1 Git:f6dd58e). Platform: Linux x86 64-bit 16:58:19 19.297583 T:140492205930560 NOTICE: Using Release Kodi x64 build 16:58:19 19.297607 T:140492205930560 NOTICE: Kodi compiled Mar 21 2016 by GCC 5.3.0 for Linux x86 64-bit version 4.4.6 (263174) 16:58:19 19.297756 T:140492205930560 NOTICE: Running on OpenELEC (Milhouse) - Version: devel-20160320235229-#0320-g0026734 [Build #0320] 8.0, kernel: Linux x86 64-bit version 4.4.6 16:58:19 19.297779 T:140492205930560 NOTICE: FFmpeg version: 2.8.6 16:58:19 19.297800 T:140492205930560 NOTICE: WARNING: unsupported ffmpeg version detected 16:58:19 19.297823 T:140492205930560 NOTICE: Host CPU: Intel(R) Celeron(R) CPU N3050 @ 1.60GHz, 2 cores available 16:58:19 19.297844 T:140492205930560 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi 16:58:19 19.297865 T:140492205930560 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi 16:58:19 19.297886 T:140492205930560 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata 16:58:19 19.297907 T:140492205930560 NOTICE: special://home/ is mapped to: /storage/.kodi 16:58:19 19.297928 T:140492205930560 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp 16:58:19 19.297951 T:140492205930560 NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp 16:58:19 19.297991 T:140492205930560 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin 16:58:19 19.298040 T:140492205930560 NOTICE: Local hostname: OpenELEC_NUC 16:58:19 19.298077 T:140492205930560 NOTICE: Log File is located: /storage/.kodi/temp//kodi.log 16:58:19 19.298109 T:140492205930560 NOTICE: ----------------------------------------------------------------------- 16:58:19 19.346624 T:140492205930560 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files 16:58:19 19.367935 T:140492205930560 NOTICE: load settings... 16:58:19 19.462776 T:140492205930560 NOTICE: Found 2 Lists of Devices 16:58:19 19.462833 T:140492205930560 NOTICE: Enumerated ALSA devices: 16:58:19 19.462856 T:140492205930560 NOTICE: Device 1 16:58:19 19.463058 T:140492205930560 NOTICE: m_deviceName : @ 16:58:19 19.463083 T:140492205930560 NOTICE: m_displayName : Default (HDA Intel PCH ALC283 Analog) 16:58:19 19.463104 T:140492205930560 NOTICE: m_displayNameExtra: 16:58:19 19.463125 T:140492205930560 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:58:19 19.463148 T:140492205930560 NOTICE: m_channels : FL,FR,BL,BR 16:58:19 19.463169 T:140492205930560 NOTICE: m_sampleRates : 48000 16:58:19 19.463188 T:140492205930560 NOTICE: m_dataFormats : AE_FMT_S32NE 16:58:19 19.463211 T:140492205930560 NOTICE: m_streamTypes : No passthrough capabilities 16:58:19 19.463245 T:140492205930560 NOTICE: Device 2 16:58:19 19.463280 T:140492205930560 NOTICE: m_deviceName : @:CARD=PCH,DEV=0 16:58:19 19.463303 T:140492205930560 NOTICE: m_displayName : HDA Intel PCH 16:58:19 19.463324 T:140492205930560 NOTICE: m_displayNameExtra: ALC283 Analog 16:58:19 19.463377 T:140492205930560 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:58:19 19.463398 T:140492205930560 NOTICE: m_channels : FL,FR,BL,BR 16:58:19 19.463421 T:140492205930560 NOTICE: m_sampleRates : 48000 16:58:19 19.463440 T:140492205930560 NOTICE: m_dataFormats : AE_FMT_S32NE 16:58:19 19.463463 T:140492205930560 NOTICE: m_streamTypes : No passthrough capabilities 16:58:19 19.463484 T:140492205930560 NOTICE: Device 3 16:58:19 19.463522 T:140492205930560 NOTICE: m_deviceName : iec958:CARD=PCH,DEV=0 16:58:19 19.463543 T:140492205930560 NOTICE: m_displayName : HDA Intel PCH 16:58:19 19.463585 T:140492205930560 NOTICE: m_displayNameExtra: ALC283 Digital S/PDIF 16:58:19 19.463606 T:140492205930560 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 16:58:19 19.463627 T:140492205930560 NOTICE: m_channels : FL,FR 16:58:19 19.463650 T:140492205930560 NOTICE: m_sampleRates : 44100,48000,88200,96000,192000 16:58:19 19.463675 T:140492205930560 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 16:58:19 19.463697 T:140492205930560 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512 16:58:19 19.463718 T:140492205930560 NOTICE: Device 4 16:58:19 19.463762 T:140492205930560 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=0 16:58:19 19.463785 T:140492205930560 NOTICE: m_displayName : HDA Intel PCH 16:58:19 19.463806 T:140492205930560 NOTICE: m_displayNameExtra: MJI marantz-AVR on HDMI 16:58:19 19.463827 T:140492205930560 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 16:58:19 19.463848 T:140492205930560 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,BC,BLOC,BROC 16:58:19 19.463869 T:140492205930560 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 16:58:19 19.463892 T:140492205930560 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 16:58:19 19.463915 T:140492205930560 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:58:19 19.463936 T:140492205930560 NOTICE: Enumerated PULSE devices: 16:58:19 19.463957 T:140492205930560 NOTICE: Device 1 16:58:19 19.463995 T:140492205930560 NOTICE: m_deviceName : Default 16:58:19 19.464018 T:140492205930560 NOTICE: m_displayName : Default 16:58:19 19.464039 T:140492205930560 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 16:58:19 19.464060 T:140492205930560 NOTICE: m_deviceType : AE_DEVTYPE_PCM 16:58:19 19.464081 T:140492205930560 NOTICE: m_channels : FL,FR 16:58:19 19.464104 T:140492205930560 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 16:58:19 19.464125 T:140492205930560 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 16:58:19 19.464148 T:140492205930560 NOTICE: m_streamTypes : No passthrough capabilities 16:58:19 19.478247 T:140492205930560 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml 16:58:19 19.478422 T:140492205930560 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are... false cputemp gputemp 30 4.0 16:58:19 19.480101 T:140492205930560 NOTICE: Loaded settings file from special://profile/advancedsettings.xml 16:58:19 19.480165 T:140492205930560 NOTICE: Contents of special://profile/advancedsettings.xml are... bicubic 16:58:19 19.480618 T:140492205930560 NOTICE: Default Video Player: VideoPlayer 16:58:19 19.480640 T:140492205930560 NOTICE: Default Audio Player: paplayer 16:58:19 19.480665 T:140492205930560 NOTICE: Disabled debug logging due to GUI setting. Level 0. 16:58:19 19.480686 T:140492205930560 NOTICE: Log level changed to "LOG_LEVEL_NORMAL" 16:58:19 19.480797 T:140492205930560 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 16:58:19 19.482038 T:140492205930560 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 16:58:19 19.482494 T:140492205930560 NOTICE: Loaded playercorefactory configuration 16:58:19 19.482624 T:140492205930560 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 16:58:19 19.482712 T:140492205930560 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 16:58:19 19.605856 T:140492205930560 NOTICE: Running database version Addons22 16:58:20 19.724237 T:140492205930560 NOTICE: ADDONS: Using repository repository.xbmc.org 16:58:20 19.724297 T:140492205930560 NOTICE: ADDONS: Using repository repository.openelec.tv 16:58:20 19.724319 T:140492205930560 NOTICE: ADDONS: Using repository repository.unofficial.addon.pro 16:58:20 19.724340 T:140492205930560 NOTICE: ADDONS: Using repository repository.kodi.game 16:58:20 19.765339 T:140492205930560 NOTICE: Checking resolution 16 16:58:20 19.863180 T:140492205930560 NOTICE: Using visual 0x20 16:58:20 19.903532 T:140492205930560 NOTICE: GL_VENDOR = Intel Open Source Technology Center 16:58:20 19.903595 T:140492205930560 NOTICE: GL_RENDERER = Mesa DRI Intel(R) HD Graphics (Cherryview) 16:58:20 19.903618 T:140492205930560 NOTICE: GL_VERSION = 3.0 Mesa 11.2.0-rc2 16:58:20 19.903641 T:140492205930560 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30 16:58:20 19.903793 T:140492205930560 NOTICE: GL_EXTENSIONS = GL_ARB_multisample GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_copy_texture GL_EXT_polygon_offset GL_EXT_subtexture GL_EXT_texture_object GL_EXT_vertex_array GL_EXT_compiled_vertex_array GL_EXT_texture GL_EXT_texture3D GL_IBM_rasterpos_clip GL_ARB_point_parameters GL_EXT_draw_range_elements GL_EXT_packed_pixels GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_separate_specular_color GL_EXT_texture_edge_clamp GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_ARB_framebuffer_sRGB GL_ARB_multitexture GL_EXT_framebuffer_sRGB GL_IBM_multimode_draw_arrays GL_IBM_texture_mirrored_repeat GL_3DFX_texture_compression_FXT1 GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_transpose_matrix GL_EXT_blend_func_separate GL_EXT_fog_coord GL_EXT_multi_draw_arrays GL_EXT_secondary_color GL_EXT_texture_env_add GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod_bias GL_INGR_blend_func_separate GL_NV_blend_square GL_NV_light_max_exponent GL_NV_texgen_reflection GL_NV_texture_env_combine4 GL_S3_s3tc GL_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_ARB_depth_texture GL_ARB_occlusion_query GL_ARB_shadow GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_mirrored_repeat GL_ARB_window_pos GL_EXT_stencil_two_side GL_EXT_texture_cube_map GL_NV_depth_clamp GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_shader GL_ARB_shader_objects GL_ARB_vertex_program GL_ARB_vertex_shader GL_ATI_draw_buffers GL_ATI_texture_env_combine3 GL_ATI_texture_float GL_EXT_shadow_funcs GL_EXT_stencil_wrap GL_MESA_pack_invert GL_NV_primitive_restart GL_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel GL_ARB_occlusion_query2 GL_ARB_point_sprite GL_ARB_shading_language_100 GL_ARB_sync GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ATI_blend_equation_separate GL_EXT_blend_equation_separate GL_OES_read_format GL_ARB_color_buffer_float GL_ARB_pixel_buffer_object GL_ARB_texture_compression_rgtc GL_ARB_texture_float GL_ARB_texture_rectangle GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_rectangle GL_EXT_texture_sRGB GL_EXT_texture_shared_exponent GL_ARB_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_packed_depth_stencil GL_APPLE_object_purgeable GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_conservative_depth GL_AMD_draw_buffers_blend GL_AMD_seamless_cubemap_per_texture GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_gather GL_ARB_texture_multisample GL_ARB_texture_query_lod GL_ARB_texture_rgb10_a2ui GL_ARB_uniform_buffer_object GL_ARB_vertex_type_2_10_10_10_rev GL_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_NV_texture_barrier GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_texture_compression_bptc GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_shader_atomic_counters GL_ARB_shader_image_load_store GL_ARB_shading_language_420pack GL_ARB_shading_language_packing GL_ARB_texture_storage GL_ARB_transform_feedback_instanced GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_transform_feedback GL_AMD_shader_trinary_minmax GL_ARB_ES3_compatibility GL_ARB_arrays_of_arrays GL_ARB_clear_buffer_object GL_ARB_copy_image GL_ARB_explicit_uniform_location GL_ARB_framebuffer_no_attachments GL_ARB_invalidate_subdata GL_ARB_program_interface_query GL_ARB_shader_image_size GL_ARB_shader_storage_buffer_object GL_ARB_stencil_texturing GL_ARB_texture_query_levels GL_ARB_texture_storage_multisample GL_ARB_texture_view GL_ARB_vertex_attrib_binding GL_KHR_debug GL_ARB_buffer_storage GL_ARB_clear_texture GL_ARB_multi_bind GL_ARB_seamless_cubemap_per_texture GL_ARB_shader_draw_parameters GL_ARB_texture_mirror_clamp_to_edge GL_ARB_vertex_type_10f_11f_11f_rev GL_EXT_shader_integer_mix GL_ARB_clip_control GL_ARB_conditional_render_inverted GL_ARB_derivative_control GL_ARB_get_texture_sub_image GL_ARB_pipeline_statistics_query GL_ARB_shader_texture_image_samples GL_ARB_texture_barrier GL_EXT_polygon_offset_clamp GL_KHR_context_flush_control GL_ARB_shader_clock GL_EXT_shader_samples_identical 16:58:20 20.199497 T:140492205930560 ERROR: CAddonCallbacksAddon - GetAddonSetting - can't find setting 'URL9' in 'Input Stream' 16:58:20 20.382080 T:140492205930560 NOTICE: Running database version Addons22 16:58:20 20.384310 T:140492205930560 NOTICE: Running database version ViewModes6 16:58:20 20.420355 T:140492205930560 NOTICE: Running database version Textures13 16:58:20 20.426907 T:140492205930560 NOTICE: Running database version MyMusic60 16:58:20 20.505747 T:140492205930560 NOTICE: Running database version MyVideos104 16:58:20 20.508471 T:140492205930560 NOTICE: Running database version TV29 16:58:20 20.510502 T:140492205930560 NOTICE: Running database version Epg11 16:58:20 20.512226 T:140492205930560 NOTICE: start dvd mediatype detection 16:58:20 20.536003 T:140491778135808 NOTICE: Register - new cec device registered on cec->/dev/ttyACM0: CEC Adapter (2548:1002) 16:58:21 20.746939 T:140492205930560 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList" 16:58:21 21.125929 T:140492205930560 NOTICE: ActiveAE DSP - starting 16:58:21 21.152260 T:140492205930560 NOTICE: initialize done 16:58:21 21.152313 T:140492205930560 NOTICE: Running the application... 16:58:21 21.166819 T:140492205930560 NOTICE: starting zeroconf publishing 16:58:21 21.167395 T:140492205930560 NOTICE: WebServer: Started the webserver 16:58:21 21.167448 T:140492205930560 NOTICE: starting upnp client 16:58:21 21.206490 T:140492205930560 NOTICE: starting upnp server 16:58:21 21.223734 T:140492205930560 NOTICE: starting upnp controller 16:58:21 21.225512 T:140492205930560 NOTICE: starting upnp renderer 16:58:21 21.249136 T:140490796136192 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 16:58:21 21.251604 T:140490796136192 NOTICE: UDP: Listening on port 9777 16:58:21 21.348301 T:140491324647168 WARNING: Create - unsupported protocol(androidapp) in androidapp://sources/apps/ 16:58:22 21.813213 T:140491294529280 WARNING: CSkinInfo: failed to load skin settings 16:59:56 115.984909 T:140492205930560 NOTICE: Samba is idle. Closing the remaining connections 17:00:15 134.783478 T:140492186994432 NOTICE: Found 2 Lists of Devices 17:00:15 134.783539 T:140492186994432 NOTICE: Enumerated ALSA devices: 17:00:15 134.783569 T:140492186994432 NOTICE: Device 1 17:00:15 134.783615 T:140492186994432 NOTICE: m_deviceName : @ 17:00:15 134.783646 T:140492186994432 NOTICE: m_displayName : Default (HDA Intel PCH ALC283 Analog) 17:00:15 134.783661 T:140492186994432 NOTICE: m_displayNameExtra: 17:00:15 134.783691 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_PCM 17:00:15 134.783707 T:140492186994432 NOTICE: m_channels : FL,FR,BL,BR 17:00:15 134.783737 T:140492186994432 NOTICE: m_sampleRates : 48000 17:00:15 134.783752 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_S32NE 17:00:15 134.783768 T:140492186994432 NOTICE: m_streamTypes : No passthrough capabilities 17:00:15 134.783798 T:140492186994432 NOTICE: Device 2 17:00:15 134.783829 T:140492186994432 NOTICE: m_deviceName : @:CARD=PCH,DEV=0 17:00:15 134.783859 T:140492186994432 NOTICE: m_displayName : HDA Intel PCH 17:00:15 134.783875 T:140492186994432 NOTICE: m_displayNameExtra: ALC283 Analog 17:00:15 134.783905 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_PCM 17:00:15 134.783920 T:140492186994432 NOTICE: m_channels : FL,FR,BL,BR 17:00:15 134.783936 T:140492186994432 NOTICE: m_sampleRates : 48000 17:00:15 134.783966 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_S32NE 17:00:15 134.783981 T:140492186994432 NOTICE: m_streamTypes : No passthrough capabilities 17:00:15 134.784012 T:140492186994432 NOTICE: Device 3 17:00:15 134.784042 T:140492186994432 NOTICE: m_deviceName : iec958:CARD=PCH,DEV=0 17:00:15 134.784073 T:140492186994432 NOTICE: m_displayName : HDA Intel PCH 17:00:15 134.784088 T:140492186994432 NOTICE: m_displayNameExtra: ALC283 Digital S/PDIF 17:00:15 134.784119 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 17:00:15 134.784134 T:140492186994432 NOTICE: m_channels : FL,FR 17:00:15 134.784164 T:140492186994432 NOTICE: m_sampleRates : 44100,48000,88200,96000,192000 17:00:15 134.784180 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 17:00:15 134.784210 T:140492186994432 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512 17:00:15 134.784225 T:140492186994432 NOTICE: Device 4 17:00:15 134.784271 T:140492186994432 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=0 17:00:15 134.784302 T:140492186994432 NOTICE: m_displayName : HDA Intel PCH 17:00:15 134.784317 T:140492186994432 NOTICE: m_displayNameExtra: HDMI 17:00:15 134.784348 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 17:00:15 134.784363 T:140492186994432 NOTICE: m_channels : FL,FR,LFE,FC,BC,BL,BR,FLOC,FROC,BLOC,BROC,TFC,TC,TFL,TFR,UNKNOWN1 17:00:15 134.784393 T:140492186994432 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 17:00:15 134.784409 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 17:00:15 134.784439 T:140492186994432 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:00:15 134.784454 T:140492186994432 NOTICE: Enumerated PULSE devices: 17:00:15 134.784485 T:140492186994432 NOTICE: Device 1 17:00:15 134.784515 T:140492186994432 NOTICE: m_deviceName : Default 17:00:15 134.784546 T:140492186994432 NOTICE: m_displayName : Default 17:00:15 134.784561 T:140492186994432 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 17:00:15 134.784607 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_PCM 17:00:15 134.784622 T:140492186994432 NOTICE: m_channels : FL,FR 17:00:15 134.784653 T:140492186994432 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 17:00:15 134.784668 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 17:00:15 134.784698 T:140492186994432 NOTICE: m_streamTypes : No passthrough capabilities 17:00:15 135.213470 T:140492186994432 NOTICE: Found 2 Lists of Devices 17:00:15 135.213531 T:140492186994432 NOTICE: Enumerated ALSA devices: 17:00:15 135.213547 T:140492186994432 NOTICE: Device 1 17:00:15 135.213608 T:140492186994432 NOTICE: m_deviceName : @ 17:00:15 135.213623 T:140492186994432 NOTICE: m_displayName : Default (HDA Intel PCH ALC283 Analog) 17:00:15 135.213654 T:140492186994432 NOTICE: m_displayNameExtra: 17:00:15 135.213669 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_PCM 17:00:15 135.213684 T:140492186994432 NOTICE: m_channels : FL,FR,BL,BR 17:00:15 135.213715 T:140492186994432 NOTICE: m_sampleRates : 48000 17:00:15 135.213730 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_S32NE 17:00:15 135.213760 T:140492186994432 NOTICE: m_streamTypes : No passthrough capabilities 17:00:15 135.213776 T:140492186994432 NOTICE: Device 2 17:00:15 135.213806 T:140492186994432 NOTICE: m_deviceName : @:CARD=PCH,DEV=0 17:00:15 135.213837 T:140492186994432 NOTICE: m_displayName : HDA Intel PCH 17:00:15 135.213852 T:140492186994432 NOTICE: m_displayNameExtra: ALC283 Analog 17:00:15 135.213867 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_PCM 17:00:15 135.213898 T:140492186994432 NOTICE: m_channels : FL,FR,BL,BR 17:00:15 135.213913 T:140492186994432 NOTICE: m_sampleRates : 48000 17:00:15 135.213928 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_S32NE 17:00:15 135.213959 T:140492186994432 NOTICE: m_streamTypes : No passthrough capabilities 17:00:15 135.213974 T:140492186994432 NOTICE: Device 3 17:00:15 135.214020 T:140492186994432 NOTICE: m_deviceName : iec958:CARD=PCH,DEV=0 17:00:15 135.214035 T:140492186994432 NOTICE: m_displayName : HDA Intel PCH 17:00:15 135.214066 T:140492186994432 NOTICE: m_displayNameExtra: ALC283 Digital S/PDIF 17:00:15 135.214081 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_IEC958 17:00:15 135.214096 T:140492186994432 NOTICE: m_channels : FL,FR 17:00:15 135.214127 T:140492186994432 NOTICE: m_sampleRates : 44100,48000,88200,96000,192000 17:00:15 135.214142 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE 17:00:15 135.214172 T:140492186994432 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512 17:00:15 135.214188 T:140492186994432 NOTICE: Device 4 17:00:15 135.214233 T:140492186994432 NOTICE: m_deviceName : hdmi:CARD=PCH,DEV=0 17:00:15 135.214249 T:140492186994432 NOTICE: m_displayName : HDA Intel PCH 17:00:15 135.214279 T:140492186994432 NOTICE: m_displayNameExtra: MJI marantz-AVR on HDMI 17:00:15 135.214294 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 17:00:15 135.214325 T:140492186994432 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,BC,BLOC,BROC 17:00:15 135.214340 T:140492186994432 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 17:00:15 135.214355 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW 17:00:15 135.214386 T:140492186994432 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:00:15 135.214432 T:140492186994432 NOTICE: Enumerated PULSE devices: 17:00:15 135.214447 T:140492186994432 NOTICE: Device 1 17:00:15 135.214478 T:140492186994432 NOTICE: m_deviceName : Default 17:00:15 135.214508 T:140492186994432 NOTICE: m_displayName : Default 17:00:15 135.214523 T:140492186994432 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 17:00:15 135.214554 T:140492186994432 NOTICE: m_deviceType : AE_DEVTYPE_PCM 17:00:15 135.214569 T:140492186994432 NOTICE: m_channels : FL,FR 17:00:15 135.214600 T:140492186994432 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 17:00:15 135.214615 T:140492186994432 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 17:00:15 135.214630 T:140492186994432 NOTICE: m_streamTypes : No passthrough capabilities 17:00:39 159.439835 T:140491302921984 ERROR: GetDirectory - Error getting 17:01:40 220.528214 T:140492205930560 ERROR: Previous line repeats 1 times. 17:01:40 220.528290 T:140492205930560 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 17:01:40 220.528305 T:140492205930560 NOTICE: Enabled debug logging due to GUI setting. Level 2. 17:01:40 220.528763 T:140492205930560 DEBUG: ------ Window Init () ------ 17:01:41 220.795746 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 278 17:01:44 224.617249 T:140491752957696 DEBUG: PushCecKeypress - received key fd duration 0 17:01:44 224.628189 T:140492205930560 DEBUG: OnKey: 253 (0xfd) pressed, action is ActivateWindow(MyMusic) 17:01:44 224.628281 T:140492205930560 DEBUG: Activating window ID: 10502 17:01:45 224.741745 T:140491752957696 DEBUG: PushCecKeypress - received key fd duration 124 17:01:45 224.946960 T:140492205930560 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 17:01:45 224.951065 T:140492205930560 DEBUG: ------ Window Init (MyMusicNav.xml) ------ 17:01:45 224.951126 T:140492205930560 INFO: Loading skin file: MyMusicNav.xml, load type: KEEP_IN_MEMORY 17:01:45 225.063873 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory () 17:01:45 225.063934 T:140492205930560 DEBUG: ParentPath = [] 17:01:45 225.074936 T:140492205930560 DEBUG: RetrieveMusicInfo() took 0 msec 17:01:45 225.079376 T:140491302921984 DEBUG: Thread BackgroundLoader start, auto delete: false 17:01:45 225.084091 T:140491302921984 DEBUG: Thread BackgroundLoader 140491302921984 terminating 17:01:47 227.469803 T:140491752957696 DEBUG: PushCecKeypress - received key fc duration 0 17:01:47 227.470245 T:140492205930560 DEBUG: OnKey: 252 (0xfc) pressed, action is ActivateWindow(MyVideos) 17:01:47 227.470398 T:140492205930560 DEBUG: Activating window ID: 10025 17:01:48 227.745682 T:140491752957696 DEBUG: PushCecKeypress - received key fc duration 276 17:01:48 227.780090 T:140492205930560 DEBUG: ------ Window Deinit (MyMusicNav.xml) ------ 17:01:48 227.786102 T:140492205930560 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 17:01:48 227.787186 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory () 17:01:48 227.787262 T:140492205930560 DEBUG: ParentPath = [] 17:01:48 227.793945 T:140491302921984 DEBUG: Thread BackgroundLoader start, auto delete: false 17:01:48 227.807663 T:140491302921984 DEBUG: Thread BackgroundLoader 140491302921984 terminating 17:01:49 229.219070 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 0 17:01:49 229.225693 T:140492205930560 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 17:01:49 229.226486 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory (library://video/files.xml/) 17:01:49 229.226517 T:140492205930560 DEBUG: ParentPath = [] 17:01:49 229.227585 T:140491302921984 DEBUG: Thread JobWorker start, auto delete: true 17:01:49 229.253281 T:140491316254464 DEBUG: Thread BackgroundLoader start, auto delete: false 17:01:49 229.260193 T:140491316254464 DEBUG: Thread BackgroundLoader 140491316254464 terminating 17:01:49 229.444168 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 226 17:01:50 230.668777 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 0 17:01:50 230.687485 T:140492205930560 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 17:01:50 230.688049 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory (/storage/mediathek/Videos/HD/) 17:01:50 230.688080 T:140492205930560 DEBUG: ParentPath = [sources://video/] 17:01:50 230.704788 T:140492205930560 DEBUG: RunQuery took 0 ms for 0 items query: select * from movie_view WHERE c23=51 17:01:50 230.706528 T:140491316254464 DEBUG: Thread BackgroundLoader start, auto delete: false 17:01:51 230.714355 T:140491302921984 DEBUG: GetImageHash - unable to stat url 17:01:51 230.733139 T:140491316254464 DEBUG: Thread BackgroundLoader 140491316254464 terminating 17:01:51 230.942871 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 274 17:01:52 232.121338 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 0 17:01:52 232.151215 T:140492205930560 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 17:01:52 232.152191 T:140491302921984 DEBUG: GetImageHash - unable to stat url 17:01:52 232.395157 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 273 17:01:53 233.071198 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 0 17:01:53 233.087540 T:140492205930560 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 17:01:53 233.088455 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory (/storage/mediathek/Videos/HD/3D/) 17:01:53 233.088516 T:140492205930560 DEBUG: ParentPath = [/storage/mediathek/Videos/HD/] 17:01:53 233.110626 T:140492205930560 DEBUG: RunQuery took 3 ms for 59 items query: select * from movie_view WHERE c23=52 17:01:53 233.117828 T:140491316254464 DEBUG: Thread BackgroundLoader start, auto delete: false 17:01:53 233.122147 T:140491302921984 DEBUG: GetImageHash - unable to stat url 17:01:53 233.143021 T:140491316254464 DEBUG: Thread BackgroundLoader 140491316254464 terminating 17:01:53 233.343475 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 273 17:01:54 233.916077 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 0 17:01:54 233.927612 T:140492205930560 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 17:01:54 233.929443 T:140491316254464 DEBUG: Thread JobWorker start, auto delete: true 17:01:54 233.929688 T:140491316254464 DEBUG: GetImageHash - unable to stat url 17:01:54 233.943375 T:140491316254464 INFO: ffmpeg[7FC6AEFFD700]: [mjpeg] Changeing bps to 8 17:01:54 233.946899 T:140491302921984 INFO: ffmpeg[7FC6AE346700]: [mjpeg] Changeing bps to 8 17:01:54 234.193069 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 277 17:01:55 235.019379 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 0 17:01:55 235.033905 T:140492205930560 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 17:01:55 235.039490 T:140492205930560 DEBUG: OnPlayMedia /storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv 17:01:55 235.039658 T:140492205930560 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 17:01:55 235.039734 T:140492205930560 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 17:01:55 235.045013 T:140492205930560 DEBUG: Loading settings for /storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv 17:01:55 235.050476 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers(/storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv) 17:01:55 235.050552 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 17:01:55 235.050583 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 17:01:55 235.050598 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 17:01:55 235.050674 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 17:01:55 235.050705 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 17:01:55 235.050751 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 17:01:55 235.050781 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 17:01:55 235.050812 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 17:01:55 235.050842 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 17:01:55 235.050858 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 17:01:55 235.050903 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 17:01:55 235.050934 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 17:01:55 235.050964 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 17:01:55 235.050995 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 17:01:55 235.051010 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 17:01:55 235.051041 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 17:01:55 235.051056 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VIERA ST60 Series 17:01:55 235.051086 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: Kodi (OpenELEC-RPi2) 17:01:55 235.051102 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: Marantz SR7010 17:01:55 235.051117 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DMP-BDT500 17:01:55 235.051147 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: added 5 players 17:01:55 235.053391 T:140492205930560 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 17:01:55 235.053482 T:140492205930560 NOTICE: VideoPlayer: Opening: /storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv 17:01:55 235.053513 T:140492205930560 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 17:01:55 235.053604 T:140492205930560 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:01:55 235.053665 T:140492205930560 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:01:55 235.054031 T:140490754172672 DEBUG: Thread VideoPlayer start, auto delete: false 17:01:55 235.055435 T:140490754172672 NOTICE: Creating InputStream 17:01:55 235.065689 T:140490754172672 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 17:01:55 235.069168 T:140490754172672 DEBUG: ScanForExternalSubtitles: END (total time: 4 ms) 17:01:55 235.069229 T:140490754172672 NOTICE: Creating Demuxer 17:01:55 235.071823 T:140490754172672 DEBUG: Open - probing detected format [matroska,webm] 17:01:55 235.072632 T:140490754172672 DEBUG: Open - avformat_find_stream_info starting 17:01:55 235.102295 T:140490754172672 DEBUG: Open - av_find_stream_info finished 17:01:55 235.102585 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Input #0, matroska,webm, /storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mk': 17:01:55 235.102646 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.102707 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: encoder : libebml v1.3.0 + libmatroska v1.4.1 17:01:55 235.102737 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: creation_time : 2014-06-07 20:18:01 17:01:55 235.102814 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Duration: 01:42:36.73, start: 0.000000, bitrate: 14228 kb/s 17:01:55 235.102844 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:0: start 0.000000, end 626.376000 17:01:55 235.102921 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.102966 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 00:00:00.000 17:01:55 235.103012 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:1: start 626.376000, end 1254.628000 17:01:55 235.103058 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103088 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 00:10:26.376 17:01:55 235.103119 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:2: start 1254.628000, end 1875.290000 17:01:55 235.103149 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103180 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 00:20:54.628 17:01:55 235.103210 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:3: start 1875.290000, end 2458.498000 17:01:55 235.103241 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103271 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 00:31:15.290 17:01:55 235.103302 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:4: start 2458.498000, end 3050.714000 17:01:55 235.103348 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103485 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 00:40:58.498 17:01:55 235.103531 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:5: start 3050.714000, end 3605.435000 17:01:55 235.103607 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103638 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 00:50:50.714 17:01:55 235.103699 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:6: start 3605.435000, end 4191.813000 17:01:55 235.103729 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103760 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 01:00:05.435 17:01:55 235.103790 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:7: start 4191.813000, end 4791.245000 17:01:55 235.103821 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103851 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 01:09:51.813 17:01:55 235.103882 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:8: start 4791.245000, end 5467.295000 17:01:55 235.103912 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.103943 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 01:19:51.245 17:01:55 235.103973 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:9: start 5467.295000, end 5637.131000 17:01:55 235.104004 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.104034 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 01:31:07.295 17:01:55 235.104065 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Chapter #0:10: start 5637.131000, end 6156.726000 17:01:55 235.104111 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.104141 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 01:33:57.131 17:01:55 235.104401 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Stream #0:0: Video: h264 (High), yuv420p, 1920x1080, SAR 1:1 DAR 16:9, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default) 17:01:55 235.104431 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.104462 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : 300.Rise.Of.An.Empire.2014.3D.1080p.Bluray.HOU.X264.DL.GER.DTSD-zman 17:01:55 235.104492 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: stereo_mode : top_bottom 17:01:55 235.104523 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Side data: 17:01:55 235.104553 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: stereo3d: top and bottom 17:01:55 235.104691 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Stream #0:1(ger): Audio: dts (DTS), 48000 Hz, 5.1(side), s32p (24 bit), 1536 kb/s (default) 17:01:55 235.104706 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Metadata: 17:01:55 235.104752 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: title : DTS Dubbed by Der Schuft, Center: Line THX @ TwixX 17:01:55 235.104828 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Stream #0:2(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), s32p (24 bit), 1536 kb/s 17:01:55 235.104874 T:140490754172672 INFO: ffmpeg[7FC68D7F2700]: Stream #0:3(eng): Subtitle: dvd_subtitle, 1920x1080 (default) 17:01:55 235.104950 T:140490754172672 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 17:01:55 235.104980 T:140490754172672 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 17:01:55 235.104996 T:140490754172672 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 17:01:55 235.105026 T:140490754172672 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 3 17:01:55 235.105179 T:140490754172672 NOTICE: Opening stream: 0 source: 256 17:01:55 235.105255 T:140490754172672 NOTICE: Creating video codec with codec id: 28 17:01:55 235.105286 T:140490754172672 DEBUG: FactoryCodec - Video: - Opening 17:01:55 235.105301 T:140490754172672 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 17:01:55 235.105560 T:140490754172672 DEBUG: FactoryCodec - Video: ff-h264 - Opened 17:01:55 235.105606 T:140490754172672 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 17:01:55 235.105637 T:140490754172672 NOTICE: Creating video thread 17:01:55 235.105759 T:140490754172672 NOTICE: Opening stream: 1 source: 256 17:01:55 235.105774 T:140490754172672 NOTICE: Finding audio codec for: 86020 17:01:55 235.105850 T:140490754172672 DEBUG: FactoryCodec - Audio: passthrough - Opening 17:01:55 235.105896 T:140490754172672 DEBUG: FactoryCodec - Audio: passthrough - Opened 17:01:55 235.105927 T:140490754172672 NOTICE: Creating audio thread 17:01:55 235.106049 T:140490754172672 NOTICE: Opening stream: 3 source: 256 17:01:55 235.106079 T:140490754172672 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 17:01:55 235.106186 T:140490829707008 DEBUG: Thread VideoPlayerVideo start, auto delete: false 17:01:55 235.106293 T:140490728998656 DEBUG: Thread VideoPlayerAudio start, auto delete: false 17:01:55 235.106354 T:140490754172672 DEBUG: Open - parsed extradata: size: 1920 x 1080 17:01:55 235.106430 T:140490829707008 NOTICE: running thread: video_thread 17:01:55 235.106491 T:140490728998656 NOTICE: running thread: CVideoPlayerAudio::Process() 17:01:55 235.106522 T:140490754172672 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened 17:01:55 235.106598 T:140490754172672 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv 17:01:55 235.106781 T:140490754172672 DEBUG: OnPlayBackStarted: play state was 1, starting 1 17:01:55 235.106827 T:140492205930560 DEBUG: PlayFile: OpenFile succeed, play state 2 17:01:55 235.106857 T:140492205930560 DEBUG: OnPlayBackStarted: play state was 2, starting 0 17:01:55 235.109344 T:140490754172672 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:01:55 235.110413 T:140490728998656 DEBUG: CDVDAudio::Pause - pausing audio stream 17:01:55 235.110855 T:140490728998656 INFO: CAEStreamParser::SyncDTS - dts stream detected (6 channels, 48000Hz, 16bit BE, period: 512) 17:01:55 235.110901 T:140490754172672 NOTICE: CDVDMessageQueue::GetLevel() - can't determine level 17:01:55 235.110962 T:140490728998656 NOTICE: Previous line repeats 1 times. 17:01:55 235.110992 T:140490728998656 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, pass-through) 17:01:55 235.111298 T:140490728998656 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 17:01:55 235.111343 T:140490754172672 NOTICE: CDVDMessageQueue::GetLevel() - can't determine level 17:01:55 235.112137 T:140490829707008 NOTICE: Previous line repeats 7 times. 17:01:55 235.112167 T:140490829707008 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:01:55 235.112183 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:55 235.112305 T:140490829707008 DEBUG: VAAPI - open decoder 17:01:55 235.126068 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:01:55 235.139526 T:140490829707008 DEBUG: VAAPI - initialize version 0.38 17:01:55 235.139603 T:140490829707008 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) CherryView - 1.6.2 17:01:55 235.139679 T:140490829707008 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0 17:01:55 235.139694 T:140490829707008 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32 17:01:55 235.139725 T:140490829707008 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0 17:01:55 235.139740 T:140490829707008 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32 17:01:55 235.139771 T:140490829707008 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0 17:01:55 235.139877 T:140490829707008 DEBUG: VAAPI - profile 0 17:01:55 235.139908 T:140490829707008 DEBUG: VAAPI - profile 1 17:01:55 235.139923 T:140490829707008 DEBUG: VAAPI - profile 13 17:01:55 235.139938 T:140490829707008 DEBUG: VAAPI - profile 6 17:01:55 235.139969 T:140490829707008 DEBUG: VAAPI - profile 7 17:01:55 235.140030 T:140490829707008 DEBUG: VAAPI - profile 15 17:01:55 235.140045 T:140490829707008 DEBUG: VAAPI - profile 16 17:01:55 235.140060 T:140490829707008 DEBUG: VAAPI - profile 8 17:01:55 235.140091 T:140490829707008 DEBUG: VAAPI - profile 9 17:01:55 235.140106 T:140490829707008 DEBUG: VAAPI - profile 10 17:01:55 235.140137 T:140490829707008 DEBUG: VAAPI - profile -1 17:01:55 235.140152 T:140490829707008 DEBUG: VAAPI - profile 12 17:01:55 235.140167 T:140490829707008 DEBUG: VAAPI - profile 14 17:01:55 235.140198 T:140490829707008 DEBUG: VAAPI - profile 15 17:01:55 235.140213 T:140490829707008 DEBUG: VAAPI - profile 16 17:01:55 235.140228 T:140490829707008 DEBUG: VAAPI - profile 17 17:01:55 235.140518 T:140490717542144 DEBUG: Thread Vaapi-Output start, auto delete: false 17:01:55 235.140549 T:140490717542144 NOTICE: COutput::OnStartup: Output Thread created 17:01:55 235.146774 T:140490717542144 DEBUG: VAAPI::COutput::CreateEGLContext - created context 17:01:55 235.147034 T:140490717542144 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so) 17:01:55 235.147110 T:140490717542144 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so 17:01:55 235.169220 T:140490717542144 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created 17:01:55 235.172379 T:140492205930560 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 17:01:55 235.172653 T:140492205930560 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 17:01:55 235.172806 T:140492205930560 DEBUG: UPnP: Building didl for object '/storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv' 17:01:55 235.174606 T:140492205930560 DEBUG: StereoscopicsManager: autodetected stereo mode for movie mode top_bottom is: split_horizontal 17:01:55 235.174667 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:01:55 235.174683 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode setting changed to split_horizontal 17:01:55 235.174713 T:140492205930560 DEBUG: StereoscopicsManager::ApplyStereoMode: trying to apply stereo mode. Current: off | Target: split_horizontal 17:01:55 235.174744 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode changed to split_horizontal 17:01:55 235.174789 T:140491316254464 DEBUG: CGUIInfoManager::SetCurrentMovie(/storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv) 17:01:55 235.177597 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:55 235.179855 T:140492205930560 DEBUG: ------ Window Init (DialogNotification.xml) ------ 17:01:55 235.180847 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:55 235.183609 T:140491316254464 DEBUG: GetMovieId (/storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv), query = select idMovie from movie where idFile=258 17:01:55 235.184692 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:55 235.207062 T:140490829707008 DEBUG: Previous line repeats 2 times. 17:01:55 235.207123 T:140490829707008 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 23.98. format: VAAPI 17:01:55 235.218048 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:01:55 235.244751 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 225 17:01:55 235.281815 T:140492205930560 DEBUG: DeleteRenderer - deleting renderer 17:01:55 235.281906 T:140492205930560 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:01:55 235.282120 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:01:55 235.282135 T:140492205930560 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:01:55 235.282196 T:140492205930560 DEBUG: GL: Requested render method: 0 17:01:55 235.284103 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:01:55 235.284607 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:01:55 235.297760 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:55 235.297836 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:55 235.301987 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:55 235.302139 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:55 235.326965 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:01:55 235.327042 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:01:55 235.330811 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02" 17:01:55 235.331146 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 17:01:55 235.333817 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:55 235.333878 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:55 235.334686 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:55 235.334717 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:55 235.336700 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 17:01:55 235.336731 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:01:55 235.337616 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:01:55 235.337646 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW 17:01:55 235.337662 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none) 17:01:55 235.337692 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:01:55 235.337708 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:01:55 235.337723 T:140492178601728 DEBUG: Sample Rate : 48000 17:01:55 235.337753 T:140492178601728 DEBUG: Sample Format : AE_FMT_S16NE 17:01:55 235.337769 T:140492178601728 DEBUG: Channel Count : 2 17:01:55 235.337799 T:140492178601728 DEBUG: Channel Layout: RAW,RAW 17:01:55 235.337814 T:140492178601728 DEBUG: Frames : 2400 17:01:55 235.337830 T:140492178601728 DEBUG: Frame Size : 4 17:01:55 235.343506 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:01:55 235.343613 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:01:55 235.343628 T:140492205930560 NOTICE: Using GL_TEXTURE_2D 17:01:55 235.343658 T:140492205930560 DEBUG: CRenderManager::Configure - 4 17:01:55 235.343857 T:140492205930560 WARNING: CRenderCaptureGL: GL_ARB_occlusion_query disabled, performance might suffer 17:01:55 235.343979 T:140492205930560 DEBUG: GL: Requested render method: 0 17:01:55 235.344177 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:01:55 235.344376 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:01:55 235.344467 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:01:55 235.344528 T:140490754172672 DEBUG: CVideoPlayer::HandleMessages - player started 2 17:01:55 235.344711 T:140490728998656 DEBUG: CDVDClock::Discontinuity - CVideoPlayerAudio::OutputPacket - was:237920.183000, should be:2624.161000, error:-235296.022000 17:01:55 235.346161 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:01:55 235.346252 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:55 235.346298 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:55 235.348907 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:55 235.348953 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:55 235.354645 T:140490754172672 DEBUG: CVideoPlayer::HandleMessages - player started 1 17:01:55 235.354691 T:140490754172672 DEBUG: CVideoPlayer::SetCaching - caching state 3 17:01:55 235.354904 T:140490754172672 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:01:55 235.354935 T:140490754172672 DEBUG: VideoPlayer::Sync - Audio - pts: 234000.000000, cache: 303480.885564, totalcache: 600000.023842 17:01:55 235.354965 T:140490754172672 DEBUG: VideoPlayer::Sync - Video - pts: -4503599627370496.000000, cache: 50000.000000, totalcache: 100000.000000 17:01:55 235.355026 T:140490829707008 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-69480.885564) 17:01:55 235.355042 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:55 235.364960 T:140490728998656 DEBUG: Previous line repeats 2 times. 17:01:55 235.365036 T:140490728998656 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-69480.885564) 17:01:55 235.365067 T:140490728998656 DEBUG: CDVDAudio::Resume - resume audio stream 17:01:55 235.365982 T:140492186994432 DEBUG: ActiveAE - start sync of audio stream 17:01:55 235.376236 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:01:55 235.376297 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:01:55 235.459106 T:140492205930560 DEBUG: Activating window ID: 12005 17:01:55 235.459808 T:140492205930560 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 17:01:55 235.460526 T:140492205930560 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 17:01:55 235.460571 T:140492205930560 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 17:01:55 235.473038 T:140492205930560 NOTICE: Display resolution ADJUST : HDMI2: 1920x1080 @ 23.98Hz (24) (weight: 0.000) 17:01:55 235.473099 T:140492205930560 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 17:01:55 235.477188 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:01:55 235.477219 T:140492205930560 DEBUG: Flush - flushing renderer 17:01:55 235.477554 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:55 235.491928 T:140490717542144 DEBUG: Previous line repeats 5 times. 17:01:55 235.492020 T:140490717542144 WARNING: COutput::QueueReturnPicture - pic not found 17:01:55 235.694733 T:140492205930560 NOTICE: VideoPlayer: OnLostDisplay received 17:01:55 235.694824 T:140492205930560 INFO: XRANDR: /usr/lib/kodi/kodi-xrandr --screen 0 --output HDMI2 --mode 0x10c 17:01:56 235.937073 T:140492197529344 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 17:01:56 236.022507 T:140491786528512 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 17:01:56 236.113281 T:140492197529344 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 17:01:56 236.114532 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:56 236.133621 T:140491786528512 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 17:01:56 236.155548 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:56 236.160553 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:01:56 236.162384 T:140492205930560 DEBUG: GL: Requested render method: 0 17:01:56 236.162704 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:01:56 236.162994 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:01:56 236.166382 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:56 236.166428 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:56 236.169220 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:56 236.169266 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:56 236.194946 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:56 236.195007 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:56 236.195862 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:56 236.195892 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:56 236.197662 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:01:56 236.203766 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:01:56 236.203842 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:01:56 236.203857 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:01:56 236.203888 T:140492205930560 NOTICE: Using GL_TEXTURE_2D 17:01:56 236.212189 T:140492205930560 DEBUG: NotifyXRREvent - notify display reset event 17:01:56 236.230667 T:140492205930560 DEBUG: RecreateWindow - current output: HDMI2, mode: 0x10c, refresh: 23.976 17:01:56 236.230759 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:01:56 236.230789 T:140492205930560 DEBUG: Flush - flushing renderer 17:01:56 236.231384 T:140492205930560 NOTICE: VideoPlayer: OnLostDisplay received 17:01:56 236.239532 T:140492205930560 DEBUG: Window Manager Name: Fluxbox 17:01:56 236.252182 T:140492205930560 DEBUG: CWinSystemX11::RefreshEGLContext: refreshing context 17:01:56 236.255325 T:140492205930560 NOTICE: VideoPlayer: OnResetDisplay received 17:01:56 236.255432 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:01:56 236.256119 T:140492186994432 DEBUG: CActiveAE - display reset event 17:01:56 236.256271 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:01:56 236.256317 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:01:56 236.256378 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:01:56 236.257797 T:140492205930560 DEBUG: GL: Requested render method: 0 17:01:56 236.258026 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:01:56 236.258194 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:01:56 236.259659 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:56 236.259689 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:56 236.261444 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02" 17:01:56 236.261765 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 17:01:56 236.262558 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:56 236.262604 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:56 236.266769 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 17:01:56 236.266830 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:01:56 236.267715 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:01:56 236.267731 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW 17:01:56 236.267761 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none) 17:01:56 236.267776 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:01:56 236.267807 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:01:56 236.267822 T:140492178601728 DEBUG: Sample Rate : 48000 17:01:56 236.267853 T:140492178601728 DEBUG: Sample Format : AE_FMT_S16NE 17:01:56 236.267868 T:140492178601728 DEBUG: Channel Count : 2 17:01:56 236.267883 T:140492178601728 DEBUG: Channel Layout: RAW,RAW 17:01:56 236.267914 T:140492178601728 DEBUG: Frames : 2400 17:01:56 236.267929 T:140492178601728 DEBUG: Frame Size : 4 17:01:56 236.274078 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:01:56 236.276978 T:140492186994432 DEBUG: Previous line repeats 1 times. 17:01:56 236.277023 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error of -127.489777, start adjusting 17:01:56 236.278015 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error -20.823110 below threshold of 30.000000 17:01:56 236.287842 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:56 236.287903 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:56 236.288849 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:56 236.288879 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:56 236.296524 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:01:56 236.296585 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:01:56 236.296600 T:140492205930560 NOTICE: Using GL_TEXTURE_2D 17:01:56 236.296738 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:56 236.301804 T:140492205930560 DEBUG: Previous line repeats 2 times. 17:01:56 236.301865 T:140492205930560 DEBUG: GL: Requested render method: 0 17:01:56 236.302094 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:56 236.302216 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:01:56 236.302444 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:01:56 236.307922 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:01:56 236.308334 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:01:56 236.321182 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:01:56 236.321335 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:01:56 236.347717 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:01:56 236.347778 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:01:56 236.349319 T:140492205930560 WARNING: CRenderCaptureGL: GL_ARB_occlusion_query disabled, performance might suffer 17:01:56 236.358795 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:56 236.363586 T:140491333039872 DEBUG: Previous line repeats 1 times. 17:01:56 236.373337 T:140490829707008 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:01:56 236.471054 T:140491333039872 DEBUG: Previous line repeats 5 times. 17:01:57 237.323563 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error -435.380807 above threshold of 100.000000 17:01:57 237.524170 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error -19.380807 below threshold of 30.000000 17:01:58 238.524704 T:140490728998656 DEBUG: CDVDClock::Discontinuity - CVideoPlayerAudio::OutputPacket - was:3100207.656436, should be:3071182.880077, error:-29024.776359 17:02:00 240.085693 T:140490829707008 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333 17:02:01 240.788177 T:140492205930560 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 17:02:15 255.269714 T:140491752957696 DEBUG: PushCecKeypress - received key e0 duration 1000 17:02:15 255.303085 T:140492205930560 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 17:02:15 255.303177 T:140492205930560 NOTICE: CVideoPlayer::CloseFile() 17:02:15 255.303207 T:140492205930560 NOTICE: VideoPlayer: waiting for threads to exit 17:02:15 255.307236 T:140490754172672 NOTICE: CVideoPlayer::OnExit() 17:02:15 255.307312 T:140490754172672 NOTICE: Closing stream player 1 17:02:15 255.307358 T:140490754172672 NOTICE: Waiting for audio thread to exit 17:02:15 255.325485 T:140490728998656 NOTICE: thread end: CVideoPlayerAudio::OnExit() 17:02:15 255.325607 T:140490728998656 DEBUG: Thread VideoPlayerAudio 140490728998656 terminating 17:02:15 255.325729 T:140490754172672 NOTICE: Closing audio device 17:02:15 255.374039 T:140490754172672 DEBUG: CDVDAudio::Flush - flush audio stream 17:02:15 255.374176 T:140492186994432 DEBUG: CActiveAE::DiscardStream - audio stream deleted 17:02:15 255.374344 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:15 255.375549 T:140492178601728 DEBUG: Previous line repeats 1 times. 17:02:15 255.375595 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:02:15 255.375732 T:140490754172672 NOTICE: Deleting audio codec 17:02:15 255.375854 T:140490754172672 NOTICE: Closing stream player 2 17:02:15 255.375900 T:140490754172672 NOTICE: waiting for video thread to exit 17:02:15 255.403427 T:140490829707008 NOTICE: thread end: video_thread 17:02:15 255.403534 T:140490829707008 DEBUG: Thread VideoPlayerVideo 140490829707008 terminating 17:02:15 255.404160 T:140490754172672 NOTICE: deleting video codec 17:02:15 255.404449 T:140490754172672 DEBUG: VAAPI::Release pre-cleanup 17:02:15 255.410156 T:140490754172672 NOTICE: Closing stream player 3 17:02:15 255.410446 T:140490754172672 DEBUG: OnPlayBackStopped: play state was 2, starting 0 17:02:15 255.410492 T:140490754172672 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 17:02:15 255.410568 T:140490754172672 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 17:02:15 255.410660 T:140490754172672 DEBUG: Thread VideoPlayer 140490754172672 terminating 17:02:15 255.410934 T:140492205930560 NOTICE: VideoPlayer: finished waiting 17:02:15 255.411041 T:140492205930560 DEBUG: DeleteRenderer - deleting renderer 17:02:15 255.411362 T:140492205930560 NOTICE: VAAPI::Close 17:02:15 255.412277 T:140490717542144 NOTICE: COutput::OnExit: Output Thread terminated 17:02:15 255.412338 T:140490717542144 DEBUG: Thread Vaapi-Output 140490717542144 terminating 17:02:15 255.413635 T:140492205930560 DEBUG: VAAPI::FiniVAAPIOutput destroying 5 video surfaces 17:02:15 255.416733 T:140492205930560 NOTICE: VAAPI::Close - closing decoder context 17:02:15 255.422485 T:140492205930560 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:02:15 255.422638 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:02:15 255.422684 T:140492205930560 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 17:02:15 255.430939 T:140492205930560 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 17:02:15 255.431137 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:15 255.576004 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:02:15 255.576096 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:02:15 255.582077 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 17:02:15 255.582794 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:02:15 255.582855 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:02:15 255.582886 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 17:02:15 255.590240 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820 17:02:15 255.590317 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:02:15 255.591934 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:02:15 255.591949 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:02:15 255.591980 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:02:15 255.592010 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:02:15 255.592041 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:02:15 255.592056 T:140492178601728 DEBUG: Sample Rate : 44100 17:02:15 255.592087 T:140492178601728 DEBUG: Sample Format : AE_FMT_S32NE 17:02:15 255.592102 T:140492178601728 DEBUG: Channel Count : 2 17:02:15 255.592133 T:140492178601728 DEBUG: Channel Layout: FL,FR 17:02:15 255.592148 T:140492178601728 DEBUG: Frames : 2205 17:02:15 255.592178 T:140492178601728 DEBUG: Frame Size : 8 17:02:15 255.594269 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:15 255.595520 T:140492205930560 NOTICE: VideoPlayer: OnLostDisplay received 17:02:15 255.595596 T:140492205930560 INFO: XRANDR: /usr/lib/kodi/kodi-xrandr --screen 0 --output HDMI2 --mode 0x49 17:02:16 255.880310 T:140492197529344 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 17:02:16 255.954056 T:140491786528512 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 17:02:16 256.131104 T:140492197529344 DEBUG: Previous line repeats 1 times. 17:02:16 256.131165 T:140492197529344 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 17:02:16 256.156555 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:16 256.158539 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:02:16 256.165070 T:140492205930560 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 17:02:16 256.167145 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory (/storage/mediathek/Videos/HD/3D/) 17:02:16 256.167206 T:140492205930560 DEBUG: ParentPath = [/storage/mediathek/Videos/HD/3D/] 17:02:16 256.218536 T:140492205930560 DEBUG: RunQuery took 3 ms for 59 items query: select * from movie_view WHERE c23=52 17:02:16 256.227020 T:140490717542144 DEBUG: Thread BackgroundLoader start, auto delete: false 17:02:16 256.232849 T:140492205930560 INFO: ffmpeg[7FC6E4073840]: [mjpeg] Changeing bps to 8 17:02:16 256.272308 T:140490717542144 DEBUG: Thread BackgroundLoader 140490717542144 terminating 17:02:16 256.285126 T:140491302921984 DEBUG: GetImageHash - unable to stat url 17:02:16 256.354919 T:140492205930560 NOTICE: CVideoPlayer::CloseFile() 17:02:16 256.354980 T:140492205930560 NOTICE: VideoPlayer: waiting for threads to exit 17:02:16 256.355011 T:140492205930560 NOTICE: VideoPlayer: finished waiting 17:02:16 256.355042 T:140492205930560 NOTICE: CVideoPlayer::CloseFile() 17:02:16 256.355072 T:140492205930560 NOTICE: VideoPlayer: waiting for threads to exit 17:02:16 256.355072 T:140492205930560 NOTICE: VideoPlayer: finished waiting 17:02:16 256.358002 T:140492205930560 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 17:02:16 256.358398 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode setting changed to off 17:02:16 256.358459 T:140492205930560 DEBUG: StereoscopicsManager::ApplyStereoMode: trying to apply stereo mode. Current: split_horizontal | Target: off 17:02:16 256.358459 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode changed to off 17:02:16 256.358765 T:140491316254464 DEBUG: DoWork - Saving file state for video item /storage/mediathek/Videos/HD/3D/300 Rise of an Empire (2014).mkv 17:02:16 256.360443 T:140492205930560 DEBUG: ------ Window Init (DialogNotification.xml) ------ 17:02:16 256.360657 T:140492205930560 DEBUG: NotifyXRREvent - notify display reset event 17:02:16 256.382507 T:140492205930560 DEBUG: RecreateWindow - current output: HDMI2, mode: 0x49, refresh: 50.000 17:02:16 256.382599 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:16 256.387054 T:140492205930560 DEBUG: Window Manager Name: Fluxbox 17:02:16 256.401825 T:140492205930560 DEBUG: CWinSystemX11::RefreshEGLContext: refreshing context 17:02:16 256.410248 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:16 256.415405 T:140492205930560 DEBUG: Window Manager Name: Fluxbox 17:02:16 256.437347 T:140492205930560 DEBUG: CWinSystemX11::RefreshEGLContext: refreshing context 17:02:16 256.438629 T:140492186994432 DEBUG: CActiveAE - display reset event 17:02:16 256.438934 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:02:16 256.439178 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:02:16 256.439240 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:02:16 256.443420 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:16 256.445709 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 17:02:16 256.446228 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:02:16 256.446259 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:02:16 256.446289 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 17:02:16 256.451935 T:140491316254464 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 17:02:16 256.451996 T:140491316254464 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 17:02:16 256.453430 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820 17:02:16 256.453491 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:02:16 256.454437 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:02:16 256.454437 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:02:16 256.454468 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:02:16 256.454498 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:02:16 256.454529 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:02:16 256.454529 T:140492178601728 DEBUG: Sample Rate : 44100 17:02:16 256.454559 T:140492178601728 DEBUG: Sample Format : AE_FMT_S32NE 17:02:16 256.454590 T:140492178601728 DEBUG: Channel Count : 2 17:02:16 256.454590 T:140492178601728 DEBUG: Channel Layout: FL,FR 17:02:16 256.454620 T:140492178601728 DEBUG: Frames : 2205 17:02:16 256.454651 T:140492178601728 DEBUG: Frame Size : 8 17:02:16 256.456390 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:16 256.469269 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:16 256.559204 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:22 261.716827 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 0 17:02:22 261.742889 T:140492205930560 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 17:02:22 261.794586 T:140491302921984 INFO: ffmpeg[7FC6AE346700]: [mjpeg] Changeing bps to 8 17:02:22 261.795990 T:140491316254464 INFO: ffmpeg[7FC6AEFFD700]: [mjpeg] Changeing bps to 8 17:02:22 261.844391 T:140491302921984 DEBUG: DoWork - took 101 ms to load special://masterprofile/Thumbnails/1/15296344.jpg 17:02:22 261.994690 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 278 17:02:22 262.244232 T:140492205930560 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 17:02:25 264.918762 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 0 17:02:25 264.947662 T:140492205930560 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 17:02:25 264.965210 T:140491302921984 INFO: ffmpeg[7FC6AE346700]: [mjpeg] Changeing bps to 8 17:02:25 264.971252 T:140491316254464 INFO: ffmpeg[7FC6AEFFD700]: [mjpeg] Changeing bps to 8 17:02:25 265.195923 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 277 17:02:25 265.605072 T:140492205930560 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so) 17:02:27 267.266724 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 0 17:02:27 267.286041 T:140492205930560 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 17:02:27 267.291992 T:140491302921984 INFO: ffmpeg[7FC6AE346700]: [mjpeg] Changeing bps to 8 17:02:27 267.298126 T:140491316254464 INFO: ffmpeg[7FC6AEFFD700]: [mjpeg] Changeing bps to 8 17:02:27 267.546204 T:140491752957696 DEBUG: PushCecKeypress - received key a7 duration 280 17:02:32 272.069427 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 0 17:02:32 272.081390 T:140492205930560 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 17:02:32 272.091248 T:140492205930560 DEBUG: OnPlayMedia /storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv 17:02:32 272.091522 T:140492205930560 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc 17:02:32 272.091614 T:140492205930560 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear 17:02:32 272.091705 T:140492205930560 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc 17:02:32 272.091766 T:140492205930560 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd 17:02:32 272.098480 T:140492205930560 DEBUG: Loading settings for /storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv 17:02:32 272.104767 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers(/storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv) 17:02:32 272.104828 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 17:02:32 272.104858 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 17:02:32 272.104858 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 17:02:32 272.104919 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 17:02:32 272.104950 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp 17:02:32 272.104980 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 17:02:32 272.105011 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 17:02:32 272.105042 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 17:02:32 272.105072 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 17:02:32 272.105103 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 17:02:32 272.105133 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 17:02:32 272.105164 T:140492205930560 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 17:02:32 272.105255 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 17:02:32 272.105255 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 17:02:32 272.105316 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 17:02:32 272.105347 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 17:02:32 272.105347 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VIERA ST60 Series 17:02:32 272.105377 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: Kodi (OpenELEC-RPi2) 17:02:32 272.105408 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: Marantz SR7010 17:02:32 272.105408 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DMP-BDT500 17:02:32 272.105438 T:140492205930560 DEBUG: CPlayerCoreFactory::GetPlayers: added 5 players 17:02:32 272.108429 T:140492205930560 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData 17:02:32 272.108521 T:140492205930560 NOTICE: VideoPlayer: Opening: /storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv 17:02:32 272.108551 T:140492205930560 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 17:02:32 272.108643 T:140492205930560 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:02:32 272.108704 T:140492205930560 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:02:32 272.109192 T:140490717542144 DEBUG: Thread VideoPlayer start, auto delete: false 17:02:32 272.109406 T:140490717542144 NOTICE: Creating InputStream 17:02:32 272.116150 T:140490717542144 DEBUG: ScanForExternalSubtitles: Searching for subtitles... 17:02:32 272.119751 T:140490717542144 DEBUG: ScanForExternalSubtitles: END (total time: 4 ms) 17:02:32 272.119843 T:140490717542144 NOTICE: Creating Demuxer 17:02:32 272.122375 T:140490717542144 DEBUG: Open - probing detected format [matroska,webm] 17:02:32 272.122681 T:140490717542144 DEBUG: Open - avformat_find_stream_info starting 17:02:32 272.136597 T:140490717542144 DEBUG: Open - av_find_stream_info finished 17:02:32 272.136688 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Input #0, matroska,webm, /storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mk': 17:02:32 272.136719 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.136749 T:140490717542144 INFO: ffmpeg[7FC68B503700]: encoder : libebml v1.2.3 + libmatroska v1.3.0 17:02:32 272.136810 T:140490717542144 INFO: ffmpeg[7FC68B503700]: creation_time : 2013-05-14 22:08:35 17:02:32 272.136841 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Duration: 01:21:00.02, start: 0.000000, bitrate: 12576 kb/s 17:02:32 272.137054 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:0: start 0.000000, end 365.824000 17:02:32 272.137054 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137085 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:00:00.000 17:02:32 272.137146 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:1: start 365.824000, end 683.892000 17:02:32 272.137146 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137177 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:06:05.824 17:02:32 272.137207 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:2: start 683.892000, end 849.432000 17:02:32 272.137238 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137268 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:11:23.892 17:02:32 272.137299 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:3: start 849.432000, end 1228.769000 17:02:32 272.137329 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137360 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:14:09.432 17:02:32 272.137390 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:4: start 1228.769000, end 1586.126000 17:02:32 272.137421 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137451 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:20:28.769 17:02:32 272.137512 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:5: start 1586.126000, end 2021.728000 17:02:32 272.137543 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137573 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:26:26.126 17:02:32 272.137604 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:6: start 2021.728000, end 2324.155000 17:02:32 272.137634 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137665 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:33:41.728 17:02:32 272.137695 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:7: start 2324.155000, end 2563.770000 17:02:32 272.137756 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.137909 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:38:44.155 17:02:32 272.137939 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:8: start 2563.770000, end 2754.961000 17:02:32 272.138062 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138092 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:42:43.770 17:02:32 272.138123 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:9: start 2754.961000, end 2982.354000 17:02:32 272.138153 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138153 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:45:54.961 17:02:32 272.138214 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:10: start 2982.354000, end 3241.989000 17:02:32 272.138214 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138245 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:49:42.354 17:02:32 272.138306 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:11: start 3241.989000, end 3642.305000 17:02:32 272.138306 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138336 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 00:54:01.989 17:02:32 272.138397 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:12: start 3642.305000, end 3926.339000 17:02:32 272.138397 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138428 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 01:00:42.305 17:02:32 272.138489 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:13: start 3926.339000, end 4218.881000 17:02:32 272.138489 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138519 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 01:05:26.339 17:02:32 272.138580 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:14: start 4218.881000, end 4340.920000 17:02:32 272.138580 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138611 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 01:10:18.881 17:02:32 272.138641 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Chapter #0:15: start 4340.920000, end 4860.022000 17:02:32 272.138672 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138702 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : 01:12:20.920 17:02:32 272.138824 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Stream #0:0: Video: h264 (High), yuv420p, 1920x1080, SAR 1:1 DAR 16:9, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default) 17:02:32 272.138824 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.138855 T:140490717542144 INFO: ffmpeg[7FC68B503700]: stereo_mode : left_right 17:02:32 272.138885 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Side data: 17:02:32 272.138916 T:140490717542144 INFO: ffmpeg[7FC68B503700]: stereo3d: side by side 17:02:32 272.139008 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Stream #0:1(ger): Audio: dts (DTS), 48000 Hz, 5.1(side), s32p (24 bit), 1536 kb/s (default) 17:02:32 272.139038 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.139069 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : DTS 17:02:32 272.139130 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Stream #0:2(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), s32p (24 bit), 1536 kb/s 17:02:32 272.139160 T:140490717542144 INFO: ffmpeg[7FC68B503700]: Metadata: 17:02:32 272.139191 T:140490717542144 INFO: ffmpeg[7FC68B503700]: title : DTS 17:02:32 272.139221 T:140490717542144 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0 17:02:32 272.139252 T:140490717542144 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1 17:02:32 272.139282 T:140490717542144 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2 17:02:32 272.139374 T:140490717542144 NOTICE: Opening stream: 0 source: 256 17:02:32 272.139435 T:140490717542144 NOTICE: Creating video codec with codec id: 28 17:02:32 272.139435 T:140490717542144 DEBUG: FactoryCodec - Video: - Opening 17:02:32 272.139465 T:140490717542144 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 17:02:32 272.139740 T:140490717542144 DEBUG: FactoryCodec - Video: ff-h264 - Opened 17:02:32 272.139771 T:140490717542144 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28 17:02:32 272.139801 T:140490717542144 NOTICE: Creating video thread 17:02:32 272.139954 T:140490717542144 NOTICE: Opening stream: 1 source: 256 17:02:32 272.139984 T:140490717542144 NOTICE: Finding audio codec for: 86020 17:02:32 272.140045 T:140490717542144 DEBUG: FactoryCodec - Audio: passthrough - Opening 17:02:32 272.140076 T:140490754172672 DEBUG: Thread VideoPlayerVideo start, auto delete: false 17:02:32 272.140259 T:140490717542144 DEBUG: FactoryCodec - Audio: passthrough - Opened 17:02:32 272.140381 T:140490754172672 NOTICE: running thread: video_thread 17:02:32 272.140503 T:140490717542144 NOTICE: Creating audio thread 17:02:32 272.140594 T:140490717542144 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv 17:02:32 272.140778 T:140490717542144 DEBUG: OnPlayBackStarted: play state was 1, starting 1 17:02:32 272.140839 T:140492205930560 DEBUG: PlayFile: OpenFile succeed, play state 2 17:02:32 272.140869 T:140492205930560 DEBUG: OnPlayBackStarted: play state was 2, starting 0 17:02:32 272.141052 T:140490829707008 DEBUG: Thread VideoPlayerAudio start, auto delete: false 17:02:32 272.141144 T:140490829707008 NOTICE: running thread: CVideoPlayerAudio::Process() 17:02:32 272.143677 T:140490717542144 DEBUG: CVideoPlayer::SetCaching - caching state 2 17:02:32 272.144104 T:140490829707008 DEBUG: CDVDAudio::Pause - pausing audio stream 17:02:32 272.148010 T:140490754172672 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback 17:02:32 272.148132 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:02:32 272.148315 T:140490754172672 DEBUG: VAAPI - open decoder 17:02:32 272.149689 T:140490754172672 DEBUG: VAAPI - initialize version 0.38 17:02:32 272.149750 T:140490754172672 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) CherryView - 1.6.2 17:02:32 272.149780 T:140490754172672 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0 17:02:32 272.149780 T:140490754172672 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32 17:02:32 272.149811 T:140490754172672 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0 17:02:32 272.149841 T:140490754172672 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32 17:02:32 272.149841 T:140490754172672 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0 17:02:32 272.149872 T:140490754172672 DEBUG: VAAPI - profile 0 17:02:32 272.149902 T:140490754172672 DEBUG: VAAPI - profile 1 17:02:32 272.149933 T:140490754172672 DEBUG: VAAPI - profile 13 17:02:32 272.149963 T:140490754172672 DEBUG: VAAPI - profile 6 17:02:32 272.150024 T:140490754172672 DEBUG: VAAPI - profile 7 17:02:32 272.150055 T:140490754172672 DEBUG: VAAPI - profile 15 17:02:32 272.150055 T:140490754172672 DEBUG: VAAPI - profile 16 17:02:32 272.150085 T:140490754172672 DEBUG: VAAPI - profile 8 17:02:32 272.150116 T:140490754172672 DEBUG: VAAPI - profile 9 17:02:32 272.150116 T:140490754172672 DEBUG: VAAPI - profile 10 17:02:32 272.150146 T:140490754172672 DEBUG: VAAPI - profile -1 17:02:32 272.150177 T:140490754172672 DEBUG: VAAPI - profile 12 17:02:32 272.150177 T:140490754172672 DEBUG: VAAPI - profile 14 17:02:32 272.150208 T:140490754172672 DEBUG: VAAPI - profile 15 17:02:32 272.150238 T:140490754172672 DEBUG: VAAPI - profile 16 17:02:32 272.150238 T:140490754172672 DEBUG: VAAPI - profile 17 17:02:32 272.150848 T:140490829707008 INFO: CAEStreamParser::SyncDTS - dts stream detected (6 channels, 48000Hz, 16bit BE, period: 512) 17:02:32 272.150909 T:140490717542144 NOTICE: CDVDMessageQueue::GetLevel() - can't determine level 17:02:32 272.151123 T:140490829707008 NOTICE: Previous line repeats 1 times. 17:02:32 272.151123 T:140490829707008 NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, pass-through) 17:02:32 272.151276 T:140490717542144 NOTICE: CDVDMessageQueue::GetLevel() - can't determine level 17:02:32 272.151337 T:140490728998656 DEBUG: Thread Vaapi-Output start, auto delete: false 17:02:32 272.151367 T:140490717542144 NOTICE: CDVDMessageQueue::GetLevel() - can't determine level 17:02:32 272.151520 T:140490829707008 NOTICE: Previous line repeats 6 times. 17:02:32 272.151550 T:140490829707008 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback 17:02:32 272.153351 T:140490728998656 NOTICE: COutput::OnStartup: Output Thread created 17:02:32 272.156281 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:02:32 272.160706 T:140490728998656 DEBUG: VAAPI::COutput::CreateEGLContext - created context 17:02:32 272.160828 T:140490728998656 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so) 17:02:32 272.160889 T:140490728998656 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so 17:02:32 272.181976 T:140490728998656 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created 17:02:32 272.190277 T:140492205930560 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 17:02:32 272.191010 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:02:32 272.191162 T:140492205930560 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 17:02:32 272.191223 T:140491316254464 DEBUG: CGUIInfoManager::SetCurrentMovie(/storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv) 17:02:32 272.191406 T:140492205930560 DEBUG: UPnP: Building didl for object '/storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv' 17:02:32 272.192902 T:140492205930560 DEBUG: StereoscopicsManager: autodetected stereo mode for movie mode left_right is: split_vertical 17:02:32 272.192932 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:02:32 272.192963 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode setting changed to split_vertical 17:02:32 272.192993 T:140492205930560 DEBUG: StereoscopicsManager::ApplyStereoMode: trying to apply stereo mode. Current: off | Target: split_vertical 17:02:32 272.193024 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode changed to split_vertical 17:02:32 272.193237 T:140492205930560 DEBUG: ------ Window Init (DialogNotification.xml) ------ 17:02:32 272.195831 T:140491316254464 DEBUG: GetMovieId (/storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv), query = select idMovie from movie where idFile=306 17:02:32 272.201233 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:02:32 272.217224 T:140490754172672 DEBUG: Previous line repeats 2 times. 17:02:32 272.217285 T:140490754172672 DEBUG: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 23.98. format: VAAPI 17:02:32 272.234863 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:32 272.274170 T:140492205930560 DEBUG: DeleteRenderer - deleting renderer 17:02:32 272.274231 T:140492205930560 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:02:32 272.274323 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:02:32 272.274353 T:140492205930560 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16 17:02:32 272.274414 T:140492205930560 DEBUG: GL: Requested render method: 0 17:02:32 272.274689 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:02:32 272.274872 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:02:32 272.277435 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:32 272.277496 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:32 272.281342 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:32 272.281403 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:32 272.314453 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:32 272.355255 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:32 272.356354 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:32 272.356384 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:32 272.357483 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 279 17:02:32 272.358887 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:02:32 272.358948 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:02:32 272.362671 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02" 17:02:32 272.362976 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 17:02:32 272.369537 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:02:32 272.369568 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:02:32 272.369598 T:140492205930560 NOTICE: Using GL_TEXTURE_2D 17:02:32 272.369629 T:140492205930560 DEBUG: CRenderManager::Configure - 4 17:02:32 272.370300 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 17:02:32 272.370361 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:02:32 272.371124 T:140492205930560 DEBUG: GL: Requested render method: 0 17:02:32 272.371368 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:02:32 272.371399 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:02:32 272.371429 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW 17:02:32 272.371460 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none) 17:02:32 272.371460 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:02:32 272.371521 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:02:32 272.371552 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:02:32 272.371643 T:140492178601728 DEBUG: Sample Rate : 48000 17:02:32 272.371674 T:140492178601728 DEBUG: Sample Format : AE_FMT_S16NE 17:02:32 272.371674 T:140492178601728 DEBUG: Channel Count : 2 17:02:32 272.371704 T:140492178601728 DEBUG: Channel Layout: RAW,RAW 17:02:32 272.371735 T:140492178601728 DEBUG: Frames : 2400 17:02:32 272.371735 T:140492178601728 DEBUG: Frame Size : 4 17:02:32 272.374146 T:140490717542144 DEBUG: CVideoPlayer::HandleMessages - player started 2 17:02:32 272.375305 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:32 272.375610 T:140490829707008 DEBUG: CDVDClock::Discontinuity - CVideoPlayerAudio::OutputPacket - was:234824.170000, should be:2967.209000, error:-231856.961000 17:02:32 272.376160 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:32 272.377472 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:32 272.377533 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:32 272.380157 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:32 272.380188 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:32 272.384277 T:140490717542144 DEBUG: CVideoPlayer::HandleMessages - player started 1 17:02:32 272.384308 T:140490717542144 DEBUG: CVideoPlayer::SetCaching - caching state 3 17:02:32 272.384491 T:140490717542144 DEBUG: CVideoPlayer::SetCaching - caching state 0 17:02:32 272.384521 T:140490717542144 DEBUG: VideoPlayer::Sync - Audio - pts: 106000.000000, cache: 302848.517033, totalcache: 600000.023842 17:02:32 272.384521 T:140490717542144 DEBUG: VideoPlayer::Sync - Video - pts: -4503599627370496.000000, cache: 50000.000000, totalcache: 100000.000000 17:02:32 272.384613 T:140490754172672 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-196848.517033) 17:02:32 272.384674 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:02:32 272.394592 T:140490829707008 DEBUG: Previous line repeats 3 times. 17:02:32 272.394653 T:140490829707008 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-196848.517033) 17:02:32 272.394684 T:140490829707008 DEBUG: CDVDAudio::Resume - resume audio stream 17:02:32 272.396240 T:140492186994432 DEBUG: ActiveAE - start sync of audio stream 17:02:32 272.414673 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:02:32 272.414734 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:02:32 272.475098 T:140492205930560 DEBUG: Activating window ID: 12005 17:02:32 272.475891 T:140492205930560 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 17:02:32 272.476776 T:140492205930560 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 17:02:32 272.478821 T:140492205930560 NOTICE: Display resolution ADJUST : HDMI2: 1920x1080 @ 23.98Hz (24) (weight: 0.000) 17:02:32 272.478882 T:140492205930560 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 17:02:32 272.485626 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:32 272.485687 T:140492205930560 DEBUG: Flush - flushing renderer 17:02:32 272.487854 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:02:33 272.723236 T:140492205930560 DEBUG: Previous line repeats 4 times. 17:02:33 272.723328 T:140492205930560 NOTICE: VideoPlayer: OnLostDisplay received 17:02:33 272.723450 T:140492205930560 INFO: XRANDR: /usr/lib/kodi/kodi-xrandr --screen 0 --output HDMI2 --mode 0x10c 17:02:33 272.966583 T:140492197529344 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 17:02:33 273.063232 T:140491786528512 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 17:02:33 273.128479 T:140492197529344 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 17:02:33 273.175049 T:140491786528512 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 17:02:33 273.176971 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:33 273.178619 T:140492205930560 DEBUG: GL: Requested render method: 0 17:02:33 273.178955 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:02:33 273.179199 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:02:33 273.181519 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:33 273.181580 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:33 273.184692 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:33 273.184753 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:33 273.208984 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:33 273.209076 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:33 273.209930 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:33 273.209961 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:33 273.217590 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:02:33 273.217651 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:02:33 273.217682 T:140492205930560 NOTICE: Using GL_TEXTURE_2D 17:02:33 273.218231 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:02:33 273.234192 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:02:33 273.234222 T:140492205930560 DEBUG: NotifyXRREvent - notify display reset event 17:02:33 273.254028 T:140492205930560 DEBUG: RecreateWindow - current output: HDMI2, mode: 0x10c, refresh: 23.976 17:02:33 273.254120 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:33 273.254181 T:140492205930560 DEBUG: Flush - flushing renderer 17:02:33 273.255463 T:140492205930560 NOTICE: VideoPlayer: OnLostDisplay received 17:02:33 273.258575 T:140492205930560 DEBUG: Window Manager Name: Fluxbox 17:02:33 273.282471 T:140490754172672 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 17:02:33 273.282623 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0 17:02:33 273.283966 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:02:33 273.284027 T:140492205930560 DEBUG: CWinSystemX11::RefreshEGLContext: refreshing context 17:02:33 273.285553 T:140492205930560 NOTICE: VideoPlayer: OnResetDisplay received 17:02:33 273.285675 T:140492186994432 DEBUG: CActiveAE - display reset event 17:02:33 273.285767 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:33 273.286011 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:02:33 273.286072 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:02:33 273.286102 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:02:33 273.290222 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02" 17:02:33 273.290527 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600 17:02:33 273.295319 T:140492205930560 DEBUG: GL: Requested render method: 0 17:02:33 273.295624 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:02:33 273.296265 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:02:33 273.296692 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600 17:02:33 273.296722 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:02:33 273.297699 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:02:33 273.297729 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW 17:02:33 273.297729 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none) 17:02:33 273.297760 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:02:33 273.297791 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:02:33 273.297821 T:140492178601728 DEBUG: Sample Rate : 48000 17:02:33 273.297821 T:140492178601728 DEBUG: Sample Format : AE_FMT_S16NE 17:02:33 273.297852 T:140492178601728 DEBUG: Channel Count : 2 17:02:33 273.297882 T:140492178601728 DEBUG: Channel Layout: RAW,RAW 17:02:33 273.297882 T:140492178601728 DEBUG: Frames : 2400 17:02:33 273.297913 T:140492178601728 DEBUG: Frame Size : 4 17:02:33 273.301117 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:33 273.301208 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:33 273.301666 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:33 273.304230 T:140492186994432 DEBUG: Previous line repeats 1 times. 17:02:33 273.304291 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error of 3.128105, start adjusting 17:02:33 273.304321 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error 0.128105 below threshold of 30.000000 17:02:33 273.305267 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:33 273.305298 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:33 273.329559 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:33 273.329651 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:33 273.330536 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:33 273.330566 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:33 273.338989 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:02:33 273.339050 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:02:33 273.339081 T:140492205930560 NOTICE: Using GL_TEXTURE_2D 17:02:33 273.339264 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:02:33 273.349121 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:02:33 273.349274 T:140492205930560 WARNING: CRenderCaptureGL: GL_ARB_occlusion_query disabled, performance might suffer 17:02:33 273.349426 T:140492205930560 DEBUG: GL: Requested render method: 0 17:02:33 273.349640 T:140492205930560 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_OUTPUT 1 #define XBMC_DITHER 1 #define XBMC_FULLRANGE 1 #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_NV12_RRG 17:02:33 273.349823 T:140492205930560 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 17:02:33 273.351807 T:140492205930560 DEBUG: GL: Vertex Shader compilation log: 17:02:33 273.351868 T:140492205930560 DEBUG: GL: Vertex Shader compiled successfully 17:02:33 273.354706 T:140492205930560 DEBUG: GL: Pixel Shader compilation log: 17:02:33 273.354736 T:140492205930560 DEBUG: GL: Fragment Shader compiled successfully 17:02:33 273.376892 T:140492205930560 NOTICE: GL: NPOT texture support detected 17:02:33 273.376953 T:140492205930560 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:02:33 273.378021 T:140492205930560 WARNING: CRenderCaptureGL: GL_ARB_occlusion_query disabled, performance might suffer 17:02:33 273.385345 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:02:33 273.423187 T:140491333039872 DEBUG: Previous line repeats 3 times. 17:02:33 273.430878 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:02:33 273.532593 T:140491333039872 DEBUG: Previous line repeats 6 times. 17:02:33 273.550995 T:140490754172672 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1 17:02:33 273.656891 T:140491333039872 DEBUG: Previous line repeats 5 times. 17:02:34 274.348511 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error -397.108412 above threshold of 100.000000 17:02:34 274.549408 T:140492186994432 DEBUG: ActiveAE::SyncStream - average error -23.775079 below threshold of 30.000000 17:02:35 275.648285 T:140490829707008 DEBUG: CDVDClock::Discontinuity - CVideoPlayerAudio::OutputPacket - was:3066856.009967, should be:3034038.449991, error:-32817.559975 17:02:38 277.763611 T:140492205930560 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 17:02:38 277.988373 T:140490754172672 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333 17:02:56 296.567932 T:140491752957696 DEBUG: PushCecKeypress - received key e0 duration 1000 17:02:56 296.589172 T:140492205930560 DEBUG: OnKey: guide (0xe0) pressed, action is Stop 17:02:56 296.589294 T:140492205930560 NOTICE: CVideoPlayer::CloseFile() 17:02:56 296.589325 T:140492205930560 NOTICE: VideoPlayer: waiting for threads to exit 17:02:56 296.591400 T:140490717542144 NOTICE: CVideoPlayer::OnExit() 17:02:56 296.591492 T:140490717542144 NOTICE: Closing stream player 1 17:02:56 296.591522 T:140490717542144 NOTICE: Waiting for audio thread to exit 17:02:56 296.599548 T:140490829707008 NOTICE: thread end: CVideoPlayerAudio::OnExit() 17:02:56 296.599854 T:140490829707008 DEBUG: Thread VideoPlayerAudio 140490829707008 terminating 17:02:56 296.600037 T:140490717542144 NOTICE: Closing audio device 17:02:56 296.648743 T:140490717542144 DEBUG: CDVDAudio::Flush - flush audio stream 17:02:56 296.648865 T:140492186994432 DEBUG: CActiveAE::DiscardStream - audio stream deleted 17:02:56 296.648987 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:56 296.649872 T:140490717542144 NOTICE: Deleting audio codec 17:02:56 296.649933 T:140490717542144 NOTICE: Closing stream player 2 17:02:56 296.650024 T:140490717542144 NOTICE: waiting for video thread to exit 17:02:56 296.650177 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:56 296.651489 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:02:56 296.688904 T:140490754172672 NOTICE: thread end: video_thread 17:02:56 296.689056 T:140490754172672 DEBUG: Thread VideoPlayerVideo 140490754172672 terminating 17:02:56 296.689636 T:140490717542144 NOTICE: deleting video codec 17:02:56 296.689941 T:140490717542144 DEBUG: VAAPI::Release pre-cleanup 17:02:56 296.693451 T:140490717542144 DEBUG: OnPlayBackStopped: play state was 2, starting 0 17:02:56 296.693542 T:140490717542144 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 17:02:56 296.693634 T:140490717542144 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 17:02:56 296.693848 T:140490717542144 DEBUG: Thread VideoPlayer 140490717542144 terminating 17:02:56 296.694000 T:140492205930560 NOTICE: VideoPlayer: finished waiting 17:02:56 296.694122 T:140492205930560 DEBUG: DeleteRenderer - deleting renderer 17:02:56 296.694794 T:140492205930560 NOTICE: VAAPI::Close 17:02:56 296.695862 T:140490728998656 NOTICE: COutput::OnExit: Output Thread terminated 17:02:56 296.695953 T:140490728998656 DEBUG: Thread Vaapi-Output 140490728998656 terminating 17:02:56 296.696320 T:140492205930560 DEBUG: VAAPI::FiniVAAPIOutput destroying 5 video surfaces 17:02:56 296.700592 T:140492205930560 NOTICE: VAAPI::Close - closing decoder context 17:02:56 296.704865 T:140492205930560 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:02:56 296.705017 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:02:56 296.705078 T:140492205930560 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 17:02:57 296.714752 T:140492205930560 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 17:02:57 296.714935 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:57 296.850281 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:02:57 296.850372 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:02:57 296.854584 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 17:02:57 296.855072 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:02:57 296.855133 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:02:57 296.855133 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 17:02:57 296.860168 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820 17:02:57 296.860229 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:02:57 296.861603 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:02:57 296.861603 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:02:57 296.861633 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:02:57 296.861664 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:02:57 296.861694 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:02:57 296.861694 T:140492178601728 DEBUG: Sample Rate : 44100 17:02:57 296.861725 T:140492178601728 DEBUG: Sample Format : AE_FMT_S32NE 17:02:57 296.861755 T:140492178601728 DEBUG: Channel Count : 2 17:02:57 296.861755 T:140492178601728 DEBUG: Channel Layout: FL,FR 17:02:57 296.861786 T:140492178601728 DEBUG: Frames : 2205 17:02:57 296.861816 T:140492178601728 DEBUG: Frame Size : 8 17:02:57 296.864197 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:57 297.015289 T:140492205930560 NOTICE: VideoPlayer: OnLostDisplay received 17:02:57 297.015472 T:140492205930560 INFO: XRANDR: /usr/lib/kodi/kodi-xrandr --screen 0 --output HDMI2 --mode 0x49 17:02:57 297.368561 T:140491786528512 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 17:02:58 298.317780 T:140492197529344 DEBUG: Previous line repeats 1 times. 17:02:58 298.318024 T:140492197529344 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 17:02:58 298.548950 T:140492205930560 DEBUG: Previous line repeats 1 times. 17:02:58 298.549103 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:58 298.554382 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:02:58 298.570038 T:140492205930560 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 17:02:58 298.573395 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory (/storage/mediathek/Videos/HD/3D/) 17:02:58 298.573486 T:140492205930560 DEBUG: ParentPath = [/storage/mediathek/Videos/HD/3D/] 17:02:58 298.637878 T:140492205930560 DEBUG: RunQuery took 3 ms for 59 items query: select * from movie_view WHERE c23=52 17:02:58 298.647156 T:140490728998656 DEBUG: Thread BackgroundLoader start, auto delete: false 17:02:58 298.653717 T:140492205930560 INFO: ffmpeg[7FC6E4073840]: [mjpeg] Changeing bps to 8 17:02:58 298.672119 T:140490728998656 DEBUG: Thread BackgroundLoader 140490728998656 terminating 17:02:58 298.696014 T:140491302921984 DEBUG: GetImageHash - unable to stat url 17:02:59 298.768860 T:140492205930560 NOTICE: CVideoPlayer::CloseFile() 17:02:59 298.768921 T:140492205930560 NOTICE: VideoPlayer: waiting for threads to exit 17:02:59 298.768951 T:140492205930560 NOTICE: VideoPlayer: finished waiting 17:02:59 298.768982 T:140492205930560 NOTICE: CVideoPlayer::CloseFile() 17:02:59 298.769012 T:140492205930560 NOTICE: VideoPlayer: waiting for threads to exit 17:02:59 298.769043 T:140492205930560 NOTICE: VideoPlayer: finished waiting 17:02:59 298.771881 T:140492205930560 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 17:02:59 298.772278 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode setting changed to off 17:02:59 298.772308 T:140492205930560 DEBUG: StereoscopicsManager::ApplyStereoMode: trying to apply stereo mode. Current: split_vertical | Target: off 17:02:59 298.772339 T:140492205930560 DEBUG: StereoscopicsManager: stereo mode changed to off 17:02:59 298.772919 T:140491316254464 DEBUG: DoWork - Saving file state for video item /storage/mediathek/Videos/HD/3D/The Art of Flight (2011).mkv 17:02:59 298.773010 T:140492205930560 DEBUG: ------ Window Init (DialogNotification.xml) ------ 17:02:59 298.773193 T:140492205930560 DEBUG: NotifyXRREvent - notify display reset event 17:02:59 298.794983 T:140492205930560 DEBUG: RecreateWindow - current output: HDMI2, mode: 0x49, refresh: 50.000 17:02:59 298.795044 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:59 298.799835 T:140492205930560 DEBUG: Window Manager Name: Fluxbox 17:02:59 298.821442 T:140492205930560 DEBUG: CWinSystemX11::RefreshEGLContext: refreshing context 17:02:59 298.822723 T:140492205930560 DEBUG: OnLostDevice - notify display change event 17:02:59 298.826660 T:140492205930560 DEBUG: Window Manager Name: Fluxbox 17:02:59 298.847473 T:140492205930560 DEBUG: CWinSystemX11::RefreshEGLContext: refreshing context 17:02:59 298.849670 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:59 298.849731 T:140492186994432 DEBUG: CActiveAE - display reset event 17:02:59 298.850189 T:140492178601728 INFO: CActiveAESink::OpenSink - initialize sink 17:02:59 298.850311 T:140492178601728 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0 17:02:59 298.850342 T:140492178601728 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0" 17:02:59 298.854401 T:140492178601728 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 17:02:59 298.854858 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 17:02:59 298.854919 T:140492178601728 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 17:02:59 298.854919 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 17:02:59 298.858337 T:140491316254464 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc 17:02:59 298.858398 T:140491316254464 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate 17:02:59 298.860016 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820 17:02:59 298.860077 T:140492178601728 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 17:02:59 298.861023 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 17:02:59 298.861053 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 17:02:59 298.861084 T:140492178601728 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 17:02:59 298.861084 T:140492178601728 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 17:02:59 298.861115 T:140492178601728 DEBUG: Output Device : HDA Intel PCH 17:02:59 298.861145 T:140492178601728 DEBUG: Sample Rate : 44100 17:02:59 298.861145 T:140492178601728 DEBUG: Sample Format : AE_FMT_S32NE 17:02:59 298.861176 T:140492178601728 DEBUG: Channel Count : 2 17:02:59 298.861206 T:140492178601728 DEBUG: Channel Layout: FL,FR 17:02:59 298.861206 T:140492178601728 DEBUG: Frames : 2205 17:02:59 298.861237 T:140492178601728 DEBUG: Frame Size : 8 17:02:59 298.862946 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:02:59 298.882294 T:140492205930560 INFO: GL: Maximum texture width: 8192 17:02:59 298.968170 T:140492186994432 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 17:03:02 302.490265 T:140492205930560 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so) 17:03:03 302.969666 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 0 17:03:03 302.992188 T:140492205930560 DEBUG: OnKey: menu (0xd8) pressed, action is Back 17:03:03 302.992798 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory (/storage/mediathek/Videos/HD/) 17:03:03 302.993561 T:140492205930560 DEBUG: ParentPath = [sources://video/] 17:03:03 303.017242 T:140492205930560 DEBUG: RunQuery took 1 ms for 0 items query: select * from movie_view WHERE c23=51 17:03:03 303.019470 T:140490728998656 DEBUG: Thread BackgroundLoader start, auto delete: false 17:03:03 303.023773 T:140491316254464 DEBUG: GetImageHash - unable to stat url 17:03:03 303.062958 T:140490728998656 DEBUG: Thread BackgroundLoader 140490728998656 terminating 17:03:03 303.244446 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 275 17:03:04 303.867249 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 0 17:03:04 303.885864 T:140492205930560 DEBUG: OnKey: menu (0xd8) pressed, action is Back 17:03:04 303.951355 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/) 17:03:04 303.951447 T:140492205930560 DEBUG: ParentPath = [] 17:03:04 303.978668 T:140490728998656 DEBUG: Thread BackgroundLoader start, auto delete: false 17:03:04 303.986206 T:140490728998656 DEBUG: Thread BackgroundLoader 140490728998656 terminating 17:03:04 304.147339 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 280 17:03:04 304.624420 T:140492205930560 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 17:03:05 305.220917 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 0 17:03:05 305.248749 T:140492205930560 DEBUG: OnKey: menu (0xd8) pressed, action is Back 17:03:05 305.250397 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory () 17:03:05 305.250519 T:140492205930560 DEBUG: ParentPath = [] 17:03:05 305.269440 T:140490754172672 DEBUG: Thread BackgroundLoader start, auto delete: false 17:03:05 305.284698 T:140490754172672 DEBUG: Thread BackgroundLoader 140490754172672 terminating 17:03:05 305.347260 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 127 17:03:06 306.170654 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 0 17:03:06 306.202515 T:140492205930560 DEBUG: OnKey: menu (0xd8) pressed, action is Back 17:03:06 306.203430 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:03:06 306.448730 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 278 17:03:06 306.518982 T:140492205930560 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 17:03:06 306.521790 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:03:06 306.524689 T:140492205930560 DEBUG: ------ Window Init (MyMusicNav.xml) ------ 17:03:06 306.536255 T:140492205930560 DEBUG: CGUIMediaWindow::GetDirectory () 17:03:06 306.536316 T:140492205930560 DEBUG: ParentPath = [] 17:03:06 306.548309 T:140492205930560 DEBUG: RetrieveMusicInfo() took 0 msec 17:03:06 306.552948 T:140490611631872 DEBUG: Thread BackgroundLoader start, auto delete: false 17:03:06 306.559631 T:140490611631872 DEBUG: Thread BackgroundLoader 140490611631872 terminating 17:03:07 307.570557 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 0 17:03:07 307.599091 T:140492205930560 DEBUG: OnKey: menu (0xd8) pressed, action is Back 17:03:07 307.601349 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 17:03:07 307.694275 T:140491752957696 DEBUG: PushCecKeypress - received key d8 duration 124 17:03:08 307.918457 T:140492205930560 DEBUG: ------ Window Deinit (MyMusicNav.xml) ------ 17:03:08 307.919922 T:140492205930560 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 17:03:08 307.920074 T:140492205930560 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 17:03:09 309.468658 T:140491752957696 DEBUG: PushCecKeypress - received key b duration 0 17:03:09 309.486572 T:140492205930560 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 17:03:09 309.486725 T:140492205930560 NOTICE: Disabled debug logging due to GUI setting. Level 0. 17:03:09 309.486816 T:140492205930560 NOTICE: Log level changed to "LOG_LEVEL_NORMAL"