2023-12-22 14:45:17.688 T:10224    info <general>: -----------------------------------------------------------------------
2023-12-22 14:45:17.688 T:10224    info <general>: Starting Kodi (20.2 (20.2.0) Git:20230629-5f418d0b13). Platform: Windows NT x86 64-bit
2023-12-22 14:45:17.689 T:10224    info <general>: Using Release Kodi x64
2023-12-22 14:45:17.689 T:10224    info <general>: Kodi compiled 2023-06-29 by MSVC 193431937 for Windows NT x86 64-bit version 10.0 (0x0A00000B)
2023-12-22 14:45:17.689 T:10224    info <general>: Running on Windows 10 22H2, kernel: Windows NT x86 64-bit version 10.0.19045.3803
2023-12-22 14:45:17.689 T:10224    info <general>: FFmpeg version/source: 4.4.1-Kodi
2023-12-22 14:45:17.689 T:10224    info <general>: Host CPU: Intel(R) Core(TM) i3-7020U CPU @ 2.30GHz, 4 cores available
2023-12-22 14:45:17.690 T:10224    info <general>: System has 8.0 GB of RAM installed
2023-12-22 14:45:17.690 T:10224    info <general>: Desktop Resolution: 1920x1080 32Bit at 60Hz
2023-12-22 14:45:17.690 T:10224    info <general>: Running with restricted rights
2023-12-22 14:45:17.690 T:10224    info <general>: Aero is enabled
2023-12-22 14:45:17.690 T:10224    info <general>: Display is not HDR capable or cannot be detected
2023-12-22 14:45:17.690 T:10224    info <general>: special://xbmc/ is mapped to: C:\Program Files\Kodi20
2023-12-22 14:45:17.690 T:10224    info <general>: special://xbmcbin/ is mapped to: C:\Program Files\Kodi20
2023-12-22 14:45:17.691 T:10224    info <general>: special://xbmcbinaddons/ is mapped to: C:\Program Files\Kodi20/addons
2023-12-22 14:45:17.691 T:10224    info <general>: special://masterprofile/ is mapped to: C:\Users\axelk\AppData\Roaming\Kodi\userdata
2023-12-22 14:45:17.691 T:10224    info <general>: special://home/ is mapped to: C:\Users\axelk\AppData\Roaming\Kodi\
2023-12-22 14:45:17.691 T:10224    info <general>: special://temp/ is mapped to: C:\Users\axelk\AppData\Roaming\Kodi\cache
2023-12-22 14:45:17.691 T:10224    info <general>: special://logpath/ is mapped to: C:\Users\axelk\AppData\Roaming\Kodi\
2023-12-22 14:45:17.691 T:10224    info <general>: Webserver extra whitelist paths: 
2023-12-22 14:45:17.691 T:10224    info <general>: The executable running is: C:\Program Files\Kodi20\kodi.exe
2023-12-22 14:45:17.700 T:10224    info <general>: Local hostname: LAPTOP-FJTKJNQQ
2023-12-22 14:45:17.700 T:10224    info <general>: Log File is located: C:\Users\axelk\AppData\Roaming\Kodi\kodi.log
2023-12-22 14:45:17.700 T:10224    info <general>: -----------------------------------------------------------------------
2023-12-22 14:45:17.700 T:10224    info <general>: loading settings
2023-12-22 14:45:17.702 T:10224    info <general>: special://profile/ is mapped to: special://masterprofile/
2023-12-22 14:45:17.717 T:10224    info <general>: No settings file to load (special://xbmc/system/advancedsettings.xml)
2023-12-22 14:45:17.718 T:10224    info <general>: No settings file to load (special://masterprofile/advancedsettings.xml)
2023-12-22 14:45:17.718 T:10224    info <general>: Default Video Player: VideoPlayer
2023-12-22 14:45:17.718 T:10224    info <general>: Default Audio Player: paplayer
2023-12-22 14:45:17.718 T:10224    info <general>: Enabled debug logging due to GUI setting (2)
2023-12-22 14:45:17.721 T:10224   debug <general>: CSkinSettings: no <skinsettings> tag found
2023-12-22 14:45:17.722 T:10224    info <general>: creating subdirectories
2023-12-22 14:45:17.722 T:10224    info <general>: userdata folder: special://masterprofile/
2023-12-22 14:45:17.722 T:10224    info <general>: recording folder: 
2023-12-22 14:45:17.722 T:10224    info <general>: screenshots folder: 
2023-12-22 14:45:17.733 T:10224    info <general>: Running database version Addons33
2023-12-22 14:45:17.777 T:10224   debug <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: peripheral.joystick
2023-12-22 14:45:17.803 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'game.controller.default' already present with version 1.0.39 at 'C:\Program Files\Kodi20\addons\game.controller.default\' replaced with version 1.0.40 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.default\'
2023-12-22 14:45:17.805 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'game.controller.keyboard' already present with version 1.1.27 at 'C:\Program Files\Kodi20\addons\game.controller.keyboard\' replaced with version 1.1.30 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.keyboard\'
2023-12-22 14:45:17.806 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'game.controller.mouse' already present with version 1.0.22 at 'C:\Program Files\Kodi20\addons\game.controller.mouse\' replaced with version 1.0.23 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.mouse\'
2023-12-22 14:45:17.808 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'game.controller.snes' already present with version 1.0.37 at 'C:\Program Files\Kodi20\addons\game.controller.snes\' replaced with version 1.0.39 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.snes\'
2023-12-22 14:45:17.812 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'metadata.album.universal' already present with version 3.1.4 at 'C:\Program Files\Kodi20\addons\metadata.album.universal\' replaced with version 3.1.9 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.album.universal\'
2023-12-22 14:45:17.815 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'metadata.artists.universal' already present with version 4.3.3 at 'C:\Program Files\Kodi20\addons\metadata.artists.universal\' replaced with version 4.3.11 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.artists.universal\'
2023-12-22 14:45:17.816 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'metadata.common.fanart.tv' already present with version 3.6.3 at 'C:\Program Files\Kodi20\addons\metadata.common.fanart.tv\' replaced with version 3.6.4 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.common.fanart.tv\'
2023-12-22 14:45:17.818 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'metadata.themoviedb.org.python' already present with version 1.3.1+matrix.1 at 'C:\Program Files\Kodi20\addons\metadata.themoviedb.org.python\' replaced with version 2.1.0 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.themoviedb.org.python\'
2023-12-22 14:45:17.820 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'metadata.tvshows.themoviedb.org.python' already present with version 1.6.0 at 'C:\Program Files\Kodi20\addons\metadata.tvshows.themoviedb.org.python\' replaced with version 1.6.5 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.tvshows.themoviedb.org.python\'
2023-12-22 14:45:17.823 T:10224   debug <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: Addon 'service.xbmc.versioncheck' already present with version 0.5.19+matrix.1 at 'C:\Program Files\Kodi20\addons\service.xbmc.versioncheck\' replaced with version 0.5.28+matrix.1 at 'C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\'
2023-12-22 14:45:17.827 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: audioencoder.kodi.builtin.aac v1.0.2 installed
2023-12-22 14:45:17.827 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: audioencoder.kodi.builtin.wma v1.0.2 installed
2023-12-22 14:45:17.828 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: game.controller.default v1.0.40 installed
2023-12-22 14:45:17.828 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: game.controller.keyboard v1.1.30 installed
2023-12-22 14:45:17.829 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: game.controller.mouse v1.0.23 installed
2023-12-22 14:45:17.829 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: game.controller.snes v1.0.39 installed
2023-12-22 14:45:17.829 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.global.audioengine v1.1.1 installed
2023-12-22 14:45:17.830 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.global.filesystem v1.1.8 installed
2023-12-22 14:45:17.830 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.global.general v1.0.5 installed
2023-12-22 14:45:17.831 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.global.gui v5.15.0 installed
2023-12-22 14:45:17.831 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.global.main v2.0.2 installed
2023-12-22 14:45:17.831 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.global.network v1.0.4 installed
2023-12-22 14:45:17.832 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.global.tools v1.0.4 installed
2023-12-22 14:45:17.832 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.audiodecoder v4.0.0 installed
2023-12-22 14:45:17.832 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.audioencoder v3.0.0 installed
2023-12-22 14:45:17.833 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.game v3.0.0 installed
2023-12-22 14:45:17.833 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.imagedecoder v3.0.1 installed
2023-12-22 14:45:17.833 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.inputstream v3.2.0 installed
2023-12-22 14:45:17.834 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.peripheral v2.0.0 installed
2023-12-22 14:45:17.834 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.pvr v8.2.0 installed
2023-12-22 14:45:17.834 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.screensaver v2.2.0 installed
2023-12-22 14:45:17.835 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.vfs v3.0.1 installed
2023-12-22 14:45:17.835 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.videocodec v2.0.3 installed
2023-12-22 14:45:17.836 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.binary.instance.visualization v4.0.0 installed
2023-12-22 14:45:17.836 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: kodi.resource v1.0.0 installed
2023-12-22 14:45:17.836 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.album.universal v3.1.9 installed
2023-12-22 14:45:17.837 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.artists.universal v4.3.11 installed
2023-12-22 14:45:17.837 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.common.allmusic.com v3.2.2 installed
2023-12-22 14:45:17.838 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.common.fanart.tv v3.6.4 installed
2023-12-22 14:45:17.838 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.common.musicbrainz.org v2.2.4 installed
2023-12-22 14:45:17.839 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.common.theaudiodb.com v2.0.3 installed
2023-12-22 14:45:17.839 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.generic.albums v1.0.14 installed
2023-12-22 14:45:17.840 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.generic.artists v1.0.14 installed
2023-12-22 14:45:17.840 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.local v1.0.1 installed
2023-12-22 14:45:17.841 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.themoviedb.org.python v2.1.0 installed
2023-12-22 14:45:17.841 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: metadata.tvshows.themoviedb.org.python v1.6.5 installed
2023-12-22 14:45:17.842 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: peripheral.joystick v20.1.9 installed
2023-12-22 14:45:17.842 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: repository.xbmc.org v3.3.1 installed
2023-12-22 14:45:17.843 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: resource.images.weathericons.default v1.1.9 installed
2023-12-22 14:45:17.843 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: resource.language.en_gb v2.0.2 installed
2023-12-22 14:45:17.843 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: resource.uisounds.kodi v1.0.1 installed
2023-12-22 14:45:17.844 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: screensaver.xbmc.builtin.black v1.0.34 installed
2023-12-22 14:45:17.844 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: screensaver.xbmc.builtin.dim v1.0.66 installed
2023-12-22 14:45:17.844 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: script.module.pil v5.1.0 installed
2023-12-22 14:45:17.845 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: script.module.pycryptodome v3.4.3 installed
2023-12-22 14:45:17.845 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: service.xbmc.versioncheck v0.5.28+matrix.1 installed
2023-12-22 14:45:17.846 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: skin.estouchy v3.0.8 installed
2023-12-22 14:45:17.846 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: skin.estuary v3.0.10 installed
2023-12-22 14:45:17.846 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: webinterface.default v19.x-2.4.8 installed
2023-12-22 14:45:17.847 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: xbmc.addon v20.2.0 installed
2023-12-22 14:45:17.847 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: xbmc.core v0.1.0 installed
2023-12-22 14:45:17.847 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: xbmc.gui v5.16.0 installed
2023-12-22 14:45:17.848 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: xbmc.json v13.0.0 installed
2023-12-22 14:45:17.848 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: xbmc.metadata v2.1.0 installed
2023-12-22 14:45:17.848 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: xbmc.python v3.0.1 installed
2023-12-22 14:45:17.848 T:10224    info <general>: CAddonMgr::ADDON::CAddonMgr::FindAddons: xbmc.webinterface v1.0.0 installed
2023-12-22 14:45:17.850 T:10224   debug <general>: CFavourites::Load - no system favourites found, skipping
2023-12-22 14:45:17.850 T:10224   debug <general>: CFavourites::Load - no userdata favourites found, skipping
2023-12-22 14:45:17.851 T:10364   debug <general>: Thread CWin32PowerStateWorker start, auto delete: false
2023-12-22 14:45:17.855 T:10224   debug <general>: CWIN32Util::GetDriveStatus: Requesting status for drive \\.\E:.
2023-12-22 14:45:17.855 T:10224   debug <general>: CWIN32Util::GetDriveStatus: Requesting media status for drive \\.\E:.
2023-12-22 14:45:17.860 T:10224    info <general>: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
2023-12-22 14:45:17.860 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
2023-12-22 14:45:17.861 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
2023-12-22 14:45:17.861 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
2023-12-22 14:45:17.862 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
2023-12-22 14:45:17.862 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
2023-12-22 14:45:17.863 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
2023-12-22 14:45:17.864 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
2023-12-22 14:45:17.864 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
2023-12-22 14:45:17.865 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
2023-12-22 14:45:17.865 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
2023-12-22 14:45:17.865 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Estonian QWERTY" successfully loaded
2023-12-22 14:45:17.866 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
2023-12-22 14:45:17.867 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
2023-12-22 14:45:17.867 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
2023-12-22 14:45:17.868 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
2023-12-22 14:45:17.868 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
2023-12-22 14:45:17.868 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
2023-12-22 14:45:17.869 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
2023-12-22 14:45:17.869 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded
2023-12-22 14:45:17.870 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
2023-12-22 14:45:17.871 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Italian ABC" successfully loaded
2023-12-22 14:45:17.872 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
2023-12-22 14:45:17.872 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
2023-12-22 14:45:17.873 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
2023-12-22 14:45:17.873 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
2023-12-22 14:45:17.874 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Persian QWERTY" successfully loaded
2023-12-22 14:45:17.874 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
2023-12-22 14:45:17.875 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Portuguese (Brazil) QWERTY" successfully loaded
2023-12-22 14:45:17.875 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
2023-12-22 14:45:17.876 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
2023-12-22 14:45:17.876 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
2023-12-22 14:45:17.877 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
2023-12-22 14:45:17.877 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
2023-12-22 14:45:17.878 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
2023-12-22 14:45:17.878 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
2023-12-22 14:45:17.878 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
2023-12-22 14:45:17.879 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovenian QWERTZ" successfully loaded
2023-12-22 14:45:17.879 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
2023-12-22 14:45:17.880 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
2023-12-22 14:45:17.881 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Thai Kedmanee" successfully loaded
2023-12-22 14:45:17.881 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
2023-12-22 14:45:17.882 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
2023-12-22 14:45:17.882 T:10224   debug <general>: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
2023-12-22 14:45:17.882 T:10224   debug <general>: CApplication::CApplication::CreateGUI - trying to init default windowing system
2023-12-22 14:45:17.883 T:6432    debug <general>: Thread RemoteControl start, auto delete: false
2023-12-22 14:45:17.883 T:6432     info <general>: Loading special://xbmc/system/IRSSmap.xml
2023-12-22 14:45:17.884 T:6432     info <general>: * Adding remote mapping for device 'Microsoft MCE'
2023-12-22 14:45:17.884 T:6432     info <general>: * Adding remote mapping for device 'Abstract'
2023-12-22 14:45:17.884 T:6432    debug <general>: CIRTranslator::Load - no userdata IRSSmap.xml found, skipping
2023-12-22 14:45:17.885 T:10224    info <general>: Primary mode: Generic PnP Monitor #1: 1920x1080 @ 60.00Hz
2023-12-22 14:45:17.886 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 320x200 @ 60.00Hz
2023-12-22 14:45:17.887 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 320x240 @ 60.00Hz
2023-12-22 14:45:17.888 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 400x300 @ 60.00Hz
2023-12-22 14:45:17.889 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 512x384 @ 60.00Hz
2023-12-22 14:45:17.889 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 640x400 @ 60.00Hz
2023-12-22 14:45:17.890 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 640x480 @ 60.00Hz
2023-12-22 14:45:17.891 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 800x600 @ 60.00Hz
2023-12-22 14:45:17.892 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1024x768 @ 60.00Hz
2023-12-22 14:45:17.892 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1280x720 @ 60.00Hz
2023-12-22 14:45:17.893 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1280x768 @ 60.00Hz
2023-12-22 14:45:17.894 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1280x800 @ 60.00Hz
2023-12-22 14:45:17.894 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1280x1024 @ 60.00Hz
2023-12-22 14:45:17.895 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1360x768 @ 60.00Hz
2023-12-22 14:45:17.895 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1366x768 @ 60.00Hz
2023-12-22 14:45:17.896 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1400x1050 @ 60.00Hz
2023-12-22 14:45:17.896 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1440x900 @ 60.00Hz
2023-12-22 14:45:17.897 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1600x900 @ 60.00Hz
2023-12-22 14:45:17.897 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1680x1050 @ 60.00Hz
2023-12-22 14:45:17.898 T:6432    debug <general>: CIRServerSuite::Connect: connecting to: ::1:24000 ...
2023-12-22 14:45:17.898 T:10224    info <general>: Additional mode: Generic PnP Monitor #1: 1920x1080 @ 60.00Hz
2023-12-22 14:45:17.898 T:10224    info <general>: CApplication::CApplication::CreateGUI - using the default windowing system
2023-12-22 14:45:17.898 T:10224    info <general>: Checking resolution 16
2023-12-22 14:45:17.905 T:10224   debug <general>: DX::DeviceResources::HandleOutputChange: selected Intel(R) HD Graphics 620 adapter. 
2023-12-22 14:45:17.905 T:10224   debug <general>: CWinSystemWin32DX::InitHooks: Hooking into UserModeDriver on device \Registry\Machine\System\CurrentControlSet\Control\Video\{9D4C91FE-F5B7-11EA-9343-482AE305ECF0}\0000. 
2023-12-22 14:45:17.911 T:10224   debug <general>: CWinSystemWin32DX::InitHooks: D3D11 hook installed and activated.
2023-12-22 14:45:17.918 T:10224   debug <general>: DX::DeviceResources::CreateDeviceResources: creating DirectX 11 device.
2023-12-22 14:45:17.919 T:10224   debug <general>: HookOpenAdapter10_2: hook into pOpenData->pAdapterFuncs->pfnCreateDevice
2023-12-22 14:45:17.944 T:10224   debug <general>: HookCreateDevice: hook into pCreateData->pDeviceFuncs->pfnCreateResource
2023-12-22 14:45:17.945 T:10224    info <general>: DX::DeviceResources::CheckNV12SharedTexturesSupport: extended NV12 shared textures is supported
2023-12-22 14:45:17.946 T:10224    info <general>: DX::DeviceResources::CreateDeviceResources: device is created on adapter 'Intel(R) HD Graphics 620' with D3D_FEATURE_LEVEL_12_1
2023-12-22 14:45:17.946 T:10224    info <general>: DX::DeviceResources::GetVideoDriverVersion: video driver version is Intel 24.20.100.6286
2023-12-22 14:45:17.946 T:10224    info <general>: DX::DeviceResources::CheckDXVA2SharedDecoderSurfaces: DXVA2 shared decoder surfaces is supported
2023-12-22 14:45:17.950 T:10224   debug <general>: DX::DeviceResources::ReleaseBackBuffer: release buffers.
2023-12-22 14:45:17.951 T:10224   debug <general>: DX::DeviceResources::SetLogicalSize: receive changing logical size to 1920.000000 x 1080.000000
2023-12-22 14:45:17.951 T:10224   debug <general>: DX::DeviceResources::SetLogicalSize: change logical size to 1920.000000 x 1080.000000
2023-12-22 14:45:17.951 T:10224   debug <general>: DX::DeviceResources::ResizeBuffers: resize buffers.
2023-12-22 14:45:17.952 T:10224   debug <general>: CWinSystemWin32DX::FixRefreshRateIfNecessary: refreshRate: 60.0524, desired: 60.0000, deviation: 0.00087, fixRequired: yes, 1
2023-12-22 14:45:17.952 T:10224   debug <general>: CWinSystemWin32DX::FixRefreshRateIfNecessary: refreshRate fix applied -> 60.000
2023-12-22 14:45:17.952 T:10224   debug <general>: CWinSystemWin32DX::FixRefreshRateIfNecessary: refreshRate: 60.0000, desired: 60.0000, deviation: 0.00000, fixRequired: no, 1
2023-12-22 14:45:17.956 T:10224    info <general>: Skipped 4 duplicate messages..
2023-12-22 14:45:17.956 T:10224    info <general>: DX::DeviceResources::ResizeBuffers: 8 bit swapchain is used with 6 flip discard buffers and SDR output
2023-12-22 14:45:17.956 T:10224   debug <general>: DX::DeviceResources::ResizeBuffers: end resize buffers.
2023-12-22 14:45:17.956 T:10224   debug <general>: DX::DeviceResources::CreateBackBuffer: create buffers.
2023-12-22 14:45:17.957 T:10224   debug <general>: CWinSystemWin32::AdjustWindow: adjusting window if required.
2023-12-22 14:45:17.957 T:10224   debug <general>: CWinSystemWin32::AdjustWindow: resizing due to size change (0,0,1920,1080 fullscreen)->(0,0,1920,1080 fullscreen)
2023-12-22 14:45:17.983 T:10224   debug <general>: CD3DVertexShader::CreateInternal: creating vertex shader.
2023-12-22 14:45:17.984 T:10224   debug <general>: CD3DVertexShader::CreateInternal: creating input layout.
2023-12-22 14:45:17.984 T:10224   debug <general>: CD3DPixelShader::CreateInternal: Create the pixel shader.
2023-12-22 14:45:17.987 T:10224    info <general>: Skipped 8 duplicate messages..
2023-12-22 14:45:17.987 T:10224    info <general>: RetroPlayer[RENDER]: Registering renderer factory for DirectX
2023-12-22 14:45:17.987 T:10224   debug <general>: CWinSystemWin32::SetFullScreen: (windowed fullscreen) with size 1920x1080, refresh 60.000000
2023-12-22 14:45:17.987 T:10224   debug <general>: DX::DeviceResources::ReleaseBackBuffer: release buffers.
2023-12-22 14:45:17.988 T:10224   debug <general>: DX::DeviceResources::SetFullScreen: switching from (1920 x 1080) to (1920 x 1080)
2023-12-22 14:45:17.988 T:10224   debug <general>: DX::DeviceResources::SetFullScreen: switching done.
2023-12-22 14:45:17.988 T:10224   debug <general>: DX::DeviceResources::ResizeBuffers: resize buffers.
2023-12-22 14:45:17.989 T:10224   debug <general>: CWinSystemWin32DX::FixRefreshRateIfNecessary: refreshRate: 60.0524, desired: 60.0000, deviation: 0.00087, fixRequired: yes, 1
2023-12-22 14:45:17.989 T:10224   debug <general>: CWinSystemWin32DX::FixRefreshRateIfNecessary: refreshRate fix applied -> 60.000
2023-12-22 14:45:17.989 T:10224   debug <general>: CWinSystemWin32DX::FixRefreshRateIfNecessary: refreshRate: 60.0000, desired: 60.0000, deviation: 0.00000, fixRequired: no, 1
2023-12-22 14:45:17.991 T:10224    info <general>: Skipped 4 duplicate messages..
2023-12-22 14:45:17.991 T:10224   debug <general>: DX::DeviceResources::ResizeBuffers: end resize buffers.
2023-12-22 14:45:17.991 T:10224   debug <general>: CWinSystemWin32::AdjustWindow: adjusting window if required.
2023-12-22 14:45:17.991 T:10224   debug <general>: DX::DeviceResources::CreateBackBuffer: create buffers.
2023-12-22 14:45:17.991 T:10224   debug <general>: guilib: Fill viewport on change for solving rendering passes
2023-12-22 14:45:17.994 T:10224   debug <general>: ffmpeg[0x0]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2023-12-22 14:45:18.065 T:10224    info <general>: load keymapping
2023-12-22 14:45:18.067 T:10224    info <general>: Loading special://xbmc/system/keymaps/appcommand.xml
2023-12-22 14:45:18.067 T:10224    info <general>: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
2023-12-22 14:45:18.068 T:10224    info <general>: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
2023-12-22 14:45:18.069 T:10224    info <general>: Loading special://xbmc/system/keymaps/customcontroller.SiriRemote.xml
2023-12-22 14:45:18.070 T:10224    info <general>: Loading special://xbmc/system/keymaps/gamepad.xml
2023-12-22 14:45:18.071 T:10224    info <general>: Loading special://xbmc/system/keymaps/joystick.xml
2023-12-22 14:45:18.073 T:10224    info <general>: Loading special://xbmc/system/keymaps/keyboard.xml
2023-12-22 14:45:18.076 T:10224    info <general>: Loading special://xbmc/system/keymaps/mouse.xml
2023-12-22 14:45:18.077 T:10224    info <general>: Loading special://xbmc/system/keymaps/remote.xml
2023-12-22 14:45:18.078 T:10224    info <general>: Loading special://xbmc/system/keymaps/touchscreen.xml
2023-12-22 14:45:18.080 T:10224    info <general>: GUI format 1920x1080, Display Generic PnP Monitor #1: 1920x1080 @ 60.00Hz
2023-12-22 14:45:18.080 T:7348    debug <general>: Thread ActiveAE start, auto delete: false
2023-12-22 14:45:18.081 T:14548   debug <general>: Thread AESink start, auto delete: false
2023-12-22 14:45:18.114 T:7348     info <general>: Found 2 Lists of Devices
2023-12-22 14:45:18.114 T:7348     info <general>: Enumerated DIRECTSOUND devices:
2023-12-22 14:45:18.114 T:7348     info <general>:     Device 1
2023-12-22 14:45:18.114 T:7348     info <general>:         m_deviceName      : {76E068FF-DB7A-4A45-9AB2-8D82F6141B19}
2023-12-22 14:45:18.114 T:7348     info <general>:         m_displayName     : Speakers - Lautsprecher (Synaptics SmartAudio HD)
2023-12-22 14:45:18.114 T:7348     info <general>:         m_displayNameExtra: DIRECTSOUND: Lautsprecher (Synaptics SmartAudio HD)
2023-12-22 14:45:18.114 T:7348     info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2023-12-22 14:45:18.114 T:7348     info <general>:         m_channels        : FL, FR
2023-12-22 14:45:18.114 T:7348     info <general>:         m_sampleRates     : 48000
2023-12-22 14:45:18.114 T:7348     info <general>:         m_dataFormats     : AE_FMT_FLOAT
2023-12-22 14:45:18.114 T:7348     info <general>:         m_streamTypes     : No passthrough capabilities
2023-12-22 14:45:18.115 T:7348     info <general>:     Device 2
2023-12-22 14:45:18.115 T:7348     info <general>:         m_deviceName      : default
2023-12-22 14:45:18.115 T:7348     info <general>:         m_displayName     : default
2023-12-22 14:45:18.115 T:7348     info <general>:         m_displayNameExtra: 
2023-12-22 14:45:18.115 T:7348     info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2023-12-22 14:45:18.115 T:7348     info <general>:         m_channels        : FL, FR
2023-12-22 14:45:18.115 T:7348     info <general>:         m_sampleRates     : 48000
2023-12-22 14:45:18.115 T:7348     info <general>:         m_dataFormats     : AE_FMT_FLOAT
2023-12-22 14:45:18.115 T:7348     info <general>:         m_streamTypes     : No passthrough capabilities
2023-12-22 14:45:18.115 T:7348     info <general>: Enumerated WASAPI devices:
2023-12-22 14:45:18.115 T:7348     info <general>:     Device 1
2023-12-22 14:45:18.115 T:7348     info <general>:         m_deviceName      : {76E068FF-DB7A-4A45-9AB2-8D82F6141B19}
2023-12-22 14:45:18.115 T:7348     info <general>:         m_displayName     : Speakers - Lautsprecher (Synaptics SmartAudio HD)
2023-12-22 14:45:18.115 T:7348     info <general>:         m_displayNameExtra: WASAPI: Lautsprecher (Synaptics SmartAudio HD)
2023-12-22 14:45:18.115 T:7348     info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2023-12-22 14:45:18.115 T:7348     info <general>:         m_channels        : FL, FR
2023-12-22 14:45:18.115 T:7348     info <general>:         m_sampleRates     : 192000,96000,48000,44100
2023-12-22 14:45:18.115 T:7348     info <general>:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE
2023-12-22 14:45:18.115 T:7348     info <general>:         m_streamTypes     : No passthrough capabilities
2023-12-22 14:45:18.115 T:7348     info <general>:     Device 2
2023-12-22 14:45:18.115 T:7348     info <general>:         m_deviceName      : default
2023-12-22 14:45:18.116 T:7348     info <general>:         m_displayName     : default
2023-12-22 14:45:18.116 T:7348     info <general>:         m_displayNameExtra: 
2023-12-22 14:45:18.116 T:7348     info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2023-12-22 14:45:18.116 T:7348     info <general>:         m_channels        : FL, FR
2023-12-22 14:45:18.116 T:7348     info <general>:         m_sampleRates     : 192000,96000,48000,44100
2023-12-22 14:45:18.116 T:7348     info <general>:         m_dataFormats     : AE_FMT_S24NE4MSB,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE
2023-12-22 14:45:18.116 T:7348     info <general>:         m_streamTypes     : No passthrough capabilities
2023-12-22 14:45:18.116 T:14548    info <general>: CActiveAESink::OpenSink - initialize sink
2023-12-22 14:45:18.116 T:14548   debug <general>: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
2023-12-22 14:45:18.146 T:14548   debug <general>: CAESinkDirectSound::Initialize: Using Window handle: 0x170544
2023-12-22 14:45:18.152 T:14548   debug <general>: CAESinkDirectSound::Initialize: secondary buffer created
2023-12-22 14:45:18.153 T:14548   debug <general>: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
2023-12-22 14:45:18.153 T:14548   debug <general>:   Audio Device    : Lautsprecher (Synaptics SmartAudio HD)
2023-12-22 14:45:18.153 T:14548   debug <general>:   Sample Rate     : 44100
2023-12-22 14:45:18.153 T:14548   debug <general>:   Sample Format   : AE_FMT_FLOAT
2023-12-22 14:45:18.153 T:14548   debug <general>:   Bits Per Sample : 32
2023-12-22 14:45:18.153 T:14548   debug <general>:   Valid Bits/Samp : 32
2023-12-22 14:45:18.153 T:14548   debug <general>:   Channel Count   : 2
2023-12-22 14:45:18.153 T:14548   debug <general>:   Block Align     : 8
2023-12-22 14:45:18.154 T:14548   debug <general>:   Avg. Bytes Sec  : 352800
2023-12-22 14:45:18.154 T:14548   debug <general>:   Samples/Block   : 32
2023-12-22 14:45:18.154 T:14548   debug <general>:   Format cBSize   : 22
2023-12-22 14:45:18.154 T:14548   debug <general>:   Channel Layout  : FL, FR
2023-12-22 14:45:18.154 T:14548   debug <general>:   Channel Mask    : 3
2023-12-22 14:45:18.154 T:14548   debug <general>:   Frames          : 661
2023-12-22 14:45:18.154 T:14548   debug <general>:   Frame Size      : 8
2023-12-22 14:45:18.154 T:14548   debug <general>: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
2023-12-22 14:45:18.154 T:14548   debug <general>:   Output Device : default
2023-12-22 14:45:18.154 T:14548   debug <general>:   Sample Rate   : 44100
2023-12-22 14:45:18.154 T:14548   debug <general>:   Sample Format : AE_FMT_FLOAT
2023-12-22 14:45:18.154 T:14548   debug <general>:   Channel Count : 2
2023-12-22 14:45:18.154 T:14548   debug <general>:   Channel Layout: FL, FR
2023-12-22 14:45:18.154 T:14548   debug <general>:   Frames        : 661
2023-12-22 14:45:18.154 T:14548   debug <general>:   Frame Size    : 8
2023-12-22 14:45:18.155 T:7348  warning <general>: CActiveAE::ActiveAE::CActiveAE::StateMachine - signal: 0 from port: OutputControlPort not handled for state: 7
2023-12-22 14:45:18.155 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:18.155 T:10224    info <general>: CLangInfo: loading resource.language.en_gb language information...
2023-12-22 14:45:18.156 T:10224   debug <general>: trying to set locale to en-us
2023-12-22 14:45:18.198 T:10224    info <general>: global locale set to en-us
2023-12-22 14:45:18.198 T:10224    info <general>: CLangInfo: loading resource.language.en_gb language strings...
2023-12-22 14:45:18.200 T:10224   debug <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: resource://resource.language.en_gb/strings.po
2023-12-22 14:45:18.209 T:10224   debug <general>: LocalizeStrings: loaded 4278 strings from file resource://resource.language.en_gb/strings.po
2023-12-22 14:45:18.210 T:10224   debug <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files\Kodi20\addons\audioencoder.kodi.builtin.aac\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.210 T:10224   debug <general>: LocalizeStrings: loaded 3 strings from file C:\Program Files\Kodi20\addons\audioencoder.kodi.builtin.aac\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.211 T:10224   debug <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files\Kodi20\addons\audioencoder.kodi.builtin.wma\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.211 T:10224   debug <general>: LocalizeStrings: loaded 3 strings from file C:\Program Files\Kodi20\addons\audioencoder.kodi.builtin.wma\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.212 T:10224   debug <general>: LocalizeStrings: loaded 18 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.default\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.213 T:10224   debug <general>: LocalizeStrings: loaded 140 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.keyboard\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.214 T:10224   debug <general>: LocalizeStrings: loaded 11 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.mouse\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.215 T:10224   debug <general>: LocalizeStrings: loaded 13 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\game.controller.snes\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.215 T:10224   debug <general>: LocalizeStrings: loaded 17 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.album.universal\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.216 T:10224   debug <general>: LocalizeStrings: loaded 32 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.artists.universal\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.218 T:10224   debug <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files\Kodi20\addons\metadata.generic.albums\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.218 T:10224   debug <general>: LocalizeStrings: loaded 17 strings from file C:\Program Files\Kodi20\addons\metadata.generic.albums\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.219 T:10224   debug <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files\Kodi20\addons\metadata.generic.artists\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.219 T:10224   debug <general>: LocalizeStrings: loaded 15 strings from file C:\Program Files\Kodi20\addons\metadata.generic.artists\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.219 T:10224   debug <general>: LocalizeStrings: loaded 20 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.themoviedb.org.python\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.220 T:10224   debug <general>: LocalizeStrings: loaded 32 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\metadata.tvshows.themoviedb.org.python\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.221 T:10224   debug <general>: LocalizeStrings: loaded 9 strings from file C:\Program Files\Kodi20\addons\peripheral.joystick\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.222 T:10224   debug <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files\Kodi20\addons\screensaver.xbmc.builtin.dim\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.222 T:10224   debug <general>: LocalizeStrings: loaded 3 strings from file C:\Program Files\Kodi20\addons\screensaver.xbmc.builtin.dim\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.223 T:10224   debug <general>: LocalizeStrings: loaded 24 strings from file C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.225 T:12224   debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:45:18.225 T:12224   debug <general>: CDatabaseManager::Initialize, updating databases...
2023-12-22 14:45:18.226 T:12224    info <general>: Running database version Addons33
2023-12-22 14:45:18.228 T:12224    info <general>: Running database version ViewModes6
2023-12-22 14:45:18.229 T:12224    info <general>: Running database version Textures13
2023-12-22 14:45:18.233 T:12224    info <general>: Running database version MyMusic82
2023-12-22 14:45:18.235 T:14548   debug <general>: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
2023-12-22 14:45:18.237 T:12224    info <general>: Running database version MyVideos121
2023-12-22 14:45:18.239 T:12224    info <general>: Running database version TV40
2023-12-22 14:45:18.240 T:12224    info <general>: Running database version Epg16
2023-12-22 14:45:18.240 T:12224   debug <general>: CDatabaseManager::Initialize, updating databases... DONE
2023-12-22 14:45:18.299 T:10224    info <general>: Unloaded skin
2023-12-22 14:45:18.300 T:10224    info <general>:   load skin from: C:\Program Files\Kodi20\addons\skin.estuary\ (version: 3.0.10)
2023-12-22 14:45:18.301 T:10224    info <general>:   load fonts for skin...
2023-12-22 14:45:18.302 T:10224    info <general>: Loading skin includes from C:\Program Files\Kodi20\addons\skin.estuary\xml\Includes.xml
2023-12-22 14:45:18.351 T:10224    info <general>: GUIFontManager::LoadFonts: Loading fonts from 'C:\Program Files\Kodi20\addons\skin.estuary\xml\Font.xml'
2023-12-22 14:45:18.369 T:10224   debug <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files\Kodi20\addons\skin.estuary\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.370 T:10224   debug <general>: LocalizeStrings: loaded 168 strings from file C:\Program Files\Kodi20\addons\skin.estuary\language\resource.language.en_gb\strings.po
2023-12-22 14:45:18.370 T:10224    info <general>: ADDON::CSkinInfo::LoadTimers: Trying to load skin timers from C:\Program Files\Kodi20\addons\skin.estuary\xml\Timers.xml
2023-12-22 14:45:18.371 T:10224    info <general>:   load new skin...
2023-12-22 14:45:18.371 T:10224    info <general>: Loading custom window XMLs from skin path C:\Program Files\Kodi20\addons\skin.estuary\xml
2023-12-22 14:45:18.379 T:10224   debug <general>: Load Skin XML: 7.68 ms
2023-12-22 14:45:18.379 T:10224    info <general>:   initialize new skin...
2023-12-22 14:45:18.379 T:10224   debug <general>: guilib: Fill viewport on change for solving rendering passes
2023-12-22 14:45:18.379 T:10224    info <general>: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.380 T:10224    info <general>: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.382 T:10224   debug <general>: CTextureBundleXBT::OpenBundle - Opened bundle C:\Program Files\Kodi20\addons\skin.estuary\media\Textures.xbt
2023-12-22 14:45:18.383 T:10224    info <general>: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.384 T:10224    info <general>: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.385 T:10224    info <general>: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.397 T:10224    info <general>: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.399 T:10224    info <general>: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.400 T:10224    info <general>: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: LOAD_ON_GUI_INIT
2023-12-22 14:45:18.410 T:10224    info <general>: Loading resource://resource.uisounds.kodi/sounds.xml
2023-12-22 14:45:18.438 T:10224    info <general>:   skin loaded...
2023-12-22 14:45:18.438 T:10224   debug <general>: Activating window ID: 12997
2023-12-22 14:45:18.438 T:10224   debug <general>: ------ Window Init () ------
2023-12-22 14:45:18.439 T:10224   debug <general>: Activating window ID: 12999
2023-12-22 14:45:18.439 T:10224   debug <general>: ------ Window Init (Startup.xml) ------
2023-12-22 14:45:18.439 T:10224    info <general>: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
2023-12-22 14:45:18.439 T:10224   debug <general>: Activating window ID: 10000
2023-12-22 14:45:18.439 T:10224   debug <general>: ------ Window Deinit (Startup.xml) ------
2023-12-22 14:45:18.440 T:10224   debug <general>: ------ Window Init (Home.xml) ------
2023-12-22 14:45:18.440 T:10224    info <general>: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:45:18.523 T:10224   debug <general>: CDirectoryProvider[addons://]: refreshing..
2023-12-22 14:45:18.523 T:10224   debug <general>: CDirectoryProvider[addons://sources/video/]: refreshing..
2023-12-22 14:45:18.524 T:6616    debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:45:18.524 T:10224   debug <general>: CDirectoryProvider[addons://sources/audio/]: refreshing..
2023-12-22 14:45:18.524 T:13408   debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:45:18.524 T:10224   debug <general>: CDirectoryProvider[addons://sources/game/]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[addons://sources/executable/]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[addons://sources/image/]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[library://video/]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[sources://video/]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[special://videoplaylists/]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[favourites://]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[sources://pictures/]: refreshing..
2023-12-22 14:45:18.525 T:10224   debug <general>: CDirectoryProvider[addons://sources/game/]: refreshing..
2023-12-22 14:45:18.526 T:12224   debug <general>: CAddonDatabase: SELECT repo.id FROM repo .. took 0 ms
2023-12-22 14:45:18.549 T:12224   debug <general>: 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) ORDER BY repo.addonID, addons.addonID returned 996 rows in 22 ms
2023-12-22 14:45:18.713 T:12224   debug <general>: CAddonDatabase::GetAddons took 187 ms
2023-12-22 14:45:18.723 T:12224   debug <general>: ADDONS: repository.xbmc.org - 993 addon(s) loaded
2023-12-22 14:45:18.733 T:12224   debug <general>: CAddonRepos::BuildUpdateOrOutdatedList: Building update list from installed add-ons
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = audioencoder.kodi.builtin.aac / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.2
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = audioencoder.kodi.builtin.wma / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.2
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = game.controller.default / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.40
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = game.controller.keyboard / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.1.30
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = game.controller.mouse / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.23
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = game.controller.snes / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.39
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = metadata.album.universal / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.1.9
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = metadata.artists.universal / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 4.3.11
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = metadata.common.allmusic.com / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.2.2
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = metadata.common.fanart.tv / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.6.4
2023-12-22 14:45:18.734 T:12224   debug <general>: ADDONS: update check: addonID = metadata.common.musicbrainz.org / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.2.4
2023-12-22 14:45:18.735 T:12224   debug <general>: ADDONS: update check: addonID = metadata.common.theaudiodb.com / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.0.3
2023-12-22 14:45:18.735 T:12224   debug <general>: ADDONS: update check: addonID = metadata.generic.albums / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.14
2023-12-22 14:45:18.735 T:12224   debug <general>: ADDONS: update check: addonID = metadata.generic.artists / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.14
2023-12-22 14:45:18.735 T:12224   debug <general>: ADDONS: update check: addonID = metadata.local / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.1
2023-12-22 14:45:18.735 T:12224   debug <general>: ADDONS: update check: addonID = metadata.themoviedb.org.python / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.1.0
2023-12-22 14:45:18.735 T:12224   debug <general>: ADDONS: update check: addonID = metadata.tvshows.themoviedb.org.python / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.6.5
2023-12-22 14:45:18.736 T:12224   debug <general>: ADDONS: update check: addonID = peripheral.joystick / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 20.1.9
2023-12-22 14:45:18.736 T:12224   debug <general>: ADDONS: update check: addonID = repository.xbmc.org / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.3.1
2023-12-22 14:45:18.736 T:12224   debug <general>: ADDONS: update check: addonID = resource.images.weathericons.default / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.1.9
2023-12-22 14:45:18.736 T:12224   debug <general>: ADDONS: update check: addonID = resource.language.en_gb / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.0.2
2023-12-22 14:45:18.736 T:12224   debug <general>: ADDONS: update check: addonID = resource.uisounds.kodi / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.1
2023-12-22 14:45:18.736 T:12224   debug <general>: ADDONS: update check: addonID = screensaver.xbmc.builtin.black / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.34
2023-12-22 14:45:18.737 T:12224   debug <general>: ADDONS: update check: addonID = screensaver.xbmc.builtin.dim / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.66
2023-12-22 14:45:18.737 T:12224   debug <general>: ADDONS: update check: addonID = script.module.pil / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 5.1.0
2023-12-22 14:45:18.737 T:12224   debug <general>: ADDONS: update check: addonID = script.module.pycryptodome / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.4.3
2023-12-22 14:45:18.737 T:12224   debug <general>: ADDONS: update check: addonID = service.xbmc.versioncheck / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 0.5.28+matrix.1
2023-12-22 14:45:18.737 T:12224   debug <general>: ADDONS: update check: addonID = skin.estouchy / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.0.8
2023-12-22 14:45:18.737 T:12224   debug <general>: ADDONS: update check: addonID = skin.estuary / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.0.10
2023-12-22 14:45:18.737 T:12224   debug <general>: ADDONS: update check: addonID = webinterface.default / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 19.x-2.4.8
2023-12-22 14:45:18.737 T:12224   debug <general>: CAddonMgr::GetAvailableUpdatesOrOutdatedAddons took 213 ms
2023-12-22 14:45:18.759 T:6616    debug <general>: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
2023-12-22 14:45:18.759 T:6616    debug <general>: Getting Directory (special://profile/playlists/video)
2023-12-22 14:45:18.761 T:6616    debug <general>: Getting Directory (special://profile/playlists/mixed)
2023-12-22 14:45:18.763 T:6616    debug <general>: CMultiPathDirectory::MergeItems, items = 0
2023-12-22 14:45:18.764 T:6616    debug <general>: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
2023-12-22 14:45:18.773 T:6616    debug <general>: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum, dateAdded FROM album ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY recentalbums.dateAdded DESC, songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 
2023-12-22 14:45:18.775 T:6616    debug <general>: CMusicDatabase::GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY dateAdded DESC, albumview.idAlbum desc, albumartistview.iOrder 
2023-12-22 14:45:18.776 T:10224   debug <general>: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
2023-12-22 14:45:18.776 T:10224 warning <general>: JSONRPC: Could not parse type "Setting.Details.SettingList"
2023-12-22 14:45:18.776 T:10224    info <general>: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
2023-12-22 14:45:18.777 T:10224    info <general>: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
2023-12-22 14:45:18.777 T:6616    debug <general>: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
2023-12-22 14:45:18.792 T:6616    debug <general>: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
2023-12-22 14:45:18.799 T:10224    info <general>: JSONRPC v13.0.0: Successfully initialized
2023-12-22 14:45:18.801 T:10224   debug <general>: PERIPHERALS::CPeripherals::LoadMappings - loaded node "Motorola Nyxboard Hybrid"
2023-12-22 14:45:18.801 T:10224   debug <general>: PERIPHERALS::CPeripherals::LoadMappings - loaded node "CEC Adapter"
2023-12-22 14:45:18.801 T:10224   debug <general>: PERIPHERALS::CPeripherals::LoadMappings - loaded node "Pulse-Eight CEC Adapter"
2023-12-22 14:45:18.801 T:10224   debug <general>: PERIPHERALS::CPeripherals::LoadMappings - loaded node "iMON HID device"
2023-12-22 14:45:18.801 T:10224   debug <general>: PERIPHERALS::CPeripherals::LoadMappings - loaded node "WETEK Play remote"
2023-12-22 14:45:18.801 T:10224   debug <general>: PERIPHERALS::CPeripherals::LoadMappings - loaded node "OSMC RF Remote"
2023-12-22 14:45:18.801 T:10224   debug <general>: PERIPHERALS::CPeripherals::LoadMappings - loaded node "joystick"
2023-12-22 14:45:18.801 T:10224   debug <general>: Add-on bus: Registering add-on peripheral.joystick
2023-12-22 14:45:18.802 T:10224   debug <general>: PERIPHERAL - PERIPHERALS::CPeripheralAddon::CreateAddon - creating peripheral add-on instance 'Joystick Support'
2023-12-22 14:45:18.802 T:10224   debug <general>: ADDON: Dll Initializing - Joystick Support
2023-12-22 14:45:18.802 T:10224   debug <general>: SECTION:LoadDLL(C:\Program Files\Kodi20\addons\peripheral.joystick\peripheral.joystick.dll)
2023-12-22 14:45:18.808 T:10224   debug <general>: AddOnLog: peripheral.joystick: Loaded 14 joystick families with 97 total joysticks
2023-12-22 14:45:18.808 T:10224   debug <general>: Calling TransferSettings for: Joystick Support
2023-12-22 14:45:18.809 T:10224    info <general>: AddOnLog: peripheral.joystick: Enabling joystick interface "xinput"
2023-12-22 14:45:18.809 T:10224   debug <general>: AddOnLog: peripheral.joystick: Attempting to load XInput1_4.dll...
2023-12-22 14:45:18.810 T:10224   debug <general>: AddOnLog: peripheral.joystick: Loaded XInput DLL version 1.4
2023-12-22 14:45:18.810 T:10224   debug <general>: AddOnLog: peripheral.joystick: Loaded XInput symbols (GetState=00007FFE432E1750, GetStateEx=0000000000000000, SetState=00007FFE432E19F0, GetCaps=00007FFE432E1CC0, GetBatteryInformation=00007FFE432E20C0, PowerOff=0000000000000000)
2023-12-22 14:45:18.810 T:10224    info <general>: AddOnLog: peripheral.joystick: Enabling joystick interface "directinput"
2023-12-22 14:45:18.810 T:1304    debug <general>: Thread PeripBusUSB start, auto delete: false
2023-12-22 14:45:18.811 T:2348    debug <general>: Thread PeripBusCEC start, auto delete: false
2023-12-22 14:45:18.811 T:11028   debug <general>: Thread PeripBusAddon start, auto delete: false
2023-12-22 14:45:18.812 T:10224   debug <general>: PERIPHERALS::CPeripheral::Initialise - initialised peripheral on 'keyboard' with 1 features and 0 sub devices
2023-12-22 14:45:18.812 T:10224    info <general>: PERIPHERALS::CPeripheralBus::Register - new keyboard device registered on application->keyboard: Keyboard (0000:0000)
2023-12-22 14:45:18.812 T:10224   debug <general>: PERIPHERALS::CPeripheral::Initialise - initialised peripheral on 'mouse' with 1 features and 0 sub devices
2023-12-22 14:45:18.812 T:10224    info <general>: PERIPHERALS::CPeripheralBus::Register - new mouse device registered on application->mouse: Mouse (0000:0000)
2023-12-22 14:45:18.815 T:13736   debug <general>: Thread PeripEventScan start, auto delete: false
2023-12-22 14:45:18.815 T:10224   debug <general>: ContextMenuManager: addon menus reloaded.
2023-12-22 14:45:18.815 T:10224    info <general>: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerCoreConfig::<ctor>: created player VideoPlayer
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerCoreConfig::<ctor>: created player PAPlayer
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerCoreConfig::<ctor>: created player RetroPlayer
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: system rules
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: mms/udp
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: rtmp
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: rtsp
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: streams
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: mp2
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: dvd
2023-12-22 14:45:18.816 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: discimage
2023-12-22 14:45:18.817 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
2023-12-22 14:45:18.817 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: nsv
2023-12-22 14:45:18.817 T:10224   debug <general>: CPlayerSelectionRule::Initialize: creating rule: radio
2023-12-22 14:45:18.817 T:10224    info <general>: Loaded playercorefactory configuration
2023-12-22 14:45:18.817 T:10224    info <general>: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
2023-12-22 14:45:18.817 T:10224    info <general>: special://masterprofile/playercorefactory.xml does not exist. Skipping.
2023-12-22 14:45:18.817 T:10224    info <general>: removing tempfiles
2023-12-22 14:45:18.820 T:10224   debug <general>: CRepositoryUpdater: closest next update check at 12/23/2023 2:44:37 PM (in 86358 s)
2023-12-22 14:45:18.821 T:9700    debug <general>: Thread Timer start, auto delete: false
2023-12-22 14:45:18.821 T:10224   debug <general>: CServiceAddonManager: starting service.xbmc.versioncheck
2023-12-22 14:45:18.822 T:13652   debug <general>: Thread LanguageInvoker start, auto delete: false
2023-12-22 14:45:18.822 T:10224    info <general>: initialize done
2023-12-22 14:45:18.822 T:13652   debug <general>: initializing python engine.
2023-12-22 14:45:18.822 T:10224    info <general>: Running the application...
2023-12-22 14:45:18.824 T:10224   debug <general>: CNetworkBase::NetworkMessage - Starting network services
2023-12-22 14:45:18.826 T:1064    debug <general>: Thread EventServer start, auto delete: false
2023-12-22 14:45:18.827 T:1064     info <general>: ES: Starting UDP Event server on port 9777
2023-12-22 14:45:18.827 T:1064     info <general>: UDP: Listening on port 9777 (ipv6 : false)
2023-12-22 14:45:18.828 T:10224    info <general>: JSONRPC Server: Successfully initialized
2023-12-22 14:45:18.829 T:13840   debug <general>: Thread TCPServer start, auto delete: false
2023-12-22 14:45:18.839 T:10224    info <general>: [WS-Discovery]: Daemon started successfully.
2023-12-22 14:45:18.840 T:10224   debug <general>: CWinSystemWin32::AdjustWindow: adjusting window if required.
2023-12-22 14:45:18.841 T:10224   debug <general>: ffmpeg[0x0]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2023-12-22 14:45:18.910 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:18.910 T:10224   debug <general>: ------ Window Init () ------
2023-12-22 14:45:18.912 T:10224   debug <general>: ------ Window Init (Pointer.xml) ------
2023-12-22 14:45:18.940 T:13652   debug <general>: CPythonInvoker(0, C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib\runner.py): start processing
2023-12-22 14:45:19.028 T:13652   debug <general>: -->Python Interpreter Initialized<--
2023-12-22 14:45:19.028 T:13652   debug <general>: 
                                                   
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0, C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib\runner.py): the source file to load is "C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib\runner.py"
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0): full python path:
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0):   custom python path:
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0):     C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0):   default python path:
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0):     C:\Program Files\Kodi20\system\python\DLLs
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0):     C:\Program Files\Kodi20\system\python\Lib
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0):     C:\Program Files\Kodi20\system\python\Lib\site-packages
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0): adding args:
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0):  
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0, C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib\runner.py): entering source directory C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib
2023-12-22 14:45:19.028 T:13652   debug <general>: CPythonInvoker(0, C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib\runner.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 3.0.0 of the xbmc.python api
2023-12-22 14:45:19.518 T:13652   debug <general>: Version Check: Version 0.5.28+matrix.1 started
2023-12-22 14:45:19.935 T:6432    debug <general>: CIRServerSuite::Connect: connecting to: 127.0.0.1:24000 ...
2023-12-22 14:45:21.646 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:21.647 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:21.650 T:10224   debug <general>: ------ Window Deinit (Pointer.xml) ------
2023-12-22 14:45:21.717 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:21.964 T:6432    debug <general>: CIRServerSuite::Connect: failed to connect
2023-12-22 14:45:21.965 T:6432     info <general>: CIRServerSuite::Process: failed to connect to irss, will keep retrying every 5 seconds
2023-12-22 14:45:24.081 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:24.081 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:24.165 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:24.533 T:13652   debug <general>: Version Check: Version installed {'major': 20, 'minor': 2, 'revision': '20230629-5f418d0b13', 'tag': 'stable'}
2023-12-22 14:45:24.533 T:13652   debug <general>: Version Check: Version available  {'major': '20', 'minor': '1', 'tag': 'stable', 'tagversion': '', 'revision': '20230312-289ec664e3', 'extrainfo': 'final', 'addon_support': 'yes'}
2023-12-22 14:45:24.533 T:13652   debug <general>: Version Check: There is no newer stable available
2023-12-22 14:45:24.534 T:13652   debug <general>: CPythonInvoker(0, C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib\runner.py): script successfully run
2023-12-22 14:45:24.534 T:13652   debug <general>: CPythonInvoker::onExecutionDone(0, C:\Users\axelk\AppData\Roaming\Kodi\addons\service.xbmc.versioncheck\resources\lib\runner.py)
2023-12-22 14:45:24.563 T:13652   debug <general>: Python interpreter stopped
2023-12-22 14:45:24.563 T:13652   debug <general>: Thread LanguageInvoker 13652 terminating
2023-12-22 14:45:25.097 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:25.097 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:25.180 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:25.348 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:25.348 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:25.413 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:25.608 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:25.608 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:25.663 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:25.848 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:25.848 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:25.946 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:26.108 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:26.108 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:26.163 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:26.297 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:26.297 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:26.379 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:26.546 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:26.546 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:26.612 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:26.779 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:26.779 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10000, action is Down
2023-12-22 14:45:26.846 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:27.434 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:27.545 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:27.545 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10000, action is Select
2023-12-22 14:45:27.545 T:10224   debug <general>: Activating window ID: 10025
2023-12-22 14:45:27.864 T:10224   debug <general>: ------ Window Deinit (Home.xml) ------
2023-12-22 14:45:27.864 T:10224   debug <general>: FreeVisualisation() done
2023-12-22 14:45:27.899 T:10224   debug <general>: ------ Window Init (MyVideoNav.xml) ------
2023-12-22 14:45:27.899 T:10224    info <general>: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:45:27.950 T:9580    debug <general>: Thread Timer start, auto delete: false
2023-12-22 14:45:28.020 T:10224   debug <general>: CGUIMediaWindow::GetDirectory ()
2023-12-22 14:45:28.020 T:10224   debug <general>:   ParentPath = []
2023-12-22 14:45:28.033 T:9868    debug <general>: Thread BackgroundLoader start, auto delete: false
2023-12-22 14:45:28.308 T:9868    debug <general>: Thread BackgroundLoader 9868 terminating
2023-12-22 14:45:28.454 T:9580    debug <general>: Thread Timer 9580 terminating
2023-12-22 14:45:28.839 T:8700    debug <general>: [WS-Discovery]: The initial servers search has completed successfully with 2 server(s) found:
                                                   192.168.178.1
                                                   192.168.178.52
2023-12-22 14:45:29.280 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:29.281 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10025, action is Down
2023-12-22 14:45:29.360 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:29.477 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:29.477 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10025, action is Down
2023-12-22 14:45:29.560 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:30.359 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:30.360 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10025, action is Up
2023-12-22 14:45:30.426 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:30.526 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:30.526 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10025, action is Up
2023-12-22 14:45:30.609 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:31.192 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:31.292 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:31.292 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10025, action is Select
2023-12-22 14:45:31.294 T:10224   debug <general>: CGUIMediaWindow::GetDirectory (library://video/files.xml/)
2023-12-22 14:45:31.294 T:10224   debug <general>:   ParentPath = []
2023-12-22 14:45:31.295 T:14604   debug <general>: Thread waiting start, auto delete: false
2023-12-22 14:45:31.297 T:14604   debug <general>: Thread waiting 14604 terminating
2023-12-22 14:45:31.303 T:11512   debug <general>: Thread BackgroundLoader start, auto delete: false
2023-12-22 14:45:31.311 T:11512   debug <general>: Thread BackgroundLoader 11512 terminating
2023-12-22 14:45:31.908 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:31.908 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10025, action is Down
2023-12-22 14:45:32.024 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:32.574 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:32.691 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:32.691 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10025, action is Select
2023-12-22 14:45:32.691 T:10224    info <general>: Loading skin file: DialogMediaSource.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:45:32.717 T:10224   debug <general>: ------ Window Init (DialogMediaSource.xml) ------
2023-12-22 14:45:33.692 T:10224   debug <general>: Keyboard: scancode: 0x4d, sym: 0x113, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:33.692 T:10224   debug <general>: CInputManager::HandleKey: right (0xf083) pressed, window 10129, action is Right
2023-12-22 14:45:33.740 T:10224   debug <general>: Keyboard: scancode: 0x4d, sym: 0x113, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:34.189 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:34.306 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:34.306 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10129, action is Select
2023-12-22 14:45:34.307 T:10224    info <general>: Loading skin file: FileBrowser.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:45:34.344 T:10224   debug <general>: ------ Window Init (FileBrowser.xml) ------
2023-12-22 14:45:35.455 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:35.456 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10126, action is Down
2023-12-22 14:45:35.555 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:35.656 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:35.656 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10126, action is Down
2023-12-22 14:45:35.722 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:35.822 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:35.822 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10126, action is Down
2023-12-22 14:45:35.922 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:36.154 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:36.255 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:36.255 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10126, action is Select
2023-12-22 14:45:38.291 T:10224   debug <general>: NFS: Context for  not open - get a new context.
2023-12-22 14:45:38.292 T:10224   debug <general>: NFS: version: 3
2023-12-22 14:45:40.336 T:10224   error <general>: NFS: Failed to mount nfs share:  (nfs_service failed)
2023-12-22 14:45:44.015 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:44.015 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10126, action is Down
2023-12-22 14:45:44.081 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:44.547 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:44.673 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:44.673 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10126, action is Select
2023-12-22 14:45:46.510 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:46.510 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10126, action is Up
2023-12-22 14:45:46.522 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:47.557 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:47.557 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10126, action is Up
2023-12-22 14:45:47.678 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:48.935 T:13408   debug <general>: Thread JobWorker 13408 terminating (autodelete)
2023-12-22 14:45:48.935 T:12224   debug <general>: Thread JobWorker 12224 terminating (autodelete)
2023-12-22 14:45:48.935 T:6616    debug <general>: Thread JobWorker 6616 terminating (autodelete)
2023-12-22 14:45:49.573 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:49.743 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:49.743 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10126, action is Select
2023-12-22 14:45:49.797 T:10224   debug <general>: NFS: Context for 192.168.178.52/volume1/Testvideos not open - get a new context.
2023-12-22 14:45:49.797 T:10224   debug <general>: NFS: version: 3
2023-12-22 14:45:49.957 T:10224   debug <general>: NFS: Connected to server 192.168.178.52 and export /volume1/Testvideos
2023-12-22 14:45:49.957 T:10224   debug <general>: NFS: chunks: r/w 131072/131072
2023-12-22 14:45:52.557 T:10224   debug <general>: Keyboard: scancode: 0x4d, sym: 0x113, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:52.558 T:10224   debug <general>: CInputManager::HandleKey: right (0xf083) pressed, window 10126, action is Right
2023-12-22 14:45:52.690 T:10224   debug <general>: Keyboard: scancode: 0x4d, sym: 0x113, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:53.373 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:53.516 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:53.516 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10126, action is Select
2023-12-22 14:45:53.761 T:10224   debug <general>: ------ Window Deinit (FileBrowser.xml) ------
2023-12-22 14:45:55.382 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:55.382 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10129, action is Down
2023-12-22 14:45:55.438 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:55.871 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:55.871 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10129, action is Down
2023-12-22 14:45:55.971 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:56.337 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:45:56.337 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, trying keyboard action 4
2023-12-22 14:45:56.447 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:57.030 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:57.172 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:57.173 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10129, action is Select
2023-12-22 14:45:57.407 T:10224   debug <general>: ------ Window Deinit (DialogMediaSource.xml) ------
2023-12-22 14:45:57.435 T:10224   debug <general>: ------ Window Init (DialogSettings.xml) ------
2023-12-22 14:45:57.435 T:10224    info <general>: Loading skin file: DialogSettings.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:45:57.474 T:10224   debug <CSettingsManager>: requested setting (contenttype) was not found.
2023-12-22 14:45:57.474 T:10224   debug <CSettingsManager>: requested setting (scraperlist) was not found.
2023-12-22 14:45:57.474 T:10224   debug <CSettingsManager>: requested setting (scrapersettings) was not found.
2023-12-22 14:45:57.475 T:10224   debug <CSettingsManager>: requested setting (exclude) was not found.
2023-12-22 14:45:57.475 T:10224   debug <CSettingsManager>: requested setting (allexternalaudio) was not found.
2023-12-22 14:45:58.818 T:10224   debug <general>: Keyboard: scancode: 0x4d, sym: 0x113, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:58.818 T:10224   debug <general>: CInputManager::HandleKey: right (0xf083) pressed, window 10132, action is Right
2023-12-22 14:45:58.918 T:10224   debug <general>: Keyboard: scancode: 0x4d, sym: 0x113, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:59.718 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:45:59.809 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:45:59.810 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10132, action is Select
2023-12-22 14:46:00.057 T:10224   debug <general>: ------ Window Deinit (DialogSettings.xml) ------
2023-12-22 14:46:00.091 T:10224   debug <general>: CGUIMediaWindow::GetDirectory (sources://video/)
2023-12-22 14:46:00.091 T:10224   debug <general>:   ParentPath = [sources://video/]
2023-12-22 14:46:00.092 T:5176    debug <general>: Thread waiting start, auto delete: false
2023-12-22 14:46:00.094 T:5176    debug <general>: Thread waiting 5176 terminating
2023-12-22 14:46:00.101 T:15276   debug <general>: Thread BackgroundLoader start, auto delete: false
2023-12-22 14:46:00.111 T:15276   debug <general>: Thread BackgroundLoader 15276 terminating
2023-12-22 14:46:03.316 T:10224   debug <general>: Keyboard: scancode: 0x5b, sym: 0x137, unicode: 0x00, modifier: 0x10
2023-12-22 14:46:03.316 T:10224   debug <general>: CInputManager::HandleKey: win-leftwindows (0x10f0d6) pressed, window 10025, action is 
2023-12-22 14:46:03.453 T:10224   debug <general>: Keyboard: scancode: 0x5b, sym: 0x137, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:10.847 T:10224   debug <general>: ------ Window Init (Pointer.xml) ------
2023-12-22 14:46:11.990 T:10224   debug <general>: CInputManager::ProcessMouse: trying mouse action leftclick
2023-12-22 14:46:12.973 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:12.973 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10025, action is Up
2023-12-22 14:46:12.977 T:10224   debug <general>: ------ Window Deinit (Pointer.xml) ------
2023-12-22 14:46:13.089 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:13.722 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:46:13.849 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:13.849 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10025, action is Select
2023-12-22 14:46:13.871 T:10224   debug <general>: CGUIMediaWindow::GetDirectory (nfs://192.168.178.52/volume1/Testvideos/)
2023-12-22 14:46:13.871 T:10224   debug <general>:   ParentPath = [sources://video/]
2023-12-22 14:46:13.872 T:8892    debug <general>: Thread waiting start, auto delete: false
2023-12-22 14:46:13.886 T:8892    debug <general>: Thread waiting 8892 terminating
2023-12-22 14:46:13.899 T:13148   debug <general>: Thread BackgroundLoader start, auto delete: false
2023-12-22 14:46:14.002 T:13148   debug <general>: Thread BackgroundLoader 13148 terminating
2023-12-22 14:46:14.618 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:14.618 T:10224   debug <general>: CInputManager::HandleKey: down (0xf081) pressed, window 10025, action is Down
2023-12-22 14:46:14.688 T:10224   debug <general>: Keyboard: scancode: 0x50, sym: 0x112, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:15.106 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:46:15.171 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:15.171 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10025, action is Select
2023-12-22 14:46:15.173 T:10224   debug <general>: CGUIMediaWindow::GetDirectory (nfs://192.168.178.52/volume1/Testvideos/TestVideos/)
2023-12-22 14:46:15.173 T:10224   debug <general>:   ParentPath = [nfs://192.168.178.52/volume1/Testvideos/]
2023-12-22 14:46:15.175 T:2732    debug <general>: Thread waiting start, auto delete: false
2023-12-22 14:46:15.206 T:2732    debug <general>: Thread waiting 2732 terminating
2023-12-22 14:46:15.232 T:14804   debug <general>: Thread BackgroundLoader start, auto delete: false
2023-12-22 14:46:15.281 T:14368   debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:46:15.285 T:14368   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv
2023-12-22 14:46:15.288 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> opening
2023-12-22 14:46:15.305 T:14368   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv
2023-12-22 14:46:15.317 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:46:15.317 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:46:15.318 T:14808   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:46:15.396 T:14368   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:46:15.404 T:14804   debug <general>: Thread BackgroundLoader 14804 terminating
2023-12-22 14:46:15.418 T:14808   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> cache completely reset for seek to position 26888224
2023-12-22 14:46:15.418 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> waiting for position 27018410
2023-12-22 14:46:15.442 T:14808   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> source read hit eof
2023-12-22 14:46:15.442 T:14808   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> cache completely reset for seek to position 5555
2023-12-22 14:46:15.443 T:14368   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:46:15.477 T:14368   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:46:15.477 T:14368   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:46:15.477 T:14368   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:46:15.478 T:14368   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:46:15.536 T:14368   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 58 ms)
2023-12-22 14:46:15.537 T:14368    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:46:15.538 T:14368   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:46:15.538 T:14368   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 201673ms (total: 605021ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv
2023-12-22 14:46:15.556 T:14808   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> cache completely reset for seek to position 26888224
2023-12-22 14:46:15.556 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> waiting for position 27006145
2023-12-22 14:46:15.569 T:14808   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> source read hit eof
2023-12-22 14:46:15.570 T:14808   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> cache completely reset for seek to position 18162
2023-12-22 14:46:15.583 T:14808   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> cache completely reset for seek to position 8900805
2023-12-22 14:46:15.584 T:14368   debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 201201
2023-12-22 14:46:15.637 T:14368   error <general>: ffmpeg[0x267a31ba760]: [h264] Missing reference picture, default is 0
2023-12-22 14:46:15.670 T:14368    info <general>: Skipped 3 duplicate messages..
2023-12-22 14:46:15.670 T:14368   error <general>: ffmpeg[0x267a31ba760]: [h264] mmco: unref short failure
2023-12-22 14:46:15.710 T:14368    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:46:15.710 T:14368   debug <general>: cached image 'special://masterprofile/Thumbnails/e/ea645a46.jpg' size 720x405
2023-12-22 14:46:15.755 T:14368   debug <general>: CDVDFileInfo::ExtractThumb - measured 469 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv> in 4 packets. 
2023-12-22 14:46:15.768 T:14808   debug <general>: Thread FileCache 14808 terminating
2023-12-22 14:46:15.772 T:14368   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/08-[1080p59.94] FPS_test_1080p59.94_L4.2.mkv
2023-12-22 14:46:15.856 T:13084   debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:46:15.860 T:14368   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv
2023-12-22 14:46:15.861 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv> opening
2023-12-22 14:46:15.885 T:14368   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv
2023-12-22 14:46:15.900 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:46:15.900 T:14368   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:46:15.901 T:12312   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:46:15.949 T:14368   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:46:15.949 T:14368   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:46:16.036 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:16.037 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10025, action is Up
2023-12-22 14:46:16.164 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:16.307 T:14368   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:46:16.307 T:14368   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:46:16.308 T:14368   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:46:16.343 T:14368   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 35 ms)
2023-12-22 14:46:16.343 T:14368    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:46:16.344 T:14368   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:46:16.344 T:14368   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 7660ms (total: 22981ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv
2023-12-22 14:46:19.280 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:46:19.396 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:46:19.396 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10025, action is Select
2023-12-22 14:46:19.410 T:10224   debug <general>: CGUIWindowVideoBase::OnPlayMedia nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:46:19.428 T:10224   debug <general>: CPlayerCoreFactory::GetPlayers(nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv)
2023-12-22 14:46:19.428 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2023-12-22 14:46:19.428 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2023-12-22 14:46:19.428 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2023-12-22 14:46:19.429 T:10224   debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2023-12-22 14:46:19.430 T:10224   debug <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2023-12-22 14:46:19.430 T:10224   debug <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2023-12-22 14:46:19.430 T:10224   debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=false
2023-12-22 14:46:19.430 T:10224   debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=true
2023-12-22 14:46:19.430 T:10224   debug <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2023-12-22 14:46:19.435 T:10224   debug <general>: Radio UECP (RDS) Processor - new CDVDRadioRDSData::CDVDRadioRDSData
2023-12-22 14:46:19.435 T:10224   debug <general>: Audio ID3 tag processor - new CVideoPlayerAudioID3::CVideoPlayerAudioID3
2023-12-22 14:46:19.436 T:10224    info <general>: VideoPlayer::OpenFile: nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:46:19.437 T:9284    debug <general>: Thread VideoPlayer start, auto delete: false
2023-12-22 14:46:19.437 T:10224   debug <general>: CApplicationPlayerCallback::OnPlayBackStarted: CApplication::OnPlayBackStarted
2023-12-22 14:46:19.438 T:9284     info <general>: Creating InputStream
2023-12-22 14:46:19.438 T:2288    debug <general>: Thread BackgroundLoader start, auto delete: false
2023-12-22 14:46:19.460 T:13084   debug <general>: Loading settings for nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:46:19.484 T:10224   debug <general>: CVideoGUIInfo::InitCurrentItem(nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv)
2023-12-22 14:46:19.522 T:10224   debug <general>: CPlayerGUIInfo::InitCurrentItem(nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv)
2023-12-22 14:46:19.542 T:9284    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> opening
2023-12-22 14:46:19.577 T:9284    debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:46:19.589 T:9284    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:46:19.589 T:9284    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> using double memory cache each sized 10485760 bytes
2023-12-22 14:46:19.590 T:11868   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:46:19.590 T:9284    debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:46:19.623 T:10224   debug <general>: ------ Window Init (DialogBusy.xml) ------
2023-12-22 14:46:19.776 T:9284    debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 186 ms)
2023-12-22 14:46:19.777 T:9284     info <general>: Creating Demuxer
2023-12-22 14:46:19.777 T:9284    debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:46:19.778 T:9284    debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:46:19.822 T:2288    debug <general>: Thread BackgroundLoader 2288 terminating
2023-12-22 14:46:20.524 T:9284    debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:46:20.524 T:9284     info <general>: ffmpeg[0x267a52120a0]: Input #0, matroska,webm, from 'nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv':
2023-12-22 14:46:20.524 T:9284     info <general>: ffmpeg[0x267a52120a0]:   Metadata:
2023-12-22 14:46:20.524 T:9284     info <general>: ffmpeg[0x267a52120a0]:     encoder         : libebml v1.2.0 + libmatroska v1.1.0
2023-12-22 14:46:20.524 T:9284     info <general>: ffmpeg[0x267a52120a0]:     creation_time   : 2016-02-06T04:01:06.000000Z
2023-12-22 14:46:20.524 T:9284     info <general>: ffmpeg[0x267a52120a0]:   Duration: 00:00:30.03, start: 0.000000, bitrate: 120490 kb/s
2023-12-22 14:46:20.524 T:9284     info <general>: ffmpeg[0x267a52120a0]:   Stream #0:0(eng): Video: h264 (High), yuv420p(tv, bt709, progressive), 3840x2160 [SAR 1:1 DAR 16:9], 29.97 fps, 29.97 tbr, 1k tbn, 59.94 tbc (default)
2023-12-22 14:46:20.524 T:9284    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:46:20.525 T:9284    debug <general>: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 16567489 bytes per second
2023-12-22 14:46:20.525 T:9284     info <general>: Opening stream: 0 source: 256
2023-12-22 14:46:20.525 T:9284     info <general>: Creating video codec with codec id: 27
2023-12-22 14:46:20.525 T:9284     info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:46:20.525 T:9284    debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:46:20.525 T:9284    debug <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 27
2023-12-22 14:46:20.525 T:9284     info <general>: Creating video thread
2023-12-22 14:46:20.526 T:11408   debug <general>: Thread VideoPlayerVideo start, auto delete: false
2023-12-22 14:46:20.526 T:11408    info <general>: running thread: video_thread
2023-12-22 14:46:20.526 T:11408   debug <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false
2023-12-22 14:46:20.542 T:9284    debug <general>: CEdl::ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:46:20.543 T:9284    debug <general>: CVideoPlayer::SetCaching - caching state 1
2023-12-22 14:46:20.543 T:9284    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-12-22 14:46:20.544 T:9284    debug <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2023-12-22 14:46:20.545 T:9284    debug <general>: CVideoPlayer::SetCaching - caching state 1
2023-12-22 14:46:20.545 T:9284    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-12-22 14:46:20.545 T:11408   debug <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2023-12-22 14:46:20.550 T:11408   debug <general>: DXVA: open decoder.
2023-12-22 14:46:20.550 T:11408    info <general>: DXVA::CContext::CreateContext: creating discrete d3d11va device for decoding.
2023-12-22 14:46:20.551 T:11408   debug <general>: HookOpenAdapter10_2: hook into pOpenData->pAdapterFuncs->pfnCreateDevice
2023-12-22 14:46:20.563 T:11408   debug <general>: HookCreateDevice: hook into pCreateData->pDeviceFuncs->pfnCreateResource
2023-12-22 14:46:20.592 T:11408   debug <general>: DXVA: supports 'MPEG2 variable-length decoder'
2023-12-22 14:46:20.592 T:11408   debug <general>: DXVA: supports 'MPEG2 inverse discrete cosine transform'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'VC-1 variable-length decoder 2010'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'VC-1 variable-length decoder 2 (Intel)'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'VC-1 variable-length decoder (Intel)'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'Windows Media Video 9 inverse discrete cosine transform'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'VC-1 inverse discrete cosine transform'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'H.264 variable-length decoder, no film grain technology'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'H.264 stereo high profile, mbs flag set'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'H.264 stereo high profile'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 'H.264 multi-view high profile'
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports C528916C-c0af-4645-8cb2-372b6d4adc2a
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 91CD2D6E-897b-4fa1-b0d7-51dc88010e0a
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 90B899EA-3a62-4705-88b3-8df04b2744e7
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 442B942A-b4d9-4940-bc45-a882e5f919f3
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 97688186-56a8-4094-b543-fc9daaa49f4b
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports 1424D4DC-7cf5-4bb1-9cd7-b63717a72a6b
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports C346E8A3-cbed-4d27-87cc-a70eb4dc8c27
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports FFC79924-5eaf-4666-a736-06190f281443
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports F416F7BD-098a-4cf1-a11b-ce54959ca03d
2023-12-22 14:46:20.593 T:11408   debug <general>: DXVA: supports BF44DACD-217f-4370-a383-d573bc56707e
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 2364D06A-f67f-4186-aed0-62b99e1784f1
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 464BDB3C-91c4-4e9b-896f-225496ac4ed6
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 28566328-f041-4466-8b14-8f5831e78f8b
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 6B4A94DB-54fe-4ae1-9be4-7a7dad004600
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 8C56EB1E-2b47-466f-8d33-7dbcd63f3df2
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 'HEVC variable-length decoder, main'
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 75FC75F7-c589-4a07-a25b-72e03b0383b3
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 'HEVC variable-length decoder, main10'
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 07CFAFFB-5a2e-4b99-b62a-e4ca53b6d5aa
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 'VP9 variable-length decoder, Profile 0'
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 'VP9 variable-length decoder, 10bit, profile 2'
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 76988A52-df13-419a-8e64-ffcf4a336cf5
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 80A3A7BD-89d8-4497-a2b8-2126af7e6eb8
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 8DE911C4-c898-4364-9715-1de6b3fe773d
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 50925B7B-e931-4978-a12a-586630f095f9
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: supports 49761BEC-4b63-4349-a5ff-87ffdf088466
2023-12-22 14:46:20.594 T:11408   debug <general>: DXVA: trying 'H.264 variable-length decoder, no film grain technology'.
2023-12-22 14:46:20.596 T:11408   debug <general>: DXVA: config 0: bitstream type 2.
2023-12-22 14:46:20.597 T:11408   debug <general>: DXVA: config 1: bitstream type 1.
2023-12-22 14:46:20.598 T:11408   debug <general>: DXVA: config 2: bitstream type 2, encrypted.
2023-12-22 14:46:20.599 T:11408   debug <general>: DXVA: config 3: bitstream type 2, encrypted.
2023-12-22 14:46:20.600 T:11408   debug <general>: DXVA: config 4: bitstream type 2, encrypted.
2023-12-22 14:46:20.601 T:11408   debug <general>: DXVA: config 5: bitstream type 2, encrypted.
2023-12-22 14:46:20.602 T:11408   debug <general>: DXVA: config 6: bitstream type 1, encrypted.
2023-12-22 14:46:20.603 T:11408   debug <general>: DXVA: config 7: bitstream type 1, encrypted.
2023-12-22 14:46:20.603 T:11408   debug <general>: DXVA: selected output format: 103.
2023-12-22 14:46:20.603 T:11408   debug <general>: DXVA: source requires 3 references.
2023-12-22 14:46:20.603 T:11408    info <general>: DXVA::CDecoder::Open: Total video memory available is 3934 MB (dedicated = 128 MB, shared = 3806 MB)
2023-12-22 14:46:20.603 T:11408   debug <general>: DXVA: allocating 11 surfaces with format 103.
2023-12-22 14:46:20.699 T:11408   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264-d3d11va
2023-12-22 14:46:20.720 T:11408   debug <general>: CRenderManager::Configure - change configuration. 3840x2160. display: 3840x2160. framerate: 29.97.
2023-12-22 14:46:20.739 T:10224   debug <general>: CRenderManager::DeleteRenderer - deleting renderer
2023-12-22 14:46:20.739 T:10224   debug <general>: CWinRenderer::SelectRenderer: requested render method: 0
2023-12-22 14:46:20.739 T:10224   debug <general>: CWinRenderer::SelectRenderer: selected render method: DXVA
2023-12-22 14:46:20.740 T:10224   debug <general>: CWIN32Util::GetWindowsHDRStatus: Display is not HDR capable or cannot be detected
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: initing video enumerator with params: 3840x2160.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: video processor has 1 rate conversion.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: video processor has 0xaef feature caps.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: video processor has 0x1e device caps.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: video processor has 0x0 input format caps.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: video processor has 8 max input streams.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: video processor has 8 max stream states.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: video processor supports HDR10.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: selected video processor index: 0.
2023-12-22 14:46:20.742 T:10224    info <general>: DXVA::CProcessorHD::InitProcessor: supported deinterlace methods: blend:yes, bob:yes, adaptive:yes, mocomp:yes.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: selected video processor allows 1 future frames and 1 past frames.
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: filter 0 has following params - max: 1000, min: -1000, default: 0
2023-12-22 14:46:20.742 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: filter 1 has following params - max: 1000, min: 0, default: 100
2023-12-22 14:46:20.743 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: filter 2 has following params - max: 1800, min: -1800, default: 0
2023-12-22 14:46:20.743 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: filter 3 has following params - max: 1000, min: 0, default: 100
2023-12-22 14:46:20.743 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: HLG color space conversion is NOT supported.
2023-12-22 14:46:20.743 T:10224   debug <general>: DXVA::CProcessorHD::InitProcessor: HDR10 RGB limited range output is supported.
2023-12-22 14:46:20.743 T:10224   debug <general>: DXVA::CProcessorHD::OpenProcessor: creating processor.
2023-12-22 14:46:20.762 T:10224   debug <general>: CRenderManager::Configure - 6
2023-12-22 14:46:20.762 T:10224 warning <general>: CRendererHQ::CheckVideoParameters: chosen scaling method 1 is not supported by renderer
2023-12-22 14:46:20.762 T:10224   debug <general>: CRendererBase::CreateIntermediateTarget: intermediate target format 87.
2023-12-22 14:46:20.763 T:10224   debug <general>: CWinShader::LoadEffect: loading shader special://xbmc/system/shaders/output_d3d.fx
2023-12-22 14:46:20.793 T:9284    debug <general>: CVideoPlayer::HandleMessages - player started 2
2023-12-22 14:46:20.793 T:13084   debug <general>: CApplicationPlayerCallback::OnAVChange: CApplication::OnAVChange
2023-12-22 14:46:20.793 T:9284    debug <general>: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
2023-12-22 14:46:20.793 T:9284  warning <general>: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
2023-12-22 14:46:20.793 T:9284    debug <general>: CVideoPlayer::SetCaching - caching state 0
2023-12-22 14:46:20.793 T:9284    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-12-22 14:46:20.793 T:11408   debug <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-100000.000000)
2023-12-22 14:46:20.794 T:13084   debug <general>: CApplicationPlayerCallback::OnAVStarted: CApplication::OnAVStarted
2023-12-22 14:46:20.965 T:10224   debug <general>: Activating window ID: 12005
2023-12-22 14:46:20.966 T:10224   debug <general>: ------ Window Deinit (MyVideoNav.xml) ------
2023-12-22 14:46:20.967 T:10224   debug <general>: FreeVisualisation() done
2023-12-22 14:46:20.967 T:10224   debug <general>: ------ Window Init (VideoFullScreen.xml) ------
2023-12-22 14:46:20.968 T:10224    info <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:46:21.032 T:10224   debug <general>: ------ Window Deinit (DialogBusy.xml) ------
2023-12-22 14:46:21.037 T:13084   debug <general>: CApplicationPlayerCallback::OnAVChange: CApplication::OnAVChange
2023-12-22 14:46:21.755 T:11408    info <general>: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33363
2023-12-22 14:46:35.558 T:11408   debug <general>: CPtsTracker: detected pattern of length 1: 33366.66, frameduration: 33366.666667
2023-12-22 14:46:36.825 T:12312   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv> cache completely reset for seek to position 204
2023-12-22 14:46:36.826 T:14368   debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 0
2023-12-22 14:46:37.455 T:14368   debug <general>: cached image 'special://masterprofile/Thumbnails/9/99b229d9.jpg' size 720x405
2023-12-22 14:46:37.473 T:14368   debug <general>: CDVDFileInfo::ExtractThumb - measured 21612 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv> in 2 packets. 
2023-12-22 14:46:37.489 T:12312   debug <general>: Thread FileCache 12312 terminating
2023-12-22 14:46:37.521 T:14368   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/bird-90-mbps-hd-h264.mkv
2023-12-22 14:47:07.590 T:14368   debug <general>: Thread JobWorker 14368 terminating (autodelete)
2023-12-22 14:47:07.590 T:13084   debug <general>: Thread JobWorker 13084 terminating (autodelete)
2023-12-22 14:47:09.641 T:10224   debug <general>: ------ Window Init (VideoOSD.xml) ------
2023-12-22 14:47:09.641 T:10224    info <general>: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:47:09.714 T:10224   debug <general>: ------ Window Init (Pointer.xml) ------
2023-12-22 14:47:09.714 T:10224   debug <general>: ------ Window Init (DialogSeekBar.xml) ------
2023-12-22 14:47:09.718 T:10224   debug <general>: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
2023-12-22 14:47:15.668 T:10224   debug <general>: ------ Window Deinit (Pointer.xml) ------
2023-12-22 14:47:15.670 T:11868   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> source read hit eof
2023-12-22 14:47:15.722 T:9284     info <general>: CVideoPlayer::OnExit()
2023-12-22 14:47:15.722 T:9284     info <general>: VideoPlayer: eof, waiting for queues to empty
2023-12-22 14:47:15.722 T:9284     info <general>: Closing stream player 2
2023-12-22 14:47:15.722 T:11408   debug <general>: CDVDVideoCodecFFmpeg::GetPicture - eof hw accel
2023-12-22 14:47:15.722 T:9284     info <general>: CDVDMessageQueue(video)::WaitUntilEmpty
2023-12-22 14:47:15.723 T:11408   debug <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
2023-12-22 14:47:15.723 T:11408    info <general>: thread end: video_thread
2023-12-22 14:47:15.723 T:11408   debug <general>: Thread VideoPlayerVideo 11408 terminating
2023-12-22 14:47:15.723 T:9284     info <general>: waiting for video thread to exit
2023-12-22 14:47:15.724 T:9284     info <general>: deleting video codec
2023-12-22 14:47:15.735 T:2604    debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:47:15.736 T:11868   debug <general>: Thread FileCache 11868 terminating
2023-12-22 14:47:15.742 T:9284    debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:47:15.742 T:9284    debug <general>: Thread VideoPlayer 9284 terminating
2023-12-22 14:47:15.749 T:15292   debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:47:15.749 T:15292   debug <general>: CSaveFileState::DoWork - Saving file state for video item nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:47:15.751 T:10224   debug <general>: CRenderManager::Flush - flushing renderer
2023-12-22 14:47:15.760 T:15292   debug <general>: CSaveFileState::DoWork - Marking video item nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv as watched
2023-12-22 14:47:15.791 T:15292   debug <general>: CApplicationPlayerCallback::OnPlayBackEnded: CApplicationPlayerCallback::OnPlayBackEnded
2023-12-22 14:47:15.812 T:10224    info <general>: CVideoPlayer::CloseFile()
2023-12-22 14:47:15.812 T:10224   debug <general>: CRenderManager::DeleteRenderer - deleting renderer
2023-12-22 14:47:15.813 T:10224   debug <general>: DXVA::CDecoder::~CDecoder: destructing decoder, 0x267a40b1420.
2023-12-22 14:47:15.824 T:10224    info <general>: DXVA::CDecoder::Close: closing decoder.
2023-12-22 14:47:15.824 T:10224    info <general>: DXVA: closing decoder context.
2023-12-22 14:47:15.824 T:10224   debug <general>: DXVA::CVideoBufferPool::~CVideoBufferPool: destructing buffer pool.
2023-12-22 14:47:15.866 T:10224    info <general>: VideoPlayer: waiting for threads to exit
2023-12-22 14:47:15.866 T:10224    info <general>: VideoPlayer: finished waiting
2023-12-22 14:47:15.866 T:10224    info <general>: CVideoPlayer::CloseFile()
2023-12-22 14:47:15.866 T:10224    info <general>: VideoPlayer: waiting for threads to exit
2023-12-22 14:47:15.866 T:10224    info <general>: VideoPlayer: finished waiting
2023-12-22 14:47:15.875 T:10224   debug <general>: Radio UECP (RDS) Processor - delete CDVDRadioRDSData::~CDVDRadioRDSData
2023-12-22 14:47:15.876 T:10224   debug <general>: Audio ID3 tag processor - delete CVideoPlayerAudioID3::~CVideoPlayerAudioID3
2023-12-22 14:47:15.876 T:10224   debug <general>: CGUIWindowManager::PreviousWindow: Deactivate
2023-12-22 14:47:15.876 T:10224   debug <general>: ------ Window Deinit (VideoOSD.xml) ------
2023-12-22 14:47:15.876 T:10224   debug <general>: ------ Window Deinit (VideoFullScreen.xml) ------
2023-12-22 14:47:15.899 T:10224   debug <general>: CGUIWindowManager::PreviousWindow: Activate new
2023-12-22 14:47:15.907 T:10224   debug <general>: ------ Window Init (MyVideoNav.xml) ------
2023-12-22 14:47:15.911 T:10224   debug <general>: CGUIMediaWindow::GetDirectory (nfs://192.168.178.52/volume1/Testvideos/TestVideos/)
2023-12-22 14:47:15.912 T:10224   debug <general>:   ParentPath = [nfs://192.168.178.52/volume1/Testvideos/TestVideos/]
2023-12-22 14:47:15.957 T:10716   debug <general>: Thread BackgroundLoader start, auto delete: false
2023-12-22 14:47:15.979 T:10224   debug <general>: ffmpeg[0x0]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2023-12-22 14:47:16.131 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:47:16.131 T:10224   debug <general>: ------ Window Deinit (DialogSeekBar.xml) ------
2023-12-22 14:47:16.147 T:10716   debug <general>: Thread BackgroundLoader 10716 terminating
2023-12-22 14:47:16.153 T:10224   debug <general>: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
2023-12-22 14:47:18.730 T:10224   debug <general>: Keyboard: scancode: 0x5b, sym: 0x137, unicode: 0x00, modifier: 0x10
2023-12-22 14:47:18.730 T:10224   debug <general>: CInputManager::HandleKey: win-leftwindows (0x10f0d6) pressed, window 10025, action is 
2023-12-22 14:47:18.815 T:10224   debug <general>: Keyboard: scancode: 0x5b, sym: 0x137, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:23.980 T:10224   debug <general>: ------ Window Init (Pointer.xml) ------
2023-12-22 14:47:29.631 T:14548   debug <general>: CAESinkDirectSound::Deinitialize: Cleaning up
2023-12-22 14:47:32.116 T:10224   debug <general>: ------ Window Deinit (Pointer.xml) ------
2023-12-22 14:47:39.343 T:10224   debug <general>: ------ Window Init (Pointer.xml) ------
2023-12-22 14:47:40.648 T:10224   debug <general>: Keyboard: scancode: 0x1, sym: 0x1b, unicode: 0x1b, modifier: 0x0
2023-12-22 14:47:40.695 T:10224   debug <general>: Keyboard: scancode: 0x1, sym: 0x1b, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:40.695 T:10224   debug <general>: CInputManager::HandleKey: escape (0xf01b) pressed, window 10025, action is PreviousMenu
2023-12-22 14:47:40.696 T:10224   debug <general>: CGUIWindowManager::PreviousWindow: Deactivate
2023-12-22 14:47:40.696 T:14548    info <general>: CActiveAESink::OpenSink - initialize sink
2023-12-22 14:47:40.696 T:14548   debug <general>: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:default
2023-12-22 14:47:40.713 T:10224   debug <general>: ------ Window Deinit (Pointer.xml) ------
2023-12-22 14:47:40.726 T:14548   debug <general>: CAESinkDirectSound::Initialize: Using Window handle: 0x170544
2023-12-22 14:47:40.748 T:14548   debug <general>: CAESinkDirectSound::Initialize: secondary buffer created
2023-12-22 14:47:40.751 T:14548   debug <general>: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
2023-12-22 14:47:40.751 T:14548   debug <general>:   Audio Device    : Lautsprecher (Synaptics SmartAudio HD)
2023-12-22 14:47:40.751 T:14548   debug <general>:   Sample Rate     : 44100
2023-12-22 14:47:40.751 T:14548   debug <general>:   Sample Format   : AE_FMT_FLOAT
2023-12-22 14:47:40.752 T:14548   debug <general>:   Bits Per Sample : 32
2023-12-22 14:47:40.752 T:14548   debug <general>:   Valid Bits/Samp : 32
2023-12-22 14:47:40.752 T:14548   debug <general>:   Channel Count   : 2
2023-12-22 14:47:40.752 T:14548   debug <general>:   Block Align     : 8
2023-12-22 14:47:40.752 T:14548   debug <general>:   Avg. Bytes Sec  : 352800
2023-12-22 14:47:40.752 T:14548   debug <general>:   Samples/Block   : 32
2023-12-22 14:47:40.752 T:14548   debug <general>:   Format cBSize   : 22
2023-12-22 14:47:40.752 T:14548   debug <general>:   Channel Layout  : FL, FR
2023-12-22 14:47:40.752 T:14548   debug <general>:   Channel Mask    : 3
2023-12-22 14:47:40.752 T:14548   debug <general>:   Frames          : 661
2023-12-22 14:47:40.753 T:14548   debug <general>:   Frame Size      : 8
2023-12-22 14:47:40.753 T:14548   debug <general>: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
2023-12-22 14:47:40.753 T:14548   debug <general>:   Output Device : default
2023-12-22 14:47:40.753 T:14548   debug <general>:   Sample Rate   : 44100
2023-12-22 14:47:40.753 T:14548   debug <general>:   Sample Format : AE_FMT_FLOAT
2023-12-22 14:47:40.753 T:14548   debug <general>:   Channel Count : 2
2023-12-22 14:47:40.753 T:14548   debug <general>:   Channel Layout: FL, FR
2023-12-22 14:47:40.753 T:14548   debug <general>:   Frames        : 661
2023-12-22 14:47:40.753 T:14548   debug <general>:   Frame Size    : 8
2023-12-22 14:47:40.915 T:14548   debug <general>: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
2023-12-22 14:47:41.007 T:10224   debug <general>: ------ Window Deinit (MyVideoNav.xml) ------
2023-12-22 14:47:41.008 T:10224   debug <general>: FreeVisualisation() done
2023-12-22 14:47:41.012 T:10224   debug <general>: CGUIWindowManager::PreviousWindow: Activate new
2023-12-22 14:47:41.012 T:10224   debug <general>: ------ Window Init (Home.xml) ------
2023-12-22 14:47:41.013 T:10224   debug <general>: CDirectoryProvider[addons://]: refreshing..
2023-12-22 14:47:41.013 T:10224   debug <general>: CDirectoryProvider[addons://sources/video/]: refreshing..
2023-12-22 14:47:41.014 T:11908   debug <general>: Thread JobWorker start, auto delete: true
2023-12-22 14:47:41.015 T:10224   debug <general>: CDirectoryProvider[addons://sources/audio/]: refreshing..
2023-12-22 14:47:41.015 T:10224   debug <general>: CDirectoryProvider[addons://sources/game/]: refreshing..
2023-12-22 14:47:41.015 T:10224   debug <general>: CDirectoryProvider[addons://sources/executable/]: refreshing..
2023-12-22 14:47:41.015 T:10224   debug <general>: CDirectoryProvider[addons://sources/image/]: refreshing..
2023-12-22 14:47:41.016 T:10224   debug <general>: CDirectoryProvider[library://video/]: refreshing..
2023-12-22 14:47:41.016 T:10224   debug <general>: CDirectoryProvider[sources://video/]: refreshing..
2023-12-22 14:47:41.016 T:10224   debug <general>: CDirectoryProvider[special://videoplaylists/]: refreshing..
2023-12-22 14:47:41.017 T:10224   debug <general>: CDirectoryProvider[favourites://]: refreshing..
2023-12-22 14:47:41.017 T:10224   debug <general>: CDirectoryProvider[sources://pictures/]: refreshing..
2023-12-22 14:47:41.018 T:10224   debug <general>: CDirectoryProvider[addons://sources/game/]: refreshing..
2023-12-22 14:47:41.019 T:15292   debug <general>: CAddonDatabase: SELECT repo.id FROM repo .. took 1 ms
2023-12-22 14:47:41.041 T:2604    debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv
2023-12-22 14:47:41.057 T:15292   debug <general>: 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) ORDER BY repo.addonID, addons.addonID returned 996 rows in 37 ms
2023-12-22 14:47:41.180 T:15292   debug <general>: CAddonDatabase::GetAddons took 160 ms
2023-12-22 14:47:41.186 T:15292   debug <general>: ADDONS: repository.xbmc.org - 993 addon(s) loaded
2023-12-22 14:47:41.192 T:15292   debug <general>: CAddonRepos::BuildUpdateOrOutdatedList: Building update list from installed add-ons
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = audioencoder.kodi.builtin.aac / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.2
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = audioencoder.kodi.builtin.wma / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.2
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = game.controller.default / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.40
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = game.controller.keyboard / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.1.30
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = game.controller.mouse / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.23
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = game.controller.snes / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.39
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = metadata.album.universal / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.1.9
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = metadata.artists.universal / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 4.3.11
2023-12-22 14:47:41.192 T:15292   debug <general>: ADDONS: update check: addonID = metadata.common.allmusic.com / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.2.2
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.common.fanart.tv / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.6.4
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.common.musicbrainz.org / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.2.4
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.common.theaudiodb.com / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.0.3
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.generic.albums / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.14
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.generic.artists / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.14
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.local / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.1
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.themoviedb.org.python / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.1.0
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = metadata.tvshows.themoviedb.org.python / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.6.5
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = peripheral.joystick / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 20.1.9
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = repository.xbmc.org / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.3.1
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = resource.images.weathericons.default / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.1.9
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = resource.language.en_gb / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 2.0.2
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = resource.uisounds.kodi / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.1
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = screensaver.xbmc.builtin.black / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.34
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = screensaver.xbmc.builtin.dim / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 1.0.66
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = script.module.pil / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 5.1.0
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = script.module.pycryptodome / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.4.3
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = service.xbmc.versioncheck / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 0.5.28+matrix.1
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = skin.estouchy / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.0.8
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = skin.estuary / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 3.0.10
2023-12-22 14:47:41.193 T:15292   debug <general>: ADDONS: update check: addonID = webinterface.default / Origin = b6a50484-93a0-4afb-a01c-8d17e059feda / Version = 19.x-2.4.8
2023-12-22 14:47:41.193 T:15292   debug <general>: CAddonMgr::GetAvailableUpdatesOrOutdatedAddons took 178 ms
2023-12-22 14:47:41.202 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> opening
2023-12-22 14:47:41.209 T:15292   debug <general>: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
2023-12-22 14:47:41.209 T:15292   debug <general>: Getting Directory (special://profile/playlists/video)
2023-12-22 14:47:41.210 T:15292   debug <general>: Getting Directory (special://profile/playlists/mixed)
2023-12-22 14:47:41.210 T:15292   debug <general>: CMultiPathDirectory::MergeItems, items = 0
2023-12-22 14:47:41.216 T:2604    debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv
2023-12-22 14:47:41.226 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:41.226 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:47:41.226 T:11548   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:41.289 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:47:41.313 T:11548   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> cache completely reset for seek to position 135107349
2023-12-22 14:47:41.313 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> waiting for position 135237607
2023-12-22 14:47:41.336 T:11548   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> source read hit eof
2023-12-22 14:47:41.336 T:11548   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> cache completely reset for seek to position 5602
2023-12-22 14:47:41.336 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:41.351 T:2604    error <general>: ffmpeg[0x267a31b8a00]: [mpeg2video] ignoring pic cod ext after 0
2023-12-22 14:47:41.370 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:41.370 T:2604    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:41.370 T:2604    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:47:41.370 T:2604    debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:41.435 T:2604    debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 64 ms)
2023-12-22 14:47:41.435 T:2604     info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video
2023-12-22 14:47:41.436 T:2604    debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video
2023-12-22 14:47:41.436 T:2604    debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 20045ms (total: 60137ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv
2023-12-22 14:47:41.458 T:11548   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> cache completely reset for seek to position 135107349
2023-12-22 14:47:41.459 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> waiting for position 135235179
2023-12-22 14:47:41.476 T:11548   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> source read hit eof
2023-12-22 14:47:41.480 T:11548   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> cache completely reset for seek to position 234360
2023-12-22 14:47:41.494 T:11548   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> cache completely reset for seek to position 44876740
2023-12-22 14:47:41.494 T:2604    debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 19920
2023-12-22 14:47:41.532 T:2604    error <general>: ffmpeg[0x267a31b8a00]: [mpeg2video] ignoring pic cod ext after 0
2023-12-22 14:47:41.584 T:2604    debug <general>: cached image 'special://masterprofile/Thumbnails/0/03c02be8.jpg' size 720x405
2023-12-22 14:47:41.610 T:2604    debug <general>: CDVDFileInfo::ExtractThumb - measured 569 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv> in 11 packets. 
2023-12-22 14:47:41.623 T:11548   debug <general>: Thread FileCache 11548 terminating
2023-12-22 14:47:41.624 T:2604    debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/12-[1080i29.97] MPEG2 1080i29.97 (U.S. ATSC OTA capture wDD5.1 audio, muxed into MKV).mkv
2023-12-22 14:47:41.670 T:2604    debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv
2023-12-22 14:47:41.671 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> opening
2023-12-22 14:47:41.679 T:2604    debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv
2023-12-22 14:47:41.691 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:41.691 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:47:41.692 T:15336   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:41.715 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:47:41.730 T:15336   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> cache completely reset for seek to position 253304542
2023-12-22 14:47:41.731 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> waiting for position 253434795
2023-12-22 14:47:41.753 T:15336   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> source read hit eof
2023-12-22 14:47:41.754 T:15336   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> cache completely reset for seek to position 5504
2023-12-22 14:47:41.755 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:41.775 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:41.775 T:2604    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:41.776 T:2604    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:47:41.776 T:2604    debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:41.848 T:2604    debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 71 ms)
2023-12-22 14:47:41.848 T:2604     info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: HEVC (High Efficiency Video Coding)
2023-12-22 14:47:41.849 T:2604    debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-hevc
2023-12-22 14:47:41.849 T:2604    debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 39970ms (total: 119912ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv
2023-12-22 14:47:41.863 T:15336   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> cache completely reset for seek to position 253304542
2023-12-22 14:47:41.863 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> waiting for position 253434466
2023-12-22 14:47:41.879 T:15336   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> source read hit eof
2023-12-22 14:47:41.879 T:15336   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> cache completely reset for seek to position 67053
2023-12-22 14:47:41.892 T:15336   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> cache completely reset for seek to position 62676068
2023-12-22 14:47:41.893 T:2604    debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 37120
2023-12-22 14:47:42.344 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.344 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:42.376 T:2604    debug <general>: cached image 'special://masterprofile/Thumbnails/2/25b1f23c.jpg' size 720x406
2023-12-22 14:47:42.405 T:2604    debug <general>: CDVDFileInfo::ExtractThumb - measured 734 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv> in 27 packets. 
2023-12-22 14:47:42.412 T:15336   debug <general>: Thread FileCache 15336 terminating
2023-12-22 14:47:42.414 T:2604    debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/17-[1080p23.976] H.265 1080p (medium bitrate) Test #1.mkv
2023-12-22 14:47:42.427 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.458 T:11908   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4
2023-12-22 14:47:42.459 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4> opening
2023-12-22 14:47:42.476 T:11908   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4
2023-12-22 14:47:42.486 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:42.486 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4> using single memory cache sized 20971520 bytes
2023-12-22 14:47:42.486 T:15104   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:42.511 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.512 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:42.529 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2023-12-22 14:47:42.535 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:42.577 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.593 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:42.593 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:42.593 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:47:42.595 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:42.641 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 46 ms)
2023-12-22 14:47:42.642 T:11908    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:47:42.642 T:11908   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:47:42.642 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 10000ms (total: 30000ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4
2023-12-22 14:47:42.642 T:11908   debug <general>: CDVDDemuxFFmpeg::SeekTime - unknown position after seek
2023-12-22 14:47:42.652 T:15104   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4> cache completely reset for seek to position 3213301
2023-12-22 14:47:42.677 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.678 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:42.735 T:11908   debug <general>: cached image 'special://masterprofile/Thumbnails/0/0f8a02da.jpg' size 720x405
2023-12-22 14:47:42.744 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.769 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - measured 310 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4> in 5 packets. 
2023-12-22 14:47:42.772 T:15104   debug <general>: Thread FileCache 15104 terminating
2023-12-22 14:47:42.773 T:11908   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/24-[576p25] TextInMotion-VideoSample-576p.mp4
2023-12-22 14:47:42.827 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.828 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:42.866 T:11908   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv
2023-12-22 14:47:42.867 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> opening
2023-12-22 14:47:42.878 T:11908   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv
2023-12-22 14:47:42.888 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:42.888 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:47:42.889 T:10260   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:42.894 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.912 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:47:42.924 T:10260   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> cache completely reset for seek to position 16396793
2023-12-22 14:47:42.925 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> waiting for position 16526979
2023-12-22 14:47:42.943 T:10260   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> source read hit eof
2023-12-22 14:47:42.944 T:10260   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> cache completely reset for seek to position 5556
2023-12-22 14:47:42.945 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:42.986 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:42.986 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:42.987 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:47:42.987 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:42.994 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:42.994 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:43.049 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 61 ms)
2023-12-22 14:47:43.049 T:11908    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:47:43.050 T:11908   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:47:43.050 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 201680ms (total: 605040ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv
2023-12-22 14:47:43.064 T:10260   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> cache completely reset for seek to position 16396793
2023-12-22 14:47:43.064 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> waiting for position 16514823
2023-12-22 14:47:43.077 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:43.083 T:10260   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> source read hit eof
2023-12-22 14:47:43.086 T:10260   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> cache completely reset for seek to position 17701
2023-12-22 14:47:43.101 T:10260   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> cache completely reset for seek to position 5337400
2023-12-22 14:47:43.101 T:11908   debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 201600
2023-12-22 14:47:43.177 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:43.178 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:43.250 T:11908   debug <general>: cached image 'special://masterprofile/Thumbnails/b/b4d86ed5.jpg' size 720x405
2023-12-22 14:47:43.283 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:43.321 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - measured 454 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv> in 3 packets. 
2023-12-22 14:47:43.333 T:10260   debug <general>: Thread FileCache 10260 terminating
2023-12-22 14:47:43.334 T:11908   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/05-[1080p25] FPS_test_1080p25_L4.1.mkv
2023-12-22 14:47:43.395 T:11908   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:47:43.398 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> opening
2023-12-22 14:47:43.431 T:11908   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:47:43.440 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:43.440 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:47:43.441 T:5980    debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:43.462 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:47:43.463 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:43.693 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:43.694 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:43.793 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:43.927 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:47:43.926 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:44.026 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:44.179 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:44.180 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:44.180 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:44.211 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 31 ms)
2023-12-22 14:47:44.211 T:11908    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:47:44.211 T:11908   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:47:44.211 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 10010ms (total: 30030ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:47:44.222 T:5980    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> cache completely reset for seek to position 452161396
2023-12-22 14:47:44.223 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> waiting for position 452291921
2023-12-22 14:47:44.236 T:5980    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> source read hit eof
2023-12-22 14:47:44.236 T:5980    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> cache completely reset for seek to position 2216150
2023-12-22 14:47:44.249 T:5980    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> cache completely reset for seek to position 158539126
2023-12-22 14:47:44.249 T:11908   debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 9676
2023-12-22 14:47:44.276 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:44.277 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:44.376 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:44.693 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:47:44.693 T:10224   debug <general>: CInputManager::HandleKey: up (0xf080) pressed, window 10000, action is Up
2023-12-22 14:47:44.776 T:10224   debug <general>: Keyboard: scancode: 0x48, sym: 0x111, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:45.263 T:11908   debug <general>: cached image 'special://masterprofile/Thumbnails/d/d03fa73c.jpg' size 720x405
2023-12-22 14:47:45.284 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - measured 1888 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv> in 2 packets. 
2023-12-22 14:47:45.301 T:5980    debug <general>: Thread FileCache 5980 terminating
2023-12-22 14:47:45.305 T:11908   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/jellyfish-120-mbps-4k-uhd-h264.mkv
2023-12-22 14:47:45.352 T:2604    debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4
2023-12-22 14:47:45.353 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4> opening
2023-12-22 14:47:45.364 T:2604    debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4
2023-12-22 14:47:45.375 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:45.375 T:2604    debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4> using single memory cache sized 20971520 bytes
2023-12-22 14:47:45.376 T:14076   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:45.395 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2023-12-22 14:47:45.401 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:45.515 T:2604    debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:45.515 T:2604    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:45.516 T:2604    debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:47:45.516 T:2604    debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:45.560 T:2604    debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 44 ms)
2023-12-22 14:47:45.561 T:2604     info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:47:45.562 T:2604    debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:47:45.562 T:2604    debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 20017ms (total: 60053ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4
2023-12-22 14:47:45.562 T:2604    debug <general>: CDVDDemuxFFmpeg::SeekTime - unknown position after seek
2023-12-22 14:47:45.571 T:14076   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4> cache completely reset for seek to position 2326174
2023-12-22 14:47:45.623 T:2604    debug <general>: cached image 'special://masterprofile/Thumbnails/8/88dd56a8.jpg' size 720x405
2023-12-22 14:47:45.640 T:2604    debug <general>: CDVDFileInfo::ExtractThumb - measured 287 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4> in 2 packets. 
2023-12-22 14:47:45.651 T:14076   debug <general>: Thread FileCache 14076 terminating
2023-12-22 14:47:45.652 T:2604    debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/01-[1080p24] RTINGS 24p Judder Test (H.264 1080p24.000) (MP4).mp4
2023-12-22 14:47:45.658 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:47:45.706 T:11908   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv
2023-12-22 14:47:45.707 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> opening
2023-12-22 14:47:45.720 T:11908   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv
2023-12-22 14:47:45.729 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:45.730 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:47:45.730 T:4276    debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:45.758 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:45.759 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10000, action is Select
2023-12-22 14:47:45.759 T:10224   debug <general>: Activating window ID: 10111
2023-12-22 14:47:45.759 T:10224   debug <general>: ------ Window Init (DialogButtonMenu.xml) ------
2023-12-22 14:47:45.759 T:10224    info <general>: Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY
2023-12-22 14:47:45.765 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:47:45.765 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:46.164 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:46.164 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:46.164 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:46.211 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 47 ms)
2023-12-22 14:47:46.212 T:11908    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:47:46.213 T:11908   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:47:46.213 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 10010ms (total: 30030ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv
2023-12-22 14:47:46.228 T:4276    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> cache completely reset for seek to position 261585771
2023-12-22 14:47:46.229 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> waiting for position 261716297
2023-12-22 14:47:46.254 T:4276    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> source read hit eof
2023-12-22 14:47:46.254 T:4276    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> cache completely reset for seek to position 1145364
2023-12-22 14:47:46.268 T:4276    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> cache completely reset for seek to position 91313868
2023-12-22 14:47:46.275 T:11908   debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 9676
2023-12-22 14:47:46.606 T:11908   debug <general>: cached image 'special://masterprofile/Thumbnails/2/299ab085.jpg' size 720x405
2023-12-22 14:47:46.622 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - measured 915 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv> in 2 packets. 
2023-12-22 14:47:46.627 T:4276    debug <general>: Thread FileCache 4276 terminating
2023-12-22 14:47:46.629 T:11908   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/jellyfish-70-mbps-hd-h264.mkv
2023-12-22 14:47:46.698 T:11908   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv
2023-12-22 14:47:46.698 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> opening
2023-12-22 14:47:46.709 T:11908   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv
2023-12-22 14:47:46.719 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:46.719 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> using single memory cache sized 20971520 bytes
2023-12-22 14:47:46.720 T:1952    debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:46.739 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [matroska,webm]
2023-12-22 14:47:46.749 T:1952    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> cache completely reset for seek to position 16194452
2023-12-22 14:47:46.749 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> waiting for position 16324638
2023-12-22 14:47:46.772 T:1952    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> source read hit eof
2023-12-22 14:47:46.772 T:1952    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> cache completely reset for seek to position 5556
2023-12-22 14:47:46.773 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:46.815 T:11908   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:46.816 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:46.816 T:11908   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:47:46.816 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:46.887 T:11908   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 70 ms)
2023-12-22 14:47:46.887 T:11908    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2023-12-22 14:47:46.888 T:11908   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2023-12-22 14:47:46.888 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 201680ms (total: 605042ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv
2023-12-22 14:47:46.903 T:1952    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> cache completely reset for seek to position 16194452
2023-12-22 14:47:46.904 T:11908   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> waiting for position 16312520
2023-12-22 14:47:46.919 T:1952    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> source read hit eof
2023-12-22 14:47:46.920 T:1952    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> cache completely reset for seek to position 20270
2023-12-22 14:47:46.933 T:1952    debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> cache completely reset for seek to position 5382812
2023-12-22 14:47:46.934 T:11908   debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 200833
2023-12-22 14:47:47.022 T:11908   debug <general>: cached image 'special://masterprofile/Thumbnails/7/773051d6.jpg' size 720x405
2023-12-22 14:47:47.051 T:11908   debug <general>: CDVDFileInfo::ExtractThumb - measured 353 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv> in 3 packets. 
2023-12-22 14:47:47.063 T:1952    debug <general>: Thread FileCache 1952 terminating
2023-12-22 14:47:47.065 T:11908   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/03-[1080p24] FPS_test_1080p24_L4.1.mkv
2023-12-22 14:47:47.073 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x0d, modifier: 0x0
2023-12-22 14:47:47.103 T:15292   debug <general>: CThumbExtractor::DoWork - trying to extract thumb from video file nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts
2023-12-22 14:47:47.104 T:15292   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> opening
2023-12-22 14:47:47.112 T:15292   debug <general>: CNFSFile::Open - opened volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts
2023-12-22 14:47:47.120 T:15292   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> source chunk size is 131072, setting cache chunk size to 131072
2023-12-22 14:47:47.121 T:15292   debug <general>: CFileCache::XFILE::CFileCache::Open - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> using single memory cache sized 20971520 bytes
2023-12-22 14:47:47.121 T:10848   debug <general>: Thread FileCache start, auto delete: false
2023-12-22 14:47:47.161 T:15292   debug <general>: CDVDDemuxFFmpeg::Open - probing detected format [mpegts]
2023-12-22 14:47:47.162 T:15292   debug <general>: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
2023-12-22 14:47:47.216 T:10224   debug <general>: Keyboard: scancode: 0x1c, sym: 0x0d, unicode: 0x00, modifier: 0x0
2023-12-22 14:47:47.216 T:10224   debug <general>: CInputManager::HandleKey: return (0xf00d) pressed, window 10111, action is Select
2023-12-22 14:47:47.259 T:10224    info <general>: Stopping the application...
2023-12-22 14:47:47.259 T:10224    info <general>: Stopping player
2023-12-22 14:47:47.259 T:10224    info <general>: Storing total System Uptime
2023-12-22 14:47:47.260 T:10224    info <general>: Saving settings
2023-12-22 14:47:47.281 T:10224    info <general>: Saving skin settings
2023-12-22 14:47:47.285 T:10224    info <general>: Stopping all
2023-12-22 14:47:47.285 T:2604    debug <general>: Thread JobWorker 2604 terminating (autodelete)
2023-12-22 14:47:47.285 T:11908   debug <general>: Thread JobWorker 11908 terminating (autodelete)
2023-12-22 14:47:47.444 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 1506979088
2023-12-22 14:47:47.478 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> source read hit eof
2023-12-22 14:47:47.530 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 1506729088
2023-12-22 14:47:47.577 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> source read hit eof
2023-12-22 14:47:47.577 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 0
2023-12-22 14:47:47.577 T:15292   debug <general>: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
2023-12-22 14:47:47.578 T:15292   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2023-12-22 14:47:47.578 T:15292   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2023-12-22 14:47:47.578 T:15292   debug <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2023-12-22 14:47:47.578 T:15292   debug <general>: CUtil::ScanForExternalSubtitles: Searching for subtitles...
2023-12-22 14:47:47.642 T:15292   debug <general>: CUtil::ScanForExternalSubtitles: END (total time: 64 ms)
2023-12-22 14:47:47.642 T:15292    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video
2023-12-22 14:47:47.642 T:15292   debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-mpeg2video
2023-12-22 14:47:47.642 T:15292   debug <general>: CDVDFileInfo::ExtractThumb - seeking to pos 128741ms (total: 386225ms) in nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts
2023-12-22 14:47:47.657 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 1507098016
2023-12-22 14:47:47.657 T:15292   debug <general>: CFileCache::XFILE::CFileCache::Seek - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> waiting for position 1507228148
2023-12-22 14:47:47.668 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> source read hit eof
2023-12-22 14:47:47.719 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 1506968144
2023-12-22 14:47:47.748 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> source read hit eof
2023-12-22 14:47:47.748 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 1506705884
2023-12-22 14:47:47.808 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> source read hit eof
2023-12-22 14:47:47.810 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 502576264
2023-12-22 14:47:47.875 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 498491024
2023-12-22 14:47:47.938 T:10848   debug <general>: CFileCache::XFILE::CFileCache::Process - <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> cache completely reset for seek to position 498280652
2023-12-22 14:47:47.989 T:15292   debug <general>: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 128728
2023-12-22 14:47:48.534 T:15292   debug <general>: ffmpeg[0x267a31b8cf0]: [src] sws_param option is deprecated and ignored
2023-12-22 14:47:48.551 T:15292   debug <general>: cached image 'special://masterprofile/Thumbnails/d/d7084f9c.jpg' size 720x405
2023-12-22 14:47:48.568 T:15292   debug <general>: CDVDFileInfo::ExtractThumb - measured 1464 ms to extract thumb from file <nfs://192.168.178.52/volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts> in 241 packets. 
2023-12-22 14:47:48.579 T:10848   debug <general>: Thread FileCache 10848 terminating
2023-12-22 14:47:48.580 T:15292   debug <general>: CNFSFile::Close closing file volume1/Testvideos/TestVideos/15-[1080p29.97] MPEG2 422@High 1080i29.97 35Mbps (C-Band satellite capture in TS wDTS-HD MA 5.1 audio, converted from E-AC3) (many media players can't play this).ts
2023-12-22 14:47:48.616 T:15292   debug <general>: Thread JobWorker 15292 terminating (autodelete)
2023-12-22 14:47:48.616 T:10224   debug <general>: CNetworkBase::NetworkMessage - Signaling network services to stop
2023-12-22 14:47:48.616 T:10224    info <general>: ES: Stopping event server
2023-12-22 14:47:48.616 T:10224    info <general>: [WS-Discovery]: terminating
2023-12-22 14:47:48.617 T:10224   debug <general>: CNetworkBase::NetworkMessage - Waiting for network services to stop
2023-12-22 14:47:48.902 T:1064     info <general>: ES: UDP Event server stopped
2023-12-22 14:47:48.902 T:13840   debug <general>: Thread TCPServer 13840 terminating
2023-12-22 14:47:48.902 T:1064    debug <general>: Thread EventServer 1064 terminating
2023-12-22 14:47:48.903 T:10224   debug <general>: CServiceAddonManager: stopping service.xbmc.versioncheck.
2023-12-22 14:47:48.903 T:10224    info <general>: CServiceAddonManager: failed to stop service.xbmc.versioncheck (may have ended)
2023-12-22 14:47:48.904 T:7348    debug <general>: Thread ActiveAE 7348 terminating
2023-12-22 14:47:48.918 T:14548   debug <general>: Thread AESink 14548 terminating
2023-12-22 14:47:48.919 T:10224   debug <general>: CAESinkDirectSound::Deinitialize: Cleaning up
2023-12-22 14:47:48.952 T:10224    info <general>: Application stopped
2023-12-22 14:47:49.154 T:13736   debug <general>: Thread PeripEventScan 13736 terminating
2023-12-22 14:47:49.155 T:1304    debug <general>: Thread PeripBusUSB 1304 terminating
2023-12-22 14:47:49.156 T:2348    debug <general>: Thread PeripBusCEC 2348 terminating
2023-12-22 14:47:49.157 T:11028   debug <general>: Thread PeripBusAddon 11028 terminating
2023-12-22 14:47:49.445 T:10224    info <general>: AddOnLog: peripheral.joystick: Disabling joystick interface "directinput"
2023-12-22 14:47:49.446 T:10224    info <general>: AddOnLog: peripheral.joystick: Disabling joystick interface "xinput"
2023-12-22 14:47:49.446 T:10224   debug <general>: SECTION:UnloadDll(C:\Program Files\Kodi20\addons\peripheral.joystick\peripheral.joystick.dll)
2023-12-22 14:47:49.447 T:10224    info <general>: ADDON: Dll Destroyed - Joystick Support
2023-12-22 14:47:49.447 T:10224    info <general>: unload skin
2023-12-22 14:47:49.450 T:10224   debug <general>: ------ Window Deinit (Home.xml) ------
2023-12-22 14:47:49.451 T:10224   debug <general>: FreeVisualisation() done
2023-12-22 14:47:49.457 T:10224    info <general>: Skipped 1 duplicate messages..
2023-12-22 14:47:49.456 T:10224   debug <general>: ------ Window Deinit () ------
2023-12-22 14:47:49.457 T:10224   debug <general>: FreeVisualisation() done
2023-12-22 14:47:49.461 T:10224   debug <general>: ------ Window Deinit (DialogButtonMenu.xml) ------
2023-12-22 14:47:49.465 T:10224   debug <general>: CTextureBundleXBT::CloseBundle - Closed bundle
2023-12-22 14:47:49.487 T:10224    info <general>: Unloaded skin
2023-12-22 14:47:49.487 T:10224   debug <general>: DX::DeviceResources::ReleaseBackBuffer: release buffers.
2023-12-22 14:47:49.661 T:10224    info <general>: unload sections
2023-12-22 14:47:49.663 T:10364   debug <general>: Thread CWin32PowerStateWorker 10364 terminating
2023-12-22 14:47:49.664 T:9700    debug <general>: Thread Timer 9700 terminating
2023-12-22 14:47:49.665 T:10224   debug <general>: CWinSystemWin32::RestoreDesktopResolution: restoring desktop resolution for 'Generic PnP Monitor #1'.
2023-12-22 14:47:49.665 T:6432    debug <general>: Thread RemoteControl 6432 terminating
2023-12-22 14:47:49.670 T:2860    debug <general>: Thread Announce 2860 terminating
2023-12-22 14:47:49.670 T:10224    info <general>: Exiting the application...