2025-08-10 15:05:09.190 T:771 info : ----------------------------------------------------------------------- 2025-08-10 15:05:09.190 T:771 info : Starting Kodi (21.2 (21.2.0) Git:21.2-Omega). Platform: Linux ARM 32-bit 2025-08-10 15:05:09.190 T:771 info : Using Release Kodi x32 2025-08-10 15:05:09.190 T:771 info : Kodi compiled 2025-01-20 by GCC 13.2.0 for Linux ARM 32-bit version 6.6.70 (394822) 2025-08-10 15:05:09.190 T:771 info : Running on BCM2835 with LibreELEC (official): 12.0.2, kernel: Linux ARM 32-bit version 6.6.70 2025-08-10 15:05:09.191 T:771 info : FFmpeg version/source: 6.0.1 2025-08-10 15:05:09.191 T:771 info : Host CPU: ARMv7 Processor rev 5 (v7l), 4 cores available 2025-08-10 15:05:09.191 T:771 info : ARM Features: Neon enabled 2025-08-10 15:05:09.191 T:771 info : special://xbmc/ is mapped to: /usr/share/kodi/ 2025-08-10 15:05:09.191 T:771 info : special://xbmcbin/ is mapped to: /usr/lib/kodi 2025-08-10 15:05:09.191 T:771 info : special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons 2025-08-10 15:05:09.191 T:771 info : special://masterprofile/ is mapped to: /storage/.kodi/userdata 2025-08-10 15:05:09.191 T:771 info : special://envhome/ is mapped to: /storage 2025-08-10 15:05:09.191 T:771 info : special://home/ is mapped to: /storage/.kodi 2025-08-10 15:05:09.191 T:771 info : special://temp/ is mapped to: /storage/.kodi/temp 2025-08-10 15:05:09.191 T:771 info : special://logpath/ is mapped to: /storage/.kodi/temp 2025-08-10 15:05:09.191 T:771 info : Webserver extra whitelist paths: 2025-08-10 15:05:09.191 T:771 info : The executable running is: /usr/lib/kodi/kodi.bin 2025-08-10 15:05:09.191 T:771 info : Local hostname: LibreELEC 2025-08-10 15:05:09.191 T:771 info : Log File is located: /storage/.kodi/temp/kodi.log 2025-08-10 15:05:09.191 T:771 info : ----------------------------------------------------------------------- 2025-08-10 15:05:09.191 T:771 info : loading settings 2025-08-10 15:05:09.194 T:771 info : special://profile/ is mapped to: special://masterprofile/ 2025-08-10 15:05:09.249 T:771 info : Loaded settings file from special://xbmc/system/advancedsettings.xml 2025-08-10 15:05:09.251 T:771 info : Contents of special://xbmc/system/advancedsettings.xml are... /usr/bin/gputemp false 1 720 540 2025-08-10 15:05:09.254 T:771 info : No settings file to load (special://masterprofile/advancedsettings.xml) 2025-08-10 15:05:09.254 T:771 info : Default Video Player: VideoPlayer 2025-08-10 15:05:09.254 T:771 info : Default Audio Player: paplayer 2025-08-10 15:05:09.254 T:771 info : Disabled debug logging due to GUI setting. Level 0. 2025-08-10 15:05:09.254 T:771 info : Log level changed to "info" 2025-08-10 15:05:09.254 T:771 info : CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml 2025-08-10 15:05:09.286 T:771 info : New Cache GUI Settings (replacement of cache in advancedsettings.xml) are: Buffer Mode: 4 Memory Size: 24 MB Read Factor: 7.00 x Chunk Size : 262144 bytes 2025-08-10 15:05:09.286 T:771 info : creating subdirectories 2025-08-10 15:05:09.286 T:771 info : userdata folder: special://masterprofile/ 2025-08-10 15:05:09.286 T:771 info : recording folder: 2025-08-10 15:05:09.286 T:771 info : screenshots folder: /storage/screenshots/ 2025-08-10 15:05:09.381 T:771 info : Running database version Addons33 2025-08-10 15:05:10.129 T:775 info : Loading special://xbmc/system/Lircmap.xml 2025-08-10 15:05:10.152 T:775 info : * Adding remote mapping for device 'mceusb' 2025-08-10 15:05:10.153 T:775 info : * Adding remote mapping for device 'XboxDVDDongle' 2025-08-10 15:05:10.153 T:775 info : * Adding remote mapping for device 'Microsoft_Xbox' 2025-08-10 15:05:10.153 T:775 info : * Adding remote mapping for device 'PinnacleSysPCTVRemote' 2025-08-10 15:05:10.153 T:775 info : * Adding remote mapping for device 'anysee' 2025-08-10 15:05:10.153 T:775 info : * Adding remote mapping for device 'iMON-PAD' 2025-08-10 15:05:10.153 T:775 info : * Adding remote mapping for device 'Antec_Veris_RM200' 2025-08-10 15:05:10.153 T:775 info : * Adding remote mapping for device 'MCE_via_iMON' 2025-08-10 15:05:10.154 T:775 info : * Adding remote mapping for device 'TwinHanRemote' 2025-08-10 15:05:10.154 T:775 info : * Adding remote mapping for device 'linux-input-layer' 2025-08-10 15:05:10.154 T:775 info : * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote' 2025-08-10 15:05:10.154 T:775 info : * Adding remote mapping for device 'mediacenter' 2025-08-10 15:05:10.154 T:775 info : * Adding remote mapping for device 'devinput' 2025-08-10 15:05:10.155 T:775 info : * Adding remote mapping for device 'devinput-32' 2025-08-10 15:05:10.155 T:775 info : * Adding remote mapping for device 'devinput-64' 2025-08-10 15:05:10.158 T:771 info : CAddonMgr::FindAddons: audioencoder.kodi.builtin.aac v1.0.2 installed 2025-08-10 15:05:10.158 T:771 info : CAddonMgr::FindAddons: audioencoder.kodi.builtin.wma v1.0.2 installed 2025-08-10 15:05:10.158 T:771 info : CAddonMgr::FindAddons: game.controller.default v1.0.42 installed 2025-08-10 15:05:10.159 T:771 info : CAddonMgr::FindAddons: game.controller.keyboard v1.1.43 installed 2025-08-10 15:05:10.159 T:771 info : CAddonMgr::FindAddons: game.controller.mouse v1.0.26 installed 2025-08-10 15:05:10.160 T:771 info : CAddonMgr::FindAddons: game.controller.snes v1.0.40 installed 2025-08-10 15:05:10.160 T:771 info : CAddonMgr::FindAddons: inputstream.adaptive v21.5.15.1 installed 2025-08-10 15:05:10.160 T:771 info : CAddonMgr::FindAddons: inputstream.ffmpegdirect v21.3.8.1 installed 2025-08-10 15:05:10.160 T:771 info : CAddonMgr::FindAddons: inputstream.rtmp v21.1.2.1 installed 2025-08-10 15:05:10.161 T:771 info : CAddonMgr::FindAddons: kodi.binary.global.audioengine v1.1.1 installed 2025-08-10 15:05:10.161 T:771 info : CAddonMgr::FindAddons: kodi.binary.global.filesystem v1.1.8 installed 2025-08-10 15:05:10.161 T:771 info : CAddonMgr::FindAddons: kodi.binary.global.general v1.0.5 installed 2025-08-10 15:05:10.162 T:771 info : CAddonMgr::FindAddons: kodi.binary.global.gui v5.15.0 installed 2025-08-10 15:05:10.162 T:771 info : CAddonMgr::FindAddons: kodi.binary.global.main v2.0.2 installed 2025-08-10 15:05:10.162 T:771 info : CAddonMgr::FindAddons: kodi.binary.global.network v1.0.4 installed 2025-08-10 15:05:10.163 T:771 info : CAddonMgr::FindAddons: kodi.binary.global.tools v1.0.4 installed 2025-08-10 15:05:10.163 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.audiodecoder v4.0.0 installed 2025-08-10 15:05:10.163 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.audioencoder v3.0.0 installed 2025-08-10 15:05:10.164 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.game v3.0.2 installed 2025-08-10 15:05:10.164 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.imagedecoder v3.0.1 installed 2025-08-10 15:05:10.164 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.inputstream v3.3.0 installed 2025-08-10 15:05:10.165 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.peripheral v3.0.2 installed 2025-08-10 15:05:10.165 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.pvr v8.3.0 installed 2025-08-10 15:05:10.165 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.screensaver v2.2.0 installed 2025-08-10 15:05:10.165 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.vfs v3.0.1 installed 2025-08-10 15:05:10.166 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.videocodec v2.1.0 installed 2025-08-10 15:05:10.166 T:771 info : CAddonMgr::FindAddons: kodi.binary.instance.visualization v4.0.0 installed 2025-08-10 15:05:10.166 T:771 info : CAddonMgr::FindAddons: kodi.resource v1.0.0 installed 2025-08-10 15:05:10.167 T:771 info : CAddonMgr::FindAddons: metadata.album.universal v3.1.14 installed 2025-08-10 15:05:10.167 T:771 info : CAddonMgr::FindAddons: metadata.artists.universal v4.3.16 installed 2025-08-10 15:05:10.167 T:771 info : CAddonMgr::FindAddons: metadata.common.allmusic.com v3.2.2 installed 2025-08-10 15:05:10.168 T:771 info : CAddonMgr::FindAddons: metadata.common.fanart.tv v3.6.4 installed 2025-08-10 15:05:10.168 T:771 info : CAddonMgr::FindAddons: metadata.common.musicbrainz.org v2.2.4 installed 2025-08-10 15:05:10.168 T:771 info : CAddonMgr::FindAddons: metadata.common.theaudiodb.com v2.0.3 installed 2025-08-10 15:05:10.168 T:771 info : CAddonMgr::FindAddons: metadata.generic.albums v1.0.18 installed 2025-08-10 15:05:10.169 T:771 info : CAddonMgr::FindAddons: metadata.generic.artists v1.0.18 installed 2025-08-10 15:05:10.169 T:771 info : CAddonMgr::FindAddons: metadata.local v1.0.1 installed 2025-08-10 15:05:10.169 T:771 info : CAddonMgr::FindAddons: metadata.themoviedb.org.python v3.1.1 installed 2025-08-10 15:05:10.170 T:771 info : CAddonMgr::FindAddons: metadata.tvshows.themoviedb.org.python v1.7.3 installed 2025-08-10 15:05:10.170 T:771 info : CAddonMgr::FindAddons: peripheral.joystick v21.1.22 installed 2025-08-10 15:05:10.170 T:771 info : CAddonMgr::FindAddons: plugin.video.3satmediathek v5.0.2 installed 2025-08-10 15:05:10.171 T:771 info : CAddonMgr::FindAddons: plugin.video.ardmediathek_de v6.0.0 installed 2025-08-10 15:05:10.171 T:771 info : CAddonMgr::FindAddons: plugin.video.artemediathek v2.0.1 installed 2025-08-10 15:05:10.171 T:771 info : CAddonMgr::FindAddons: plugin.video.zdf_de_lite v5.0.2 installed 2025-08-10 15:05:10.171 T:771 info : CAddonMgr::FindAddons: pvr.hts v21.2.6.1 installed 2025-08-10 15:05:10.172 T:771 info : CAddonMgr::FindAddons: repository.libreelec.tv v12.0.0 installed 2025-08-10 15:05:10.172 T:771 info : CAddonMgr::FindAddons: repository.xbmc.org v3.4.0 installed 2025-08-10 15:05:10.172 T:771 info : CAddonMgr::FindAddons: resource.images.weathericons.default v1.1.9 installed 2025-08-10 15:05:10.173 T:771 info : CAddonMgr::FindAddons: resource.language.de_de v11.0.96 installed 2025-08-10 15:05:10.173 T:771 info : CAddonMgr::FindAddons: resource.language.en_gb v2.0.2 installed 2025-08-10 15:05:10.173 T:771 info : CAddonMgr::FindAddons: resource.uisounds.kodi v1.0.1 installed 2025-08-10 15:05:10.174 T:771 info : CAddonMgr::FindAddons: screensaver.xbmc.builtin.black v1.0.34 installed 2025-08-10 15:05:10.174 T:771 info : CAddonMgr::FindAddons: screensaver.xbmc.builtin.dim v1.0.66 installed 2025-08-10 15:05:10.174 T:771 info : CAddonMgr::FindAddons: script.logviewer v2.1.7+matrix.1 installed 2025-08-10 15:05:10.175 T:771 info : CAddonMgr::FindAddons: script.module.certifi v2023.5.7 installed 2025-08-10 15:05:10.175 T:771 info : CAddonMgr::FindAddons: script.module.chardet v5.1.0 installed 2025-08-10 15:05:10.175 T:771 info : CAddonMgr::FindAddons: script.module.idna v3.10.0 installed 2025-08-10 15:05:10.175 T:771 info : CAddonMgr::FindAddons: script.module.libard v6.0.2 installed 2025-08-10 15:05:10.176 T:771 info : CAddonMgr::FindAddons: script.module.libarte v2.0.2 installed 2025-08-10 15:05:10.176 T:771 info : CAddonMgr::FindAddons: script.module.libmediathek4 v1.0.0 installed 2025-08-10 15:05:10.176 T:771 info : CAddonMgr::FindAddons: script.module.libzdf v5.0.2 installed 2025-08-10 15:05:10.177 T:771 info : CAddonMgr::FindAddons: script.module.pil v5.1.0 installed 2025-08-10 15:05:10.177 T:771 info : CAddonMgr::FindAddons: script.module.pycryptodome v3.4.3 installed 2025-08-10 15:05:10.177 T:771 info : CAddonMgr::FindAddons: script.module.requests v2.31.0 installed 2025-08-10 15:05:10.177 T:771 info : CAddonMgr::FindAddons: script.module.urllib3 v2.2.3 installed 2025-08-10 15:05:10.178 T:771 info : CAddonMgr::FindAddons: service.libreelec.settings v12.0 installed 2025-08-10 15:05:10.178 T:771 info : CAddonMgr::FindAddons: skin.estuary v4.0.0 installed 2025-08-10 15:05:10.178 T:771 info : CAddonMgr::FindAddons: tools.ffmpeg-tools v12.0.0.3 installed 2025-08-10 15:05:10.179 T:771 info : CAddonMgr::FindAddons: virtual.dvb-tools v12.0.0.1 installed 2025-08-10 15:05:10.179 T:771 info : CAddonMgr::FindAddons: webinterface.default v21.x-1.0.1 installed 2025-08-10 15:05:10.179 T:771 info : CAddonMgr::FindAddons: xbmc.addon v21.2.0 installed 2025-08-10 15:05:10.180 T:771 info : CAddonMgr::FindAddons: xbmc.core v0.1.0 installed 2025-08-10 15:05:10.180 T:771 info : CAddonMgr::FindAddons: xbmc.gui v5.17.0 installed 2025-08-10 15:05:10.180 T:771 info : CAddonMgr::FindAddons: xbmc.json v13.5.0 installed 2025-08-10 15:05:10.180 T:771 info : CAddonMgr::FindAddons: xbmc.metadata v2.1.0 installed 2025-08-10 15:05:10.181 T:771 info : CAddonMgr::FindAddons: xbmc.python v3.0.1 installed 2025-08-10 15:05:10.181 T:771 info : CAddonMgr::FindAddons: xbmc.webinterface v1.0.0 installed 2025-08-10 15:05:10.193 T:771 info : Selected Logind/UPower as PowerSyscall 2025-08-10 15:05:10.195 T:771 error : DBus error: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files 2025-08-10 15:05:10.195 T:771 info : LogindUPowerSyscall - UPower not found, battery information will not be available 2025-08-10 15:05:10.258 T:771 info : CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts... 2025-08-10 15:05:10.694 T:771 error : [xkb] couldn't find a Compose file for locale "C" (mapped to "C") 2025-08-10 15:05:10.695 T:771 warning : CLibInputKeyboard: Failed to compile localized compose table, composed key support will be disabled 2025-08-10 15:05:10.729 T:771 info : RetroPlayer[RENDER]: Registering renderer factory for DMA 2025-08-10 15:05:10.729 T:771 info : RetroPlayer[RENDER]: Registering renderer factory for OpenGLES 2025-08-10 15:05:10.781 T:771 info : CDRMUtils::FindConnector - using connector: HDMI-A-1 2025-08-10 15:05:10.853 T:771 info : Skipped 1 duplicate messages.. 2025-08-10 15:05:10.853 T:771 info : CDRMUtils::FindEncoder - using encoder: 31 2025-08-10 15:05:10.853 T:771 info : CDRMUtils::FindPlanes - using crtc: 95 2025-08-10 15:05:11.269 T:771 info : Found resolution 720x480 with 720x480 @ 60.000000 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 1920x1080 with 1920x1080i @ 60.000000 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 1920x1080 with 1920x1080i @ 59.940063 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 1920x1080 with 1920x1080i @ 50.000000 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 1280x720 with 1280x720 @ 60.000000 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 1280x720 with 1280x720 @ 59.940063 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 1280x720 with 1280x720 @ 50.000000 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 720x576 with 720x576 @ 50.000000 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 720x576 with 720x576i @ 50.000000 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 720x480 with 720x480 @ 59.940063 Hz 2025-08-10 15:05:11.269 T:771 info : Found resolution 720x480 with 720x480i @ 59.940063 Hz 2025-08-10 15:05:11.270 T:771 info : Found resolution 720x480 with 720x480i @ 60.000000 Hz 2025-08-10 15:05:11.270 T:771 info : Found resolution 640x480 with 640x480 @ 60.000000 Hz 2025-08-10 15:05:11.276 T:771 info : Skipped 1 duplicate messages.. 2025-08-10 15:05:11.276 T:771 info : EGL_VERSION = 1.4 2025-08-10 15:05:11.277 T:771 info : EGL_VENDOR = Mesa Project 2025-08-10 15:05:11.277 T:771 info : EGL_EXTENSIONS = EGL_ANDROID_blob_cache EGL_ANDROID_native_fence_sync EGL_EXT_buffer_age EGL_EXT_image_dma_buf_import EGL_EXT_image_dma_buf_import_modifiers EGL_KHR_cl_event2 EGL_KHR_config_attribs EGL_KHR_context_flush_control EGL_KHR_create_context EGL_KHR_create_context_no_error EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_colorspace EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_EXT_pixel_format_float EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_gl_interop EGL_MESA_image_dma_buf_export EGL_MESA_query_driver 2025-08-10 15:05:11.277 T:771 info : EGL_CLIENT_EXTENSIONS = EGL_EXT_client_extensions EGL_EXT_device_base EGL_EXT_device_enumeration EGL_EXT_device_query EGL_EXT_platform_base EGL_KHR_client_get_all_proc_addresses EGL_KHR_debug EGL_EXT_platform_device EGL_EXT_explicit_device EGL_MESA_platform_gbm EGL_KHR_platform_gbm EGL_MESA_platform_surfaceless 2025-08-10 15:05:11.428 T:771 info : CApplication::CreateGUI - using the gbm windowing system 2025-08-10 15:05:11.428 T:771 info : Checking resolution 21 2025-08-10 15:05:11.458 T:771 info : GL_VENDOR = Broadcom 2025-08-10 15:05:11.458 T:771 info : GL_RENDERER = VC4 V3D 2.1 2025-08-10 15:05:11.458 T:771 info : GL_VERSION = OpenGL ES 2.0 Mesa 24.0.9 2025-08-10 15:05:11.458 T:771 info : GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.0.16 2025-08-10 15:05:11.458 T:771 info : GL_EXTENSIONS = GL_EXT_blend_minmax GL_EXT_multi_draw_arrays GL_EXT_texture_compression_s3tc GL_EXT_texture_compression_dxt1 GL_EXT_texture_format_BGRA8888 GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_rgb8_rgba8 GL_OES_stencil8 GL_OES_texture_npot GL_OES_vertex_half_float GL_OES_EGL_image GL_OES_depth_texture GL_AMD_performance_monitor GL_OES_packed_depth_stencil GL_OES_get_program_binary GL_APPLE_texture_max_level GL_EXT_discard_framebuffer GL_EXT_read_format_bgra GL_NV_pack_subimage GL_NV_texture_barrier GL_EXT_frag_depth GL_NV_fbo_color_attachments GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_vertex_array_object GL_ANGLE_pack_reverse_row_order GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_EXT_unpack_subimage GL_NV_draw_buffers GL_NV_read_buffer GL_NV_read_depth GL_NV_read_depth_stencil GL_NV_read_stencil GL_APPLE_sync GL_EXT_draw_buffers GL_EXT_map_buffer_range GL_KHR_debug GL_KHR_texture_compression_astc_ldr GL_NV_generate_mipmap_sRGB GL_NV_pixel_buffer_object GL_OES_required_internalformat GL_OES_surfaceless_context GL_EXT_debug_label GL_EXT_separate_shader_objects GL_EXT_compressed_ETC1_RGB8_sub_texture GL_EXT_draw_elements_base_vertex GL_EXT_texture_border_clamp GL_KHR_context_flush_control GL_OES_draw_elements_base_vertex GL_OES_texture_border_clamp GL_KHR_no_error GL_KHR_texture_compression_astc_sliced_3d GL_EXT_texture_compression_s3tc_srgb GL_KHR_parallel_shader_compile GL_MESA_tile_raster_order GL_MESA_sampler_objects GL_MESA_bgra 2025-08-10 15:05:11.711 T:771 info : GLES: Maximum texture width: 2048 2025-08-10 15:05:13.103 T:771 info : load keymapping 2025-08-10 15:05:13.110 T:771 info : Loading special://xbmc/system/keymaps/appcommand.xml 2025-08-10 15:05:13.112 T:771 info : Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml 2025-08-10 15:05:13.116 T:771 info : Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml 2025-08-10 15:05:13.121 T:771 info : Loading special://xbmc/system/keymaps/customcontroller.SiriRemote.xml 2025-08-10 15:05:13.123 T:771 info : Loading special://xbmc/system/keymaps/gamepad.xml 2025-08-10 15:05:13.129 T:771 info : Loading special://xbmc/system/keymaps/joystick.xml 2025-08-10 15:05:13.134 T:771 info : Loading special://xbmc/system/keymaps/keyboard.xml 2025-08-10 15:05:13.145 T:771 info : Loading special://xbmc/system/keymaps/mouse.xml 2025-08-10 15:05:13.148 T:771 info : Loading special://xbmc/system/keymaps/remote.xml 2025-08-10 15:05:13.155 T:771 info : Loading special://xbmc/system/keymaps/touchscreen.xml 2025-08-10 15:05:13.161 T:771 info : GUI format 1280x720, Display 1280x720 @ 60.000000 Hz 2025-08-10 15:05:13.186 T:777 info : CAESinkALSA - Unable to open device "surround71" for playback 2025-08-10 15:05:13.188 T:777 info : CAESinkALSA - Unable to open device "surround51" for playback 2025-08-10 15:05:13.188 T:777 info : CAESinkALSA - Unable to open device "surround71" for playback 2025-08-10 15:05:13.190 T:777 info : CAESinkALSA - Unable to open device "surround40" for playback 2025-08-10 15:05:13.190 T:777 info : CAESinkALSA - Unable to open device "surround51" for playback 2025-08-10 15:05:13.190 T:777 info : CAESinkALSA - Unable to open device "surround71" for playback 2025-08-10 15:05:13.242 T:777 warning : Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail 2025-08-10 15:05:13.244 T:777 info : Found 2 Lists of Devices 2025-08-10 15:05:13.244 T:777 info : Enumerated ALSA devices: 2025-08-10 15:05:13.244 T:777 info : Device 1 2025-08-10 15:05:13.245 T:777 info : m_deviceName : @ 2025-08-10 15:05:13.245 T:777 info : m_displayName : Default (vc4-hdmi MAI PCM i2s-hifi-0) () 2025-08-10 15:05:13.245 T:777 info : m_displayNameExtra: 2025-08-10 15:05:13.245 T:777 info : m_deviceType : AE_DEVTYPE_PCM 2025-08-10 15:05:13.245 T:777 info : m_channels : FL, FR 2025-08-10 15:05:13.245 T:777 info : m_sampleRates : 32000,44100,48000 2025-08-10 15:05:13.245 T:777 info : m_dataFormats : AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE 2025-08-10 15:05:13.245 T:777 info : m_streamTypes : No passthrough capabilities 2025-08-10 15:05:13.245 T:777 info : Device 2 2025-08-10 15:05:13.245 T:777 info : m_deviceName : sysdefault 2025-08-10 15:05:13.245 T:777 info : m_displayName : vc4-hdmi () 2025-08-10 15:05:13.245 T:777 info : m_displayNameExtra: MAI PCM i2s-hifi-0 2025-08-10 15:05:13.245 T:777 info : m_deviceType : AE_DEVTYPE_PCM 2025-08-10 15:05:13.245 T:777 info : m_channels : FL, FR 2025-08-10 15:05:13.245 T:777 info : m_sampleRates : 32000,44100,48000 2025-08-10 15:05:13.245 T:777 info : m_dataFormats : AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE 2025-08-10 15:05:13.245 T:777 info : m_streamTypes : No passthrough capabilities 2025-08-10 15:05:13.245 T:777 info : Device 3 2025-08-10 15:05:13.245 T:777 info : m_deviceName : sysdefault:CARD=vc4hdmi 2025-08-10 15:05:13.245 T:777 info : m_displayName : vc4-hdmi (vc4hdmi) 2025-08-10 15:05:13.245 T:777 info : m_displayNameExtra: MAI PCM i2s-hifi-0 2025-08-10 15:05:13.245 T:777 info : m_deviceType : AE_DEVTYPE_PCM 2025-08-10 15:05:13.245 T:777 info : m_channels : FL, FR 2025-08-10 15:05:13.245 T:777 info : m_sampleRates : 32000,44100,48000 2025-08-10 15:05:13.245 T:777 info : m_dataFormats : AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE 2025-08-10 15:05:13.246 T:777 info : m_streamTypes : No passthrough capabilities 2025-08-10 15:05:13.246 T:777 info : Device 4 2025-08-10 15:05:13.246 T:777 info : m_deviceName : hdmi:CARD=vc4hdmi,DEV=0 2025-08-10 15:05:13.246 T:777 info : m_displayName : vc4-hdmi (vc4hdmi) 2025-08-10 15:05:13.246 T:777 info : m_displayNameExtra: GSM 42LE2R-ZJ on HDMI 2025-08-10 15:05:13.246 T:777 info : m_deviceType : AE_DEVTYPE_HDMI 2025-08-10 15:05:13.246 T:777 info : m_channels : FL, FR 2025-08-10 15:05:13.246 T:777 info : m_sampleRates : 32000,44100,48000 2025-08-10 15:05:13.246 T:777 info : m_dataFormats : AE_FMT_RAW,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_U8,AE_FMT_RAW 2025-08-10 15:05:13.246 T:777 info : m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_MA,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD 2025-08-10 15:05:13.246 T:777 info : Enumerated PULSE devices: 2025-08-10 15:05:13.246 T:777 info : Device 1 2025-08-10 15:05:13.246 T:777 info : m_deviceName : Default 2025-08-10 15:05:13.246 T:777 info : m_displayName : Default 2025-08-10 15:05:13.246 T:777 info : m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 2025-08-10 15:05:13.246 T:777 info : m_deviceType : AE_DEVTYPE_PCM 2025-08-10 15:05:13.246 T:777 info : m_channels : FL, FR 2025-08-10 15:05:13.246 T:777 info : m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 2025-08-10 15:05:13.246 T:777 info : m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 2025-08-10 15:05:13.246 T:777 info : m_streamTypes : No passthrough capabilities 2025-08-10 15:05:13.246 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:05:13.247 T:778 info : CActiveAESink::OpenSink - initialize sink 2025-08-10 15:05:13.247 T:778 info : CAESinkALSA::Initialize - Attempting to open device "sysdefault" 2025-08-10 15:05:13.257 T:778 info : CAESinkALSA::Initialize - Opened device "sysdefault" 2025-08-10 15:05:13.258 T:778 info : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2025-08-10 15:05:13.258 T:778 info : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2025-08-10 15:05:13.270 T:771 info : CLangInfo: loading resource.language.de_de language information... 2025-08-10 15:05:13.274 T:771 info : global locale set to C 2025-08-10 15:05:13.274 T:771 info : CLangInfo: loading resource.language.de_de language strings... 2025-08-10 15:05:13.666 T:781 info : Running database version Addons33 2025-08-10 15:05:13.671 T:781 info : Running database version ViewModes6 2025-08-10 15:05:13.684 T:781 info : Running database version Textures13 2025-08-10 15:05:13.701 T:781 info : Running database version MyMusic83 2025-08-10 15:05:13.728 T:781 info : Running database version MyVideos131 2025-08-10 15:05:13.737 T:781 info : Running database version TV46 2025-08-10 15:05:13.906 T:781 info : Running database version Epg16 2025-08-10 15:05:14.052 T:771 info : Unloaded skin 2025-08-10 15:05:14.056 T:771 info : load skin from: /usr/share/kodi/addons/skin.estuary/ (version: 4.0.0) 2025-08-10 15:05:14.056 T:771 info : load fonts for skin... 2025-08-10 15:05:14.105 T:771 info : Loading skin includes from /usr/share/kodi/addons/skin.estuary/xml/Includes.xml 2025-08-10 15:05:14.407 T:771 info : LoadFontsFromFile: Loading with name 'Default' from '/usr/share/kodi/addons/skin.estuary/xml/Font.xml' 2025-08-10 15:05:14.599 T:771 info : LoadTimers: Trying to load skin timers from /usr/share/kodi/addons/skin.estuary/xml/Timers.xml 2025-08-10 15:05:14.618 T:771 info : load new skin... 2025-08-10 15:05:14.618 T:771 info : Loading custom window XMLs from skin path /usr/share/kodi/addons/skin.estuary/xml 2025-08-10 15:05:14.675 T:771 info : initialize new skin... 2025-08-10 15:05:14.675 T:771 info : Loading skin file: Custom_1109_TopBarOverlay.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.775 T:771 info : Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.779 T:771 info : Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.784 T:771 info : Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.832 T:771 info : Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.839 T:771 info : Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.843 T:771 info : Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.846 T:771 info : Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:05:14.851 T:771 info : Loading resource://resource.uisounds.kodi/sounds.xml 2025-08-10 15:05:15.001 T:771 info : skin loaded... 2025-08-10 15:05:15.002 T:771 info : Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME 2025-08-10 15:05:15.020 T:771 info : Loading skin file: MyPVRChannels.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:05:15.161 T:771 warning : JSONRPC: Could not parse type "Setting.Details.SettingList" 2025-08-10 15:05:15.161 T:771 info : JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting") 2025-08-10 15:05:15.162 T:771 info : JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting 2025-08-10 15:05:15.232 T:771 info : JSONRPC v13.5.0: Successfully initialized 2025-08-10 15:05:15.232 T:771 info : [Media Detection] starting service for optical media detection 2025-08-10 15:05:15.355 T:771 info : AddOnLog: peripheral.joystick: Enabling joystick interface "linux" 2025-08-10 15:05:15.360 T:771 info : Register - new keyboard device registered on application->keyboard: Tastatur (0000:0000) 2025-08-10 15:05:15.361 T:771 info : Register - new mouse device registered on application->mouse: Maus (0000:0000) 2025-08-10 15:05:15.363 T:771 info : Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 2025-08-10 15:05:15.385 T:771 info : Loaded playercorefactory configuration 2025-08-10 15:05:15.386 T:771 info : Loading player core factory settings from special://masterprofile/playercorefactory.xml. 2025-08-10 15:05:15.386 T:771 info : special://masterprofile/playercorefactory.xml does not exist. Skipping. 2025-08-10 15:05:15.386 T:771 info : removing tempfiles 2025-08-10 15:05:15.393 T:781 info : UpdateClients: Creating PVR client: addonId=pvr.hts, instanceId=1, clientId=3 2025-08-10 15:05:15.431 T:771 info : initialize done 2025-08-10 15:05:15.432 T:771 info : Running the application... 2025-08-10 15:05:15.439 T:781 info : AddOnLog: pvr.hts: starting PVR client 2025-08-10 15:05:15.454 T:771 info : starting zeroconf publishing 2025-08-10 15:05:15.455 T:771 info : starting upnp client 2025-08-10 15:05:15.466 T:804 info : ES: Starting UDP Event server on port 9777 2025-08-10 15:05:15.466 T:799 info : AddOnLog: pvr.hts: Received permissions: 2025-08-10 15:05:15.466 T:799 info : AddOnLog: pvr.hts: administrator : 1 2025-08-10 15:05:15.466 T:804 info : UDP: Listening on port 9777 (ipv6 : true) 2025-08-10 15:05:15.466 T:799 info : AddOnLog: pvr.hts: HTSP streaming : 1 2025-08-10 15:05:15.466 T:799 info : AddOnLog: pvr.hts: HTSP DVR : 1 2025-08-10 15:05:15.466 T:799 info : AddOnLog: pvr.hts: Failed/aborted DVR : 0 2025-08-10 15:05:15.466 T:799 info : AddOnLog: pvr.hts: anonymous HTSP only : 0 2025-08-10 15:05:15.467 T:799 info : AddOnLog: pvr.hts: global connection limit : 0 2025-08-10 15:05:15.467 T:799 info : AddOnLog: pvr.hts: DVR connection limit : 0 2025-08-10 15:05:15.467 T:799 info : AddOnLog: pvr.hts: streaming connection limit : 0 2025-08-10 15:05:15.470 T:799 info : AddOnLog: pvr.hts: Available streaming profiles: 2025-08-10 15:05:15.472 T:799 info : AddOnLog: pvr.hts: Name: htsp, Comment: HTSP Default Stream Settings 2025-08-10 15:05:15.472 T:799 info : AddOnLog: pvr.hts: Name: webtv-vp8-vorbis-webm, Comment: WEBTV profile VP8/Vorbis/WEBM 2025-08-10 15:05:15.473 T:799 info : AddOnLog: pvr.hts: Name: webtv-h264-aac-mpegts, Comment: WEBTV profile H264/AAC/MPEG-TS 2025-08-10 15:05:15.475 T:799 info : AddOnLog: pvr.hts: Name: webtv-h264-vorbis-mp4, Comment: WEBTV profile H264/Vorbis/MP4 2025-08-10 15:05:15.475 T:799 info : AddOnLog: pvr.hts: Name: webtv-h264-aac-matroska, Comment: WEBTV profile H264/AAC/Matroska 2025-08-10 15:05:15.477 T:799 info : AddOnLog: pvr.hts: Request async EPG (3 days) 2025-08-10 15:05:15.479 T:799 info : AddOnLog: pvr.hts: Async updates requested 2025-08-10 15:05:15.668 T:771 error : JSONRPC Server: Failed to connect to sdpd 2025-08-10 15:05:15.669 T:771 info : JSONRPC Server: Successfully initialized 2025-08-10 15:05:15.675 T:771 info : Started 2025-08-10 15:05:15.692 T:781 info : PVR Manager: Starting 2025-08-10 15:05:15.767 T:785 info : Register - new cec device registered on cec->Linux: CEC Adapter (0000:0000) 2025-08-10 15:05:17.185 T:821 info : PVR Manager: Started 2025-08-10 15:05:17.722 T:794 info : AddOnLog: pvr.hts: Async updates initialised 2025-08-10 15:05:20.140 T:816 info : JSONRPC Server: New connection added 2025-08-10 15:05:22.398 T:793 info : SETTINGS: # ## LibreELEC Addon ## 12.0 2025-08-10 15:05:22.837 T:793 info : SETTINGS: set_keyboard_layout # Settings keyboard layout: de 2025-08-10 15:05:22.842 T:793 info : SETTINGS: set_keyboard_layout # Found keyboard layout: /usr/lib/keymaps/de/de.bmap 2025-08-10 15:05:22.843 T:793 info : SETTINGS: set_keyboard_layout # Executing loadkmap < /usr/lib/keymaps/de/de.bmap 2025-08-10 15:05:23.006 T:771 info : Loading controller layout: /usr/share/kodi/addons/game.controller.keyboard/resources/layout.xml 2025-08-10 15:05:23.386 T:771 info : Loading skin file: Home.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:05:24.147 T:820 error : CUPnPDirectory::GetResource - unable to find object 64/folder.jpg 2025-08-10 15:05:27.273 T:793 info : SETTINGS: __init__ # updateThread Started 2025-08-10 15:05:27.281 T:793 info : SETTINGS: set_auto_update # auto 2025-08-10 15:05:27.606 T:867 info : SETTINGS: run # Waiting 2025-08-10 15:05:27.917 T:771 info : Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:05:47.302 T:821 info : PVR Manager: Starting cleanup of cached images. 2025-08-10 15:05:47.927 T:821 info : PVR Manager: Cleaned up 0 cached images. 2025-08-10 15:05:52.093 T:771 info : Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:05:53.627 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:05:53.641 T:894 info : Creating InputStream 2025-08-10 15:05:53.664 T:894 info : Creating Demuxer 2025-08-10 15:05:53.781 T:894 info : Opening stream: 0 source: 256 2025-08-10 15:05:53.782 T:894 info : Creating video codec with codec id: 167 2025-08-10 15:05:53.782 T:894 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:05:53.786 T:894 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:05:53.786 T:894 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:05:53.791 T:894 info : Creating video thread 2025-08-10 15:05:53.791 T:901 info : running thread: video_thread 2025-08-10 15:05:53.792 T:894 info : Opening stream: 1 source: 256 2025-08-10 15:05:53.792 T:894 info : Finding audio codec for: 86076 2025-08-10 15:05:53.795 T:894 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:05:53.795 T:894 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:05:53.795 T:894 info : Creating audio thread 2025-08-10 15:05:53.796 T:902 info : running thread: CVideoPlayerAudio::Process() 2025-08-10 15:05:53.949 T:902 info : Creating audio stream (codec id: 86076, channels: 2, sample rate: 48000, no pass-through) 2025-08-10 15:05:53.953 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:05:56.984 T:771 info : Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:06:04.719 T:901 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:06:04.816 T:771 info : Skipped 1 duplicate messages.. 2025-08-10 15:06:04.816 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:06:04.816 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:06:04.816 T:894 info : CVideoPlayer::OnExit() 2025-08-10 15:06:04.816 T:894 info : Closing stream player 1 2025-08-10 15:06:04.816 T:894 info : Waiting for audio thread to exit 2025-08-10 15:06:04.823 T:901 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:06:04.826 T:902 info : thread end: CVideoPlayerAudio::OnExit() 2025-08-10 15:06:04.827 T:894 info : Closing audio device 2025-08-10 15:06:04.879 T:894 info : Deleting audio codec 2025-08-10 15:06:04.879 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:06:04.881 T:894 info : Closing stream player 2 2025-08-10 15:06:04.881 T:894 info : waiting for video thread to exit 2025-08-10 15:06:04.886 T:901 info : thread end: video_thread 2025-08-10 15:06:04.897 T:894 info : deleting video codec 2025-08-10 15:06:04.994 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:06:04.994 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:06:04.994 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:06:04.994 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:06:12.806 T:771 info : Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:06:16.357 T:771 info : Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:06:20.910 T:771 info : Loading skin file: DialogSelect.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:07:04.843 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:07:04.892 T:923 info : Creating InputStream 2025-08-10 15:07:04.915 T:923 info : Creating Demuxer 2025-08-10 15:07:05.018 T:923 info : Opening stream: 0 source: 256 2025-08-10 15:07:05.019 T:923 info : Creating video codec with codec id: 167 2025-08-10 15:07:05.019 T:923 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:07:05.022 T:923 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:07:05.022 T:923 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:07:05.026 T:923 info : Creating video thread 2025-08-10 15:07:05.027 T:930 info : running thread: video_thread 2025-08-10 15:07:05.028 T:923 info : Opening stream: 1 source: 256 2025-08-10 15:07:05.028 T:923 info : Finding audio codec for: 86076 2025-08-10 15:07:05.030 T:923 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:07:05.030 T:923 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:07:05.030 T:923 info : Creating audio thread 2025-08-10 15:07:05.031 T:931 info : running thread: CVideoPlayerAudio::Process() 2025-08-10 15:07:05.183 T:931 info : Creating audio stream (codec id: 86076, channels: 2, sample rate: 48000, no pass-through) 2025-08-10 15:07:05.185 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:07:14.643 T:930 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:07:14.704 T:771 info : Skipped 1 duplicate messages.. 2025-08-10 15:07:14.703 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:07:14.704 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:07:14.712 T:923 info : CVideoPlayer::OnExit() 2025-08-10 15:07:14.712 T:923 info : Closing stream player 1 2025-08-10 15:07:14.713 T:923 info : Waiting for audio thread to exit 2025-08-10 15:07:14.730 T:931 info : thread end: CVideoPlayerAudio::OnExit() 2025-08-10 15:07:14.731 T:923 info : Closing audio device 2025-08-10 15:07:14.746 T:930 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:07:14.785 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:07:14.785 T:923 info : Deleting audio codec 2025-08-10 15:07:14.786 T:923 info : Closing stream player 2 2025-08-10 15:07:14.786 T:923 info : waiting for video thread to exit 2025-08-10 15:07:14.787 T:930 info : thread end: video_thread 2025-08-10 15:07:14.794 T:923 info : deleting video codec 2025-08-10 15:07:14.863 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:07:14.863 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:07:14.863 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:07:14.863 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:07:37.607 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:07:37.618 T:953 info : Creating InputStream 2025-08-10 15:07:37.653 T:953 info : Creating Demuxer 2025-08-10 15:07:37.751 T:953 info : Opening stream: 0 source: 256 2025-08-10 15:07:37.751 T:953 info : Creating video codec with codec id: 167 2025-08-10 15:07:37.751 T:953 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:07:37.754 T:953 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:07:37.754 T:953 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:07:37.757 T:953 info : Creating video thread 2025-08-10 15:07:37.757 T:959 info : running thread: video_thread 2025-08-10 15:07:37.758 T:953 info : Opening stream: 1 source: 256 2025-08-10 15:07:37.758 T:953 info : Finding audio codec for: 86076 2025-08-10 15:07:37.760 T:953 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:07:37.760 T:953 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:07:37.760 T:953 info : Creating audio thread 2025-08-10 15:07:37.761 T:960 info : running thread: CVideoPlayerAudio::Process() 2025-08-10 15:07:37.935 T:960 info : Creating audio stream (codec id: 86076, channels: 2, sample rate: 48000, no pass-through) 2025-08-10 15:07:37.938 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:07:48.353 T:959 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:07:48.461 T:771 info : Skipped 2 duplicate messages.. 2025-08-10 15:07:48.461 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:07:48.461 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:07:48.468 T:953 info : CVideoPlayer::OnExit() 2025-08-10 15:07:48.468 T:953 info : Closing stream player 1 2025-08-10 15:07:48.468 T:953 info : Waiting for audio thread to exit 2025-08-10 15:07:48.482 T:960 info : thread end: CVideoPlayerAudio::OnExit() 2025-08-10 15:07:48.483 T:953 info : Closing audio device 2025-08-10 15:07:48.509 T:959 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:07:48.535 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:07:48.535 T:953 info : Deleting audio codec 2025-08-10 15:07:48.536 T:953 info : Closing stream player 2 2025-08-10 15:07:48.536 T:953 info : waiting for video thread to exit 2025-08-10 15:07:48.551 T:959 info : thread end: video_thread 2025-08-10 15:07:48.560 T:953 info : deleting video codec 2025-08-10 15:07:48.658 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:07:48.659 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:07:48.659 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:07:48.659 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:08:12.332 T:771 info : Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY 2025-08-10 15:08:37.465 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:08:37.478 T:980 info : Creating InputStream 2025-08-10 15:08:37.504 T:980 info : Creating Demuxer 2025-08-10 15:08:37.619 T:980 info : Opening stream: 0 source: 256 2025-08-10 15:08:37.620 T:980 info : Creating video codec with codec id: 167 2025-08-10 15:08:37.620 T:980 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:08:37.622 T:980 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:08:37.623 T:980 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:08:37.626 T:980 info : Creating video thread 2025-08-10 15:08:37.627 T:989 info : running thread: video_thread 2025-08-10 15:08:37.628 T:980 info : Opening stream: 1 source: 256 2025-08-10 15:08:37.628 T:980 info : Finding audio codec for: 86076 2025-08-10 15:08:37.631 T:980 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:08:37.631 T:980 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:08:37.631 T:980 info : Creating audio thread 2025-08-10 15:08:37.631 T:990 info : running thread: CVideoPlayerAudio::Process() 2025-08-10 15:08:37.973 T:990 info : Creating audio stream (codec id: 86076, channels: 2, sample rate: 48000, no pass-through) 2025-08-10 15:08:37.981 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:08:49.530 T:989 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:08:49.597 T:771 info : Skipped 1 duplicate messages.. 2025-08-10 15:08:49.597 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:08:49.598 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:08:49.603 T:980 info : CVideoPlayer::OnExit() 2025-08-10 15:08:49.603 T:980 info : Closing stream player 1 2025-08-10 15:08:49.603 T:980 info : Waiting for audio thread to exit 2025-08-10 15:08:49.633 T:989 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:08:49.635 T:990 info : thread end: CVideoPlayerAudio::OnExit() 2025-08-10 15:08:49.636 T:980 info : Closing audio device 2025-08-10 15:08:49.687 T:980 info : Deleting audio codec 2025-08-10 15:08:49.688 T:980 info : Closing stream player 2 2025-08-10 15:08:49.688 T:980 info : waiting for video thread to exit 2025-08-10 15:08:49.688 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:08:49.695 T:989 info : thread end: video_thread 2025-08-10 15:08:49.709 T:980 info : deleting video codec 2025-08-10 15:08:49.816 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:08:49.817 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:08:49.817 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:08:49.817 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:09:40.298 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:09:40.311 T:1012 info : Creating InputStream 2025-08-10 15:09:40.335 T:1012 info : Creating Demuxer 2025-08-10 15:09:40.448 T:1012 info : Opening stream: 0 source: 256 2025-08-10 15:09:40.449 T:1012 info : Creating video codec with codec id: 167 2025-08-10 15:09:40.449 T:1012 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:09:40.452 T:1012 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:09:40.452 T:1012 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:09:40.456 T:1012 info : Creating video thread 2025-08-10 15:09:40.457 T:1021 info : running thread: video_thread 2025-08-10 15:09:40.457 T:1012 info : Opening stream: 1 source: 256 2025-08-10 15:09:40.457 T:1012 info : Finding audio codec for: 86076 2025-08-10 15:09:40.459 T:1012 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:09:40.460 T:1012 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:09:40.460 T:1012 info : Creating audio thread 2025-08-10 15:09:40.460 T:1022 info : running thread: CVideoPlayerAudio::Process() 2025-08-10 15:09:40.683 T:1022 info : Creating audio stream (codec id: 86076, channels: 2, sample rate: 48000, no pass-through) 2025-08-10 15:09:40.697 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:09:50.461 T:1021 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:09:50.518 T:771 info : Skipped 1 duplicate messages.. 2025-08-10 15:09:50.518 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:09:50.518 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:09:50.520 T:1012 info : CVideoPlayer::OnExit() 2025-08-10 15:09:50.520 T:1012 info : Closing stream player 1 2025-08-10 15:09:50.520 T:1012 info : Waiting for audio thread to exit 2025-08-10 15:09:50.537 T:1022 info : thread end: CVideoPlayerAudio::OnExit() 2025-08-10 15:09:50.538 T:1012 info : Closing audio device 2025-08-10 15:09:50.564 T:1021 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:09:50.590 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:09:50.591 T:1012 info : Deleting audio codec 2025-08-10 15:09:50.591 T:1012 info : Closing stream player 2 2025-08-10 15:09:50.592 T:1012 info : waiting for video thread to exit 2025-08-10 15:09:50.608 T:1021 info : thread end: video_thread 2025-08-10 15:09:50.624 T:1012 info : deleting video codec 2025-08-10 15:09:50.770 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:09:50.770 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:09:50.770 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:09:50.770 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:11:31.059 T:804 info : ES: Client from ::ffff:192.168.178.103 timed out 2025-08-10 15:12:17.000 T:778 info : CActiveAESink::OpenSink - initialize sink 2025-08-10 15:12:17.001 T:778 info : CAESinkALSA::Initialize - Attempting to open device "sysdefault" 2025-08-10 15:12:17.017 T:778 info : CAESinkALSA::Initialize - Opened device "sysdefault" 2025-08-10 15:12:17.019 T:778 info : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2025-08-10 15:12:17.020 T:778 info : CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3 2025-08-10 15:12:32.702 T:867 info : SETTINGS: run # Received openConfigurationWindow 2025-08-10 15:12:32.706 T:771 info : Loading skin file: /usr/share/kodi/addons/service.libreelec.settings/resources/skins/Default/1080i/service-LibreELEC-Settings-mainWindow.xml, load type: LOAD_ON_GUI_INIT 2025-08-10 15:12:32.819 T:771 error : Control 1000 in window 13001 has been asked to focus, but it can't 2025-08-10 15:12:32.820 T:867 info : SETTINGS: run # Waiting 2025-08-10 15:12:54.123 T:771 info : Log level changed to "trace" 2025-08-10 15:12:54.124 T:771 info : Enabled debug logging due to GUI setting. Level 2. 2025-08-10 15:12:54.126 T:771 debug : ------ Window Init () ------ 2025-08-10 15:12:54.342 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:04.378 T:851 info : Skipped 16 duplicate messages.. 2025-08-10 15:13:04.378 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:14.419 T:851 info : Skipped 18 duplicate messages.. 2025-08-10 15:13:14.419 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:24.470 T:851 info : Skipped 16 duplicate messages.. 2025-08-10 15:13:24.470 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:28.292 T:804 info : Skipped 10 duplicate messages.. 2025-08-10 15:13:28.292 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:13:28.302 T:771 debug : HandleKey: menu (0xd8) pressed, window 10016, action is PreviousMenu 2025-08-10 15:13:28.335 T:771 debug : CGUIWindowManager::PreviousWindow: Deactivate 2025-08-10 15:13:28.611 T:771 debug : ------ Window Deinit (SettingsCategory.xml) ------ 2025-08-10 15:13:28.612 T:771 debug : FreeVisualisation() done 2025-08-10 15:13:28.639 T:771 debug : CGUIWindowManager::PreviousWindow: Activate new 2025-08-10 15:13:28.639 T:771 debug : ------ Window Init (Settings.xml) ------ 2025-08-10 15:13:28.917 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:13:28.939 T:771 debug : HandleKey: menu (0xd8) pressed, window 10004, action is PreviousMenu 2025-08-10 15:13:28.939 T:771 debug : CGUIWindowManager::PreviousWindow: Deactivate 2025-08-10 15:13:29.210 T:771 debug : ------ Window Deinit (Settings.xml) ------ 2025-08-10 15:13:29.211 T:771 debug : FreeVisualisation() done 2025-08-10 15:13:29.222 T:771 debug : CGUIWindowManager::PreviousWindow: Activate new 2025-08-10 15:13:29.222 T:771 debug : ------ Window Init (Home.xml) ------ 2025-08-10 15:13:29.473 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:30.797 T:804 info : Skipped 1 duplicate messages.. 2025-08-10 15:13:30.797 T:804 debug : EventClient: button name "down" map "R1" pressed 2025-08-10 15:13:30.806 T:771 debug : HandleKey: 167 (0xa7, obc88) pressed, window 10000, action is Down 2025-08-10 15:13:30.867 T:1052 debug : Thread JobWorker 1661981248 terminating (autodelete) 2025-08-10 15:13:31.024 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:31.623 T:804 info : Skipped 1 duplicate messages.. 2025-08-10 15:13:31.623 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:13:31.628 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10000, action is Select 2025-08-10 15:13:31.628 T:771 debug : Activating window ID: 10025 2025-08-10 15:13:31.943 T:771 debug : ------ Window Deinit (Home.xml) ------ 2025-08-10 15:13:31.944 T:771 debug : FreeVisualisation() done 2025-08-10 15:13:31.969 T:771 debug : ------ Window Init (MyVideoNav.xml) ------ 2025-08-10 15:13:31.973 T:771 debug : CGUIMediaWindow::GetDirectory (sources://video/) 2025-08-10 15:13:31.973 T:771 debug : ParentPath = [sources://video/] 2025-08-10 15:13:32.169 T:1054 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:13:32.169 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:13:32.190 T:1054 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:13:32.571 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:32.664 T:804 info : Skipped 1 duplicate messages.. 2025-08-10 15:13:32.664 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:13:32.672 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10025, action is Select 2025-08-10 15:13:32.674 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64/) 2025-08-10 15:13:32.674 T:771 debug : ParentPath = [sources://video/] 2025-08-10 15:13:32.675 T:1055 debug : Thread waiting start, auto delete: false 2025-08-10 15:13:32.677 T:1055 debug : Thread waiting 1661981248 terminating 2025-08-10 15:13:32.684 T:1056 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:13:32.684 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:13:32.706 T:1056 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:13:33.292 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:13:33.305 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10025, action is Select 2025-08-10 15:13:33.307 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241/) 2025-08-10 15:13:33.307 T:771 debug : ParentPath = [upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64/] 2025-08-10 15:13:33.308 T:1057 debug : Thread waiting start, auto delete: false 2025-08-10 15:13:33.353 T:1057 debug : Thread waiting 1661981248 terminating 2025-08-10 15:13:33.497 T:1058 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:13:33.498 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:13:33.569 T:1058 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:13:34.117 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:34.118 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:13:34.126 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10025, action is Select 2025-08-10 15:13:34.126 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:34.127 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179/) 2025-08-10 15:13:34.127 T:771 debug : ParentPath = [upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241/] 2025-08-10 15:13:34.128 T:1059 debug : Thread waiting start, auto delete: false 2025-08-10 15:13:34.131 T:1059 debug : Thread waiting 1661981248 terminating 2025-08-10 15:13:34.235 T:1060 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:13:34.236 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:13:34.265 T:1060 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:13:34.507 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:34.979 T:804 info : Skipped 2 duplicate messages.. 2025-08-10 15:13:34.979 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:13:34.989 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10025, action is Select 2025-08-10 15:13:34.990 T:771 debug : ------ Window Init (DialogContextMenu.xml) ------ 2025-08-10 15:13:35.660 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:36.931 T:804 info : Skipped 3 duplicate messages.. 2025-08-10 15:13:36.931 T:804 debug : EventClient: button name "down" map "R1" pressed 2025-08-10 15:13:36.939 T:771 debug : HandleKey: 167 (0xa7, obc88) pressed, window 10106, action is Down 2025-08-10 15:13:37.208 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:37.270 T:804 info : Skipped 1 duplicate messages.. 2025-08-10 15:13:37.270 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:13:37.272 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10106, action is Select 2025-08-10 15:13:37.550 T:771 debug : ------ Window Deinit (DialogContextMenu.xml) ------ 2025-08-10 15:13:37.573 T:771 debug : OnPlayMedia upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:13:37.578 T:771 debug : resource protocol info 'http-get:*:video/x-matroska:DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000' 2025-08-10 15:13:37.603 T:771 debug : CPlayerCoreFactory::GetPlayers(http://192.168.178.144:8200/MediaItems/2664.mkv) 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: matches rule: streams 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: mp2 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2025-08-10 15:13:37.604 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2025-08-10 15:13:37.604 T:771 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2025-08-10 15:13:37.604 T:771 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2025-08-10 15:13:37.605 T:771 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2025-08-10 15:13:37.605 T:771 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2025-08-10 15:13:37.605 T:771 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2025-08-10 15:13:37.613 T:771 debug : Radio UECP (RDS) Processor - new CDVDRadioRDSData 2025-08-10 15:13:37.613 T:771 debug : Audio ID3 tag processor - new CVideoPlayerAudioID3 2025-08-10 15:13:37.613 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:13:37.614 T:1061 debug : Thread VideoPlayer start, auto delete: false 2025-08-10 15:13:37.614 T:771 debug : OnPlayBackStarted: CApplication::OnPlayBackStarted 2025-08-10 15:13:37.614 T:1061 debug : easy_acquire - Created session to http://192.168.178.144 2025-08-10 15:13:37.614 T:1062 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:13:37.615 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:13:37.640 T:1062 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:13:37.656 T:1063 debug : Thread JobWorker start, auto delete: true 2025-08-10 15:13:37.656 T:1063 debug : [threads] name: 'JobWorker' priority: '1' 2025-08-10 15:13:37.656 T:771 debug : CVideoGUIInfo::InitCurrentItem(upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/) 2025-08-10 15:13:37.688 T:771 debug : CPlayerGUIInfo::InitCurrentItem(upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/) 2025-08-10 15:13:37.775 T:1061 debug : CCurlFile::GetMimeType - -> video/x-matroska 2025-08-10 15:13:37.776 T:1061 info : Creating InputStream 2025-08-10 15:13:37.776 T:1061 debug : CurlFile::Open - 2025-08-10 15:13:37.787 T:1061 debug : CFileCache::Open - opening 2025-08-10 15:13:37.787 T:1061 debug : CurlFile::Open - 2025-08-10 15:13:37.788 T:771 debug : ------ Window Init (DialogBusy.xml) ------ 2025-08-10 15:13:37.792 T:1063 debug : Loading settings for upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:13:37.811 T:1061 debug : CFileCache::Open - source chunk size is 0, setting cache chunk size to 1048576 2025-08-10 15:13:37.811 T:1061 debug : CFileCache::Open - using double memory cache each sized 10485760 bytes 2025-08-10 15:13:37.812 T:1064 debug : Thread FileCache start, auto delete: false 2025-08-10 15:13:37.813 T:1061 info : Creating Demuxer 2025-08-10 15:13:37.817 T:1061 debug : Open - probing detected format [matroska,webm] 2025-08-10 15:13:37.818 T:1061 debug : Open - avformat_find_stream_info starting 2025-08-10 15:13:37.920 T:1061 debug : Open - av_find_stream_info finished 2025-08-10 15:13:37.921 T:1061 info : ffmpeg[0x26d23d0]: Input #0, matroska,webm, from 'http://192.168.178.144:8200/MediaItems/2664.mkv': 2025-08-10 15:13:37.921 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:13:37.921 T:1061 info : ffmpeg[0x26d23d0]: ENCODER : Lavf60.16.100 2025-08-10 15:13:37.921 T:1061 info : ffmpeg[0x26d23d0]: Duration: 01:26:48.92, start: 0.000000, bitrate: 5276 kb/s 2025-08-10 15:13:37.921 T:1061 info : ffmpeg[0x26d23d0]: Stream #0:0(eng): Video: vp9 (Profile 0), yuv420p(tv), 1920x1080, SAR 1:1 DAR 16:9, 50 fps, 50 tbr, 1k tbn (default) 2025-08-10 15:13:37.921 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:13:37.921 T:1061 info : ffmpeg[0x26d23d0]: DURATION : 01:26:48.920000000 2025-08-10 15:13:37.922 T:1061 info : ffmpeg[0x26d23d0]: Stream #0:1(deu): Audio: opus, 48000 Hz, stereo, fltp (default) 2025-08-10 15:13:37.922 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:13:37.922 T:1061 info : ffmpeg[0x26d23d0]: DURATION : 01:26:48.921000000 2025-08-10 15:13:37.922 T:1061 debug : CDVDDemuxFFmpeg::AddStream ID: 0 2025-08-10 15:13:37.922 T:1061 debug : CDVDDemuxFFmpeg::AddStream ID: 1 2025-08-10 15:13:37.922 T:1061 debug : CFileCache::IoControl - setting maxRate to 5.53 Mbit/s with processWait of 100 ms 2025-08-10 15:13:37.923 T:1061 debug : CDVDInputStreamFile::SetReadRate - set cache throttle rate to 725481 bytes per second 2025-08-10 15:13:37.923 T:1061 info : Opening stream: 0 source: 256 2025-08-10 15:13:37.923 T:1061 info : Creating video codec with codec id: 167 2025-08-10 15:13:37.923 T:1061 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:13:37.926 T:1061 error : ffmpeg[0x26d23d0]: [vp9_v4l2m2m] Could not find a valid device 2025-08-10 15:13:37.927 T:1061 error : ffmpeg[0x26d23d0]: [vp9_v4l2m2m] can't configure decoder 2025-08-10 15:13:37.927 T:1061 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:13:37.927 T:1061 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:13:37.929 T:1061 debug : CVideoPlayerVideo::OpenStream - open stream with codec id: 167 2025-08-10 15:13:37.932 T:1061 info : Creating video thread 2025-08-10 15:13:37.933 T:1069 debug : Thread VideoPlayerVideo start, auto delete: false 2025-08-10 15:13:37.933 T:1069 info : running thread: video_thread 2025-08-10 15:13:37.933 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2025-08-10 15:13:37.933 T:1061 info : Opening stream: 1 source: 256 2025-08-10 15:13:37.934 T:1061 info : Finding audio codec for: 86076 2025-08-10 15:13:37.935 T:1061 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:13:37.936 T:1061 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:13:37.936 T:1061 info : Creating audio thread 2025-08-10 15:13:37.937 T:1070 debug : Thread VideoPlayerAudio start, auto delete: false 2025-08-10 15:13:37.938 T:1070 info : running thread: CVideoPlayerAudio::Process() 2025-08-10 15:13:37.938 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:13:37.938 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:13:37.938 T:1061 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2025-08-10 15:13:37.939 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:13:37.939 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:13:37.939 T:1069 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2025-08-10 15:13:37.939 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:13:37.950 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2025-08-10 15:13:37.950 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:13:37.950 T:1061 info : Skipped 1 duplicate messages.. 2025-08-10 15:13:37.950 T:1061 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2025-08-10 15:13:37.951 T:1070 debug : ffmpeg[0x26b52d8]: [opus] Could not update timestamps for skipped samples. 2025-08-10 15:13:37.952 T:1070 info : Creating audio stream (codec id: 86076, channels: 2, sample rate: 48000, no pass-through) 2025-08-10 15:13:37.953 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:13:37.955 T:1070 debug : CVideoPlayerAudio:: synctype set to 0: clock feedback 2025-08-10 15:13:37.960 T:777 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2025-08-10 15:13:38.014 T:1061 debug : CVideoPlayer::HandleMessages - player started 1 2025-08-10 15:13:38.018 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:13:38.162 T:1069 debug : CDVDVideoCodecDRMPRIME::FilterTest - found deinterlacing filter deinterlace_v4l2m2m 2025-08-10 15:13:38.162 T:1069 debug : CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 50.00. 2025-08-10 15:13:38.181 T:771 debug : DeleteRenderer - deleting renderer 2025-08-10 15:13:38.181 T:771 debug : LinuxRendererGLES: Cleaning up GLES resources 2025-08-10 15:13:38.182 T:771 debug : SetHDR: setting connector colorspace to Default 2025-08-10 15:13:38.182 T:771 debug : CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (LINEAR) 2025-08-10 15:13:38.182 T:771 debug : CRenderManager::Configure - 5 2025-08-10 15:13:38.184 T:1061 debug : CVideoPlayer::HandleMessages - player started 2 2025-08-10 15:13:38.184 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:13:38.184 T:1061 debug : VideoPlayer::Sync - Audio - pts: 314000.000000, cache: 491193.871261, totalcache: 800000.011921 2025-08-10 15:13:38.185 T:1061 debug : VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 2025-08-10 15:13:38.185 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-177193.871261), level: 50, cache: 463491.318594 2025-08-10 15:13:38.185 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-177193.871261) 2025-08-10 15:13:38.186 T:1061 debug : CVideoPlayer::SetCaching - caching state 0 2025-08-10 15:13:38.186 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:13:38.186 T:1070 debug : CDVDAudio::Resume - resume audio stream 2025-08-10 15:13:38.187 T:777 debug : ActiveAE - start sync of audio stream 2025-08-10 15:13:38.192 T:1063 debug : OnAVStarted: CApplication::OnAVStarted 2025-08-10 15:13:38.215 T:771 debug : CVideoLayerBridgeDRMPRIME::Configure - setting max bpc to 8 (true) 2025-08-10 15:13:38.221 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 670 2025-08-10 15:13:38.222 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:13:38.223 T:771 debug : CDRMAtomic::FlipPage - Execute modeset at next commit 2025-08-10 15:13:38.242 T:771 debug : Activating window ID: 12005 2025-08-10 15:13:38.255 T:771 debug : ------ Window Deinit (MyVideoNav.xml) ------ 2025-08-10 15:13:38.263 T:771 debug : FreeVisualisation() done 2025-08-10 15:13:38.267 T:771 debug : ------ Window Init (VideoFullScreen.xml) ------ 2025-08-10 15:13:38.276 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:13:38.286 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 671 2025-08-10 15:13:38.287 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:13:38.293 T:777 debug : ActiveAE::SyncStream - average error of -11.596556, start adjusting 2025-08-10 15:13:38.293 T:777 debug : ActiveAE::SyncStream - average error -0.013223 below threshold of 30.000000 2025-08-10 15:13:38.306 T:771 debug : ------ Window Deinit (DialogBusy.xml) ------ 2025-08-10 15:13:38.317 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 672 2025-08-10 15:13:38.318 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:13:38.330 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 668 2025-08-10 15:13:38.331 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:13:38.772 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:43.280 T:1069 info : Skipped 11 duplicate messages.. 2025-08-10 15:13:43.280 T:1069 debug : CPtsTracker: detected pattern of length 1: 20000.00, frameduration: 20000.000000 2025-08-10 15:13:43.931 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:54.321 T:851 info : Skipped 28 duplicate messages.. 2025-08-10 15:13:54.321 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:54.667 T:1070 info : Skipped 6 duplicate messages.. 2025-08-10 15:13:54.667 T:1070 info : CVideoPlayerAudio::Process - stream stalled 2025-08-10 15:13:54.668 T:1061 debug : CVideoPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync 2025-08-10 15:13:54.668 T:1061 debug : CVideoPlayer::FlushBuffers - flushing buffers 2025-08-10 15:13:54.687 T:1070 debug : CDVDAudio::Flush - flush audio stream 2025-08-10 15:13:54.687 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:13:54.809 T:1069 debug : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2025-08-10 15:13:54.813 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:13:54.813 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:13:54.814 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:13:54.814 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:13:54.815 T:1061 debug : demuxer seek to: 16450.000000 2025-08-10 15:13:54.819 T:1064 debug : easy_acquire - Created session to http://192.168.178.144 2025-08-10 15:13:54.819 T:1064 debug : CurlFile::CReadState::Connect - (0x51673218) Resume from position 3434390493 2025-08-10 15:13:54.824 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:13:54.824 T:771 debug : ------ Window Init (DialogSeekBar.xml) ------ 2025-08-10 15:13:54.831 T:1064 debug : CDoubleCache::Reset - (0x2558d30) Cache miss for 3434390493 with new=0-0 and old=8150200-18635960 2025-08-10 15:13:54.831 T:1064 debug : CFileCache::Process - cache completely reset for seek to position 3434390493 2025-08-10 15:13:54.831 T:1061 debug : CFileCache::Seek - waiting for position 3435410475 2025-08-10 15:13:54.839 T:771 debug : ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 2025-08-10 15:13:54.917 T:1064 debug : CFileCache::Process - source read hit eof 2025-08-10 15:13:54.926 T:1061 debug : SeekTime - seek ended up on time 20000 2025-08-10 15:13:54.926 T:1061 debug : demuxer seek to: 16450.000000, success 2025-08-10 15:13:54.926 T:1061 debug : CVideoPlayer::FlushBuffers - flushing buffers 2025-08-10 15:13:54.926 T:1069 debug : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2025-08-10 15:13:54.937 T:1070 debug : CDVDAudio::Flush - flush audio stream 2025-08-10 15:13:54.937 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:13:54.937 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:13:54.937 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:13:54.938 T:1069 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2025-08-10 15:13:54.969 T:1061 debug : CVideoPlayer::HandleMessages - player started 1 2025-08-10 15:13:54.970 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:13:54.994 T:1061 debug : CVideoPlayer::SetCaching - caching state 2 2025-08-10 15:13:54.994 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:13:54.994 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:13:55.303 T:1061 debug : CVideoPlayer::HandleMessages - player started 2 2025-08-10 15:13:55.303 T:1061 debug : CVideoPlayer::SetCaching - caching state 3 2025-08-10 15:13:55.303 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:13:55.303 T:1061 debug : CVideoPlayer::SetCaching - caching state 0 2025-08-10 15:13:55.303 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:13:55.303 T:1061 debug : VideoPlayer::Sync - Audio - pts: 20294000.000000, cache: 300000.011921, totalcache: 800000.011921 2025-08-10 15:13:55.303 T:1061 debug : VideoPlayer::Sync - Video - pts: 20000000.000000, cache: 50000.000000, totalcache: 100000.000000 2025-08-10 15:13:55.304 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(19900000.000000) 2025-08-10 15:13:55.304 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(19900000.000000), level: 97, cache: 469737.460588 2025-08-10 15:13:55.305 T:1070 debug : CDVDAudio::Resume - resume audio stream 2025-08-10 15:13:55.306 T:777 debug : ActiveAE - start sync of audio stream 2025-08-10 15:13:55.437 T:777 debug : ActiveAE::SyncStream - average error of -72.612935, start adjusting 2025-08-10 15:13:55.437 T:777 debug : ActiveAE::SyncStream - average error -22.612935 below threshold of 30.000000 2025-08-10 15:13:56.092 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:56.442 T:1070 info : Skipped 5 duplicate messages.. 2025-08-10 15:13:56.442 T:1070 debug : CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-56029.970317, adjusted:-56029.970317 2025-08-10 15:13:57.744 T:1069 debug : CPtsTracker: detected pattern of length 1: 20000.00, frameduration: 20000.000000 2025-08-10 15:13:57.826 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:58.244 T:771 info : Skipped 3 duplicate messages.. 2025-08-10 15:13:58.244 T:771 debug : ------ Window Deinit (DialogSeekBar.xml) ------ 2025-08-10 15:13:58.313 T:771 debug : ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 2025-08-10 15:13:59.021 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:13:59.023 T:771 debug : HandleKey: menu (0xd8) pressed, window 12005, action is Back 2025-08-10 15:13:59.023 T:771 debug : CGUIWindowManager::PreviousWindow: Deactivate 2025-08-10 15:13:59.023 T:771 debug : ------ Window Deinit (VideoFullScreen.xml) ------ 2025-08-10 15:13:59.054 T:771 debug : CGUIWindowManager::PreviousWindow: Activate new 2025-08-10 15:13:59.068 T:771 debug : ------ Window Init (MyVideoNav.xml) ------ 2025-08-10 15:13:59.073 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179/) 2025-08-10 15:13:59.074 T:771 debug : ParentPath = [upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179/] 2025-08-10 15:13:59.180 T:1075 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:13:59.180 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:13:59.207 T:1075 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:13:59.510 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:13:59.511 T:1069 debug : CPtsTracker: pattern lost on diff 0.000000, number of losses 1 2025-08-10 15:13:59.556 T:851 debug : request received for /jsonrpc 2025-08-10 15:13:59.662 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:00.139 T:851 info : Skipped 7 duplicate messages.. 2025-08-10 15:14:00.139 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:00.142 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:00.150 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:02.232 T:804 info : Skipped 5 duplicate messages.. 2025-08-10 15:14:02.232 T:804 debug : EventClient: button name "up" map "R1" pressed 2025-08-10 15:14:02.256 T:771 debug : HandleKey: 166 (0xa6, obc89) pressed, window 10025, action is Up 2025-08-10 15:14:02.265 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:02.733 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:14:02.756 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10025, action is Select 2025-08-10 15:14:02.757 T:771 debug : ------ Window Init (DialogContextMenu.xml) ------ 2025-08-10 15:14:03.601 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:03.601 T:804 debug : EventClient: button name "down" map "R1" pressed 2025-08-10 15:14:03.606 T:771 debug : HandleKey: 167 (0xa7, obc88) pressed, window 10106, action is Down 2025-08-10 15:14:03.610 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:04.054 T:804 info : Skipped 2 duplicate messages.. 2025-08-10 15:14:04.054 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:14:04.089 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10106, action is Select 2025-08-10 15:14:04.356 T:771 debug : ------ Window Deinit (DialogContextMenu.xml) ------ 2025-08-10 15:14:04.390 T:771 debug : OnPlayMedia upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%241/ 2025-08-10 15:14:04.401 T:771 debug : resource protocol info 'http-get:*:video/x-matroska:DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000' 2025-08-10 15:14:04.408 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:04.433 T:771 debug : CPlayerCoreFactory::GetPlayers(http://192.168.178.144:8200/MediaItems/2662.mkv) 2025-08-10 15:14:04.433 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2025-08-10 15:14:04.433 T:771 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2025-08-10 15:14:04.433 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2025-08-10 15:14:04.433 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2025-08-10 15:14:04.433 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2025-08-10 15:14:04.433 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: matches rule: streams 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: mp2 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2025-08-10 15:14:04.434 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2025-08-10 15:14:04.434 T:771 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2025-08-10 15:14:04.434 T:771 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2025-08-10 15:14:04.434 T:771 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2025-08-10 15:14:04.434 T:771 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2025-08-10 15:14:04.434 T:771 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2025-08-10 15:14:04.435 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%241/ 2025-08-10 15:14:04.436 T:771 debug : easy_acquire - Created session to http://192.168.178.144 2025-08-10 15:14:04.445 T:771 debug : CCurlFile::GetMimeType - -> video/x-matroska 2025-08-10 15:14:04.446 T:1061 debug : CVideoPlayer::FlushBuffers - flushing buffers 2025-08-10 15:14:04.456 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:04.456 T:1069 debug : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2025-08-10 15:14:04.465 T:1077 debug : Thread JobWorker start, auto delete: true 2025-08-10 15:14:04.465 T:1077 debug : [threads] name: 'JobWorker' priority: '1' 2025-08-10 15:14:04.466 T:1063 debug : Updating resume point for item upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:14:04.467 T:1063 debug : attempting to invoke UpdateObject for upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%242/ 2025-08-10 15:14:04.468 T:1063 debug : invoked UpdateObject successfully 2025-08-10 15:14:04.468 T:1063 debug : OnPlayBackStarted: CApplication::OnPlayBackStarted 2025-08-10 15:14:04.490 T:771 debug : CVideoGUIInfo::InitCurrentItem(upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%241/) 2025-08-10 15:14:04.537 T:1070 debug : CDVDAudio::Flush - flush audio stream 2025-08-10 15:14:04.537 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:04.537 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:04.537 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:04.537 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:14:04.537 T:771 debug : CPlayerGUIInfo::InitCurrentItem(upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%241/) 2025-08-10 15:14:04.537 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:04.542 T:1064 debug : Thread FileCache 1244635712 terminating 2025-08-10 15:14:04.547 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:04.549 T:1061 info : Creating InputStream 2025-08-10 15:14:04.550 T:1061 debug : CurlFile::Open - 2025-08-10 15:14:04.562 T:1061 debug : CFileCache::Open - opening 2025-08-10 15:14:04.563 T:1061 debug : CurlFile::Open - 2025-08-10 15:14:04.572 T:1061 debug : CFileCache::Open - source chunk size is 0, setting cache chunk size to 1048576 2025-08-10 15:14:04.572 T:1061 debug : CFileCache::Open - using double memory cache each sized 10485760 bytes 2025-08-10 15:14:04.572 T:1077 debug : Loading settings for upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%241/ 2025-08-10 15:14:04.572 T:1079 debug : Thread FileCache start, auto delete: false 2025-08-10 15:14:04.573 T:1061 info : Creating Demuxer 2025-08-10 15:14:04.575 T:1061 debug : Open - probing detected format [matroska,webm] 2025-08-10 15:14:04.576 T:1061 debug : Open - avformat_find_stream_info starting 2025-08-10 15:14:04.581 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:04.623 T:1061 info : Skipped 2 duplicate messages.. 2025-08-10 15:14:04.623 T:1061 debug : Open - av_find_stream_info finished 2025-08-10 15:14:04.623 T:1061 info : ffmpeg[0x26d23d0]: Input #0, matroska,webm, from 'http://192.168.178.144:8200/MediaItems/2662.mkv': 2025-08-10 15:14:04.623 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:14:04.623 T:1061 info : ffmpeg[0x26d23d0]: ENCODER : Lavf60.16.100 2025-08-10 15:14:04.623 T:1061 info : ffmpeg[0x26d23d0]: Duration: 01:26:48.92, start: 0.000000, bitrate: 2597 kb/s 2025-08-10 15:14:04.624 T:1061 info : ffmpeg[0x26d23d0]: Stream #0:0(eng): Video: vp9 (Profile 0), yuv420p(tv), 1280x720, SAR 1:1 DAR 16:9, 50 fps, 50 tbr, 1k tbn (default) 2025-08-10 15:14:04.624 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:14:04.624 T:1061 info : ffmpeg[0x26d23d0]: DURATION : 01:26:48.920000000 2025-08-10 15:14:04.624 T:1061 info : ffmpeg[0x26d23d0]: Stream #0:1(deu): Audio: opus, 48000 Hz, stereo, fltp (default) 2025-08-10 15:14:04.624 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:14:04.624 T:1061 info : ffmpeg[0x26d23d0]: DURATION : 01:26:48.921000000 2025-08-10 15:14:04.625 T:1061 debug : CDVDDemuxFFmpeg::AddStream ID: 0 2025-08-10 15:14:04.625 T:1061 debug : CDVDDemuxFFmpeg::AddStream ID: 1 2025-08-10 15:14:04.625 T:1061 debug : CFileCache::IoControl - setting maxRate to 2.72 Mbit/s with processWait of 100 ms 2025-08-10 15:14:04.625 T:1061 debug : CDVDInputStreamFile::SetReadRate - set cache throttle rate to 357134 bytes per second 2025-08-10 15:14:04.625 T:1061 info : Opening stream: 0 source: 256 2025-08-10 15:14:04.625 T:1061 info : Creating video codec with codec id: 167 2025-08-10 15:14:04.626 T:1061 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:14:04.630 T:1061 error : ffmpeg[0x26d23d0]: [vp9_v4l2m2m] Could not find a valid device 2025-08-10 15:14:04.630 T:1061 error : ffmpeg[0x26d23d0]: [vp9_v4l2m2m] can't configure decoder 2025-08-10 15:14:04.630 T:1061 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:14:04.630 T:1061 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:14:04.632 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2025-08-10 15:14:04.633 T:1069 debug : CDVDVideoCodecDRMPRIME::GetPicture - flush buffers 2025-08-10 15:14:04.633 T:1061 info : Opening stream: 1 source: 256 2025-08-10 15:14:04.633 T:1069 debug : CVideoPlayerVideo::OpenStream - open stream with codec id: 167 2025-08-10 15:14:04.633 T:1061 info : Finding audio codec for: 86076 2025-08-10 15:14:04.635 T:1061 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:14:04.636 T:1061 debug : CVideoPlayer::HandleMessages - player started 2 2025-08-10 15:14:04.637 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:14:04.637 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:04.639 T:771 debug : ------ Window Init (DialogBusy.xml) ------ 2025-08-10 15:14:04.639 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2025-08-10 15:14:04.639 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:04.640 T:1070 info : Skipped 1 duplicate messages.. 2025-08-10 15:14:04.640 T:1070 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:14:04.640 T:1061 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2025-08-10 15:14:04.640 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:04.641 T:1070 debug : ffmpeg[0x26b52d8]: [opus] Could not update timestamps for skipped samples. 2025-08-10 15:14:04.641 T:1070 debug : CVideoPlayerAudio:: synctype set to 0: clock feedback 2025-08-10 15:14:04.643 T:771 debug : Flush - flushing renderer 2025-08-10 15:14:04.659 T:1061 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2025-08-10 15:14:04.666 T:1061 debug : CVideoPlayer::HandleMessages - player started 1 2025-08-10 15:14:04.666 T:1077 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:04.690 T:771 debug : CVideoLayerBridgeDRMPRIME::Configure - setting max bpc to 8 (true) 2025-08-10 15:14:04.796 T:1069 debug : CDVDVideoCodecDRMPRIME::FilterTest - found deinterlacing filter deinterlace_v4l2m2m 2025-08-10 15:14:04.796 T:1069 debug : CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 50.00. 2025-08-10 15:14:04.815 T:771 debug : DeleteRenderer - deleting renderer 2025-08-10 15:14:04.815 T:771 debug : CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (LINEAR) 2025-08-10 15:14:04.815 T:771 debug : CRenderManager::Configure - 5 2025-08-10 15:14:04.816 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:04.819 T:1061 debug : CVideoPlayer::HandleMessages - player started 2 2025-08-10 15:14:04.820 T:1061 debug : VideoPlayer::Sync - Audio - pts: 314000.000000, cache: 473367.933928, totalcache: 800000.011921 2025-08-10 15:14:04.820 T:1061 debug : VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000 2025-08-10 15:14:04.820 T:1061 debug : CVideoPlayer::SetCaching - caching state 0 2025-08-10 15:14:04.820 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:04.820 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-159367.933928) 2025-08-10 15:14:04.820 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-159367.933928), level: 71, cache: 467105.433928 2025-08-10 15:14:04.820 T:1070 debug : CDVDAudio::Resume - resume audio stream 2025-08-10 15:14:04.821 T:777 debug : ActiveAE - start sync of audio stream 2025-08-10 15:14:04.822 T:1063 debug : OnAVStarted: CApplication::OnAVStarted 2025-08-10 15:14:04.837 T:771 debug : CVideoLayerBridgeDRMPRIME::Configure - setting max bpc to 8 (true) 2025-08-10 15:14:04.880 T:771 debug : Activating window ID: 12005 2025-08-10 15:14:04.893 T:771 debug : ------ Window Deinit (MyVideoNav.xml) ------ 2025-08-10 15:14:04.894 T:771 debug : FreeVisualisation() done 2025-08-10 15:14:04.905 T:771 debug : ------ Window Init (VideoFullScreen.xml) ------ 2025-08-10 15:14:04.918 T:1077 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:04.939 T:777 debug : ActiveAE::SyncStream - average error of 8.979792, start adjusting 2025-08-10 15:14:04.939 T:777 debug : ActiveAE::SyncStream - average error 0.979792 below threshold of 30.000000 2025-08-10 15:14:04.973 T:771 debug : ------ Window Deinit (DialogBusy.xml) ------ 2025-08-10 15:14:05.337 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:07.399 T:1069 info : Skipped 9 duplicate messages.. 2025-08-10 15:14:07.399 T:1069 debug : CPtsTracker: detected pattern of length 1: 20000.00, frameduration: 20000.000000 2025-08-10 15:14:08.783 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:19.166 T:851 info : Skipped 28 duplicate messages.. 2025-08-10 15:14:19.166 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:21.262 T:804 info : Skipped 7 duplicate messages.. 2025-08-10 15:14:21.262 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:14:21.275 T:771 debug : HandleKey: menu (0xd8) pressed, window 12005, action is Back 2025-08-10 15:14:21.276 T:771 debug : CGUIWindowManager::PreviousWindow: Deactivate 2025-08-10 15:14:21.276 T:771 debug : ------ Window Deinit (VideoFullScreen.xml) ------ 2025-08-10 15:14:21.311 T:771 debug : CGUIWindowManager::PreviousWindow: Activate new 2025-08-10 15:14:21.330 T:771 debug : ------ Window Init (MyVideoNav.xml) ------ 2025-08-10 15:14:21.337 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179/) 2025-08-10 15:14:21.337 T:771 debug : ParentPath = [upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179/] 2025-08-10 15:14:21.457 T:1088 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:14:21.457 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:14:21.483 T:1088 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:14:21.787 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:21.789 T:1069 debug : CPtsTracker: pattern lost on diff 0.000000, number of losses 1 2025-08-10 15:14:21.941 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:22.646 T:851 info : Skipped 9 duplicate messages.. 2025-08-10 15:14:22.646 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:23.061 T:804 info : Skipped 3 duplicate messages.. 2025-08-10 15:14:23.061 T:804 debug : EventClient: button name "up" map "R1" pressed 2025-08-10 15:14:23.076 T:771 debug : HandleKey: 166 (0xa6, obc89) pressed, window 10025, action is Up 2025-08-10 15:14:23.935 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:14:23.940 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10025, action is Select 2025-08-10 15:14:23.941 T:771 debug : ------ Window Init (DialogContextMenu.xml) ------ 2025-08-10 15:14:24.366 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:24.995 T:1069 info : Skipped 6 duplicate messages.. 2025-08-10 15:14:24.995 T:1069 debug : CPtsTracker: detected pattern of length 1: 20000.00, frameduration: 20000.000000 2025-08-10 15:14:25.296 T:804 debug : EventClient: button name "down" map "R1" pressed 2025-08-10 15:14:25.306 T:771 debug : HandleKey: 167 (0xa7, obc88) pressed, window 10106, action is Down 2025-08-10 15:14:25.661 T:804 debug : EventClient: button name "select" map "R1" pressed 2025-08-10 15:14:25.690 T:771 debug : HandleKey: 11 (0xb, obc244) pressed, window 10106, action is Select 2025-08-10 15:14:25.989 T:771 debug : ------ Window Deinit (DialogContextMenu.xml) ------ 2025-08-10 15:14:26.006 T:771 debug : OnPlayMedia upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%240/ 2025-08-10 15:14:26.018 T:771 debug : resource protocol info 'http-get:*:video/mp4:DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000' 2025-08-10 15:14:26.041 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:26.045 T:771 debug : CPlayerCoreFactory::GetPlayers(http://192.168.178.144:8200/MediaItems/932.mp4) 2025-08-10 15:14:26.045 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: system rules 2025-08-10 15:14:26.045 T:771 debug : CPlayerSelectionRule::GetPlayers: matches rule: system rules 2025-08-10 15:14:26.045 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: mms/udp 2025-08-10 15:14:26.045 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 2025-08-10 15:14:26.046 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtmp 2025-08-10 15:14:26.046 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: rtsp 2025-08-10 15:14:26.046 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: streams 2025-08-10 15:14:26.046 T:771 debug : CPlayerSelectionRule::GetPlayers: matches rule: streams 2025-08-10 15:14:26.046 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp 2025-08-10 15:14:26.046 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: mp2 2025-08-10 15:14:26.046 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: dvd 2025-08-10 15:14:26.047 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: discimage 2025-08-10 15:14:26.047 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 2025-08-10 15:14:26.047 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: nsv 2025-08-10 15:14:26.047 T:771 debug : CPlayerSelectionRule::GetPlayers: considering rule: radio 2025-08-10 15:14:26.047 T:771 debug : CPlayerCoreFactory::GetPlayers: matched 0 rules with players 2025-08-10 15:14:26.047 T:771 debug : CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer) 2025-08-10 15:14:26.047 T:771 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=false 2025-08-10 15:14:26.047 T:771 debug : CPlayerCoreFactory::GetPlayers: for video=true, audio=true 2025-08-10 15:14:26.047 T:771 debug : CPlayerCoreFactory::GetPlayers: added 1 players 2025-08-10 15:14:26.047 T:771 info : VideoPlayer::OpenFile: upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%240/ 2025-08-10 15:14:26.057 T:771 debug : CCurlFile::GetMimeType - -> video/mp4 2025-08-10 15:14:26.066 T:1061 debug : CVideoPlayer::FlushBuffers - flushing buffers 2025-08-10 15:14:26.068 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:26.082 T:1069 info : Skipped 1 duplicate messages.. 2025-08-10 15:14:26.082 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:26.084 T:1069 debug : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2025-08-10 15:14:26.089 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:26.095 T:1077 debug : Updating resume point for item upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%241/ 2025-08-10 15:14:26.096 T:1077 debug : attempting to invoke UpdateObject for upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%241/ 2025-08-10 15:14:26.096 T:1077 debug : invoked UpdateObject successfully 2025-08-10 15:14:26.097 T:1077 debug : OnPlayBackStarted: CApplication::OnPlayBackStarted 2025-08-10 15:14:26.101 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:26.107 T:771 debug : CVideoGUIInfo::InitCurrentItem(upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%240/) 2025-08-10 15:14:26.138 T:1070 debug : CDVDAudio::Flush - flush audio stream 2025-08-10 15:14:26.138 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:26.138 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:26.138 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:26.138 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:14:26.139 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:26.145 T:1079 debug : Thread FileCache 1244635712 terminating 2025-08-10 15:14:26.148 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:26.153 T:1061 info : Creating InputStream 2025-08-10 15:14:26.155 T:1061 debug : CurlFile::Open - 2025-08-10 15:14:26.180 T:1061 debug : CFileCache::Open - opening 2025-08-10 15:14:26.181 T:1061 debug : CurlFile::Open - 2025-08-10 15:14:26.206 T:1061 debug : CFileCache::Open - source chunk size is 0, setting cache chunk size to 1048576 2025-08-10 15:14:26.206 T:1061 debug : CFileCache::Open - using double memory cache each sized 10485760 bytes 2025-08-10 15:14:26.210 T:1091 debug : Thread FileCache start, auto delete: false 2025-08-10 15:14:26.210 T:1061 info : Creating Demuxer 2025-08-10 15:14:26.214 T:1061 debug : Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2] 2025-08-10 15:14:26.215 T:1091 debug : CurlFile::CReadState::Connect - (0x6c806ca8) Resume from position 3431748808 2025-08-10 15:14:26.219 T:771 debug : CPlayerGUIInfo::InitCurrentItem(upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%240/) 2025-08-10 15:14:26.220 T:1077 debug : Loading settings for upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%240/ 2025-08-10 15:14:26.228 T:1091 debug : CDoubleCache::Reset - (0x235c628) Cache miss for 3431748808 with new=0-0 and old=0-244279 2025-08-10 15:14:26.228 T:1091 debug : CFileCache::Process - cache completely reset for seek to position 3431748808 2025-08-10 15:14:26.293 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:26.320 T:771 info : Skipped 1 duplicate messages.. 2025-08-10 15:14:26.320 T:771 debug : ------ Window Init (DialogBusy.xml) ------ 2025-08-10 15:14:26.322 T:771 debug : Flush - flushing renderer 2025-08-10 15:14:26.339 T:771 debug : CVideoLayerBridgeDRMPRIME::Configure - setting max bpc to 8 (true) 2025-08-10 15:14:26.639 T:1091 debug : CFileCache::Process - source read hit eof 2025-08-10 15:14:27.664 T:1061 debug : Open - avformat_find_stream_info starting 2025-08-10 15:14:27.771 T:1061 debug : Open - av_find_stream_info finished 2025-08-10 15:14:27.771 T:1061 info : ffmpeg[0x26d23d0]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, from 'http://192.168.178.144:8200/MediaItems/932.mp4': 2025-08-10 15:14:27.772 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:14:27.772 T:1061 info : ffmpeg[0x26d23d0]: major_brand : isom 2025-08-10 15:14:27.772 T:1061 info : ffmpeg[0x26d23d0]: minor_version : 512 2025-08-10 15:14:27.772 T:1061 info : ffmpeg[0x26d23d0]: compatible_brands: isomiso2mp41 2025-08-10 15:14:27.772 T:1061 info : ffmpeg[0x26d23d0]: encoder : Lavf61.3.100 2025-08-10 15:14:27.772 T:1061 info : ffmpeg[0x26d23d0]: Duration: 01:26:48.92, start: 0.000000, bitrate: 5276 kb/s 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: Stream #0:0[0x1](eng): Video: vp9 (Profile 0) (vp09 / 0x39307076), yuv420p(tv), 1920x1080, 5145 kb/s, SAR 1:1 DAR 16:9, 49.99 fps, 50 tbr, 16k tbn (default) 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: handler_name : VideoHandler 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: vendor_id : [0][0][0][0] 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: Stream #0:1[0x2](deu): Audio: opus (Opus / 0x7375704F), 48000 Hz, stereo, fltp, 124 kb/s (default) 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: Metadata: 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: handler_name : SoundHandler 2025-08-10 15:14:27.773 T:1061 info : ffmpeg[0x26d23d0]: vendor_id : [0][0][0][0] 2025-08-10 15:14:27.773 T:1061 debug : CDVDDemuxFFmpeg::AddStream ID: 0 2025-08-10 15:14:27.774 T:1061 debug : CDVDDemuxFFmpeg::AddStream ID: 1 2025-08-10 15:14:27.774 T:1061 debug : CFileCache::IoControl - setting maxRate to 5.54 Mbit/s with processWait of 100 ms 2025-08-10 15:14:27.774 T:1061 debug : CDVDInputStreamFile::SetReadRate - set cache throttle rate to 725585 bytes per second 2025-08-10 15:14:27.774 T:1061 info : Opening stream: 0 source: 256 2025-08-10 15:14:27.775 T:1061 info : Creating video codec with codec id: 167 2025-08-10 15:14:27.775 T:1061 info : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem VP9 decoder wrapper 2025-08-10 15:14:27.778 T:1061 error : ffmpeg[0x26d23d0]: [vp9_v4l2m2m] Could not find a valid device 2025-08-10 15:14:27.779 T:1061 error : ffmpeg[0x26d23d0]: [vp9_v4l2m2m] can't configure decoder 2025-08-10 15:14:27.779 T:1061 info : CDVDVideoCodecDRMPRIME::Open - unable to open codec 2025-08-10 15:14:27.779 T:1061 info : CDVDVideoCodecDRMPRIME::Open - using decoder Google VP9 2025-08-10 15:14:27.782 T:1061 info : Opening stream: 1 source: 256 2025-08-10 15:14:27.783 T:1061 info : Finding audio codec for: 86076 2025-08-10 15:14:27.784 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false 2025-08-10 15:14:27.785 T:1061 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus 2025-08-10 15:14:27.786 T:1061 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2025-08-10 15:14:27.787 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:14:27.787 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:27.787 T:1069 debug : CDVDVideoCodecDRMPRIME::GetPicture - flush buffers 2025-08-10 15:14:27.788 T:1069 debug : CVideoPlayerVideo::OpenStream - open stream with codec id: 167 2025-08-10 15:14:27.788 T:1061 debug : CVideoPlayer::HandleMessages - player started 2 2025-08-10 15:14:27.791 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2025-08-10 15:14:27.791 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:27.791 T:1061 info : Skipped 1 duplicate messages.. 2025-08-10 15:14:27.791 T:1061 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2025-08-10 15:14:27.792 T:1070 info : OpenStream: Allowing max Out-Of-Sync Value of 50 ms 2025-08-10 15:14:27.794 T:1070 debug : ffmpeg[0x26b52d8]: [opus] Could not update timestamps for skipped samples. 2025-08-10 15:14:27.794 T:1070 debug : CVideoPlayerAudio:: synctype set to 0: clock feedback 2025-08-10 15:14:27.795 T:1077 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:27.806 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:27.968 T:1061 info : Skipped 3 duplicate messages.. 2025-08-10 15:14:27.968 T:1061 debug : CVideoPlayer::HandleMessages - player started 1 2025-08-10 15:14:27.972 T:1061 debug : VideoPlayer::Sync - Audio - pts: 314000.000000, cache: 466070.694261, totalcache: 800000.011921 2025-08-10 15:14:27.973 T:1061 debug : VideoPlayer::Sync - Video - pts: 18442240474082181120.000000, cache: 50000.000000, totalcache: 100000.000000 2025-08-10 15:14:27.973 T:1061 debug : CVideoPlayer::SetCaching - caching state 0 2025-08-10 15:14:27.973 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:27.975 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-152070.694261), level: 52, cache: 472708.662928 2025-08-10 15:14:27.975 T:1070 debug : CDVDAudio::Resume - resume audio stream 2025-08-10 15:14:27.975 T:771 debug : Activating window ID: 12005 2025-08-10 15:14:27.976 T:1077 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:27.976 T:1077 debug : OnAVStarted: CApplication::OnAVStarted 2025-08-10 15:14:27.980 T:777 debug : ActiveAE - start sync of audio stream 2025-08-10 15:14:27.986 T:771 debug : ------ Window Deinit (MyVideoNav.xml) ------ 2025-08-10 15:14:27.986 T:771 debug : FreeVisualisation() done 2025-08-10 15:14:27.988 T:771 debug : ------ Window Init (VideoFullScreen.xml) ------ 2025-08-10 15:14:28.006 T:1077 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:28.088 T:777 debug : ActiveAE::SyncStream - average error of -37.758620, start adjusting 2025-08-10 15:14:28.089 T:777 debug : ActiveAE::SyncStream - average error -0.008620 below threshold of 30.000000 2025-08-10 15:14:28.091 T:1069 debug : CDVDVideoCodecDRMPRIME::FilterTest - found deinterlacing filter deinterlace_v4l2m2m 2025-08-10 15:14:28.092 T:1069 debug : CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 50.00. 2025-08-10 15:14:28.092 T:771 debug : ------ Window Deinit (DialogBusy.xml) ------ 2025-08-10 15:14:28.095 T:771 debug : DeleteRenderer - deleting renderer 2025-08-10 15:14:28.095 T:771 debug : CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (LINEAR) 2025-08-10 15:14:28.095 T:771 debug : CRenderManager::Configure - 5 2025-08-10 15:14:28.099 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:28.101 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-152070.694261) 2025-08-10 15:14:28.103 T:1061 debug : CVideoPlayer::HandleMessages - player started 2 2025-08-10 15:14:28.109 T:771 debug : CVideoLayerBridgeDRMPRIME::Configure - setting max bpc to 8 (true) 2025-08-10 15:14:28.143 T:1077 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:28.273 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-21286.111261) 2025-08-10 15:14:29.727 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:33.277 T:1069 info : Skipped 11 duplicate messages.. 2025-08-10 15:14:33.277 T:1069 debug : CPtsTracker: detected pattern of length 1: 20000.00, frameduration: 20000.000000 2025-08-10 15:14:34.607 T:771 debug : CheckIdle - Closing session to http://192.168.178.144 (easy=0x22e40d8, multi=0x0) 2025-08-10 15:14:34.695 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:44.298 T:1070 info : Skipped 28 duplicate messages.. 2025-08-10 15:14:44.298 T:1070 info : CVideoPlayerAudio::Process - stream stalled 2025-08-10 15:14:44.299 T:1061 debug : CVideoPlayer::HandlePlaySpeed - audio stream stalled, triggering re-sync 2025-08-10 15:14:44.299 T:1061 debug : CVideoPlayer::FlushBuffers - flushing buffers 2025-08-10 15:14:44.337 T:1070 debug : CDVDAudio::Flush - flush audio stream 2025-08-10 15:14:44.339 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:44.428 T:1069 debug : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2025-08-10 15:14:44.433 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:44.433 T:1061 debug : CVideoPlayer::SetCaching - caching state 1 2025-08-10 15:14:44.433 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:44.434 T:1061 debug : demuxer seek to: 16308.000000 2025-08-10 15:14:44.433 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:44.434 T:1069 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2025-08-10 15:14:44.435 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:44.435 T:1061 debug : SeekTime - seek ended up on time 20000 2025-08-10 15:14:44.435 T:1061 debug : demuxer seek to: 16308.000000, success 2025-08-10 15:14:44.435 T:1061 debug : CVideoPlayer::FlushBuffers - flushing buffers 2025-08-10 15:14:44.436 T:1092 error : ffmpeg[0x0]: [vp9] Not all references are available 2025-08-10 15:14:44.436 T:1069 error : CDVDVideoCodecDRMPRIME::Drain - send packet failed: Invalid data found when processing input (-1094995529) 2025-08-10 15:14:44.436 T:1069 debug : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2025-08-10 15:14:44.442 T:771 debug : ------ Window Init (DialogSeekBar.xml) ------ 2025-08-10 15:14:44.446 T:1070 debug : CDVDAudio::Flush - flush audio stream 2025-08-10 15:14:44.446 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:44.446 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:44.446 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2025-08-10 15:14:44.450 T:1069 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2025-08-10 15:14:44.457 T:771 debug : ------ Window Init (Custom_1109_TopBarOverlay.xml) ------ 2025-08-10 15:14:44.487 T:1061 debug : CVideoPlayer::HandleMessages - player started 1 2025-08-10 15:14:44.488 T:1063 debug : OnAVChange: CApplication::OnAVChange 2025-08-10 15:14:44.504 T:1061 debug : CVideoPlayer::SetCaching - caching state 2 2025-08-10 15:14:44.505 T:1070 debug : CDVDAudio::Pause - pausing audio stream 2025-08-10 15:14:44.505 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:44.731 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:44.809 T:1061 info : Skipped 2 duplicate messages.. 2025-08-10 15:14:44.809 T:1061 debug : CVideoPlayer::HandleMessages - player started 2 2025-08-10 15:14:44.810 T:1061 debug : CVideoPlayer::SetCaching - caching state 3 2025-08-10 15:14:44.810 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:44.810 T:1061 debug : CVideoPlayer::SetCaching - caching state 0 2025-08-10 15:14:44.810 T:1061 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2025-08-10 15:14:44.810 T:1061 debug : VideoPlayer::Sync - Audio - pts: 20294000.000000, cache: 407802.668254, totalcache: 800000.011921 2025-08-10 15:14:44.810 T:1061 debug : VideoPlayer::Sync - Video - pts: 20000000.000000, cache: 50000.000000, totalcache: 100000.000000 2025-08-10 15:14:44.811 T:1069 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(19886197.331746) 2025-08-10 15:14:44.811 T:1070 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(19886197.331746), level: 97, cache: 434208.605588 2025-08-10 15:14:44.811 T:1070 debug : CDVDAudio::Resume - resume audio stream 2025-08-10 15:14:44.813 T:777 debug : ActiveAE - start sync of audio stream 2025-08-10 15:14:44.887 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:44.906 T:771 debug : CGUIWindowManager::PreviousWindow: Deactivate 2025-08-10 15:14:44.907 T:771 debug : ------ Window Deinit (VideoFullScreen.xml) ------ 2025-08-10 15:14:44.935 T:771 debug : CGUIWindowManager::PreviousWindow: Activate new 2025-08-10 15:14:44.937 T:777 debug : ActiveAE::SyncStream - average error of -23.918209, start adjusting 2025-08-10 15:14:44.937 T:777 debug : ActiveAE::SyncStream - average error -0.001542 below threshold of 30.000000 2025-08-10 15:14:44.952 T:771 debug : ------ Window Init (MyVideoNav.xml) ------ 2025-08-10 15:14:44.960 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179/) 2025-08-10 15:14:44.960 T:771 debug : ParentPath = [upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179/] 2025-08-10 15:14:45.069 T:1100 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:14:45.069 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:14:45.083 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:14:45.083 T:771 debug : DeleteRenderer - deleting renderer 2025-08-10 15:14:45.083 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:14:45.086 T:1061 info : CVideoPlayer::OnExit() 2025-08-10 15:14:45.087 T:1061 info : Closing stream player 1 2025-08-10 15:14:45.087 T:1061 info : Waiting for audio thread to exit 2025-08-10 15:14:45.094 T:1100 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:14:45.188 T:1070 info : thread end: CVideoPlayerAudio::OnExit() 2025-08-10 15:14:45.188 T:1070 debug : Thread VideoPlayerAudio 1219457600 terminating 2025-08-10 15:14:45.189 T:1061 info : Closing audio device 2025-08-10 15:14:45.237 T:1061 debug : CDVDAudio::Flush - flush audio stream 2025-08-10 15:14:45.238 T:777 debug : CActiveAE::DiscardStream - audio stream deleted 2025-08-10 15:14:45.238 T:777 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2025-08-10 15:14:45.239 T:777 info : Skipped 2 duplicate messages.. 2025-08-10 15:14:45.239 T:777 info : CActiveAE::ApplySettings - Forcing samplerate to 48000 2025-08-10 15:14:45.239 T:777 debug : CActiveAE::ClearDiscardedBuffers - buffer pool deleted 2025-08-10 15:14:45.240 T:1061 info : Deleting audio codec 2025-08-10 15:14:45.241 T:1061 info : Closing stream player 2 2025-08-10 15:14:45.241 T:1061 info : waiting for video thread to exit 2025-08-10 15:14:45.244 T:1069 warning : OutputPicture - timeout waiting for buffer 2025-08-10 15:14:45.245 T:1069 info : thread end: video_thread 2025-08-10 15:14:45.245 T:1069 debug : Thread VideoPlayerVideo 1227850304 terminating 2025-08-10 15:14:45.245 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:45.248 T:1061 info : deleting video codec 2025-08-10 15:14:45.259 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:45.262 T:1091 debug : Thread FileCache 1261421120 terminating 2025-08-10 15:14:45.269 T:1061 debug : Thread VideoPlayer 1661981248 terminating 2025-08-10 15:14:45.271 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:14:45.271 T:771 info : CVideoPlayer::CloseFile() 2025-08-10 15:14:45.271 T:771 info : VideoPlayer: waiting for threads to exit 2025-08-10 15:14:45.271 T:771 info : VideoPlayer: finished waiting 2025-08-10 15:14:45.275 T:771 debug : Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData 2025-08-10 15:14:45.275 T:771 debug : Audio ID3 tag processor - delete ~CVideoPlayerAudioID3 2025-08-10 15:14:45.275 T:1077 debug : Updating resume point for item upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%240/ 2025-08-10 15:14:45.275 T:1077 debug : attempting to invoke UpdateObject for upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241%24179%240/ 2025-08-10 15:14:45.276 T:1077 debug : invoked UpdateObject successfully 2025-08-10 15:14:45.276 T:1063 debug : OnPlayBackStopped: CApplication::OnPlayBackStopped 2025-08-10 15:14:45.295 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:46.092 T:771 info : Skipped 4 duplicate messages.. 2025-08-10 15:14:46.092 T:771 debug : ------ Window Deinit (DialogSeekBar.xml) ------ 2025-08-10 15:14:46.102 T:771 debug : CVideoLayerBridgeDRMPRIME::Disable - setting max bpc to 8 (true) 2025-08-10 15:14:46.103 T:771 debug : SetHDR: setting connector colorspace to Default 2025-08-10 15:14:46.103 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 671 2025-08-10 15:14:46.103 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:14:46.103 T:771 debug : CDRMAtomic::FlipPage - Execute modeset at next commit 2025-08-10 15:14:46.225 T:771 debug : ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------ 2025-08-10 15:14:46.228 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 672 2025-08-10 15:14:46.228 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:14:46.252 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 668 2025-08-10 15:14:46.253 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:14:46.283 T:771 debug : CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 670 2025-08-10 15:14:46.284 T:771 debug : CDRMUtils::DrmFbGetFromBo - using modifier: BROADCOM_VC4_T_TILED 2025-08-10 15:14:46.787 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:48.219 T:804 info : Skipped 1 duplicate messages.. 2025-08-10 15:14:48.219 T:804 debug : EventClient: button name "up" map "R1" pressed 2025-08-10 15:14:48.240 T:771 debug : HandleKey: 166 (0xa6, obc89) pressed, window 10025, action is Up 2025-08-10 15:14:48.337 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:49.361 T:804 info : Skipped 1 duplicate messages.. 2025-08-10 15:14:49.361 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:14:49.390 T:771 debug : HandleKey: menu (0xd8) pressed, window 10025, action is Back 2025-08-10 15:14:49.391 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64%241/) 2025-08-10 15:14:49.391 T:771 debug : ParentPath = [upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64/] 2025-08-10 15:14:49.392 T:1102 debug : Thread waiting start, auto delete: false 2025-08-10 15:14:49.447 T:1102 debug : Thread waiting 1661981248 terminating 2025-08-10 15:14:49.604 T:1103 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:14:49.604 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:14:49.674 T:1103 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:14:49.878 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:50.225 T:804 info : Skipped 1 duplicate messages.. 2025-08-10 15:14:50.225 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:14:50.240 T:771 debug : HandleKey: menu (0xd8) pressed, window 10025, action is Back 2025-08-10 15:14:50.241 T:771 debug : CGUIMediaWindow::GetDirectory (upnp://4d696e69-444c-164e-9d41-dca6323cb69f/64/) 2025-08-10 15:14:50.241 T:771 debug : ParentPath = [sources://video/] 2025-08-10 15:14:50.242 T:1104 debug : Thread waiting start, auto delete: false 2025-08-10 15:14:50.244 T:1104 debug : Thread waiting 1661981248 terminating 2025-08-10 15:14:50.257 T:1105 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:14:50.257 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:14:50.280 T:1105 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:14:50.689 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:14:50.699 T:771 debug : HandleKey: menu (0xd8) pressed, window 10025, action is Back 2025-08-10 15:14:50.700 T:771 debug : CGUIMediaWindow::GetDirectory (sources://video/) 2025-08-10 15:14:50.700 T:771 debug : ParentPath = [] 2025-08-10 15:14:50.701 T:1106 debug : Thread waiting start, auto delete: false 2025-08-10 15:14:50.794 T:1106 debug : Thread waiting 1661981248 terminating 2025-08-10 15:14:50.897 T:1107 debug : Thread BackgroundLoader start, auto delete: false 2025-08-10 15:14:50.898 T:771 debug : [threads] name: 'BackgroundLoader' priority: '1' 2025-08-10 15:14:50.922 T:1107 debug : Thread BackgroundLoader 1299477056 terminating 2025-08-10 15:14:51.037 T:804 debug : EventClient: button name "back" map "R1" pressed 2025-08-10 15:14:51.040 T:771 debug : HandleKey: menu (0xd8) pressed, window 10025, action is Back 2025-08-10 15:14:51.040 T:771 debug : CGUIWindowManager::PreviousWindow: Deactivate 2025-08-10 15:14:51.366 T:771 debug : ------ Window Deinit (MyVideoNav.xml) ------ 2025-08-10 15:14:51.367 T:771 debug : FreeVisualisation() done 2025-08-10 15:14:51.390 T:771 debug : CGUIWindowManager::PreviousWindow: Activate new 2025-08-10 15:14:51.390 T:771 debug : ------ Window Init (Home.xml) ------ 2025-08-10 15:14:51.391 T:771 debug : CDirectoryProvider[addons://]: refreshing.. 2025-08-10 15:14:51.391 T:771 debug : CDirectoryProvider[addons://sources/video/]: refreshing.. 2025-08-10 15:14:51.391 T:771 debug : CDirectoryProvider[addons://sources/audio/]: refreshing.. 2025-08-10 15:14:51.391 T:1108 debug : Thread JobWorker start, auto delete: true 2025-08-10 15:14:51.391 T:1108 debug : [threads] name: 'JobWorker' priority: '1' 2025-08-10 15:14:51.391 T:771 debug : CDirectoryProvider[addons://sources/game/]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[addons://sources/executable/]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[addons://sources/image/]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[library://video/]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[sources://video/]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[special://videoplaylists/]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[pvr://tv/]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[pvr://channels/tv/*?view=lastplayed]: refreshing.. 2025-08-10 15:14:51.392 T:771 debug : CDirectoryProvider[pvr://channels/tv]: refreshing.. 2025-08-10 15:14:51.393 T:771 debug : CDirectoryProvider[pvr://radio/]: refreshing.. 2025-08-10 15:14:51.393 T:771 debug : CDirectoryProvider[pvr://channels/radio/*?view=lastplayed]: refreshing.. 2025-08-10 15:14:51.393 T:771 debug : CDirectoryProvider[pvr://channels/radio]: refreshing.. 2025-08-10 15:14:51.393 T:771 debug : CDirectoryProvider[favourites://]: refreshing.. 2025-08-10 15:14:51.393 T:771 debug : CDirectoryProvider[sources://pictures/]: refreshing.. 2025-08-10 15:14:51.393 T:771 debug : CDirectoryProvider[addons://sources/game/]: refreshing.. 2025-08-10 15:14:51.398 T:1063 debug : CAddonDatabase: SELECT repo.id FROM repo .. took 1 ms 2025-08-10 15:14:51.428 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:51.433 T:1077 debug : CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/) 2025-08-10 15:14:51.434 T:1077 debug : Getting Directory (special://profile/playlists/video) 2025-08-10 15:14:51.435 T:1077 debug : Getting Directory (special://profile/playlists/mixed) 2025-08-10 15:14:51.436 T:1077 debug : CMultiPathDirectory::MergeItems, items = 0 2025-08-10 15:14:51.439 T:851 debug : request received for /jsonrpc 2025-08-10 15:14:51.517 T:1063 debug : CAddonDatabase: query SELECT addons.*, repo.addonID AS repoID FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon JOIN repo ON repo.id=addonlinkrepo.idRepo WHERE addonlinkrepo.idRepo IN (1,2) ORDER BY repo.addonID, addons.addonID returned 1349 rows in 119 ms 2025-08-10 15:14:52.246 T:1063 debug : CAddonDatabase::GetAddons took 847 ms 2025-08-10 15:14:52.312 T:1063 debug : CAddonMgr::GetAvailableUpdatesOrOutdatedAddons took 920 ms 2025-08-10 15:14:52.928 T:804 debug : EventClient: button name "up" map "R1" pressed 2025-08-10 15:14:52.932 T:771 debug : HandleKey: 166 (0xa6, obc89) pressed, window 10000, action is Up 2025-08-10 15:14:52.952 T:851 debug : request received for /jsonrpc