09:58:37 T:140705316055104 NOTICE: special://profile/ is mapped to: special://masterprofile/
09:58:37 T:140705316055104 NOTICE: -----------------------------------------------------------------------
09:58:37 T:140705316055104 NOTICE: Starting Kodi (17.0-ALPHA3 Git:fc46cf2). Platform: Linux x86 64-bit
09:58:37 T:140705316055104 NOTICE: Using Release Kodi x64 build
09:58:37 T:140705316055104 NOTICE: Kodi compiled Aug 1 2016 by GCC 4.8.4 for Linux x86 64-bit version 3.13.11 (199947)
09:58:37 T:140705316055104 NOTICE: Running on Ubuntu 14.04.5 LTS, kernel: Linux x86 64-bit version 3.13.0-92-generic
09:58:37 T:140705316055104 NOTICE: FFmpeg statically linked, version: 3.1.1-kodi-3.1.1-Krypton-Alpha3
09:58:37 T:140705316055104 NOTICE: Host CPU: Intel(R) Atom(TM) CPU D525 @ 1.80GHz, 4 cores available
09:58:37 T:140705316055104 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
09:58:37 T:140705316055104 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
09:58:37 T:140705316055104 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons
09:58:37 T:140705316055104 NOTICE: special://masterprofile/ is mapped to: /home/fubar/.kodi/userdata
09:58:37 T:140705316055104 NOTICE: special://envhome/ is mapped to: /home/fubar
09:58:37 T:140705316055104 NOTICE: special://home/ is mapped to: /home/fubar/.kodi
09:58:37 T:140705316055104 NOTICE: special://temp/ is mapped to: /home/fubar/.kodi/temp
09:58:37 T:140705316055104 NOTICE: special://logpath/ is mapped to: /home/fubar/.kodi/temp
09:58:37 T:140705316055104 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
09:58:37 T:140705316055104 NOTICE: Local hostname: FUBAR-XBMC
09:58:37 T:140705316055104 NOTICE: Log File is located: /home/fubar/.kodi/temp//kodi.log
09:58:37 T:140705316055104 NOTICE: -----------------------------------------------------------------------
09:58:38 T:140705316055104 NOTICE: load settings...
09:58:38 T:140705316055104 ERROR: PulseAudio: Failed to connect context
09:58:38 T:140705316055104 NOTICE: PulseAudio might not be running. Context was not created.
09:58:38 T:140705316055104 NOTICE: CAESinkOSS::EnumerateDevicesEx - No OSS mixer device present: /dev/mixer
09:58:38 T:140705316055104 NOTICE: No Devices found - retry: 4
09:58:39 T:140705316055104 ERROR: PulseAudio: Failed to connect context
09:58:39 T:140705316055104 NOTICE: PulseAudio might not be running. Context was not created.
09:58:39 T:140705316055104 NOTICE: CAESinkOSS::EnumerateDevicesEx - No OSS mixer device present: /dev/mixer
09:58:39 T:140705316055104 NOTICE: No Devices found - retry: 3
09:58:41 T:140705316055104 ERROR: PulseAudio: Failed to connect context
09:58:41 T:140705316055104 NOTICE: PulseAudio might not be running. Context was not created.
09:58:41 T:140705316055104 NOTICE: Found 1 Lists of Devices
09:58:41 T:140705316055104 NOTICE: Enumerated ALSA devices:
09:58:41 T:140705316055104 NOTICE: Device 1
09:58:41 T:140705316055104 NOTICE: m_deviceName : default
09:58:41 T:140705316055104 NOTICE: m_displayName : Default (HDA NVidia HDMI 0)
09:58:41 T:140705316055104 NOTICE: m_displayNameExtra:
09:58:41 T:140705316055104 NOTICE: m_deviceType : AE_DEVTYPE_PCM
09:58:41 T:140705316055104 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
09:58:41 T:140705316055104 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
09:58:41 T:140705316055104 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
09:58:41 T:140705316055104 NOTICE: m_streamTypes : No passthrough capabilities
09:58:41 T:140705316055104 NOTICE: Device 2
09:58:41 T:140705316055104 NOTICE: m_deviceName : @:CARD=Intel,DEV=0
09:58:41 T:140705316055104 NOTICE: m_displayName : HDA Intel
09:58:41 T:140705316055104 NOTICE: m_displayNameExtra: ALC888 Analog
09:58:41 T:140705316055104 NOTICE: m_deviceType : AE_DEVTYPE_PCM
09:58:41 T:140705316055104 NOTICE: m_channels : FL,FR
09:58:41 T:140705316055104 NOTICE: m_sampleRates : 48000
09:58:41 T:140705316055104 NOTICE: m_dataFormats : AE_FMT_S32NE
09:58:41 T:140705316055104 NOTICE: m_streamTypes : No passthrough capabilities
09:58:41 T:140705316055104 NOTICE: Device 3
09:58:41 T:140705316055104 NOTICE: m_deviceName : iec958:CARD=Intel,DEV=0
09:58:41 T:140705316055104 NOTICE: m_displayName : HDA Intel
09:58:41 T:140705316055104 NOTICE: m_displayNameExtra: ALC888 Digital S/PDIF
09:58:41 T:140705316055104 NOTICE: m_deviceType : AE_DEVTYPE_IEC958
09:58:41 T:140705316055104 NOTICE: m_channels : FL,FR
09:58:41 T:140705316055104 NOTICE: m_sampleRates : 44100,48000,88200,96000,192000
09:58:41 T:140705316055104 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
09:58:41 T:140705316055104 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
09:58:41 T:140705316055104 NOTICE: Device 4
09:58:41 T:140705316055104 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0
09:58:41 T:140705316055104 NOTICE: m_displayName : HDA NVidia
09:58:41 T:140705316055104 NOTICE: m_displayNameExtra: PIO VSX-924 on HDMI
09:58:41 T:140705316055104 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
09:58:41 T:140705316055104 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,SL,SR
09:58:41 T:140705316055104 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
09:58:41 T:140705316055104 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
09:58:41 T:140705316055104 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
09:58:41 T:140705316055104 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
09:58:41 T:140705316055104 NOTICE: Loaded settings file from special://profile/advancedsettings.xml
09:58:41 T:140705316055104 NOTICE: Contents of special://profile/advancedsettings.xml are...
true
cputemp
gputemp
30
true
09:58:41 T:140705316055104 NOTICE: Default Video Player: VideoPlayer
09:58:41 T:140705316055104 NOTICE: Default Audio Player: paplayer
09:58:41 T:140705316055104 NOTICE: Enabled debug logging due to GUI setting (2)
09:58:41 T:140705316055104 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
09:58:41 T:140705316055104 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
09:58:41 T:140705316055104 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
09:58:41 T:140705316055104 DEBUG: CPlayerCoreConfig::: created player VideoPlayer
09:58:41 T:140705316055104 DEBUG: CPlayerCoreConfig::: created player PAPlayer
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
09:58:41 T:140705316055104 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
09:58:41 T:140705316055104 NOTICE: Loaded playercorefactory configuration
09:58:41 T:140705316055104 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
09:58:41 T:140705316055104 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
09:58:41 T:140705316055104 INFO: creating subdirectories
09:58:41 T:140705316055104 INFO: userdata folder: special://masterprofile/
09:58:41 T:140705316055104 INFO: recording folder:
09:58:41 T:140705316055104 INFO: screenshots folder:
09:58:41 T:140705316055104 NOTICE: Running database version Addons26
09:58:41 T:140705316055104 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
09:58:41 T:140705316055104 DEBUG: Loading: /usr/lib/kodi/system/libcpluff-x86_64-linux.so
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.refocus has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.cu.lrclyrics has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.chroma has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.bello has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.white has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.rapier has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.pydes has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup4 has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.recordlabels.white has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.watchlist has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.ace.extrapack has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.kodi65 has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.cirrus.extended has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.myconnpy has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weatherfanart.prairie has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weatherfanart.single has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.amazon-test has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.pyxbmct has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.image.resource.select has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.neon has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.mimic has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.pytz has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.grab.fanart has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.unity has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in superrepo.kodi.jarvis.programs has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.moviegenreicons.coloured has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in repository.sandmann79.plugins has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in service.subtitles.opensubtitles has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in repository.ytplugin has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.blackglassnova has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.mimic.extras has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.actionhandler has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in service.library.data.provider has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.hybrid has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.amber has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.rss.editor has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.cinema.experience has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.ardmediathek_de has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.outline has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.xbmc.debug.log has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.skin.helper.service has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.t9.search has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.confluence has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.mechanize has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.videolanguage has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.ace has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.tv.show.next.aired has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.xbmcswift2 has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.eminence.2 has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.SportsDevil has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.requests2 has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.aeon.nox.5 has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.nebula has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.addon.signals has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in weather.openweathermap.extended has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.transparent has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.autocompletion has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.extendedinfo has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.openweathermap.maps has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in superrepo.kodi.jarvis.repositories has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.musicgenreicons.grey has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.ted.talks has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.chardet has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.simpleplaylists has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.refocus has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.moviegenreicons.white has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.youtube.dl has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.xperience1080 has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.favourites has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.twitch has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.program.autocompletion has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.playalbum has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.addon.common has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weatherfanart.multi has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.musicgenreicons.poster has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.toolbox has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.globalsearch has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in webinterface.arch has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.nebula has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.randomandlastitems has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.tvtunes has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.rapier has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in service.skin.widgets has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.languageflags.colour has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.unidecode has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.skinshortcuts has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.vimeo has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.simple.downloader has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.animated has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.estouchy has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.p2p-streams has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in inputstream.mpd has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.artistslideshow has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.requests has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.artwork.downloader has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.kover has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.xbmc.audio.mixer has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.amber has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in webinterface.chorus has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.zdf_de_lite has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.routing has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in repository.p2p-streams.xbmc has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.titan.modern has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in resource.images.studios.white has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.elementtree has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in repository.xlordkx has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in pvr.hts has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in skin.transparency has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Plug-in script.module.metahandler has been installed.'
09:58:41 T:140705316055104 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
09:58:41 T:140704835860224 DEBUG: Thread JobWorker start, auto delete: true
09:58:42 T:140705316055104 NOTICE: ADDONS: Using repository superrepo.kodi.jarvis.programs
09:58:42 T:140705316055104 NOTICE: ADDONS: Using repository repository.sandmann79.plugins
09:58:42 T:140705316055104 NOTICE: ADDONS: Using repository repository.ytplugin
09:58:42 T:140705316055104 NOTICE: ADDONS: Using repository superrepo.kodi.jarvis.repositories
09:58:42 T:140705316055104 NOTICE: ADDONS: Using repository repository.xbmc.org
09:58:42 T:140705316055104 NOTICE: ADDONS: Using repository repository.p2p-streams.xbmc
09:58:42 T:140705316055104 NOTICE: ADDONS: Using repository repository.xlordkx
09:58:42 T:140705316055104 DEBUG: ADDON: Dll Initializing - InputStream MPEG DASH
09:58:42 T:140705316055104 DEBUG: SECTION:LoadDLL(/home/fubar/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.0)
09:58:42 T:140705316055104 DEBUG: Loading: /home/fubar/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.0
09:58:42 T:140705316055104 DEBUG: AddOnLog: InputStream MPEG DASH: libXBMC_addon successfully loaded
09:58:42 T:140705316055104 DEBUG: AddOnLog: InputStream MPEG DASH: ADDON_Create()
09:58:42 T:140705316055104 DEBUG: AddOnLog: InputStream MPEG DASH: ADDON_Destroy()
09:58:42 T:140705316055104 DEBUG: SECTION:UnloadDll(/home/fubar/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.0)
09:58:42 T:140705316055104 INFO: ADDON: Dll Destroyed - InputStream MPEG DASH
09:58:42 T:140704827467520 DEBUG: Thread ActiveAE start, auto delete: false
09:58:42 T:140704819074816 DEBUG: Thread AESink start, auto delete: false
09:58:42 T:140704827467520 WARNING: CActiveAE::StateMachine - signal: 3 from port: OutputControlPort not handled for state: 2
09:58:42 T:140704819074816 WARNING: Previous line repeats 1 times.
09:58:42 T:140704819074816 INFO: CActiveAESink::OpenSink - initialize sink
09:58:42 T:140704819074816 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:default
09:58:42 T:140704819074816 INFO: CAESinkALSA::Initialize - Attempting to open device "default"
09:58:42 T:140704819074816 INFO: CAESinkALSA::Initialize - Opened device "default"
09:58:42 T:140704819074816 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
09:58:42 T:140704819074816 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
09:58:42 T:140704819074816 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
09:58:42 T:140704819074816 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
09:58:42 T:140704819074816 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms
09:58:42 T:140704819074816 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
09:58:42 T:140704819074816 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
09:58:42 T:140704819074816 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
09:58:42 T:140704819074816 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
09:58:42 T:140704819074816 DEBUG: Output Device : Default (HDA NVidia HDMI 0)
09:58:42 T:140704819074816 DEBUG: Sample Rate : 44100
09:58:42 T:140704819074816 DEBUG: Sample Format : AE_FMT_S32NE
09:58:42 T:140704819074816 DEBUG: Channel Count : 2
09:58:42 T:140704819074816 DEBUG: Channel Layout: FL,FR
09:58:42 T:140704819074816 DEBUG: Frames : 2048
09:58:42 T:140704819074816 DEBUG: Frame Size : 8
09:58:42 T:140704672114432 DEBUG: Thread RemoteControl start, auto delete: false
09:58:42 T:140704672114432 INFO: LIRC Process: using: /dev/lircd
09:58:42 T:140704672114432 INFO: LIRC Connect: connect failed: No such file or directory
09:58:42 T:140704672114432 INFO: CRemoteControl::Process - failed to connect to LIRC, will keep retrying every 5 seconds
09:58:42 T:140705316055104 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
09:58:42 T:140705316055104 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
09:58:42 T:140705316055104 DEBUG: Selected UDisks as storage provider
09:58:42 T:140705316055104 DEBUG: UDisks: DaemonVersion 1
09:58:42 T:140705316055104 DEBUG: UDisks: Querying available devices
09:58:42 T:140705316055104 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
09:58:42 T:140705316055104 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
09:58:42 T:140705316055104 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
09:58:42 T:140705316055104 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sr0: IsFileSystem false HasFileSystem IsSystemInternal false IsMounted false IsRemovable true IsPartition false IsOptical false
09:58:42 T:140705316055104 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
09:58:42 T:140705316055104 INFO: Available videomodes (xrandr):
09:58:42 T:140705316055104 INFO: Output 'HDMI-0' has 22 modes
09:58:42 T:140705316055104 INFO: ID:0x1c6 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1c7 Name:1920x1080 Refresh:59.939388 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1c8 Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1c9 Name:1920x1080 Refresh:29.971720 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1ca Name:1920x1080 Refresh:25.000000 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1cb Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1cc Name:1920x1080 Refresh:23.970909 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1cd Name:1920x1080 Refresh:29.998381 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1ce Name:1920x1080 Refresh:25.022240 Width:1920 Height:1080
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1cf Name:1600x1200 Refresh:60.000000 Width:1600 Height:1200
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.333333
09:58:42 T:140705316055104 INFO: ID:0x1d0 Name:1360x765 Refresh:60.003189 Width:1360 Height:765
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1d1 Name:1280x1024 Refresh:60.019741 Width:1280 Height:1024
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.422222
09:58:42 T:140705316055104 INFO: ID:0x1d2 Name:1280x720 Refresh:59.943432 Width:1280 Height:720
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1d3 Name:1280x720 Refresh:50.000000 Width:1280 Height:720
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.000000
09:58:42 T:140705316055104 INFO: ID:0x1d4 Name:1024x768 Refresh:60.003841 Width:1024 Height:768
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.333333
09:58:42 T:140705316055104 INFO: ID:0x1d5 Name:800x600 Refresh:60.316540 Width:800 Height:600
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.333333
09:58:42 T:140705316055104 INFO: ID:0x1d6 Name:720x576 Refresh:50.000000 Width:720 Height:576
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.422222
09:58:42 T:140705316055104 INFO: ID:0x1d7 Name:720x576 Refresh:25.040060 Width:720 Height:576
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.422222
09:58:42 T:140705316055104 INFO: ID:0x1d8 Name:720x480 Refresh:59.940060 Width:720 Height:480
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.185185
09:58:42 T:140705316055104 INFO: ID:0x1d9 Name:720x480 Refresh:30.027220 Width:720 Height:480
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.185185
09:58:42 T:140705316055104 INFO: ID:0x1da Name:640x480 Refresh:59.940479 Width:640 Height:480
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.333333
09:58:42 T:140705316055104 INFO: ID:0x1db Name:640x480 Refresh:59.928570 Width:640 Height:480
09:58:42 T:140705316055104 INFO: Pixel Ratio: 1.333333
09:58:42 T:140705316055104 NOTICE: Checking resolution 16
09:58:42 T:140705316055104 ERROR: Failed to determine egl config for visual info
09:58:42 T:140705316055104 WARNING: Visual 0x27 of the window is not suitable, looking for another one...
09:58:42 T:140705316055104 NOTICE: Using visual 0x21
09:58:42 T:140705316055104 NOTICE: Using visual 0x27
09:58:43 T:140705316055104 INFO: GL: Maximum texture width: 8192
09:58:43 T:140705316055104 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_ARB_create_context GLX_ARB_create_context_profile GLX_EXT_create_context_es2_profile GLX_ARB_create_context_robustness GLX_ARB_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_EXT_framebuffer_sRGB GLX_NV_multisample_coverage GLX_ARB_get_proc_address
09:58:43 T:140705316055104 NOTICE: GL_VENDOR = NVIDIA Corporation
09:58:43 T:140705316055104 NOTICE: GL_RENDERER = ION/PCIe/SSE2
09:58:43 T:140705316055104 NOTICE: GL_VERSION = 3.3.0 NVIDIA 304.131
09:58:43 T:140705316055104 NOTICE: GL_SHADING_LANGUAGE_VERSION = 3.30 NVIDIA via Cg compiler
09:58:43 T:140705316055104 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 524288
09:58:43 T:140705316055104 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 524288
09:58:43 T:140705316055104 NOTICE: GL_EXTENSIONS = GL_ARB_base_instance GL_ARB_blend_func_extended GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_compressed_texture_pixel_storage GL_ARB_conservative_depth GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_ES2_compatibility GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_provoking_vertex GL_ARB_robustness GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_objects 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_sync GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_compression 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_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_lod GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_storage GL_ARB_texture_swizzle GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback_instanced GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader 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_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_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color 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_format_BGRA8888 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_texture_type_2_10_10_10_REV GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_EXT_x11_sync_object GL_EXT_import_sync_object GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KTX_buffer_region GL_NV_alpha_test GL_NV_blend_minmax GL_NV_blend_square GL_NV_complex_primitives 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_ES1_1_compatibility GL_NV_explicit_multisample GL_NV_fbo_color_attachments GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragdepth 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_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_buffer_load GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_lod_clamp 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_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 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_OES_compressed_paletted_texture GL_OES_depth24 GL_OES_depth32 GL_OES_depth_texture GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_get_program_binary GL_OES_mapbuffer GL_OES_packed_depth_stencil GL_OES_point_size_array GL_OES_point_sprite GL_OES_rgb8_rgba8 GL_OES_read_format GL_OES_standard_derivatives GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_float_linear GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_array_object GL_OES_vertex_half_float GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
09:58:43 T:140705316055104 INFO: GL: Maximum texture width: 8192
09:58:43 T:140705316055104 INFO: GL: Enabling VSYNC
09:58:43 T:140705316055104 INFO: load keymapping
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/joystick.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/mouse.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/remote.xml
09:58:43 T:140705316055104 ERROR: Window Translator: Can't find window librarysettings
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
09:58:43 T:140705316055104 INFO: Loading special://masterprofile/keymaps/noBS.xml
09:58:43 T:140705316055104 INFO: Loading special://profile/keymaps/noBS.xml
09:58:43 T:140705316055104 INFO: Loading special://xbmc/system/Lircmap.xml
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'mceusb'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'XboxDVDDongle'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'anysee'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'iMON-PAD'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'MCE_via_iMON'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'TwinHanRemote'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'linux-input-layer'
09:58:43 T:140705316055104 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'mediacenter'
09:58:43 T:140705316055104 INFO: * Adding remote mapping for device 'devinput'
09:58:43 T:140705316055104 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
09:58:43 T:140705316055104 INFO: GUI format 1920x1080, Display 1920x1080@ 60.00 - Full Screen
09:58:43 T:140705316055104 DEBUG: guilib: Fill viewport on change for solving rendering passes
09:58:43 T:140705316055104 INFO: CLangInfo: loading resource.language.en_gb language information...
09:58:43 T:140705316055104 DEBUG: trying to set locale to en_DE.UTF-8
09:58:43 T:140705316055104 INFO: global locale set to C
09:58:43 T:140705316055104 INFO: CLangInfo: loading resource.language.en_gb language strings...
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 3727 strings from file resource://resource.language.en_gb/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 36 strings from file /home/fubar/.kodi/addons/script.cu.lrclyrics/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 7 strings from file /home/fubar/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 6 strings from file /home/fubar/.kodi/addons/script.watchlist/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 21 strings from file /home/fubar/.kodi/addons/service.xbmc.versioncheck/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 7 strings from file /home/fubar/.kodi/addons/script.common.plugin.cache/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 2 strings from file /home/fubar/.kodi/addons/metadata.musicvideos.theaudiodb.com/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 4 strings from file /home/fubar/.kodi/addons/service.subtitles.opensubtitles/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 24 strings from file /home/fubar/.kodi/addons/service.library.data.provider/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 21 strings from file /home/fubar/.kodi/addons/script.rss.editor/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 212 strings from file /home/fubar/.kodi/addons/script.cinema.experience/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 44 strings from file /home/fubar/.kodi/addons/plugin.video.ardmediathek_de/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 11 strings from file /home/fubar/.kodi/addons/script.xbmc.debug.log/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 187 strings from file /home/fubar/.kodi/addons/script.skin.helper.service/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 43 strings from file /home/fubar/.kodi/addons/script.tv.show.next.aired/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 81 strings from file /home/fubar/.kodi/addons/plugin.video.SportsDevil/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 105 strings from file /home/fubar/.kodi/addons/weather.openweathermap.extended/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 167 strings from file /home/fubar/.kodi/addons/script.extendedinfo/resources/language/resource.language.en_gb/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 31 strings from file /home/fubar/.kodi/addons/plugin.video.ted.talks/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 31 strings from file /home/fubar/.kodi/addons/script.simpleplaylists/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 37 strings from file /home/fubar/.kodi/addons/script.module.youtube.dl/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 3 strings from file /home/fubar/.kodi/addons/script.favourites/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 76 strings from file /home/fubar/.kodi/addons/plugin.video.twitch/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 18 strings from file /home/fubar/.kodi/addons/script.toolbox/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 24 strings from file /home/fubar/.kodi/addons/script.globalsearch/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 113 strings from file /home/fubar/.kodi/addons/script.tvtunes/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 13 strings from file /home/fubar/.kodi/addons/service.skin.widgets/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 115 strings from file /home/fubar/.kodi/addons/script.skinshortcuts/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 77 strings from file /home/fubar/.kodi/addons/plugin.video.vimeo/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 18 strings from file /home/fubar/.kodi/addons/script.module.simple.downloader/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /home/fubar/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 108 strings from file /home/fubar/.kodi/addons/plugin.video.youtube/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 310 strings from file /home/fubar/.kodi/addons/plugin.video.p2p-streams/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 107 strings from file /home/fubar/.kodi/addons/script.artistslideshow/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 100 strings from file /home/fubar/.kodi/addons/script.artwork.downloader/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 2 strings from file /home/fubar/.kodi/addons/script.xbmc.audio.mixer/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 13 strings from file /home/fubar/.kodi/addons/metadata.album.universal/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 23 strings from file /home/fubar/.kodi/addons/plugin.video.zdf_de_lite/resources/language/English/strings.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 7 strings from file /home/fubar/.kodi/addons/metadata.tvdb.com/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 26 strings from file /home/fubar/.kodi/addons/metadata.artists.universal/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 68 strings from file /usr/share/kodi/addons/pvr.hts/resources/language/resource.language.en_gb/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 3 strings from file /home/fubar/.kodi/addons/plugin.program.autocompletion/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 10 strings from file /home/fubar/.kodi/addons/script.mimic.extras/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 6 strings from file /home/fubar/.kodi/addons/inputstream.mpd/resources/language/resource.language.en_gb/strings.po
09:58:43 T:140705316055104 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /home/fubar/.kodi/addons/plugin.video.amazon-test/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 118 strings from file /home/fubar/.kodi/addons/plugin.video.amazon-test/resources/language/English/strings.po
09:58:43 T:140705316055104 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
09:58:43 T:140705316055104 DEBUG: LoadMappings - loaded node "CEC Adapter"
09:58:43 T:140705316055104 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
09:58:43 T:140705316055104 DEBUG: LoadMappings - loaded node "iMON HID device"
09:58:43 T:140705316055104 DEBUG: LoadMappings - loaded node "WETEK Play remote"
09:58:43 T:140705316055104 DEBUG: CPeripheralBusUSB - initialised udev monitor
09:58:43 T:140705316055104 DEBUG: SECTION:LoadDLL(libcec.so.3)
09:58:43 T:140705316055104 DEBUG: Loading: libcec.so.3
09:58:43 T:140704378971904 DEBUG: Thread PeripBusUSBUdev start, auto delete: false
09:58:43 T:140704387364608 DEBUG: Thread PeripBusCEC start, auto delete: false
09:58:43 T:140704395757312 DEBUG: Thread PeripBusAddon start, auto delete: false
09:58:43 T:140705316055104 DEBUG: SECTION:LoadDLL(libcurl.so.4)
09:58:43 T:140704346642176 DEBUG: Thread PeripEventScanner start, auto delete: false
09:58:43 T:140705316055104 DEBUG: Loading: libcurl.so.4
09:58:43 T:140705316055104 NOTICE: Running database version Addons26
09:58:43 T:140705316055104 DEBUG: Initialize, updating databases...
09:58:43 T:140705316055104 NOTICE: Running database version ViewModes6
09:58:43 T:140705316055104 NOTICE: Running database version Textures13
09:58:43 T:140705316055104 NOTICE: Running database version MyMusic60
09:58:43 T:140705316055104 NOTICE: Running database version MyVideos107
09:58:43 T:140705316055104 NOTICE: Running database version TV29
09:58:43 T:140705316055104 NOTICE: Running database version Epg11
09:58:43 T:140705316055104 DEBUG: Initialize, updating databases... DONE
09:58:43 T:140705316055104 NOTICE: start dvd mediatype detection
09:58:43 T:140705315108608 DEBUG: Thread DetectDVDMedia start, auto delete: false
09:58:43 T:140705315108608 DEBUG: Compiled with libcdio Version 0.83
09:58:43 T:140705316055104 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
09:58:43 T:140704863520512 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
09:58:43 T:140704863520512 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
09:58:43 T:140705316055104 DEBUG: Activating window ID: 12997
09:58:43 T:140705316055104 DEBUG: ------ Window Init () ------
09:58:43 T:140705316055104 INFO: load splash image: /usr/share/kodi/media/Splash.png
09:58:43 T:140705316055104 INFO: Unloading old skin ...
09:58:43 T:140705316055104 INFO: load skin from: /usr/share/kodi/addons/skin.estuary (version: 0.8.4)
09:58:43 T:140705316055104 INFO: load fonts for skin...
09:58:43 T:140705316055104 INFO: Loading colors from /usr/share/kodi/addons/skin.estuary/colors/charcoal.xml
09:58:43 T:140705316055104 INFO: Loading fonts from /usr/share/kodi/addons/skin.estuary/1080i/Font.xml
09:58:43 T:140705316055104 DEBUG: LocalizeStrings: loaded 142 strings from file /usr/share/kodi/addons/skin.estuary/language/resource.language.en_gb/strings.po
09:58:43 T:140705316055104 INFO: Loading skin includes from /usr/share/kodi/addons/skin.estuary/1080i/Includes.xml
09:58:43 T:140705316055104 INFO: load new skin...
09:58:43 T:140705316055104 INFO: Loading user windows, path /usr/share/kodi/addons/skin.estuary/1080i
09:58:43 T:140705316055104 DEBUG: Load Skin XML: 17.23ms
09:58:43 T:140705316055104 INFO: initialize new skin...
09:58:43 T:140705316055104 DEBUG: guilib: Fill viewport on change for solving rendering passes
09:58:43 T:140705316055104 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
09:58:43 T:140705316055104 DEBUG: OpenBundle - Opened bundle /usr/share/kodi/addons/skin.estuary/media/Textures.xbt
09:58:43 T:140705316055104 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
09:58:43 T:140705316055104 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
09:58:43 T:140705316055104 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
09:58:43 T:140705316055104 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
09:58:43 T:140705316055104 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
09:58:43 T:140705316055104 INFO: Loading resource://resource.uisounds.confluence/sounds.xml
09:58:44 T:140705316055104 INFO: skin loaded...
09:58:44 T:140705316055104 DEBUG: Activating window ID: 12997
09:58:44 T:140705316055104 DEBUG: ------ Window Init () ------
09:58:44 T:140705316055104 INFO: load splash image: /usr/share/kodi/media/Splash.png
09:58:44 T:140705316055104 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
09:58:44 T:140705316055104 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
09:58:44 T:140705316055104 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
09:58:44 T:140705316055104 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
09:58:44 T:140705316055104 ERROR: JSONRPC: Unable to parse JSON Schema definition for ""
09:58:44 T:140705316055104 ERROR: JSONRPC: Invalid JSON Schema definition for method ""
09:58:44 T:140705316055104 INFO: JSONRPC v7.20.1: Successfully initialized
09:58:44 T:140705316055104 DEBUG: ADDON: Starting service addons.
09:58:44 T:140703836202752 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:44 T:140703836202752 INFO: initializing python engine.
09:58:44 T:140703827810048 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:44 T:140703827810048 DEBUG: Previous line repeats 1 times.
09:58:44 T:140703827810048 INFO: initializing python engine.
09:58:44 T:140703531267840 INFO: Previous line repeats 1 times.
09:58:44 T:140703531267840 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:44 T:140703531267840 DEBUG: Previous line repeats 2 times.
09:58:44 T:140703531267840 INFO: initializing python engine.
09:58:44 T:140703506089728 INFO: Previous line repeats 2 times.
09:58:44 T:140703506089728 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:44 T:140703506089728 INFO: initializing python engine.
09:58:44 T:140703514482432 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:44 T:140703514482432 DEBUG: Previous line repeats 1 times.
09:58:44 T:140703514482432 INFO: initializing python engine.
09:58:44 T:140705316055104 INFO: Previous line repeats 1 times.
09:58:44 T:140705316055104 DEBUG: Activating window ID: 12999
09:58:44 T:140703489304320 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:44 T:140705316055104 DEBUG: ------ Window Init (Startup.xml) ------
09:58:44 T:140703489304320 INFO: initializing python engine.
09:58:44 T:140705316055104 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
09:58:44 T:140705316055104 DEBUG: Activating window ID: 10000
09:58:44 T:140705316055104 DEBUG: ------ Window Deinit (Startup.xml) ------
09:58:44 T:140705316055104 DEBUG: ------ Window Init (Home.xml) ------
09:58:44 T:140705316055104 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
09:58:44 T:140703836202752 DEBUG: CPythonInvoker(0, /home/fubar/.kodi/addons/script.cu.lrclyrics/default.py): start processing
09:58:44 T:140703827810048 DEBUG: CPythonInvoker(1, /home/fubar/.kodi/addons/service.xbmc.versioncheck/service.py): start processing
09:58:44 T:140703819417344 DEBUG: CPythonInvoker(2, /home/fubar/.kodi/addons/script.grab.fanart/service.py): start processing
09:58:44 T:140703531267840 DEBUG: CPythonInvoker(4, /home/fubar/.kodi/addons/script.cinema.experience/service.py): start processing
09:58:44 T:140703811024640 DEBUG: CPythonInvoker(3, /home/fubar/.kodi/addons/service.library.data.provider/service.py): start processing
09:58:44 T:140703522875136 DEBUG: CPythonInvoker(5, /home/fubar/.kodi/addons/script.skin.helper.service/service.py): start processing
09:58:44 T:140703506089728 DEBUG: CPythonInvoker(7, /home/fubar/.kodi/addons/script.tvtunes/service.py): start processing
09:58:44 T:140703497697024 DEBUG: CPythonInvoker(8, /home/fubar/.kodi/addons/service.skin.widgets/default.py): start processing
09:58:44 T:140703489304320 DEBUG: CPythonInvoker(9, /home/fubar/.kodi/addons/script.module.simple.downloader/default.py): start processing
09:58:44 T:140703514482432 DEBUG: CPythonInvoker(6, /home/fubar/.kodi/addons/script.tv.show.next.aired/service.py): start processing
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[videodb://recentlyaddedmovies/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_movies.xsp]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/random_movies.xsp]: refreshing..
09:58:44 T:140703480911616 DEBUG: Thread JobWorker start, auto delete: true
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[videodb://recentlyaddedepisodes/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
09:58:44 T:140702860179200 DEBUG: Thread JobWorker start, auto delete: true
09:58:44 T:140704835860224 DEBUG: RunQuery took 70 ms for 20 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[musicdb://recentlyplayedalbums]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[musicdb://recentlyaddedalbums/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/random_albums.xsp]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/random_artists.xsp]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/unplayed_albums.xsp]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://skin/playlists/mostplayed_albums.xsp]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[addons://sources/video/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[addons://sources/audio/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[addons://sources/executable/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[-]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[addons://sources/image/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[sources://video/]: refreshing..
09:58:44 T:140705316055104 DEBUG: CDirectoryProvider[special://videoplaylists/]: refreshing..
09:58:44 T:140702860179200 DEBUG: RunQuery took 102 ms for 9 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
09:58:44 T:140705316055104 INFO: removing tempfiles
09:58:44 T:140705316055104 DEBUG: ADDON: Starting service addons.
09:58:44 T:140702851786496 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:44 T:140702851786496 INFO: initializing python engine.
09:58:44 T:140702851786496 DEBUG: CPythonInvoker(10, /home/fubar/.kodi/addons/script.common.plugin.cache/default.py): start processing
09:58:44 T:140703480911616 DEBUG: RunQuery took 289 ms for 25 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 25
09:58:44 T:140705316055104 DEBUG: CRepositoryUpdater: previous update at 01-08-2016 19:39:24, next at 02-08-2016 19:39:24
09:58:44 T:140705316055104 NOTICE: initialize done
09:58:44 T:140705316055104 NOTICE: Running the application...
09:58:44 T:140702843393792 DEBUG: Thread Timer start, auto delete: false
09:58:44 T:140705316055104 DEBUG: no profile autoexec.py (/home/fubar/.kodi/userdata/autoexec.py) found, skipping
09:58:44 T:140705316055104 DEBUG: NetworkMessage - Starting network services
09:58:44 T:140705316055104 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
09:58:44 T:140705316055104 NOTICE: starting zeroconf publishing
09:58:44 T:140705316055104 NOTICE: CWebServer[8080]: Started
09:58:44 T:140705316055104 NOTICE: starting upnp client
09:58:44 T:140705316055104 NOTICE: starting upnp renderer
09:58:44 T:140702373631744 DEBUG: Thread EventServer start, auto delete: false
09:58:44 T:140702373631744 NOTICE: ES: Starting UDP Event server on port 9777
09:58:44 T:140702373631744 NOTICE: UDP: Listening on port 9777 (ipv6 : true)
09:58:45 T:140705316055104 ERROR: JSONRPC Server: Failed to connect to sdpd
09:58:45 T:140705316055104 INFO: JSONRPC Server: Successfully initialized
09:58:45 T:140702356846336 DEBUG: Thread TCPServer start, auto delete: false
09:58:45 T:140702860179200 DEBUG: RunQuery took 292 ms for 595 items query: select * from movie_view
09:58:45 T:140704835860224 DEBUG: RunQuery took 359 ms for 4 items query: SELECT * FROM tvshow_view WHERE watchedCount != 0 AND totalCount != watchedCount ORDER BY c00
09:58:45 T:140702348453632 DEBUG: Thread JobWorker start, auto delete: true
09:58:45 T:140705316055104 DEBUG: ------ Window Init () ------
09:58:45 T:140704835860224 DEBUG: RunQuery took 154 ms for 0 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))
09:58:45 T:140705316055104 DEBUG: UPower: Received an unknown signal NameAcquired
09:58:45 T:140702860179200 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
09:58:45 T:140703836202752 DEBUG: -->Python Interpreter Initialized<--
09:58:45 T:140703836202752 DEBUG: CPythonInvoker(0, /home/fubar/.kodi/addons/script.cu.lrclyrics/default.py): the source file to load is "/home/fubar/.kodi/addons/script.cu.lrclyrics/default.py"
09:58:45 T:140703522875136 DEBUG: -->Python Interpreter Initialized<--
09:58:45 T:140703522875136 DEBUG: CPythonInvoker(5, /home/fubar/.kodi/addons/script.skin.helper.service/service.py): the source file to load is "/home/fubar/.kodi/addons/script.skin.helper.service/service.py"
09:58:45 T:140703514482432 DEBUG: -->Python Interpreter Initialized<--
09:58:45 T:140703514482432 DEBUG: CPythonInvoker(6, /home/fubar/.kodi/addons/script.tv.show.next.aired/service.py): the source file to load is "/home/fubar/.kodi/addons/script.tv.show.next.aired/service.py"
09:58:45 T:140702348453632 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
09:58:45 T:140702860179200 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strReleaseType = 'album'
09:58:45 T:140702348453632 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist ) OR artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist WHERE song_artist.idRole = 1)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
09:58:45 T:140702860179200 DEBUG: GetAlbumsByWhere - query took 215 ms
09:58:45 T:140702348453632 DEBUG: Time to retrieve artists from dataset = 176
09:58:46 T:140703480911616 DEBUG: RunQuery took 1054 ms for 25 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 25
09:58:46 T:140702348453632 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE (albumview.strReleaseType = 'album') AND (((CAST(albumview.iTimesPlayed as DECIMAL(5,1)) = 0)))
09:58:46 T:140702860179200 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE (albumview.strReleaseType = 'album') AND (((CAST(albumview.iTimesPlayed as DECIMAL(5,1)) > 0)))
09:58:46 T:140702860179200 DEBUG: GetAlbumsByWhere - query took 42 ms
09:58:46 T:140702348453632 DEBUG: GetAlbumsByWhere - query took 164 ms
09:58:46 T:140703522875136 DEBUG: CPythonInvoker(5, /home/fubar/.kodi/addons/script.skin.helper.service/service.py): setting the Python path to /home/fubar/.kodi/addons/script.skin.helper.service:/home/fubar/.kodi/addons/script.module.beautifulsoup/lib:/home/fubar/.kodi/addons/script.module.requests/lib:/home/fubar/.kodi/addons/script.module.simplejson/lib:/usr/share/kodi/addons/script.module.pil/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
09:58:46 T:140703522875136 DEBUG: CPythonInvoker(5, /home/fubar/.kodi/addons/script.skin.helper.service/service.py): entering source directory /home/fubar/.kodi/addons/script.skin.helper.service
09:58:46 T:140703522875136 DEBUG: CPythonInvoker(5, /home/fubar/.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
09:58:46 T:140703836202752 DEBUG: CPythonInvoker(0, /home/fubar/.kodi/addons/script.cu.lrclyrics/default.py): setting the Python path to /home/fubar/.kodi/addons/script.cu.lrclyrics:/home/fubar/.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
09:58:46 T:140703836202752 DEBUG: CPythonInvoker(0, /home/fubar/.kodi/addons/script.cu.lrclyrics/default.py): entering source directory /home/fubar/.kodi/addons/script.cu.lrclyrics
09:58:46 T:140702860179200 ERROR: GetDirectory - Error getting -
09:58:46 T:140702348453632 DEBUG: SECTION:LoadDLL(libnfs.so.1)
09:58:46 T:140702348453632 DEBUG: Loading: libnfs.so.1
09:58:46 T:140703480911616 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3A%2F%2Fprofile%2Fplaylists%2Fvideo/special%3A%2F%2Fprofile%2Fplaylists%2Fmixed/)
09:58:46 T:140703480911616 DEBUG: Getting Directory (special://profile/playlists/video)
09:58:46 T:140703480911616 DEBUG: Getting Directory (special://profile/playlists/mixed)
09:58:46 T:140703480911616 DEBUG: CMultiPathDirectory::MergeItems, items = 0
09:58:46 T:140703480911616 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
09:58:46 T:140702348453632 DEBUG: NFS: Context for 192.168.178.20/Multimedia not open - get a new context.
09:58:46 T:140703480911616 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
09:58:46 T:140702348453632 DEBUG: NFS: Connected to server 192.168.178.20 and export /Multimedia
09:58:46 T:140702348453632 DEBUG: NFS: chunks: r/w 32768/32768
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (FUBAR-XBMC) port:8080
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::addService() named: Kodi (FUBAR-XBMC) type: _http._tcp port:8080
09:58:46 T:140703836202752 DEBUG: CPythonInvoker(0, /home/fubar/.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
09:58:46 T:140702860179200 DEBUG: PVR - Create - creating PVR add-on instance 'Tvheadend HTSP Client'
09:58:46 T:140702860179200 DEBUG: ADDON: Dll Initializing - Tvheadend HTSP Client
09:58:46 T:140702860179200 DEBUG: SECTION:LoadDLL(/usr/lib/kodi/addons/pvr.hts/pvr.hts.so.3.4.4)
09:58:46 T:140702860179200 DEBUG: Loading: /usr/lib/kodi/addons/pvr.hts/pvr.hts.so.3.4.4
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (FUBAR-XBMC) port:8080
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::addService() named: Kodi (FUBAR-XBMC) type: _xbmc-jsonrpc-h._tcp port:8080
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (FUBAR-XBMC) port:9777
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::addService() named: Kodi (FUBAR-XBMC) type: _xbmc-events._udp port:9777
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (FUBAR-XBMC) port:9090
09:58:46 T:140702348453632 DEBUG: CZeroconfAvahi::addService() named: Kodi (FUBAR-XBMC) type: _xbmc-jsonrpc._tcp port:9090
09:58:46 T:140703480911616 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
09:58:46 T:140702860179200 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - starting PVR client
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'host'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'htsp_port'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'http_port'
09:58:46 T:140703480911616 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'user'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'pass'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'connect_timeout'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'response_timeout'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'trace_debug'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'epg_async'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'pretuner_enabled'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'autorec_approxtime'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'autorec_maxdiff'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'streaming_profile'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_priority'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_lifetime'
09:58:46 T:140702860179200 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'dvr_dubdetect'
09:58:46 T:140702860179200 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (0 -> 7)
09:58:46 T:140702860179200 DEBUG: PVR - PVRConnectionStateChange - state for connection '192.168.178.20:9982' on client 'Tvheadend HTSP Client' changed from '0' to '7'
09:58:46 T:140704835860224 DEBUG: PVR - ConnectionStateChange - invalid client id
09:58:46 T:140702390417152 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - new connection requested
09:58:46 T:140702390417152 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connecting to 192.168.178.20:9982
09:58:46 T:140702390417152 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected
09:58:46 T:140702365239040 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending hello
09:58:46 T:140702365239040 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected to Tvheadend / 4.1-1648~g9f44f72 (HTSPv25)
09:58:46 T:140702365239040 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending auth
09:58:46 T:140702365239040 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - rebuilding state
09:58:46 T:140702365239040 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - async updates requested
09:58:46 T:140702365239040 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - registered
09:58:46 T:140702365239040 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (7 -> 5)
09:58:46 T:140702365239040 DEBUG: PVR - PVRConnectionStateChange - state for connection '192.168.178.20:9982' on client 'Tvheadend HTSP Client' changed from '7' to '5'
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:3, name:HDTV
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:2, name:TV channels
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:1, name:SDTV
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:230328540, name:ProSieben
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:360699901, name:ZDF HD
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:366887969, name:Das Erste HD
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1078809159, name:PHOENIX HD
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1182706132, name:RTL Television
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1357059505, name:EuroNews
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1616652319, name:SAT.1
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1843196827, name:kabel eins
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:1998295056, name:BBC World
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - channel added id:2101076034, name:CNBC Europe
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:3, name:HDTV
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:2, name:TV channels
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - tag updated id:1, name:SDTV
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1580413004, state:completed, title:Vor Ort: u.a. Regierungserklärung vor dem EU-Gipfel, desc:* Berlin/Bonn: Bo(n)njour Berlin mit Ernst Elitz (Journalist)* Tagesgespräch: Klaus Weber im Gespräch mit Margrethe Vestager (EU-Kommissarin für Arbeit und Wachstum)* Brüssel: Auswirkungen des Brexit-Votums auf die EU, mit Schaltgespräch zu Klaus Weber* London: Rede von David Cameron (Premierminister Großbritannien) während der Parlamentssitzung des Unterhauses am Montag* Brüssel: Sondersitzung des Europäischen Parlaments* Berlin: Regierungserklärung von Bundeskanzlerin Angela Merkel zum bevorstehenden EU-Gipfel nach dem britischen Brexit-Votum mit anschließender Aussprache, error:none
09:58:46 T:140702046480128 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - profile name: htsp, comment: HTSP Default Stream Settings added
09:58:46 T:140702365239040 DEBUG: Thread PVRManager start, auto delete: false
09:58:46 T:140702365239040 DEBUG: PVRManager - Load - active clients found. continue to start
09:58:46 T:140705316055104 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - Load - loading all radio channel groups
09:58:46 T:140702365239040 DEBUG: PVR - Get - group 'All channels' loaded from the database
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - Load - 1 radio groups fetched from the database
09:58:46 T:140702365239040 INFO: PVRChannelGroupInternal - LoadFromDb - no channels in the database
09:58:46 T:140702365239040 DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'All channels'
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - LoadUserDefinedChannelGroups - 0 new user defined radio channel groups fetched from clients
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - PersistAll - persisting all changes in channel groups
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - Load - 1 radio channel groups loaded
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - Load - loading all TV channel groups
09:58:46 T:140702365239040 DEBUG: PVR - Get - group 'All channels' loaded from the database
09:58:46 T:140702365239040 DEBUG: PVR - Get - group 'TV channels' loaded from the database
09:58:46 T:140702365239040 DEBUG: PVR - Get - group 'HDTV' loaded from the database
09:58:46 T:140702365239040 DEBUG: PVR - Get - group 'SDTV' loaded from the database
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - Load - 4 TV groups fetched from the database
09:58:46 T:140702365239040 DEBUG: PVRChannelGroup - Load - 10 channels loaded from the database for group 'All channels'
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - LoadUserDefinedChannelGroups - 0 new user defined TV channel groups fetched from clients
09:58:46 T:140702365239040 DEBUG: PVRChannelGroup - Load - 10 channels loaded from the database for group 'TV channels'
09:58:46 T:140702365239040 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'TV channels' from add-on 'Tvheadend:192.168.178.20:9982'
09:58:46 T:140702365239040 DEBUG: PVRChannelGroup - Load - 3 channels loaded from the database for group 'HDTV'
09:58:46 T:140702365239040 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'HDTV' from add-on 'Tvheadend:192.168.178.20:9982'
09:58:46 T:140702365239040 DEBUG: PVRChannelGroup - Load - 7 channels loaded from the database for group 'SDTV'
09:58:46 T:140702365239040 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'SDTV' from add-on 'Tvheadend:192.168.178.20:9982'
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - PersistAll - persisting all changes in channel groups
09:58:46 T:140702365239040 DEBUG: CPVRChannelGroups - Load - 4 TV channel groups loaded
09:58:46 T:140702365239040 DEBUG: CPVRTimers - Update - updating timers
09:58:46 T:140702365239040 DEBUG: CPVRRecordings - Update - updating recordings
09:58:46 T:140702541387520 DEBUG: Thread PVRGUIInfo start, auto delete: false
09:58:46 T:140702365239040 DEBUG: PVRManager - Process - entering main loop
09:58:46 T:140702860179200 INFO: PVRChannelGroupsContainer - SearchMissingChannelIcons - starting channel icon search
09:58:46 T:140703480911616 DEBUG: RunQuery took 122 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10
09:58:46 T:140705316055104 DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
09:58:46 T:140704835860224 DEBUG: EPG - Load - no database entries found for table 'EuroNews'.
09:58:46 T:140704835860224 DEBUG: EPG - Load - no database entries found for table 'BBC World'.
09:58:46 T:140704835860224 DEBUG: EPG - Load - no database entries found for table 'CNBC Europe'.
09:58:46 T:140701996123904 DEBUG: Thread EPGUpdater start, auto delete: false
09:58:46 T:140704835860224 NOTICE: Start - EPG thread started
09:58:46 T:140701996123904 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'EuroNews' from client '2025648334'
09:58:46 T:140701996123904 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'BBC World' from client '2025648334'
09:58:46 T:140701996123904 DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'CNBC Europe' from client '2025648334'
09:58:46 T:140702365239040 DEBUG: CPVRTimers - Update - updating timers
09:58:46 T:140703819417344 DEBUG: -->Python Interpreter Initialized<--
09:58:46 T:140703819417344 DEBUG: CPythonInvoker(2, /home/fubar/.kodi/addons/script.grab.fanart/service.py): the source file to load is "/home/fubar/.kodi/addons/script.grab.fanart/service.py"
09:58:46 T:140705316055104 DEBUG: ------ Window Deinit (DialogExtendedProgressBar.xml) ------
09:58:46 T:140703480911616 DEBUG: RunQuery took 377 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10
09:58:46 T:140703480911616 DEBUG: RunQuery took 3 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10
09:58:46 T:140703480911616 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
09:58:46 T:140703480911616 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist ) OR artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist WHERE song_artist.idRole = 1)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
09:58:46 T:140702851786496 DEBUG: -->Python Interpreter Initialized<--
09:58:46 T:140702851786496 DEBUG: CPythonInvoker(10, /home/fubar/.kodi/addons/script.common.plugin.cache/default.py): the source file to load is "/home/fubar/.kodi/addons/script.common.plugin.cache/default.py"
09:58:47 T:140703819417344 DEBUG: CPythonInvoker(2, /home/fubar/.kodi/addons/script.grab.fanart/service.py): setting the Python path to /home/fubar/.kodi/addons/script.grab.fanart:/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
09:58:47 T:140703819417344 DEBUG: CPythonInvoker(2, /home/fubar/.kodi/addons/script.grab.fanart/service.py): entering source directory /home/fubar/.kodi/addons/script.grab.fanart
09:58:47 T:140703819417344 DEBUG: CPythonInvoker(2, /home/fubar/.kodi/addons/script.grab.fanart/service.py): instantiating addon using automatically obtained id of "script.grab.fanart" dependent on version 2.19.0 of the xbmc.python api
09:58:47 T:140702835001088 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
09:58:48 T:140703497697024 DEBUG: Previous line repeats 3 times.
09:58:48 T:140703497697024 DEBUG: -->Python Interpreter Initialized<--
09:58:48 T:140703497697024 DEBUG: CPythonInvoker(8, /home/fubar/.kodi/addons/service.skin.widgets/default.py): the source file to load is "/home/fubar/.kodi/addons/service.skin.widgets/default.py"
09:58:48 T:140703514482432 DEBUG: CPythonInvoker(6, /home/fubar/.kodi/addons/script.tv.show.next.aired/service.py): setting the Python path to /home/fubar/.kodi/addons/script.tv.show.next.aired:/home/fubar/.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
09:58:48 T:140703514482432 DEBUG: CPythonInvoker(6, /home/fubar/.kodi/addons/script.tv.show.next.aired/service.py): entering source directory /home/fubar/.kodi/addons/script.tv.show.next.aired
09:58:48 T:140703531267840 DEBUG: -->Python Interpreter Initialized<--
09:58:48 T:140703531267840 DEBUG: CPythonInvoker(4, /home/fubar/.kodi/addons/script.cinema.experience/service.py): the source file to load is "/home/fubar/.kodi/addons/script.cinema.experience/service.py"
09:58:48 T:140703514482432 DEBUG: CPythonInvoker(6, /home/fubar/.kodi/addons/script.tv.show.next.aired/service.py): instantiating addon using automatically obtained id of "script.tv.show.next.aired" dependent on version 2.1.0 of the xbmc.python api
09:58:48 T:140703827810048 DEBUG: -->Python Interpreter Initialized<--
09:58:48 T:140703827810048 DEBUG: CPythonInvoker(1, /home/fubar/.kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/fubar/.kodi/addons/service.xbmc.versioncheck/service.py"
09:58:48 T:140703506089728 DEBUG: -->Python Interpreter Initialized<--
09:58:48 T:140703506089728 DEBUG: CPythonInvoker(7, /home/fubar/.kodi/addons/script.tvtunes/service.py): the source file to load is "/home/fubar/.kodi/addons/script.tvtunes/service.py"
09:58:48 T:140702851786496 DEBUG: CPythonInvoker(10, /home/fubar/.kodi/addons/script.common.plugin.cache/default.py): setting the Python path to /home/fubar/.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
09:58:48 T:140702851786496 DEBUG: CPythonInvoker(10, /home/fubar/.kodi/addons/script.common.plugin.cache/default.py): entering source directory /home/fubar/.kodi/addons/script.common.plugin.cache
09:58:48 T:140702851786496 DEBUG: CPythonInvoker(10, /home/fubar/.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
09:58:48 T:140703489304320 DEBUG: -->Python Interpreter Initialized<--
09:58:48 T:140703489304320 DEBUG: CPythonInvoker(9, /home/fubar/.kodi/addons/script.module.simple.downloader/default.py): the source file to load is "/home/fubar/.kodi/addons/script.module.simple.downloader/default.py"
09:58:48 T:140703811024640 DEBUG: -->Python Interpreter Initialized<--
09:58:48 T:140703811024640 DEBUG: CPythonInvoker(3, /home/fubar/.kodi/addons/service.library.data.provider/service.py): the source file to load is "/home/fubar/.kodi/addons/service.library.data.provider/service.py"
09:58:48 T:140703514482432 DEBUG: script.tv.show.next.aired: ### params: {'service': 'true'}
09:58:48 T:140703514482432 NOTICE: script.tv.show.next.aired: ### TV Show - Next Aired starting background proc (6.0.15)
09:58:48 T:140703827810048 DEBUG: CPythonInvoker(1, /home/fubar/.kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/fubar/.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
09:58:48 T:140703827810048 DEBUG: CPythonInvoker(1, /home/fubar/.kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /home/fubar/.kodi/addons/service.xbmc.versioncheck
09:58:48 T:140703827810048 DEBUG: CPythonInvoker(1, /home/fubar/.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
09:58:49 T:140703489304320 DEBUG: CPythonInvoker(9, /home/fubar/.kodi/addons/script.module.simple.downloader/default.py): setting the Python path to /home/fubar/.kodi/addons/script.module.simple.downloader:/home/fubar/.kodi/addons/script.common.plugin.cache/lib:/home/fubar/.kodi/addons/script.module.parsedom/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
09:58:49 T:140703489304320 DEBUG: CPythonInvoker(9, /home/fubar/.kodi/addons/script.module.simple.downloader/default.py): entering source directory /home/fubar/.kodi/addons/script.module.simple.downloader
09:58:49 T:140703489304320 DEBUG: CPythonInvoker(9, /home/fubar/.kodi/addons/script.module.simple.downloader/default.py): instantiating addon using automatically obtained id of "script.module.simple.downloader" dependent on version 2.1.0 of the xbmc.python api
09:58:49 T:140703531267840 DEBUG: CPythonInvoker(4, /home/fubar/.kodi/addons/script.cinema.experience/service.py): setting the Python path to /home/fubar/.kodi/addons/script.cinema.experience:/home/fubar/.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
09:58:49 T:140703531267840 DEBUG: CPythonInvoker(4, /home/fubar/.kodi/addons/script.cinema.experience/service.py): entering source directory /home/fubar/.kodi/addons/script.cinema.experience
09:58:49 T:140703522875136 NOTICE: Skin Helper Service --> skin helper service version 1.0.90 started
09:58:49 T:140703531267840 DEBUG: CPythonInvoker(4, /home/fubar/.kodi/addons/script.cinema.experience/service.py): instantiating addon using automatically obtained id of "script.cinema.experience" dependent on version 2.12.0 of the xbmc.python api
09:58:49 T:140703522875136 NOTICE: Skin Helper Service --> WebService - start helper webservice on port 52307
09:58:49 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:58:49 T:140703514482432 DEBUG: RunQuery took 110 ms for 95 items query: SELECT * FROM tvshow_view
09:58:50 T:140703811024640 DEBUG: CPythonInvoker(3, /home/fubar/.kodi/addons/service.library.data.provider/service.py): setting the Python path to /home/fubar/.kodi/addons/service.library.data.provider:/home/fubar/.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
09:58:50 T:140703811024640 DEBUG: CPythonInvoker(3, /home/fubar/.kodi/addons/service.library.data.provider/service.py): entering source directory /home/fubar/.kodi/addons/service.library.data.provider
09:58:50 T:140703506089728 DEBUG: CPythonInvoker(7, /home/fubar/.kodi/addons/script.tvtunes/service.py): setting the Python path to /home/fubar/.kodi/addons/script.tvtunes:/home/fubar/.kodi/addons/script.module.beautifulsoup/lib:/home/fubar/.kodi/addons/script.module.requests/lib:/home/fubar/.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
09:58:50 T:140703506089728 DEBUG: CPythonInvoker(7, /home/fubar/.kodi/addons/script.tvtunes/service.py): entering source directory /home/fubar/.kodi/addons/script.tvtunes
09:58:50 T:140703811024640 DEBUG: CPythonInvoker(3, /home/fubar/.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
09:58:50 T:140703827810048 DEBUG: Version Check: Version 0.3.19 started
09:58:50 T:140703506089728 DEBUG: CPythonInvoker(7, /home/fubar/.kodi/addons/script.tvtunes/service.py): instantiating addon using automatically obtained id of "script.tvtunes" dependent on version 2.20.0 of the xbmc.python api
09:58:50 T:140703819417344 NOTICE: script.grab.fanart: Grab Fanart Service Started
09:58:50 T:140703819417344 DEBUG: script.grab.fanart: media type is: random
09:58:50 T:140703497697024 DEBUG: CPythonInvoker(8, /home/fubar/.kodi/addons/service.skin.widgets/default.py): setting the Python path to /home/fubar/.kodi/addons/service.skin.widgets:/home/fubar/.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
09:58:50 T:140703497697024 DEBUG: CPythonInvoker(8, /home/fubar/.kodi/addons/service.skin.widgets/default.py): entering source directory /home/fubar/.kodi/addons/service.skin.widgets
09:58:50 T:140703497697024 DEBUG: CPythonInvoker(8, /home/fubar/.kodi/addons/service.skin.widgets/default.py): instantiating addon using automatically obtained id of "service.skin.widgets" dependent on version 2.14.0 of the xbmc.python api
09:58:50 T:140703819417344 DEBUG: RunQuery took 96 ms for 595 items query: select * from movie_view
09:58:50 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:58:51 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:58:51 T:140703819417344 DEBUG: script.grab.fanart: found 592 movies files
09:58:51 T:140703819417344 DEBUG: RunQuery took 94 ms for 95 items query: SELECT * FROM tvshow_view
09:58:51 T:140702860179200 DEBUG: DoWork - took 130 ms to load special://masterprofile/Thumbnails/6/6defef16.jpg
09:58:51 T:140702348453632 DEBUG: DoWork - took 138 ms to load special://masterprofile/Thumbnails/7/7e3307d1.jpg
09:58:51 T:140703480911616 DEBUG: DoWork - took 184 ms to load special://masterprofile/Thumbnails/8/805b00fb.jpg
09:58:51 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:58:51 T:140703819417344 DEBUG: script.grab.fanart: found 95 tv files
09:58:51 T:140703819417344 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist ) OR artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist WHERE song_artist.idRole = 1)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
09:58:51 T:140704835860224 DEBUG: DoWork - took 259 ms to load special://masterprofile/Thumbnails/e/ea712684.jpg
09:58:52 T:140703819417344 DEBUG: Time to retrieve artists from dataset = 245
09:58:52 T:140703531267840 DEBUG: [Cinema Experience] - settings() - __init__
09:58:52 T:140703531267840 DEBUG: [Cinema Experience] - settings() - start
09:58:52 T:140703531267840 DEBUG: [Cinema Experience] - Reading settings.xml
09:58:52 T:140703811024640 DEBUG: Library Data Provider: service version 0.1.6 started
09:58:52 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:58:52 T:140702860179200 DEBUG: CNFSFile::Open - opened Multimedia/Music/05 Cry Me a River.mp3
09:58:52 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:58:52 T:140703531267840 DEBUG: [Cinema Experience] - Reading settings.xml
09:58:52 T:140704835860224 DEBUG: CNFSFile::Open - opened Multimedia/Music/08 Mercedes Benz.mp3
09:58:52 T:140702348453632 DEBUG: CNFSFile::Open - opened Multimedia/Music/07 A Woman's Threat.mp3
09:58:52 T:140703811024640 DEBUG: RunQuery took 60 ms for 9 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
09:58:52 T:140702860179200 DEBUG: unrecognized ID3 frame detected: TSOP
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:BARCODE
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:ALBUMARTISTSORT
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Type
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:ASIN
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Status
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:CATALOGNUMBER
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Release Country
09:58:52 T:140702860179200 DEBUG: unrecognized user text tag detected: TXXX:rating
09:58:52 T:140702860179200 DEBUG: CNFSFile::Close closing file Multimedia/Music/05 Cry Me a River.mp3
09:58:52 T:140702860179200 DEBUG: Caching image 'nfs://192.168.178.20/Multimedia/Music/05 Cry Me a River.mp3' to '9/9d498bed.jpg':
09:58:52 T:140702860179200 DEBUG: cached image 'special://masterprofile/Thumbnails/9/9d498bed.jpg' size 450x450
09:58:52 T:140702348453632 DEBUG: unrecognized ID3 frame detected: TSOP
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:ALBUMARTISTSORT
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Type
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:BARCODE
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:ASIN
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:CATALOGNUMBER
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Release Country
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Status
09:58:52 T:140702348453632 DEBUG: unrecognized user text tag detected: TXXX:rating
09:58:52 T:140702348453632 DEBUG: CNFSFile::Close closing file Multimedia/Music/07 A Woman's Threat.mp3
09:58:52 T:140702348453632 DEBUG: Caching image 'nfs://192.168.178.20/Multimedia/Music/07 A Woman's Threat.mp3' to 'e/eacff155.jpg':
09:58:52 T:140702348453632 DEBUG: cached image 'special://masterprofile/Thumbnails/e/eacff155.jpg' size 170x170
09:58:52 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:58:52 T:140704835860224 DEBUG: unrecognized ID3 frame detected: TSOP
09:58:52 T:140704835860224 DEBUG: unrecognized user text tag detected: TXXX:ALBUMARTISTSORT
09:58:52 T:140704835860224 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Type
09:58:52 T:140704835860224 DEBUG: unrecognized user text tag detected: TXXX:ASIN
09:58:52 T:140704835860224 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Status
09:58:52 T:140704835860224 DEBUG: unrecognized user text tag detected: TXXX:CATALOGNUMBER
09:58:52 T:140704835860224 DEBUG: unrecognized user text tag detected: TXXX:MusicBrainz Album Release Country
09:58:52 T:140704835860224 DEBUG: unrecognized user text tag detected: TXXX:rating
09:58:52 T:140704835860224 DEBUG: CNFSFile::Close closing file Multimedia/Music/08 Mercedes Benz.mp3
09:58:52 T:140704835860224 DEBUG: Caching image 'nfs://192.168.178.20/Multimedia/Music/08 Mercedes Benz.mp3' to 'b/bc8596b1.jpg':
09:58:52 T:140704835860224 DEBUG: cached image 'special://masterprofile/Thumbnails/b/bc8596b1.jpg' size 439x450
09:58:53 T:140703811024640 DEBUG: RunQuery took 40 ms for 14 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
09:58:53 T:140702851786496 DEBUG: StorageServer Module loaded RUN
09:58:53 T:140702851786496 DEBUG: StorageClient-2.5.4 Starting server
09:58:53 T:140703811024640 DEBUG: GetSongsFullByWhere query = SELECT songview.*, songartistview.* FROM songview JOIN songartistview ON songartistview.idsong = songview.idsong WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
09:58:53 T:140703819417344 DEBUG: script.grab.fanart: found 0 music files
09:58:53 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:58:53 T:140705316055104 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
09:58:53 T:140703531267840 DEBUG: [Cinema Experience] - Cinema Experience service script version 4.0.11 started
09:58:53 T:140703531267840 DEBUG: [Cinema Experience] - Reading settings.xml
09:58:53 T:140703811024640 DEBUG: GetSongsFullByWhere() - took 645 ms
09:58:53 T:140703531267840 DEBUG: [Cinema Experience] - settings() - start
09:58:53 T:140703531267840 DEBUG: [Cinema Experience] - Reading settings.xml
09:58:53 T:140703489304320 INFO: CPythonInvoker(9, /home/fubar/.kodi/addons/script.module.simple.downloader/default.py): script successfully run
09:58:53 T:140703497697024 DEBUG: Skin Widgets: script version 0.0.32 started
09:58:54 T:140703489304320 INFO: Python script stopped
09:58:54 T:140703489304320 DEBUG: Thread LanguageInvoker 140703489304320 terminating
09:58:54 T:140703811024640 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
09:58:54 T:140703489304320 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:54 T:140703489304320 INFO: initializing python engine.
09:58:54 T:140703489304320 DEBUG: CPythonInvoker(11, /home/fubar/.kodi/addons/script.tvtunes/resources/lib/upload.py): start processing
09:58:54 T:140703497697024 DEBUG: RunQuery took 38 ms for 9 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
09:58:54 T:140703811024640 DEBUG: GetAlbumsByWhere - query took 158 ms
09:58:54 T:140703506089728 ERROR: Window Translator: Can't find window videolibrary
09:58:54 T:140703489304320 DEBUG: -->Python Interpreter Initialized<--
09:58:54 T:140703489304320 DEBUG: CPythonInvoker(11, /home/fubar/.kodi/addons/script.tvtunes/resources/lib/upload.py): the source file to load is "/home/fubar/.kodi/addons/script.tvtunes/resources/lib/upload.py"
09:58:54 T:140703489304320 WARNING: CPythonInvoker(11): Script invoked without an addon. Adding all addon modules installed to python path as fallback. This behaviour will be removed in future version.
09:58:54 T:140703489304320 DEBUG: CPythonInvoker(11, /home/fubar/.kodi/addons/script.tvtunes/resources/lib/upload.py): setting the Python path to /home/fubar/.kodi/addons/script.tvtunes/resources/lib:/home/fubar/.kodi/addons/script.module.pydes/lib:/home/fubar/.kodi/addons/script.module.beautifulsoup4/lib:/home/fubar/.kodi/addons/script.module.myconnpy/lib:/home/fubar/.kodi/addons/script.module.pyxbmct/lib:/home/fubar/.kodi/addons/script.module.beautifulsoup/lib:/home/fubar/.kodi/addons/script.common.plugin.cache/lib:/home/fubar/.kodi/addons/script.module.pytz/lib:/home/fubar/.kodi/addons/script.module.actionhandler/lib:/home/fubar/.kodi/addons/script.module.t9.search/lib:/home/fubar/.kodi/addons/script.module.mechanize/lib:/home/fubar/.kodi/addons/script.module.xbmcswift2/lib:/home/fubar/.kodi/addons/script.module.requests2/lib:/home/fubar/.kodi/addons/script.module.addon.signals/lib:/home/fubar/.kodi/addons/script.module.autocompletion/lib:/home/fubar/.kodi/addons/script.module.chardet/lib:/home/fubar/.kodi/addons/script.module.youtube.dl/lib:/home/fubar/.kodi/addons/script.module.simplejson/lib:/home/fubar/.kodi/addons/script.module.addon.common/lib:/home/fubar/.kodi/addons/script.tvtunes/resources/lib:/usr/share/kodi/addons/script.module.pil/lib:/home/fubar/.kodi/addons/script.module.unidecode/lib:/home/fubar/.kodi/addons/script.module.simple.downloader/lib:/home/fubar/.kodi/addons/script.module.requests/lib:/home/fubar/.kodi/addons/script.module.parsedom/lib:/home/fubar/.kodi/addons/script.module.elementtree/lib:/home/fubar/.kodi/addons/script.module.metahandler/lib:/home/fubar/.kodi/addons/script.module.kodi65/lib:/home/fubar/.kodi/addons/script.module.routing/lib:/home/fubar/.kodi/addons/script.mimic.extras/addon.py:/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
09:58:54 T:140703489304320 DEBUG: CPythonInvoker(11, /home/fubar/.kodi/addons/script.tvtunes/resources/lib/upload.py): entering source directory /home/fubar/.kodi/addons/script.tvtunes/resources/lib
09:58:54 T:140703811024640 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1))
09:58:54 T:140703811024640 DEBUG: RunQuery took 20 ms for 9 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
09:58:54 T:140705316055104 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
09:58:54 T:140705316055104 DEBUG: Activating window ID: 10025
09:58:55 T:140705316055104 DEBUG: ------ Window Deinit (Home.xml) ------
09:58:55 T:140705316055104 DEBUG: ------ Window Init (MyVideoNav.xml) ------
09:58:55 T:140705316055104 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
09:58:55 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:58:55 T:140705316055104 DEBUG: CDirectoryProvider[]: refreshing..
09:58:55 T:140704835860224 ERROR: GetDirectory - Error getting
09:58:55 T:140705316055104 DEBUG: CDirectoryProvider[]: refreshing..
09:58:55 T:140703480911616 ERROR: GetDirectory - Error getting
09:58:55 T:140705316055104 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.amazon-test/)
09:58:55 T:140705316055104 DEBUG: ParentPath = [plugin://plugin.video.amazon-test/]
09:58:55 T:140705316055104 DEBUG: Loading items: 5, directory: plugin://plugin.video.amazon-test/ sort method: 0, ascending: false
09:58:55 T:140702860179200 DEBUG: CAddonDatabase::SetLastUsed[plugin.video.amazon-test] took 13 ms
09:58:55 T:140702054872832 DEBUG: Thread BackgroundLoader start, auto delete: false
09:58:55 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:55 T:140702054872832 DEBUG: Thread BackgroundLoader 140702054872832 terminating
09:58:55 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:55 T:140703811024640 DEBUG: Previous line repeats 5 times.
09:58:55 T:140703811024640 DEBUG: RunQuery took 36 ms for 14 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
09:58:55 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:55 T:140703497697024 DEBUG: RunQuery took 45 ms for 14 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
09:58:55 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:58:55 T:140703811024640 DEBUG: Previous line repeats 8 times.
09:58:55 T:140703811024640 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
09:58:55 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: Previous line repeats 1 times.
09:58:56 T:140703811024640 DEBUG: GetAlbumsByWhere - query took 92 ms
09:58:56 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703827810048 DEBUG: Previous line repeats 7 times.
09:58:56 T:140703827810048 DEBUG: Version Check: Version installed {u'major': 17, u'tag': u'alpha', u'tagversion': u'3', u'minor': 0, u'revision': u'fc46cf2'}
09:58:56 T:140703827810048 INFO: CPythonInvoker(1, /home/fubar/.kodi/addons/service.xbmc.versioncheck/service.py): script successfully run
09:58:56 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703827810048 DEBUG: Previous line repeats 2 times.
09:58:56 T:140703827810048 INFO: Python script stopped
09:58:56 T:140703827810048 DEBUG: Thread LanguageInvoker 140703827810048 terminating
09:58:56 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1))
09:58:56 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: RunQuery took 11 ms for 20 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
09:58:56 T:140703497697024 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view
09:58:56 T:140703497697024 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
09:58:56 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703497697024 DEBUG: Previous line repeats 4 times.
09:58:56 T:140703497697024 DEBUG: GetAlbumsByWhere - query took 133 ms
09:58:56 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703497697024 DEBUG: Previous line repeats 3 times.
09:58:56 T:140703497697024 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist ) OR artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist WHERE song_artist.idRole = 1)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
09:58:56 T:140705316055104 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
09:58:56 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: Previous line repeats 1 times.
09:58:56 T:140703811024640 DEBUG: RunQuery took 89 ms for 4 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))))
09:58:56 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: RunQuery took 3 ms for 7 items query: select * from episode_view WHERE (episode_view.idShow = 122) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
09:58:56 T:140703811024640 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:56 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703497697024 DEBUG: Time to retrieve artists from dataset = 125
09:58:56 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: RunQuery took 4 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 19) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
09:58:56 T:140703811024640 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:56 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: Previous line repeats 1 times.
09:58:56 T:140703811024640 DEBUG: RunQuery took 8 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 105) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
09:58:56 T:140703811024640 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:56 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:58:56 T:140703497697024 DEBUG: GetSongsFullByWhere query = SELECT songview.*, songartistview.* FROM songview JOIN songartistview ON songartistview.idsong = songview.idsong WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
09:58:56 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:56 T:140703811024640 DEBUG: RunQuery took 6 ms for 5 items query: select * from episode_view WHERE (episode_view.idShow = 18) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
09:58:56 T:140703811024640 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:57 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:57 T:140703811024640 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
09:58:57 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:57 T:140703811024640 DEBUG: Previous line repeats 2 times.
09:58:57 T:140703811024640 DEBUG: GetAlbumsByWhere - query took 107 ms
09:58:57 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:57 T:140703811024640 DEBUG: Previous line repeats 4 times.
09:58:57 T:140703811024640 DEBUG: CFavourites::Load - no system favourites found, skipping
09:58:57 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:57 T:140703811024640 DEBUG: Library Data Provider: Total time needed for all queries: 0:00:05.063910
09:58:57 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:57 T:140703497697024 DEBUG: Previous line repeats 5 times.
09:58:57 T:140703497697024 DEBUG: GetSongsFullByWhere() - took 573 ms
09:58:57 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:58:57 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:58:57 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:57 T:140705316055104 DEBUG: Previous line repeats 8 times.
09:58:57 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:58:57 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: Previous line repeats 3 times.
09:58:58 T:140703497697024 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:03.899683
09:58:58 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: RunQuery took 6 ms for 20 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
09:58:58 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: Previous line repeats 5 times.
09:58:58 T:140703497697024 DEBUG: RunQuery took 70 ms for 4 items query: SELECT * FROM tvshow_view WHERE ( ((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))))
09:58:58 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: RunQuery took 3 ms for 7 items query: select * from episode_view WHERE (episode_view.idShow = 122) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
09:58:58 T:140703497697024 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:58 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: RunQuery took 3 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 19) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
09:58:58 T:140703497697024 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:58 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: RunQuery took 2 ms for 1 items query: select * from episode_view WHERE (episode_view.idShow = 105) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
09:58:58 T:140703497697024 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:58 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: RunQuery took 4 ms for 5 items query: select * from episode_view WHERE (episode_view.idShow = 18) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
09:58:58 T:140703497697024 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
09:58:58 T:140703497697024 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
09:58:58 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140701982979840 DEBUG: Previous line repeats 2 times.
09:58:58 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:58:58 T:140703497697024 DEBUG: GetAlbumsByWhere - query took 92 ms
09:58:58 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: Previous line repeats 5 times.
09:58:58 T:140703497697024 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view
09:58:58 T:140703497697024 DEBUG: Skin Widgets: Total time needed to request recommended queries: 0:00:00.624318
09:58:58 T:140703497697024 DEBUG: RunQuery took 17 ms for 9 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
09:58:58 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140703497697024 DEBUG: Previous line repeats 2 times.
09:58:58 T:140703497697024 DEBUG: RunQuery took 23 ms for 14 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
09:58:58 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:58 T:140705316055104 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
09:58:58 T:140705316055104 DEBUG: Clearing cached fileitems [plugin://plugin.video.amazon-test/?cat=Browse&mode=listCategories&opt=30143&page=1&url=020]
09:58:58 T:140703497697024 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view
09:58:58 T:140703497697024 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
09:58:58 T:140703497697024 DEBUG: GetAlbumsByWhere - query took 88 ms
09:58:58 T:140705316055104 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.amazon-test/?cat=Browse&mode=listCategories&opt=30143&page=1&url=020)
09:58:58 T:140705316055104 DEBUG: ParentPath = [plugin://plugin.video.amazon-test/]
09:58:58 T:140702348453632 DEBUG: CAddonDatabase::SetLastUsed[plugin.video.amazon-test] took 11 ms
09:58:58 T:140704835860224 DEBUG: StartScript - calling plugin Amazon VOD('plugin://plugin.video.amazon-test/','1','?cat=Browse&mode=listCategories&opt=30143&page=1&url=020')
09:58:58 T:140703827810048 DEBUG: Thread LanguageInvoker start, auto delete: false
09:58:58 T:140703827810048 INFO: initializing python engine.
09:58:58 T:140703827810048 DEBUG: CPythonInvoker(12, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): start processing
09:58:59 T:140705316055104 DEBUG: ------ Window Init (DialogBusy.xml) ------
09:58:59 T:140702348453632 DEBUG: GetImageHash - unable to stat url
09:58:59 T:140703827810048 DEBUG: Previous line repeats 10 times.
09:58:59 T:140703827810048 DEBUG: -->Python Interpreter Initialized<--
09:58:59 T:140703827810048 DEBUG: CPythonInvoker(12, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): the source file to load is "/home/fubar/.kodi/addons/plugin.video.amazon-test/default.py"
09:58:59 T:140703827810048 DEBUG: CPythonInvoker(12, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): setting the Python path to /home/fubar/.kodi/addons/plugin.video.amazon-test:/home/fubar/.kodi/addons/script.module.beautifulsoup/lib:/home/fubar/.kodi/addons/script.module.mechanize/lib:/home/fubar/.kodi/addons/script.module.pydes/lib:/home/fubar/.kodi/addons/script.module.pyxbmct/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
09:58:59 T:140703827810048 DEBUG: CPythonInvoker(12, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): entering source directory /home/fubar/.kodi/addons/plugin.video.amazon-test
09:58:59 T:140703827810048 DEBUG: CPythonInvoker(12, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): instantiating addon using automatically obtained id of "plugin.video.amazon-test" dependent on version 2.1.0 of the xbmc.python api
09:58:59 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:58:59 T:140701982979840 DEBUG: Previous line repeats 6 times.
09:58:59 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:00 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:59:00 T:140702054872832 DEBUG: Previous line repeats 20 times.
09:59:00 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124740816
09:59:00 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124740823
09:59:00 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:59:01 T:140701982979840 DEBUG: Previous line repeats 24 times.
09:59:01 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:01 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:59:01 T:140703497697024 DEBUG: Previous line repeats 4 times.
09:59:01 T:140703497697024 DEBUG: Skin Widgets: Total time needed to request recent items queries: 0:00:03.062358
09:59:01 T:140703497697024 DEBUG: Skin Widgets: Total time needed for all queries: 0:00:07.587109
09:59:01 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:59:01 T:140703827810048 NOTICE: [Amazon VOD] [('cat', 'Browse'), ('mode', 'listCategories'), ('opt', '30143'), ('page', '1'), ('url', '020')]
09:59:01 T:140702860179200 DEBUG: GetImageHash - unable to stat url
09:59:01 T:140703827810048 INFO: CPythonInvoker(12, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): script successfully run
09:59:01 T:140705316055104 DEBUG: Saving fileitems [plugin://plugin.video.amazon-test/?cat=Browse&mode=listCategories&opt=30143&page=1&url=020]
09:59:01 T:140705316055104 DEBUG: -- items: 9, sort method: 0, ascending: false
09:59:01 T:140701974587136 DEBUG: Thread BackgroundLoader start, auto delete: false
09:59:01 T:140701974587136 DEBUG: Thread BackgroundLoader 140701974587136 terminating
09:59:02 T:140705316055104 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
09:59:02 T:140703827810048 INFO: Python script stopped
09:59:02 T:140703827810048 DEBUG: Thread LanguageInvoker 140703827810048 terminating
09:59:03 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:03 T:140705316055104 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
09:59:04 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:05 T:140705316055104 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
09:59:05 T:140705316055104 DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.amazon-test/?cat=Browse&mode=listContent&opt&page=1&url=OrderBy%3DTitle%26OfferGroups%3DB0043YVHMY%26contentType%3Dmovie)
09:59:05 T:140705316055104 DEBUG: ParentPath = [plugin://plugin.video.amazon-test/?cat=Browse&mode=listCategories&opt=30143&page=1&url=020]
09:59:05 T:140702860179200 DEBUG: CAddonDatabase::SetLastUsed[plugin.video.amazon-test] took 10 ms
09:59:05 T:140702348453632 DEBUG: StartScript - calling plugin Amazon VOD('plugin://plugin.video.amazon-test/','2','?cat=Browse&mode=listContent&opt&page=1&url=OrderBy%3DTitle%26OfferGroups%3DB0043YVHMY%26contentType%3Dmovie')
09:59:05 T:140703827810048 DEBUG: Thread LanguageInvoker start, auto delete: false
09:59:05 T:140703827810048 INFO: initializing python engine.
09:59:05 T:140703827810048 DEBUG: CPythonInvoker(13, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): start processing
09:59:05 T:140705316055104 DEBUG: ------ Window Init (DialogBusy.xml) ------
09:59:05 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124745816
09:59:05 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124745820
09:59:05 T:140703827810048 DEBUG: -->Python Interpreter Initialized<--
09:59:05 T:140703827810048 DEBUG: CPythonInvoker(13, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): the source file to load is "/home/fubar/.kodi/addons/plugin.video.amazon-test/default.py"
09:59:05 T:140703827810048 DEBUG: CPythonInvoker(13, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): setting the Python path to /home/fubar/.kodi/addons/plugin.video.amazon-test:/home/fubar/.kodi/addons/script.module.beautifulsoup/lib:/home/fubar/.kodi/addons/script.module.mechanize/lib:/home/fubar/.kodi/addons/script.module.pydes/lib:/home/fubar/.kodi/addons/script.module.pyxbmct/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
09:59:05 T:140703827810048 DEBUG: CPythonInvoker(13, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): entering source directory /home/fubar/.kodi/addons/plugin.video.amazon-test
09:59:05 T:140703827810048 DEBUG: CPythonInvoker(13, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): instantiating addon using automatically obtained id of "plugin.video.amazon-test" dependent on version 2.1.0 of the xbmc.python api
09:59:06 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:07 T:140703827810048 DEBUG: Previous line repeats 1 times.
09:59:07 T:140703827810048 NOTICE: [Amazon VOD] [('cat', 'Browse'), ('mode', 'listContent'), ('page', '1'), ('url', 'OrderBy=Title&OfferGroups=B0043YVHMY&contentType=movie')]
09:59:07 T:140703827810048 NOTICE: [Amazon VOD] getURL: https://atv-eu.amazon.com/cdp/catalog/Browse?firmware=fmw:17-app:2.0.45.1210&deviceTypeID=A2M4YX06LWP8WI&deviceID=493adf63af3edfcef3b52d3b9859fd7a1178e2b22865b8ee46e14ee3&format=json&version=2&formatVersion=3&marketplaceId=A1PA6795UKMFR9&IncludeAll=T&AID=T&OrderBy=Title&OfferGroups=B0043YVHMY&contentType=movie&NumberOfResults=40&StartIndex=0&Detailed=T
09:59:09 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:10 T:140701974587136 DEBUG: CFavourites::Load - no system favourites found, skipping
09:59:10 T:140701974587136 DEBUG: RunQuery took 51 ms for 226 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet
09:59:10 T:140701974587136 DEBUG: RunQuery took 13 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=1 ORDER BY sets.idSet
09:59:10 T:140701974587136 DEBUG: RunQuery took 89 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 1
09:59:10 T:140701974587136 DEBUG: RunQuery took 10 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=2 ORDER BY sets.idSet
09:59:10 T:140701974587136 DEBUG: RunQuery took 90 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 2
09:59:10 T:140701974587136 DEBUG: RunQuery took 8 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=3 ORDER BY sets.idSet
09:59:10 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:10 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124750817
09:59:10 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124750821
09:59:10 T:140701974587136 DEBUG: RunQuery took 94 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 3
09:59:10 T:140701974587136 DEBUG: RunQuery took 9 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=4 ORDER BY sets.idSet
09:59:10 T:140701974587136 DEBUG: RunQuery took 90 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 4
09:59:10 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=5 ORDER BY sets.idSet
09:59:11 T:140701974587136 DEBUG: RunQuery took 89 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 5
09:59:11 T:140701974587136 DEBUG: RunQuery took 9 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=6 ORDER BY sets.idSet
09:59:11 T:140701974587136 DEBUG: RunQuery took 90 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 6
09:59:11 T:140701974587136 DEBUG: RunQuery took 10 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=7 ORDER BY sets.idSet
09:59:11 T:140701974587136 DEBUG: RunQuery took 98 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 7
09:59:11 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=8 ORDER BY sets.idSet
09:59:11 T:140701974587136 DEBUG: RunQuery took 92 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 8
09:59:11 T:140701974587136 DEBUG: RunQuery took 9 ms for 6 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=9 ORDER BY sets.idSet
09:59:11 T:140701974587136 DEBUG: RunQuery took 90 ms for 6 items query: select * from movie_view WHERE movie_view.idSet = 9
09:59:11 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=10 ORDER BY sets.idSet
09:59:11 T:140701974587136 DEBUG: RunQuery took 94 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 10
09:59:11 T:140701974587136 DEBUG: RunQuery took 15 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=11 ORDER BY sets.idSet
09:59:11 T:140701974587136 DEBUG: RunQuery took 113 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 11
09:59:11 T:140701974587136 DEBUG: RunQuery took 11 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=12 ORDER BY sets.idSet
09:59:12 T:140701974587136 DEBUG: RunQuery took 113 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 12
09:59:12 T:140701974587136 DEBUG: RunQuery took 10 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=13 ORDER BY sets.idSet
09:59:12 T:140701974587136 DEBUG: RunQuery took 117 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 13
09:59:12 T:140701974587136 DEBUG: RunQuery took 11 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=14 ORDER BY sets.idSet
09:59:12 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:12 T:140701974587136 DEBUG: RunQuery took 90 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 14
09:59:12 T:140701974587136 DEBUG: RunQuery took 8 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=15 ORDER BY sets.idSet
09:59:12 T:140701974587136 DEBUG: RunQuery took 102 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 15
09:59:12 T:140701974587136 DEBUG: RunQuery took 10 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=16 ORDER BY sets.idSet
09:59:12 T:140701974587136 DEBUG: RunQuery took 99 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 16
09:59:12 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=17 ORDER BY sets.idSet
09:59:12 T:140701974587136 DEBUG: RunQuery took 108 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 17
09:59:12 T:140701974587136 DEBUG: RunQuery took 11 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=18 ORDER BY sets.idSet
09:59:12 T:140701974587136 DEBUG: RunQuery took 92 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 18
09:59:12 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=19 ORDER BY sets.idSet
09:59:13 T:140701974587136 DEBUG: RunQuery took 91 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 19
09:59:13 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=20 ORDER BY sets.idSet
09:59:13 T:140701974587136 DEBUG: RunQuery took 91 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 20
09:59:13 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=21 ORDER BY sets.idSet
09:59:13 T:140701974587136 DEBUG: RunQuery took 91 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 21
09:59:13 T:140701974587136 DEBUG: RunQuery took 8 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=22 ORDER BY sets.idSet
09:59:13 T:140701974587136 DEBUG: RunQuery took 89 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 22
09:59:13 T:140701974587136 DEBUG: RunQuery took 8 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=23 ORDER BY sets.idSet
09:59:13 T:140701974587136 DEBUG: RunQuery took 90 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 23
09:59:13 T:140701974587136 DEBUG: RunQuery took 8 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=24 ORDER BY sets.idSet
09:59:13 T:140701974587136 DEBUG: RunQuery took 88 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 24
09:59:13 T:140701974587136 DEBUG: RunQuery took 12 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=25 ORDER BY sets.idSet
09:59:13 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:13 T:140701974587136 DEBUG: RunQuery took 102 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 25
09:59:13 T:140701974587136 DEBUG: RunQuery took 12 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=26 ORDER BY sets.idSet
09:59:13 T:140701974587136 DEBUG: RunQuery took 89 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 26
09:59:13 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=27 ORDER BY sets.idSet
09:59:14 T:140701974587136 DEBUG: RunQuery took 97 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 27
09:59:14 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=28 ORDER BY sets.idSet
09:59:14 T:140701974587136 DEBUG: RunQuery took 91 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 28
09:59:14 T:140701974587136 DEBUG: RunQuery took 9 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=29 ORDER BY sets.idSet
09:59:14 T:140701974587136 DEBUG: RunQuery took 92 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 29
09:59:14 T:140701974587136 DEBUG: RunQuery took 11 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=30 ORDER BY sets.idSet
09:59:14 T:140701974587136 DEBUG: RunQuery took 103 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 30
09:59:14 T:140701974587136 DEBUG: RunQuery took 9 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=31 ORDER BY sets.idSet
09:59:14 T:140701974587136 DEBUG: RunQuery took 99 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 31
09:59:14 T:140701974587136 DEBUG: RunQuery took 9 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=32 ORDER BY sets.idSet
09:59:14 T:140701974587136 DEBUG: RunQuery took 96 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 32
09:59:14 T:140701974587136 DEBUG: RunQuery took 10 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=33 ORDER BY sets.idSet
09:59:14 T:140701974587136 DEBUG: RunQuery took 91 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 33
09:59:14 T:140701974587136 DEBUG: RunQuery took 10 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=34 ORDER BY sets.idSet
09:59:15 T:140701974587136 DEBUG: RunQuery took 90 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 34
09:59:15 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=35 ORDER BY sets.idSet
09:59:15 T:140701974587136 DEBUG: RunQuery took 91 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 35
09:59:15 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=36 ORDER BY sets.idSet
09:59:15 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:15 T:140701974587136 DEBUG: RunQuery took 174 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 36
09:59:15 T:140701974587136 DEBUG: RunQuery took 9 ms for 6 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=37 ORDER BY sets.idSet
09:59:15 T:140701974587136 DEBUG: RunQuery took 89 ms for 6 items query: select * from movie_view WHERE movie_view.idSet = 37
09:59:15 T:140701974587136 DEBUG: RunQuery took 7 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=38 ORDER BY sets.idSet
09:59:15 T:140701974587136 DEBUG: RunQuery took 102 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 38
09:59:15 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124755816
09:59:15 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124755820
09:59:15 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=39 ORDER BY sets.idSet
09:59:15 T:140701974587136 DEBUG: RunQuery took 95 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 39
09:59:15 T:140701974587136 DEBUG: RunQuery took 8 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=40 ORDER BY sets.idSet
09:59:15 T:140701974587136 DEBUG: RunQuery took 90 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 40
09:59:16 T:140701974587136 DEBUG: RunQuery took 9 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=41 ORDER BY sets.idSet
09:59:16 T:140701974587136 DEBUG: RunQuery took 127 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 41
09:59:16 T:140701974587136 DEBUG: RunQuery took 9 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=42 ORDER BY sets.idSet
09:59:16 T:140701974587136 DEBUG: RunQuery took 121 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 42
09:59:16 T:140701974587136 DEBUG: RunQuery took 8 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=43 ORDER BY sets.idSet
09:59:16 T:140701974587136 DEBUG: RunQuery took 113 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 43
09:59:16 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=44 ORDER BY sets.idSet
09:59:16 T:140701974587136 DEBUG: RunQuery took 126 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 44
09:59:16 T:140701974587136 DEBUG: RunQuery took 10 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=45 ORDER BY sets.idSet
09:59:16 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:16 T:140701974587136 DEBUG: RunQuery took 115 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 45
09:59:17 T:140701974587136 DEBUG: RunQuery took 10 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=46 ORDER BY sets.idSet
09:59:17 T:140701974587136 DEBUG: RunQuery took 122 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 46
09:59:17 T:140701974587136 DEBUG: RunQuery took 10 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=47 ORDER BY sets.idSet
09:59:17 T:140701974587136 DEBUG: RunQuery took 101 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 47
09:59:17 T:140701974587136 DEBUG: RunQuery took 19 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=48 ORDER BY sets.idSet
09:59:17 T:140701974587136 DEBUG: RunQuery took 107 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 48
09:59:17 T:140701974587136 DEBUG: RunQuery took 12 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=49 ORDER BY sets.idSet
09:59:17 T:140701974587136 DEBUG: RunQuery took 117 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 49
09:59:17 T:140701974587136 DEBUG: RunQuery took 13 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=50 ORDER BY sets.idSet
09:59:18 T:140701974587136 DEBUG: RunQuery took 109 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 50
09:59:18 T:140701974587136 DEBUG: RunQuery took 9 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=51 ORDER BY sets.idSet
09:59:18 T:140701974587136 DEBUG: RunQuery took 94 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 51
09:59:18 T:140701974587136 DEBUG: RunQuery took 11 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=52 ORDER BY sets.idSet
09:59:18 T:140701974587136 DEBUG: RunQuery took 98 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 52
09:59:18 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:18 T:140701974587136 DEBUG: RunQuery took 15 ms for 7 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=53 ORDER BY sets.idSet
09:59:18 T:140701974587136 DEBUG: RunQuery took 115 ms for 7 items query: select * from movie_view WHERE movie_view.idSet = 53
09:59:18 T:140701974587136 DEBUG: RunQuery took 10 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=54 ORDER BY sets.idSet
09:59:18 T:140701974587136 DEBUG: RunQuery took 120 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 54
09:59:18 T:140701974587136 DEBUG: RunQuery took 10 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=55 ORDER BY sets.idSet
09:59:18 T:140701974587136 DEBUG: RunQuery took 99 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 55
09:59:19 T:140701974587136 DEBUG: RunQuery took 14 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=56 ORDER BY sets.idSet
09:59:19 T:140701974587136 DEBUG: RunQuery took 102 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 56
09:59:19 T:140701974587136 DEBUG: RunQuery took 9 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=57 ORDER BY sets.idSet
09:59:19 T:140701974587136 DEBUG: RunQuery took 127 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 57
09:59:19 T:140701974587136 DEBUG: RunQuery took 10 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=58 ORDER BY sets.idSet
09:59:19 T:140701974587136 DEBUG: RunQuery took 129 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 58
09:59:19 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=59 ORDER BY sets.idSet
09:59:20 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:20 T:140701974587136 DEBUG: RunQuery took 134 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 59
09:59:20 T:140701974587136 DEBUG: RunQuery took 9 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=60 ORDER BY sets.idSet
09:59:20 T:140701974587136 DEBUG: RunQuery took 121 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 60
09:59:20 T:140701974587136 DEBUG: RunQuery took 14 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=61 ORDER BY sets.idSet
09:59:20 T:140701974587136 DEBUG: RunQuery took 126 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 61
09:59:20 T:140701974587136 DEBUG: RunQuery took 13 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=62 ORDER BY sets.idSet
09:59:20 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124760817
09:59:20 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124760821
09:59:20 T:140701974587136 DEBUG: RunQuery took 120 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 62
09:59:20 T:140701974587136 DEBUG: RunQuery took 10 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=63 ORDER BY sets.idSet
09:59:21 T:140701974587136 DEBUG: RunQuery took 121 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 63
09:59:21 T:140701974587136 DEBUG: RunQuery took 9 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=64 ORDER BY sets.idSet
09:59:21 T:140701974587136 DEBUG: RunQuery took 110 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 64
09:59:21 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:21 T:140701974587136 DEBUG: RunQuery took 17 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=65 ORDER BY sets.idSet
09:59:21 T:140701974587136 DEBUG: RunQuery took 99 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 65
09:59:21 T:140701974587136 DEBUG: RunQuery took 8 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=66 ORDER BY sets.idSet
09:59:21 T:140701974587136 DEBUG: RunQuery took 101 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 66
09:59:22 T:140701974587136 DEBUG: RunQuery took 12 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=67 ORDER BY sets.idSet
09:59:22 T:140701974587136 DEBUG: RunQuery took 132 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 67
09:59:22 T:140701974587136 DEBUG: RunQuery took 11 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=68 ORDER BY sets.idSet
09:59:22 T:140701974587136 DEBUG: RunQuery took 121 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 68
09:59:22 T:140701974587136 DEBUG: RunQuery took 11 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=69 ORDER BY sets.idSet
09:59:22 T:140701974587136 DEBUG: RunQuery took 111 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 69
09:59:22 T:140701974587136 DEBUG: RunQuery took 11 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=70 ORDER BY sets.idSet
09:59:22 T:140701974587136 DEBUG: RunQuery took 107 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 70
09:59:23 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:23 T:140701974587136 DEBUG: RunQuery took 13 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=71 ORDER BY sets.idSet
09:59:23 T:140701974587136 DEBUG: RunQuery took 112 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 71
09:59:23 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=72 ORDER BY sets.idSet
09:59:23 T:140701974587136 DEBUG: RunQuery took 112 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 72
09:59:23 T:140701974587136 DEBUG: RunQuery took 11 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=73 ORDER BY sets.idSet
09:59:23 T:140701974587136 DEBUG: RunQuery took 127 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 73
09:59:23 T:140701974587136 DEBUG: RunQuery took 10 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=74 ORDER BY sets.idSet
09:59:23 T:140701974587136 DEBUG: RunQuery took 104 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 74
09:59:23 T:140701974587136 DEBUG: RunQuery took 10 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=75 ORDER BY sets.idSet
09:59:23 T:140701974587136 DEBUG: RunQuery took 117 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 75
09:59:24 T:140701974587136 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=76 ORDER BY sets.idSet
09:59:24 T:140701974587136 DEBUG: RunQuery took 110 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 76
09:59:24 T:140701974587136 DEBUG: RunQuery took 12 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=77 ORDER BY sets.idSet
09:59:24 T:140701974587136 DEBUG: RunQuery took 115 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 77
09:59:24 T:140701974587136 DEBUG: RunQuery took 11 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=78 ORDER BY sets.idSet
09:59:24 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:24 T:140701974587136 DEBUG: RunQuery took 117 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 78
09:59:24 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=79 ORDER BY sets.idSet
09:59:24 T:140701974587136 DEBUG: RunQuery took 101 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 79
09:59:25 T:140701974587136 DEBUG: RunQuery took 10 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=80 ORDER BY sets.idSet
09:59:25 T:140701974587136 DEBUG: RunQuery took 262 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 80
09:59:25 T:140701974587136 DEBUG: RunQuery took 9 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=81 ORDER BY sets.idSet
09:59:25 T:140701974587136 DEBUG: RunQuery took 100 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 81
09:59:25 T:140701974587136 DEBUG: RunQuery took 10 ms for 7 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=82 ORDER BY sets.idSet
09:59:25 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124765817
09:59:25 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124765821
09:59:25 T:140701974587136 DEBUG: RunQuery took 110 ms for 7 items query: select * from movie_view WHERE movie_view.idSet = 82
09:59:25 T:140701974587136 DEBUG: RunQuery took 12 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=83 ORDER BY sets.idSet
09:59:25 T:140701974587136 DEBUG: RunQuery took 123 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 83
09:59:26 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:26 T:140701974587136 DEBUG: RunQuery took 13 ms for 4 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=84 ORDER BY sets.idSet
09:59:26 T:140701974587136 DEBUG: RunQuery took 122 ms for 4 items query: select * from movie_view WHERE movie_view.idSet = 84
09:59:26 T:140701974587136 DEBUG: RunQuery took 11 ms for 2 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=85 ORDER BY sets.idSet
09:59:27 T:140701974587136 DEBUG: RunQuery took 141 ms for 2 items query: select * from movie_view WHERE movie_view.idSet = 85
09:59:27 T:140701974587136 DEBUG: RunQuery took 13 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=86 ORDER BY sets.idSet
09:59:27 T:140701974587136 DEBUG: RunQuery took 130 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 86
09:59:27 T:140701974587136 DEBUG: RunQuery took 9 ms for 3 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=87 ORDER BY sets.idSet
09:59:27 T:140703827810048 INFO: CPythonInvoker(13, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): script successfully run
09:59:27 T:140701974587136 DEBUG: RunQuery took 118 ms for 3 items query: select * from movie_view WHERE movie_view.idSet = 87
09:59:27 T:140705316055104 DEBUG: Saving fileitems [plugin://plugin.video.amazon-test/?cat=Browse&mode=listContent&opt&page=1&url=OrderBy%3DTitle%26OfferGroups%3DB0043YVHMY%26contentType%3Dmovie]
09:59:27 T:140705316055104 DEBUG: -- items: 41, sort method: 0, ascending: false
09:59:27 T:140705316055104 DEBUG: RunQuery took 1 ms for 3 items query: SELECT files.strFilename, files.playCount, bookmark.timeInSeconds, bookmark.totalTimeInSeconds FROM files LEFT JOIN bookmark ON files.idFile = bookmark.idFile AND bookmark.type = 1 WHERE files.idPath=958
09:59:27 T:140701920315136 DEBUG: Thread BackgroundLoader start, auto delete: false
09:59:27 T:140703480911616 DEBUG: GetImageHash - unable to stat url
09:59:27 T:140705316055104 DEBUG: RunScriptWithParams - calling plugin Amazon VOD('plugin://plugin.video.amazon-test/','-1','?mode=checkMissing')
09:59:27 T:140701911922432 DEBUG: Thread LanguageInvoker start, auto delete: false
09:59:27 T:140701911922432 INFO: initializing python engine.
09:59:27 T:140701911922432 DEBUG: CPythonInvoker(14, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): start processing
09:59:27 T:140701920315136 DEBUG: Thread BackgroundLoader 140701920315136 terminating
09:59:27 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:27 T:140705316055104 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
09:59:27 T:140701974587136 DEBUG: RunQuery took 17 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=88 ORDER BY sets.idSet
09:59:27 T:140701974587136 DEBUG: RunQuery took 115 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 88
09:59:28 T:140703827810048 INFO: Python script stopped
09:59:28 T:140703827810048 DEBUG: Thread LanguageInvoker 140703827810048 terminating
09:59:28 T:140701974587136 DEBUG: RunQuery took 15 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=89 ORDER BY sets.idSet
09:59:28 T:140701974587136 DEBUG: RunQuery took 103 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 89
09:59:28 T:140701974587136 DEBUG: RunQuery took 16 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=90 ORDER BY sets.idSet
09:59:28 T:140701911922432 DEBUG: -->Python Interpreter Initialized<--
09:59:28 T:140701911922432 DEBUG: CPythonInvoker(14, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): the source file to load is "/home/fubar/.kodi/addons/plugin.video.amazon-test/default.py"
09:59:28 T:140701911922432 DEBUG: CPythonInvoker(14, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): setting the Python path to /home/fubar/.kodi/addons/plugin.video.amazon-test:/home/fubar/.kodi/addons/script.module.beautifulsoup/lib:/home/fubar/.kodi/addons/script.module.mechanize/lib:/home/fubar/.kodi/addons/script.module.pydes/lib:/home/fubar/.kodi/addons/script.module.pyxbmct/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
09:59:28 T:140701911922432 DEBUG: CPythonInvoker(14, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): entering source directory /home/fubar/.kodi/addons/plugin.video.amazon-test
09:59:28 T:140701911922432 DEBUG: CPythonInvoker(14, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): instantiating addon using automatically obtained id of "plugin.video.amazon-test" dependent on version 2.1.0 of the xbmc.python api
09:59:28 T:140701974587136 DEBUG: RunQuery took 97 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 90
09:59:28 T:140701974587136 DEBUG: RunQuery took 8 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=91 ORDER BY sets.idSet
09:59:28 T:140701974587136 DEBUG: RunQuery took 108 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 91
09:59:28 T:140701974587136 DEBUG: RunQuery took 10 ms for 1 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=92 ORDER BY sets.idSet
09:59:28 T:140701974587136 DEBUG: RunQuery took 102 ms for 1 items query: select * from movie_view WHERE movie_view.idSet = 92
09:59:29 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:29 T:140704835860224 DEBUG: GetImageHash - unable to stat url
09:59:29 T:140703480911616 INFO: easy_aquire - Created session to http://image.tmdb.org
09:59:29 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:30 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:30 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:30 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124770817
09:59:30 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124770826
09:59:30 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:30 T:140702348453632 INFO: easy_aquire - Created session to https://images-na.ssl-images-amazon.com
09:59:30 T:140703489304320 DEBUG: RunQuery took 71 ms for 95 items query: SELECT * FROM tvshow_view
09:59:30 T:140703480911616 DEBUG: CCurlFile::GetMimeType - http://image.tmdb.org/t/p/original/lqYG4hFMi3Xi0uXAYHzvAnijdXT.jpg -> image/jpeg
09:59:30 T:140703480911616 DEBUG: CurlFile::Open(0x7ff7f422b690) http://image.tmdb.org/t/p/original/lqYG4hFMi3Xi0uXAYHzvAnijdXT.jpg
09:59:31 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:32 T:140702860179200 DEBUG: Previous line repeats 2 times.
09:59:32 T:140702860179200 INFO: easy_aquire - Created session to http://ecx.images-amazon.com
09:59:32 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:32 T:140703480911616 DEBUG: Caching image 'http://image.tmdb.org/t/p/original/lqYG4hFMi3Xi0uXAYHzvAnijdXT.jpg' to '9/9cc3af36.jpg':
09:59:32 T:140703480911616 DEBUG: cached image 'special://masterprofile/Thumbnails/9/9cc3af36.jpg' size 1920x1080
09:59:32 T:140702348453632 DEBUG: CCurlFile::GetMimeType - https://images-na.ssl-images-amazon.com/images/S/videodirect-images/hero/795555b4-7827-f017-68a9-d9821174fceeDE._V1_.jpg -> image/jpeg
09:59:32 T:140702348453632 DEBUG: CurlFile::Open(0x9b50db0) https://images-na.ssl-images-amazon.com/images/S/videodirect-images/hero/795555b4-7827-f017-68a9-d9821174fceeDE._V1_.jpg
09:59:32 T:140703489304320 DEBUG: RunQuery took 91 ms for 595 items query: select * from movie_view
09:59:33 T:140702860179200 DEBUG: CCurlFile::GetMimeType - http://ecx.images-amazon.com/images/I/51h5KR73nhL.jpg -> image/jpeg
09:59:33 T:140702860179200 DEBUG: CurlFile::Open(0x7ff7e82bacf0) http://ecx.images-amazon.com/images/I/51h5KR73nhL.jpg
09:59:33 T:140701911922432 NOTICE: [Amazon VOD] [('mode', 'checkMissing')]
09:59:33 T:140701911922432 NOTICE: [Amazon VOD] Starting Fanart Update
09:59:33 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 1 NIGHTER at tmdb.com
09:59:33 T:140702860179200 DEBUG: Caching image 'http://ecx.images-amazon.com/images/I/51h5KR73nhL.jpg' to 'f/f926b696.jpg':
09:59:33 T:140702860179200 DEBUG: cached image 'special://masterprofile/Thumbnails/f/f926b696.jpg' size 375x500
09:59:33 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:35 T:140701911922432 DEBUG: Previous line repeats 1 times.
09:59:35 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 1. BLUE CITY - THE BUST PILOT at tmdb.com
09:59:35 T:140702348453632 DEBUG: Caching image 'https://images-na.ssl-images-amazon.com/images/S/videodirect-images/hero/795555b4-7827-f017-68a9-d9821174fceeDE._V1_.jpg' to '0/0e05f64d.jpg':
09:59:35 T:140702348453632 DEBUG: cached image 'special://masterprofile/Thumbnails/0/0e05f64d.jpg' size 1920x1080
09:59:35 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:35 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124775817
09:59:35 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124775823
09:59:35 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:35 T:140704835860224 DEBUG: CCurlFile::GetMimeType - http://ecx.images-amazon.com/images/I/51Vqd7Z1LFL.jpg -> image/jpeg
09:59:35 T:140704835860224 DEBUG: CurlFile::Open(0x7ff8444ec1c0) http://ecx.images-amazon.com/images/I/51Vqd7Z1LFL.jpg
09:59:36 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:36 T:140702860179200 INFO: easy_aquire - Created session to http://ecx.images-amazon.com
09:59:36 T:140703480911616 DEBUG: CCurlFile::GetMimeType - https://images-na.ssl-images-amazon.com/images/S/videodirect-images/hero/d3558aa2-8e7f-ea17-ebd7-c1b97b8394d1DE._V1_.jpg -> image/jpeg
09:59:36 T:140703480911616 DEBUG: CurlFile::Open(0x7ff7f428a310) https://images-na.ssl-images-amazon.com/images/S/videodirect-images/hero/d3558aa2-8e7f-ea17-ebd7-c1b97b8394d1DE._V1_.jpg
09:59:36 T:140704835860224 DEBUG: Caching image 'http://ecx.images-amazon.com/images/I/51Vqd7Z1LFL.jpg' to 'f/fb861b9a.jpg':
09:59:36 T:140704835860224 DEBUG: cached image 'special://masterprofile/Thumbnails/f/fb861b9a.jpg' size 375x500
09:59:36 T:140702348453632 DEBUG: CCurlFile::GetMimeType - http://image.tmdb.org/t/p/original/wHJurKivFAJQcIXa8OHWeHwRjtI.jpg -> image/jpeg
09:59:36 T:140702348453632 DEBUG: CurlFile::Open(0x4da3cb0) http://image.tmdb.org/t/p/original/wHJurKivFAJQcIXa8OHWeHwRjtI.jpg
09:59:36 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:36 T:140705316055104 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
09:59:36 T:140704835860224 INFO: easy_aquire - Created session to http://image.tmdb.org
09:59:38 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:38 T:140702348453632 DEBUG: Caching image 'http://image.tmdb.org/t/p/original/wHJurKivFAJQcIXa8OHWeHwRjtI.jpg' to 'c/c424a841.jpg':
09:59:38 T:140702348453632 DEBUG: cached image 'special://masterprofile/Thumbnails/c/c424a841.jpg' size 1280x720
09:59:38 T:140702860179200 DEBUG: CCurlFile::GetMimeType - http://ecx.images-amazon.com/images/I/61Lk3KK6MYL.jpg -> image/jpeg
09:59:38 T:140702860179200 DEBUG: CurlFile::Open(0x7ff7e82b6f10) http://ecx.images-amazon.com/images/I/61Lk3KK6MYL.jpg
09:59:38 T:140704835860224 DEBUG: CCurlFile::GetMimeType - http://image.tmdb.org/t/p/original/6KXbhaxkgExC5EdDqAzRinhmoZ8.jpg -> image/jpeg
09:59:38 T:140704835860224 DEBUG: CurlFile::Open(0x7ff8444ec1c0) http://image.tmdb.org/t/p/original/6KXbhaxkgExC5EdDqAzRinhmoZ8.jpg
09:59:39 T:140703480911616 DEBUG: Caching image 'https://images-na.ssl-images-amazon.com/images/S/videodirect-images/hero/d3558aa2-8e7f-ea17-ebd7-c1b97b8394d1DE._V1_.jpg' to '8/8f93f6a2.jpg':
09:59:39 T:140703480911616 DEBUG: cached image 'special://masterprofile/Thumbnails/8/8f93f6a2.jpg' size 1920x1080
09:59:39 T:140702860179200 DEBUG: Caching image 'http://ecx.images-amazon.com/images/I/61Lk3KK6MYL.jpg' to 'a/a9bcd984.jpg':
09:59:39 T:140702860179200 DEBUG: cached image 'special://masterprofile/Thumbnails/a/a9bcd984.jpg' size 375x500
09:59:39 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 1/6 MARVEL LEGENDS ICONS WOLVERINE at tmdb.com
09:59:39 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:40 T:140702348453632 DEBUG: CCurlFile::GetMimeType - http://ecx.images-amazon.com/images/I/51gUho1LMLL.jpg -> image/jpeg
09:59:40 T:140702348453632 DEBUG: CurlFile::Open(0x4e74920) http://ecx.images-amazon.com/images/I/51gUho1LMLL.jpg
09:59:40 T:140704835860224 DEBUG: Caching image 'http://image.tmdb.org/t/p/original/6KXbhaxkgExC5EdDqAzRinhmoZ8.jpg' to '6/64750205.jpg':
09:59:40 T:140704835860224 DEBUG: cached image 'special://masterprofile/Thumbnails/6/64750205.jpg' size 1920x1080
09:59:40 T:140702348453632 DEBUG: Caching image 'http://ecx.images-amazon.com/images/I/51gUho1LMLL.jpg' to 'a/adea9c5a.jpg':
09:59:40 T:140702348453632 DEBUG: cached image 'special://masterprofile/Thumbnails/a/adea9c5a.jpg' size 354x500
09:59:40 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 1/6 SPIDER-MAN at tmdb.com
09:59:40 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124780816
09:59:40 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124780820
09:59:41 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for A 10 MINUTE NATURE VISIT at tmdb.com
09:59:41 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:41 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 10 MINUTE WAIST WORKOUT FOR WOMEN - SLIM WAIST EXERCISES AT HOME at tmdb.com
09:59:42 T:140705316055104 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
09:59:42 T:140705316055104 DEBUG: OnPlayMedia plugin://plugin.video.amazon-test/?asin=B00FZKQ7RA&mode=PlayVideo&name=12+Monkeys&adult=0&trailer=0&selbitrate=0
09:59:42 T:140705316055104 DEBUG: StartScript - calling plugin Amazon VOD('plugin://plugin.video.amazon-test/','3','?asin=B00FZKQ7RA&mode=PlayVideo&name=12+Monkeys&adult=0&trailer=0&selbitrate=0')
09:59:42 T:140701966194432 DEBUG: Thread LanguageInvoker start, auto delete: false
09:59:42 T:140701966194432 INFO: initializing python engine.
09:59:42 T:140701966194432 DEBUG: CPythonInvoker(15, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): start processing
09:59:42 T:140704863520512 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
09:59:42 T:140704863520512 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
09:59:42 T:140701896189696 DEBUG: Thread scriptobs start, auto delete: false
09:59:42 T:140705316055104 DEBUG: ------ Window Init (DialogBusy.xml) ------
09:59:42 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:43 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 11. BLUE CITY - BRING OUT THE BODY BAGS at tmdb.com
09:59:44 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:45 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124785816
09:59:45 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124785961
09:59:45 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:48 T:140701966194432 DEBUG: Previous line repeats 1 times.
09:59:48 T:140701966194432 DEBUG: -->Python Interpreter Initialized<--
09:59:48 T:140701966194432 DEBUG: CPythonInvoker(15, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): the source file to load is "/home/fubar/.kodi/addons/plugin.video.amazon-test/default.py"
09:59:48 T:140701966194432 DEBUG: CPythonInvoker(15, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): setting the Python path to /home/fubar/.kodi/addons/plugin.video.amazon-test:/home/fubar/.kodi/addons/script.module.beautifulsoup/lib:/home/fubar/.kodi/addons/script.module.mechanize/lib:/home/fubar/.kodi/addons/script.module.pydes/lib:/home/fubar/.kodi/addons/script.module.pyxbmct/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
09:59:48 T:140701966194432 DEBUG: CPythonInvoker(15, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): entering source directory /home/fubar/.kodi/addons/plugin.video.amazon-test
09:59:48 T:140701966194432 DEBUG: CPythonInvoker(15, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): instantiating addon using automatically obtained id of "plugin.video.amazon-test" dependent on version 2.1.0 of the xbmc.python api
09:59:49 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:50 T:140702054872832 DEBUG: Previous line repeats 1 times.
09:59:50 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124790816
09:59:50 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124790821
09:59:52 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:55 T:140702054872832 DEBUG: Previous line repeats 2 times.
09:59:55 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124795817
09:59:55 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124795822
09:59:56 T:140701966194432 NOTICE: [Amazon VOD] [('asin', 'B00FZKQ7RA'), ('mode', 'PlayVideo'), ('name', '12 Monkeys'), ('adult', '0'), ('trailer', '0'), ('selbitrate', '0')]
09:59:56 T:140701966194432 NOTICE: [Amazon VOD] getURL: https://www.amazon.de/gp/deal/ajax/getNotifierResources.html
09:59:56 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:56 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 12 OF THE MOST DIFFICULT PROBLEMS FACED BY COURSE CREATORS at tmdb.com
09:59:57 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 12 TACTICS TO ACCELERATE YOUR ONLINE SALES - PART 5 at tmdb.com
09:59:58 T:140701966194432 NOTICE: [Amazon VOD] getURL: https://www.amazon.de/gp/video/streaming/player-token.json?callback=onWebToken_354
09:59:58 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
09:59:59 T:140701911922432 DEBUG: Previous line repeats 1 times.
09:59:59 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 12 TACTICS TO ACCELERATE YOUR ONLINE SALES - PART 6 at tmdb.com
10:00:00 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124800817
10:00:00 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124800961
10:00:01 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:01 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 12 TACTICS TO ACCELERATE YOUR ONLINE SALES PART 1 at tmdb.com
10:00:02 T:140701966194432 NOTICE: [Amazon VOD] getURL: https://atv-eu.amazon.com/cdp/catalog/GetPlaybackResources?asin=B00FZKQ7RA&deviceTypeID=AOAGZA014O5RE&firmware=1&deviceID=493adf63af3edfcef3b52d3b9859fd7a1178e2b22865b8ee46e14ee3&marketplaceID=A1PA6795UKMFR9&format=json&version=1&titleDecorationScheme=primary-content&resourceUsage=ImmediateConsumption&consumptionType=Streaming&deviceDrmOverride=CENC&deviceStreamingTechnologyOverride=DASH&deviceProtocolOverride=Http&audioTrackId=all&videoMaterialType=Feature&desiredResources=AudioVideoUrls%2CSubtitleUrls
10:00:02 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:03 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 12 TACTICS TO ACCELERATE YOUR ONLINE SALES PART 2 at tmdb.com
10:00:03 T:140703489304320 INFO: CPythonInvoker(11, /home/fubar/.kodi/addons/script.tvtunes/resources/lib/upload.py): script successfully run
10:00:04 T:140703489304320 INFO: Python script stopped
10:00:04 T:140703489304320 DEBUG: Thread LanguageInvoker 140703489304320 terminating
10:00:04 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:04 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 12 TACTICS TO ACCELERATE YOUR ONLINE SALES PART 3 at tmdb.com
10:00:04 T:140701966194432 NOTICE: [Amazon VOD] Convert German Subtitle
10:00:04 T:140701966194432 NOTICE: [Amazon VOD] getURL: http://dmqdd6hw24ucf.cloudfront.net/2/f/8/2f8607cd-fbb2-4051-be7e-4fcce7a4d2d0/1cdaa2fa-51ae-44e6-814c-0a0377959b68.dfxp
10:00:05 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124805816
10:00:05 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124805820
10:00:05 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:05 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 12 TACTICS TO ACCELERATE YOUR ONLINE SALES PART 4 at tmdb.com
10:00:06 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 15 MINUTE FULL BODY WORKOUT - EXERCISES TO TONE AND LOSE WEIGHT at tmdb.com
10:00:07 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:08 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 15 MINUTE PILATES WORKOUT. EXERCISES FOR A TONED, SLIM BODY at tmdb.com
10:00:08 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:09 T:140705316055104 INFO: CheckIdle - Closing session to https://images-na.ssl-images-amazon.com (easy=0x9545780, multi=0x7590130)
10:00:09 T:140705316055104 INFO: CheckIdle - Closing session to http://image.tmdb.org (easy=0x7ff8444ec470, multi=(nil))
10:00:09 T:140702860179200 DEBUG: Thread JobWorker 140702860179200 terminating (autodelete)
10:00:09 T:140703480911616 DEBUG: Thread JobWorker 140703480911616 terminating (autodelete)
10:00:09 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 17 ESSENTIAL COMPONENTS FOR A WEBINAR at tmdb.com
10:00:10 T:140705316055104 INFO: CheckIdle - Closing session to http://ecx.images-amazon.com (easy=0x7ff7e858d5f0, multi=(nil))
10:00:10 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 18 AWESOME FACTS ABOUT MONEY at tmdb.com
10:00:10 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:10 T:140702348453632 DEBUG: Thread JobWorker 140702348453632 terminating (autodelete)
10:00:10 T:140705316055104 INFO: CheckIdle - Closing session to http://image.tmdb.org (easy=0x7ff7f40cbdf0, multi=0x7ff7f4547330)
10:00:10 T:140705316055104 INFO: CheckIdle - Closing session to http://ecx.images-amazon.com (easy=0x7ff7e801d3f0, multi=0x7ff7e802e010)
10:00:10 T:140704835860224 DEBUG: Thread JobWorker 140704835860224 terminating (autodelete)
10:00:10 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124810816
10:00:10 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124810825
10:00:11 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 18 BILDER/SEK at tmdb.com
10:00:11 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:12 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 1980S CUPCAKE at tmdb.com
10:00:12 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 1UP BOX RPG UNBOXING at tmdb.com
10:00:13 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 2. BLUE CITY - TAKING HITS at tmdb.com
10:00:15 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 20,000 MILES ON A HORSE at tmdb.com
10:00:15 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124815817
10:00:15 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124815821
10:00:15 T:140701911922432 NOTICE: [Amazon VOD] searching fanart for 2016 CROP CIRCLE PREDICTED POSSIBLE OUTBREAK & MORE CONFLICT at tmdb.com
10:00:16 T:140701911922432 NOTICE: [Amazon VOD] Finished Fanart Update
10:00:16 T:140701911922432 INFO: CPythonInvoker(14, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): script successfully run
10:00:17 T:140701911922432 INFO: Python script stopped
10:00:17 T:140701911922432 DEBUG: Thread LanguageInvoker 140701911922432 terminating
10:00:17 T:140701966194432 NOTICE: [Amazon VOD] Using Host: Akamai
10:00:17 T:140701966194432 NOTICE: [Amazon VOD] http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd
10:00:17 T:140701966194432 INFO: CPythonInvoker(15, /home/fubar/.kodi/addons/plugin.video.amazon-test/default.py): script successfully run
10:00:17 T:140701896189696 DEBUG: Thread scriptobs 140701896189696 terminating
10:00:17 T:140705316055104 INFO: easy_aquire - Created session to http://a275avoddashs3eu-a.akamaihd.net
10:00:17 T:140705316055104 DEBUG: CCurlFile::GetMimeType - http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd -> application/octet-stream
10:00:17 T:140705316055104 DEBUG: Loading settings for http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd
10:00:17 T:140705316055104 DEBUG: CPlayerCoreFactory::GetPlayers(http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd)
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
10:00:17 T:140705316055104 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
10:00:17 T:140705316055104 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
10:00:17 T:140705316055104 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
10:00:17 T:140705316055104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
10:00:17 T:140705316055104 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
10:00:17 T:140705316055104 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
10:00:17 T:140705316055104 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
10:00:17 T:140705316055104 NOTICE: VideoPlayer: Opening: http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd
10:00:17 T:140705316055104 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
10:00:17 T:140705316055104 DEBUG: CCurlFile::GetMimeType - http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd -> application/octet-stream
10:00:17 T:140705316055104 DEBUG: LinuxRendererGL: Cleaning up GL resources
10:00:17 T:140705316055104 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
10:00:17 T:140701896189696 DEBUG: Thread VideoPlayer start, auto delete: false
10:00:17 T:140701896189696 NOTICE: Creating InputStream
10:00:17 T:140701896189696 DEBUG: ADDON: Dll Initializing - InputStream MPEG DASH
10:00:17 T:140701896189696 DEBUG: SECTION:LoadDLL(/home/fubar/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.0)
10:00:17 T:140701896189696 DEBUG: Loading: /home/fubar/.kodi/addons/inputstream.mpd/inputstream.mpd.so.1.2.0
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: libXBMC_addon successfully loaded
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: ADDON_Create()
10:00:17 T:140701896189696 INFO: AddOnLog: InputStream MPEG DASH: SetVideoResolution (1920 x 1080)
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: Open()
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: found inputstream.mpd.license_key: [not shown]
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: found inputstream.mpd.license_type: com.widevine.alpha
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: Initial bandwidth: 0
10:00:17 T:140701896189696 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream MPEG DASH' requests setting 'MAXRESOLUTION'
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: MAXRESOLUTION selected: 1
10:00:17 T:140701896189696 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream MPEG DASH' requests setting 'STREAMSELECTION'
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: STREAMSELECTION selected: 0
10:00:17 T:140701896189696 DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'InputStream MPEG DASH' requests setting 'DECRYPTERPATH'
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: Searching for decrypters in:
10:00:17 T:140701896189696 ERROR: GetDirectory - Error getting
10:00:17 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: Supported URN:
10:00:17 T:140701896189696 DEBUG: CurlFile::Open(0x7ff8081822a0) http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd
10:00:18 T:140705316055104 DEBUG: ------ Window Init (DialogBusy.xml) ------
10:00:18 T:140701966194432 INFO: Python script stopped
10:00:18 T:140701966194432 DEBUG: Thread LanguageInvoker 140701966194432 terminating
10:00:20 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: Download http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd finished
10:00:20 T:140701896189696 ERROR: AddOnLog: InputStream MPEG DASH: Could not open / parse mpdURL (http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd)
10:00:20 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: GetStreamIds()
10:00:20 T:140701896189696 ERROR: CVideoPlayer::OpenInputStream - error opening [http://a275avoddashs3eu-a.akamaihd.net/d/1$AOAGZA014O5RE,57B1863B/videoquality$1080p/ondemand/514a/18da/1dcf/467d-b076-e6aaf7293e42/dbcd51f6-94c7-46aa-8575-075cba517026_corrected.mpd]
10:00:20 T:140701896189696 NOTICE: CVideoPlayer::OnExit()
10:00:20 T:140701896189696 DEBUG: AddOnLog: InputStream MPEG DASH: Close()
10:00:20 T:140701896189696 INFO: ADDON: Dll Stopped - InputStream MPEG DASH
10:00:20 T:140701896189696 DEBUG: OnPlayBackStopped: play state was 1, starting 1
10:00:20 T:140701896189696 DEBUG: Thread VideoPlayer 140701896189696 terminating
10:00:20 T:140705316055104 DEBUG: OnPlayBackStopped: play state was 3, starting 0
10:00:20 T:140705316055104 ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.amazon-test/?asin=B00FZKQ7RA&mode=PlayVideo&name=12+Monkeys&adult=0&trailer=0&selbitrate=0]
10:00:20 T:140705316055104 DEBUG: Playlist Player: one or more items failed to play... aborting playback
10:00:20 T:140705316055104 DEBUG: ------ Window Init (DialogConfirm.xml) ------
10:00:20 T:140705316055104 INFO: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY
10:00:20 T:140704863520512 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
10:00:20 T:140704863520512 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
10:00:20 T:140705316055104 NOTICE: CVideoPlayer::CloseFile()
10:00:20 T:140705316055104 NOTICE: VideoPlayer: waiting for threads to exit
10:00:20 T:140705316055104 NOTICE: VideoPlayer: finished waiting
10:00:20 T:140705316055104 DEBUG: DeleteRenderer - deleting renderer
10:00:20 T:140705316055104 DEBUG: LinuxRendererGL: Cleaning up GL resources
10:00:20 T:140705316055104 NOTICE: CVideoPlayer::CloseFile()
10:00:20 T:140705316055104 NOTICE: VideoPlayer: waiting for threads to exit
10:00:20 T:140705316055104 NOTICE: VideoPlayer: finished waiting
10:00:20 T:140705316055104 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
10:00:20 T:140705316055104 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
10:00:20 T:140701896189696 DEBUG: Thread JobWorker start, auto delete: true
10:00:20 T:140701896189696 DEBUG: DoWork - Saving file state for video item plugin://plugin.video.amazon-test/?asin=B00FZKQ7RA&mode=PlayVideo&name=12+Monkeys&adult=0&trailer=0&selbitrate=0
10:00:20 T:140701896189696 DEBUG: GetImageHash - unable to stat url
10:00:20 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124820817
10:00:20 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124820821
10:00:24 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:25 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124825817
10:00:25 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124825826
10:00:25 T:140705316055104 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
10:00:26 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:26 T:140705316055104 DEBUG: ------ Window Deinit (DialogConfirm.xml) ------
10:00:26 T:140704863520512 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
10:00:26 T:140701966194432 DEBUG: Thread BackgroundLoader start, auto delete: false
10:00:26 T:140704863520512 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
10:00:26 T:140701966194432 DEBUG: Thread BackgroundLoader 140701966194432 terminating
10:00:27 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:30 T:140702054872832 DEBUG: Previous line repeats 2 times.
10:00:30 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124830817
10:00:30 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124830821
10:00:32 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:35 T:140702054872832 DEBUG: Previous line repeats 2 times.
10:00:35 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124835817
10:00:35 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124835843
10:00:36 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:40 T:140702054872832 DEBUG: Previous line repeats 2 times.
10:00:40 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124840817
10:00:40 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124840821
10:00:41 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc
10:00:45 T:140702054872832 DEBUG: Previous line repeats 2 times.
10:00:45 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124845817
10:00:45 T:140702054872832 DEBUG: CWebServer[8080]: request received for /jsonrpc?tm=1470124845821
10:00:45 T:140701982979840 DEBUG: CWebServer[8080]: request received for /jsonrpc