18:46:01 T:140658126428224 NOTICE: special://profile/ is mapped to: special://masterprofile/
18:46:01 T:140658126428224 NOTICE: -----------------------------------------------------------------------
18:46:01 T:140658126428224 NOTICE: Starting Kodi (17.0-BETA1 Git:81d5d26). Platform: Linux x86 64-bit
18:46:01 T:140658126428224 NOTICE: Using Release Kodi x64 build
18:46:01 T:140658126428224 NOTICE: Kodi compiled Aug 23 2016 by GCC 4.8.4 for Linux x86 64-bit version 3.13.11 (199947)
18:46:01 T:140658126428224 NOTICE: Running on Ubuntu 14.04.5 LTS, kernel: Linux x86 64-bit version 3.13.0-93-generic
18:46:01 T:140658126428224 NOTICE: FFmpeg statically linked, version: 3.1.2-kodi-3.1.2-Krypton-Beta1
18:46:01 T:140658126428224 NOTICE: Host CPU: Intel(R) Celeron(R) CPU G1610 @ 2.60GHz, 2 cores available
18:46:01 T:140658126428224 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
18:46:01 T:140658126428224 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
18:46:01 T:140658126428224 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons
18:46:01 T:140658126428224 NOTICE: special://masterprofile/ is mapped to: /var/lib/vdr/.kodi/userdata
18:46:01 T:140658126428224 NOTICE: special://envhome/ is mapped to: /var/lib/vdr
18:46:01 T:140658126428224 NOTICE: special://home/ is mapped to: /var/lib/vdr/.kodi
18:46:01 T:140658126428224 NOTICE: special://temp/ is mapped to: /var/lib/vdr/.kodi/temp
18:46:01 T:140658126428224 NOTICE: special://logpath/ is mapped to: /var/lib/vdr/.kodi/temp
18:46:01 T:140658126428224 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
18:46:01 T:140658126428224 NOTICE: Local hostname: yavdr
18:46:01 T:140658126428224 NOTICE: Log File is located: /var/lib/vdr/.kodi/temp//kodi.log
18:46:01 T:140658126428224 NOTICE: -----------------------------------------------------------------------
18:46:01 T:140658126428224 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
18:46:01 T:140658126428224 NOTICE: load settings...
18:46:01 T:140658126428224 ERROR: PulseAudio: Failed to connect context
18:46:01 T:140658126428224 NOTICE: PulseAudio might not be running. Context was not created.
18:46:01 T:140658126428224 NOTICE: Found 1 Lists of Devices
18:46:01 T:140658126428224 NOTICE: Enumerated ALSA devices:
18:46:01 T:140658126428224 NOTICE: Device 1
18:46:01 T:140658126428224 NOTICE: m_deviceName : default
18:46:01 T:140658126428224 NOTICE: m_displayName : Default (HDA NVidia HDMI 1)
18:46:01 T:140658126428224 NOTICE: m_displayNameExtra:
18:46:01 T:140658126428224 NOTICE: m_deviceType : AE_DEVTYPE_PCM
18:46:01 T:140658126428224 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
18:46:01 T:140658126428224 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
18:46:01 T:140658126428224 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
18:46:01 T:140658126428224 NOTICE: m_streamTypes : No passthrough capabilities
18:46:01 T:140658126428224 NOTICE: Device 2
18:46:01 T:140658126428224 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0
18:46:01 T:140658126428224 NOTICE: m_displayName : HDA NVidia
18:46:01 T:140658126428224 NOTICE: m_displayNameExtra: HDMI
18:46:01 T:140658126428224 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:46:01 T:140658126428224 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
18:46:01 T:140658126428224 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
18:46:01 T:140658126428224 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
18:46:01 T:140658126428224 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD
18:46:01 T:140658126428224 NOTICE: Device 3
18:46:01 T:140658126428224 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=1
18:46:01 T:140658126428224 NOTICE: m_displayName : HDA NVidia
18:46:01 T:140658126428224 NOTICE: m_displayNameExtra: DON DENON-AVRHD on HDMI
18:46:01 T:140658126428224 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
18:46:01 T:140658126428224 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,SL,SR
18:46:01 T:140658126428224 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
18:46:01 T:140658126428224 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
18:46:01 T:140658126428224 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512,STREAM_TYPE_EAC3,STREAM_TYPE_TRUEHD
18:46:01 T:140658126428224 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
18:46:01 T:140658126428224 NOTICE: Loaded settings file from special://profile/advancedsettings.xml
18:46:01 T:140658126428224 NOTICE: Contents of special://profile/advancedsettings.xml are...
mysql
192.168.178.8
3306
xbmc
xbmc
xbmc_video
mysql
192.168.178.8
3306
xbmc
xbmc
xbmc_music
true
true
true
true
sensors|sed -ne "s/Core0 Temp: \+[-+]\([0-9]\+\).*/\1 C/p"
echo "$(nvidia-smi -q | sed -ne "/Gpu/p" | sed -e "/N\/A/d" | sed -e 's/.*: \(.*\) C/\1 C/')"
157286400
1
4
18:46:01 T:140658126428224 WARNING: VIDEO database configuration is experimental.
18:46:01 T:140658126428224 NOTICE: Default Video Player: VideoPlayer
18:46:01 T:140658126428224 NOTICE: Default Audio Player: paplayer
18:46:01 T:140658126428224 NOTICE: Enabled debug logging due to GUI setting (2)
18:46:01 T:140658126428224 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
18:46:01 T:140658126428224 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
18:46:01 T:140658126428224 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
18:46:01 T:140658126428224 DEBUG: CPlayerCoreConfig::: created player VideoPlayer
18:46:01 T:140658126428224 DEBUG: CPlayerCoreConfig::: created player PAPlayer
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
18:46:01 T:140658126428224 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
18:46:01 T:140658126428224 NOTICE: Loaded playercorefactory configuration
18:46:01 T:140658126428224 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
18:46:01 T:140658126428224 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
18:46:01 T:140658126428224 INFO: creating subdirectories
18:46:01 T:140658126428224 INFO: userdata folder: special://masterprofile/
18:46:01 T:140658126428224 INFO: recording folder:
18:46:01 T:140658126428224 INFO: screenshots folder:
18:46:01 T:140658126428224 NOTICE: Running database version Addons26
18:46:01 T:140658126428224 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
18:46:01 T:140658126428224 DEBUG: Loading: /usr/lib/kodi/system/libcpluff-x86_64-linux.so
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Suspicious plug-in descriptor content in /var/lib/vdr/.kodi/addons/script.skinshortcuts/addon.xml, line 41, column 2 (ignoring unexpected element assets and its contents).'
18:46:01 T:140658126428224 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/kodi/addons: No such file or directory'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.autocompletion has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.libZdf has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.cu.lrclyrics has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.extendedinfo has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.moviegenreicons.arctic.zephyr has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in weather.yahoo has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in pvr.vdr.vnsi has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.chardet has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in repository.kodinerds has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.pydes has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.youtube.dl has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.libMediathek has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.program.autocompletion has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in repository.membrane has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.recordlabels.white has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.audio.radio_de has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.kodi65 has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.moviegenreicons.filmstrip has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.image.photoapp has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weatherfanart.multi has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.image.iphoto has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weatherfanart.single has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in skin.phenomenal has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.image.resource.select has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.pyxbmct has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.amazon-test has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.keymap has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.languageflags.colour has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.unidecode has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.skinshortcuts has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.ardmediathek has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.musicgenreicons.text has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.tivi_de has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in skin.estouchy has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in inputstream.mpd has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.requests has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.moviegenreicons.coloured has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.artistslideshow has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in inputstream.smoothstream has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.artwork.downloader has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in repository.sandmann79.plugins has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.artistslideshow-helper has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.actionhandler has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in service.library.data.provider has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in webinterface.chorus has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.routing has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.cdartmanager has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.zdf_de_lite has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.titan.modern has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in skin.titan has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.skin.helper.service has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.t9.search has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.confluence has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.images.studios.white has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.amazon.database has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.mechanize has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.libArd has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in resource.language.de_de has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.xbmcswift2 has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.amazon has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Plug-in script.module.addon.signals has been installed.'
18:46:01 T:140658126428224 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
18:46:02 T:140657652434688 DEBUG: Thread JobWorker start, auto delete: true
18:46:02 T:140658126428224 NOTICE: ADDONS: Using repository repository.xbmc.org
18:46:02 T:140658126428224 NOTICE: ADDONS: Using repository repository.kodinerds
18:46:02 T:140658126428224 NOTICE: ADDONS: Using repository repository.membrane
18:46:02 T:140658126428224 NOTICE: ADDONS: Using repository repository.sandmann79.plugins
18:46:02 T:140658126428224 DEBUG: ADDON: Dll Initializing - InputStream MPEG DASH
18:46:02 T:140658126428224 DEBUG: SECTION:LoadDLL(/var/lib/vdr/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.6)
18:46:02 T:140658126428224 DEBUG: Loading: /var/lib/vdr/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.6
18:46:02 T:140658126428224 DEBUG: AddOnLog: InputStream MPEG DASH: libXBMC_addon successfully loaded
18:46:02 T:140658126428224 DEBUG: AddOnLog: InputStream MPEG DASH: ADDON_Create()
18:46:02 T:140658126428224 DEBUG: AddOnLog: InputStream MPEG DASH: ADDON_Destroy()
18:46:02 T:140658126428224 DEBUG: SECTION:UnloadDll(/var/lib/vdr/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.6)
18:46:02 T:140658126428224 INFO: ADDON: Dll Destroyed - InputStream MPEG DASH
18:46:02 T:140658126428224 DEBUG: ADDON: Dll Initializing - InputStream SmoothStreamingMedia
18:46:02 T:140658126428224 DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/inputstream.smoothstream/inputstream.smoothstream.so.1.2.2)
18:46:02 T:140658126428224 DEBUG: Loading: /usr/lib/kodi/addons/inputstream.smoothstream/inputstream.smoothstream.so.1.2.2
18:46:02 T:140658126428224 DEBUG: AddOnLog: InputStream SmoothStreamingMedia: ADDON_Create()
18:46:02 T:140658126428224 DEBUG: AddOnLog: InputStream SmoothStreamingMedia: ADDON_Destroy()
18:46:02 T:140658126428224 DEBUG: SECTION:UnloadDll(/usr/lib/kodi/addons/inputstream.smoothstream/inputstream.smoothstream.so.1.2.2)
18:46:02 T:140658126428224 INFO: ADDON: Dll Destroyed - InputStream SmoothStreamingMedia
18:46:02 T:140657639593728 DEBUG: Thread ActiveAE start, auto delete: false
18:46:02 T:140657494583040 DEBUG: Thread AESink start, auto delete: false
18:46:02 T:140657494583040 INFO: CActiveAESink::OpenSink - initialize sink
18:46:02 T:140657494583040 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=1
18:46:02 T:140657494583040 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=1"
18:46:02 T:140657494583040 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
18:46:02 T:140657494583040 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
18:46:02 T:140657494583040 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
18:46:02 T:140657494583040 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
18:46:02 T:140657494583040 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
18:46:02 T:140657494583040 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms
18:46:02 T:140657494583040 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:46:02 T:140657494583040 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
18:46:02 T:140657494583040 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
18:46:02 T:140657494583040 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
18:46:02 T:140657494583040 DEBUG: Output Device : HDA NVidia
18:46:02 T:140657494583040 DEBUG: Sample Rate : 44100
18:46:02 T:140657494583040 DEBUG: Sample Format : AE_FMT_S32NE
18:46:02 T:140657494583040 DEBUG: Channel Count : 2
18:46:02 T:140657494583040 DEBUG: Channel Layout: FL,FR
18:46:02 T:140657494583040 DEBUG: Frames : 2048
18:46:02 T:140657494583040 DEBUG: Frame Size : 8
18:46:02 T:140657486190336 DEBUG: Thread RemoteControl start, auto delete: false
18:46:02 T:140657486190336 INFO: LIRC Process: using: /var/run/lirc/lircd
18:46:02 T:140657486190336 INFO: LIRC Connect: successfully started
18:46:02 T:140657486190336 DEBUG: Thread RemoteControl 140657486190336 terminating
18:46:02 T:140658126428224 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
18:46:02 T:140658126428224 DEBUG: Selected UDisks as storage provider
18:46:02 T:140658126428224 DEBUG: UDisks: DaemonVersion 1
18:46:02 T:140658126428224 DEBUG: UDisks: Querying available devices
18:46:02 T:140658126428224 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sda: IsFileSystem false HasFileSystem IsSystemInternal true IsMounted false IsRemovable false IsPartition false IsOptical false
18:46:02 T:140658126428224 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sda1: IsFileSystem true HasFileSystem ext4 IsSystemInternal true IsMounted true IsRemovable false IsPartition true IsOptical false
18:46:02 T:140658126428224 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sda2: IsFileSystem false HasFileSystem IsSystemInternal true IsMounted false IsRemovable false IsPartition true IsOptical false
18:46:02 T:140658126428224 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sda5: IsFileSystem false HasFileSystem IsSystemInternal true IsMounted false IsRemovable false IsPartition true IsOptical false
18:46:02 T:140658126428224 INFO: Available videomodes (xrandr):
18:46:02 T:140658126428224 INFO: Output 'HDMI-0' has 42 modes
18:46:02 T:140658126428224 INFO: ID:0x1e9 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1ea Name:1920x1080 Refresh:59.939388 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1eb Name:1920x1080 Refresh:59.933880 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1ec Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1ed Name:1920x1080 Refresh:23.970909 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1ee Name:1920x1080 Refresh:30.026690 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1ef Name:1920x1080 Refresh:29.998381 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1f0 Name:1920x1080 Refresh:25.022240 Width:1920 Height:1080
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1f1 Name:1680x1050 Refresh:59.954250 Width:1680 Height:1050
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.111111
18:46:02 T:140658126428224 INFO: ID:0x1f2 Name:1680x1050 Refresh:59.883251 Width:1680 Height:1050
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.111111
18:46:02 T:140658126428224 INFO: ID:0x1f3 Name:1440x900 Refresh:59.887451 Width:1440 Height:900
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.111111
18:46:02 T:140658126428224 INFO: ID:0x1f4 Name:1400x1050 Refresh:59.975620 Width:1400 Height:1050
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x1f5 Name:1360x768 Refresh:59.960030 Width:1360 Height:768
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.003922
18:46:02 T:140658126428224 INFO: ID:0x1f6 Name:1360x768 Refresh:59.798988 Width:1360 Height:768
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.003922
18:46:02 T:140658126428224 INFO: ID:0x1f7 Name:1280x1024 Refresh:60.019741 Width:1280 Height:1024
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.422222
18:46:02 T:140658126428224 INFO: ID:0x1f8 Name:1280x960 Refresh:60.000000 Width:1280 Height:960
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x1f9 Name:1280x720 Refresh:60.000000 Width:1280 Height:720
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1fa Name:1280x720 Refresh:59.943432 Width:1280 Height:720
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1fb Name:1280x720 Refresh:50.000000 Width:1280 Height:720
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1fc Name:1152x864 Refresh:59.997059 Width:1152 Height:864
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x1fd Name:1024x768 Refresh:60.003841 Width:1024 Height:768
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x1fe Name:960x540 Refresh:119.975739 Width:960 Height:540
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.000000
18:46:02 T:140658126428224 INFO: ID:0x1ff Name:840x525 Refresh:120.018707 Width:840 Height:525
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.111111
18:46:02 T:140658126428224 INFO: ID:0x200 Name:840x525 Refresh:119.766510 Width:840 Height:525
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.111111
18:46:02 T:140658126428224 INFO: ID:0x201 Name:800x600 Refresh:60.316540 Width:800 Height:600
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x202 Name:800x600 Refresh:56.250000 Width:800 Height:600
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x203 Name:720x576 Refresh:50.000000 Width:720 Height:576
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.422222
18:46:02 T:140658126428224 INFO: ID:0x204 Name:720x576 Refresh:25.040060 Width:720 Height:576
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.422222
18:46:02 T:140658126428224 INFO: ID:0x205 Name:720x480 Refresh:59.940060 Width:720 Height:480
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.185185
18:46:02 T:140658126428224 INFO: ID:0x206 Name:720x480 Refresh:30.027220 Width:720 Height:480
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.185185
18:46:02 T:140658126428224 INFO: ID:0x207 Name:720x450 Refresh:119.774887 Width:720 Height:450
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.111111
18:46:02 T:140658126428224 INFO: ID:0x208 Name:700x525 Refresh:119.951233 Width:700 Height:525
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x209 Name:680x384 Refresh:119.920052 Width:680 Height:384
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.003922
18:46:02 T:140658126428224 INFO: ID:0x20a Name:680x384 Refresh:119.597977 Width:680 Height:384
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.003922
18:46:02 T:140658126428224 INFO: ID:0x20b Name:640x480 Refresh:59.940479 Width:640 Height:480
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x20c Name:640x480 Refresh:59.928570 Width:640 Height:480
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x20d Name:512x384 Refresh:120.007683 Width:512 Height:384
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 INFO: ID:0x20e Name:480x576 Refresh:50.000000 Width:480 Height:576
18:46:02 T:140658126428224 INFO: Pixel Ratio: 2.133333
18:46:02 T:140658126428224 INFO: ID:0x20f Name:480x480 Refresh:59.940060 Width:480 Height:480
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.777778
18:46:02 T:140658126428224 INFO: ID:0x210 Name:411x576 Refresh:50.070591 Width:411 Height:576
18:46:02 T:140658126428224 INFO: Pixel Ratio: 2.491484
18:46:02 T:140658126428224 INFO: ID:0x211 Name:411x480 Refresh:59.972790 Width:411 Height:480
18:46:02 T:140658126428224 INFO: Pixel Ratio: 2.076237
18:46:02 T:140658126428224 INFO: ID:0x212 Name:320x240 Refresh:120.104958 Width:320 Height:240
18:46:02 T:140658126428224 INFO: Pixel Ratio: 1.333333
18:46:02 T:140658126428224 NOTICE: Checking resolution 16
18:46:02 T:140658126428224 DEBUG: Window Manager Name: Openbox
18:46:02 T:140658126428224 ERROR: Failed to determine egl config for visual info
18:46:02 T:140658126428224 WARNING: Visual 0x27 of the window is not suitable, looking for another one...
18:46:02 T:140658126428224 NOTICE: Using visual 0x21
18:46:02 T:140658126428224 NOTICE: Using visual 0x27
18:46:02 T:140658126428224 INFO: GL: Maximum texture width: 16384
18:46:02 T:140658126428224 DEBUG: GLX_EXTENSIONS: GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control GLX_EXT_swap_control GLX_EXT_swap_control_tear GLX_EXT_texture_from_pixmap GLX_EXT_buffer_age GLX_ARB_create_context GLX_ARB_create_context_profile GLX_EXT_create_context_es_profile GLX_EXT_create_context_es2_profile GLX_ARB_create_context_robustness GLX_NV_delay_before_swap GLX_EXT_stereo_tree GLX_ARB_context_flush_control GLX_ARB_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_EXT_framebuffer_sRGB GLX_NV_multisample_coverage GLX_NV_copy_image GLX_ARB_get_proc_address
18:46:02 T:140658126428224 NOTICE: GL_VENDOR = NVIDIA Corporation
18:46:02 T:140658126428224 NOTICE: GL_RENDERER = GeForce GT 630/PCIe/SSE2
18:46:02 T:140658126428224 NOTICE: GL_VERSION = 4.5.0 NVIDIA 352.63
18:46:02 T:140658126428224 NOTICE: GL_SHADING_LANGUAGE_VERSION = 4.50 NVIDIA
18:46:02 T:140658126428224 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 1048576
18:46:02 T:140658126428224 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 1048576
18:46:02 T:140658126428224 NOTICE: GL_EXTENSIONS = GL_AMD_multi_draw_indirect GL_AMD_seamless_cubemap_per_texture GL_ARB_arrays_of_arrays GL_ARB_base_instance GL_ARB_bindless_texture GL_ARB_blend_func_extended GL_ARB_buffer_storage GL_ARB_clear_buffer_object GL_ARB_clear_texture GL_ARB_clip_control GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_compute_shader GL_ARB_compute_variable_group_size GL_ARB_conditional_render_inverted GL_ARB_copy_buffer GL_ARB_copy_image GL_ARB_cull_distance GL_ARB_debug_output GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_derivative_control GL_ARB_direct_state_access GL_ARB_draw_buffers GL_ARB_draw_buffers_blend GL_ARB_draw_indirect GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_enhanced_layouts GL_ARB_ES2_compatibility GL_ARB_ES3_compatibility GL_ARB_ES3_1_compatibility GL_ARB_explicit_attrib_location GL_ARB_explicit_uniform_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_layer_viewport GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_no_attachments GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_get_texture_sub_image GL_ARB_gpu_shader5 GL_ARB_gpu_shader_fp64 GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_indirect_parameters GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_internalformat_query2 GL_NV_internalformat_sample_query GL_ARB_invalidate_subdata GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multi_bind GL_ARB_multi_draw_indirect GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pipeline_statistics_query GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_program_interface_query GL_ARB_provoking_vertex GL_ARB_robust_buffer_access_behavior GL_ARB_robustness GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_seamless_cubemap_per_texture GL_ARB_separate_shader_objects GL_ARB_shader_atomic_counters GL_ARB_shader_bit_encoding GL_ARB_shader_draw_parameters GL_ARB_shader_group_vote GL_ARB_shader_image_load_store GL_ARB_shader_image_size GL_ARB_shader_objects GL_ARB_shader_precision GL_ARB_query_buffer_object GL_ARB_shader_storage_buffer_object GL_ARB_shader_subroutine GL_ARB_shader_texture_image_samples GL_ARB_shader_texture_lod GL_ARB_shading_language_100 GL_ARB_shading_language_420pack GL_ARB_shading_language_include GL_ARB_shading_language_packing GL_ARB_shadow GL_ARB_sparse_buffer GL_ARB_sparse_texture GL_ARB_stencil_texturing GL_ARB_sync GL_ARB_tessellation_shader GL_ARB_texture_barrier GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_object_rgb32 GL_ARB_texture_buffer_range GL_ARB_texture_compression GL_ARB_texture_compression_bptc GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_cube_map_array GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_gather GL_ARB_texture_mirror_clamp_to_edge GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_levels GL_ARB_texture_query_lod GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_stencil8 GL_ARB_texture_storage GL_ARB_texture_storage_multisample GL_ARB_texture_swizzle GL_ARB_texture_view GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ARB_transform_feedback_instanced GL_ARB_transform_feedback_overflow_query GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_attrib_64bit GL_ARB_vertex_attrib_binding GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_vertex_type_10f_11f_11f_rev GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_bindable_uniform GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_polygon_offset_clamp GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shader_image_load_store GL_EXT_shader_integer_mix GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent GL_EXT_texture_sRGB GL_EXT_texture_sRGB_decode GL_EXT_texture_storage GL_EXT_texture_swizzle GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_EXT_vertex_attrib_64bit GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KHR_context_flush_control GL_KHR_debug GL_KHR_robust_buffer_access_behavior GL_KHR_robustness GL_KTX_buffer_region GL_NV_bindless_multi_draw_indirect GL_NV_bindless_multi_draw_indirect_count GL_NV_bindless_texture GL_NV_blend_equation_advanced GL_NV_blend_square GL_NV_command_list GL_NV_compute_program5 GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_draw_texture GL_NV_ES1_1_compatibility GL_NV_ES3_1_compatibility GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_gpu_program4_1 GL_NV_gpu_program5 GL_NV_gpu_program5_mem_extended GL_NV_gpu_program_fp64 GL_NV_gpu_shader5 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_path_rendering GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_atomic_counters GL_NV_shader_atomic_float GL_NV_shader_atomic_int64 GL_NV_shader_buffer_load GL_NV_shader_storage_buffer_object GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_uniform_buffer_unified_memory GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_attrib_integer_64bit GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_NVX_nvenc_interop GL_NV_shader_thread_group GL_NV_shader_thread_shuffle GL_KHR_blend_equation_advanced GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
18:46:02 T:140658126428224 INFO: GL: Maximum texture width: 16384
18:46:02 T:140658126428224 INFO: GL: Enabling VSYNC
18:46:02 T:140658126428224 INFO: load keymapping
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/joystick.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/mouse.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/remote.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
18:46:02 T:140658126428224 INFO: Loading special://masterprofile/keymaps/gen.xml
18:46:02 T:140658126428224 INFO: Loading special://profile/keymaps/gen.xml
18:46:02 T:140658126428224 INFO: Loading special://xbmc/system/Lircmap.xml
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'mceusb'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'XboxDVDDongle'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'anysee'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'iMON-PAD'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'MCE_via_iMON'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'TwinHanRemote'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'linux-input-layer'
18:46:02 T:140658126428224 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'mediacenter'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'devinput'
18:46:02 T:140658126428224 INFO: Loading special://profile/Lircmap.xml
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'mceusb'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'XboxDVDDongle'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'anysee'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'iMON-PAD'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'MCE_via_iMON'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'TwinHanRemote'
18:46:02 T:140658126428224 INFO: * Adding remote mapping for device 'linux-input-layer'
18:46:02 T:140658126428224 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
18:46:02 T:140658126428224 INFO: * Linking remote mapping for 'linux-input-layer' to 'devinput'
18:46:02 T:140658126428224 INFO: GUI format 1920x1080, Display 1920x1080@ 50.00 - Full Screen
18:46:02 T:140658126428224 DEBUG: guilib: Fill viewport on change for solving rendering passes
18:46:02 T:140658126428224 INFO: CLangInfo: loading resource.language.de_de language information...
18:46:02 T:140658126428224 DEBUG: trying to set locale to de_DE.UTF-8
18:46:02 T:140658126428224 INFO: global locale set to de_DE.UTF-8
18:46:02 T:140658126428224 INFO: CLangInfo: loading resource.language.de_de language strings...
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 3693 strings from file resource://resource.language.de_de/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 51 strings from file resource://resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 29 strings from file /var/lib/vdr/.kodi/addons/script.cu.lrclyrics/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: POParser: id:32101 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
18:46:02 T:140658126428224 DEBUG: POParser: id:32103 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
18:46:02 T:140658126428224 DEBUG: POParser: id:32110 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
18:46:02 T:140658126428224 DEBUG: POParser: id:32111 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
18:46:02 T:140658126428224 DEBUG: POParser: id:32112 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
18:46:02 T:140658126428224 DEBUG: POParser: id:32113 was recently re-used in the English string file, which is not yet changed in the translated file. Using the English string instead
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 13 strings from file /var/lib/vdr/.kodi/addons/script.cu.lrclyrics/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 167 strings from file /var/lib/vdr/.kodi/addons/script.extendedinfo/resources/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 4 strings from file /var/lib/vdr/.kodi/addons/weather.yahoo/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 2 strings from file /var/lib/vdr/.kodi/addons/weather.yahoo/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 69 strings from file /usr/share/kodi/addons/pvr.vdr.vnsi/resources/language/resource.language.de_de/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /usr/share/kodi/addons/pvr.vdr.vnsi/resources/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 18 strings from file /var/lib/vdr/.kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 37 strings from file /var/lib/vdr/.kodi/addons/script.module.libMediathek/resources/language/German/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/script.module.libMediathek/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 37 strings from file /var/lib/vdr/.kodi/addons/script.module.youtube.dl/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.de_de/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 7 strings from file /var/lib/vdr/.kodi/addons/metadata.themoviedb.org/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 3 strings from file /var/lib/vdr/.kodi/addons/plugin.program.autocompletion/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 21 strings from file /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 25 strings from file /var/lib/vdr/.kodi/addons/plugin.audio.radio_de/resources/language/German/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/plugin.audio.radio_de/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 18 strings from file /var/lib/vdr/.kodi/addons/plugin.image.photoapp/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/plugin.image.photoapp/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 80 strings from file /var/lib/vdr/.kodi/addons/plugin.image.iphoto/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/plugin.image.iphoto/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 7 strings from file /var/lib/vdr/.kodi/addons/script.common.plugin.cache/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 12 strings from file /var/lib/vdr/.kodi/addons/script.keymap/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 115 strings from file /var/lib/vdr/.kodi/addons/script.skinshortcuts/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 2 strings from file /var/lib/vdr/.kodi/addons/script.skinshortcuts/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 17 strings from file /var/lib/vdr/.kodi/addons/plugin.video.ardmediathek/resources/language/German/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 9 strings from file /var/lib/vdr/.kodi/addons/plugin.video.ardmediathek/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 2 strings from file /var/lib/vdr/.kodi/addons/metadata.musicvideos.theaudiodb.com/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/metadata.musicvideos.theaudiodb.com/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 7 strings from file /var/lib/vdr/.kodi/addons/plugin.video.tivi_de/resources/language/German/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 1 strings from file /var/lib/vdr/.kodi/addons/plugin.video.tivi_de/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 101 strings from file /var/lib/vdr/.kodi/addons/plugin.video.youtube/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 7 strings from file /var/lib/vdr/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 6 strings from file /var/lib/vdr/.kodi/addons/inputstream.mpd/resources/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 106 strings from file /var/lib/vdr/.kodi/addons/script.artistslideshow/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 1 strings from file /var/lib/vdr/.kodi/addons/script.artistslideshow/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 99 strings from file /var/lib/vdr/.kodi/addons/script.artwork.downloader/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 1 strings from file /var/lib/vdr/.kodi/addons/script.artwork.downloader/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 5 strings from file /usr/share/kodi/addons/inputstream.smoothstream/resources/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 21 strings from file /var/lib/vdr/.kodi/addons/script.artistslideshow-helper/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 8 strings from file /var/lib/vdr/.kodi/addons/script.artistslideshow-helper/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 24 strings from file /var/lib/vdr/.kodi/addons/service.library.data.provider/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 17 strings from file /var/lib/vdr/.kodi/addons/plugin.video.zdf_de_lite/resources/language/German/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 9 strings from file /var/lib/vdr/.kodi/addons/plugin.video.zdf_de_lite/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 11 strings from file /var/lib/vdr/.kodi/addons/metadata.album.universal/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 2 strings from file /var/lib/vdr/.kodi/addons/metadata.album.universal/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 179 strings from file /var/lib/vdr/.kodi/addons/script.cdartmanager/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 12 strings from file /var/lib/vdr/.kodi/addons/script.cdartmanager/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 5 strings from file /var/lib/vdr/.kodi/addons/metadata.tvdb.com/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 2 strings from file /var/lib/vdr/.kodi/addons/metadata.tvdb.com/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 197 strings from file /var/lib/vdr/.kodi/addons/script.skin.helper.service/resources/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 35 strings from file /var/lib/vdr/.kodi/addons/script.module.libArd/resources/language/German/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/script.module.libArd/resources/language/English/strings.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 23 strings from file /var/lib/vdr/.kodi/addons/metadata.artists.universal/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 3 strings from file /var/lib/vdr/.kodi/addons/metadata.artists.universal/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /var/lib/vdr/.kodi/addons/plugin.video.amazon-test/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 118 strings from file /var/lib/vdr/.kodi/addons/plugin.video.amazon-test/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /var/lib/vdr/.kodi/addons/plugin.video.amazon-test/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/plugin.video.amazon-test/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /var/lib/vdr/.kodi/addons/plugin.video.amazon/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 165 strings from file /var/lib/vdr/.kodi/addons/plugin.video.amazon/resources/language/German/strings.po
18:46:02 T:140658126428224 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /var/lib/vdr/.kodi/addons/plugin.video.amazon/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 0 strings from file /var/lib/vdr/.kodi/addons/plugin.video.amazon/resources/language/English/strings.po
18:46:02 T:140658126428224 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
18:46:02 T:140658126428224 DEBUG: LoadMappings - loaded node "CEC Adapter"
18:46:02 T:140658126428224 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
18:46:02 T:140658126428224 DEBUG: LoadMappings - loaded node "iMON HID device"
18:46:02 T:140658126428224 DEBUG: LoadMappings - loaded node "WETEK Play remote"
18:46:02 T:140658126428224 DEBUG: CPeripheralBusUSB - initialised udev monitor
18:46:02 T:140658126428224 DEBUG: SECTION:LoadDLL(libcec.so.3)
18:46:02 T:140658126428224 DEBUG: Loading: libcec.so.3
18:46:02 T:140657486190336 DEBUG: Thread PeripBusUSBUdev start, auto delete: false
18:46:02 T:140657117193984 DEBUG: Thread PeripBusCEC start, auto delete: false
18:46:02 T:140657108801280 DEBUG: Thread PeripBusAddon start, auto delete: false
18:46:02 T:140658126428224 DEBUG: SECTION:LoadDLL(libcurl.so.4)
18:46:02 T:140658126428224 DEBUG: Loading: libcurl.so.4
18:46:02 T:140658126428224 NOTICE: Running database version Addons26
18:46:02 T:140658126428224 DEBUG: Initialize, updating databases...
18:46:02 T:140658126428224 NOTICE: Running database version ViewModes6
18:46:02 T:140658126428224 NOTICE: Running database version Textures13
18:46:02 T:140657100408576 DEBUG: Thread PeripEventScanner start, auto delete: false
18:46:02 T:140658126428224 INFO: MYSQL: Connected to version 5.5.44-MariaDB-log
18:46:02 T:140658126428224 NOTICE: Running database version xbmc_music60
18:46:02 T:140658126428224 NOTICE: Running database version xbmc_video107
18:46:02 T:140658126428224 NOTICE: Running database version TV29
18:46:02 T:140658126428224 NOTICE: Running database version Epg11
18:46:02 T:140658126428224 DEBUG: Initialize, updating databases... DONE
18:46:02 T:140658126428224 NOTICE: start dvd mediatype detection
18:46:02 T:140657628960512 DEBUG: Thread DetectDVDMedia start, auto delete: false
18:46:02 T:140657628960512 DEBUG: Compiled with libcdio Version 0.83
18:46:02 T:140658126428224 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
18:46:02 T:140657628960512 DEBUG: Thread DetectDVDMedia 140657628960512 terminating
18:46:02 T:140657671345920 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
18:46:02 T:140657671345920 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
18:46:02 T:140658126428224 DEBUG: Activating window ID: 12997
18:46:02 T:140658126428224 DEBUG: ------ Window Init () ------
18:46:02 T:140658126428224 INFO: load splash image: /usr/share/kodi/media/Splash.png
18:46:02 T:140658126428224 INFO: Unloading old skin ...
18:46:02 T:140658126428224 INFO: load skin from: /usr/share/kodi/addons/skin.estuary (version: 0.8.5)
18:46:02 T:140658126428224 INFO: load fonts for skin...
18:46:02 T:140658126428224 INFO: Loading fonts from /usr/share/kodi/addons/skin.estuary/1080i/Font.xml
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 135 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.de_de/strings.po
18:46:02 T:140658126428224 DEBUG: LocalizeStrings: loaded 8 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po
18:46:02 T:140658126428224 INFO: Loading skin includes from /usr/share/kodi/addons/skin.estuary/1080i/Includes.xml
18:46:02 T:140658126428224 INFO: load new skin...
18:46:02 T:140658126428224 INFO: Loading user windows, path /usr/share/kodi/addons/skin.estuary/1080i
18:46:02 T:140658126428224 DEBUG: Load Skin XML: 2.99ms
18:46:02 T:140658126428224 INFO: initialize new skin...
18:46:02 T:140658126428224 DEBUG: guilib: Fill viewport on change for solving rendering passes
18:46:02 T:140658126428224 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
18:46:02 T:140658126428224 DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.estuary/media/Textures.xbt
18:46:02 T:140658126428224 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
18:46:02 T:140658126428224 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
18:46:02 T:140658126428224 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
18:46:02 T:140658126428224 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
18:46:02 T:140658126428224 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
18:46:02 T:140658126428224 INFO: Loading resource://resource.uisounds.confluence/sounds.xml
18:46:02 T:140658126428224 INFO: skin loaded...
18:46:02 T:140658126428224 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
18:46:02 T:140658126428224 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
18:46:02 T:140658126428224 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
18:46:02 T:140658126428224 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
18:46:02 T:140658126428224 ERROR: JSONRPC: Unable to parse JSON Schema definition for ""
18:46:02 T:140658126428224 ERROR: JSONRPC: Invalid JSON Schema definition for method ""
18:46:02 T:140658126428224 INFO: JSONRPC v7.21.0: Successfully initialized
18:46:02 T:140658126428224 DEBUG: ADDON: Starting service addons.
18:46:02 T:140658126428224 DEBUG: Activating window ID: 12999
18:46:02 T:140658126428224 DEBUG: ------ Window Init (Startup.xml) ------
18:46:02 T:140658126428224 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
18:46:02 T:140658126428224 DEBUG: Activating window ID: 10000
18:46:02 T:140658126428224 DEBUG: ------ Window Deinit (Startup.xml) ------
18:46:02 T:140658126428224 DEBUG: ------ Window Init (Home.xml) ------
18:46:02 T:140658126428224 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
18:46:02 T:140656723875584 DEBUG: Thread LanguageInvoker start, auto delete: false
18:46:02 T:140656723875584 INFO: initializing python engine.
18:46:02 T:140656934045440 DEBUG: Thread LanguageInvoker start, auto delete: false
18:46:02 T:140656934045440 INFO: initializing python engine.
18:46:02 T:140656715482880 DEBUG: Thread LanguageInvoker start, auto delete: false
18:46:02 T:140656715482880 INFO: initializing python engine.
18:46:02 T:140656707090176 DEBUG: Thread LanguageInvoker start, auto delete: false
18:46:02 T:140656707090176 INFO: initializing python engine.
18:46:02 T:140656723875584 DEBUG: CPythonInvoker(1, /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/service.py): start processing
18:46:02 T:140656934045440 DEBUG: CPythonInvoker(0, /var/lib/vdr/.kodi/addons/script.cu.lrclyrics/default.py): start processing
18:46:02 T:140656715482880 DEBUG: CPythonInvoker(2, /var/lib/vdr/.kodi/addons/service.library.data.provider/service.py): start processing
18:46:02 T:140656707090176 DEBUG: CPythonInvoker(3, /var/lib/vdr/.kodi/addons/script.skin.helper.service/service.py): start processing
18:46:02 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
18:46:02 T:140658126428224 DEBUG: CDirectoryProvider[videodb://recentlyaddedmovies/]: refreshing..
18:46:02 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
18:46:02 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
18:46:02 T:140658126428224 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
18:46:02 T:140658126428224 DEBUG: CDirectoryProvider[videodb://recentlyaddedepisodes/]: refreshing..
18:46:02 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
18:46:02 T:140656697911040 DEBUG: Thread JobWorker start, auto delete: true
18:46:02 T:140657652434688 DEBUG: RunQuery took 34 ms for 57 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
18:46:02 T:140656723875584 DEBUG: -->Python Interpreter Initialized<--
18:46:02 T:140656723875584 DEBUG: CPythonInvoker(1, /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/service.py"
18:46:02 T:140656707090176 DEBUG: -->Python Interpreter Initialized<--
18:46:02 T:140656707090176 DEBUG: CPythonInvoker(3, /var/lib/vdr/.kodi/addons/script.skin.helper.service/service.py): the source file to load is "/var/lib/vdr/.kodi/addons/script.skin.helper.service/service.py"
18:46:02 T:140656934045440 DEBUG: -->Python Interpreter Initialized<--
18:46:02 T:140656934045440 DEBUG: CPythonInvoker(0, /var/lib/vdr/.kodi/addons/script.cu.lrclyrics/default.py): the source file to load is "/var/lib/vdr/.kodi/addons/script.cu.lrclyrics/default.py"
18:46:02 T:140656723875584 DEBUG: CPythonInvoker(1, /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:46:02 T:140656723875584 DEBUG: CPythonInvoker(1, /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck
18:46:02 T:140656723875584 DEBUG: CPythonInvoker(1, /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/service.py): instantiating addon using automatically obtained id of "service.xbmc.versioncheck" dependent on version 2.1.0 of the xbmc.python api
18:46:02 T:140656707090176 DEBUG: CPythonInvoker(3, /var/lib/vdr/.kodi/addons/script.skin.helper.service/service.py): setting the Python path to /var/lib/vdr/.kodi/addons/script.skin.helper.service:/usr/share/kodi/addons/script.module.pil/lib:/var/lib/vdr/.kodi/addons/script.module.beautifulsoup/lib:/var/lib/vdr/.kodi/addons/script.module.requests/lib:/var/lib/vdr/.kodi/addons/script.module.simplejson/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:46:02 T:140656707090176 DEBUG: CPythonInvoker(3, /var/lib/vdr/.kodi/addons/script.skin.helper.service/service.py): entering source directory /var/lib/vdr/.kodi/addons/script.skin.helper.service
18:46:02 T:140656707090176 DEBUG: CPythonInvoker(3, /var/lib/vdr/.kodi/addons/script.skin.helper.service/service.py): instantiating addon using automatically obtained id of "script.skin.helper.service" dependent on version 2.13.0 of the xbmc.python api
18:46:02 T:140656723875584 DEBUG: Version Check: Version 0.3.19 started
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[musicdb://recentlyplayedalbums]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[musicdb://recentlyaddedalbums/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/random_albums.xsp]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/random_artists.xsp]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/unplayed_albums.xsp]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[special://skin/playlists/mostplayed_albums.xsp]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[-]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[addons://repository.xbmc.org/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[pvr://channels/tv/All channels/]: refreshing..
18:46:03 T:140658126428224 DEBUG: CDirectoryProvider[pvr://channels/radio/All channels/]: refreshing..
18:46:03 T:140656539416320 DEBUG: Thread JobWorker start, auto delete: true
18:46:03 T:140658126428224 INFO: removing tempfiles
18:46:03 T:140658126428224 DEBUG: ADDON: Starting service addons.
18:46:03 T:140658126428224 DEBUG: CRepositoryUpdater: previous update at 26.08.2016 07:17:08, next at 27.08.2016 07:17:08
18:46:03 T:140658126428224 NOTICE: initialize done
18:46:03 T:140658126428224 NOTICE: Running the application...
18:46:03 T:140656531023616 DEBUG: Thread LanguageInvoker start, auto delete: false
18:46:03 T:140656531023616 INFO: initializing python engine.
18:46:03 T:140656531023616 DEBUG: CPythonInvoker(4, /var/lib/vdr/.kodi/addons/script.common.plugin.cache/default.py): start processing
18:46:03 T:140658126428224 DEBUG: no profile autoexec.py (/var/lib/vdr/.kodi/userdata/autoexec.py) found, skipping
18:46:03 T:140658126428224 DEBUG: NetworkMessage - Starting network services
18:46:03 T:140658126428224 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
18:46:03 T:140658126428224 NOTICE: starting zeroconf publishing
18:46:03 T:140658126428224 NOTICE: CWebServer[8080]: Started
18:46:03 T:140658126428224 NOTICE: starting upnp client
18:46:03 T:140656522630912 DEBUG: Thread Timer start, auto delete: false
18:46:03 T:140657652434688 DEBUG: RunQuery took 300 ms for 718 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
18:46:03 T:140658126428224 INFO: JSONRPC Server: Successfully initialized
18:46:03 T:140656253056768 DEBUG: Thread EventServer start, auto delete: false
18:46:03 T:140656253056768 NOTICE: ES: Starting UDP Event server on port 9777
18:46:03 T:140658126428224 DEBUG: SECTION:LoadDLL(libshairplay.so.0)
18:46:03 T:140656253056768 NOTICE: UDP: Listening on port 9777 (ipv6 : false)
18:46:03 T:140658126428224 DEBUG: Loading: libshairplay.so.0
18:46:03 T:140658126428224 INFO: AIRPLAY: Cleaning up photoassetcache
18:46:03 T:140658126428224 INFO: AIRPLAY Server: Successfully initialized
18:46:03 T:140656152409856 DEBUG: Thread AirPlayServer start, auto delete: false
18:46:03 T:140656244664064 DEBUG: Thread TCPServer start, auto delete: false
18:46:03 T:140656110446336 DEBUG: Thread JobWorker start, auto delete: true
18:46:03 T:140658126428224 DEBUG: ------ Window Init () ------
18:46:03 T:140656697911040 DEBUG: RunQuery took 541 ms for 25 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 25
18:46:03 T:140657652434688 DEBUG: SECTION:LoadDLL(libnfs.so.1)
18:46:03 T:140657652434688 DEBUG: Loading: libnfs.so.1
18:46:03 T:140657652434688 DEBUG: NFS: Context for 192.168.178.8/XBMC not open - get a new context.
18:46:03 T:140657652434688 DEBUG: NFS: Connected to server 192.168.178.8 and export /XBMC
18:46:03 T:140657652434688 DEBUG: NFS: chunks: r/w 32768/32768
18:46:03 T:140657652434688 DEBUG: CNFSFile::Open - opened XBMC/Filme/The Hateful 8 (2015)/poster.jpg
18:46:03 T:140657652434688 DEBUG: CNFSFile::Close closing file XBMC/Filme/The Hateful 8 (2015)/poster.jpg
18:46:03 T:140657652434688 DEBUG: Caching image 'nfs://192.168.178.8/XBMC/Filme/The Hateful 8 (2015)/poster.jpg' to '9/9d9722a9.jpg':
18:46:03 T:140656539416320 DEBUG: RunQuery took 325 ms for 792 items query: select * from movie_view
18:46:03 T:140656934045440 DEBUG: CPythonInvoker(0, /var/lib/vdr/.kodi/addons/script.cu.lrclyrics/default.py): setting the Python path to /var/lib/vdr/.kodi/addons/script.cu.lrclyrics:/var/lib/vdr/.kodi/addons/script.module.chardet/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:46:03 T:140656934045440 DEBUG: CPythonInvoker(0, /var/lib/vdr/.kodi/addons/script.cu.lrclyrics/default.py): entering source directory /var/lib/vdr/.kodi/addons/script.cu.lrclyrics
18:46:03 T:140656934045440 DEBUG: CPythonInvoker(0, /var/lib/vdr/.kodi/addons/script.cu.lrclyrics/default.py): instantiating addon using automatically obtained id of "script.cu.lrclyrics" dependent on version 2.25.0 of the xbmc.python api
18:46:03 T:140657652434688 DEBUG: cached image 'special://masterprofile/Thumbnails/9/9d9722a9.jpg' size 480x720
18:46:03 T:140656110446336 DEBUG: RunQuery took 191 ms for 18 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00
18:46:03 T:140656707090176 NOTICE: Skin Helper Service --> skin helper service version 1.0.100 started
18:46:03 T:140656707090176 NOTICE: Skin Helper Service --> WebService - start helper webservice on port 52307
18:46:03 T:140656715482880 DEBUG: -->Python Interpreter Initialized<--
18:46:03 T:140656715482880 DEBUG: CPythonInvoker(2, /var/lib/vdr/.kodi/addons/service.library.data.provider/service.py): the source file to load is "/var/lib/vdr/.kodi/addons/service.library.data.provider/service.py"
18:46:03 T:140656070240000 ERROR: Remove - Error removing /var/lib/vdr/.kodi/userdata/addon_data/script.skin.helper.service/musicart/
18:46:03 T:140656070240000 ERROR: Remove - Error removing special://profile/addon_data/script.skin.helper.service/musicart/
18:46:03 T:140656539416320 DEBUG: GetRecentlyPlayedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM albumview WHERE albumview.lastplayed IS NOT NULL ORDER BY albumview.lastplayed DESC LIMIT 25) as playedalbums JOIN albumview ON albumview.idAlbum = playedalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.lastplayed DESC, albumartistview.iorder
18:46:03 T:140658126428224 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
18:46:03 T:140656715482880 DEBUG: CPythonInvoker(2, /var/lib/vdr/.kodi/addons/service.library.data.provider/service.py): setting the Python path to /var/lib/vdr/.kodi/addons/service.library.data.provider:/var/lib/vdr/.kodi/addons/script.module.simplejson/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:46:03 T:140656715482880 DEBUG: CPythonInvoker(2, /var/lib/vdr/.kodi/addons/service.library.data.provider/service.py): entering source directory /var/lib/vdr/.kodi/addons/service.library.data.provider
18:46:03 T:140656715482880 DEBUG: CPythonInvoker(2, /var/lib/vdr/.kodi/addons/service.library.data.provider/service.py): instantiating addon using automatically obtained id of "service.library.data.provider" dependent on version 2.25.0 of the xbmc.python api
18:46:03 T:140656531023616 DEBUG: -->Python Interpreter Initialized<--
18:46:03 T:140656531023616 DEBUG: CPythonInvoker(4, /var/lib/vdr/.kodi/addons/script.common.plugin.cache/default.py): the source file to load is "/var/lib/vdr/.kodi/addons/script.common.plugin.cache/default.py"
18:46:03 T:140656531023616 DEBUG: CPythonInvoker(4, /var/lib/vdr/.kodi/addons/script.common.plugin.cache/default.py): setting the Python path to /var/lib/vdr/.kodi/addons/script.common.plugin.cache:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7
18:46:03 T:140656531023616 DEBUG: CPythonInvoker(4, /var/lib/vdr/.kodi/addons/script.common.plugin.cache/default.py): entering source directory /var/lib/vdr/.kodi/addons/script.common.plugin.cache
18:46:03 T:140656715482880 DEBUG: Library Data Provider: service version 0.1.6 started
18:46:03 T:140656531023616 DEBUG: CPythonInvoker(4, /var/lib/vdr/.kodi/addons/script.common.plugin.cache/default.py): instantiating addon using automatically obtained id of "script.common.plugin.cache" dependent on version 2.24.0 of the xbmc.python api
18:46:03 T:140656110446336 DEBUG: RunQuery took 186 ms for 36 items query: SELECT * FROM tvshow_view WHERE ((CAST(tvshow_view.watchedcount as DECIMAL(5,1)) = 0)) AND ((CAST(tvshow_view.totalCount as DECIMAL(5,1)) > 0))
18:46:03 T:140656110446336 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 25) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
18:46:03 T:140656531023616 DEBUG: StorageServer Module loaded RUN
18:46:03 T:140656531023616 DEBUG: StorageClient-2.5.4 Starting server
18:46:04 T:140657652434688 DEBUG: RunQuery took 429 ms for 25 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 25
18:46:04 T:140657652434688 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strReleaseType = 'album'
18:46:04 T:140656715482880 DEBUG: RunQuery took 308 ms for 718 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
18:46:04 T:140656715482880 DEBUG: RunQuery took 409 ms for 1919 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
18:46:05 T:140658126428224 DEBUG: LIRC: Update - NEW at 3768:6c 0 KEY_DOWN devinput (KEY_DOWN)
18:46:05 T:140658126428224 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
18:46:05 T:140656715482880 DEBUG: GetSongsFullByWhere query = SELECT songview.*, songartistview.* FROM songview JOIN songartistview ON songartistview.idsong = songview.idsong WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
18:46:06 T:140656697911040 DEBUG: NFS: Context for 192.168.178.8/USBDisk1 not open - get a new context.
18:46:06 T:140656697911040 DEBUG: NFS: Connected to server 192.168.178.8 and export /USBDisk1
18:46:06 T:140656697911040 DEBUG: NFS: chunks: r/w 32768/32768
18:46:07 T:140657652434688 DEBUG: GetAlbumsByWhere - query took 3835 ms
18:46:07 T:140656723875584 DEBUG: Version Check: Version installed {u'major': 17, u'tag': u'beta', u'tagversion': u'1', u'minor': 0, u'revision': u'81d5d26'}
18:46:07 T:140656723875584 INFO: CPythonInvoker(1, /var/lib/vdr/.kodi/addons/service.xbmc.versioncheck/service.py): script successfully run
18:46:07 T:140656723875584 INFO: Python script stopped
18:46:07 T:140656723875584 DEBUG: Thread LanguageInvoker 140656723875584 terminating
18:46:08 T:140658126428224 DEBUG: LIRC: Update - NEW at 6308:6a 0 KEY_RIGHT devinput (KEY_RIGHT)
18:46:08 T:140658126428224 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
18:46:08 T:140656110446336 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist )) and artistview.strArtist != ''
18:46:08 T:140658126428224 DEBUG: LIRC: Update - NEW at 6570:6a 0 KEY_RIGHT devinput (KEY_RIGHT)
18:46:08 T:140658126428224 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
18:46:08 T:140656723875584 DEBUG: CFavourites::Load - no system favourites found, skipping
18:46:09 T:140658126428224 DEBUG: LIRC: Update - NEW at 7330:6a 0 KEY_RIGHT devinput (KEY_RIGHT)
18:46:09 T:140658126428224 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
18:46:09 T:140656723875584 DEBUG: RunQuery took 412 ms for 324 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet
18:46:09 T:140656723875584 DEBUG: RunQuery took 33 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=140 ORDER BY sets.idSet
18:46:09 T:140656723875584 DEBUG: RunQuery took 15 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 140
18:46:09 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=141 ORDER BY sets.idSet
18:46:09 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 141
18:46:09 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=142 ORDER BY sets.idSet
18:46:09 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 142
18:46:09 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=143 ORDER BY sets.idSet
18:46:09 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 143
18:46:09 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=144 ORDER BY sets.idSet
18:46:09 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 144
18:46:09 T:140656723875584 DEBUG: RunQuery took 27 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=145 ORDER BY sets.idSet
18:46:09 T:140656723875584 DEBUG: RunQuery took 19 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 145
18:46:09 T:140656723875584 DEBUG: RunQuery took 25 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=146 ORDER BY sets.idSet
18:46:09 T:140658126428224 DEBUG: LIRC: Update - NEW at 8122:160 0 KEY_OK devinput (KEY_OK)
18:46:09 T:140658126428224 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
18:46:09 T:140658126428224 DEBUG: Activating window ID: 10025
18:46:09 T:140656723875584 DEBUG: RunQuery took 14 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 146
18:46:10 T:140656715482880 DEBUG: GetSongsFullByWhere() - took 4686 ms
18:46:10 T:140656723875584 DEBUG: RunQuery took 29 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=147 ORDER BY sets.idSet
18:46:10 T:140656723875584 DEBUG: RunQuery took 24 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 147
18:46:10 T:140658126428224 DEBUG: ------ Window Deinit (Home.xml) ------
18:46:10 T:140658126428224 DEBUG: ------ Window Init (MyVideoNav.xml) ------
18:46:10 T:140658126428224 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
18:46:10 T:140658126428224 DEBUG: CDirectoryProvider[]: refreshing..
18:46:10 T:140658126428224 DEBUG: Previous line repeats 1 times.
18:46:10 T:140658126428224 DEBUG: CGUIMediaWindow::GetDirectory (videodb://inprogresstvshows/135/)
18:46:10 T:140658126428224 DEBUG: ParentPath = [videodb://inprogresstvshows/135/]
18:46:10 T:140656110446336 DEBUG: Time to retrieve artists from dataset = 2536
18:46:11 T:140658126428224 DEBUG: RunQuery took 661 ms for 1 items query: SELECT * FROM season_view WHERE season_view.idShow = 135
18:46:11 T:140658126428224 DEBUG: RunQuery took 21 ms for 0 items query: select * from movie_view join movielinktvshow on movielinktvshow.idMovie=movie_view.idMovie WHERE movielinktvshow.idShow = 135
18:46:11 T:140658126428224 DEBUG: CGUIMediaWindow::GetDirectory (videodb://inprogresstvshows/135/-2/?tvshowid=135)
18:46:11 T:140658126428224 DEBUG: ParentPath = [videodb://inprogresstvshows/135/]
18:46:11 T:140658126428224 DEBUG: RunQuery took 7 ms for 16 items query: select * from episode_view WHERE episode_view.idShow = 135
18:46:11 T:140658126428224 DEBUG: RunQuery took 2 ms for 0 items query: select * from movie_view join movielinktvshow on movielinktvshow.idMovie=movie_view.idMovie WHERE movielinktvshow.idShow = 135
18:46:11 T:140658126428224 DEBUG: WindowVideoNav::GetDirectory
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (yavdr) port:8080
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::addService() named: Kodi (yavdr) type: _http._tcp port:8080
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (yavdr) port:8080
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::addService() named: Kodi (yavdr) type: _xbmc-jsonrpc-h._tcp port:8080
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (yavdr) port:9090
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::addService() named: Kodi (yavdr) type: _xbmc-jsonrpc._tcp port:9090
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (yavdr) port:9777
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::addService() named: Kodi (yavdr) type: _xbmc-events._udp port:9777
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airtunes type: _raop._tcp name:00224DA9DEC6@Kodi (yavdr) port:36666
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::addService() named: 00224DA9DEC6@Kodi (yavdr) type: _raop._tcp port:36666
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airplay type: _airplay._tcp name:Kodi (yavdr) port:36667
18:46:11 T:140656539416320 DEBUG: CZeroconfAvahi::addService() named: Kodi (yavdr) type: _airplay._tcp port:36667
18:46:11 T:140656028276480 DEBUG: Thread BackgroundLoader start, auto delete: false
18:46:11 T:140656539416320 ERROR: GetDirectory - Error getting
18:46:11 T:140656110446336 ERROR: Previous line repeats 1 times.
18:46:11 T:140656110446336 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
18:46:11 T:140657652434688 DEBUG: GetImageHash - unable to stat url
18:46:11 T:140656110446336 DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum FROM album ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY songview.idAlbum desc, songview.itrack, songartistview.iOrder
18:46:11 T:140656723875584 DEBUG: RunQuery took 24 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=148 ORDER BY sets.idSet
18:46:11 T:140656723875584 DEBUG: RunQuery took 19 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 148
18:46:11 T:140656028276480 DEBUG: Thread BackgroundLoader 140656028276480 terminating
18:46:11 T:140656723875584 DEBUG: RunQuery took 22 ms for 8 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=149 ORDER BY sets.idSet
18:46:11 T:140656110446336 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
18:46:11 T:140656723875584 DEBUG: RunQuery took 17 ms for 8 items query: select * from movie_view WHERE movie_view.idSet = 149
18:46:11 T:140656723875584 DEBUG: RunQuery took 24 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=150 ORDER BY sets.idSet
18:46:11 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 150
18:46:11 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=151 ORDER BY sets.idSet
18:46:11 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 151
18:46:11 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=152 ORDER BY sets.idSet
18:46:11 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 152
18:46:11 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=153 ORDER BY sets.idSet
18:46:11 T:140656723875584 DEBUG: RunQuery took 21 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 153
18:46:11 T:140656697911040 ERROR: NFS: Failed to stat(USBDisk1/XBMC/Serien/How to get Away with Murder/landscape.jpg) stat call failed with "NFS: Lookup of //XBMC failed with NFS3ERR_NOENT(-2)"
18:46:11 T:140656697911040 DEBUG: GetImageHash - unable to stat url nfs://192.168.178.8/USBDisk1/XBMC/Serien/How to get Away with Murder/landscape.jpg
18:46:11 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/XBMC, old: 1631, new: 10013
18:46:11 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:11 T:140656723875584 DEBUG: RunQuery took 28 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=154 ORDER BY sets.idSet
18:46:11 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/USBDisk1, old: 4247, new: 10098
18:46:11 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:11 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/XBMC, old: 10013, new: 10122
18:46:11 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:11 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/USBDisk1, old: 10098, new: 10133
18:46:11 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:11 T:140656697911040 ERROR: NFS: Failed to stat(USBDisk1/XBMC/Serien/Suits (2011)/landscape.jpg) stat call failed with "NFS: Lookup of //XBMC failed with NFS3ERR_NOENT(-2)"
18:46:11 T:140656697911040 DEBUG: GetImageHash - unable to stat url nfs://192.168.178.8/USBDisk1/XBMC/Serien/Suits (2011)/landscape.jpg
18:46:11 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/XBMC, old: 10122, new: 10135
18:46:11 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:12 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/USBDisk1, old: 10133, new: 10179
18:46:12 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:12 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/XBMC, old: 10135, new: 10207
18:46:12 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:12 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/USBDisk1, old: 10179, new: 10219
18:46:12 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:12 T:140656697911040 DEBUG: NFS: Refreshing context for 192.168.178.8/XBMC, old: 10207, new: 10253
18:46:12 T:140656697911040 DEBUG: NFS: Using cached context.
18:46:12 T:140656723875584 DEBUG: RunQuery took 23 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 154
18:46:12 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=155 ORDER BY sets.idSet
18:46:12 T:140656514238208 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
18:46:12 T:140656723875584 DEBUG: Previous line repeats 5 times.
18:46:12 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 155
18:46:12 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=156 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 21 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 156
18:46:12 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=157 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 157
18:46:12 T:140658126428224 DEBUG: LIRC: Update - NEW at 10590:6c 0 KEY_DOWN devinput (KEY_DOWN)
18:46:12 T:140658126428224 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
18:46:12 T:140657652434688 DEBUG: GetImageHash - unable to stat url
18:46:12 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=158 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 158
18:46:12 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=159 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 159
18:46:12 T:140656723875584 DEBUG: RunQuery took 25 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=160 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 160
18:46:12 T:140658126428224 DEBUG: LIRC: Update - NEW at 10830:6c 0 KEY_DOWN devinput (KEY_DOWN)
18:46:12 T:140658126428224 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
18:46:12 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=161 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 161
18:46:12 T:140656723875584 DEBUG: RunQuery took 26 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=162 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 162
18:46:12 T:140656723875584 DEBUG: RunQuery took 25 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=163 ORDER BY sets.idSet
18:46:12 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 163
18:46:12 T:140658126428224 DEBUG: LIRC: Update - NEW at 11110:6c 0 KEY_DOWN devinput (KEY_DOWN)
18:46:12 T:140658126428224 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
18:46:12 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=164 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 164
18:46:13 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=165 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 24 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 165
18:46:13 T:140656723875584 DEBUG: RunQuery took 24 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=166 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 166
18:46:13 T:140656723875584 DEBUG: RunQuery took 26 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=167 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 167
18:46:13 T:140656723875584 DEBUG: RunQuery took 28 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=168 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 23 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 168
18:46:13 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=169 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 169
18:46:13 T:140656723875584 DEBUG: RunQuery took 27 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=170 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 170
18:46:13 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=171 ORDER BY sets.idSet
18:46:13 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 171
18:46:13 T:140658126428224 DEBUG: LIRC: Update - NEW at 11844:160 0 KEY_OK devinput (KEY_OK)
18:46:13 T:140658126428224 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
18:46:13 T:140656723875584 DEBUG: RunQuery took 27 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=172 ORDER BY sets.idSet
18:46:13 T:140658126428224 DEBUG: OnPlayMedia nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv
18:46:13 T:140657671345920 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
18:46:13 T:140657671345920 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
18:46:13 T:140656723875584 DEBUG: RunQuery took 19 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 172
18:46:13 T:140658126428224 DEBUG: Loading settings for nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv
18:46:13 T:140658126428224 DEBUG: CPlayerCoreFactory::GetPlayers(nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv)
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:46:13 T:140658126428224 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
18:46:13 T:140658126428224 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:46:13 T:140658126428224 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
18:46:13 T:140658126428224 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
18:46:13 T:140658126428224 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:46:13 T:140658126428224 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
18:46:13 T:140658126428224 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
18:46:13 T:140658126428224 NOTICE: VideoPlayer: Opening: nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv
18:46:13 T:140658126428224 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:46:13 T:140658126428224 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:46:13 T:140658126428224 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
18:46:13 T:140656028276480 DEBUG: Thread VideoPlayer start, auto delete: false
18:46:13 T:140656028276480 NOTICE: Creating InputStream
18:46:13 T:140656028276480 DEBUG: CFileCache::Open - opening using cache
18:46:13 T:140656028276480 DEBUG: CNFSFile::Open - opened XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv
18:46:13 T:140656080799488 DEBUG: Thread FileCache start, auto delete: false
18:46:13 T:140656028276480 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
18:46:13 T:140656028276480 DEBUG: ScanForExternalSubtitles: END (total time: 60 ms)
18:46:13 T:140656028276480 NOTICE: Creating Demuxer
18:46:13 T:140656028276480 DEBUG: Open - probing detected format [matroska,webm]
18:46:13 T:140656028276480 DEBUG: Open - avformat_find_stream_info starting
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: [h264] Increasing reorder buffer to 1
18:46:13 T:140656028276480 DEBUG: Open - av_find_stream_info finished
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: Input #0, matroska,webm, nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mk':
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: Metadata:
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: title : Suits.S05E03.Weggefaehrten.GERMAN.DUBBED.DL.720p.WebHD.x264-TVP
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: encoder : libebml v1.2.2 + libmatroska v1.3.0
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: creation_time : 2016-04-12 20:29:02
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: Duration: 00:40:23.60, start: 0.000000, bitrate: 4795 kb/s
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: Stream #0:0(ger): Video: h264 (High), yuv420p(tv, bt709), 1280x720 [SAR 1:1 DAR 16:9], 25 fps, 25 tbr, 1k tbn, 2k tbc (default) (forced)
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: Metadata:
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: title : tvp-suits-s05e03-720p
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: Stream #0:1(ger): Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s (default) (forced)
18:46:13 T:140656028276480 INFO: ffmpeg[7FED0899D700]: Stream #0:2(eng): Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s
18:46:13 T:140656028276480 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
18:46:13 T:140656028276480 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
18:46:13 T:140656028276480 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 2
18:46:13 T:140656028276480 DEBUG: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 659446 bytes per second
18:46:13 T:140656028276480 NOTICE: Opening stream: 0 source: 256
18:46:13 T:140656028276480 NOTICE: Creating video codec with codec id: 28
18:46:13 T:140656028276480 DEBUG: FactoryCodec - Video: - Opening
18:46:13 T:140656028276480 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
18:46:13 T:140656028276480 DEBUG: CDVDVideoCodecFFmpeg - open frame threaded with 3 threads
18:46:13 T:140656028276480 DEBUG: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
18:46:13 T:140656028276480 DEBUG: FactoryCodec - Video: ff-h264 - Opened
18:46:13 T:140656028276480 DEBUG: CVideoPlayerVideo::OpenStream - open stream with codec id: 28
18:46:13 T:140656028276480 NOTICE: Creating video thread
18:46:13 T:140656028276480 NOTICE: Opening stream: 1 source: 256
18:46:13 T:140656120153856 DEBUG: Thread VideoPlayerVideo start, auto delete: false
18:46:13 T:140656028276480 NOTICE: Finding audio codec for: 86019
18:46:13 T:140656120153856 NOTICE: running thread: video_thread
18:46:13 T:140656028276480 DEBUG: FactoryCodec - Audio: passthrough - Opening
18:46:13 T:140656028276480 DEBUG: FactoryCodec - Audio: passthrough - Opened
18:46:13 T:140656028276480 NOTICE: Creating audio thread
18:46:13 T:140656102053632 DEBUG: Thread VideoPlayerAudio start, auto delete: false
18:46:13 T:140656102053632 NOTICE: running thread: CVideoPlayerAudio::Process()
18:46:13 T:140656028276480 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv
18:46:13 T:140656028276480 DEBUG: OnPlayBackStarted: play state was 1, starting 1
18:46:13 T:140656028276480 DEBUG: CVideoPlayer::SetCaching - caching state 1
18:46:13 T:140658126428224 DEBUG: PlayFile: OpenFile succeed, play state 2
18:46:13 T:140658126428224 DEBUG: OnPlayBackStarted: play state was 2, starting 0
18:46:13 T:140656028276480 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
18:46:13 T:140656028276480 DEBUG: CVideoPlayer::SetCaching - caching state 2
18:46:13 T:140656028276480 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
18:46:13 T:140656120153856 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
18:46:13 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
18:46:13 T:140656120153856 DEBUG: Previous line repeats 4 times.
18:46:13 T:140656120153856 DEBUG: CRenderManager::Configure - change configuration. 1280x720. display: 1280x720. framerate: 25.00. format: YV12
18:46:13 T:140656102053632 DEBUG: CDVDAudio::Pause - pausing audio stream
18:46:13 T:140656102053632 DEBUG: Previous line repeats 1 times.
18:46:13 T:140656102053632 INFO: CAEStreamParser::SyncAC3 - AC3 stream detected (2 channels, 48000Hz)
18:46:13 T:140656102053632 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)
18:46:13 T:140656697911040 DEBUG: CGUIInfoManager::SetCurrentMovie(nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv)
18:46:13 T:140657671345920 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:46:13 T:140657671345920 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:46:13 T:140658126428224 DEBUG: DeleteRenderer - deleting renderer
18:46:13 T:140658126428224 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:46:13 T:140656102053632 DEBUG: CVideoPlayerAudio:: synctype set to 0: clock feedback
18:46:13 T:140658126428224 DEBUG: LinuxRendererGL: Cleaning up GL resources
18:46:13 T:140658126428224 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
18:46:13 T:140658126428224 DEBUG: GL: Requested render method: 1
18:46:13 T:140658126428224 NOTICE: GL: ARB shaders support detected
18:46:13 T:140658126428224 DEBUG: GL: YUV2RGBProgressiveShaderARB: loading yuv2rgb_basic_2d.arb
18:46:13 T:140658126428224 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader
18:46:13 T:140658126428224 NOTICE: GL: No vertex shader, fixed pipeline in use
18:46:13 T:140658126428224 DEBUG: GL: Vertex Shader compilation log:
18:46:13 T:140658126428224 DEBUG: GL: Vertex Shader compiled successfully
18:46:13 T:140658126428224 DEBUG: GL: Pixel Shader compilation log:
18:46:13 T:140658126428224 DEBUG: GL: Fragment Shader compiled successfully
18:46:13 T:140658126428224 NOTICE: GL: NPOT texture support detected
18:46:13 T:140658126428224 NOTICE: GL: Using GL_ARB_pixel_buffer_object
18:46:13 T:140658126428224 NOTICE: Using GL_TEXTURE_2D
18:46:13 T:140656697911040 DEBUG: GetMovieId (nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv), query = select idMovie from movie where idFile=9145
18:46:13 T:140657494583040 INFO: CActiveAESink::OpenSink - initialize sink
18:46:13 T:140658126428224 DEBUG: CRenderManager::Configure - 4
18:46:13 T:140656697911040 DEBUG: GetEpisodeId (nfs://192.168.178.8/XBMC/Serien/Suits (2011)/Season 05/S05E03 - Weggefährten.mkv), query = select idEpisode from episode where idFile=9145
18:46:13 T:140656028276480 DEBUG: CVideoPlayer::HandleMessages - player started 2
18:46:13 T:140658126428224 DEBUG: GL: Requested render method: 1
18:46:13 T:140658126428224 NOTICE: GL: ARB shaders support detected
18:46:13 T:140658126428224 DEBUG: GL: YUV2RGBProgressiveShaderARB: loading yuv2rgb_basic_2d.arb
18:46:13 T:140658126428224 NOTICE: GL: Selecting Single Pass ARB YUV2RGB shader
18:46:13 T:140658126428224 NOTICE: GL: No vertex shader, fixed pipeline in use
18:46:13 T:140658126428224 NOTICE: GL: NPOT texture support detected
18:46:13 T:140658126428224 NOTICE: GL: Using GL_ARB_pixel_buffer_object
18:46:13 T:140656723875584 DEBUG: RunQuery took 33 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=173 ORDER BY sets.idSet
18:46:13 T:140658126428224 DEBUG: Activating window ID: 12005
18:46:13 T:140658126428224 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
18:46:13 T:140658126428224 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
18:46:13 T:140658126428224 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
18:46:13 T:140656723875584 DEBUG: RunQuery took 27 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 173
18:46:14 T:140657494583040 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=1
18:46:14 T:140657494583040 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=1"
18:46:14 T:140657494583040 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=1,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02"
18:46:14 T:140657494583040 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
18:46:14 T:140657494583040 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
18:46:14 T:140657494583040 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
18:46:14 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=174 ORDER BY sets.idSet
18:46:14 T:140657494583040 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
18:46:14 T:140657494583040 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW
18:46:14 T:140657494583040 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none)
18:46:14 T:140657494583040 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
18:46:14 T:140657494583040 DEBUG: Output Device : HDA NVidia
18:46:14 T:140657494583040 DEBUG: Sample Rate : 48000
18:46:14 T:140657494583040 DEBUG: Sample Format : AE_FMT_S16NE
18:46:14 T:140657494583040 DEBUG: Channel Count : 2
18:46:14 T:140657494583040 DEBUG: Channel Layout: RAW,RAW
18:46:14 T:140657494583040 DEBUG: Frames : 2400
18:46:14 T:140657494583040 DEBUG: Frame Size : 4
18:46:14 T:140657639593728 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:46:14 T:140656723875584 DEBUG: Previous line repeats 1 times.
18:46:14 T:140656723875584 DEBUG: RunQuery took 16 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 174
18:46:14 T:140656028276480 DEBUG: CVideoPlayer::HandleMessages - player started 1
18:46:14 T:140656028276480 DEBUG: CVideoPlayer::SetCaching - caching state 3
18:46:14 T:140656028276480 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
18:46:14 T:140656028276480 DEBUG: CVideoPlayer::SetCaching - caching state 0
18:46:14 T:140656028276480 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
18:46:14 T:140656028276480 DEBUG: VideoPlayer::Sync - Audio - pts: 96000.000000, cache: 317698.343080, totalcache: 600000.023842
18:46:14 T:140656028276480 DEBUG: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
18:46:14 T:140656102053632 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-221698.343080)
18:46:14 T:140656120153856 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(-221698.343080)
18:46:14 T:140656102053632 DEBUG: CDVDAudio::Resume - resume audio stream
18:46:14 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
18:46:14 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
18:46:14 T:140657639593728 DEBUG: ActiveAE - start sync of audio stream
18:46:14 T:140656715482880 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
18:46:14 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=175 ORDER BY sets.idSet
18:46:14 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 175
18:46:14 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=176 ORDER BY sets.idSet
18:46:14 T:140657639593728 DEBUG: ActiveAE::SyncStream - average error of 31.232352, start adjusting
18:46:14 T:140657639593728 DEBUG: ActiveAE::SyncStream - average error 0.232352 below threshold of 30.000000
18:46:14 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 176
18:46:14 T:140656723875584 DEBUG: RunQuery took 28 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=177 ORDER BY sets.idSet
18:46:14 T:140656723875584 DEBUG: RunQuery took 15 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 177
18:46:14 T:140656723875584 DEBUG: RunQuery took 28 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=178 ORDER BY sets.idSet
18:46:14 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 178
18:46:14 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=179 ORDER BY sets.idSet
18:46:14 T:140656120153856 NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000
18:46:14 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 179
18:46:14 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=180 ORDER BY sets.idSet
18:46:14 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 180
18:46:15 T:140656110446336 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
18:46:15 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=181 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 181
18:46:15 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=182 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 182
18:46:15 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=183 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 15 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 183
18:46:15 T:140656723875584 DEBUG: RunQuery took 27 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=184 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 27 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 184
18:46:15 T:140656723875584 DEBUG: RunQuery took 25 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=185 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 27 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 185
18:46:15 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=186 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 17 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 186
18:46:15 T:140656110446336 DEBUG: RunQuery took 491 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10
18:46:15 T:140656723875584 DEBUG: RunQuery took 24 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=187 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 187
18:46:15 T:140656102053632 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-21257.003533, adjusted:-21257.003533
18:46:15 T:140656723875584 DEBUG: RunQuery took 28 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=188 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 21 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 188
18:46:15 T:140656723875584 DEBUG: RunQuery took 25 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=189 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 23 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 189
18:46:15 T:140656723875584 DEBUG: RunQuery took 26 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=190 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 24 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 190
18:46:15 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=191 ORDER BY sets.idSet
18:46:15 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 191
18:46:15 T:140656110446336 DEBUG: RunQuery took 416 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10
18:46:16 T:140656723875584 DEBUG: RunQuery took 26 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=192 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 25 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 192
18:46:16 T:140656110446336 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10
18:46:16 T:140656110446336 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
18:46:16 T:140656110446336 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist )) and artistview.strArtist != ''
18:46:16 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=193 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 193
18:46:16 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=194 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 194
18:46:16 T:140656723875584 DEBUG: RunQuery took 28 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=195 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 24 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 195
18:46:16 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=196 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 25 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 196
18:46:16 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=197 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 197
18:46:16 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=198 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 198
18:46:16 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=199 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 199
18:46:16 T:140656723875584 DEBUG: RunQuery took 27 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=200 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 200
18:46:16 T:140656723875584 DEBUG: RunQuery took 34 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=201 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 201
18:46:16 T:140656723875584 DEBUG: RunQuery took 33 ms for 7 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=202 ORDER BY sets.idSet
18:46:16 T:140656723875584 DEBUG: RunQuery took 24 ms for 7 items query: select * from movie_view WHERE movie_view.idSet = 202
18:46:17 T:140656723875584 DEBUG: RunQuery took 27 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=203 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 203
18:46:17 T:140656723875584 DEBUG: RunQuery took 25 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=204 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 23 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 204
18:46:17 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=205 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 205
18:46:17 T:140656723875584 DEBUG: RunQuery took 26 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=206 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 23 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 206
18:46:17 T:140656723875584 DEBUG: RunQuery took 30 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=207 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 207
18:46:17 T:140656723875584 DEBUG: RunQuery took 30 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=208 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 25 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 208
18:46:17 T:140656723875584 DEBUG: RunQuery took 28 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=209 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 15 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 209
18:46:17 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=210 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 210
18:46:17 T:140656723875584 DEBUG: RunQuery took 29 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=211 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 24 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 211
18:46:17 T:140656723875584 DEBUG: RunQuery took 28 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=212 ORDER BY sets.idSet
18:46:17 T:140656723875584 DEBUG: RunQuery took 23 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 212
18:46:18 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=213 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 213
18:46:18 T:140656723875584 DEBUG: RunQuery took 25 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=214 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 16 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 214
18:46:18 T:140656723875584 DEBUG: RunQuery took 28 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=215 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 23 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 215
18:46:18 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=216 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 216
18:46:18 T:140656723875584 DEBUG: RunQuery took 33 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=217 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 17 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 217
18:46:18 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=218 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 218
18:46:18 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=219 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 23 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 219
18:46:18 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=220 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 21 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 220
18:46:18 T:140656723875584 DEBUG: RunQuery took 27 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=221 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 221
18:46:18 T:140656723875584 DEBUG: RunQuery took 32 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=222 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 222
18:46:18 T:140656723875584 DEBUG: RunQuery took 19 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=223 ORDER BY sets.idSet
18:46:18 T:140656723875584 DEBUG: RunQuery took 14 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 223
18:46:19 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=224 ORDER BY sets.idSet
18:46:19 T:140656715482880 DEBUG: GetAlbumsByWhere - query took 4571 ms
18:46:19 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 224
18:46:19 T:140656723875584 DEBUG: RunQuery took 21 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=225 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 225
18:46:19 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=226 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 19 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 226
18:46:19 T:140656715482880 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1))
18:46:19 T:140656723875584 DEBUG: RunQuery took 30 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=227 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 25 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 227
18:46:19 T:140656120153856 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000
18:46:19 T:140656723875584 DEBUG: RunQuery took 31 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=228 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 28 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 228
18:46:19 T:140656723875584 DEBUG: RunQuery took 30 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=229 ORDER BY sets.idSet
18:46:19 T:140656715482880 DEBUG: RunQuery took 272 ms for 718 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
18:46:19 T:140656723875584 DEBUG: RunQuery took 15 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 229
18:46:19 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=230 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 17 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 230
18:46:19 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=231 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 231
18:46:19 T:140656723875584 DEBUG: RunQuery took 24 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=232 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 16 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 232
18:46:19 T:140656723875584 DEBUG: RunQuery took 26 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=233 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 21 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 233
18:46:19 T:140656723875584 DEBUG: RunQuery took 28 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=234 ORDER BY sets.idSet
18:46:19 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 234
18:46:20 T:140656723875584 DEBUG: RunQuery took 19 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=235 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 235
18:46:20 T:140656723875584 DEBUG: RunQuery took 27 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=236 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 236
18:46:20 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=237 ORDER BY sets.idSet
18:46:20 T:140656715482880 DEBUG: RunQuery took 385 ms for 1919 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
18:46:20 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 237
18:46:20 T:140656723875584 DEBUG: RunQuery took 27 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=238 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 19 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 238
18:46:20 T:140656723875584 DEBUG: RunQuery took 25 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=239 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 239
18:46:20 T:140656723875584 DEBUG: RunQuery took 27 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=240 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 24 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 240
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:20 T:140656723875584 DEBUG: Previous line repeats 1 times.
18:46:20 T:140656723875584 DEBUG: RunQuery took 20 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=241 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 17 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 241
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:20 T:140656723875584 DEBUG: RunQuery took 26 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=242 ORDER BY sets.idSet
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:20 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 242
18:46:20 T:140656723875584 DEBUG: RunQuery took 19 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=243 ORDER BY sets.idSet
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:20 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 243
18:46:20 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=244 ORDER BY sets.idSet
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:20 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 244
18:46:20 T:140656723875584 DEBUG: RunQuery took 26 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=245 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 245
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:20 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=246 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 246
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:20 T:140656723875584 DEBUG: RunQuery took 17 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=247 ORDER BY sets.idSet
18:46:20 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 247
18:46:20 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 12, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 20 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=248 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 248
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=249 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 13 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 249
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 19 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=250 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 14 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 250
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 19 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=251 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 17 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 251
18:46:21 T:140656723875584 DEBUG: RunQuery took 22 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=252 ORDER BY sets.idSet
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 21 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 252
18:46:21 T:140656723875584 DEBUG: RunQuery took 23 ms for 7 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=253 ORDER BY sets.idSet
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 15 ms for 7 items query: select * from movie_view WHERE movie_view.idSet = 253
18:46:21 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=254 ORDER BY sets.idSet
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 254
18:46:21 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=255 ORDER BY sets.idSet
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 21 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 255
18:46:21 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=256 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 256
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=257 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 14 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 257
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=258 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 16 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 258
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 13, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 19 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=259 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 16 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 259
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=260 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 20 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 260
18:46:21 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:21 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=261 ORDER BY sets.idSet
18:46:21 T:140656723875584 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 261
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 19 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=262 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 17 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 262
18:46:22 T:140656723875584 DEBUG: RunQuery took 20 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=263 ORDER BY sets.idSet
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 14 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 263
18:46:22 T:140656723875584 DEBUG: RunQuery took 21 ms for 7 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=264 ORDER BY sets.idSet
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 16 ms for 7 items query: select * from movie_view WHERE movie_view.idSet = 264
18:46:22 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=265 ORDER BY sets.idSet
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 15 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 265
18:46:22 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=266 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 266
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=267 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 267
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=268 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 16 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 268
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=269 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 269
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 14, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 21 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=270 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 15 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 270
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 22 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=271 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 17 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 271
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 19 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=272 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 272
18:46:22 T:140656723875584 DEBUG: RunQuery took 21 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=273 ORDER BY sets.idSet
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 15 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 273
18:46:22 T:140656723875584 DEBUG: RunQuery took 26 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=274 ORDER BY sets.idSet
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:22 T:140656723875584 DEBUG: RunQuery took 17 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 274
18:46:22 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=275 ORDER BY sets.idSet
18:46:22 T:140656723875584 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 275
18:46:22 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 19 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=276 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 276
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=277 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 23 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 277
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=278 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 278
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=279 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 15 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 279
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 26 ms for 7 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=280 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 15 ms for 7 items query: select * from movie_view WHERE movie_view.idSet = 280
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 15, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=281 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 281
18:46:23 T:140656715482880 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 18 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=282 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 15 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 282
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=283 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 283
18:46:23 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=284 ORDER BY sets.idSet
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 284
18:46:23 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=285 ORDER BY sets.idSet
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 16 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 285
18:46:23 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=286 ORDER BY sets.idSet
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 23 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 286
18:46:23 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=287 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 287
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=288 ORDER BY sets.idSet
18:46:23 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 288
18:46:23 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:23 T:140656723875584 DEBUG: RunQuery took 18 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=289 ORDER BY sets.idSet
18:46:24 T:140656723875584 DEBUG: RunQuery took 24 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 289
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 18 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=290 ORDER BY sets.idSet
18:46:24 T:140656723875584 DEBUG: RunQuery took 17 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 290
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 22 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=291 ORDER BY sets.idSet
18:46:24 T:140656723875584 DEBUG: RunQuery took 20 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 291
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 16, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 24 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=292 ORDER BY sets.idSet
18:46:24 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 292
18:46:24 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=293 ORDER BY sets.idSet
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 293
18:46:24 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=294 ORDER BY sets.idSet
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 294
18:46:24 T:140656723875584 DEBUG: RunQuery took 20 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=295 ORDER BY sets.idSet
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 295
18:46:24 T:140656723875584 DEBUG: RunQuery took 20 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=296 ORDER BY sets.idSet
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 16 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 296
18:46:24 T:140656723875584 DEBUG: RunQuery took 18 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=297 ORDER BY sets.idSet
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 14 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 297
18:46:24 T:140656723875584 DEBUG: RunQuery took 17 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=298 ORDER BY sets.idSet
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 16 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 298
18:46:24 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=299 ORDER BY sets.idSet
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 21 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 299
18:46:24 T:140656723875584 DEBUG: RunQuery took 17 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=300 ORDER BY sets.idSet
18:46:24 T:140656723875584 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 300
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:24 T:140656723875584 DEBUG: RunQuery took 22 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=301 ORDER BY sets.idSet
18:46:24 T:140656723875584 DEBUG: RunQuery took 15 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 301
18:46:24 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 17, Bufferlevel: 3, dropped: 1
18:46:25 T:140656120153856 DEBUG: Previous line repeats 1 times.
18:46:25 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 18, Bufferlevel: 3, dropped: 1
18:46:25 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:25 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 19, Bufferlevel: 3, dropped: 1
18:46:26 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:26 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 20, Bufferlevel: 3, dropped: 1
18:46:26 T:140656715482880 DEBUG: Previous line repeats 2 times.
18:46:26 T:140656715482880 DEBUG: GetAlbumsByWhere - query took 3402 ms
18:46:26 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 20, Bufferlevel: 3, dropped: 1
18:46:27 T:140656715482880 DEBUG: Previous line repeats 2 times.
18:46:27 T:140656715482880 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1))
18:46:27 T:140656715482880 DEBUG: RunQuery took 23 ms for 57 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
18:46:27 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 20, Bufferlevel: 3, dropped: 1
18:46:27 T:140656120153856 DEBUG: Previous line repeats 3 times.
18:46:27 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:27 T:140656715482880 DEBUG: Previous line repeats 1 times.
18:46:27 T:140656715482880 DEBUG: RunQuery took 149 ms for 20 items query: SELECT * FROM tvshow_view WHERE ((CASE WHEN COALESCE(tvshow_view.totalCount - tvshow_view.watchedcount, 0) > 0 THEN 0 ELSE 1 END < 1)) AND ( ((tvshow_view.watchedcount > 0 AND tvshow_view.watchedcount < tvshow_view.totalCount) OR (tvshow_view.watchedcount = 0 AND EXISTS (SELECT 1 FROM episode_view WHERE episode_view.idShow = tvshow_view.idShow AND episode_view.resumeTimeInSeconds > 0))))
18:46:27 T:140656715482880 DEBUG: RunQuery took 9 ms for 43 items query: select * from episode_view WHERE (episode_view.idShow = 106) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:27 T:140656715482880 DEBUG: RunQuery took 12 ms for 39 items query: select * from episode_view WHERE (episode_view.idShow = 80) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:27 T:140656715482880 DEBUG: RunQuery took 5 ms for 13 items query: select * from episode_view WHERE (episode_view.idShow = 135) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656715482880 DEBUG: RunQuery took 8 ms for 21 items query: select * from episode_view WHERE (episode_view.idShow = 90) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:27 T:140656715482880 DEBUG: RunQuery took 7 ms for 8 items query: select * from episode_view WHERE (episode_view.idShow = 76) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656715482880 DEBUG: RunQuery took 4 ms for 6 items query: select * from episode_view WHERE (episode_view.idShow = 87) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:27 T:140656715482880 DEBUG: RunQuery took 15 ms for 50 items query: select * from episode_view WHERE (episode_view.idShow = 64) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656715482880 DEBUG: RunQuery took 11 ms for 40 items query: select * from episode_view WHERE (episode_view.idShow = 56) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:27 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:27 T:140656715482880 DEBUG: RunQuery took 11 ms for 77 items query: select * from episode_view WHERE (episode_view.idShow = 100) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:27 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656715482880 DEBUG: RunQuery took 8 ms for 20 items query: select * from episode_view WHERE (episode_view.idShow = 136) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:28 T:140656715482880 DEBUG: RunQuery took 9 ms for 50 items query: select * from episode_view WHERE (episode_view.idShow = 144) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656715482880 DEBUG: RunQuery took 4 ms for 5 items query: select * from episode_view WHERE (episode_view.idShow = 95) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:28 T:140656715482880 DEBUG: RunQuery took 8 ms for 36 items query: select * from episode_view WHERE (episode_view.idShow = 132) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656715482880 DEBUG: RunQuery took 7 ms for 37 items query: select * from episode_view WHERE (episode_view.idShow = 111) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 21, Bufferlevel: 3, dropped: 1
18:46:28 T:140656715482880 DEBUG: RunQuery took 51 ms for 423 items query: select * from episode_view WHERE (episode_view.idShow = 105) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 22, Bufferlevel: 3, dropped: 1
18:46:28 T:140656715482880 DEBUG: RunQuery took 10 ms for 25 items query: select * from episode_view WHERE (episode_view.idShow = 67) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656715482880 DEBUG: RunQuery took 6 ms for 27 items query: select * from episode_view WHERE (episode_view.idShow = 117) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 22, Bufferlevel: 3, dropped: 1
18:46:28 T:140656715482880 DEBUG: RunQuery took 9 ms for 47 items query: select * from episode_view WHERE (episode_view.idShow = 140) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656715482880 DEBUG: RunQuery took 19 ms for 128 items query: select * from episode_view WHERE (episode_view.idShow = 61) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 22, Bufferlevel: 3, dropped: 1
18:46:28 T:140656715482880 DEBUG: Previous line repeats 1 times.
18:46:28 T:140656715482880 DEBUG: RunQuery took 17 ms for 108 items query: select * from episode_view WHERE (episode_view.idShow = 70) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
18:46:28 T:140656715482880 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
18:46:28 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 22, Bufferlevel: 3, dropped: 1
18:46:29 T:140656120153856 DEBUG: Previous line repeats 5 times.
18:46:29 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 23, Bufferlevel: 3, dropped: 1
18:46:29 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:29 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 24, Bufferlevel: 3, dropped: 1
18:46:30 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:30 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 25, Bufferlevel: 3, dropped: 1
18:46:31 T:140656715482880 DEBUG: Previous line repeats 4 times.
18:46:31 T:140656715482880 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
18:46:31 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 25, Bufferlevel: 3, dropped: 1
18:46:31 T:140656120153856 DEBUG: Previous line repeats 4 times.
18:46:31 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 26, Bufferlevel: 3, dropped: 1
18:46:32 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:32 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 27, Bufferlevel: 3, dropped: 1
18:46:33 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:33 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 28, Bufferlevel: 3, dropped: 1
18:46:33 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:33 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 29, Bufferlevel: 3, dropped: 1
18:46:34 T:140656715482880 DEBUG: Previous line repeats 4 times.
18:46:34 T:140656715482880 DEBUG: GetAlbumsByWhere - query took 3176 ms
18:46:34 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 29, Bufferlevel: 3, dropped: 1
18:46:34 T:140656715482880 DEBUG: Previous line repeats 1 times.
18:46:34 T:140656715482880 DEBUG: CFavourites::Load - no system favourites found, skipping
18:46:34 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 29, Bufferlevel: 3, dropped: 1
18:46:34 T:140656715482880 DEBUG: Previous line repeats 1 times.
18:46:34 T:140656715482880 DEBUG: RunQuery took 125 ms for 54 items query: SELECT * FROM tvshow_view WHERE ((CASE WHEN COALESCE(tvshow_view.totalCount - tvshow_view.watchedcount, 0) > 0 THEN 0 ELSE 1 END < 1))
18:46:34 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 29, Bufferlevel: 3, dropped: 1
18:46:34 T:140656715482880 DEBUG: Library Data Provider: Total time needed for all queries: 0:00:30.834991
18:46:34 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 30, Bufferlevel: 3, dropped: 1
18:46:35 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:35 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 31, Bufferlevel: 3, dropped: 1
18:46:36 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:36 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 32, Bufferlevel: 3, dropped: 1
18:46:37 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:37 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 33, Bufferlevel: 3, dropped: 1
18:46:37 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:37 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 34, Bufferlevel: 3, dropped: 1
18:46:38 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:38 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 35, Bufferlevel: 3, dropped: 1
18:46:39 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:39 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 36, Bufferlevel: 3, dropped: 1
18:46:40 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:40 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 37, Bufferlevel: 3, dropped: 1
18:46:41 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:41 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 38, Bufferlevel: 3, dropped: 1
18:46:41 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:41 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 39, Bufferlevel: 3, dropped: 1
18:46:42 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:42 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 40, Bufferlevel: 3, dropped: 1
18:46:43 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:43 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 41, Bufferlevel: 3, dropped: 1
18:46:43 T:140657652434688 DEBUG: Previous line repeats 5 times.
18:46:43 T:140657652434688 DEBUG: Thread JobWorker 140657652434688 terminating (autodelete)
18:46:43 T:140656539416320 DEBUG: Thread JobWorker 140656539416320 terminating (autodelete)
18:46:43 T:140656697911040 DEBUG: Thread JobWorker 140656697911040 terminating (autodelete)
18:46:43 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 41, Bufferlevel: 3, dropped: 1
18:46:44 T:140656120153856 DEBUG: Previous line repeats 3 times.
18:46:44 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 42, Bufferlevel: 3, dropped: 1
18:46:45 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:45 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 43, Bufferlevel: 3, dropped: 1
18:46:45 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:45 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 44, Bufferlevel: 3, dropped: 1
18:46:46 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:46 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 45, Bufferlevel: 3, dropped: 1
18:46:47 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:47 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 46, Bufferlevel: 3, dropped: 1
18:46:48 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:48 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 47, Bufferlevel: 3, dropped: 1
18:46:49 T:140656120153856 DEBUG: Previous line repeats 9 times.
18:46:49 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 48, Bufferlevel: 3, dropped: 1
18:46:49 T:140658126428224 DEBUG: Previous line repeats 5 times.
18:46:49 T:140658126428224 DEBUG: LIRC: Update - NEW at 47667:69 0 KEY_LEFT devinput (KEY_LEFT)
18:46:49 T:140658126428224 DEBUG: OnKey: 169 (0xa9, obc86) pressed, action is StepBack
18:46:49 T:140658126428224 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
18:46:49 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 48, Bufferlevel: 3, dropped: 1
18:46:49 T:140656120153856 DEBUG: Previous line repeats 3 times.
18:46:49 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - dropped in decoder, lateframes: 49, Bufferlevel: 3, dropped: 1
18:46:50 T:140656028276480 DEBUG: Previous line repeats 4 times.
18:46:50 T:140656028276480 DEBUG: CVideoPlayer::SetCaching - caching state 1
18:46:50 T:140656028276480 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
18:46:50 T:140656028276480 DEBUG: demuxer seek to: 25564
18:46:50 T:140656028276480 DEBUG: Seek - waiting for position 1452930176.
18:46:50 T:140656102053632 DEBUG: CDVDAudio::Pause - pausing audio stream
18:46:50 T:140656080799488 INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
18:46:50 T:140656028276480 DEBUG: SeekTime - seek ended up on time 25200
18:46:50 T:140656028276480 DEBUG: demuxer seek to: 25564, success
18:46:50 T:140656028276480 DEBUG: CVideoPlayer::FlushBuffers - flushing buffers
18:46:50 T:140656102053632 DEBUG: CDVDAudio::Flush - flush audio stream
18:46:50 T:140656102053632 DEBUG: CDVDAudio::Pause - pausing audio stream
18:46:50 T:140658126428224 DEBUG: CDVDMsgGeneralSynchronize - global timeout
18:46:50 T:140657671345920 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
18:46:50 T:140657671345920 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
18:46:50 T:140656120153856 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
18:46:50 T:140656102053632 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
18:46:50 T:140656028276480 DEBUG: CVideoPlayer - CDVDMsg::GENERAL_SYNCHRONIZE
18:46:50 T:140656120153856 INFO: CVideoPlayerVideo - Stillframe left, switching to normal playback
18:46:50 T:140656102053632 INFO: CAEStreamParser::SyncAC3 - AC3 stream detected (2 channels, 48000Hz)
18:46:50 T:140656028276480 DEBUG: CVideoPlayer::HandleMessages - player started 1
18:46:50 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
18:46:50 T:140656028276480 DEBUG: CVideoPlayer::HandleMessages - player started 2
18:46:50 T:140656028276480 DEBUG: VideoPlayer::Sync - Audio - pts: 25376000.000000, cache: 316754.783992, totalcache: 600000.023842
18:46:50 T:140656028276480 DEBUG: VideoPlayer::Sync - Video - pts: 25200000.000000, cache: 50000.000000, totalcache: 100000.000000
18:46:50 T:140656102053632 DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(25059245.216008)
18:46:50 T:140656120153856 DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_RESYNC(25059245.216008)
18:46:50 T:140656028276480 DEBUG: CVideoPlayer::SetCaching - caching state 0
18:46:50 T:140656102053632 DEBUG: CDVDAudio::Resume - resume audio stream
18:46:50 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
18:46:50 T:140657639593728 DEBUG: ActiveAE - start sync of audio stream
18:46:50 T:140656120153856 DEBUG: CVideoPlayerVideo::CalcDropRequirement - hurry: 1
18:46:50 T:140656028276480 DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
18:46:50 T:140656110446336 DEBUG: Thread JobWorker 140656110446336 terminating (autodelete)
18:46:50 T:140657639593728 DEBUG: ActiveAE::SyncStream - average error of 25.000777, start adjusting
18:46:50 T:140657639593728 DEBUG: ActiveAE::SyncStream - average error 0.000777 below threshold of 30.000000
18:46:51 T:140656102053632 DEBUG: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-22436.140449, adjusted:-22436.140449
18:46:53 T:140658126428224 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
18:46:55 T:140656120153856 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000
18:47:42 T:140658126428224 DEBUG: LIRC: Update - NEW at 100306:77 0 KEY_PAUSE devinput (KEY_PAUSE)
18:47:42 T:140658126428224 DEBUG: OnKey: blue (0xe6) pressed, action is Pause
18:47:42 T:140657671345920 DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
18:47:42 T:140657671345920 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
18:47:42 T:140658126428224 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
18:47:42 T:140656102053632 DEBUG: CDVDAudio::Pause - pausing audio stream
18:47:42 T:140656120153856 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer