Kodi Log - procobain -
initramfs initramfs-linux.img followkernel
dtparam=audio=on
#gpu_mem=320
dtoverlay=vc4-kms-v3d,cma-512
dtoverlay=rpivid-v4l2
disable_fw_fkms_setup=1
disable_overscan=1
2021-07-22 05:13:58.896 T:474 INFO <general>: -----------------------------------------------------------------------
2021-07-22 05:13:58.897 T:474 INFO <general>: Starting Kodi (19.1 (19.1.0) Git:20210719-nogitfound). Platform: Linux ARM 32-bit
2021-07-22 05:13:58.897 T:474 INFO <general>: Using Release Kodi x32
2021-07-22 05:13:58.897 T:474 INFO <general>: Kodi compiled 2021-07-19 by GCC 10.2.0 for Linux ARM 32-bit version 5.12.3 (330755)
2021-07-22 05:13:58.897 T:474 INFO <general>: Running on BCM2711 with Arch Linux ARM 0.0, kernel: Linux ARM 32-bit version 5.10.50-2-ARCH
2021-07-22 05:13:58.900 T:474 INFO <general>: FFmpeg version/source: 4.3.2-kodi-4.3.2-Matrix-19.1
2021-07-22 05:13:58.900 T:474 INFO <general>: Host CPU: ARMv7 Processor rev 3 (v7l), 4 cores available
2021-07-22 05:13:58.900 T:474 INFO <general>: ARM Features: Neon enabled
2021-07-22 05:13:58.900 T:474 INFO <general>: special://xbmc/ is mapped to: /usr/share/kodi
2021-07-22 05:13:58.900 T:474 INFO <general>: special://xbmcbin/ is mapped to: /usr/lib/kodi
2021-07-22 05:13:58.900 T:474 INFO <general>: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons
2021-07-22 05:13:58.900 T:474 INFO <general>: special://masterprofile/ is mapped to: /home/paulo/.kodi/userdata
2021-07-22 05:13:58.900 T:474 INFO <general>: special://envhome/ is mapped to: /home/paulo
2021-07-22 05:13:58.900 T:474 INFO <general>: special://home/ is mapped to: /home/paulo/.kodi
2021-07-22 05:13:58.900 T:474 INFO <general>: special://temp/ is mapped to: /home/paulo/.kodi/temp
2021-07-22 05:13:58.900 T:474 INFO <general>: special://logpath/ is mapped to: /home/paulo/.kodi/temp
2021-07-22 05:13:58.901 T:474 INFO <general>: The executable running is: /usr/lib/kodi/kodi.bin
2021-07-22 05:13:58.901 T:474 INFO <general>: Local hostname: HTPC
2021-07-22 05:13:58.901 T:474 INFO <general>: Log File is located: /home/paulo/.kodi/temp/kodi.log
2021-07-22 05:13:58.901 T:474 INFO <general>: -----------------------------------------------------------------------
2021-07-22 05:13:58.914 T:474 INFO <general>: loading settings
2021-07-22 05:13:58.915 T:474 INFO <general>: special://profile/ is mapped to: special://masterprofile/
2021-07-22 05:13:58.951 T:474 INFO <general>: No settings file to load (special://xbmc/system/advancedsettings.xml)
2021-07-22 05:13:58.952 T:474 INFO <general>: Loaded settings file from special://profile/advancedsettings.xml
2021-07-22 05:13:58.952 T:474 INFO <general>: Contents of special://profile/advancedsettings.xml are...
<advancedsettings>
<loglevel>2</loglevel>
<cache>
<buffermode>1</buffermode>
<memorysize>52428800</memorysize>
</cache>
</advancedsettings>
2021-07-22 05:13:58.952 T:474 WARNING <CSettingsManager>: missing version attribute
2021-07-22 05:13:58.953 T:474 INFO <general>: Default Video Player: VideoPlayer
2021-07-22 05:13:58.953 T:474 INFO <general>: Default Audio Player: paplayer
2021-07-22 05:13:58.953 T:474 INFO <general>: Disabled debug logging due to GUI setting. Level 1.
2021-07-22 05:13:58.953 T:474 INFO <general>: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
2021-07-22 05:13:58.971 T:474 DEBUG <general>: CSkinSettings: no <skinsettings> tag found
2021-07-22 05:13:58.981 T:474 INFO <general>: creating subdirectories
2021-07-22 05:13:58.981 T:474 INFO <general>: userdata folder: special://masterprofile/
2021-07-22 05:13:58.981 T:474 INFO <general>: recording folder:
2021-07-22 05:13:58.981 T:474 INFO <general>: screenshots folder:
2021-07-22 02:13:59.018 T:474 INFO <general>: Running database version Addons33
2021-07-22 02:13:59.058 T:474 DEBUG <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: inputstream.adaptive
2021-07-22 02:13:59.070 T:474 DEBUG <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: audioencoder.kodi.builtin.aac
2021-07-22 02:13:59.116 T:474 DEBUG <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: screensaver.xbmc.builtin.black
2021-07-22 02:13:59.120 T:474 DEBUG <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: screensaver.xbmc.builtin.dim
2021-07-22 02:13:59.128 T:474 DEBUG <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: audioencoder.kodi.builtin.wma
2021-07-22 02:13:59.156 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.artists.universal' already present with version 4.3.3 at '/usr/share/kodi/addons/metadata.artists.universal/' replaced with version 4.3.3 at '/home/paulo/.kodi/addons/metadata.artists.universal/'
2021-07-22 02:13:59.167 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.common.theaudiodb.com' already present with version 2.0.3 at '/usr/share/kodi/addons/metadata.common.theaudiodb.com/' replaced with version 2.0.3 at '/home/paulo/.kodi/addons/metadata.common.theaudiodb.com/'
2021-07-22 02:13:59.174 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.album.universal' already present with version 3.1.4 at '/usr/share/kodi/addons/metadata.album.universal/' replaced with version 3.1.4 at '/home/paulo/.kodi/addons/metadata.album.universal/'
2021-07-22 02:13:59.176 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.common.themoviedb.org' already present with version 3.2.13 at '/usr/share/kodi/addons/metadata.common.themoviedb.org/' replaced with version 3.2.17 at '/home/paulo/.kodi/addons/metadata.common.themoviedb.org/'
2021-07-22 02:13:59.184 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.themoviedb.org' already present with version 5.2.6 at '/usr/share/kodi/addons/metadata.themoviedb.org/' replaced with version 5.2.6 at '/home/paulo/.kodi/addons/metadata.themoviedb.org/'
2021-07-22 02:13:59.194 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.tvshows.themoviedb.org' already present with version 3.5.12 at '/usr/share/kodi/addons/metadata.tvshows.themoviedb.org/' replaced with version 3.5.13 at '/home/paulo/.kodi/addons/metadata.tvshows.themoviedb.org/'
2021-07-22 02:13:59.196 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.common.fanart.tv' already present with version 3.6.3 at '/usr/share/kodi/addons/metadata.common.fanart.tv/' replaced with version 3.6.3 at '/home/paulo/.kodi/addons/metadata.common.fanart.tv/'
2021-07-22 02:13:59.204 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.common.imdb.com' already present with version 3.1.6 at '/usr/share/kodi/addons/metadata.common.imdb.com/' replaced with version 3.2.3 at '/home/paulo/.kodi/addons/metadata.common.imdb.com/'
2021-07-22 02:13:59.205 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.common.musicbrainz.org' already present with version 2.2.4 at '/usr/share/kodi/addons/metadata.common.musicbrainz.org/' replaced with version 2.2.4 at '/home/paulo/.kodi/addons/metadata.common.musicbrainz.org/'
2021-07-22 02:13:59.208 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.tvshows.themoviedb.org.python' already present with version 1.3.14 at '/usr/share/kodi/addons/metadata.tvshows.themoviedb.org.python/' replaced with version 1.4.6 at '/home/paulo/.kodi/addons/metadata.tvshows.themoviedb.org.python/'
2021-07-22 02:13:59.215 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'service.xbmc.versioncheck' already present with version 0.5.9+matrix.1 at '/usr/share/kodi/addons/service.xbmc.versioncheck/' replaced with version 0.5.19+matrix.1 at '/home/paulo/.kodi/addons/service.xbmc.versioncheck/'
2021-07-22 02:13:59.225 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.themoviedb.org.python' already present with version 1.3.1+matrix.1 at '/usr/share/kodi/addons/metadata.themoviedb.org.python/' replaced with version 1.4.0+matrix.1 at '/home/paulo/.kodi/addons/metadata.themoviedb.org.python/'
2021-07-22 02:13:59.230 T:474 DEBUG <general>: CAddonMgr::FindAddons: Addon 'metadata.common.allmusic.com' already present with version 3.2.2 at '/usr/share/kodi/addons/metadata.common.allmusic.com/' replaced with version 3.2.2 at '/home/paulo/.kodi/addons/metadata.common.allmusic.com/'
2021-07-22 02:13:59.243 T:474 INFO <general>: CAddonMgr::FindAddons: audioencoder.kodi.builtin.aac v1.0.2 installed
2021-07-22 02:13:59.243 T:474 INFO <general>: CAddonMgr::FindAddons: audioencoder.kodi.builtin.wma v1.0.2 installed
2021-07-22 02:13:59.243 T:474 INFO <general>: CAddonMgr::FindAddons: game.controller.default v1.0.9 installed
2021-07-22 02:13:59.243 T:474 INFO <general>: CAddonMgr::FindAddons: game.controller.snes v1.0.9 installed
2021-07-22 02:13:59.244 T:474 INFO <general>: CAddonMgr::FindAddons: hellyrepo.kodi v0.2.6 installed
2021-07-22 02:13:59.244 T:474 INFO <general>: CAddonMgr::FindAddons: inputstream.adaptive v2.6.17 installed
2021-07-22 02:13:59.244 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.global.audioengine v1.1.1 installed
2021-07-22 02:13:59.244 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.global.filesystem v1.1.6 installed
2021-07-22 02:13:59.244 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.global.general v1.0.5 installed
2021-07-22 02:13:59.245 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.global.gui v5.15.0 installed
2021-07-22 02:13:59.245 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.global.main v1.3.0 installed
2021-07-22 02:13:59.245 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.global.network v1.0.4 installed
2021-07-22 02:13:59.245 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.global.tools v1.0.4 installed
2021-07-22 02:13:59.246 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.audiodecoder v3.0.0 installed
2021-07-22 02:13:59.246 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.audioencoder v2.1.0 installed
2021-07-22 02:13:59.246 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.game v2.1.0 installed
2021-07-22 02:13:59.246 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.imagedecoder v2.1.1 installed
2021-07-22 02:13:59.246 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.inputstream v3.0.1 installed
2021-07-22 02:13:59.247 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.peripheral v2.0.0 installed
2021-07-22 02:13:59.247 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.pvr v7.1.0 installed
2021-07-22 02:13:59.247 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.screensaver v2.1.0 installed
2021-07-22 02:13:59.247 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.vfs v3.0.0 installed
2021-07-22 02:13:59.247 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.videocodec v2.0.2 installed
2021-07-22 02:13:59.248 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.binary.instance.visualization v3.0.0 installed
2021-07-22 02:13:59.248 T:474 INFO <general>: CAddonMgr::FindAddons: kodi.resource v1.0.0 installed
2021-07-22 02:13:59.248 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.album.universal v3.1.4 installed
2021-07-22 02:13:59.248 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.artists.universal v4.3.3 installed
2021-07-22 02:13:59.248 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.common.allmusic.com v3.2.2 installed
2021-07-22 02:13:59.248 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.common.fanart.tv v3.6.3 installed
2021-07-22 02:13:59.249 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.common.imdb.com v3.2.3 installed
2021-07-22 02:13:59.249 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.common.musicbrainz.org v2.2.4 installed
2021-07-22 02:13:59.249 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.common.theaudiodb.com v2.0.3 installed
2021-07-22 02:13:59.249 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.common.themoviedb.org v3.2.17 installed
2021-07-22 02:13:59.249 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.generic.albums v1.0.14 installed
2021-07-22 02:13:59.249 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.generic.artists v1.0.14 installed
2021-07-22 02:13:59.250 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.local v1.0.1 installed
2021-07-22 02:13:59.250 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.themoviedb.org v5.2.6 installed
2021-07-22 02:13:59.250 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.themoviedb.org.python v1.4.0+matrix.1 installed
2021-07-22 02:13:59.250 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.tvshows.themoviedb.org v3.5.13 installed
2021-07-22 02:13:59.250 T:474 INFO <general>: CAddonMgr::FindAddons: metadata.tvshows.themoviedb.org.python v1.4.6 installed
2021-07-22 02:13:59.250 T:474 INFO <general>: CAddonMgr::FindAddons: plugin.video.ipcams v0.0.3 installed
2021-07-22 02:13:59.251 T:474 INFO <general>: CAddonMgr::FindAddons: plugin.video.kodipopcorntime v1.7.5 installed
2021-07-22 02:13:59.251 T:474 INFO <general>: CAddonMgr::FindAddons: plugin.video.kodipopcorntime.repository v1.1.0 installed
2021-07-22 02:13:59.251 T:474 INFO <general>: CAddonMgr::FindAddons: plugin.video.twitch v2.5.10+matrix.1 installed
2021-07-22 02:13:59.251 T:474 INFO <general>: CAddonMgr::FindAddons: plugin.video.videodevil v1.8.12 installed
2021-07-22 02:13:59.251 T:474 INFO <general>: CAddonMgr::FindAddons: plugin.video.youtube v6.8.15+matrix.1 installed
2021-07-22 02:13:59.251 T:474 INFO <general>: CAddonMgr::FindAddons: repository.xbmc.org v3.2.5 installed
2021-07-22 02:13:59.252 T:474 INFO <general>: CAddonMgr::FindAddons: repository.xbmcadult v1.0.7 installed
2021-07-22 02:13:59.252 T:474 INFO <general>: CAddonMgr::FindAddons: repository.xonfluence v1.0.2 installed
2021-07-22 02:13:59.252 T:474 INFO <general>: CAddonMgr::FindAddons: resource.images.recordlabels.white v0.0.7 installed
2021-07-22 02:13:59.252 T:474 INFO <general>: CAddonMgr::FindAddons: resource.images.skinbackgrounds.xonfluence v0.0.1 installed
2021-07-22 02:13:59.252 T:474 INFO <general>: CAddonMgr::FindAddons: resource.images.studios.white v0.0.28 installed
2021-07-22 02:13:59.252 T:474 INFO <general>: CAddonMgr::FindAddons: resource.images.weathericons.default v1.1.9 installed
2021-07-22 02:13:59.253 T:474 INFO <general>: CAddonMgr::FindAddons: resource.language.en_gb v2.0.2 installed
2021-07-22 02:13:59.253 T:474 INFO <general>: CAddonMgr::FindAddons: resource.language.pt_br v9.0.37 installed
2021-07-22 02:13:59.253 T:474 INFO <general>: CAddonMgr::FindAddons: resource.uisounds.amber v1.0.0 installed
2021-07-22 02:13:59.253 T:474 INFO <general>: CAddonMgr::FindAddons: resource.uisounds.kodi v1.0.1 installed
2021-07-22 02:13:59.253 T:474 INFO <general>: CAddonMgr::FindAddons: screensaver.xbmc.builtin.black v1.0.34 installed
2021-07-22 02:13:59.253 T:474 INFO <general>: CAddonMgr::FindAddons: screensaver.xbmc.builtin.dim v1.0.64 installed
2021-07-22 02:13:59.253 T:474 INFO <general>: CAddonMgr::FindAddons: script.embuary.helper v2.0.8 installed
2021-07-22 02:13:59.254 T:474 INFO <general>: CAddonMgr::FindAddons: script.facebook.media v0.9.17 installed
2021-07-22 02:13:59.254 T:474 INFO <general>: CAddonMgr::FindAddons: script.favourites v8.1.1 installed
2021-07-22 02:13:59.254 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.certifi v2020.12.05+matrix.1 installed
2021-07-22 02:13:59.254 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.chardet v4.0.0+matrix.1 installed
2021-07-22 02:13:59.254 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.future v0.18.2+matrix.1 installed
2021-07-22 02:13:59.254 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.html5lib v1.0.1+matrix.2 installed
2021-07-22 02:13:59.255 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.idna v2.10.0+matrix.1 installed
2021-07-22 02:13:59.255 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.kodi-six v0.1.3.1 installed
2021-07-22 02:13:59.255 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.mechanize v0.4.3+matrix.2 installed
2021-07-22 02:13:59.255 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.oauth.helper v0.0.9 installed
2021-07-22 02:13:59.255 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.pil v5.1.0 installed
2021-07-22 02:13:59.255 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.pycryptodome v3.4.3 installed
2021-07-22 02:13:59.256 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.python.twitch v2.0.18+matrix.1 installed
2021-07-22 02:13:59.256 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.requests v2.25.1+matrix.1 installed
2021-07-22 02:13:59.256 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.routing v0.2.3+matrix.1 installed
2021-07-22 02:13:59.256 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.simpleeval v0.9.10 installed
2021-07-22 02:13:59.256 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.simplejson v3.17.0+matrix.2 installed
2021-07-22 02:13:59.256 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.six v1.15.0+matrix.1 installed
2021-07-22 02:13:59.257 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.unidecode v1.1.1+matrix.2 installed
2021-07-22 02:13:59.257 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.urllib3 v1.26.4+matrix.1 installed
2021-07-22 02:13:59.257 T:474 INFO <general>: CAddonMgr::FindAddons: script.module.webencodings v0.5.1+matrix.2 installed
2021-07-22 02:13:59.257 T:474 INFO <general>: CAddonMgr::FindAddons: script.randomandlastitems v2.2.2 installed
2021-07-22 02:13:59.257 T:474 INFO <general>: CAddonMgr::FindAddons: script.skinshortcuts v1.1.5 installed
2021-07-22 02:13:59.257 T:474 INFO <general>: CAddonMgr::FindAddons: service.library.data.provider v0.4.1 installed
2021-07-22 02:13:59.258 T:474 INFO <general>: CAddonMgr::FindAddons: service.skin.widgets v0.0.33 installed
2021-07-22 02:13:59.258 T:474 INFO <general>: CAddonMgr::FindAddons: service.subtitles.legendastv v2.3.2 installed
2021-07-22 02:13:59.258 T:474 INFO <general>: CAddonMgr::FindAddons: service.subtitles.opensubtitles v5.1.2 installed
2021-07-22 02:13:59.258 T:474 INFO <general>: CAddonMgr::FindAddons: service.xbmc.versioncheck v0.5.19+matrix.1 installed
2021-07-22 02:13:59.258 T:474 INFO <general>: CAddonMgr::FindAddons: skin.amber v3.4.11 installed
2021-07-22 02:13:59.258 T:474 INFO <general>: CAddonMgr::FindAddons: skin.confluence v4.7.14 installed
2021-07-22 02:13:59.259 T:474 INFO <general>: CAddonMgr::FindAddons: skin.estouchy v3.0.6 installed
2021-07-22 02:13:59.259 T:474 INFO <general>: CAddonMgr::FindAddons: skin.estuary v3.0.5 installed
2021-07-22 02:13:59.259 T:474 INFO <general>: CAddonMgr::FindAddons: skin.xonfluence v4.1.5 installed
2021-07-22 02:13:59.259 T:474 INFO <general>: CAddonMgr::FindAddons: superrepo.kodi.krypton.all v0.7.04 installed
2021-07-22 02:13:59.259 T:474 INFO <general>: CAddonMgr::FindAddons: webinterface.default v19.x-2.4.8 installed
2021-07-22 02:13:59.259 T:474 INFO <general>: CAddonMgr::FindAddons: xbmc.addon v19.1.0 installed
2021-07-22 02:13:59.259 T:474 INFO <general>: CAddonMgr::FindAddons: xbmc.core v0.1.0 installed
2021-07-22 02:13:59.260 T:474 INFO <general>: CAddonMgr::FindAddons: xbmc.gui v5.15.0 installed
2021-07-22 02:13:59.260 T:474 INFO <general>: CAddonMgr::FindAddons: xbmc.json v12.3.0 installed
2021-07-22 02:13:59.260 T:474 INFO <general>: CAddonMgr::FindAddons: xbmc.metadata v2.1.0 installed
2021-07-22 02:13:59.260 T:474 INFO <general>: CAddonMgr::FindAddons: xbmc.python v3.0.0 installed
2021-07-22 02:13:59.260 T:474 INFO <general>: CAddonMgr::FindAddons: xbmc.webinterface v1.0.0 installed
2021-07-22 02:13:59.271 T:474 DEBUG <general>: CFavourites::Load - no system favourites found, skipping
2021-07-22 02:13:59.275 T:474 DEBUG <general>: DBus method call to org.freedesktop.ConsoleKit.Manager.CanStop at /org/freedesktop/ConsoleKit/Manager of org.freedesktop.ConsoleKit failed: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.ConsoleKit was not provided by any .service files
2021-07-22 02:13:59.275 T:474 INFO <general>: Selected Logind/UPower as PowerSyscall
2021-07-22 02:13:59.339 T:474 DEBUG <general>: LogindUPowerSyscall - inhibit lock taken, fd 13
2021-07-22 02:13:59.342 T:474 INFO <general>: Selected UPower as PowerSyscall
2021-07-22 02:13:59.344 T:474 ERROR <general>: DBus error: org.freedesktop.DBus.Error.InvalidArgs - No such property “CanSuspend”
2021-07-22 02:13:59.345 T:474 ERROR <general>: DBus error: org.freedesktop.DBus.Error.InvalidArgs - No such property “CanHibernate”
2021-07-22 02:13:59.347 T:474 DEBUG <general>: DBus method call to org.freedesktop.DBus.Peer.Ping at /org/freedesktop/UDisks2 of org.freedesktop.UDisks2 failed: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks2 was not provided by any .service files
2021-07-22 02:13:59.348 T:474 DEBUG <general>: DBus method call to org.freedesktop.UDisks.EnumerateDevices at /org/freedesktop/UDisks of org.freedesktop.UDisks failed: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
2021-07-22 02:13:59.349 T:474 DEBUG <general>: Selected UDev as storage provider
2021-07-22 02:13:59.350 T:498 DEBUG <general>: Thread ActiveAE start, auto delete: false
2021-07-22 02:13:59.351 T:499 DEBUG <general>: Thread AESink start, auto delete: false
2021-07-22 02:13:59.353 T:498 DEBUG <general>: PulseAudio: Context authorizing
2021-07-22 02:13:59.353 T:498 DEBUG <general>: PulseAudio: Context setting name
2021-07-22 02:13:59.354 T:498 DEBUG <general>: PulseAudio: Context ready
2021-07-22 02:13:59.354 T:498 WARNING <general>: Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail
2021-07-22 02:13:59.355 T:500 DEBUG <general>: PulseAudio: Found Built-in Audio Stereo with devicestring alsa_output.platform-bcm2835_audio.stereo-fallback
2021-07-22 02:13:59.355 T:500 DEBUG <general>: PulseAudio: Found Built-in Audio Digital Stereo (IEC958) with devicestring alsa_output.platform-fef00700.hdmi.iec958-stereo
2021-07-22 02:13:59.356 T:498 INFO <general>: Found 1 Lists of Devices
2021-07-22 02:13:59.356 T:498 INFO <general>: Enumerated PULSE devices:
2021-07-22 02:13:59.356 T:498 INFO <general>: Device 1
2021-07-22 02:13:59.356 T:498 INFO <general>: m_deviceName : Default
2021-07-22 02:13:59.356 T:498 INFO <general>: m_displayName : Default
2021-07-22 02:13:59.356 T:498 INFO <general>: m_displayNameExtra: Default Output Device (PULSEAUDIO)
2021-07-22 02:13:59.356 T:498 INFO <general>: m_deviceType : AE_DEVTYPE_PCM
2021-07-22 02:13:59.356 T:498 INFO <general>: m_channels : FL, FR
2021-07-22 02:13:59.356 T:498 INFO <general>: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2021-07-22 02:13:59.356 T:498 INFO <general>: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2021-07-22 02:13:59.356 T:498 INFO <general>: m_streamTypes : No passthrough capabilities
2021-07-22 02:13:59.356 T:498 INFO <general>: Device 2
2021-07-22 02:13:59.356 T:498 INFO <general>: m_deviceName : alsa_output.platform-bcm2835_audio.stereo-fallback
2021-07-22 02:13:59.356 T:498 INFO <general>: m_displayName : Built-in Audio Stereo
2021-07-22 02:13:59.356 T:498 INFO <general>: m_displayNameExtra: Headphones (PULSEAUDIO)
2021-07-22 02:13:59.356 T:498 INFO <general>: m_deviceType : AE_DEVTYPE_PCM
2021-07-22 02:13:59.356 T:498 INFO <general>: m_channels : FL, FR
2021-07-22 02:13:59.356 T:498 INFO <general>: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2021-07-22 02:13:59.356 T:498 INFO <general>: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2021-07-22 02:13:59.356 T:498 INFO <general>: m_streamTypes : No passthrough capabilities
2021-07-22 02:13:59.356 T:498 INFO <general>: Device 3
2021-07-22 02:13:59.356 T:498 INFO <general>: m_deviceName : alsa_output.platform-fef00700.hdmi.iec958-stereo
2021-07-22 02:13:59.356 T:498 INFO <general>: m_displayName : Built-in Audio Digital Stereo (IEC958)
2021-07-22 02:13:59.356 T:498 INFO <general>: m_displayNameExtra: Digital Output (S/PDIF) (PULSEAUDIO)
2021-07-22 02:13:59.356 T:498 INFO <general>: m_deviceType : AE_DEVTYPE_PCM
2021-07-22 02:13:59.356 T:498 INFO <general>: m_channels : FL, FR
2021-07-22 02:13:59.356 T:498 INFO <general>: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2021-07-22 02:13:59.356 T:498 INFO <general>: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2021-07-22 02:13:59.356 T:498 INFO <general>: m_streamTypes : No passthrough capabilities
2021-07-22 02:13:59.357 T:499 INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-07-22 02:13:59.357 T:499 DEBUG <general>: CActiveAESink::OpenSink - trying to open device PULSE:Default
2021-07-22 02:13:59.358 T:499 DEBUG <general>: PulseAudio: Context authorizing
2021-07-22 02:13:59.359 T:499 DEBUG <general>: PulseAudio: Context ready
2021-07-22 02:13:59.361 T:496 DEBUG <general>: Sink changed
2021-07-22 02:13:59.361 T:499 DEBUG <general>: PulseAudio: Stream ready
2021-07-22 02:13:59.361 T:499 INFO <general>: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms Periodsize 50 ms
2021-07-22 02:13:59.362 T:496 DEBUG <general>: Sink changed
2021-07-22 02:13:59.362 T:499 DEBUG <general>: CActiveAESink::OpenSink - PULSE Initialized:
2021-07-22 02:13:59.362 T:499 DEBUG <general>: Output Device : Default
2021-07-22 02:13:59.363 T:499 DEBUG <general>: Sample Rate : 44100
2021-07-22 02:13:59.363 T:499 DEBUG <general>: Sample Format : AE_FMT_FLOAT
2021-07-22 02:13:59.363 T:499 DEBUG <general>: Channel Count : 2
2021-07-22 02:13:59.363 T:499 DEBUG <general>: Channel Layout: FL, FR
2021-07-22 02:13:59.363 T:499 DEBUG <general>: Frames : 2205
2021-07-22 02:13:59.363 T:499 DEBUG <general>: Frame Size : 8
2021-07-22 02:13:59.377 T:496 DEBUG <general>: Sink changed
2021-07-22 02:13:59.381 T:474 INFO <general>: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
2021-07-22 02:13:59.385 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
2021-07-22 02:13:59.386 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
2021-07-22 02:13:59.387 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
2021-07-22 02:13:59.388 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
2021-07-22 02:13:59.389 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
2021-07-22 02:13:59.390 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
2021-07-22 02:13:59.390 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
2021-07-22 02:13:59.390 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
2021-07-22 02:13:59.391 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
2021-07-22 02:13:59.392 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
2021-07-22 02:13:59.392 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
2021-07-22 02:13:59.393 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Persian QWERTY" successfully loaded
2021-07-22 02:13:59.393 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
2021-07-22 02:13:59.394 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
2021-07-22 02:13:59.394 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
2021-07-22 02:13:59.395 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
2021-07-22 02:13:59.396 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded
2021-07-22 02:13:59.397 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
2021-07-22 02:13:59.397 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
2021-07-22 02:13:59.398 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
2021-07-22 02:13:59.398 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
2021-07-22 02:13:59.399 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
2021-07-22 02:13:59.399 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
2021-07-22 02:13:59.400 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
2021-07-22 02:13:59.401 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
2021-07-22 02:13:59.401 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
2021-07-22 02:13:59.402 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
2021-07-22 02:13:59.402 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Slovenian QWERTZ" successfully loaded
2021-07-22 02:13:59.403 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
2021-07-22 02:13:59.404 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
2021-07-22 02:13:59.404 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
2021-07-22 02:13:59.405 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
2021-07-22 02:13:59.405 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
2021-07-22 02:13:59.406 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
2021-07-22 02:13:59.407 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
2021-07-22 02:13:59.407 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
2021-07-22 02:13:59.408 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
2021-07-22 02:13:59.409 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Thai Kedmanee" successfully loaded
2021-07-22 02:13:59.410 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
2021-07-22 02:13:59.411 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
2021-07-22 02:13:59.411 T:474 DEBUG <general>: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
2021-07-22 02:13:59.411 T:474 DEBUG <general>: CApplication::CreateGUI - trying to init x11 windowing system
2021-07-22 02:13:59.411 T:474 DEBUG <general>: CWinSystemX11::InitWindowSystem - DISPLAY env not set
2021-07-22 02:13:59.411 T:474 DEBUG <general>: CApplication::CreateGUI - unable to init x11 windowing system
2021-07-22 02:13:59.412 T:474 DEBUG <general>: CApplication::CreateGUI - trying to init gbm windowing system
2021-07-22 02:13:59.537 T:502 DEBUG <general>: Thread libinput start, auto delete: false
2021-07-22 02:13:59.542 T:474 INFO <general>: RetroPlayer[PROCESS]: Registering process control for GBM
2021-07-22 02:13:59.543 T:474 INFO <general>: RetroPlayer[RENDER]: Registering renderer factory for DMA
2021-07-22 02:13:59.543 T:474 INFO <general>: RetroPlayer[RENDER]: Registering renderer factory for OpenGLES
2021-07-22 02:13:59.543 T:474 DEBUG <general>: CDRMUtils::OpenDrm - drm devices found: 2
2021-07-22 02:13:59.573 T:474 INFO <general>: CDRMUtils::FindConnector - using connector: 32
2021-07-22 02:13:59.573 T:474 DEBUG <general>: CDRMUtils::OpenDrm - opened device: /dev/dri/card0
2021-07-22 02:13:59.573 T:474 DEBUG <general>: CDRMUtils::PrintDrmDeviceInfo - DRM Device Info:
available_nodes: 0x01
nodes:
nodes[0]: /dev/dri/card0
bustype: 0x02
platform:
fullname: /gpu
2021-07-22 02:13:59.573 T:474 DEBUG <general>: CDRMUtils::OpenDrm - opened render node: /dev/dri/card0
2021-07-22 02:13:59.607 T:474 INFO <general>: CDRMUtils::FindConnector - using connector: 32
2021-07-22 02:13:59.607 T:474 INFO <general>: CDRMUtils::FindEncoder - using encoder: 31
2021-07-22 02:13:59.607 T:474 DEBUG <general>: CDRMUtils::FindCrtc - original crtc mode: 1920x1080 @ 60 Hz
2021-07-22 02:13:59.607 T:474 INFO <general>: CDRMUtils::FindPlanes - using crtc: 76
2021-07-22 02:13:59.607 T:474 DEBUG <general>: CDRMUtils::FindPlanes - using video plane 70
2021-07-22 02:13:59.607 T:474 DEBUG <general>: CDRMUtils::FindPlanes - using gui plane 91
2021-07-22 02:13:59.607 T:474 DEBUG <general>: CDRMAtomic::InitDrm - initialized atomic DRM
2021-07-22 02:13:59.738 T:502 DEBUG <general>: CLibInputHandler::DeviceAdded - pointer type device added: Logitech K400 Plus (event0)
2021-07-22 02:13:59.738 T:502 DEBUG <general>: CLibInputHandler::DeviceAdded - keyboard type device added: Logitech K400 Plus (event0)
2021-07-22 02:13:59.738 T:502 DEBUG <general>: CLibInputKeyboard::GetRepeat - delay: 250ms repeat: 33ms for Logitech K400 Plus (event0)
2021-07-22 02:13:59.891 T:497 INFO <general>: Loading special://xbmc/system/Lircmap.xml
2021-07-22 02:13:59.894 T:497 INFO <general>: * Adding remote mapping for device 'mceusb'
2021-07-22 02:13:59.894 T:497 INFO <general>: * Adding remote mapping for device 'XboxDVDDongle'
2021-07-22 02:13:59.894 T:497 INFO <general>: * Adding remote mapping for device 'Microsoft_Xbox'
2021-07-22 02:13:59.894 T:497 INFO <general>: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'anysee'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'iMON-PAD'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'Antec_Veris_RM200'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'MCE_via_iMON'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'TwinHanRemote'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'linux-input-layer'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'mediacenter'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'devinput'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'devinput-32'
2021-07-22 02:13:59.895 T:497 INFO <general>: * Adding remote mapping for device 'devinput-64'
2021-07-22 02:13:59.896 T:497 DEBUG <general>: CIRTranslator::Load - no userdata Lircmap.xml found, skipping
2021-07-22 02:14:00.719 T:474 DEBUG <general>: CWinSystemGbm::InitWindowSystem - initialized DRM
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 60.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 59.940063 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080i @ 60.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 60.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080i @ 59.940063 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 59.940063 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 50.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080i @ 50.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 50.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 30.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 29.970032 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 25.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 24.000000 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1920x1080 with 1920x1080 @ 23.976025 Hz
2021-07-22 02:14:00.720 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.720 T:474 INFO <general>: Found resolution 1680x1050 with 1680x1050 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1600x900 with 1600x900 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1280x1024 with 1280x1024 @ 75.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1280x1024 with 1280x1024 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1440x900 with 1440x900 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1280x800 with 1280x800 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1152x864 with 1152x864 @ 75.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1280x720 with 1280x720 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1280x720 with 1280x720 @ 59.940063 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1280x720 with 1280x720 @ 50.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1024x768 with 1024x768 @ 75.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1024x768 with 1024x768 @ 70.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 1024x768 with 1024x768 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 832x624 with 832x624 @ 74.925079 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 800x600 with 800x600 @ 75.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 800x600 with 800x600 @ 72.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 800x600 with 800x600 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 720x576 with 720x576 @ 50.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 720x576 with 720x576i @ 50.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 720x480 with 720x480 @ 59.940063 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 720x480 with 720x480 @ 60.000000 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 720x480 with 720x480i @ 59.940063 Hz
2021-07-22 02:14:00.721 T:474 INFO <general>: Found resolution 720x480 with 720x480i @ 60.000000 Hz
2021-07-22 02:14:00.722 T:474 INFO <general>: Found resolution 640x480 with 640x480 @ 75.000000 Hz
2021-07-22 02:14:00.722 T:474 INFO <general>: Found resolution 640x480 with 640x480 @ 73.000000 Hz
2021-07-22 02:14:00.722 T:474 INFO <general>: Found resolution 640x480 with 640x480 @ 67.000000 Hz
2021-07-22 02:14:00.722 T:474 INFO <general>: Found resolution 640x480 with 640x480 @ 60.000000 Hz
2021-07-22 02:14:00.722 T:474 INFO <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:00.722 T:474 INFO <general>: Found resolution 720x400 with 720x400 @ 70.000000 Hz
2021-07-22 02:14:00.726 T:474 INFO <general>: EGL_VERSION = 1.4
2021-07-22 02:14:00.726 T:474 INFO <general>: EGL_VENDOR = Mesa Project
2021-07-22 02:14:00.726 T:474 INFO <general>: EGL_EXTENSIONS = EGL_ANDROID_blob_cache EGL_EXT_buffer_age EGL_EXT_image_dma_buf_import EGL_EXT_image_dma_buf_import_modifiers EGL_KHR_cl_event2 EGL_KHR_config_attribs EGL_KHR_create_context EGL_KHR_create_context_no_error EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_colorspace EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_EXT_pixel_format_float EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_image_dma_buf_export EGL_MESA_query_driver EGL_WL_bind_wayland_display
2021-07-22 02:14:00.726 T:474 INFO <general>: EGL_CLIENT_EXTENSIONS = EGL_EXT_device_base EGL_EXT_device_enumeration EGL_EXT_device_query EGL_EXT_platform_base EGL_KHR_client_get_all_proc_addresses EGL_EXT_client_extensions EGL_KHR_debug EGL_EXT_platform_device EGL_EXT_platform_wayland EGL_KHR_platform_wayland EGL_EXT_platform_x11 EGL_KHR_platform_x11 EGL_MESA_platform_xcb EGL_MESA_platform_gbm EGL_KHR_platform_gbm EGL_MESA_platform_surfaceless
2021-07-22 02:14:00.726 T:474 DEBUG <general>: EGL Config Attributes:
2021-07-22 02:14:00.726 T:474 DEBUG <general>: EGL_BUFFER_SIZE: 32
2021-07-22 02:14:00.726 T:474 DEBUG <general>: EGL_ALPHA_SIZE: 8
2021-07-22 02:14:00.726 T:474 DEBUG <general>: EGL_BLUE_SIZE: 8
2021-07-22 02:14:00.726 T:474 DEBUG <general>: EGL_GREEN_SIZE: 8
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_RED_SIZE: 8
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_DEPTH_SIZE: 16
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_STENCIL_SIZE: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_CONFIG_CAVEAT: 0x3038
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_CONFIG_ID: 10
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_LEVEL: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_MAX_PBUFFER_HEIGHT: 4096
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_MAX_PBUFFER_PIXELS: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_MAX_PBUFFER_WIDTH: 4096
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_NATIVE_RENDERABLE: 1
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_NATIVE_VISUAL_ID: 875713089
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_NATIVE_VISUAL_TYPE: 0x3038
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_SAMPLES: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_SAMPLE_BUFFERS: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_SURFACE_TYPE: 4
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_TRANSPARENT_TYPE: 0x3038
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_TRANSPARENT_BLUE_VALUE: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_TRANSPARENT_GREEN_VALUE: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_TRANSPARENT_RED_VALUE: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_BIND_TO_TEXTURE_RGB: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_BIND_TO_TEXTURE_RGBA: 0
2021-07-22 02:14:00.727 T:474 DEBUG <general>: EGL_MIN_SWAP_INTERVAL: 1
2021-07-22 02:14:00.728 T:474 DEBUG <general>: EGL_MAX_SWAP_INTERVAL: 1
2021-07-22 02:14:00.728 T:474 DEBUG <general>: EGL_LUMINANCE_SIZE: 0
2021-07-22 02:14:00.728 T:474 DEBUG <general>: EGL_ALPHA_MASK_SIZE: 0
2021-07-22 02:14:00.728 T:474 DEBUG <general>: EGL_COLOR_BUFFER_TYPE: 0x308e
2021-07-22 02:14:00.728 T:474 DEBUG <general>: EGL_RENDERABLE_TYPE: 77
2021-07-22 02:14:00.728 T:474 DEBUG <general>: EGL_CONFORMANT: 77
2021-07-22 02:14:00.775 T:474 DEBUG <general>: CUDMABufferObject::Register - unable to open /dev/udmabuf: No such file or directory
2021-07-22 02:14:00.776 T:474 DEBUG <general>: CDMAHeapBufferObject::Register unable to open /dev/dma_heap/reserved: No such file or directory
2021-07-22 02:14:00.776 T:474 DEBUG <general>: CDMAHeapBufferObject::Register - using /dev/dma_heap/linux,cma
2021-07-22 02:14:00.776 T:474 INFO <general>: CApplication::CreateGUI - using the gbm windowing system
2021-07-22 02:14:00.776 T:474 INFO <general>: Checking resolution 16
2021-07-22 02:14:00.776 T:474 DEBUG <general>: OnLostDevice - notify display change event
2021-07-22 02:14:00.776 T:474 DEBUG <general>: CWinSystemGbmEGLContext::DestroyWindow - deinitialized GBM
2021-07-22 02:14:00.776 T:474 DEBUG <general>: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 60 Hz
2021-07-22 02:14:00.776 T:474 DEBUG <general>: CGBMUtils::CreateSurface - created surface with size 1920x1080
2021-07-22 02:14:00.793 T:474 DEBUG <general>: CWinSystemGbmEGLContext::CreateNewWindow - initialized GBM
2021-07-22 02:14:00.793 T:474 INFO <general>: GL_VENDOR = Broadcom
2021-07-22 02:14:00.793 T:474 INFO <general>: GL_RENDERER = V3D 4.2
2021-07-22 02:14:00.793 T:474 INFO <general>: GL_VERSION = OpenGL ES 3.1 Mesa 21.1.5
2021-07-22 02:14:00.793 T:474 INFO <general>: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 3.10
2021-07-22 02:14:00.793 T:474 INFO <general>: GL_EXTENSIONS = GL_EXT_blend_minmax GL_EXT_multi_draw_arrays GL_EXT_texture_compression_s3tc GL_EXT_texture_compression_dxt1 GL_EXT_texture_format_BGRA8888 GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_stencil8 GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_half_float GL_EXT_draw_instanced GL_EXT_texture_sRGB_decode GL_OES_EGL_image GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_type_2_10_10_10_REV GL_OES_get_program_binary GL_APPLE_texture_max_level GL_EXT_discard_framebuffer GL_EXT_read_format_bgra GL_EXT_frag_depth GL_NV_fbo_color_attachments GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_vertex_array_object GL_ANGLE_pack_reverse_row_order GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_EXT_occlusion_query_boolean GL_EXT_texture_rg GL_EXT_unpack_subimage GL_NV_draw_buffers GL_NV_read_buffer GL_NV_read_depth GL_NV_read_depth_stencil GL_NV_read_stencil GL_EXT_draw_buffers GL_EXT_map_buffer_range GL_KHR_debug GL_KHR_texture_compression_astc_ldr GL_NV_pixel_buffer_object GL_OES_depth_texture_cube_map GL_OES_required_internalformat GL_OES_surfaceless_context GL_EXT_color_buffer_float GL_EXT_sRGB_write_control GL_EXT_separate_shader_objects GL_EXT_shader_implicit_conversions GL_EXT_shader_integer_mix GL_EXT_base_instance GL_EXT_compressed_ETC1_RGB8_sub_texture GL_EXT_draw_elements_base_vertex GL_EXT_primitive_bounding_box GL_EXT_shader_io_blocks GL_EXT_texture_border_clamp GL_KHR_context_flush_control GL_NV_image_formats GL_OES_draw_elements_base_vertex GL_OES_primitive_bounding_box GL_OES_shader_io_blocks GL_OES_texture_border_clamp GL_OES_texture_stencil8 GL_OES_texture_storage_multisample_2d_array GL_EXT_buffer_storage GL_EXT_float_blend GL_EXT_geometry_point_size GL_EXT_geometry_shader GL_KHR_no_error GL_KHR_texture_compression_astc_sliced_3d GL_OES_EGL_image_external_essl3 GL_OES_geometry_point_size GL_OES_geometry_shader GL_OES_shader_image_atomic GL_EXT_texture_compression_s3tc_srgb GL_MESA_shader_integer_functions GL_KHR_parallel_shader_compile GL_EXT_EGL_image_storage GL_MESA_framebuffer_flip_y GL_EXT_texture_query_lod
2021-07-22 02:14:00.962 T:474 DEBUG <general>: OnLostDevice - notify display change event
2021-07-22 02:14:00.962 T:474 DEBUG <general>: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 60 Hz
2021-07-22 02:14:00.962 T:474 INFO <general>: GLES: Maximum texture width: 4096
2021-07-22 02:14:00.966 T:474 DEBUG <general>: guilib: Fill viewport on change for solving rendering passes
2021-07-22 02:14:01.011 T:474 DEBUG <general>: ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:14:01.450 T:474 DEBUG <general>: EGL Debugging:
Error: EGL_BAD_SURFACE
Command: eglSwapBuffers
Type: EGL_DEBUG_MSG_ERROR_KHR
Message: dri2_swap_buffers
2021-07-22 02:14:01.452 T:474 DEBUG <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2021-07-22 02:14:01.464 T:474 DEBUG <general>: CWinSystemGbmGLESContext::PresentRender - Sending display reset to all clients
2021-07-22 02:14:01.464 T:474 INFO <general>: load keymapping
2021-07-22 02:14:01.468 T:474 INFO <general>: Loading special://xbmc/system/keymaps/appcommand.xml
2021-07-22 02:14:01.468 T:474 INFO <general>: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
2021-07-22 02:14:01.471 T:474 INFO <general>: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
2021-07-22 02:14:01.475 T:474 INFO <general>: Loading special://xbmc/system/keymaps/customcontroller.SiriRemote.xml
2021-07-22 02:14:01.476 T:474 INFO <general>: Loading special://xbmc/system/keymaps/gamepad.xml
2021-07-22 02:14:01.480 T:474 INFO <general>: Loading special://xbmc/system/keymaps/joystick.xml
2021-07-22 02:14:01.483 T:474 INFO <general>: Loading special://xbmc/system/keymaps/keyboard.xml
2021-07-22 02:14:01.493 T:474 INFO <general>: Loading special://xbmc/system/keymaps/mouse.xml
2021-07-22 02:14:01.494 T:474 INFO <general>: Loading special://xbmc/system/keymaps/remote.xml
2021-07-22 02:14:01.498 T:474 INFO <general>: Loading special://xbmc/system/keymaps/touchscreen.xml
2021-07-22 02:14:01.504 T:474 INFO <general>: Loading special://masterprofile/keymaps/gen.xml
2021-07-22 02:14:01.505 T:474 INFO <general>: Loading special://masterprofile/keymaps/portuguese (brazil).xml
2021-07-22 02:14:01.506 T:474 ERROR <general>: special://masterprofile/keymaps/portuguese (brazil).xml Doesn't contain <keymap>
2021-07-22 02:14:01.507 T:474 INFO <general>: Loading special://profile/keymaps/gen.xml
2021-07-22 02:14:01.507 T:474 INFO <general>: Loading special://profile/keymaps/portuguese (brazil).xml
2021-07-22 02:14:01.507 T:474 ERROR <general>: special://profile/keymaps/portuguese (brazil).xml Doesn't contain <keymap>
2021-07-22 02:14:01.507 T:474 INFO <general>: GUI format 1920x1080, Display 1920x1080 @ 60.000000 Hz
2021-07-22 02:14:01.507 T:474 INFO <general>: CLangInfo: loading resource.language.pt_br language information...
2021-07-22 02:14:01.510 T:474 DEBUG <general>: trying to set locale to pt_BR.UTF-8
2021-07-22 02:14:01.521 T:474 INFO <general>: global locale set to C
2021-07-22 02:14:01.521 T:474 INFO <general>: CLangInfo: loading resource.language.pt_br language strings...
2021-07-22 02:14:01.562 T:474 DEBUG <general>: LocalizeStrings: loaded 4143 strings from file resource://resource.language.pt_br/strings.po
2021-07-22 02:14:01.591 T:474 DEBUG <general>: POParser: id:24154 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.591 T:474 DEBUG <general>: POParser: id:24155 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.591 T:474 DEBUG <general>: POParser: id:24160 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.591 T:474 DEBUG <general>: POParser: id:24161 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.591 T:474 DEBUG <general>: POParser: id:29900 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.596 T:474 DEBUG <general>: LocalizeStrings: loaded 8 strings from file resource://resource.language.en_gb/strings.po
2021-07-22 02:14:01.597 T:474 WARNING <general>: CRepository::ParseDirConfiguration: Repository has MD5 hashes enabled - this hash function is broken and will only guard against unintentional data corruption
2021-07-22 02:14:01.597 T:474 WARNING <general>: Repository add-on superrepo.kodi.krypton.all uses plain HTTP for add-on downloads in path http://redirect.superrepo.org/v7/addons/ - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2021-07-22 02:14:01.600 T:474 DEBUG <general>: LocalizeStrings: loaded 3 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.aac/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.602 T:474 DEBUG <general>: LocalizeStrings: loaded 3 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.wma/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.604 T:474 DEBUG <general>: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.605 T:474 DEBUG <general>: LocalizeStrings: loaded 13 strings from file /usr/share/kodi/addons/game.controller.snes/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.608 T:474 DEBUG <general>: LocalizeStrings: loaded 25 strings from file /usr/share/kodi/addons/inputstream.adaptive/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.610 T:474 DEBUG <general>: LocalizeStrings: loaded 11 strings from file /home/paulo/.kodi/addons/metadata.album.universal/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.611 T:474 DEBUG <general>: LocalizeStrings: loaded 6 strings from file /home/paulo/.kodi/addons/metadata.album.universal/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.613 T:474 DEBUG <general>: LocalizeStrings: loaded 23 strings from file /home/paulo/.kodi/addons/metadata.artists.universal/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.614 T:474 DEBUG <general>: LocalizeStrings: loaded 9 strings from file /home/paulo/.kodi/addons/metadata.artists.universal/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.623 T:474 DEBUG <general>: LocalizeStrings: loaded 17 strings from file /usr/share/kodi/addons/metadata.generic.albums/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.624 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /usr/share/kodi/addons/metadata.generic.albums/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.626 T:474 DEBUG <general>: LocalizeStrings: loaded 15 strings from file /usr/share/kodi/addons/metadata.generic.artists/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.627 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /usr/share/kodi/addons/metadata.generic.artists/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.629 T:474 DEBUG <general>: LocalizeStrings: loaded 7 strings from file /home/paulo/.kodi/addons/metadata.themoviedb.org/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.631 T:474 DEBUG <general>: POParser: id:30003 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.631 T:474 DEBUG <general>: LocalizeStrings: loaded 3 strings from file /home/paulo/.kodi/addons/metadata.themoviedb.org/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.633 T:474 DEBUG <general>: LocalizeStrings: loaded 5 strings from file /home/paulo/.kodi/addons/metadata.themoviedb.org.python/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.634 T:474 DEBUG <general>: LocalizeStrings: loaded 11 strings from file /home/paulo/.kodi/addons/metadata.themoviedb.org.python/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.636 T:474 DEBUG <general>: LocalizeStrings: loaded 3 strings from file /home/paulo/.kodi/addons/metadata.tvshows.themoviedb.org/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.637 T:474 DEBUG <general>: LocalizeStrings: loaded 10 strings from file /home/paulo/.kodi/addons/metadata.tvshows.themoviedb.org/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.639 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /home/paulo/.kodi/addons/metadata.tvshows.themoviedb.org.python/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.640 T:474 DEBUG <general>: LocalizeStrings: loaded 31 strings from file /home/paulo/.kodi/addons/metadata.tvshows.themoviedb.org.python/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.643 T:474 DEBUG <general>: LocalizeStrings: loaded 10 strings from file /home/paulo/.kodi/addons/plugin.video.ipcams/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.648 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /home/paulo/.kodi/addons/plugin.video.twitch/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.650 T:474 DEBUG <general>: LocalizeStrings: loaded 236 strings from file /home/paulo/.kodi/addons/plugin.video.twitch/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.653 T:474 DEBUG <general>: LocalizeStrings: loaded 59 strings from file /home/paulo/.kodi/addons/plugin.video.videodevil/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.660 T:474 DEBUG <general>: LocalizeStrings: loaded 285 strings from file /home/paulo/.kodi/addons/plugin.video.youtube/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.662 T:474 DEBUG <general>: LocalizeStrings: loaded 12 strings from file /home/paulo/.kodi/addons/plugin.video.youtube/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.676 T:474 DEBUG <general>: LocalizeStrings: loaded 3 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.677 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.683 T:474 DEBUG <general>: LocalizeStrings: loaded 24 strings from file /home/paulo/.kodi/addons/script.embuary.helper/resources/language/resource.language.pt_BR/strings.po
2021-07-22 02:14:01.684 T:474 DEBUG <general>: LocalizeStrings: loaded 11 strings from file /home/paulo/.kodi/addons/script.embuary.helper/resources/language/resource.language.en_GB/strings.po
2021-07-22 02:14:01.686 T:474 DEBUG <general>: LocalizeStrings: loaded 65 strings from file /home/paulo/.kodi/addons/script.facebook.media/resources/language/English/strings.po
2021-07-22 02:14:01.688 T:474 DEBUG <general>: LocalizeStrings: loaded 3 strings from file /home/paulo/.kodi/addons/script.favourites/resources/language/resource.language.pt_BR/strings.po
2021-07-22 02:14:01.689 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /home/paulo/.kodi/addons/script.favourites/resources/language/resource.language.en_GB/strings.po
2021-07-22 02:14:01.700 T:474 DEBUG <general>: LocalizeStrings: loaded 26 strings from file /home/paulo/.kodi/addons/script.module.oauth.helper/resources/language/English/strings.po
2021-07-22 02:14:01.703 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /home/paulo/.kodi/addons/script.module.python.twitch/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.704 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /home/paulo/.kodi/addons/script.module.python.twitch/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.718 T:474 DEBUG <general>: LocalizeStrings: loaded 97 strings from file /home/paulo/.kodi/addons/script.skinshortcuts/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32024 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32028 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32034 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32037 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32038 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32048 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32058 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32060 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32064 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32065 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.719 T:474 DEBUG <general>: POParser: id:32093 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.720 T:474 DEBUG <general>: POParser: id:32094 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.720 T:474 DEBUG <general>: POParser: id:32095 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
2021-07-22 02:14:01.720 T:474 DEBUG <general>: LocalizeStrings: loaded 40 strings from file /home/paulo/.kodi/addons/script.skinshortcuts/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.722 T:474 DEBUG <general>: LocalizeStrings: loaded 24 strings from file /home/paulo/.kodi/addons/service.library.data.provider/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.727 T:474 DEBUG <general>: LocalizeStrings: loaded 13 strings from file /home/paulo/.kodi/addons/service.skin.widgets/resources/language/Portuguese (Brazil)/strings.po
2021-07-22 02:14:01.729 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /home/paulo/.kodi/addons/service.skin.widgets/resources/language/English/strings.po
2021-07-22 02:14:01.733 T:474 DEBUG <general>: LocalizeStrings: loaded 5 strings from file /home/paulo/.kodi/addons/service.subtitles.opensubtitles/resources/language/resource.language.en_GB/strings.po
2021-07-22 02:14:01.738 T:474 DEBUG <general>: LocalizeStrings: loaded 24 strings from file /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/language/resource.language.pt_br/strings.po
2021-07-22 02:14:01.739 T:474 DEBUG <general>: LocalizeStrings: loaded 0 strings from file /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/language/resource.language.en_gb/strings.po
2021-07-22 02:14:01.751 T:503 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:14:01.751 T:503 DEBUG <general>: Initialize, updating databases...
2021-07-22 02:14:01.752 T:503 INFO <general>: Running database version Addons33
2021-07-22 02:14:01.755 T:503 INFO <general>: Running database version ViewModes6
2021-07-22 02:14:01.758 T:503 INFO <general>: Running database version Textures13
2021-07-22 02:14:01.768 T:503 INFO <general>: Running database version MyMusic82
2021-07-22 02:14:01.777 T:503 INFO <general>: Running database version MyVideos119
2021-07-22 02:14:01.780 T:503 INFO <general>: Running database version TV38
2021-07-22 02:14:01.782 T:503 INFO <general>: Running database version Epg13
2021-07-22 02:14:01.783 T:503 DEBUG <general>: Initialize, updating databases... DONE
2021-07-22 02:14:01.812 T:474 INFO <general>: start dvd mediatype detection
2021-07-22 02:14:01.812 T:504 DEBUG <general>: Thread DetectDVDMedia start, auto delete: false
2021-07-22 02:14:01.812 T:504 DEBUG <general>: Compiled with libcdio Version 0.20100
2021-07-22 02:14:01.815 T:504 DEBUG <general>: Thread DetectDVDMedia 2229006464 terminating
2021-07-22 02:14:01.864 T:474 INFO <general>: Unloaded skin
2021-07-22 02:14:01.898 T:474 INFO <general>: load skin from: /home/paulo/.kodi/addons/skin.xonfluence/ (version: 4.1.5)
2021-07-22 02:14:01.898 T:474 INFO <general>: load fonts for skin...
2021-07-22 02:14:01.902 T:474 INFO <general>: Loading skin includes from /home/paulo/.kodi/addons/skin.xonfluence/xml/Includes.xml
2021-07-22 02:14:02.352 T:474 INFO <general>: Loading fonts from /home/paulo/.kodi/addons/skin.xonfluence/xml/Font.xml
2021-07-22 02:14:02.462 T:474 DEBUG <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /home/paulo/.kodi/addons/skin.xonfluence/language/resource.language.pt_br/strings.po
2021-07-22 02:14:02.463 T:474 DEBUG <general>: LocalizeStrings: loaded 150 strings from file /home/paulo/.kodi/addons/skin.xonfluence/language/resource.language.pt_br/strings.po
2021-07-22 02:14:02.465 T:474 DEBUG <general>: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /home/paulo/.kodi/addons/skin.xonfluence/language/resource.language.en_gb/strings.po
2021-07-22 02:14:02.467 T:474 DEBUG <general>: LocalizeStrings: loaded 400 strings from file /home/paulo/.kodi/addons/skin.xonfluence/language/resource.language.en_gb/strings.po
2021-07-22 02:14:02.467 T:474 INFO <general>: load new skin...
2021-07-22 02:14:02.467 T:474 INFO <general>: Loading custom window XMLs from skin path /home/paulo/.kodi/addons/skin.xonfluence/xml
2021-07-22 02:14:02.557 T:474 DEBUG <general>: Load Skin XML: 89.88ms
2021-07-22 02:14:02.557 T:474 INFO <general>: initialize new skin...
2021-07-22 02:14:02.557 T:474 DEBUG <general>: guilib: Fill viewport on change for solving rendering passes
2021-07-22 02:14:02.557 T:474 INFO <general>: Loading skin file: Custom_2134_ShowInfoOnPlayback.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.558 T:474 INFO <general>: Loading skin file: Custom_1113_PVRChannelNumberInput.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.560 T:474 INFO <general>: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.578 T:474 DEBUG <general>: OpenBundle - Opened bundle /home/paulo/.kodi/addons/skin.xonfluence/media/Textures.xbt
2021-07-22 02:14:02.580 T:474 INFO <general>: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.582 T:474 INFO <general>: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.594 T:474 INFO <general>: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.596 T:474 INFO <general>: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.598 T:474 INFO <general>: Loading skin file: Custom_2131_AutoPartySaver.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.599 T:474 INFO <general>: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.600 T:474 INFO <general>: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.603 T:474 INFO <general>: Loading skin file: Custom_2122_AutoMusicViz.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.604 T:474 INFO <general>: Loading skin file: Custom_1195_AutoReFreshList.xml, load type: LOAD_ON_GUI_INIT
2021-07-22 02:14:02.606 T:474 INFO <general>: Loading resource://resource.uisounds.kodi/sounds.xml
2021-07-22 02:14:02.698 T:474 INFO <general>: skin loaded...
2021-07-22 02:14:02.698 T:474 DEBUG <general>: Activating window ID: 12997
2021-07-22 02:14:02.698 T:474 DEBUG <general>: ------ Window Init () ------
2021-07-22 02:14:02.698 T:474 DEBUG <general>: Activating window ID: 12999
2021-07-22 02:14:02.698 T:474 DEBUG <general>: ------ Window Init (Startup.xml) ------
2021-07-22 02:14:02.698 T:474 INFO <general>: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
2021-07-22 02:14:02.820 T:474 DEBUG <general>: Activating window ID: 10000
2021-07-22 02:14:02.820 T:474 DEBUG <general>: [Warning] CGUITextureManager::GetTexturePath: could not find texture '$VAR[SkinBackgroundPath]startup.jpg'
2021-07-22 02:14:02.840 T:474 DEBUG <general>: ------ Window Deinit (Startup.xml) ------
2021-07-22 02:14:02.841 T:562 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:02.854 T:562 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:14:02.854 T:563 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:02.859 T:503 DEBUG <general>: ffmpeg[0x513df08X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:14:02.869 T:563 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:14:02.870 T:564 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:02.880 T:564 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:14:02.881 T:565 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:02.891 T:565 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:14:02.891 T:566 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:02.901 T:566 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:14:02.901 T:567 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:02.911 T:567 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:14:02.911 T:568 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:02.921 T:474 DEBUG <general>: FreeVisualisation() done
2021-07-22 02:14:02.925 T:474 DEBUG <general>: CGBMUtils - using 4 buffers
2021-07-22 02:14:02.945 T:474 DEBUG <general>: ------ Window Init (Home.xml) ------
2021-07-22 02:14:02.946 T:474 INFO <general>: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
2021-07-22 02:14:03.068 T:503 DEBUG <general>: DoWork - took 247 ms to load resource://resource.images.skinbackgrounds.xonfluence/SKINDEFAULT.jpg
2021-07-22 02:14:03.313 T:474 ERROR <general>: unmatched parentheses in string.isempty(listitem.thumb))
2021-07-22 02:14:03.420 T:568 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:14:03.854 T:496 DEBUG <general>: Sink changed
2021-07-22 02:14:03.948 T:474 DEBUG <general>: CDirectoryProvider[videodb://movies/titles/?xsp=%7B%22rules%22%3A%7B%22and%22%3A%5B%7B%22field%22%3A%22playcount%22%2C%22operator%22%3A%22is%22%2C%22value%22%3A%220%22%7D%5D%7D%2C%22type%22%3A%22movies%22%7D]: refreshing..
2021-07-22 02:14:03.952 T:569 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:03.952 T:569 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:14:03.957 T:474 DEBUG <general>: CDirectoryProvider[videodb://movies/titles/?xsp=%7B%22rules%22%3A%7B%22and%22%3A%5B%7B%22field%22%3A%22playcount%22%2C%22operator%22%3A%22is%22%2C%22value%22%3A%220%22%7D%5D%7D%2C%22type%22%3A%22movies%22%7D]: refreshing..
2021-07-22 02:14:03.957 T:570 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:14:03.957 T:474 DEBUG <general>: CDirectoryProvider[videodb://movies/titles/?xsp=%7B%22rules%22%3A%7B%22and%22%3A%5B%7B%22field%22%3A%22playcount%22%2C%22operator%22%3A%22is%22%2C%22value%22%3A%220%22%7D%5D%7D%2C%22type%22%3A%22movies%22%7D]: refreshing..
2021-07-22 02:14:03.957 T:474 DEBUG <general>: CDirectoryProvider[videodb://movies/titles/]: refreshing..
2021-07-22 02:14:03.957 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.957 T:474 DEBUG <general>: CDirectoryProvider[videodb://movies/titles/?xsp=%7B%22rules%22%3A%7B%22and%22%3A%5B%7B%22field%22%3A%22playcount%22%2C%22operator%22%3A%22is%22%2C%22value%22%3A%220%22%7D%5D%7D%2C%22type%22%3A%22movies%22%7D]: refreshing..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: CDirectoryProvider[videodb://movies/titles/?xsp=%7b%22limit%22%3a20%2c%22order%22%3a%7b%22direction%22%3a%22descending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22lastplayed%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22inprogress%22%2c%22operator%22%3a%22true%22%2c%22value%22%3a%5b%5d%7d%5d%7d%2c%22type%22%3a%22movies%22%7d]: refreshing..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: CDirectoryProvider[videodb://movies/titles/?xsp=%7B%22rules%22%3A%7B%22and%22%3A%5B%7B%22field%22%3A%22playcount%22%2C%22operator%22%3A%22is%22%2C%22value%22%3A%220%22%7D%5D%7D%2C%22type%22%3A%22movies%22%7D]: refreshing..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: CDirectoryProvider[videodb://tvshows/titles/-1/-1/-1/-1/?xsp=%7b%22limit%22%3a10%2c%22order%22%3a%7b%22direction%22%3a%22descending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22dateadded%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22playcount%22%2c%22operator%22%3a%22is%22%2c%22value%22%3a%5b%220%22%5d%7d%2c%7b%22field%22%3a%22dateadded%22%2c%22operator%22%3a%22after%22%2c%22value%22%3a%5b%22%201%2f%201%2f2021%22%5d%7d%5d%7d%2c%22type%22%3a%22episodes%22%7d]: refreshing..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: CDirectoryProvider[videodb://tvshows/titles/-1/-1/]: refreshing..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.958 T:474 DEBUG <general>: CDirectoryProvider[videodb://tvshows/titles/-1/-1/?xsp=%7B%22rules%22%3A%7B%22and%22%3A%5B%7B%22field%22%3A%22playcount%22%2C%22operator%22%3A%22is%22%2C%22value%22%3A%220%22%7D%5D%7D%2C%22type%22%3A%22episodes%22%7D]: refreshing..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: CDirectoryProvider[musicdb://recentlyaddedalbums]: refreshing..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: CDirectoryProvider[library://music/albums.xml/]: refreshing..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: CDirectoryProvider[musicdb://albums/?xsp=%7b%22limit%22%3a20%2c%22order%22%3a%7b%22direction%22%3a%22descending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22playcount%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22playcount%22%2c%22operator%22%3a%22greaterthan%22%2c%22value%22%3a%5b%220%22%5d%7d%5d%7d%2c%22type%22%3a%22albums%22%7d]: refreshing..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: CDirectoryProvider[videodb://musicvideos/titles/?xsp=%7B%22rules%22%3A%7B%22and%22%3A%5B%7B%22field%22%3A%22playcount%22%2C%22operator%22%3A%22is%22%2C%22value%22%3A%220%22%7D%5D%7D%2C%22type%22%3A%22musicvideos%22%7D]: refreshing..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: CDirectoryProvider[videodb://musicvideos/titles/]: refreshing..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: CDirectoryProvider[videodb://musicvideos/titles/?xsp=%7b%22limit%22%3a10%2c%22order%22%3a%7b%22direction%22%3a%22ascending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22random%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22playcount%22%2c%22operator%22%3a%22is%22%2c%22value%22%3a%5b%220%22%5d%7d%5d%7d%2c%22type%22%3a%22musicvideos%22%7d]: refreshing..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:03.959 T:474 DEBUG <general>: CDirectoryProvider[videodb://musicvideos/titles/?xsp=%7b%22limit%22%3a20%2c%22order%22%3a%7b%22direction%22%3a%22descending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22playcount%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22playcount%22%2c%22operator%22%3a%22greaterthan%22%2c%22value%22%3a%5b%220%22%5d%7d%5d%7d%2c%22type%22%3a%22musicvideos%22%7d]: refreshing..
2021-07-22 02:14:04.200 T:474 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:04.200 T:474 DEBUG <general>: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
2021-07-22 02:14:04.200 T:474 WARNING <general>: JSONRPC: Could not parse type "Setting.Details.SettingList"
2021-07-22 02:14:04.200 T:474 INFO <general>: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
2021-07-22 02:14:04.201 T:474 INFO <general>: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
2021-07-22 02:14:04.235 T:474 INFO <general>: JSONRPC v12.3.0: Successfully initialized
2021-07-22 02:14:04.237 T:474 DEBUG <general>: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
2021-07-22 02:14:04.237 T:474 DEBUG <general>: LoadMappings - loaded node "CEC Adapter"
2021-07-22 02:14:04.237 T:474 DEBUG <general>: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
2021-07-22 02:14:04.237 T:474 DEBUG <general>: LoadMappings - loaded node "iMON HID device"
2021-07-22 02:14:04.237 T:474 DEBUG <general>: LoadMappings - loaded node "WETEK Play remote"
2021-07-22 02:14:04.237 T:474 DEBUG <general>: LoadMappings - loaded node "OSMC RF Remote"
2021-07-22 02:14:04.237 T:474 DEBUG <general>: LoadMappings - loaded node ""
2021-07-22 02:14:04.244 T:572 DEBUG <general>: Thread PeripBusUSBUdev start, auto delete: false
2021-07-22 02:14:04.244 T:572 DEBUG <general>: Process - initialised udev monitor
2021-07-22 02:14:04.245 T:573 DEBUG <general>: Thread PeripBusCEC start, auto delete: false
2021-07-22 02:14:04.245 T:574 DEBUG <general>: Thread PeripBusAddon start, auto delete: false
2021-07-22 02:14:04.248 T:474 DEBUG <general>: Initialise - initialised peripheral on 'keyboard' with 1 features and 0 sub devices
2021-07-22 02:14:04.248 T:474 INFO <general>: Register - new keyboard device registered on application->keyboard: Teclado (0000:0000)
2021-07-22 02:14:04.249 T:474 DEBUG <general>: Initialise - initialised peripheral on 'mouse' with 1 features and 0 sub devices
2021-07-22 02:14:04.249 T:474 INFO <general>: Register - new mouse device registered on application->mouse: Mouse (0000:0000)
2021-07-22 02:14:04.254 T:575 DEBUG <general>: Thread PeripEventScan start, auto delete: false
2021-07-22 02:14:04.255 T:474 DEBUG <general>: ContextMenuManager: addon menus reloaded.
2021-07-22 02:14:04.255 T:474 INFO <general>: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerCoreConfig::<ctor>: created player VideoPlayer
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerCoreConfig::<ctor>: created player PAPlayer
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerCoreConfig::<ctor>: created player RetroPlayer
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: system rules
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: mms/udp
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: rtmp
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: rtsp
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: streams
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: mp2
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: dvd
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: discimage
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: nsv
2021-07-22 02:14:04.258 T:474 DEBUG <general>: CPlayerSelectionRule::Initialize: creating rule: radio
2021-07-22 02:14:04.258 T:474 INFO <general>: Loaded playercorefactory configuration
2021-07-22 02:14:04.259 T:474 INFO <general>: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
2021-07-22 02:14:04.259 T:474 INFO <general>: special://masterprofile/playercorefactory.xml does not exist. Skipping.
2021-07-22 02:14:04.259 T:474 INFO <general>: removing tempfiles
2021-07-22 02:14:04.260 T:474 WARNING <general>: CRepository::ParseDirConfiguration: Repository has MD5 hashes enabled - this hash function is broken and will only guard against unintentional data corruption
2021-07-22 02:14:04.260 T:474 WARNING <general>: Repository add-on superrepo.kodi.krypton.all uses plain HTTP for add-on downloads in path http://redirect.superrepo.org/v7/addons/ - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2021-07-22 02:14:04.263 T:474 DEBUG <general>: CRepositoryUpdater: closest next update check at 22/07/2021 16:01:24 (in 49640 s)
2021-07-22 02:14:04.263 T:576 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:14:04.264 T:474 DEBUG <general>: CServiceAddonManager: starting plugin.video.twitch
2021-07-22 02:14:04.279 T:577 DEBUG <general>: Thread LanguageInvoker start, auto delete: false
2021-07-22 02:14:04.279 T:474 DEBUG <general>: CServiceAddonManager: starting plugin.video.youtube
2021-07-22 02:14:04.279 T:577 INFO <general>: initializing python engine.
2021-07-22 02:14:04.281 T:578 DEBUG <general>: Thread LanguageInvoker start, auto delete: false
2021-07-22 02:14:04.281 T:474 DEBUG <general>: CServiceAddonManager: starting script.embuary.helper
2021-07-22 02:14:04.281 T:578 INFO <general>: initializing python engine.
2021-07-22 02:14:04.281 T:579 DEBUG <general>: Thread LanguageInvoker start, auto delete: false
2021-07-22 02:14:04.281 T:474 DEBUG <general>: CServiceAddonManager: starting service.library.data.provider
2021-07-22 02:14:04.281 T:579 INFO <general>: initializing python engine.
2021-07-22 02:14:04.282 T:580 DEBUG <general>: Thread LanguageInvoker start, auto delete: false
2021-07-22 02:14:04.282 T:474 DEBUG <general>: CServiceAddonManager: starting service.xbmc.versioncheck
2021-07-22 02:14:04.282 T:580 INFO <general>: initializing python engine.
2021-07-22 02:14:04.284 T:581 DEBUG <general>: Thread LanguageInvoker start, auto delete: false
2021-07-22 02:14:04.284 T:474 INFO <general>: initialize done
2021-07-22 02:14:04.284 T:581 INFO <general>: initializing python engine.
2021-07-22 02:14:04.284 T:474 INFO <general>: Running the application...
2021-07-22 02:14:04.289 T:474 WARNING <general>: CRepository::ParseDirConfiguration: Repository has MD5 hashes enabled - this hash function is broken and will only guard against unintentional data corruption
2021-07-22 02:14:04.289 T:474 WARNING <general>: Repository add-on superrepo.kodi.krypton.all uses plain HTTP for add-on downloads in path http://redirect.superrepo.org/v7/addons/ - this is insecure and will make your Kodi installation vulnerable to attacks if enabled!
2021-07-22 02:14:04.290 T:474 DEBUG <general>: NetworkMessage - Starting network services
2021-07-22 02:14:04.297 T:573 DEBUG <general>: GetMappingForDevice - device (0000:0000) mapped to CEC Adapter (type = cec)
2021-07-22 02:14:04.298 T:474 DEBUG <general>: CZeroconfAvahi::clientCallback: client is up and running
2021-07-22 02:14:04.299 T:474 INFO <general>: starting zeroconf publishing
2021-07-22 02:14:04.299 T:573 DEBUG <general>: InitialiseFeature - using libCEC v6.0.2
2021-07-22 02:14:04.299 T:584 DEBUG <general>: Thread EventServer start, auto delete: false
2021-07-22 02:14:04.299 T:584 INFO <general>: ES: Starting UDP Event server on port 9777
2021-07-22 02:14:04.299 T:585 DEBUG <general>: Thread CECAdapter start, auto delete: false
2021-07-22 02:14:04.299 T:585 DEBUG <general>: OpenConnection - opening a connection to the CEC adapter: Linux
2021-07-22 02:14:04.299 T:573 DEBUG <general>: Initialise - initialised peripheral on 'Linux' with 2 features and 0 sub devices
2021-07-22 02:14:04.299 T:573 INFO <general>: Register - new cec device registered on cec->Linux: CEC Adapter (0000:0000)
2021-07-22 02:14:04.299 T:584 DEBUG <general>: CheckIPv6: IPv6 socket bound successfully
2021-07-22 02:14:04.299 T:584 INFO <general>: UDP: Listening on port 9777 (ipv6 : true)
2021-07-22 02:14:04.314 T:474 ERROR <general>: JSONRPC Server: Failed to connect to sdpd
2021-07-22 02:14:04.314 T:474 INFO <general>: JSONRPC Server: Successfully initialized
2021-07-22 02:14:04.314 T:593 DEBUG <general>: Thread TCPServer start, auto delete: false
2021-07-22 02:14:04.315 T:474 DEBUG <libmicrohttpd>: MHD_OPTION_EXTERNAL_LOGGER is not the first option specified for the daemon. Some messages may be printed by the standard MHD logger.
2021-07-22 02:14:04.315 T:474 INFO <CWebserver[8080]>: Skipped 1 duplicate messages..
2021-07-22 02:14:04.315 T:474 INFO <CWebserver[8080]>: Started
2021-07-22 02:14:04.315 T:474 DEBUG <general>: ------ Window Init (DialogNotification.xml) ------
2021-07-22 02:14:04.316 T:596 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:14:04.322 T:596 DEBUG <general>: ffmpeg[0x5f3e700X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:14:04.435 T:503 DEBUG <general>: ffmpeg[0x513df08X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:14:04.438 T:503 DEBUG <general>: ffmpeg[0x513df08X]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
2021-07-22 02:14:04.488 T:503 DEBUG <general>: ffmpeg[0x513df08X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:14:04.506 T:503 DEBUG <general>: ffmpeg[0x513df08X]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
2021-07-22 02:14:04.561 T:577 DEBUG <general>: CPythonInvoker(0, /home/paulo/.kodi/addons/plugin.video.twitch/resources/lib/service_runner.py): start processing
2021-07-22 02:14:04.561 T:578 DEBUG <general>: CPythonInvoker(1, /home/paulo/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): start processing
2021-07-22 02:14:04.561 T:579 DEBUG <general>: CPythonInvoker(2, /home/paulo/.kodi/addons/script.embuary.helper/service.py): start processing
2021-07-22 02:14:04.561 T:580 DEBUG <general>: CPythonInvoker(3, /home/paulo/.kodi/addons/service.library.data.provider/service.py): start processing
2021-07-22 02:14:04.561 T:581 DEBUG <general>: CPythonInvoker(4, /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py): start processing
2021-07-22 02:14:04.585 T:596 DEBUG <general>: DoWork - took 269 ms to load resource://resource.images.skinbackgrounds.xonfluence/SKINDEFAULT.jpg
2021-07-22 02:14:04.800 T:474 DEBUG <general>: LogindUPowerSyscall - Received unknown signal NameAcquired
2021-07-22 02:14:04.853 T:577 DEBUG <general>: -->Python Interpreter Initialized<--
2021-07-22 02:14:04.853 T:577 DEBUG <general>:
2021-07-22 02:14:04.853 T:577 DEBUG <general>: CPythonInvoker(0, /home/paulo/.kodi/addons/plugin.video.twitch/resources/lib/service_runner.py): the source file to load is "/home/paulo/.kodi/addons/plugin.video.twitch/resources/lib/service_runner.py"
2021-07-22 02:14:04.854 T:577 DEBUG <general>: CPythonInvoker(0, /home/paulo/.kodi/addons/plugin.video.twitch/resources/lib/service_runner.py): setting the Python path to /home/paulo/.kodi/addons/plugin.video.twitch/resources/lib:/home/paulo/.kodi/addons/script.module.certifi/lib:/home/paulo/.kodi/addons/script.module.chardet/lib:/home/paulo/.kodi/addons/script.module.idna/lib:/home/paulo/.kodi/addons/script.module.python.twitch/resources/lib:/home/paulo/.kodi/addons/script.module.requests/lib:/home/paulo/.kodi/addons/script.module.six/lib:/home/paulo/.kodi/addons/script.module.urllib3/lib:/usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload:/usr/lib/python3.9/site-packages
2021-07-22 02:14:04.857 T:577 DEBUG <general>: CPythonInvoker(0, /home/paulo/.kodi/addons/plugin.video.twitch/resources/lib/service_runner.py): entering source directory /home/paulo/.kodi/addons/plugin.video.twitch/resources/lib
2021-07-22 02:14:04.866 T:577 DEBUG <general>: CPythonInvoker(0, /home/paulo/.kodi/addons/plugin.video.twitch/resources/lib/service_runner.py): instantiating addon using automatically obtained id of "plugin.video.twitch" dependent on version 3.0.0 of the xbmc.python api
2021-07-22 02:14:04.895 T:578 DEBUG <general>: -->Python Interpreter Initialized<--
2021-07-22 02:14:04.895 T:578 DEBUG <general>:
2021-07-22 02:14:04.895 T:578 DEBUG <general>: CPythonInvoker(1, /home/paulo/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): the source file to load is "/home/paulo/.kodi/addons/plugin.video.youtube/resources/lib/startup.py"
2021-07-22 02:14:04.895 T:578 DEBUG <general>: CPythonInvoker(1, /home/paulo/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): setting the Python path to /home/paulo/.kodi/addons/plugin.video.youtube/resources/lib:/home/paulo/.kodi/addons/script.module.certifi/lib:/home/paulo/.kodi/addons/script.module.chardet/lib:/home/paulo/.kodi/addons/script.module.idna/lib:/home/paulo/.kodi/addons/script.module.requests/lib:/home/paulo/.kodi/addons/script.module.six/lib:/home/paulo/.kodi/addons/script.module.urllib3/lib:/usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload:/usr/lib/python3.9/site-packages
2021-07-22 02:14:04.895 T:578 DEBUG <general>: CPythonInvoker(1, /home/paulo/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): entering source directory /home/paulo/.kodi/addons/plugin.video.youtube/resources/lib
2021-07-22 02:14:04.896 T:578 DEBUG <general>: CPythonInvoker(1, /home/paulo/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 3.0.0 of the xbmc.python api
2021-07-22 02:14:04.900 T:579 DEBUG <general>: -->Python Interpreter Initialized<--
2021-07-22 02:14:04.900 T:579 DEBUG <general>:
2021-07-22 02:14:04.900 T:579 DEBUG <general>: CPythonInvoker(2, /home/paulo/.kodi/addons/script.embuary.helper/service.py): the source file to load is "/home/paulo/.kodi/addons/script.embuary.helper/service.py"
2021-07-22 02:14:04.900 T:579 DEBUG <general>: CPythonInvoker(2, /home/paulo/.kodi/addons/script.embuary.helper/service.py): setting the Python path to /home/paulo/.kodi/addons/script.embuary.helper:/usr/share/kodi/addons/script.module.pil/lib:/usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload:/usr/lib/python3.9/site-packages
2021-07-22 02:14:04.900 T:579 DEBUG <general>: CPythonInvoker(2, /home/paulo/.kodi/addons/script.embuary.helper/service.py): entering source directory /home/paulo/.kodi/addons/script.embuary.helper
2021-07-22 02:14:04.902 T:579 DEBUG <general>: CPythonInvoker(2, /home/paulo/.kodi/addons/script.embuary.helper/service.py): instantiating addon using automatically obtained id of "script.embuary.helper" dependent on version 3.0.0 of the xbmc.python api
2021-07-22 02:14:04.906 T:581 DEBUG <general>: -->Python Interpreter Initialized<--
2021-07-22 02:14:04.906 T:581 DEBUG <general>:
2021-07-22 02:14:04.906 T:581 DEBUG <general>: CPythonInvoker(4, /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py): the source file to load is "/home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py"
2021-07-22 02:14:04.907 T:581 DEBUG <general>: CPythonInvoker(4, /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py): setting the Python path to /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib:/usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload:/usr/lib/python3.9/site-packages
2021-07-22 02:14:04.907 T:581 DEBUG <general>: CPythonInvoker(4, /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py): entering source directory /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib
2021-07-22 02:14:04.907 T:581 DEBUG <general>: CPythonInvoker(4, /home/paulo/.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
2021-07-22 02:14:04.942 T:580 DEBUG <general>: -->Python Interpreter Initialized<--
2021-07-22 02:14:04.942 T:580 DEBUG <general>:
2021-07-22 02:14:04.942 T:580 DEBUG <general>: CPythonInvoker(3, /home/paulo/.kodi/addons/service.library.data.provider/service.py): the source file to load is "/home/paulo/.kodi/addons/service.library.data.provider/service.py"
2021-07-22 02:14:04.943 T:580 DEBUG <general>: CPythonInvoker(3, /home/paulo/.kodi/addons/service.library.data.provider/service.py): setting the Python path to /home/paulo/.kodi/addons/service.library.data.provider:/home/paulo/.kodi/addons/script.module.routing/lib:/usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload:/usr/lib/python3.9/site-packages
2021-07-22 02:14:04.943 T:580 DEBUG <general>: CPythonInvoker(3, /home/paulo/.kodi/addons/service.library.data.provider/service.py): entering source directory /home/paulo/.kodi/addons/service.library.data.provider
2021-07-22 02:14:04.943 T:580 DEBUG <general>: CPythonInvoker(3, /home/paulo/.kodi/addons/service.library.data.provider/service.py): instantiating addon using automatically obtained id of "service.library.data.provider" dependent on version 3.0.0 of the xbmc.python api
2021-07-22 02:14:04.955 T:583 DEBUG <general>: SetConfigurationFromLibCEC - settings updated by libCEC
2021-07-22 02:14:05.162 T:596 DEBUG <general>: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY dateAdded DESC LIMIT 25) 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
2021-07-22 02:14:05.228 T:596 ERROR <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:05.228 T:596 ERROR <general>: GetDirectory - Error getting library://music/albums.xml/
2021-07-22 02:14:05.240 T:570 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:05.240 T:570 DEBUG <general>: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE (((CAST(albumview.iTimesPlayed as DECIMAL(6,1)) > 0))) AND (albumview.strReleaseType = 'album') ORDER BY albumview.iTimesPlayed DESC, albumview.strAlbum COLLATE ALPHANUM DESC, albumview.idAlbum DESC LIMIT 20
2021-07-22 02:14:05.355 T:596 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:05.355 T:596 DEBUG <general>: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
2021-07-22 02:14:05.357 T:503 DEBUG <general>: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (HTPC) port:9777
2021-07-22 02:14:05.357 T:503 DEBUG <general>: CZeroconfAvahi::addService() named: Kodi (HTPC) type: _xbmc-events._udp port:9777
2021-07-22 02:14:05.361 T:503 DEBUG <general>: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (HTPC) port:9090
2021-07-22 02:14:05.361 T:503 DEBUG <general>: CZeroconfAvahi::addService() named: Kodi (HTPC) type: _xbmc-jsonrpc._tcp port:9090
2021-07-22 02:14:05.364 T:503 DEBUG <general>: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (HTPC) port:8080
2021-07-22 02:14:05.364 T:503 DEBUG <general>: CZeroconfAvahi::addService() named: Kodi (HTPC) type: _http._tcp port:8080
2021-07-22 02:14:05.366 T:570 DEBUG <general>: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (HTPC) port:8080
2021-07-22 02:14:05.367 T:570 DEBUG <general>: CZeroconfAvahi::addService() named: Kodi (HTPC) type: _xbmc-jsonrpc-h._tcp port:8080
2021-07-22 02:14:05.372 T:570 DEBUG <general>: GetImageHash - unable to stat url /home/paulo/.kodi/addons/plugin.video.twitch/icon.png
2021-07-22 02:14:05.374 T:596 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
2021-07-22 02:14:05.377 T:596 DEBUG <general>: 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
2021-07-22 02:14:05.385 T:596 DEBUG <general>: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
2021-07-22 02:14:05.435 T:580 DEBUG <CAddonSettings[service.library.data.provider]>: trying to load setting definitions from old format...
2021-07-22 02:14:05.437 T:580 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:05.437 T:580 DEBUG <general>: Library Data Provider: b'service version 0.4.1 started'
2021-07-22 02:14:05.469 T:596 DEBUG <general>: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
2021-07-22 02:14:05.695 T:585 DEBUG <general>: OpenConnection - connection to the CEC adapter opened
2021-07-22 02:14:05.695 T:585 DEBUG <general>: SetConfigurationFromLibCEC - settings updated by libCEC
2021-07-22 02:14:05.695 T:598 DEBUG <general>: Thread CECAdapterUpdate start, auto delete: false
2021-07-22 02:14:05.696 T:598 DEBUG <general>: UpdateMenuLanguage - using TV menu language is disabled
2021-07-22 02:14:05.822 T:577 WARNING <general>: script.module.python.twitch: API version |V5| is deprecated, update to |Helix| by |TBD|
2021-07-22 02:14:05.832 T:580 DEBUG <general>: GetSongsByWhereJSON query: SELECT sv.*, song_artist.idArtist AS idArtist, songartist.strArtist AS strArtist FROM (SELECT song.idSong, strTitle, song.strGenres, iTimesPlayed, lastPlayed, rating, path.strPath|| strFilename AS strPathFile, album.strAlbum AS strAlbum, CAST(song.strReleaseDate AS INTEGER) AS iYear FROM song JOIN album ON album.idAlbum = song.idAlbum JOIN path ON path.idPath = song.idPath WHERE ((CAST(song.iTimesPlayed as DECIMAL(6,1)) < 1)) ORDER BY RANDOM() LIMIT 20) AS sv JOIN song_artist ON song_artist.idSong = sv.idSong AND song_artist.idRole = 1 JOIN artist AS songartist ON songartist.idArtist = song_artist.idArtist GROUP BY sv.idSong, song_artist.idArtist ORDER BY sv.idSong, song_artist.iOrder
2021-07-22 02:14:05.833 T:580 DEBUG <general>: GetSongsByWhereJSON - query took 1 ms
2021-07-22 02:14:05.838 T:598 DEBUG <general>: UpdateAudioSystemStatus - no CEC capable amplifier found
2021-07-22 02:14:05.842 T:580 DEBUG <general>: GetAlbumsByWhereJSON query: SELECT a1.*, album_artist.idArtist AS idArtist, artist.strArtist AS strArtist FROM (SELECT albumview.idAlbum, strAlbum, strReview, strGenres, strThemes, strMoods, strStyles, strType, strLabel, fRating, iTimesPlayed, CAST(strReleaseDate AS INTEGER) AS iYear, art.url AS thumbnail FROM albumview LEFT JOIN art ON art.media_id = idAlbum AND art.media_type = 'album' AND art.type = 'thumb' WHERE albumview.strReleaseType = 'album' ORDER BY RANDOM() LIMIT 20) AS a1 JOIN album_artist ON album_artist.idAlbum = a1.idAlbum JOIN artist ON artist.idArtist = album_artist.idArtist GROUP BY a1.idAlbum, album_artist.idArtist ORDER BY a1.idAlbum, album_artist.iOrder
2021-07-22 02:14:05.843 T:580 DEBUG <general>: GetAlbumsByWhereJSON - query took 1 ms
2021-07-22 02:14:05.980 T:581 DEBUG <CSettingsManager>: requested setting (lastnotified_stable) was not found.
2021-07-22 02:14:05.981 T:581 DEBUG <general>: Version Check: Version 0.5.19+matrix.1 started
2021-07-22 02:14:06.155 T:580 DEBUG <general>: GetAlbumsByWhereJSON query: SELECT a1.*, album_artist.idArtist AS idArtist, artist.strArtist AS strArtist FROM (SELECT albumview.idAlbum, strAlbum, strReview, strGenres, strThemes, strMoods, strStyles, strType, strLabel, fRating, iTimesPlayed, dateAdded, CAST(strReleaseDate AS INTEGER) AS iYear, art.url AS thumbnail FROM albumview LEFT JOIN art ON art.media_id = idAlbum AND art.media_type = 'album' AND art.type = 'thumb' WHERE albumview.strReleaseType = 'album' ORDER BY albumview.dateAdded DESC, albumview.idAlbum DESC LIMIT 20) AS a1 JOIN album_artist ON album_artist.idAlbum = a1.idAlbum JOIN artist ON artist.idArtist = album_artist.idArtist GROUP BY a1.idAlbum, album_artist.idArtist ORDER BY a1.dateAdded DESC, a1.idAlbum DESC, album_artist.iOrder
2021-07-22 02:14:06.156 T:580 DEBUG <general>: GetAlbumsByWhereJSON - query took 1 ms
2021-07-22 02:14:06.176 T:582 DEBUG <general>: CZeroconfAvahi::groupCallback: Service successfully established
2021-07-22 02:14:06.266 T:580 DEBUG <general>: Skipped 3 duplicate messages..
2021-07-22 02:14:06.266 T:580 DEBUG <general>: DatabaseUtils::GetSortFieldList: unknown field 36
2021-07-22 02:14:06.553 T:580 DEBUG <general>: Skipped 9 duplicate messages..
2021-07-22 02:14:06.553 T:580 DEBUG <general>: GetAlbumsByWhereJSON query: SELECT a1.*, album_artist.idArtist AS idArtist, artist.strArtist AS strArtist FROM (SELECT albumview.idAlbum, strAlbum, strReview, strGenres, strThemes, strMoods, strStyles, strType, strLabel, fRating, iTimesPlayed, CAST(strReleaseDate AS INTEGER) AS iYear, art.url AS thumbnail FROM albumview LEFT JOIN art ON art.media_id = idAlbum AND art.media_type = 'album' AND art.type = 'thumb' WHERE albumview.strReleaseType = 'album' ORDER BY albumview.iTimesPlayed DESC, albumview.strAlbum COLLATE ALPHANUM DESC, albumview.idAlbum DESC LIMIT 20) AS a1 JOIN album_artist ON album_artist.idAlbum = a1.idAlbum JOIN artist ON artist.idArtist = album_artist.idArtist GROUP BY a1.idAlbum, album_artist.idArtist ORDER BY a1.iTimesPlayed DESC, a1.strAlbum COLLATE ALPHANUM DESC, a1.idAlbum DESC, album_artist.iOrder
2021-07-22 02:14:06.554 T:580 DEBUG <general>: GetAlbumsByWhereJSON - query took 1 ms
2021-07-22 02:14:06.600 T:580 DEBUG <general>: Library Data Provider: b'Total time needed for all queries: 0:00:01.162944'
2021-07-22 02:14:06.854 T:579 DEBUG <CAddonSettings[script.embuary.helper]>: trying to load setting definitions from old format...
2021-07-22 02:14:06.968 T:579 INFO <general>: [ script.embuary.helper ] Service: Started
2021-07-22 02:14:06.968 T:579 INFO <general>: [ script.embuary.helper ] Service: Player monitor started
2021-07-22 02:14:06.974 T:579 DEBUG <general>: [ script.embuary.helper ] Start new fanart grabber process
2021-07-22 02:14:07.117 T:579 DEBUG <general>: GetArtistsByWhereJSON query: SELECT a1.*, art.art_id AS idArt, art.type AS artType, art.url AS artURL FROM (SELECT artist.idArtist, strArtist FROM artist WHERE (EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artist.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artist.idArtist)) AND (artist.strArtist != '') ORDER BY RANDOM() LIMIT 40) AS a1 LEFT JOIN art ON art.media_id = a1.idArtist AND art.media_type = 'artist' GROUP BY a1.idArtist, art.art_id ORDER BY a1.idArtist, arttype
2021-07-22 02:14:07.117 T:579 DEBUG <general>: GetArtistsByWhereJSON - query took 0 ms
2021-07-22 02:14:07.735 T:577 DEBUG <CSettingsManager>: requested setting (use_cache) was not found.
2021-07-22 02:14:07.756 T:577 INFO <general>: Twitch: Service: Start
2021-07-22 02:14:07.781 T:577 DEBUG <general>: Twitch: Startup: detected |Kodi| |19.1 (19.1.0) Git:20210719-nogitfound| -> |19|1|nogitfound|0|20210719-nogitfound|, setting IA_SUPPORT_BUILTIN = False, IA_SUPPORT_ADDON = True
2021-07-22 02:14:07.793 T:601 DEBUG <general>: Twitch: LiveNotificationsThread: Starting...
2021-07-22 02:14:07.793 T:601 DEBUG <general>: Twitch: Player: Start
2021-07-22 02:14:07.814 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:14:07.814 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:14:07.815 T:578 DEBUG <general>: [plugin.video.youtube] send_notification: |check_settings| -> |{"use_httpd": true, "httpd_port": 50152, "whitelist": "", "httpd_address": "0.0.0.0"}|
2021-07-22 02:14:07.819 T:578 DEBUG <general>: [plugin.video.youtube] JSONStore Load |b'/home/paulo/.kodi/userdata/addon_data/plugin.video.youtube/api_keys.json'|
2021-07-22 02:14:07.820 T:578 DEBUG <general>: [plugin.video.youtube] JSONStore Load |b'/home/paulo/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json'|
2021-07-22 02:14:07.821 T:578 DEBUG <general>: [plugin.video.youtube] User: |0| Using API key set: |own|
2021-07-22 02:14:07.848 T:578 DEBUG <general>: [plugin.video.youtube] YouTube service initialization...
2021-07-22 02:14:07.855 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:14:07.856 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:14:07.856 T:578 DEBUG <general>: [plugin.video.youtube] HTTPServer: Starting |0.0.0.0:50152|
2021-07-22 02:14:07.862 T:578 DEBUG <general>: [plugin.video.youtube] HTTPServer: Serving on |0.0.0.0:50152|
2021-07-22 02:14:07.871 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:14:07.871 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:14:07.893 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:14:07.893 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:14:07.916 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:14:07.916 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:14:07.924 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:14:07.925 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:14:10.990 T:581 DEBUG <general>: Version Check: Version installed {'major': 19, 'minor': 1, 'revision': '20210719-nogitfound', 'tag': 'stable'}
2021-07-22 02:14:10.990 T:581 DEBUG <general>: Version Check: Version available {'major': '19', 'minor': '1', 'tag': 'stable', 'tagversion': '', 'revision': '20210508-85e05228b4', 'extrainfo': 'final', 'addon_support': 'yes'}
2021-07-22 02:14:10.990 T:581 DEBUG <general>: Version Check: There is no newer stable available
2021-07-22 02:14:10.990 T:581 INFO <general>: CPythonInvoker(4, /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py): script successfully run
2021-07-22 02:14:10.990 T:581 DEBUG <general>: onExecutionDone(4, /home/paulo/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py)
2021-07-22 02:14:10.999 T:581 DEBUG <general>: .
2021-07-22 02:14:11.021 T:581 INFO <general>: Python interpreter stopped
2021-07-22 02:14:11.021 T:581 DEBUG <general>: Thread LanguageInvoker 2102349952 terminating
2021-07-22 02:14:14.238 T:474 DEBUG <general>: ------ Window Deinit (DialogNotification.xml) ------
2021-07-22 02:14:18.203 T:593 DEBUG <general>: JSONRPC Server: New connection detected
2021-07-22 02:14:18.204 T:593 INFO <general>: JSONRPC Server: New connection added
2021-07-22 02:14:18.302 T:603 DEBUG <CWebserver[8080]>: request received for /
2021-07-22 02:14:23.212 T:474 DEBUG <general>: Activating window ID: 10111
2021-07-22 02:14:23.213 T:474 DEBUG <general>: ------ Window Init (DialogButtonMenu.xml) ------
2021-07-22 02:14:23.213 T:474 INFO <general>: Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY
2021-07-22 02:14:25.902 T:474 DEBUG <general>: ------ Window Deinit (DialogButtonMenu.xml) ------
2021-07-22 02:14:26.292 T:474 DEBUG <general>: Activating window ID: 10111
2021-07-22 02:14:26.292 T:474 DEBUG <general>: ------ Window Init (DialogButtonMenu.xml) ------
2021-07-22 02:14:28.036 T:474 DEBUG <general>: ------ Window Deinit (DialogButtonMenu.xml) ------
2021-07-22 02:14:29.364 T:474 DEBUG <general>: Activating window ID: 10111
2021-07-22 02:14:29.364 T:474 DEBUG <general>: ------ Window Init (DialogButtonMenu.xml) ------
2021-07-22 02:14:30.653 T:474 DEBUG <general>: ------ Window Deinit (DialogButtonMenu.xml) ------
2021-07-22 02:14:31.044 T:474 DEBUG <general>: Activating window ID: 10111
2021-07-22 02:14:31.044 T:474 DEBUG <general>: ------ Window Init (DialogButtonMenu.xml) ------
2021-07-22 02:14:31.901 T:474 DEBUG <general>: ------ Window Deinit (DialogButtonMenu.xml) ------
2021-07-22 02:14:33.089 T:474 DEBUG <general>: Activating window ID: 10111
2021-07-22 02:14:33.089 T:474 DEBUG <general>: ------ Window Init (DialogButtonMenu.xml) ------
2021-07-22 02:14:34.118 T:474 DEBUG <general>: ------ Window Deinit (DialogButtonMenu.xml) ------
2021-07-22 02:14:34.501 T:569 DEBUG <general>: Thread JobWorker 2206634112 terminating (autodelete)
2021-07-22 02:14:35.204 T:474 DEBUG <general>: Activating window ID: 10134
2021-07-22 02:14:35.204 T:474 DEBUG <general>: ------ Window Init (DialogFavourites.xml) ------
2021-07-22 02:14:35.204 T:474 INFO <general>: Loading skin file: DialogFavourites.xml, load type: KEEP_IN_MEMORY
2021-07-22 02:14:35.367 T:503 DEBUG <general>: Thread JobWorker 2237399168 terminating (autodelete)
2021-07-22 02:14:35.372 T:570 DEBUG <general>: Thread JobWorker 2198241408 terminating (autodelete)
2021-07-22 02:14:35.513 T:596 DEBUG <general>: Thread JobWorker 2010030208 terminating (autodelete)
2021-07-22 02:14:35.539 T:474 DEBUG <general>: ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:14:35.546 T:474 DEBUG <general>: ffmpeg[(nil)X]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra.
2021-07-22 02:14:35.645 T:474 DEBUG <general>: ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:14:41.435 T:474 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:41.435 T:474 DEBUG <general>: Activating window ID: 10003
2021-07-22 02:14:41.672 T:474 DEBUG <general>: ------ Window Deinit (DialogFavourites.xml) ------
2021-07-22 02:14:41.701 T:474 DEBUG <general>: ------ Window Deinit (Home.xml) ------
2021-07-22 02:14:41.703 T:474 DEBUG <general>: FreeVisualisation() done
2021-07-22 02:14:41.729 T:474 INFO <general>: Attempting to default to:
2021-07-22 02:14:41.729 T:474 DEBUG <general>: ------ Window Init (FileManager.xml) ------
2021-07-22 02:14:41.729 T:474 INFO <general>: Loading skin file: FileManager.xml, load type: KEEP_IN_MEMORY
2021-07-22 02:14:41.848 T:632 DEBUG <general>: Thread waiting start, auto delete: false
2021-07-22 02:14:41.873 T:632 DEBUG <general>: Thread waiting 2010030208 terminating
2021-07-22 02:14:41.889 T:633 DEBUG <general>: Thread waiting start, auto delete: false
2021-07-22 02:14:41.899 T:633 DEBUG <general>: Thread waiting 2010030208 terminating
2021-07-22 02:14:47.838 T:634 DEBUG <general>: Thread waiting start, auto delete: false
2021-07-22 02:14:47.845 T:634 DEBUG <general>: Thread waiting 2010030208 terminating
2021-07-22 02:14:56.104 T:635 DEBUG <general>: Thread waiting start, auto delete: false
2021-07-22 02:14:56.166 T:635 DEBUG <general>: Thread waiting 2010030208 terminating
2021-07-22 02:14:56.482 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Deactivate
2021-07-22 02:14:56.721 T:474 DEBUG <general>: ------ Window Deinit (FileManager.xml) ------
2021-07-22 02:14:56.722 T:474 DEBUG <general>: FreeVisualisation() done
2021-07-22 02:14:56.745 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Activate new
2021-07-22 02:14:56.745 T:474 DEBUG <general>: ------ Window Init (Home.xml) ------
2021-07-22 02:14:56.755 T:474 DEBUG <general>: CDirectoryProvider[None]: refreshing..
2021-07-22 02:14:56.756 T:636 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:14:56.756 T:474 DEBUG <general>: CDirectoryProvider[None]: refreshing..
2021-07-22 02:14:56.756 T:636 ERROR <general>: GetDirectory - Error getting None
2021-07-22 02:14:56.756 T:637 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:14:56.756 T:474 DEBUG <general>: CDirectoryProvider[None]: refreshing..
2021-07-22 02:14:56.756 T:637 ERROR <general>: GetDirectory - Error getting None
2021-07-22 02:14:56.756 T:474 DEBUG <general>: CDirectoryProvider[None]: refreshing..
2021-07-22 02:14:56.756 T:636 ERROR <general>: GetDirectory - Error getting None
2021-07-22 02:14:59.252 T:474 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:14:59.252 T:474 DEBUG <general>: Activating window ID: 10134
2021-07-22 02:14:59.252 T:474 DEBUG <general>: ------ Window Init (DialogFavourites.xml) ------
2021-07-22 02:14:59.274 T:474 DEBUG <general>: ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:01.593 T:474 DEBUG <general>: Activating window ID: 10002
2021-07-22 02:15:01.802 T:474 DEBUG <general>: ------ Window Deinit (DialogFavourites.xml) ------
2021-07-22 02:15:01.837 T:474 DEBUG <general>: ------ Window Deinit (Home.xml) ------
2021-07-22 02:15:01.838 T:474 DEBUG <general>: FreeVisualisation() done
2021-07-22 02:15:01.874 T:474 DEBUG <general>: ------ Window Init (MyPics.xml) ------
2021-07-22 02:15:01.874 T:474 INFO <general>: Loading skin file: MyPics.xml, load type: KEEP_IN_MEMORY
2021-07-22 02:15:02.030 T:474 DEBUG <general>: CGUIMediaWindow::GetDirectory (/home/paulo/torrents-nas/4 - BB/)
2021-07-22 02:15:02.030 T:474 DEBUG <general>: ParentPath = []
2021-07-22 02:15:02.118 T:638 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:15:02.293 T:638 DEBUG <general>: Thread BackgroundLoader 2237399168 terminating
2021-07-22 02:15:03.750 T:474 DEBUG <general>: CGUIMediaWindow::GetDirectory (/home/paulo/torrents-nas/4 - BB/Benchmark/)
2021-07-22 02:15:03.750 T:474 DEBUG <general>: ParentPath = [/home/paulo/torrents-nas/4 - BB/]
2021-07-22 02:15:03.751 T:639 DEBUG <general>: Thread waiting start, auto delete: false
2021-07-22 02:15:03.760 T:639 DEBUG <general>: Thread waiting 2206634112 terminating
2021-07-22 02:15:03.775 T:640 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:15:03.775 T:640 DEBUG <general>: Thread BackgroundLoader 2206634112 terminating
2021-07-22 02:15:03.780 T:641 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:15:03.800 T:636 DEBUG <general>: DoWork - trying to extract thumb from video file /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4
2021-07-22 02:15:03.803 T:636 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> opening
2021-07-22 02:15:03.808 T:636 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> source chunk size is 0, setting cache chunk size to 131072
2021-07-22 02:15:03.808 T:636 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> using single memory cache sized 52428800 bytes
2021-07-22 02:15:03.809 T:641 DEBUG <general>: Thread BackgroundLoader 2237399168 terminating
2021-07-22 02:15:03.809 T:642 DEBUG <general>: Thread FileCache start, auto delete: false
2021-07-22 02:15:03.813 T:643 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:15:03.821 T:637 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:15:03.821 T:637 DEBUG <general>: ffmpeg[0x71dc208X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:03.830 T:643 DEBUG <general>: ffmpeg[0x61feca8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:03.832 T:644 DEBUG <general>: ffmpeg[0x7451100X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:03.933 T:636 DEBUG <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2021-07-22 02:15:04.118 T:642 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> cache completely reset for seek to position 673092790
2021-07-22 02:15:04.118 T:636 DEBUG <general>: CFileCache::Seek - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> waiting for position 673223804
2021-07-22 02:15:04.467 T:636 DEBUG <general>: Open - avformat_find_stream_info starting
2021-07-22 02:15:04.467 T:642 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> source read hit eof
2021-07-22 02:15:04.488 T:642 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> cache completely reset for seek to position 430604
2021-07-22 02:15:04.678 T:636 DEBUG <general>: Open - av_find_stream_info finished
2021-07-22 02:15:04.679 T:636 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-07-22 02:15:04.679 T:636 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-07-22 02:15:04.679 T:636 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-07-22 02:15:04.679 T:636 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-07-22 02:15:05.911 T:636 DEBUG <general>: ScanForExternalSubtitles: END (total time: 1232 ms)
2021-07-22 02:15:05.912 T:636 INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2021-07-22 02:15:05.912 T:636 DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2021-07-22 02:15:05.912 T:636 DEBUG <general>: ExtractThumb - seeking to pos 211511ms (total: 634533ms) in /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4
2021-07-22 02:15:05.913 T:636 DEBUG <general>: SeekTime - unknown position after seek
2021-07-22 02:15:05.914 T:642 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> cache completely reset for seek to position 193353535
2021-07-22 02:15:06.625 T:636 DEBUG <general>: cached image 'special://masterprofile/Thumbnails/e/e17d344a.jpg' size 720x405
2021-07-22 02:15:06.682 T:636 DEBUG <general>: ExtractThumb - measured 2880 ms to extract thumb from file </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_60fps_normal.mp4> in 3 packets.
2021-07-22 02:15:07.321 T:642 DEBUG <general>: Thread FileCache 2206634112 terminating
2021-07-22 02:15:07.400 T:643 DEBUG <general>: DoWork - trying to extract thumb from video file /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4
2021-07-22 02:15:07.401 T:643 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> opening
2021-07-22 02:15:07.418 T:637 DEBUG <general>: ffmpeg[0x71dc208X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:07.962 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:15:07.963 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:15:07.980 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:15:07.981 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:15:07.991 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:15:07.991 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:15:08.011 T:643 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> source chunk size is 0, setting cache chunk size to 131072
2021-07-22 02:15:08.012 T:643 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> using single memory cache sized 52428800 bytes
2021-07-22 02:15:08.012 T:645 DEBUG <general>: Thread FileCache start, auto delete: false
2021-07-22 02:15:08.176 T:643 DEBUG <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2021-07-22 02:15:08.340 T:645 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> cache completely reset for seek to position 276003875
2021-07-22 02:15:08.340 T:643 DEBUG <general>: CFileCache::Seek - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> waiting for position 276134889
2021-07-22 02:15:08.724 T:643 DEBUG <general>: Open - avformat_find_stream_info starting
2021-07-22 02:15:08.724 T:645 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> source read hit eof
2021-07-22 02:15:08.726 T:645 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> cache completely reset for seek to position 236997
2021-07-22 02:15:08.778 T:643 DEBUG <general>: Open - av_find_stream_info finished
2021-07-22 02:15:08.779 T:643 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-07-22 02:15:08.779 T:643 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-07-22 02:15:08.779 T:643 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-07-22 02:15:08.779 T:643 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-07-22 02:15:08.787 T:643 DEBUG <general>: ScanForExternalSubtitles: END (total time: 8 ms)
2021-07-22 02:15:08.787 T:643 INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2021-07-22 02:15:08.788 T:643 DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2021-07-22 02:15:08.788 T:643 DEBUG <general>: ExtractThumb - seeking to pos 211511ms (total: 634533ms) in /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4
2021-07-22 02:15:08.788 T:643 DEBUG <general>: SeekTime - unknown position after seek
2021-07-22 02:15:09.488 T:645 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> cache completely reset for seek to position 77173651
2021-07-22 02:15:10.553 T:643 DEBUG <general>: cached image 'special://masterprofile/Thumbnails/f/f3eabef5.jpg' size 720x405
2021-07-22 02:15:10.596 T:643 DEBUG <general>: ExtractThumb - measured 3195 ms to extract thumb from file </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_30fps_normal.mp4> in 3 packets.
2021-07-22 02:15:10.946 T:645 DEBUG <general>: Thread FileCache 2206634112 terminating
2021-07-22 02:15:11.012 T:643 DEBUG <general>: DoWork - trying to extract thumb from video file /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:11.012 T:643 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> opening
2021-07-22 02:15:11.037 T:644 DEBUG <general>: ffmpeg[0x7451100X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:11.630 T:643 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> source chunk size is 0, setting cache chunk size to 131072
2021-07-22 02:15:11.630 T:643 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> using single memory cache sized 52428800 bytes
2021-07-22 02:15:11.630 T:646 DEBUG <general>: Thread FileCache start, auto delete: false
2021-07-22 02:15:11.749 T:643 DEBUG <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2021-07-22 02:15:11.949 T:646 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> cache completely reset for seek to position 355725490
2021-07-22 02:15:11.949 T:643 DEBUG <general>: CFileCache::Seek - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> waiting for position 355856504
2021-07-22 02:15:12.305 T:643 DEBUG <general>: Open - avformat_find_stream_info starting
2021-07-22 02:15:12.305 T:646 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> source read hit eof
2021-07-22 02:15:12.307 T:646 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> cache completely reset for seek to position 424821
2021-07-22 02:15:12.355 T:643 DEBUG <general>: Open - av_find_stream_info finished
2021-07-22 02:15:12.355 T:643 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-07-22 02:15:12.355 T:643 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-07-22 02:15:12.355 T:643 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-07-22 02:15:12.356 T:643 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-07-22 02:15:13.700 T:643 DEBUG <general>: ScanForExternalSubtitles: END (total time: 1344 ms)
2021-07-22 02:15:13.700 T:643 INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2021-07-22 02:15:13.701 T:643 DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2021-07-22 02:15:13.701 T:643 DEBUG <general>: ExtractThumb - seeking to pos 211511ms (total: 634533ms) in /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:13.701 T:643 DEBUG <general>: SeekTime - unknown position after seek
2021-07-22 02:15:13.702 T:646 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> cache completely reset for seek to position 102094683
2021-07-22 02:15:13.997 T:643 DEBUG <general>: cached image 'special://masterprofile/Thumbnails/b/bb754adc.jpg' size 720x405
2021-07-22 02:15:14.038 T:643 DEBUG <general>: ExtractThumb - measured 3026 ms to extract thumb from file </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> in 3 packets.
2021-07-22 02:15:14.373 T:646 DEBUG <general>: Thread FileCache 2206634112 terminating
2021-07-22 02:15:14.440 T:636 DEBUG <general>: DoWork - trying to extract thumb from video file /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:15:14.440 T:636 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> opening
2021-07-22 02:15:14.468 T:644 DEBUG <general>: ffmpeg[0x7451100X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:15.098 T:636 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> source chunk size is 0, setting cache chunk size to 131072
2021-07-22 02:15:15.098 T:636 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> using single memory cache sized 52428800 bytes
2021-07-22 02:15:15.098 T:647 DEBUG <general>: Thread FileCache start, auto delete: false
2021-07-22 02:15:15.212 T:636 DEBUG <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2021-07-22 02:15:15.383 T:647 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> cache completely reset for seek to position 632885377
2021-07-22 02:15:15.383 T:636 DEBUG <general>: CFileCache::Seek - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> waiting for position 633016391
2021-07-22 02:15:15.761 T:636 DEBUG <general>: Open - avformat_find_stream_info starting
2021-07-22 02:15:15.761 T:647 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> source read hit eof
2021-07-22 02:15:15.763 T:647 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> cache completely reset for seek to position 235877
2021-07-22 02:15:15.935 T:636 DEBUG <general>: Open - av_find_stream_info finished
2021-07-22 02:15:15.935 T:636 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-07-22 02:15:15.935 T:636 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-07-22 02:15:15.935 T:636 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-07-22 02:15:15.936 T:636 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-07-22 02:15:17.267 T:636 DEBUG <general>: ScanForExternalSubtitles: END (total time: 1331 ms)
2021-07-22 02:15:17.267 T:636 INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2021-07-22 02:15:17.267 T:636 DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2021-07-22 02:15:17.267 T:636 DEBUG <general>: ExtractThumb - seeking to pos 211511ms (total: 634533ms) in /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:15:17.268 T:636 DEBUG <general>: SeekTime - unknown position after seek
2021-07-22 02:15:17.268 T:647 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> cache completely reset for seek to position 182134830
2021-07-22 02:15:17.497 T:474 DEBUG <general>: OnPlayMedia /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers(/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4)
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
2021-07-22 02:15:17.505 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2021-07-22 02:15:17.507 T:474 DEBUG <general>: Radio UECP (RDS) Processor - new CDVDRadioRDSData
2021-07-22 02:15:17.507 T:474 INFO <general>: VideoPlayer::OpenFile: /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:17.508 T:648 DEBUG <general>: Thread VideoPlayer start, auto delete: false
2021-07-22 02:15:17.508 T:474 DEBUG <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2021-07-22 02:15:17.508 T:648 INFO <general>: Creating InputStream
2021-07-22 02:15:17.513 T:644 DEBUG <general>: Loading settings for /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:17.545 T:474 DEBUG <general>: CVideoGUIInfo::InitCurrentItem(/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4)
2021-07-22 02:15:17.545 T:474 DEBUG <general>: CPlayerGUIInfo::InitCurrentItem(/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4)
2021-07-22 02:15:17.556 T:601 DEBUG <general>: Twitch: Player: |onPlayBackStarted| isTwitch |False| SeekTime ||
2021-07-22 02:15:17.646 T:474 DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2021-07-22 02:15:17.996 T:636 DEBUG <general>: cached image 'special://masterprofile/Thumbnails/a/a9e2c063.jpg' size 720x405
2021-07-22 02:15:18.040 T:636 DEBUG <general>: ExtractThumb - measured 3600 ms to extract thumb from file </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> in 3 packets.
2021-07-22 02:15:18.625 T:647 DEBUG <general>: Thread FileCache 2206634112 terminating
2021-07-22 02:15:18.719 T:637 DEBUG <general>: ffmpeg[0x71dc208X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:19.286 T:648 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> opening
2021-07-22 02:15:19.295 T:648 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> source chunk size is 0, setting cache chunk size to 131072
2021-07-22 02:15:19.296 T:648 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> using double memory cache each sized 26214400 bytes
2021-07-22 02:15:19.296 T:649 DEBUG <general>: Thread FileCache start, auto delete: false
2021-07-22 02:15:19.296 T:648 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-07-22 02:15:20.040 T:648 DEBUG <general>: ScanForExternalSubtitles: END (total time: 744 ms)
2021-07-22 02:15:20.040 T:648 INFO <general>: Creating Demuxer
2021-07-22 02:15:20.041 T:648 DEBUG <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2021-07-22 02:15:20.858 T:649 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> cache completely reset for seek to position 355725490
2021-07-22 02:15:20.859 T:648 DEBUG <general>: CFileCache::Seek - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> waiting for position 355856504
2021-07-22 02:15:21.532 T:648 DEBUG <general>: Open - avformat_find_stream_info starting
2021-07-22 02:15:21.532 T:649 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> source read hit eof
2021-07-22 02:15:21.583 T:648 DEBUG <general>: Open - av_find_stream_info finished
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, from '/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4':
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Metadata:
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: major_brand : isom
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: minor_version : 1
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: compatible_brands: isomavc1
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: creation_time : 2013-12-16T17:59:32.000000Z
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: title : Big Buck Bunny, Sunflower version
2021-07-22 02:15:21.583 T:648 INFO <general>: ffmpeg[0x67d9b08X]: artist : Blender Foundation 2008, Janus Bager Kristensen 2013
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: comment : Creative Commons Attribution 3.0 - http://bbb3d.renderfarming.net
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: genre : Animation
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: composer : Sacha Goedegebure
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Duration: 00:10:34.53, start: 0.000000, bitrate: 4486 kb/s
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Stream #0:0(und): Video: h264 (High) (avc1 / 0x31637661), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 4001 kb/s, 60 fps, 60 tbr, 60k tbn, 120 tbc (default)
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Metadata:
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: creation_time : 2013-12-16T17:59:32.000000Z
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: handler_name : GPAC ISO Video Handler
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Stream #0:1(und): Audio: mp3 (mp4a / 0x6134706D), 48000 Hz, stereo, fltp, 160 kb/s (default)
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Metadata:
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: creation_time : 2013-12-16T17:59:37.000000Z
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: handler_name : GPAC ISO Audio Handler
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Stream #0:2(und): Audio: ac3 (ac-3 / 0x332D6361), 48000 Hz, 5.1(side), fltp, 320 kb/s (default)
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Metadata:
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: creation_time : 2013-12-16T17:59:37.000000Z
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: handler_name : GPAC ISO Audio Handler
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: Side data:
2021-07-22 02:15:21.584 T:648 INFO <general>: ffmpeg[0x67d9b08X]: audio service type: main
2021-07-22 02:15:21.584 T:648 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-07-22 02:15:21.584 T:648 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-07-22 02:15:21.584 T:648 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-07-22 02:15:21.585 T:648 DEBUG <general>: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 616897 bytes per second
2021-07-22 02:15:21.585 T:648 INFO <general>: Opening stream: 0 source: 256
2021-07-22 02:15:21.585 T:648 INFO <general>: [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 60.000, 3D: false
2021-07-22 02:15:21.585 T:648 DEBUG <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2021-07-22 02:15:21.585 T:648 DEBUG <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2021-07-22 02:15:21.585 T:648 DEBUG <general>: [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 60.000000 Hz (16)
2021-07-22 02:15:21.585 T:648 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:15:21.585 T:648 DEBUG <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2021-07-22 02:15:21.586 T:648 INFO <general>: Display resolution ADJUST : 1920x1080 @ 60.000000 Hz (16) (weight: -0.000)
2021-07-22 02:15:21.613 T:648 INFO <general>: Creating video codec with codec id: 27
2021-07-22 02:15:21.613 T:648 INFO <general>: CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper
2021-07-22 02:15:21.615 T:648 INFO <general>: ffmpeg[0x67d9b08X]: [h264_v4l2m2m] Using device /dev/video10
2021-07-22 02:15:21.615 T:648 INFO <general>: ffmpeg[0x67d9b08X]: [h264_v4l2m2m] driver 'bcm2835-codec' on card 'bcm2835-codec-decode' in mplane mode
2021-07-22 02:15:21.615 T:648 INFO <general>: ffmpeg[0x67d9b08X]: [h264_v4l2m2m] requesting formats: output=H264 capture=YU12
2021-07-22 02:15:21.641 T:648 DEBUG <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 27
2021-07-22 02:15:21.641 T:648 INFO <general>: Creating video thread
2021-07-22 02:15:21.641 T:650 DEBUG <general>: Thread VideoPlayerVideo start, auto delete: false
2021-07-22 02:15:21.641 T:650 INFO <general>: running thread: video_thread
2021-07-22 02:15:21.641 T:650 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
2021-07-22 02:15:21.641 T:648 DEBUG <general>: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:21.642 T:648 INFO <general>: Opening stream: 2 source: 256
2021-07-22 02:15:21.642 T:648 INFO <general>: Finding audio codec for: 86019
2021-07-22 02:15:21.643 T:648 INFO <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3
2021-07-22 02:15:21.643 T:648 INFO <general>: Creating audio thread
2021-07-22 02:15:21.643 T:651 DEBUG <general>: Thread VideoPlayerAudio start, auto delete: false
2021-07-22 02:15:21.643 T:651 INFO <general>: running thread: CVideoPlayerAudio::Process()
2021-07-22 02:15:21.643 T:651 DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0
2021-07-22 02:15:21.643 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:15:21.643 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:21.643 T:648 DEBUG <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2021-07-22 02:15:21.643 T:648 DEBUG <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2021-07-22 02:15:21.643 T:636 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:15:21.643 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:15:21.643 T:650 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:15:21.643 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:21.644 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 2
2021-07-22 02:15:21.644 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:21.648 T:474 DEBUG <general>: ------ Window Init (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:15:21.651 T:652 DEBUG <general>: Thread AlarmClock start, auto delete: false
2021-07-22 02:15:21.651 T:474 DEBUG <general>: started alarm with name: showinfostart
2021-07-22 02:15:21.652 T:474 DEBUG <general>: started alarm with name: showinfostartdone
2021-07-22 02:15:21.653 T:651 DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2021-07-22 02:15:21.658 T:651 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:15:21.658 T:651 INFO <general>: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through)
2021-07-22 02:15:21.658 T:651 DEBUG <general>: CVideoPlayerAudio:: synctype set to 0: clock feedback
2021-07-22 02:15:21.681 T:474 DEBUG <general>: ------ Window Deinit (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:15:21.740 T:650 DEBUG <general>: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 60.00.
2021-07-22 02:15:21.742 T:499 INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-07-22 02:15:21.752 T:474 DEBUG <general>: DeleteRenderer - deleting renderer
2021-07-22 02:15:21.753 T:474 DEBUG <general>: LinuxRendererGLES: Cleaning up GLES resources
2021-07-22 02:15:21.753 T:474 DEBUG <general>: CDRMPlane::SupportsFormatAndModifier - found plane format (YU12) and modifier (0x0)
2021-07-22 02:15:21.753 T:474 DEBUG <general>: CRenderManager::Configure - 5
2021-07-22 02:15:21.762 T:644 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:15:21.762 T:648 DEBUG <general>: CVideoPlayer::HandleMessages - player started 2
2021-07-22 02:15:21.769 T:474 DEBUG <general>: CVideoLayerBridgeDRMPRIME::Configure - setting connector colorspace to Default
2021-07-22 02:15:21.770 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 228
2021-07-22 02:15:21.770 T:474 DEBUG <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2021-07-22 02:15:21.805 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 229
2021-07-22 02:15:21.844 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226
2021-07-22 02:15:21.869 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 225
2021-07-22 02:15:21.966 T:496 DEBUG <general>: Sink changed
2021-07-22 02:15:21.967 T:499 DEBUG <general>: CActiveAESink::OpenSink - trying to open device PULSE:Default
2021-07-22 02:15:21.968 T:499 DEBUG <general>: PulseAudio: Context authorizing
2021-07-22 02:15:21.969 T:499 DEBUG <general>: PulseAudio: Context setting name
2021-07-22 02:15:21.970 T:499 DEBUG <general>: PulseAudio: Context ready
2021-07-22 02:15:21.970 T:499 DEBUG <general>: PULSE does not support format: AE_FMT_FLOATP - will fallback to AE_FMT_FLOAT
2021-07-22 02:15:22.008 T:499 DEBUG <general>: PulseAudio: Stream ready
2021-07-22 02:15:22.008 T:499 INFO <general>: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms Periodsize 50 ms
2021-07-22 02:15:22.008 T:496 DEBUG <general>: Sink changed
2021-07-22 02:15:22.009 T:499 DEBUG <general>: CActiveAESink::OpenSink - PULSE Initialized:
2021-07-22 02:15:22.009 T:499 DEBUG <general>: Output Device : Default
2021-07-22 02:15:22.009 T:499 DEBUG <general>: Sample Rate : 48000
2021-07-22 02:15:22.009 T:499 DEBUG <general>: Sample Format : AE_FMT_FLOAT
2021-07-22 02:15:22.009 T:499 DEBUG <general>: Channel Count : 2
2021-07-22 02:15:22.009 T:499 DEBUG <general>: Channel Layout: FL, FR
2021-07-22 02:15:22.009 T:499 DEBUG <general>: Frames : 2400
2021-07-22 02:15:22.009 T:499 DEBUG <general>: Frame Size : 8
2021-07-22 02:15:22.010 T:496 DEBUG <general>: Sink changed
2021-07-22 02:15:22.013 T:498 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:15:22.013 T:498 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-07-22 02:15:22.025 T:648 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:15:22.025 T:648 DEBUG <general>: CVideoPlayer::HandleMessages - player started 1
2021-07-22 02:15:22.025 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 3
2021-07-22 02:15:22.025 T:636 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:15:22.025 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:22.025 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 0
2021-07-22 02:15:22.025 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:22.025 T:648 DEBUG <general>: VideoPlayer::Sync - Audio - pts: 288000.000000, cache: 465779.348452, totalcache: 750000.000000
2021-07-22 02:15:22.025 T:648 DEBUG <general>: VideoPlayer::Sync - Video - pts: 50000.000000, cache: 50000.000000, totalcache: 100000.000000
2021-07-22 02:15:22.025 T:650 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-177779.348452)
2021-07-22 02:15:22.025 T:651 DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-177779.348452), level: 92, cache: 457331.792452
2021-07-22 02:15:22.025 T:651 DEBUG <general>: CDVDAudio::Resume - resume audio stream
2021-07-22 02:15:22.026 T:644 DEBUG <general>: OnAVStarted: CApplication::OnAVStarted
2021-07-22 02:15:22.027 T:498 DEBUG <general>: ActiveAE - start sync of audio stream
2021-07-22 02:15:22.039 T:474 DEBUG <general>: Activating window ID: 12005
2021-07-22 02:15:22.039 T:474 DEBUG <general>: ------ Window Deinit (MyPics.xml) ------
2021-07-22 02:15:22.044 T:474 DEBUG <general>: FreeVisualisation() done
2021-07-22 02:15:22.055 T:474 DEBUG <general>: ------ Window Init (VideoFullScreen.xml) ------
2021-07-22 02:15:22.055 T:474 INFO <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2021-07-22 02:15:22.083 T:654 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:15:22.104 T:474 INFO <general>: [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 60.000, 3D: false
2021-07-22 02:15:22.104 T:474 DEBUG <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2021-07-22 02:15:22.104 T:474 DEBUG <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2021-07-22 02:15:22.104 T:474 DEBUG <general>: [WHITELIST] Matched an exact resolution with an exact refresh rate 1920x1080 @ 60.000000 Hz (16)
2021-07-22 02:15:22.105 T:474 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:15:22.105 T:474 DEBUG <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2021-07-22 02:15:22.105 T:474 INFO <general>: Display resolution ADJUST : 1920x1080 @ 60.000000 Hz (16) (weight: -0.297)
2021-07-22 02:15:22.112 T:643 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:15:22.141 T:498 DEBUG <general>: ActiveAE::SyncStream - average error of 23.605032, start adjusting
2021-07-22 02:15:22.141 T:498 DEBUG <general>: ActiveAE::SyncStream - average error 0.605032 below threshold of 30.000000
2021-07-22 02:15:22.145 T:474 DEBUG <general>: ------ Window Deinit (DialogBusy.xml) ------
2021-07-22 02:15:22.603 T:654 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:15:23.158 T:651 DEBUG <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-33011.041132, adjusted:-16666.666667
2021-07-22 02:15:24.679 T:474 DEBUG <general>: ------ Window Init (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:15:24.680 T:474 DEBUG <general>: started alarm with name: showinfostart
2021-07-22 02:15:24.680 T:474 DEBUG <general>: started alarm with name: showinfostartdone
2021-07-22 02:15:24.697 T:474 DEBUG <general>: ------ Window Deinit (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:15:24.832 T:650 DEBUG <general>: CPtsTracker: detected pattern of length 1: 16666.66, frameduration: 16666.666667
2021-07-22 02:15:32.868 T:583 DEBUG <general>: PushCecKeypress - received key a6 duration 0
2021-07-22 02:15:32.880 T:474 DEBUG <general>: HandleKey: 166 (0xa6, obc89) pressed, action is ChapterOrBigStepForward
2021-07-22 02:15:32.882 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:15:32.882 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:32.882 T:648 DEBUG <general>: demuxer seek to: 73966.000000
2021-07-22 02:15:32.882 T:648 DEBUG <general>: SeekTime - unknown position after seek
2021-07-22 02:15:32.882 T:648 DEBUG <general>: demuxer seek to: 73966.000000, success
2021-07-22 02:15:32.882 T:648 DEBUG <general>: CVideoPlayer::FlushBuffers - flushing buffers
2021-07-22 02:15:32.903 T:651 DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2021-07-22 02:15:32.998 T:651 DEBUG <general>: CDVDAudio::Flush - flush audio stream
2021-07-22 02:15:32.998 T:651 DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2021-07-22 02:15:33.205 T:583 DEBUG <general>: PushCecKeypress - received key a6 duration 337
2021-07-22 02:15:33.261 T:650 DEBUG <general>: CDVDVideoCodecDRMPRIME::Reset - flush buffers
2021-07-22 02:15:33.265 T:650 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
2021-07-22 02:15:33.265 T:648 DEBUG <general>: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE
2021-07-22 02:15:33.265 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 2
2021-07-22 02:15:33.265 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:33.265 T:474 DEBUG <general>: ------ Window Init (DialogSeekBar.xml) ------
2021-07-22 02:15:33.265 T:651 DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
2021-07-22 02:15:33.267 T:651 DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2021-07-22 02:15:33.268 T:649 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4> cache completely reset for seek to position 42189872
2021-07-22 02:15:33.408 T:650 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:15:33.417 T:648 DEBUG <general>: CVideoPlayer::HandleMessages - player started 1
2021-07-22 02:15:33.417 T:644 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:15:33.589 T:648 DEBUG <general>: CVideoPlayer::HandleMessages - player started 2
2021-07-22 02:15:33.589 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 3
2021-07-22 02:15:33.589 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:33.590 T:648 DEBUG <general>: CVideoPlayer::SetCaching - caching state 0
2021-07-22 02:15:33.590 T:648 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:15:33.590 T:648 DEBUG <general>: VideoPlayer::Sync - Audio - pts: 75968000.000000, cache: 490229.218452, totalcache: 750000.000000
2021-07-22 02:15:33.590 T:648 DEBUG <general>: VideoPlayer::Sync - Video - pts: 75683333.000000, cache: 50000.000000, totalcache: 100000.000000
2021-07-22 02:15:33.590 T:651 DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(75477770.781548), level: 10, cache: 467198.811452
2021-07-22 02:15:33.590 T:650 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(75477770.781548)
2021-07-22 02:15:33.590 T:651 DEBUG <general>: CDVDAudio::Resume - resume audio stream
2021-07-22 02:15:33.591 T:498 DEBUG <general>: ActiveAE - start sync of audio stream
2021-07-22 02:15:33.698 T:498 DEBUG <general>: ActiveAE::SyncStream - average error of 46.098939, start adjusting
2021-07-22 02:15:33.698 T:498 DEBUG <general>: ActiveAE::SyncStream - average error 0.098939 below threshold of 30.000000
2021-07-22 02:15:34.753 T:651 DEBUG <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-36455.470493, adjusted:-16666.666667
2021-07-22 02:15:35.548 T:474 DEBUG <general>: ------ Window Deinit (DialogSeekBar.xml) ------
2021-07-22 02:15:36.223 T:650 DEBUG <general>: CPtsTracker: detected pattern of length 1: 16666.67, frameduration: 16666.666667
2021-07-22 02:15:51.343 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 675
2021-07-22 02:15:51.346 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:15:51.347 T:474 INFO <general>: CVideoPlayer::CloseFile()
2021-07-22 02:15:51.347 T:474 DEBUG <general>: DeleteRenderer - deleting renderer
2021-07-22 02:15:51.347 T:474 INFO <general>: VideoPlayer: waiting for threads to exit
2021-07-22 02:15:51.350 T:648 INFO <general>: CVideoPlayer::OnExit()
2021-07-22 02:15:51.350 T:648 INFO <general>: Closing stream player 1
2021-07-22 02:15:51.350 T:648 INFO <general>: Waiting for audio thread to exit
2021-07-22 02:15:51.406 T:651 INFO <general>: thread end: CVideoPlayerAudio::OnExit()
2021-07-22 02:15:51.406 T:651 DEBUG <general>: Thread VideoPlayerAudio 1763700864 terminating
2021-07-22 02:15:51.406 T:648 INFO <general>: Closing audio device
2021-07-22 02:15:51.437 T:648 DEBUG <general>: CDVDAudio::Flush - flush audio stream
2021-07-22 02:15:51.439 T:498 DEBUG <general>: CActiveAE::DiscardStream - audio stream deleted
2021-07-22 02:15:51.439 T:498 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-07-22 02:15:51.441 T:648 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:15:51.441 T:648 INFO <general>: Deleting audio codec
2021-07-22 02:15:51.441 T:648 INFO <general>: Closing stream player 2
2021-07-22 02:15:51.441 T:648 INFO <general>: waiting for video thread to exit
2021-07-22 02:15:51.442 T:499 INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-07-22 02:15:51.448 T:650 INFO <general>: thread end: video_thread
2021-07-22 02:15:51.448 T:650 DEBUG <general>: Thread VideoPlayerVideo 1785979008 terminating
2021-07-22 02:15:51.450 T:648 INFO <general>: deleting video codec
2021-07-22 02:15:51.527 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 184
2021-07-22 02:15:51.669 T:496 DEBUG <general>: Sink changed
2021-07-22 02:15:51.672 T:499 DEBUG <general>: CActiveAESink::OpenSink - trying to open device PULSE:Default
2021-07-22 02:15:51.678 T:499 DEBUG <general>: PulseAudio: Context authorizing
2021-07-22 02:15:51.682 T:499 DEBUG <general>: PulseAudio: Context setting name
2021-07-22 02:15:51.683 T:499 DEBUG <general>: PulseAudio: Context ready
2021-07-22 02:15:51.712 T:499 DEBUG <general>: PulseAudio: Stream ready
2021-07-22 02:15:51.713 T:499 INFO <general>: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms Periodsize 50 ms
2021-07-22 02:15:51.713 T:496 DEBUG <general>: Sink changed
2021-07-22 02:15:51.713 T:499 DEBUG <general>: CActiveAESink::OpenSink - PULSE Initialized:
2021-07-22 02:15:51.713 T:499 DEBUG <general>: Output Device : Default
2021-07-22 02:15:51.713 T:499 DEBUG <general>: Sample Rate : 44100
2021-07-22 02:15:51.713 T:499 DEBUG <general>: Sample Format : AE_FMT_FLOAT
2021-07-22 02:15:51.713 T:496 DEBUG <general>: Sink changed
2021-07-22 02:15:51.713 T:499 DEBUG <general>: Channel Count : 2
2021-07-22 02:15:51.713 T:499 DEBUG <general>: Channel Layout: FL, FR
2021-07-22 02:15:51.713 T:499 DEBUG <general>: Frames : 2205
2021-07-22 02:15:51.713 T:499 DEBUG <general>: Frame Size : 8
2021-07-22 02:15:51.717 T:498 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-07-22 02:15:51.718 T:496 DEBUG <general>: Sink changed
2021-07-22 02:15:51.718 T:498 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-07-22 02:15:51.732 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 205
2021-07-22 02:15:51.783 T:649 DEBUG <general>: Thread FileCache 2206634112 terminating
2021-07-22 02:15:51.788 T:637 INFO <general>: Deleting settings information for files /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:51.789 T:644 DEBUG <general>: DoWork - Saving file state for video item /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_1080p_60fps_normal.mp4
2021-07-22 02:15:51.804 T:648 DEBUG <general>: Thread VideoPlayer 1808789632 terminating
2021-07-22 02:15:51.806 T:474 INFO <general>: VideoPlayer: finished waiting
2021-07-22 02:15:51.806 T:474 INFO <general>: CVideoPlayer::CloseFile()
2021-07-22 02:15:51.806 T:474 INFO <general>: VideoPlayer: waiting for threads to exit
2021-07-22 02:15:51.806 T:474 INFO <general>: VideoPlayer: finished waiting
2021-07-22 02:15:51.807 T:474 DEBUG <general>: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2021-07-22 02:15:51.814 T:637 DEBUG <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2021-07-22 02:15:51.819 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Deactivate
2021-07-22 02:15:51.819 T:474 DEBUG <general>: ------ Window Deinit (VideoFullScreen.xml) ------
2021-07-22 02:15:51.819 T:656 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:15:51.843 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Activate new
2021-07-22 02:15:51.843 T:474 DEBUG <general>: ------ Window Init (MyPics.xml) ------
2021-07-22 02:15:51.849 T:474 DEBUG <general>: CGUIMediaWindow::GetDirectory (/home/paulo/torrents-nas/4 - BB/Benchmark/)
2021-07-22 02:15:51.849 T:474 DEBUG <general>: ParentPath = [/home/paulo/torrents-nas/4 - BB/Benchmark/]
2021-07-22 02:15:51.899 T:657 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:15:51.900 T:657 DEBUG <general>: Thread BackgroundLoader 1808789632 terminating
2021-07-22 02:15:51.901 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 169
2021-07-22 02:15:51.903 T:658 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:15:51.921 T:636 DEBUG <general>: ffmpeg[0x6101b70X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:51.921 T:658 DEBUG <general>: Thread BackgroundLoader 2237399168 terminating
2021-07-22 02:15:51.930 T:644 DEBUG <general>: ffmpeg[0x7451100X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:51.940 T:643 DEBUG <general>: ffmpeg[0x61feca8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:51.940 T:637 DEBUG <general>: ffmpeg[0x71dc208X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:51.964 T:643 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:15:51.964 T:643 DEBUG <general>: ffmpeg[0x61feca8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:51.974 T:644 DEBUG <general>: ffmpeg[0x7451100X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:51.981 T:637 DEBUG <general>: ffmpeg[0x71dc208X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:15:51.993 T:474 DEBUG <general>: CVideoLayerBridgeDRMPRIME::Disable - setting connector colorspace to Default
2021-07-22 02:15:51.993 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226
2021-07-22 02:15:51.993 T:474 DEBUG <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2021-07-22 02:15:52.014 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:15:52.058 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 225
2021-07-22 02:15:52.079 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:15:52.083 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 228
2021-07-22 02:15:52.093 T:601 DEBUG <general>: Twitch: Player: |onPlayBackStopped|
2021-07-22 02:15:52.095 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:15:52.100 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 227
2021-07-22 02:15:52.109 T:578 DEBUG <general>: [plugin.video.youtube] PlaybackMonitor active threads: ||
2021-07-22 02:15:52.136 T:636 DEBUG <general>: DoWork - took 222 ms to load resource://resource.images.skinbackgrounds.xonfluence/SKINDEFAULT.jpg
2021-07-22 02:15:52.335 T:656 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:15:52.902 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 1001
2021-07-22 02:15:52.912 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:15:55.688 T:593 INFO <general>: WebSocket: connection closed by client
2021-07-22 02:15:55.688 T:593 INFO <general>: JSONRPC Server: Disconnection detected
2021-07-22 02:16:08.016 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:16:08.016 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:16:08.033 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:16:08.033 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:16:08.042 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:16:08.042 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:16:21.986 T:643 DEBUG <general>: Thread JobWorker 1850200192 terminating (autodelete)
2021-07-22 02:16:22.000 T:637 DEBUG <general>: Thread JobWorker 2198241408 terminating (autodelete)
2021-07-22 02:16:22.004 T:644 DEBUG <general>: Thread JobWorker 1841807488 terminating (autodelete)
2021-07-22 02:16:22.137 T:636 DEBUG <general>: Thread JobWorker 2010030208 terminating (autodelete)
2021-07-22 02:16:51.947 T:496 DEBUG <general>: Sink changed
2021-07-22 02:17:08.063 T:578 DEBUG <CSettingsManager>: Skipped 1 duplicate messages..
2021-07-22 02:17:08.063 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:17:08.063 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:17:08.081 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:17:08.081 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:17:08.089 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:17:08.089 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:17:36.499 T:579 DEBUG <general>: [ script.embuary.helper ] Start new fanart grabber process
2021-07-22 02:17:36.569 T:579 DEBUG <general>: GetArtistsByWhereJSON query: SELECT a1.*, art.art_id AS idArt, art.type AS artType, art.url AS artURL FROM (SELECT artist.idArtist, strArtist FROM artist WHERE (EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artist.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artist.idArtist)) AND (artist.strArtist != '') ORDER BY RANDOM() LIMIT 40) AS a1 LEFT JOIN art ON art.media_id = a1.idArtist AND art.media_type = 'artist' GROUP BY a1.idArtist, art.art_id ORDER BY a1.idArtist, arttype
2021-07-22 02:17:36.570 T:579 DEBUG <general>: GetArtistsByWhereJSON - query took 1 ms
2021-07-22 02:18:08.111 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:18:08.111 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:18:08.129 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:18:08.130 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:18:08.138 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:18:08.138 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:18:20.486 T:593 DEBUG <general>: JSONRPC Server: New connection detected
2021-07-22 02:18:20.486 T:593 INFO <general>: JSONRPC Server: New connection added
2021-07-22 02:18:20.679 T:663 DEBUG <CWebserver[8080]>: request received for /
2021-07-22 02:18:22.170 T:499 INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-07-22 02:18:22.170 T:499 DEBUG <general>: CActiveAESink::OpenSink - trying to open device PULSE:Default
2021-07-22 02:18:22.172 T:499 DEBUG <general>: PulseAudio: Context authorizing
2021-07-22 02:18:22.174 T:499 DEBUG <general>: PulseAudio: Context ready
2021-07-22 02:18:22.197 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:22.197 T:499 DEBUG <general>: PulseAudio: Stream ready
2021-07-22 02:18:22.198 T:499 INFO <general>: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms Periodsize 50 ms
2021-07-22 02:18:22.199 T:499 DEBUG <general>: CActiveAESink::OpenSink - PULSE Initialized:
2021-07-22 02:18:22.199 T:499 DEBUG <general>: Output Device : Default
2021-07-22 02:18:22.199 T:499 DEBUG <general>: Sample Rate : 44100
2021-07-22 02:18:22.199 T:499 DEBUG <general>: Sample Format : AE_FMT_FLOAT
2021-07-22 02:18:22.199 T:499 DEBUG <general>: Channel Count : 2
2021-07-22 02:18:22.199 T:499 DEBUG <general>: Channel Layout: FL, FR
2021-07-22 02:18:22.199 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:22.200 T:499 DEBUG <general>: Frames : 2205
2021-07-22 02:18:22.200 T:499 DEBUG <general>: Frame Size : 8
2021-07-22 02:18:22.203 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:24.696 T:474 DEBUG <general>: OnPlayMedia /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:18:24.712 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers(/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4)
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2021-07-22 02:18:24.713 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2021-07-22 02:18:24.714 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2021-07-22 02:18:24.714 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2021-07-22 02:18:24.714 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2021-07-22 02:18:24.714 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2021-07-22 02:18:24.714 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
2021-07-22 02:18:24.714 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
2021-07-22 02:18:24.715 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2021-07-22 02:18:24.720 T:474 DEBUG <general>: Radio UECP (RDS) Processor - new CDVDRadioRDSData
2021-07-22 02:18:24.720 T:474 INFO <general>: VideoPlayer::OpenFile: /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:18:24.720 T:665 DEBUG <general>: Thread VideoPlayer start, auto delete: false
2021-07-22 02:18:24.721 T:474 DEBUG <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2021-07-22 02:18:24.721 T:666 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:18:24.721 T:665 INFO <general>: Creating InputStream
2021-07-22 02:18:24.726 T:666 DEBUG <general>: Loading settings for /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:18:24.745 T:665 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> opening
2021-07-22 02:18:24.745 T:474 DEBUG <general>: CVideoGUIInfo::InitCurrentItem(/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4)
2021-07-22 02:18:24.746 T:474 DEBUG <general>: CPlayerGUIInfo::InitCurrentItem(/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4)
2021-07-22 02:18:24.747 T:665 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> source chunk size is 0, setting cache chunk size to 131072
2021-07-22 02:18:24.747 T:665 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> using double memory cache each sized 26214400 bytes
2021-07-22 02:18:24.747 T:667 DEBUG <general>: Thread FileCache start, auto delete: false
2021-07-22 02:18:24.748 T:665 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-07-22 02:18:24.791 T:601 DEBUG <general>: Twitch: Player: |onPlayBackStarted| isTwitch |False| SeekTime ||
2021-07-22 02:18:24.846 T:474 DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2021-07-22 02:18:24.854 T:665 DEBUG <general>: ScanForExternalSubtitles: END (total time: 106 ms)
2021-07-22 02:18:24.854 T:665 INFO <general>: Creating Demuxer
2021-07-22 02:18:24.856 T:665 DEBUG <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2021-07-22 02:18:25.030 T:667 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> cache completely reset for seek to position 632885377
2021-07-22 02:18:25.030 T:665 DEBUG <general>: CFileCache::Seek - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> waiting for position 633016391
2021-07-22 02:18:25.384 T:665 DEBUG <general>: Open - avformat_find_stream_info starting
2021-07-22 02:18:25.384 T:667 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4> source read hit eof
2021-07-22 02:18:25.559 T:665 DEBUG <general>: Open - av_find_stream_info finished
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: Input #0, mov,mp4,m4a,3gp,3g2,mj2, from '/home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4':
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: Metadata:
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: major_brand : isom
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: minor_version : 1
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: compatible_brands: isomavc1
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: creation_time : 2013-12-18T14:43:04.000000Z
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: title : Big Buck Bunny, Sunflower version
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: artist : Blender Foundation 2008, Janus Bager Kristensen 2013
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: comment : Creative Commons Attribution 3.0 - http://bbb3d.renderfarming.net
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: genre : Animation
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: composer : Sacha Goedegebure
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: Duration: 00:10:34.53, start: 0.000000, bitrate: 7980 kb/s
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: Stream #0:0(und): Video: h264 (High) (avc1 / 0x31637661), yuv420p, 3840x2160 [SAR 1:1 DAR 16:9], 7498 kb/s, 30 fps, 30 tbr, 30k tbn, 60 tbc (default)
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: Metadata:
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: creation_time : 2013-12-18T14:43:04.000000Z
2021-07-22 02:18:25.559 T:665 INFO <general>: ffmpeg[0x6569b70X]: handler_name : GPAC ISO Video Handler
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: Stream #0:1(und): Audio: mp3 (mp4a / 0x6134706D), 48000 Hz, stereo, fltp, 160 kb/s (default)
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: Metadata:
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: creation_time : 2013-12-18T14:43:06.000000Z
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: handler_name : GPAC ISO Audio Handler
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: Stream #0:2(und): Audio: ac3 (ac-3 / 0x332D6361), 48000 Hz, 5.1(side), fltp, 320 kb/s (default)
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: Metadata:
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: creation_time : 2013-12-18T14:43:06.000000Z
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: handler_name : GPAC ISO Audio Handler
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: Side data:
2021-07-22 02:18:25.560 T:665 INFO <general>: ffmpeg[0x6569b70X]: audio service type: main
2021-07-22 02:18:25.560 T:665 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-07-22 02:18:25.560 T:665 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-07-22 02:18:25.561 T:665 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-07-22 02:18:25.561 T:665 DEBUG <general>: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 1097369 bytes per second
2021-07-22 02:18:25.561 T:665 INFO <general>: Opening stream: 0 source: 256
2021-07-22 02:18:25.561 T:665 INFO <general>: [WHITELIST] Searching the whitelist for: width: 3840, height: 2160, fps: 30.000, 3D: false
2021-07-22 02:18:25.561 T:665 DEBUG <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2021-07-22 02:18:25.561 T:665 DEBUG <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2021-07-22 02:18:25.562 T:665 DEBUG <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2021-07-22 02:18:25.562 T:665 DEBUG <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2021-07-22 02:18:25.562 T:665 DEBUG <general>: [WHITELIST] No match for an exact resolution with double the refresh rate
2021-07-22 02:18:25.562 T:665 DEBUG <general>: [WHITELIST] Searching for an exact resolution with a 3:2 pulldown refresh rate
2021-07-22 02:18:25.562 T:665 DEBUG <general>: [WHITELIST] No match for a resolution with a 3:2 pulldown refresh rate
2021-07-22 02:18:25.562 T:665 DEBUG <general>: [WHITELIST] Searching for a desktop resolution with an exact refresh rate
2021-07-22 02:18:25.563 T:665 DEBUG <general>: [WHITELIST] No match for a desktop resolution with an exact refresh rate
2021-07-22 02:18:25.563 T:665 DEBUG <general>: [WHITELIST] Searching for a desktop resolution with double the refresh rate
2021-07-22 02:18:25.563 T:665 DEBUG <general>: [WHITELIST] Matched a desktop resolution with double the refresh rate 1920x1080 @ 60.000000 Hz (16)
2021-07-22 02:18:25.563 T:665 INFO <general>: Display resolution ADJUST : 1920x1080 @ 60.000000 Hz (16) (weight: -0.000)
2021-07-22 02:18:25.580 T:665 INFO <general>: Creating video codec with codec id: 27
2021-07-22 02:18:25.580 T:665 INFO <general>: CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper
2021-07-22 02:18:25.581 T:665 INFO <general>: ffmpeg[0x6569b70X]: [h264_v4l2m2m] Using device /dev/video10
2021-07-22 02:18:25.581 T:665 INFO <general>: ffmpeg[0x6569b70X]: [h264_v4l2m2m] driver 'bcm2835-codec' on card 'bcm2835-codec-decode' in mplane mode
2021-07-22 02:18:25.581 T:665 INFO <general>: ffmpeg[0x6569b70X]: [h264_v4l2m2m] requesting formats: output=H264 capture=YU12
2021-07-22 02:18:25.638 T:665 DEBUG <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 27
2021-07-22 02:18:25.638 T:665 INFO <general>: Creating video thread
2021-07-22 02:18:25.639 T:668 DEBUG <general>: Thread VideoPlayerVideo start, auto delete: false
2021-07-22 02:18:25.639 T:668 INFO <general>: running thread: video_thread
2021-07-22 02:18:25.639 T:668 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
2021-07-22 02:18:25.639 T:665 DEBUG <general>: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:18:25.639 T:665 INFO <general>: Opening stream: 2 source: 256
2021-07-22 02:18:25.639 T:665 INFO <general>: Finding audio codec for: 86019
2021-07-22 02:18:25.640 T:665 INFO <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3
2021-07-22 02:18:25.640 T:665 INFO <general>: Creating audio thread
2021-07-22 02:18:25.640 T:669 DEBUG <general>: Thread VideoPlayerAudio start, auto delete: false
2021-07-22 02:18:25.640 T:669 INFO <general>: running thread: CVideoPlayerAudio::Process()
2021-07-22 02:18:25.640 T:669 DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: 0
2021-07-22 02:18:25.640 T:665 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:18:25.640 T:665 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:18:25.640 T:665 DEBUG <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2021-07-22 02:18:25.640 T:665 DEBUG <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2021-07-22 02:18:25.640 T:666 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:18:25.640 T:665 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:18:25.640 T:668 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:18:25.640 T:665 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:18:25.641 T:665 DEBUG <general>: CVideoPlayer::SetCaching - caching state 2
2021-07-22 02:18:25.641 T:665 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:18:25.649 T:474 DEBUG <general>: ------ Window Init (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:18:25.650 T:474 DEBUG <general>: started alarm with name: showinfostart
2021-07-22 02:18:25.651 T:669 DEBUG <general>: CDVDAudio::Pause - pausing audio stream
2021-07-22 02:18:25.651 T:474 DEBUG <general>: Skipped 2 duplicate messages..
2021-07-22 02:18:25.651 T:474 DEBUG <general>: started alarm with name: showinfostartdone
2021-07-22 02:18:25.651 T:669 INFO <general>: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through)
2021-07-22 02:18:25.652 T:669 DEBUG <general>: CVideoPlayerAudio:: synctype set to 0: clock feedback
2021-07-22 02:18:25.681 T:474 DEBUG <general>: ------ Window Deinit (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:18:25.685 T:499 INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-07-22 02:18:25.869 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:25.871 T:499 DEBUG <general>: CActiveAESink::OpenSink - trying to open device PULSE:Default
2021-07-22 02:18:25.874 T:499 DEBUG <general>: PulseAudio: Context authorizing
2021-07-22 02:18:25.876 T:499 DEBUG <general>: PulseAudio: Context setting name
2021-07-22 02:18:25.877 T:499 DEBUG <general>: PulseAudio: Context ready
2021-07-22 02:18:25.877 T:499 DEBUG <general>: PULSE does not support format: AE_FMT_FLOATP - will fallback to AE_FMT_FLOAT
2021-07-22 02:18:25.900 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:25.900 T:499 DEBUG <general>: PulseAudio: Stream ready
2021-07-22 02:18:25.901 T:499 INFO <general>: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms Periodsize 50 ms
2021-07-22 02:18:25.901 T:499 DEBUG <general>: CActiveAESink::OpenSink - PULSE Initialized:
2021-07-22 02:18:25.901 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:25.901 T:499 DEBUG <general>: Output Device : Default
2021-07-22 02:18:25.902 T:499 DEBUG <general>: Sample Rate : 48000
2021-07-22 02:18:25.902 T:499 DEBUG <general>: Sample Format : AE_FMT_FLOAT
2021-07-22 02:18:25.902 T:499 DEBUG <general>: Channel Count : 2
2021-07-22 02:18:25.902 T:499 DEBUG <general>: Channel Layout: FL, FR
2021-07-22 02:18:25.902 T:499 DEBUG <general>: Frames : 2400
2021-07-22 02:18:25.902 T:499 DEBUG <general>: Frame Size : 8
2021-07-22 02:18:25.905 T:498 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-07-22 02:18:25.909 T:496 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:18:25.909 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:26.110 T:665 DEBUG <general>: CVideoPlayer::HandleMessages - player started 1
2021-07-22 02:18:26.112 T:666 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:18:26.144 T:665 DEBUG <general>: VideoPlayer::Sync - Audio - pts: 288000.000000, cache: 469669.015452, totalcache: 750000.000000
2021-07-22 02:18:26.144 T:665 DEBUG <general>: CVideoPlayer::SetCaching - caching state 0
2021-07-22 02:18:26.144 T:669 DEBUG <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-181669.015452), level: 100, cache: 485137.015452
2021-07-22 02:18:26.145 T:665 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:18:26.145 T:669 DEBUG <general>: CDVDAudio::Resume - resume audio stream
2021-07-22 02:18:26.146 T:666 DEBUG <general>: OnAVStarted: CApplication::OnAVStarted
2021-07-22 02:18:26.145 T:668 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-181669.015452)
2021-07-22 02:18:26.146 T:498 DEBUG <general>: ActiveAE - start sync of audio stream
2021-07-22 02:18:26.164 T:474 DEBUG <general>: Activating window ID: 12005
2021-07-22 02:18:26.164 T:474 DEBUG <general>: ------ Window Deinit (MyPics.xml) ------
2021-07-22 02:18:26.165 T:474 DEBUG <general>: FreeVisualisation() done
2021-07-22 02:18:26.166 T:474 DEBUG <general>: ------ Window Init (VideoFullScreen.xml) ------
2021-07-22 02:18:26.171 T:474 INFO <general>: [WHITELIST] Searching the whitelist for: width: 3840, height: 2160, fps: 30.000, 3D: false
2021-07-22 02:18:26.171 T:474 DEBUG <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2021-07-22 02:18:26.171 T:474 DEBUG <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2021-07-22 02:18:26.172 T:474 DEBUG <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2021-07-22 02:18:26.172 T:474 DEBUG <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2021-07-22 02:18:26.173 T:474 DEBUG <general>: [WHITELIST] No match for an exact resolution with double the refresh rate
2021-07-22 02:18:26.173 T:474 DEBUG <general>: [WHITELIST] Searching for an exact resolution with a 3:2 pulldown refresh rate
2021-07-22 02:18:26.173 T:474 DEBUG <general>: [WHITELIST] No match for a resolution with a 3:2 pulldown refresh rate
2021-07-22 02:18:26.173 T:474 DEBUG <general>: [WHITELIST] Searching for a desktop resolution with an exact refresh rate
2021-07-22 02:18:26.174 T:474 DEBUG <general>: [WHITELIST] No match for a desktop resolution with an exact refresh rate
2021-07-22 02:18:26.174 T:474 DEBUG <general>: [WHITELIST] Searching for a desktop resolution with double the refresh rate
2021-07-22 02:18:26.174 T:474 DEBUG <general>: [WHITELIST] Matched a desktop resolution with double the refresh rate 1920x1080 @ 60.000000 Hz (16)
2021-07-22 02:18:26.174 T:474 INFO <general>: Display resolution ADJUST : 1920x1080 @ 60.000000 Hz (16) (weight: -0.297)
2021-07-22 02:18:26.195 T:474 DEBUG <general>: ------ Window Deinit (DialogBusy.xml) ------
2021-07-22 02:18:26.285 T:498 DEBUG <general>: ActiveAE::SyncStream - average error of -0.601281, start adjusting
2021-07-22 02:18:26.285 T:498 DEBUG <general>: ActiveAE::SyncStream - average error -0.017948 below threshold of 30.000000
2021-07-22 02:18:26.804 T:666 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:18:27.367 T:669 DEBUG <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-36567.686056, adjusted:-36567.686056
2021-07-22 02:18:28.746 T:474 DEBUG <general>: ------ Window Init (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:18:28.747 T:474 DEBUG <general>: started alarm with name: showinfostart
2021-07-22 02:18:28.747 T:474 DEBUG <general>: started alarm with name: showinfostartdone
2021-07-22 02:18:28.763 T:474 DEBUG <general>: ------ Window Deinit (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:18:29.535 T:668 ERROR <general>: ffmpeg[0x754a5c8X]: [h264_v4l2m2m] === poll unexpected TIMEOUT: events=0x43, cap buffers=20
2021-07-22 02:18:32.537 T:668 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:18:32.537 T:668 DEBUG <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 30.000000 fps
2021-07-22 02:18:32.537 T:668 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:18:32.537 T:668 ERROR <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2021-07-22 02:18:35.540 T:668 ERROR <general>: ffmpeg[0x754a5c8X]: [h264_v4l2m2m] === poll unexpected TIMEOUT: events=0x43, cap buffers=20
2021-07-22 02:18:35.540 T:668 ERROR <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)
2021-07-22 02:18:38.437 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 430
2021-07-22 02:18:38.439 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:18:38.439 T:474 INFO <general>: CVideoPlayer::CloseFile()
2021-07-22 02:18:38.439 T:474 DEBUG <general>: DeleteRenderer - deleting renderer
2021-07-22 02:18:38.439 T:474 DEBUG <general>: LinuxRendererGLES: Cleaning up GLES resources
2021-07-22 02:18:38.440 T:474 INFO <general>: VideoPlayer: waiting for threads to exit
2021-07-22 02:18:38.445 T:665 INFO <general>: CVideoPlayer::OnExit()
2021-07-22 02:18:38.445 T:665 INFO <general>: Closing stream player 1
2021-07-22 02:18:38.445 T:665 INFO <general>: Waiting for audio thread to exit
2021-07-22 02:18:38.484 T:669 INFO <general>: thread end: CVideoPlayerAudio::OnExit()
2021-07-22 02:18:38.484 T:669 DEBUG <general>: Thread VideoPlayerAudio 1687416960 terminating
2021-07-22 02:18:38.485 T:665 INFO <general>: Closing audio device
2021-07-22 02:18:38.515 T:665 DEBUG <general>: CDVDAudio::Flush - flush audio stream
2021-07-22 02:18:38.516 T:498 DEBUG <general>: CActiveAE::DiscardStream - audio stream deleted
2021-07-22 02:18:38.516 T:498 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-07-22 02:18:38.518 T:665 INFO <general>: Skipped 2 duplicate messages..
2021-07-22 02:18:38.518 T:665 INFO <general>: Deleting audio codec
2021-07-22 02:18:38.518 T:665 INFO <general>: Closing stream player 2
2021-07-22 02:18:38.518 T:665 INFO <general>: waiting for video thread to exit
2021-07-22 02:18:38.519 T:499 INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-07-22 02:18:38.544 T:668 ERROR <general>: ffmpeg[0x754a5c8X]: [h264_v4l2m2m] === poll unexpected TIMEOUT: events=0x43, cap buffers=20
2021-07-22 02:18:38.544 T:668 INFO <general>: thread end: video_thread
2021-07-22 02:18:38.544 T:668 DEBUG <general>: Thread VideoPlayerVideo 1695809664 terminating
2021-07-22 02:18:38.546 T:665 INFO <general>: deleting video codec
2021-07-22 02:18:38.567 T:667 DEBUG <general>: Thread FileCache 2198241408 terminating
2021-07-22 02:18:38.573 T:665 DEBUG <general>: Thread VideoPlayer 2010030208 terminating
2021-07-22 02:18:38.573 T:474 INFO <general>: VideoPlayer: finished waiting
2021-07-22 02:18:38.574 T:474 INFO <general>: CVideoPlayer::CloseFile()
2021-07-22 02:18:38.574 T:474 INFO <general>: VideoPlayer: waiting for threads to exit
2021-07-22 02:18:38.574 T:474 INFO <general>: VideoPlayer: finished waiting
2021-07-22 02:18:38.574 T:666 INFO <general>: Deleting settings information for files /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:18:38.575 T:474 DEBUG <general>: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2021-07-22 02:18:38.576 T:671 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:18:38.576 T:671 DEBUG <general>: DoWork - Saving file state for video item /home/paulo/torrents-nas/4 - BB/Benchmark/bbb_sunflower_2160p_30fps_normal.mp4
2021-07-22 02:18:38.593 T:671 DEBUG <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2021-07-22 02:18:38.595 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Deactivate
2021-07-22 02:18:38.595 T:474 DEBUG <general>: ------ Window Deinit (VideoFullScreen.xml) ------
2021-07-22 02:18:38.596 T:672 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:18:38.616 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Activate new
2021-07-22 02:18:38.616 T:474 DEBUG <general>: ------ Window Init (MyPics.xml) ------
2021-07-22 02:18:38.622 T:474 DEBUG <general>: CGUIMediaWindow::GetDirectory (/home/paulo/torrents-nas/4 - BB/Benchmark/)
2021-07-22 02:18:38.622 T:474 DEBUG <general>: ParentPath = [/home/paulo/torrents-nas/4 - BB/Benchmark/]
2021-07-22 02:18:38.653 T:673 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:18:38.653 T:673 DEBUG <general>: Thread BackgroundLoader 2198241408 terminating
2021-07-22 02:18:38.656 T:674 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:18:38.668 T:671 DEBUG <general>: ffmpeg[0x82538b50X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.684 T:675 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:18:38.684 T:674 DEBUG <general>: Thread BackgroundLoader 2237399168 terminating
2021-07-22 02:18:38.685 T:666 DEBUG <general>: ffmpeg[0x7274e48X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.690 T:675 DEBUG <general>: ffmpeg[0x6f8ffc8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.713 T:666 DEBUG <general>: ffmpeg[0x7274e48X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.720 T:675 DEBUG <general>: ffmpeg[0x6f8ffc8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.737 T:666 DEBUG <general>: ffmpeg[0x7274e48X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.746 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:38.747 T:578 DEBUG <general>: [plugin.video.youtube] PlaybackMonitor active threads: ||
2021-07-22 02:18:38.747 T:675 DEBUG <general>: ffmpeg[0x6f8ffc8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.749 T:499 DEBUG <general>: CActiveAESink::OpenSink - trying to open device PULSE:Default
2021-07-22 02:18:38.751 T:499 DEBUG <general>: PulseAudio: Context authorizing
2021-07-22 02:18:38.758 T:499 DEBUG <general>: PulseAudio: Context ready
2021-07-22 02:18:38.773 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:38.773 T:499 DEBUG <general>: PulseAudio: Stream ready
2021-07-22 02:18:38.773 T:499 INFO <general>: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms Periodsize 50 ms
2021-07-22 02:18:38.774 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:38.774 T:499 DEBUG <general>: CActiveAESink::OpenSink - PULSE Initialized:
2021-07-22 02:18:38.775 T:499 DEBUG <general>: Output Device : Default
2021-07-22 02:18:38.775 T:499 DEBUG <general>: Sample Rate : 44100
2021-07-22 02:18:38.775 T:499 DEBUG <general>: Sample Format : AE_FMT_FLOAT
2021-07-22 02:18:38.775 T:499 DEBUG <general>: Channel Count : 2
2021-07-22 02:18:38.775 T:499 DEBUG <general>: Channel Layout: FL, FR
2021-07-22 02:18:38.775 T:499 DEBUG <general>: Frames : 2205
2021-07-22 02:18:38.775 T:499 DEBUG <general>: Frame Size : 8
2021-07-22 02:18:38.775 T:666 DEBUG <general>: ffmpeg[0x7274e48X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:18:38.777 T:498 DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-07-22 02:18:38.778 T:496 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:18:38.778 T:496 DEBUG <general>: Sink changed
2021-07-22 02:18:38.800 T:601 DEBUG <general>: Twitch: Player: |onPlayBackStopped|
2021-07-22 02:18:38.906 T:671 DEBUG <general>: DoWork - took 245 ms to load resource://resource.images.skinbackgrounds.xonfluence/SKINDEFAULT.jpg
2021-07-22 02:18:38.926 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 489
2021-07-22 02:18:38.980 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:18:39.108 T:672 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:18:39.428 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 502
2021-07-22 02:18:39.445 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:18:40.428 T:583 DEBUG <general>: PushCecKeypress - received key e0 duration 1000
2021-07-22 02:18:40.446 T:474 DEBUG <general>: HandleKey: guide (0xe0) pressed, action is Stop
2021-07-22 02:18:47.724 T:593 INFO <general>: WebSocket: connection closed by client
2021-07-22 02:18:47.725 T:593 INFO <general>: JSONRPC Server: Disconnection detected
2021-07-22 02:18:48.242 T:593 DEBUG <general>: JSONRPC Server: New connection detected
2021-07-22 02:18:48.243 T:593 INFO <general>: JSONRPC Server: New connection added
2021-07-22 02:18:48.454 T:677 DEBUG <CWebserver[8080]>: request received for /
2021-07-22 02:19:01.663 T:474 DEBUG <general>: OnPlayMedia /home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv
2021-07-22 02:19:01.675 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers(/home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv)
2021-07-22 02:19:01.675 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2021-07-22 02:19:01.675 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
2021-07-22 02:19:01.676 T:474 DEBUG <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2021-07-22 02:19:01.679 T:474 DEBUG <general>: Radio UECP (RDS) Processor - new CDVDRadioRDSData
2021-07-22 02:19:01.679 T:474 INFO <general>: VideoPlayer::OpenFile: /home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv
2021-07-22 02:19:01.679 T:682 DEBUG <general>: Thread VideoPlayer start, auto delete: false
2021-07-22 02:19:01.680 T:474 DEBUG <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2021-07-22 02:19:01.680 T:682 INFO <general>: Creating InputStream
2021-07-22 02:19:01.686 T:675 DEBUG <general>: Loading settings for /home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv
2021-07-22 02:19:01.706 T:682 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv> opening
2021-07-22 02:19:01.708 T:682 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv> source chunk size is 0, setting cache chunk size to 131072
2021-07-22 02:19:01.708 T:682 DEBUG <general>: CFileCache::Open - </home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv> using double memory cache each sized 26214400 bytes
2021-07-22 02:19:01.709 T:683 DEBUG <general>: Thread FileCache start, auto delete: false
2021-07-22 02:19:01.709 T:682 DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-07-22 02:19:01.712 T:474 DEBUG <general>: CVideoGUIInfo::InitCurrentItem(/home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv)
2021-07-22 02:19:01.712 T:474 DEBUG <general>: CPlayerGUIInfo::InitCurrentItem(/home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv)
2021-07-22 02:19:01.718 T:682 DEBUG <general>: ScanForExternalSubtitles: END (total time: 9 ms)
2021-07-22 02:19:01.718 T:682 INFO <general>: Creating Demuxer
2021-07-22 02:19:01.811 T:601 DEBUG <general>: Twitch: Player: |onPlayBackStarted| isTwitch |False| SeekTime ||
2021-07-22 02:19:01.813 T:682 DEBUG <general>: Open - probing detected format [matroska,webm]
2021-07-22 02:19:01.814 T:682 DEBUG <general>: Open - avformat_find_stream_info starting
2021-07-22 02:19:01.815 T:474 DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2021-07-22 02:19:02.002 T:682 DEBUG <general>: Open - av_find_stream_info finished
2021-07-22 02:19:02.002 T:682 INFO <general>: ffmpeg[0x6f180a8X]: Input #0, matroska,webm, from '/home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv':
2021-07-22 02:19:02.002 T:682 INFO <general>: ffmpeg[0x6f180a8X]: Metadata:
2021-07-22 02:19:02.002 T:682 INFO <general>: ffmpeg[0x6f180a8X]: COMPATIBLE_BRANDS: iso4hvc1iso6
2021-07-22 02:19:02.002 T:682 INFO <general>: ffmpeg[0x6f180a8X]: MAJOR_BRAND : iso4
2021-07-22 02:19:02.002 T:682 INFO <general>: ffmpeg[0x6f180a8X]: MINOR_VERSION : 1
2021-07-22 02:19:02.002 T:682 INFO <general>: ffmpeg[0x6f180a8X]: ENCODER : Lavf56.3.100
2021-07-22 02:19:02.003 T:682 INFO <general>: ffmpeg[0x6f180a8X]: Duration: 00:00:30.10, start: 0.067000, bitrate: 60014 kb/s
2021-07-22 02:19:02.003 T:682 INFO <general>: ffmpeg[0x6f180a8X]: Stream #0:0(und): Video: hevc (Main 10), yuv420p10le(tv), 1920x1080 [SAR 1:1 DAR 16:9], 29.97 fps, 29.97 tbr, 1k tbn, 29.97 tbc (default)
2021-07-22 02:19:02.003 T:682 INFO <general>: ffmpeg[0x6f180a8X]: Metadata:
2021-07-22 02:19:02.003 T:682 INFO <general>: ffmpeg[0x6f180a8X]: CREATION_TIME : 2016-02-06 03:52:39
2021-07-22 02:19:02.003 T:682 INFO <general>: ffmpeg[0x6f180a8X]: LANGUAGE : und
2021-07-22 02:19:02.003 T:682 INFO <general>: ffmpeg[0x6f180a8X]: HANDLER_NAME : hevc@GPAC0.5.2-DEV-rev565-g71748d7-ab-suite
2021-07-22 02:19:02.003 T:682 DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-07-22 02:19:02.003 T:682 DEBUG <general>: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 8252002 bytes per second
2021-07-22 02:19:02.003 T:682 INFO <general>: Opening stream: 0 source: 256
2021-07-22 02:19:02.004 T:682 INFO <general>: [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 29.970, 3D: false
2021-07-22 02:19:02.004 T:682 DEBUG <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2021-07-22 02:19:02.004 T:682 DEBUG <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2021-07-22 02:19:02.005 T:682 DEBUG <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2021-07-22 02:19:02.005 T:682 DEBUG <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2021-07-22 02:19:02.005 T:682 DEBUG <general>: [WHITELIST] Matched an exact resolution with double the refresh rate 1920x1080 @ 59.940063 Hz (20)
2021-07-22 02:19:02.006 T:682 INFO <general>: Display resolution ADJUST : 1920x1080 @ 59.940063 Hz (20) (weight: -0.000)
2021-07-22 02:19:02.030 T:474 DEBUG <general>: OnLostDevice - notify display change event
2021-07-22 02:19:02.030 T:474 INFO <general>: VideoPlayer: OnLostDisplay received
2021-07-22 02:19:02.030 T:474 WARNING <general>: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
2021-07-22 02:19:02.030 T:474 WARNING <general>: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
2021-07-22 02:19:02.031 T:474 DEBUG <general>: Flush - flushing renderer
2021-07-22 02:19:02.031 T:474 DEBUG <general>: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 60 Hz
2021-07-22 02:19:02.031 T:474 INFO <general>: GLES: Maximum texture width: 4096
2021-07-22 02:19:02.034 T:682 INFO <general>: Creating video codec with codec id: 173
2021-07-22 02:19:02.034 T:682 INFO <general>: CDVDVideoCodecDRMPRIME::Open - using decoder HEVC (High Efficiency Video Coding)
2021-07-22 02:19:02.034 T:682 DEBUG <general>: CDVDVideoCodecDRMPRIME::Open - using drm device for av_hwdevice_ctx: /dev/dri/card0
2021-07-22 02:19:02.035 T:682 DEBUG <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 173
2021-07-22 02:19:02.036 T:682 INFO <general>: Creating video thread
2021-07-22 02:19:02.036 T:688 DEBUG <general>: Thread VideoPlayerVideo start, auto delete: false
2021-07-22 02:19:02.037 T:688 INFO <general>: running thread: video_thread
2021-07-22 02:19:02.037 T:682 DEBUG <general>: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv
2021-07-22 02:19:02.037 T:688 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 1
2021-07-22 02:19:02.037 T:682 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:19:02.037 T:682 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:19:02.039 T:474 DEBUG <general>: ------ Window Init (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:19:02.040 T:474 DEBUG <general>: started alarm with name: showinfostart
2021-07-22 02:19:02.040 T:474 DEBUG <general>: started alarm with name: showinfostartdone
2021-07-22 02:19:02.046 T:474 DEBUG <general>: EGL Debugging:
Error: EGL_BAD_SURFACE
Command: eglSwapBuffers
Type: EGL_DEBUG_MSG_ERROR_KHR
Message: dri2_swap_buffers
2021-07-22 02:19:02.047 T:474 DEBUG <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2021-07-22 02:19:02.091 T:474 DEBUG <general>: ------ Window Deinit (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:19:02.254 T:474 DEBUG <general>: CWinSystemGbmGLESContext::PresentRender - Sending display reset to all clients
2021-07-22 02:19:02.255 T:474 INFO <general>: VideoPlayer: OnResetDisplay received
2021-07-22 02:19:02.255 T:474 WARNING <general>: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
2021-07-22 02:19:02.255 T:688 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: 0
2021-07-22 02:19:02.288 T:682 DEBUG <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2021-07-22 02:19:02.290 T:682 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:19:02.290 T:682 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:19:02.290 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:19:02.294 T:682 DEBUG <general>: CVideoPlayer::CheckContinuity - wrapback :2, prev:133000.000000, curr:0.000000, diff:-133000.000000
2021-07-22 02:19:02.386 T:688 DEBUG <general>: CRenderManager::Configure - change configuration. 1920x1080. display: 1920x1080. framerate: 29.97.
2021-07-22 02:19:02.392 T:474 DEBUG <general>: DeleteRenderer - deleting renderer
2021-07-22 02:19:02.392 T:474 DEBUG <general>: LinuxRendererGLES: Cleaning up GLES resources
2021-07-22 02:19:02.393 T:474 DEBUG <general>: CDRMPlane::SupportsFormatAndModifier - found plane format (P030) and modifier (0x700000000000004)
2021-07-22 02:19:02.394 T:474 DEBUG <general>: CRenderManager::Configure - 5
2021-07-22 02:19:02.400 T:474 DEBUG <general>: CVideoLayerBridgeDRMPRIME::Configure - setting connector colorspace to Default
2021-07-22 02:19:02.403 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226
2021-07-22 02:19:02.403 T:474 DEBUG <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2021-07-22 02:19:02.429 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 225
2021-07-22 02:19:02.469 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 228
2021-07-22 02:19:02.496 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 227
2021-07-22 02:19:03.175 T:666 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:19:03.175 T:682 DEBUG <general>: CVideoPlayer::HandleMessages - player started 2
2021-07-22 02:19:03.175 T:682 DEBUG <general>: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
2021-07-22 02:19:03.175 T:682 WARNING <general>: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
2021-07-22 02:19:03.175 T:688 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-100000.000000)
2021-07-22 02:19:03.178 T:682 DEBUG <general>: CVideoPlayer::SetCaching - caching state 0
2021-07-22 02:19:03.178 T:682 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:19:03.186 T:675 DEBUG <general>: OnAVStarted: CApplication::OnAVStarted
2021-07-22 02:19:03.226 T:474 DEBUG <general>: Activating window ID: 12005
2021-07-22 02:19:03.226 T:474 DEBUG <general>: ------ Window Deinit (MyPics.xml) ------
2021-07-22 02:19:03.228 T:474 DEBUG <general>: FreeVisualisation() done
2021-07-22 02:19:03.233 T:474 DEBUG <general>: ------ Window Init (VideoFullScreen.xml) ------
2021-07-22 02:19:03.239 T:474 INFO <general>: [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 29.970, 3D: false
2021-07-22 02:19:03.239 T:474 DEBUG <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2021-07-22 02:19:03.239 T:474 DEBUG <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2021-07-22 02:19:03.239 T:474 DEBUG <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2021-07-22 02:19:03.240 T:474 DEBUG <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2021-07-22 02:19:03.240 T:474 DEBUG <general>: [WHITELIST] Matched an exact resolution with double the refresh rate 1920x1080 @ 59.940063 Hz (20)
2021-07-22 02:19:03.241 T:474 INFO <general>: Display resolution ADJUST : 1920x1080 @ 59.940063 Hz (20) (weight: -0.297)
2021-07-22 02:19:03.243 T:666 DEBUG <general>: OnAVChange: CApplication::OnAVChange
2021-07-22 02:19:03.255 T:474 DEBUG <general>: ------ Window Deinit (DialogBusy.xml) ------
2021-07-22 02:19:05.091 T:474 DEBUG <general>: ------ Window Init (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:19:05.093 T:474 DEBUG <general>: started alarm with name: showinfostart
2021-07-22 02:19:05.093 T:474 DEBUG <general>: started alarm with name: showinfostartdone
2021-07-22 02:19:05.124 T:474 DEBUG <general>: ------ Window Deinit (Custom_2134_ShowInfoOnPlayback.xml) ------
2021-07-22 02:19:05.142 T:688 DEBUG <general>: CDVDVideoCodecDRMPRIME::GetPicture - flush buffers
2021-07-22 02:19:05.143 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 29.970030 fps
2021-07-22 02:19:05.278 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:19:05.281 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 56
2021-07-22 02:19:05.281 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 54
2021-07-22 02:19:05.288 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 52
2021-07-22 02:19:05.295 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 50
2021-07-22 02:19:05.300 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 46
2021-07-22 02:19:05.361 T:688 ERROR <general>: ffmpeg[0x6595a50X]: [hevc] frame_post_process: Decode fail
2021-07-22 02:19:05.361 T:688 DEBUG <general>: OutputPicture - dropped in output
2021-07-22 02:19:05.792 T:688 DEBUG <general>: CDVDVideoCodecDRMPRIME::GetPicture - flush buffers
2021-07-22 02:19:05.792 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 29.970030 fps
2021-07-22 02:19:05.918 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:19:05.921 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 72
2021-07-22 02:19:05.921 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 70
2021-07-22 02:19:05.921 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 68
2021-07-22 02:19:05.921 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 66
2021-07-22 02:19:05.922 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 62
2021-07-22 02:19:05.970 T:688 ERROR <general>: ffmpeg[0x6595a50X]: [hevc] frame_post_process: Decode fail
2021-07-22 02:19:05.970 T:688 DEBUG <general>: OutputPicture - dropped in output
2021-07-22 02:19:06.393 T:688 DEBUG <general>: CDVDVideoCodecDRMPRIME::GetPicture - flush buffers
2021-07-22 02:19:06.393 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 29.970030 fps
2021-07-22 02:19:06.614 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:19:06.614 T:682 DEBUG <general>: CVideoPlayer::SetCaching - caching state 1
2021-07-22 02:19:06.614 T:682 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:19:06.617 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 88
2021-07-22 02:19:06.617 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 86
2021-07-22 02:19:06.618 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 84
2021-07-22 02:19:06.618 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 82
2021-07-22 02:19:06.618 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 78
2021-07-22 02:19:06.678 T:688 ERROR <general>: ffmpeg[0x6595a50X]: [hevc] frame_post_process: Decode fail
2021-07-22 02:19:06.679 T:688 DEBUG <general>: OutputPicture - dropped in output
2021-07-22 02:19:07.110 T:688 DEBUG <general>: CDVDVideoCodecDRMPRIME::GetPicture - flush buffers
2021-07-22 02:19:07.111 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe detected, switching to forced 29.970030 fps
2021-07-22 02:19:07.287 T:688 DEBUG <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2021-07-22 02:19:07.290 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 104
2021-07-22 02:19:07.290 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 102
2021-07-22 02:19:07.290 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 100
2021-07-22 02:19:07.290 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 98
2021-07-22 02:19:07.291 T:684 ERROR <general>: ffmpeg[(nil)X]: [hevc] Could not find ref with POC 94
2021-07-22 02:19:08.160 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:19:08.161 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:19:08.176 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:19:08.176 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:19:08.184 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:19:08.185 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:19:12.004 T:593 INFO <general>: WebSocket: connection closed by client
2021-07-22 02:19:12.005 T:593 INFO <general>: JSONRPC Server: Disconnection detected
2021-07-22 02:19:15.636 T:682 DEBUG <general>: CVideoPlayer::SetCaching - caching state 2
2021-07-22 02:19:15.636 T:682 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:19:15.636 T:682 DEBUG <general>: CVideoPlayer::SetCaching - caching state 3
2021-07-22 02:19:15.636 T:682 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:19:15.636 T:682 DEBUG <general>: CVideoPlayer::SetCaching - caching state 0
2021-07-22 02:19:15.636 T:682 DEBUG <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2021-07-22 02:19:15.688 T:688 ERROR <general>: ffmpeg[0x6595a50X]: [hevc] frame_post_process: Decode fail
2021-07-22 02:19:15.688 T:688 DEBUG <general>: OutputPicture - dropped in output
2021-07-22 02:19:19.691 T:688 DEBUG <general>: CPtsTracker: detected pattern of length 1: 33366.67, frameduration: 33366.666667
2021-07-22 02:19:20.326 T:688 DEBUG <general>: CPtsTracker: pattern lost on diff 134000.000000, number of losses 1
2021-07-22 02:19:24.497 T:688 DEBUG <general>: CPtsTracker: detected pattern of length 1: 33366.67, frameduration: 33366.666667
2021-07-22 02:19:33.243 T:675 DEBUG <general>: Thread JobWorker 2198241408 terminating (autodelete)
2021-07-22 02:19:33.243 T:671 DEBUG <general>: Thread JobWorker 2010030208 terminating (autodelete)
2021-07-22 02:19:33.243 T:666 DEBUG <general>: Thread JobWorker 1841807488 terminating (autodelete)
2021-07-22 02:19:39.084 T:683 DEBUG <general>: CFileCache::Process - </home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv> source read hit eof
2021-07-22 02:19:41.990 T:682 INFO <general>: Process - eof reading from demuxer
2021-07-22 02:19:41.990 T:682 INFO <general>: CVideoPlayer::OnExit()
2021-07-22 02:19:41.990 T:682 INFO <general>: VideoPlayer: eof, waiting for queues to empty
2021-07-22 02:19:41.990 T:682 INFO <general>: Closing stream player 2
2021-07-22 02:19:41.991 T:682 INFO <general>: CDVDMessageQueue(video)::WaitUntilEmpty
2021-07-22 02:19:42.111 T:688 DEBUG <general>: CDVDVideoCodecDRMPRIME::GetPicture - flush buffers
2021-07-22 02:19:42.112 T:688 DEBUG <general>: Skipped 1 duplicate messages..
2021-07-22 02:19:42.112 T:688 DEBUG <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
2021-07-22 02:19:42.112 T:682 INFO <general>: waiting for video thread to exit
2021-07-22 02:19:42.112 T:688 INFO <general>: thread end: video_thread
2021-07-22 02:19:42.113 T:688 DEBUG <general>: Thread VideoPlayerVideo 1888051328 terminating
2021-07-22 02:19:42.113 T:682 INFO <general>: deleting video codec
2021-07-22 02:19:42.134 T:696 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:19:42.134 T:683 DEBUG <general>: Thread FileCache 1687416960 terminating
2021-07-22 02:19:42.139 T:696 INFO <general>: Deleting settings information for files /home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv
2021-07-22 02:19:42.139 T:697 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:19:42.140 T:697 DEBUG <general>: DoWork - Saving file state for video item /home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv
2021-07-22 02:19:42.141 T:682 DEBUG <general>: Thread VideoPlayer 2582638720 terminating
2021-07-22 02:19:42.146 T:697 DEBUG <general>: DoWork - Marking video item /home/paulo/torrents-nas/4 - BB/Benchmark/jellyfish-60-mbps-hd-hevc-10bit.mkv as watched
2021-07-22 02:19:42.162 T:697 DEBUG <general>: OnPlayBackEnded: CApplication::OnPlayBackEnded
2021-07-22 02:19:42.166 T:474 INFO <general>: CVideoPlayer::CloseFile()
2021-07-22 02:19:42.166 T:474 DEBUG <general>: DeleteRenderer - deleting renderer
2021-07-22 02:19:42.166 T:474 INFO <general>: VideoPlayer: waiting for threads to exit
2021-07-22 02:19:42.166 T:474 INFO <general>: VideoPlayer: finished waiting
2021-07-22 02:19:42.167 T:474 INFO <general>: CVideoPlayer::CloseFile()
2021-07-22 02:19:42.167 T:474 INFO <general>: VideoPlayer: waiting for threads to exit
2021-07-22 02:19:42.167 T:474 INFO <general>: VideoPlayer: finished waiting
2021-07-22 02:19:42.169 T:474 DEBUG <general>: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2021-07-22 02:19:42.169 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Deactivate
2021-07-22 02:19:42.170 T:474 DEBUG <general>: ------ Window Deinit (VideoFullScreen.xml) ------
2021-07-22 02:19:42.170 T:698 DEBUG <general>: Thread Timer start, auto delete: false
2021-07-22 02:19:42.194 T:474 DEBUG <general>: OnLostDevice - notify display change event
2021-07-22 02:19:42.195 T:474 DEBUG <general>: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 60 Hz
2021-07-22 02:19:42.195 T:474 INFO <general>: GLES: Maximum texture width: 4096
2021-07-22 02:19:42.197 T:474 DEBUG <general>: CGUIWindowManager::PreviousWindow: Activate new
2021-07-22 02:19:42.198 T:474 DEBUG <general>: ------ Window Init (MyPics.xml) ------
2021-07-22 02:19:42.203 T:474 DEBUG <general>: CGUIMediaWindow::GetDirectory (/home/paulo/torrents-nas/4 - BB/Benchmark/)
2021-07-22 02:19:42.203 T:474 DEBUG <general>: ParentPath = [/home/paulo/torrents-nas/4 - BB/Benchmark/]
2021-07-22 02:19:42.229 T:699 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:19:42.229 T:699 DEBUG <general>: Thread BackgroundLoader 2582638720 terminating
2021-07-22 02:19:42.229 T:474 ERROR <general>: SetPriority: error No such process
2021-07-22 02:19:42.233 T:700 DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-07-22 02:19:42.250 T:697 DEBUG <general>: ffmpeg[0x9f302230X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.266 T:700 DEBUG <general>: Thread BackgroundLoader 2237399168 terminating
2021-07-22 02:19:42.268 T:701 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:19:42.269 T:696 DEBUG <general>: ffmpeg[0x9f3506c8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.269 T:702 DEBUG <general>: Thread JobWorker start, auto delete: true
2021-07-22 02:19:42.270 T:701 DEBUG <general>: ffmpeg[0x73ef500X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.276 T:601 DEBUG <general>: Twitch: Player: |onPlayBackEnded| isTwitch |False|
2021-07-22 02:19:42.282 T:579 DEBUG <general>: [ script.embuary.helper ] Force widgets to refresh (VideoLibrary.OnUpdate)
2021-07-22 02:19:42.283 T:579 DEBUG <general>: [ script.embuary.helper ] Execute: AlarmClock(WidgetRefresh,SetProperty(EmbuaryWidgetUpdate,20210722051942,home),00:10,silent)
2021-07-22 02:19:42.285 T:702 DEBUG <general>: ffmpeg[0x7341f50X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.295 T:474 DEBUG <general>: EGL Debugging:
Error: EGL_BAD_SURFACE
Command: eglSwapBuffers
Type: EGL_DEBUG_MSG_ERROR_KHR
Message: dri2_swap_buffers
2021-07-22 02:19:42.296 T:696 DEBUG <general>: ffmpeg[0x9f3506c8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.299 T:578 DEBUG <general>: [plugin.video.youtube] PlaybackMonitor active threads: ||
2021-07-22 02:19:42.300 T:474 DEBUG <general>: CVideoLayerBridgeDRMPRIME::Disable - setting connector colorspace to Default
2021-07-22 02:19:42.300 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 228
2021-07-22 02:19:42.300 T:474 DEBUG <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2021-07-22 02:19:42.310 T:702 DEBUG <general>: ffmpeg[0x7341f50X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.318 T:701 DEBUG <general>: ffmpeg[0x73ef500X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.321 T:696 DEBUG <general>: ffmpeg[0x9f3506c8X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-07-22 02:19:42.339 T:474 DEBUG <general>: started alarm with name: widgetrefresh
2021-07-22 02:19:42.373 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 227
2021-07-22 02:19:42.398 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 226
2021-07-22 02:19:42.415 T:474 DEBUG <general>: CWinSystemGbmGLESContext::PresentRender - Sending display reset to all clients
2021-07-22 02:19:42.420 T:474 DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 225
2021-07-22 02:19:42.509 T:697 DEBUG <general>: DoWork - took 267 ms to load resource://resource.images.skinbackgrounds.xonfluence/SKINDEFAULT.jpg
2021-07-22 02:19:42.680 T:698 DEBUG <general>: Thread Timer 2215026816 terminating
2021-07-22 02:20:02.418 T:496 DEBUG <general>: Sink changed
2021-07-22 02:20:08.207 T:578 DEBUG <CSettingsManager>: Skipped 1 duplicate messages..
2021-07-22 02:20:08.207 T:578 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:20:08.207 T:578 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:20:08.226 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:20:08.226 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:20:08.236 T:602 DEBUG <CSettingsManager>: requested setting (youtube.get.watch_later.playlist.id) was not found.
2021-07-22 02:20:08.236 T:602 DEBUG <CSettingsManager>: requested setting (youtube.search.remote.friendly) was not found.
2021-07-22 02:20:12.330 T:702 DEBUG <general>: Thread JobWorker 1896444032 terminating (autodelete)
2021-07-22 02:20:12.343 T:696 DEBUG <general>: Thread JobWorker 1879658624 terminating (autodelete)
2021-07-22 02:20:12.365 T:701 DEBUG <general>: Thread JobWorker 2582638720 terminating (autodelete)
2021-07-22 02:20:12.509 T:697 DEBUG <general>: Thread JobWorker 1687416960 terminating (autodelete)
Public Last updated: 2021-07-23 01:16:05 AM
