· 8 years ago · Apr 26, 2018, 07:58 AM
103:48:22.055 T:139751857735744 NOTICE: special://profile/ is mapped to: special://masterprofile/
203:48:22.055 T:139751857735744 NOTICE: -----------------------------------------------------------------------
303:48:22.055 T:139751857735744 NOTICE: Starting Kodi (17.6 Git:20171114-a9a7a20). Platform: Linux x86 64-bit
403:48:22.055 T:139751857735744 NOTICE: Using Release Kodi x64 build
503:48:22.055 T:139751857735744 NOTICE: Kodi compiled Nov 14 2017 by GCC 4.8.4 for Linux x86 64-bit version 3.13.11 (199947)
603:48:22.055 T:139751857735744 NOTICE: Running on Ubuntu 14.04.5 LTS, kernel: Linux x86 64-bit version 3.13.0-135-generic
703:48:22.077 T:139751857735744 NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi
803:48:22.077 T:139751857735744 NOTICE: Host CPU: Intel(R) Atom(TM) CPU D525 @ 1.80GHz, 4 cores available
903:48:22.077 T:139751857735744 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
1003:48:22.077 T:139751857735744 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/x86_64-linux-gnu/kodi
1103:48:22.077 T:139751857735744 NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/x86_64-linux-gnu/kodi/addons
1203:48:22.077 T:139751857735744 NOTICE: special://masterprofile/ is mapped to: /home/jamesz/.kodi/userdata
1303:48:22.077 T:139751857735744 NOTICE: special://envhome/ is mapped to: /home/jamesz
1403:48:22.077 T:139751857735744 NOTICE: special://home/ is mapped to: /home/jamesz/.kodi
1503:48:22.077 T:139751857735744 NOTICE: special://temp/ is mapped to: /home/jamesz/.kodi/temp
1603:48:22.077 T:139751857735744 NOTICE: special://logpath/ is mapped to: /home/jamesz/.kodi/temp
1703:48:22.077 T:139751857735744 NOTICE: The executable running is: /usr/lib/x86_64-linux-gnu/kodi/kodi.bin
1803:48:22.077 T:139751857735744 NOTICE: Local hostname: JamesZ
1903:48:22.077 T:139751857735744 NOTICE: Log File is located: /home/jamesz/.kodi/temp//kodi.log
2003:48:22.077 T:139751857735744 NOTICE: -----------------------------------------------------------------------
2103:48:25.036 T:139751857735744 NOTICE: load settings...
2203:48:25.641 T:139751857735744 ERROR: PulseAudio: Failed to connect context
2303:48:25.641 T:139751857735744 NOTICE: PulseAudio might not be running. Context was not created.
2403:48:26.030 T:139751857735744 NOTICE: Found 1 Lists of Devices
2503:48:26.030 T:139751857735744 NOTICE: Enumerated ALSA devices:
2603:48:26.030 T:139751857735744 NOTICE: Device 1
2703:48:26.030 T:139751857735744 NOTICE: m_deviceName : default
2803:48:26.031 T:139751857735744 NOTICE: m_displayName : Default (HDA NVidia HDMI 0)
2903:48:26.031 T:139751857735744 NOTICE: m_displayNameExtra:
3003:48:26.031 T:139751857735744 NOTICE: m_deviceType : AE_DEVTYPE_PCM
3103:48:26.031 T:139751857735744 NOTICE: m_channels : FL,FR,BL,BR,FC,LFE,SL,SR
3203:48:26.031 T:139751857735744 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
3303:48:26.031 T:139751857735744 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
3403:48:26.031 T:139751857735744 NOTICE: m_streamTypes : No passthrough capabilities
3503:48:26.031 T:139751857735744 NOTICE: Device 2
3603:48:26.031 T:139751857735744 NOTICE: m_deviceName : @:CARD=Intel,DEV=0
3703:48:26.031 T:139751857735744 NOTICE: m_displayName : HDA Intel
3803:48:26.031 T:139751857735744 NOTICE: m_displayNameExtra: ALC888 Analog
3903:48:26.031 T:139751857735744 NOTICE: m_deviceType : AE_DEVTYPE_PCM
4003:48:26.031 T:139751857735744 NOTICE: m_channels : FL,FR
4103:48:26.031 T:139751857735744 NOTICE: m_sampleRates : 48000
4203:48:26.031 T:139751857735744 NOTICE: m_dataFormats : AE_FMT_S32NE
4303:48:26.031 T:139751857735744 NOTICE: m_streamTypes : No passthrough capabilities
4403:48:26.031 T:139751857735744 NOTICE: Device 3
4503:48:26.031 T:139751857735744 NOTICE: m_deviceName : iec958:CARD=Intel,DEV=0
4603:48:26.031 T:139751857735744 NOTICE: m_displayName : HDA Intel
4703:48:26.031 T:139751857735744 NOTICE: m_displayNameExtra: ALC888 Digital S/PDIF
4803:48:26.031 T:139751857735744 NOTICE: m_deviceType : AE_DEVTYPE_IEC958
4903:48:26.031 T:139751857735744 NOTICE: m_channels : FL,FR
5003:48:26.031 T:139751857735744 NOTICE: m_sampleRates : 44100,48000,88200,96000,192000
5103:48:26.031 T:139751857735744 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
5203:48:26.031 T:139751857735744 NOTICE: m_streamTypes : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
5303:48:26.031 T:139751857735744 NOTICE: Device 4
5403:48:26.031 T:139751857735744 NOTICE: m_deviceName : hdmi:CARD=NVidia,DEV=0
5503:48:26.031 T:139751857735744 NOTICE: m_displayName : HDA NVidia
5603:48:26.031 T:139751857735744 NOTICE: m_displayNameExtra: PIO VSX-1122 on HDMI
5703:48:26.031 T:139751857735744 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
5803:48:26.032 T:139751857735744 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,SL,SR
5903:48:26.032 T:139751857735744 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000
6003:48:26.032 T:139751857735744 NOTICE: m_dataFormats : AE_FMT_RAW,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_RAW
6103:48:26.032 T:139751857735744 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
6203:48:26.262 T:139751857735744 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
6303:48:26.294 T:139751857735744 NOTICE: Loaded settings file from special://profile/advancedsettings.xml
6403:48:26.294 T:139751857735744 NOTICE: Contents of special://profile/advancedsettings.xml are...
65 <advancedsettings>
66 <useddsfanart>true</useddsfanart>
67 <cputempcommand>cputemp</cputempcommand>
68 <gputempcommand>gputemp</gputempcommand>
69 <samba>
70 <clienttimeout>30</clienttimeout>
71 </samba>
72 <network>
73 <disableipv6>true</disableipv6>
74 </network>
75 <sorttokens>
76 <token>A</token>
77 </sorttokens>
78 </advancedsettings>
7903:48:26.295 T:139751857735744 NOTICE: Default Video Player: VideoPlayer
8003:48:26.295 T:139751857735744 NOTICE: Default Audio Player: paplayer
8103:48:26.295 T:139751857735744 NOTICE: Enabled debug logging due to GUI setting (2)
8203:48:26.295 T:139751857735744 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
8303:48:26.296 T:139751857735744 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
8403:48:26.328 T:139751857735744 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
8503:48:26.371 T:139751857735744 DEBUG: CPlayerCoreConfig::<ctor>: created player VideoPlayer
8603:48:26.371 T:139751857735744 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer
8703:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
8803:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mms/udp
8903:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
9003:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
9103:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
9203:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
9303:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
9403:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
9503:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
9603:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
9703:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
9803:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
9903:48:26.371 T:139751857735744 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
10003:48:26.372 T:139751857735744 NOTICE: Loaded playercorefactory configuration
10103:48:26.372 T:139751857735744 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
10203:48:26.372 T:139751857735744 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
10303:48:26.590 T:139751857735744 INFO: creating subdirectories
10403:48:26.590 T:139751857735744 INFO: userdata folder: special://masterprofile/
10503:48:26.590 T:139751857735744 INFO: recording folder:
10603:48:26.591 T:139751857735744 INFO: screenshots folder:
10703:48:26.909 T:139751857735744 NOTICE: Running database version Addons27
10803:48:26.909 T:139751857735744 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
10903:48:26.944 T:139751857735744 DEBUG: Loading: /usr/lib/x86_64-linux-gnu/kodi/system/libcpluff-x86_64-linux.so
11003:48:35.189 T:139751857735744 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/x86_64-linux-gnu/kodi/addons: No such file or directory'
11103:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in repository.exodus has been installed.'
11203:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.backgroundoverlays.basic has been installed.'
11303:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
11403:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
11503:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.autocompletion has been installed.'
11603:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.skin.helper.widgets has been installed.'
11703:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.weathericons.default has been installed.'
11803:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.skinbackgrounds.titanium has been installed.'
11903:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.skin.helper.backgrounds has been installed.'
12003:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.extendedinfo has been installed.'
12103:48:35.189 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.'
12203:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in plugin.audio.shoutcast has been installed.'
12303:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.'
12403:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
12503:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
12603:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.thetvdb has been installed.'
12703:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in weather.yahoo has been installed.'
12803:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.six has been installed.'
12903:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in kodi.peripheral has been installed.'
13003:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
13103:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.'
13203:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.rapier has been installed.'
13303:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.tmz has been installed.'
13403:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.t1mlib has been installed.'
13503:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
13603:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in kodi.adsp has been installed.'
13703:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.chardet has been installed.'
13803:48:35.190 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in repository.tknorris.release has been installed.'
13903:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.simpleplaylists has been installed.'
14003:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in game.controller.default has been installed.'
14103:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
14203:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.youtube.dl has been installed.'
14303:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
14403:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.favourites has been installed.'
14503:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.fromashes has been installed.'
14603:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.idna has been installed.'
14703:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.xmltodict has been installed.'
14803:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in kodi.game has been installed.'
14903:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
15003:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.urllib3 has been installed.'
15103:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.recordlabels.white has been installed.'
15203:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
15303:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
15403:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in repository.beta.emby.kodi has been installed.'
15503:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.kodi65 has been installed.'
15603:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.1channel has been installed.'
15703:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.playalbum has been installed.'
15803:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.toolbox has been installed.'
15903:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.myconnpy has been installed.'
16003:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.addon.common has been installed.'
16103:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.globalsearch has been installed.'
16203:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.'
16303:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.randomandlastitems has been installed.'
16403:48:35.191 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.image.resource.select has been installed.'
16503:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in skin.titan.kryptonbeta has been installed.'
16603:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.'
16703:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
16803:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
16903:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
17003:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplecache has been installed.'
17103:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in service.skin.widgets has been installed.'
17203:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in service.nextup.notification has been installed.'
17303:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.hgtv has been installed.'
17403:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.skinshortcuts has been installed.'
17503:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.unidecode has been installed.'
17603:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
17703:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.musicgenreicons.text has been installed.'
17803:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
17903:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.skinicons.wide has been installed.'
18003:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in skin.estouchy has been installed.'
18103:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
18203:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.moviegenreicons.coloured has been installed.'
18303:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.artistslideshow has been installed.'
18403:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.requests has been installed.'
18503:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.exodus has been installed.'
18603:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.grab.fanart has been installed.'
18703:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.arrow has been installed.'
18803:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.artwork.downloader has been installed.'
18903:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in kodi.inputstream has been installed.'
19003:48:35.192 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.certifi has been installed.'
19103:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.crackler has been installed.'
19203:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.cherrypy has been installed.'
19303:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.artistslideshow-helper has been installed.'
19403:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
19503:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.skin.helper.skinbackup has been installed.'
19603:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.urlresolver has been installed.'
19703:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in repository.marcelveldt has been installed.'
19803:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.actionhandler has been installed.'
19903:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in service.library.data.provider has been installed.'
20003:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
20103:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
20203:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.routing has been installed.'
20303:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.studios.coloured has been installed.'
20403:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.dateutil has been installed.'
20503:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
20603:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.'
20703:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.exodus.artwork has been installed.'
20803:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.skin.helper.colorpicker has been installed.'
20903:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.musicbrainz has been installed.'
21003:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
21103:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.exodus.metadata has been installed.'
21203:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
21303:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
21403:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.xbmc.debug.log has been installed.'
21503:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
21603:48:35.193 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.titan.modern has been installed.'
21703:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in skin.titan has been installed.'
21803:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.skin.helper.service has been installed.'
21903:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.'
22003:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.t9.search has been installed.'
22103:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.buggalo has been installed.'
22203:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.images.studios.white has been installed.'
22303:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.kodi has been installed.'
22403:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
22503:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
22603:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been installed.'
22703:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
22803:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
22903:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.tv.show.next.aired has been installed.'
23003:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
23103:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.xbmcswift2 has been installed.'
23203:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in screensaver.atv4 has been installed.'
23303:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
23403:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
23503:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.metadatautils has been installed.'
23603:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in skin.estuary has been installed.'
23703:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.addon.signals has been installed.'
23803:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Plug-in script.module.metahandler has been installed.'
23903:48:35.194 T:139751857735744 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
24003:48:35.575 T:139751243679488 DEBUG: Thread JobWorker start, auto delete: true
24103:48:35.647 T:139751857735744 NOTICE: ADDONS: Using repository repository.exodus
24203:48:35.647 T:139751857735744 NOTICE: ADDONS: Using repository repository.xbmc.org
24303:48:35.647 T:139751857735744 NOTICE: ADDONS: Using repository repository.tknorris.release
24403:48:35.647 T:139751857735744 NOTICE: ADDONS: Using repository repository.beta.emby.kodi
24503:48:35.647 T:139751857735744 NOTICE: ADDONS: Using repository repository.marcelveldt
24603:48:35.679 T:139751235286784 DEBUG: Thread ActiveAE start, auto delete: false
24703:48:35.680 T:139751226894080 DEBUG: Thread AESink start, auto delete: false
24803:48:35.709 T:139751226894080 INFO: CActiveAESink::OpenSink - initialize sink
24903:48:35.709 T:139751226894080 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=0
25003:48:35.709 T:139751226894080 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=0"
25103:48:35.716 T:139751226894080 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
25203:48:35.717 T:139751226894080 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
25303:48:35.717 T:139751226894080 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
25403:48:35.717 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
25503:48:35.770 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
25603:48:35.770 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms
25703:48:35.874 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
25803:48:35.874 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
25903:48:35.874 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
26003:48:35.874 T:139751226894080 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
26103:48:35.874 T:139751226894080 DEBUG: Output Device : HDA NVidia
26203:48:35.874 T:139751226894080 DEBUG: Sample Rate : 44100
26303:48:35.874 T:139751226894080 DEBUG: Sample Format : AE_FMT_S32NE
26403:48:35.874 T:139751226894080 DEBUG: Channel Count : 2
26503:48:35.874 T:139751226894080 DEBUG: Channel Layout: FL,FR
26603:48:35.874 T:139751226894080 DEBUG: Frames : 2048
26703:48:35.874 T:139751226894080 DEBUG: Frame Size : 8
26803:48:36.053 T:139751218501376 DEBUG: Thread RemoteControl start, auto delete: false
26903:48:36.054 T:139751218501376 INFO: LIRC Process: using: /dev/lircd
27003:48:36.054 T:139751218501376 INFO: LIRC Connect: successfully started
27103:48:36.054 T:139751218501376 DEBUG: Thread RemoteControl 139751218501376 terminating
27203:48:36.107 T:139751857735744 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
27303:48:36.166 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
27403:48:36.192 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
27503:48:36.193 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
27603:48:36.194 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
27703:48:36.195 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
27803:48:36.197 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
27903:48:36.198 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
28003:48:36.199 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
28103:48:36.199 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
28203:48:36.200 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
28303:48:36.211 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
28403:48:36.212 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
28503:48:36.213 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
28603:48:36.213 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
28703:48:36.214 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
28803:48:36.215 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
28903:48:36.216 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
29003:48:36.218 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕÐ" successfully loaded
29103:48:36.218 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ÐБВ" successfully loaded
29203:48:36.219 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
29303:48:36.220 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
29403:48:36.221 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
29503:48:36.243 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
29603:48:36.253 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded
29703:48:36.269 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕÐ" successfully loaded
29803:48:36.269 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ÐБВ" successfully loaded
29903:48:36.315 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
30003:48:36.316 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
30103:48:36.332 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
30203:48:36.333 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
30303:48:36.334 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
30403:48:36.334 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Chinese BaiduPY" successfully loaded
30503:48:36.336 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
30603:48:36.336 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ÐБВ" successfully loaded
30703:48:36.356 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
30803:48:36.356 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
30903:48:36.357 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
31003:48:36.369 T:139751857735744 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
31103:48:36.635 T:139751857735744 DEBUG: Selected UDisks as storage provider
31203:48:36.636 T:139751857735744 DEBUG: UDisks: DaemonVersion 1
31303:48:36.636 T:139751857735744 DEBUG: UDisks: Querying available devices
31403:48:36.641 T:139751857735744 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
31503:48:36.647 T:139751857735744 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
31603:48:36.652 T:139751857735744 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
31703:48:36.657 T:139751857735744 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
31803:48:36.662 T:139751857735744 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
31903:48:36.748 T:139751857735744 INFO: Available videomodes (xrandr):
32003:48:36.748 T:139751857735744 INFO: Output 'HDMI-0' has 12 modes
32103:48:36.748 T:139751857735744 INFO: ID:0x24e Name:1280x720 Refresh:60.000000 Width:1280 Height:720
32203:48:36.748 T:139751857735744 INFO: Pixel Ratio: 0.999035
32303:48:36.748 T:139751857735744 INFO: ID:0x253 Name:1280x720 Refresh:59.943432 Width:1280 Height:720
32403:48:36.748 T:139751857735744 INFO: Pixel Ratio: 0.999035
32503:48:36.748 T:139751857735744 INFO: ID:0x24f Name:1920x1080 Refresh:59.939388 Width:1920 Height:1080
32603:48:36.748 T:139751857735744 INFO: Pixel Ratio: 0.999035
32703:48:36.748 T:139751857735744 INFO: ID:0x250 Name:1920x1080 Refresh:30.026690 Width:1920 Height:1080
32803:48:36.748 T:139751857735744 INFO: Pixel Ratio: 0.999035
32903:48:36.748 T:139751857735744 INFO: ID:0x251 Name:1920x1080 Refresh:29.998381 Width:1920 Height:1080
33003:48:36.748 T:139751857735744 INFO: Pixel Ratio: 0.999035
33103:48:36.748 T:139751857735744 INFO: ID:0x252 Name:1440x480 Refresh:30.027220 Width:1440 Height:480
33203:48:36.748 T:139751857735744 INFO: Pixel Ratio: 0.592021
33303:48:36.748 T:139751857735744 INFO: ID:0x254 Name:720x480 Refresh:59.940060 Width:720 Height:480
33403:48:36.748 T:139751857735744 INFO: Pixel Ratio: 1.184041
33503:48:36.748 T:139751857735744 INFO: ID:0x255 Name:720x480 Refresh:30.027220 Width:720 Height:480
33603:48:36.748 T:139751857735744 INFO: Pixel Ratio: 1.184041
33703:48:36.749 T:139751857735744 INFO: ID:0x256 Name:640x480 Refresh:59.928570 Width:640 Height:480
33803:48:36.749 T:139751857735744 INFO: Pixel Ratio: 1.332046
33903:48:36.749 T:139751857735744 INFO: ID:0x257 Name:480x480 Refresh:59.940060 Width:480 Height:480
34003:48:36.749 T:139751857735744 INFO: Pixel Ratio: 1.776062
34103:48:36.749 T:139751857735744 INFO: ID:0x258 Name:411x480 Refresh:59.972790 Width:411 Height:480
34203:48:36.749 T:139751857735744 INFO: Pixel Ratio: 2.074233
34303:48:36.749 T:139751857735744 INFO: ID:0x259 Name:3x480 Refresh:35.305340 Width:3 Height:480
34403:48:36.749 T:139751857735744 INFO: Pixel Ratio: 284.169891
34503:48:36.749 T:139751857735744 NOTICE: Checking resolution 16
34603:48:40.617 T:139751857735744 ERROR: Failed to determine egl config for visual info
34703:48:40.617 T:139751857735744 WARNING: Visual 0x28 of the window is not suitable, looking for another one...
34803:48:40.617 T:139751857735744 NOTICE: Using visual 0x21
34903:48:40.967 T:139751857735744 NOTICE: Using visual 0x28
35003:48:41.650 T:139751857735744 INFO: GL: Maximum texture width: 8192
35103:48:41.650 T:139751857735744 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
35203:48:41.650 T:139751857735744 NOTICE: GL_VENDOR = NVIDIA Corporation
35303:48:41.650 T:139751857735744 NOTICE: GL_RENDERER = ION/PCIe/SSE2
35403:48:41.650 T:139751857735744 NOTICE: GL_VERSION = 3.3.0 NVIDIA 304.135
35503:48:41.650 T:139751857735744 NOTICE: GL_SHADING_LANGUAGE_VERSION = 3.30 NVIDIA via Cg compiler
35603:48:41.651 T:139751857735744 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 524288
35703:48:41.651 T:139751857735744 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 524288
35803:48:41.651 T:139751857735744 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
35903:48:41.652 T:139751857735744 INFO: GL: Maximum texture width: 8192
36003:48:41.985 T:139751857735744 INFO: GL: Enabling VSYNC
36103:48:42.004 T:139751857735744 INFO: load keymapping
36203:48:42.068 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
36303:48:42.087 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
36403:48:42.090 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
36503:48:42.095 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
36603:48:42.100 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/joystick.xml
36703:48:42.104 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
36803:48:42.134 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/mouse.xml
36903:48:42.135 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/remote.xml
37003:48:42.146 T:139751857735744 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
37103:48:42.172 T:139751857735744 INFO: Loading special://masterprofile/keymaps/noBS.xml
37203:48:42.183 T:139751857735744 INFO: Loading special://profile/keymaps/noBS.xml
37303:48:42.184 T:139751857735744 INFO: Loading special://xbmc/system/Lircmap.xml
37403:48:42.188 T:139751857735744 INFO: * Adding remote mapping for device 'mceusb'
37503:48:42.188 T:139751857735744 INFO: * Adding remote mapping for device 'XboxDVDDongle'
37603:48:42.188 T:139751857735744 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
37703:48:42.188 T:139751857735744 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
37803:48:42.188 T:139751857735744 INFO: * Adding remote mapping for device 'anysee'
37903:48:42.188 T:139751857735744 INFO: * Adding remote mapping for device 'iMON-PAD'
38003:48:42.189 T:139751857735744 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
38103:48:42.189 T:139751857735744 INFO: * Adding remote mapping for device 'MCE_via_iMON'
38203:48:42.189 T:139751857735744 INFO: * Adding remote mapping for device 'TwinHanRemote'
38303:48:42.189 T:139751857735744 INFO: * Adding remote mapping for device 'linux-input-layer'
38403:48:42.189 T:139751857735744 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
38503:48:42.189 T:139751857735744 INFO: * Adding remote mapping for device 'mediacenter'
38603:48:42.189 T:139751857735744 INFO: * Adding remote mapping for device 'devinput'
38703:48:42.190 T:139751857735744 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
38803:48:42.190 T:139751857735744 INFO: GUI format 1280x720, Display 1280x720@ 60.00 - Full Screen
38903:48:42.190 T:139751857735744 DEBUG: guilib: Fill viewport on change for solving rendering passes
39003:48:42.191 T:139751857735744 INFO: CLangInfo: loading resource.language.en_gb language information...
39103:48:42.235 T:139751857735744 DEBUG: trying to set locale to en_US.UTF-8
39203:48:42.235 T:139751857735744 INFO: global locale set to en_US.UTF-8
39303:48:42.236 T:139751857735744 INFO: CLangInfo: loading resource.language.en_gb language strings...
39403:48:42.287 T:139751857735744 DEBUG: LocalizeStrings: loaded 3780 strings from file resource://resource.language.en_gb/strings.po
39503:48:42.481 T:139751857735744 DEBUG: LocalizeStrings: loaded 76 strings from file /home/jamesz/.kodi/addons/script.skin.helper.widgets/resources/language/resource.language.en_gb/strings.po
39603:48:42.620 T:139751857735744 DEBUG: LocalizeStrings: loaded 167 strings from file /home/jamesz/.kodi/addons/script.extendedinfo/resources/language/resource.language.en_gb/strings.po
39703:48:42.645 T:139751857735744 DEBUG: LocalizeStrings: loaded 48 strings from file /home/jamesz/.kodi/addons/script.skin.helper.backgrounds/resources/language/resource.language.en_gb/strings.po
39803:48:42.840 T:139751857735744 DEBUG: LocalizeStrings: loaded 19 strings from file /home/jamesz/.kodi/addons/plugin.audio.shoutcast/resources/language/English/strings.xml
39903:48:43.039 T:139751857735744 DEBUG: LocalizeStrings: loaded 6 strings from file /home/jamesz/.kodi/addons/weather.yahoo/resources/language/English/strings.po
40003:48:43.091 T:139751857735744 DEBUG: LocalizeStrings: loaded 19 strings from file /home/jamesz/.kodi/addons/plugin.video.tmz/resources/language/English/strings.xml
40103:48:43.177 T:139751857735744 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.wma/resources/language/English/strings.po
40203:48:43.259 T:139751857735744 DEBUG: LocalizeStrings: loaded 31 strings from file /home/jamesz/.kodi/addons/script.simpleplaylists/resources/language/English/strings.xml
40303:48:43.353 T:139751857735744 DEBUG: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
40403:48:43.403 T:139751857735744 DEBUG: LocalizeStrings: loaded 38 strings from file /home/jamesz/.kodi/addons/script.module.youtube.dl/resources/language/English/strings.po
40503:48:43.455 T:139751857735744 DEBUG: LocalizeStrings: loaded 3 strings from file /home/jamesz/.kodi/addons/script.favourites/resources/language/English/strings.po
40603:48:43.520 T:139751857735744 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
40703:48:43.585 T:139751857735744 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /home/jamesz/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
40803:48:43.585 T:139751857735744 DEBUG: LocalizeStrings: loaded 8 strings from file /home/jamesz/.kodi/addons/metadata.themoviedb.org/resources/language/English/strings.po
40903:48:43.612 T:139751857735744 DEBUG: LocalizeStrings: loaded 21 strings from file /home/jamesz/.kodi/addons/service.xbmc.versioncheck/resources/language/English/strings.po
41003:48:43.637 T:139751857735744 DEBUG: LocalizeStrings: loaded 252 strings from file /home/jamesz/.kodi/addons/plugin.video.1channel/resources/language/English/strings.po
41103:48:43.666 T:139751857735744 DEBUG: LocalizeStrings: loaded 18 strings from file /home/jamesz/.kodi/addons/script.toolbox/resources/language/English/strings.po
41203:48:43.707 T:139751857735744 DEBUG: LocalizeStrings: loaded 27 strings from file /home/jamesz/.kodi/addons/script.globalsearch/resources/language/English/strings.po
41303:48:43.742 T:139751857735744 DEBUG: LocalizeStrings: loaded 13 strings from file /home/jamesz/.kodi/addons/service.skin.widgets/resources/language/English/strings.po
41403:48:43.762 T:139751857735744 DEBUG: LocalizeStrings: loaded 7 strings from file /home/jamesz/.kodi/addons/script.common.plugin.cache/resources/language/English/strings.xml
41503:48:43.805 T:139751857735744 DEBUG: LocalizeStrings: loaded 120 strings from file /home/jamesz/.kodi/addons/script.skinshortcuts/resources/language/English/strings.po
41603:48:43.846 T:139751857735744 DEBUG: LocalizeStrings: loaded 24 strings from file /home/jamesz/.kodi/addons/service.nextup.notification/resources/language/English/strings.xml
41703:48:43.899 T:139751857735744 DEBUG: LocalizeStrings: loaded 2 strings from file /home/jamesz/.kodi/addons/metadata.musicvideos.theaudiodb.com/resources/language/English/strings.po
41803:48:43.936 T:139751857735744 DEBUG: LocalizeStrings: loaded 183 strings from file /home/jamesz/.kodi/addons/plugin.video.youtube/resources/language/resource.language.en_gb/strings.po
41903:48:44.009 T:139751857735744 DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: /home/jamesz/.kodi/addons/plugin.video.exodus/resources/language/English/strings.po
42003:48:44.010 T:139751857735744 DEBUG: LocalizeStrings: loaded 192 strings from file /home/jamesz/.kodi/addons/plugin.video.exodus/resources/language/English/strings.po
42103:48:44.030 T:139751857735744 DEBUG: LocalizeStrings: loaded 119 strings from file /home/jamesz/.kodi/addons/script.artistslideshow/resources/language/English/strings.po
42203:48:44.087 T:139751857735744 DEBUG: LocalizeStrings: loaded 100 strings from file /home/jamesz/.kodi/addons/script.artwork.downloader/resources/language/English/strings.po
42303:48:44.117 T:139751857735744 DEBUG: LocalizeStrings: loaded 29 strings from file /home/jamesz/.kodi/addons/script.artistslideshow-helper/resources/language/English/strings.po
42403:48:44.123 T:139751857735744 DEBUG: LocalizeStrings: loaded 16 strings from file /home/jamesz/.kodi/addons/plugin.video.crackler/resources/language/English/strings.xml
42503:48:44.170 T:139751857735744 DEBUG: LocalizeStrings: loaded 43 strings from file /home/jamesz/.kodi/addons/script.module.urlresolver/resources/language/English/strings.po
42603:48:44.191 T:139751857735744 DEBUG: LocalizeStrings: loaded 39 strings from file /home/jamesz/.kodi/addons/script.skin.helper.skinbackup/resources/language/resource.language.en_gb/strings.po
42703:48:44.229 T:139751857735744 DEBUG: LocalizeStrings: loaded 24 strings from file /home/jamesz/.kodi/addons/service.library.data.provider/resources/language/resource.language.en_gb/strings.po
42803:48:44.282 T:139751857735744 DEBUG: LocalizeStrings: loaded 13 strings from file /home/jamesz/.kodi/addons/metadata.album.universal/resources/language/English/strings.po
42903:48:44.305 T:139751857735744 DEBUG: LocalizeStrings: loaded 1 strings from file /usr/share/kodi/addons/audioencoder.xbmc.builtin.aac/resources/language/English/strings.po
43003:48:44.357 T:139751857735744 DEBUG: LocalizeStrings: loaded 6 strings from file /home/jamesz/.kodi/addons/script.skin.helper.colorpicker/resources/language/resource.language.en_gb/strings.po
43103:48:44.392 T:139751857735744 DEBUG: LocalizeStrings: loaded 11 strings from file /home/jamesz/.kodi/addons/script.xbmc.debug.log/resources/language/English/strings.xml
43203:48:44.430 T:139751857735744 DEBUG: LocalizeStrings: loaded 10 strings from file /home/jamesz/.kodi/addons/metadata.tvdb.com/resources/language/English/strings.po
43303:48:44.435 T:139751857735744 DEBUG: LocalizeStrings: loaded 26 strings from file /home/jamesz/.kodi/addons/script.skin.helper.service/resources/language/resource.language.en_gb/strings.po
43403:48:44.508 T:139751857735744 DEBUG: LocalizeStrings: loaded 22 strings from file /home/jamesz/.kodi/addons/script.module.buggalo/resources/language/English/strings.xml
43503:48:44.612 T:139751857735744 DEBUG: LocalizeStrings: loaded 26 strings from file /home/jamesz/.kodi/addons/metadata.artists.universal/resources/language/English/strings.po
43603:48:44.745 T:139751857735744 DEBUG: LocalizeStrings: loaded 46 strings from file /home/jamesz/.kodi/addons/script.tv.show.next.aired/resources/language/English/strings.po
43703:48:44.821 T:139751857735744 DEBUG: LocalizeStrings: loaded 60 strings from file /home/jamesz/.kodi/addons/script.module.metadatautils/resources/language/resource.language.en_gb/strings.po
43803:48:44.886 T:139751857735744 DEBUG: LocalizeStrings: loaded 48 strings from file /home/jamesz/.kodi/addons/screensaver.atv4/resources/language/resource.language.en_gb/strings.po
43903:48:44.903 T:139751857735744 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
44003:48:44.903 T:139751857735744 DEBUG: LoadMappings - loaded node "CEC Adapter"
44103:48:44.903 T:139751857735744 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
44203:48:44.904 T:139751857735744 DEBUG: LoadMappings - loaded node "iMON HID device"
44303:48:44.904 T:139751857735744 DEBUG: LoadMappings - loaded node "WETEK Play remote"
44403:48:44.904 T:139751857735744 DEBUG: LoadMappings - loaded node ""
44503:48:44.937 T:139751857735744 DEBUG: CPeripheralBusUSB - initialised udev monitor
44603:48:44.937 T:139751857735744 DEBUG: SECTION:LoadDLL(libcec.so.4)
44703:48:44.949 T:139751857735744 DEBUG: Loading: libcec.so.4
44803:48:45.136 T:139750798141184 DEBUG: Thread PeripBusUSBUdev start, auto delete: false
44903:48:45.136 T:139750806533888 DEBUG: Thread PeripBusCEC start, auto delete: false
45003:48:45.137 T:139751218501376 DEBUG: Thread PeripBusAddon start, auto delete: false
45103:48:45.137 T:139751857735744 DEBUG: SECTION:LoadDLL(libcurl.so.4)
45203:48:45.137 T:139750757508864 DEBUG: Thread PeripEventScanner start, auto delete: false
45303:48:45.138 T:139751857735744 DEBUG: Loading: libcurl.so.4
45403:48:45.357 T:139751243679488 NOTICE: Running database version Addons27
45503:48:45.357 T:139751243679488 DEBUG: Initialize, updating databases...
45603:48:45.392 T:139751243679488 NOTICE: Running database version ViewModes6
45703:48:45.462 T:139751243679488 NOTICE: Running database version Textures13
45803:48:45.549 T:139751243679488 NOTICE: Running database version MyMusic60
45903:48:45.650 T:139751243679488 NOTICE: Running database version MyVideos107
46003:48:45.680 T:139751243679488 NOTICE: Running database version TV29
46103:48:45.784 T:139751243679488 NOTICE: Running database version Epg11
46203:48:45.786 T:139751243679488 DEBUG: Initialize, updating databases... DONE
46303:48:45.790 T:139751857735744 NOTICE: start dvd mediatype detection
46403:48:45.791 T:139751391409920 DEBUG: Thread DetectDVDMedia start, auto delete: false
46503:48:45.791 T:139751391409920 DEBUG: Compiled with libcdio Version 0.83
46603:48:45.791 T:139751857735744 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
46703:48:45.904 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
46803:48:45.905 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
46903:48:45.934 T:139751857735744 INFO: Unloading old skin ...
47003:48:46.052 T:139751857735744 INFO: load skin from: /home/jamesz/.kodi/addons/skin.titan.kryptonbeta (version: 3.7.65)
47103:48:46.052 T:139751857735744 INFO: load fonts for skin...
47203:48:46.098 T:139751857735744 INFO: Loading fonts from /home/jamesz/.kodi/addons/skin.titan.kryptonbeta/1080i/Font.xml
47303:48:46.435 T:139751857735744 DEBUG: LocalizeStrings: loaded 736 strings from file /home/jamesz/.kodi/addons/skin.titan.kryptonbeta/language/resource.language.en_gb/strings.po
47403:48:46.445 T:139751857735744 INFO: Loading skin includes from /home/jamesz/.kodi/addons/skin.titan.kryptonbeta/1080i/Includes.xml
47503:48:47.489 T:139751857735744 INFO: load new skin...
47603:48:47.489 T:139751857735744 INFO: Loading user windows, path /home/jamesz/.kodi/addons/skin.titan.kryptonbeta/1080i
47703:48:47.505 T:139751857735744 DEBUG: Load Skin XML: 16.49ms
47803:48:47.505 T:139751857735744 INFO: initialize new skin...
47903:48:47.505 T:139751857735744 DEBUG: guilib: Fill viewport on change for solving rendering passes
48003:48:47.505 T:139751857735744 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
48103:48:47.546 T:139751857735744 DEBUG: OpenBundle - Opened bundle /home/jamesz/.kodi/addons/skin.titan.kryptonbeta/media/Textures.xbt
48203:48:47.552 T:139751857735744 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
48303:48:47.620 T:139751857735744 INFO: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
48403:48:47.635 T:139751857735744 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
48503:48:47.809 T:139751857735744 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
48603:48:47.811 T:139751857735744 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
48703:48:47.816 T:139751857735744 INFO: Loading resource://resource.uisounds.kodi/sounds.xml
48803:48:47.956 T:139751857735744 INFO: skin loaded...
48903:48:47.957 T:139751857735744 DEBUG: Activating window ID: 12997
49003:48:47.967 T:139751857735744 DEBUG: ------ Window Init () ------
49103:48:47.967 T:139751857735744 INFO: load splash image: /usr/share/kodi/media/Splash.png
49203:48:48.018 T:139751857735744 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
49303:48:48.018 T:139751857735744 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
49403:48:48.018 T:139751857735744 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
49503:48:48.018 T:139751857735744 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
49603:48:48.074 T:139751857735744 INFO: JSONRPC v8.0.0: Successfully initialized
49703:48:48.074 T:139751857735744 DEBUG: ADDON: Starting service addons.
49803:48:48.081 T:139750496478976 DEBUG: Thread LanguageInvoker start, auto delete: false
49903:48:48.082 T:139750496478976 INFO: initializing python engine.
50003:48:48.082 T:139750488086272 DEBUG: Thread LanguageInvoker start, auto delete: false
50103:48:48.082 T:139750488086272 DEBUG: Previous line repeats 1 times.
50203:48:48.082 T:139750488086272 INFO: initializing python engine.
50303:48:48.083 T:139750471300864 INFO: Previous line repeats 1 times.
50403:48:48.083 T:139750471300864 DEBUG: Thread LanguageInvoker start, auto delete: false
50503:48:48.083 T:139750471300864 INFO: initializing python engine.
50603:48:48.083 T:139750462908160 DEBUG: Thread LanguageInvoker start, auto delete: false
50703:48:48.083 T:139750462908160 INFO: initializing python engine.
50803:48:48.084 T:139750048528128 DEBUG: Thread LanguageInvoker start, auto delete: false
50903:48:48.084 T:139750048528128 INFO: initializing python engine.
51003:48:48.084 T:139750040135424 DEBUG: Thread LanguageInvoker start, auto delete: false
51103:48:48.085 T:139750040135424 INFO: initializing python engine.
51203:48:48.085 T:139750031742720 DEBUG: Thread LanguageInvoker start, auto delete: false
51303:48:48.085 T:139750031742720 INFO: initializing python engine.
51403:48:48.085 T:139750023350016 DEBUG: Thread LanguageInvoker start, auto delete: false
51503:48:48.085 T:139750023350016 INFO: initializing python engine.
51603:48:48.085 T:139750014957312 DEBUG: Thread LanguageInvoker start, auto delete: false
51703:48:48.085 T:139750014957312 INFO: initializing python engine.
51803:48:48.085 T:139750006564608 DEBUG: Thread LanguageInvoker start, auto delete: false
51903:48:48.085 T:139750006564608 INFO: initializing python engine.
52003:48:48.086 T:139751857735744 DEBUG: Activating window ID: 12999
52103:48:48.086 T:139751857735744 DEBUG: ------ Window Init (Startup.xml) ------
52203:48:48.086 T:139751857735744 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
52303:48:48.137 T:139749998171904 DEBUG: Thread Timer start, auto delete: false
52403:48:48.147 T:139749998171904 DEBUG: Thread Timer 139749998171904 terminating
52503:48:48.148 T:139749511657216 DEBUG: Thread Timer start, auto delete: false
52603:48:48.157 T:139749511657216 DEBUG: Thread Timer 139749511657216 terminating
52703:48:48.157 T:139749998171904 DEBUG: Thread Timer start, auto delete: false
52803:48:48.187 T:139749998171904 DEBUG: Thread Timer 139749998171904 terminating
52903:48:48.188 T:139749511657216 DEBUG: Thread Timer start, auto delete: false
53003:48:48.197 T:139749511657216 DEBUG: Thread Timer 139749511657216 terminating
53103:48:48.198 T:139749998171904 DEBUG: Thread Timer start, auto delete: false
53203:48:48.206 T:139751857735744 WARNING: CApplication::Initialize - startup.xml taints init process
53303:48:48.215 T:139751857735744 DEBUG: ContextMenuManager: addon menus reloaded.
53403:48:48.215 T:139751857735744 INFO: removing tempfiles
53503:48:48.275 T:139751857735744 DEBUG: ADDON: Starting service addons.
53603:48:48.281 T:139749511657216 DEBUG: Thread LanguageInvoker start, auto delete: false
53703:48:48.282 T:139749511657216 INFO: initializing python engine.
53803:48:48.282 T:139749503264512 DEBUG: Thread LanguageInvoker start, auto delete: false
53903:48:48.282 T:139749503264512 INFO: initializing python engine.
54003:48:48.312 T:139749494871808 DEBUG: Thread LanguageInvoker start, auto delete: false
54103:48:48.312 T:139749494871808 INFO: initializing python engine.
54203:48:48.313 T:139749486479104 DEBUG: Thread LanguageInvoker start, auto delete: false
54303:48:48.313 T:139749486479104 INFO: initializing python engine.
54403:48:48.329 T:139751857735744 DEBUG: CRepositoryUpdater: previous update at 04/25/2018 9:34:46 PM, next at 04/26/2018 9:34:46 PM
54503:48:48.330 T:139751857735744 NOTICE: initialize done
54603:48:48.330 T:139751857735744 NOTICE: Running the application...
54703:48:48.330 T:139749478086400 DEBUG: Thread Timer start, auto delete: false
54803:48:48.476 T:139751857735744 DEBUG: Activating window ID: 10000
54903:48:48.476 T:139751857735744 DEBUG: ------ Window Deinit (Startup.xml) ------
55003:48:48.476 T:139751857735744 DEBUG: ------ Window Init (Home.xml) ------
55103:48:48.476 T:139751857735744 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
55203:48:48.568 T:139751857735744 WARNING: Skin has invalid include: skinshortcuts-template-Widgets
55303:48:48.719 T:139749998171904 DEBUG: Thread Timer 139749998171904 terminating
55403:48:48.953 T:139750496478976 DEBUG: CPythonInvoker(0, /home/jamesz/.kodi/addons/script.skin.helper.widgets/service.py): start processing
55503:48:48.953 T:139750488086272 DEBUG: CPythonInvoker(1, /home/jamesz/.kodi/addons/script.skin.helper.backgrounds/service.py): start processing
55603:48:48.953 T:139750479693568 DEBUG: CPythonInvoker(2, /home/jamesz/.kodi/addons/service.xbmc.versioncheck/service.py): start processing
55703:48:48.953 T:139750471300864 DEBUG: CPythonInvoker(3, /home/jamesz/.kodi/addons/service.skin.widgets/default.py): start processing
55803:48:48.953 T:139750462908160 DEBUG: CPythonInvoker(4, /home/jamesz/.kodi/addons/service.nextup.notification/service.py): start processing
55903:48:48.953 T:139750048528128 DEBUG: CPythonInvoker(5, /home/jamesz/.kodi/addons/script.grab.fanart/service.py): start processing
56003:48:48.953 T:139750040135424 DEBUG: CPythonInvoker(6, /home/jamesz/.kodi/addons/script.skin.helper.skinbackup/service.py): start processing
56103:48:48.953 T:139750031742720 DEBUG: CPythonInvoker(7, /home/jamesz/.kodi/addons/service.library.data.provider/service.py): start processing
56203:48:48.953 T:139750023350016 DEBUG: CPythonInvoker(8, /home/jamesz/.kodi/addons/script.skin.helper.service/service.py): start processing
56303:48:48.954 T:139750014957312 DEBUG: CPythonInvoker(9, /home/jamesz/.kodi/addons/script.tv.show.next.aired/service.py): start processing
56403:48:48.954 T:139750006564608 DEBUG: CPythonInvoker(10, /home/jamesz/.kodi/addons/screensaver.atv4/service.py): start processing
56503:48:48.954 T:139749511657216 DEBUG: CPythonInvoker(11, /home/jamesz/.kodi/addons/plugin.video.1channel/service.py): start processing
56603:48:48.954 T:139749503264512 DEBUG: CPythonInvoker(12, /home/jamesz/.kodi/addons/script.common.plugin.cache/default.py): start processing
56703:48:48.954 T:139749494871808 DEBUG: CPythonInvoker(13, /home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): start processing
56803:48:48.954 T:139749486479104 DEBUG: CPythonInvoker(14, /home/jamesz/.kodi/addons/plugin.video.exodus/service.py): start processing
56903:48:49.089 T:139751857735744 WARNING: Trying to add unsupported control type 1
57003:48:49.424 T:139749998171904 WARNING: Previous line repeats 4 times.
57103:48:49.424 T:139749998171904 DEBUG: Thread LanguageInvoker start, auto delete: false
57203:48:49.424 T:139751243679488 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
57303:48:49.424 T:139749998171904 INFO: initializing python engine.
57403:48:49.424 T:139749998171904 DEBUG: CPythonInvoker(15, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): start processing
57503:48:49.426 T:139751857735744 DEBUG: no profile autoexec.py (/home/jamesz/.kodi/userdata/autoexec.py) found, skipping
57603:48:49.426 T:139751857735744 DEBUG: NetworkMessage - Starting network services
57703:48:49.436 T:139751857735744 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
57803:48:49.436 T:139751857735744 NOTICE: starting zeroconf publishing
57903:48:49.439 T:139749461300992 DEBUG: Thread JobWorker start, auto delete: true
58003:48:49.439 T:139751857735744 NOTICE: CWebServer[8080]: Started
58103:48:49.439 T:139751857735744 NOTICE: starting upnp client
58203:48:49.440 T:139749461300992 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi Living Room port:8080
58303:48:49.440 T:139749461300992 DEBUG: CZeroconfAvahi::addService() named: Kodi Living Room type: _http._tcp port:8080
58403:48:49.444 T:139749461300992 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi Living Room port:8080
58503:48:49.444 T:139749461300992 DEBUG: CZeroconfAvahi::addService() named: Kodi Living Room type: _xbmc-jsonrpc-h._tcp port:8080
58603:48:49.461 T:139751243679488 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.idSong, songartistview.idRole, songartistview.iOrder
58703:48:49.511 T:139751857735744 NOTICE: starting upnp server
58803:48:49.642 T:139750040135424 DEBUG: -->Python Interpreter Initialized<--
58903:48:49.642 T:139750040135424 DEBUG: CPythonInvoker(6, /home/jamesz/.kodi/addons/script.skin.helper.skinbackup/service.py): the source file to load is "/home/jamesz/.kodi/addons/script.skin.helper.skinbackup/service.py"
59003:48:49.716 T:139751857735744 NOTICE: starting upnp controller
59103:48:49.716 T:139751857735744 NOTICE: starting upnp renderer
59203:48:49.722 T:139748748281600 DEBUG: Thread EventServer start, auto delete: false
59303:48:49.722 T:139748748281600 NOTICE: ES: Starting UDP Event server on port 9777
59403:48:49.722 T:139748748281600 NOTICE: UDP: Listening on port 9777 (ipv6 : true)
59503:48:49.722 T:139749461300992 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi Living Room port:9777
59603:48:49.722 T:139749461300992 DEBUG: CZeroconfAvahi::addService() named: Kodi Living Room type: _xbmc-events._udp port:9777
59703:48:49.724 T:139751857735744 INFO: JSONRPC Server: Successfully initialized
59803:48:49.724 T:139748731496192 DEBUG: Thread JobWorker start, auto delete: true
59903:48:49.724 T:139751857735744 DEBUG: UPower: Received an unknown signal NameAcquired
60003:48:49.724 T:139748739888896 DEBUG: Thread TCPServer start, auto delete: false
60103:48:49.725 T:139748731496192 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi Living Room port:9090
60203:48:49.726 T:139748723103488 DEBUG: Thread JobWorker start, auto delete: true
60303:48:49.727 T:139748731496192 DEBUG: CZeroconfAvahi::addService() named: Kodi Living Room type: _xbmc-jsonrpc._tcp port:9090
60403:48:49.925 T:139748723103488 DEBUG: DoWork - took 198 ms to load resource://resource.images.skinbackgrounds.titanium/global_blue.jpg
60503:48:50.034 T:139751857735744 DEBUG: ------ Window Init () ------
60603:48:50.040 T:139750496478976 DEBUG: -->Python Interpreter Initialized<--
60703:48:50.040 T:139750496478976 DEBUG: CPythonInvoker(0, /home/jamesz/.kodi/addons/script.skin.helper.widgets/service.py): the source file to load is "/home/jamesz/.kodi/addons/script.skin.helper.widgets/service.py"
60803:48:50.311 T:139749469693696 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
60903:48:50.560 T:139749461300992 DEBUG: Previous line repeats 1 times.
61003:48:50.560 T:139749461300992 DEBUG: DoWork - took 379 ms to load special://masterprofile/Thumbnails/2/2527756f.jpg
61103:48:50.594 T:139749469693696 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
61203:48:50.638 T:139751243679488 DEBUG: Previous line repeats 1 times.
61303:48:50.639 T:139751243679488 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
61403:48:50.656 T:139751243679488 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
61503:48:50.663 T:139748731496192 DEBUG: DoWork - took 191 ms to load special://masterprofile/Thumbnails/1/104b3030.jpg
61603:48:50.698 T:139749494871808 DEBUG: -->Python Interpreter Initialized<--
61703:48:50.698 T:139749494871808 DEBUG: CPythonInvoker(13, /home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): the source file to load is "/home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/startup.py"
61803:48:50.852 T:139748723103488 DEBUG: DoWork - took 239 ms to load special://masterprofile/Thumbnails/1/197b1b43.jpg
61903:48:50.910 T:139748731496192 DEBUG: DoWork - took 119 ms to load special://masterprofile/Thumbnails/2/253c6d5a.png
62003:48:50.910 T:139748731496192 INFO: WEATHER: Downloading weather
62103:48:50.911 T:139748706318080 DEBUG: Thread LanguageInvoker start, auto delete: false
62203:48:50.911 T:139748706318080 INFO: initializing python engine.
62303:48:50.911 T:139748706318080 DEBUG: CPythonInvoker(16, /home/jamesz/.kodi/addons/weather.yahoo/default.py): start processing
62403:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/1/
62503:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/4/
62603:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/5/
62703:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/6/
62803:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/100/
62903:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/107/
63003:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/108/
63103:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/7/
63203:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/101/
63303:48:51.041 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/102/
63403:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/103/
63503:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/104/
63603:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/105/
63703:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/106/
63803:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/F/
63903:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/14/
64003:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/2/
64103:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/8/
64203:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/9/
64303:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/A/
64403:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/E/
64503:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/200/
64603:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/201/
64703:48:51.042 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/202/
64803:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/203/
64903:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/204/
65003:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/205/
65103:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/10/
65203:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/15/
65303:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/3/
65403:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/B/
65503:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/C/
65603:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/D/
65703:48:51.043 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/D2/
65803:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/300/
65903:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/306/
66003:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/301/
66103:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/302/
66203:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/303/
66303:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/304/
66403:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/305/
66503:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/11/
66603:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/16/
66703:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/18/
66803:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/19/
66903:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/1A/
67003:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/1B/
67103:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/1C/
67203:48:51.044 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/1D/
67303:48:51.045 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/1E/
67403:48:51.045 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/400/
67503:48:51.045 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/401/
67603:48:51.045 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/402/
67703:48:51.045 T:139749461300992 DEBUG: DoWork - took 259 ms to load special://masterprofile/Thumbnails/a/a11d4f1a.png
67803:48:51.045 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/403/
67903:48:51.045 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/404/
68003:48:51.045 T:139748612282112 DEBUG: UPNP: notfified container update upnp://4112c9e5-f41f-4232-ad3e-1fe65097eb9d/405/
68103:48:51.239 T:139750479693568 DEBUG: -->Python Interpreter Initialized<--
68203:48:51.239 T:139750479693568 DEBUG: CPythonInvoker(2, /home/jamesz/.kodi/addons/service.xbmc.versioncheck/service.py): the source file to load is "/home/jamesz/.kodi/addons/service.xbmc.versioncheck/service.py"
68303:48:51.239 T:139750496478976 DEBUG: CPythonInvoker(0, /home/jamesz/.kodi/addons/script.skin.helper.widgets/service.py): setting the Python path to /home/jamesz/.kodi/addons/script.skin.helper.widgets:/home/jamesz/.kodi/addons/script.module.arrow/lib:/home/jamesz/.kodi/addons/script.module.beautifulsoup/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.dateutil/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.metadatautils/lib:/home/jamesz/.kodi/addons/script.module.musicbrainz/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplecache/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.six/lib:/home/jamesz/.kodi/addons/script.module.thetvdb/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
68403:48:51.239 T:139750496478976 DEBUG: CPythonInvoker(0, /home/jamesz/.kodi/addons/script.skin.helper.widgets/service.py): entering source directory /home/jamesz/.kodi/addons/script.skin.helper.widgets
68503:48:51.239 T:139750496478976 DEBUG: CPythonInvoker(0, /home/jamesz/.kodi/addons/script.skin.helper.widgets/service.py): instantiating addon using automatically obtained id of "script.skin.helper.widgets" dependent on version 2.24.0 of the xbmc.python api
68603:48:51.241 T:139751243679488 DEBUG: RunQuery took 456 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10
68703:48:51.676 T:139750040135424 DEBUG: CPythonInvoker(6, /home/jamesz/.kodi/addons/script.skin.helper.skinbackup/service.py): setting the Python path to /home/jamesz/.kodi/addons/script.skin.helper.skinbackup:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
68803:48:51.676 T:139750040135424 DEBUG: CPythonInvoker(6, /home/jamesz/.kodi/addons/script.skin.helper.skinbackup/service.py): entering source directory /home/jamesz/.kodi/addons/script.skin.helper.skinbackup
68903:48:51.765 T:139751243679488 DEBUG: RunQuery took 407 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10
69003:48:51.821 T:139751243679488 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10
69103:48:51.824 T:139751243679488 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
69203:48:51.830 T:139751243679488 DEBUG: GetArtistsByWhere query: SELECT COUNT(DISTINCT artistview.idArtist) FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
69303:48:51.833 T:139750040135424 DEBUG: CPythonInvoker(6, /home/jamesz/.kodi/addons/script.skin.helper.skinbackup/service.py): instantiating addon using automatically obtained id of "script.skin.helper.skinbackup" dependent on version 2.13.0 of the xbmc.python api
69403:48:51.945 T:139749494871808 DEBUG: CPythonInvoker(13, /home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): setting the Python path to /home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
69503:48:51.945 T:139749494871808 DEBUG: CPythonInvoker(13, /home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): entering source directory /home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib
69603:48:51.976 T:139749494871808 DEBUG: CPythonInvoker(13, /home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/startup.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 2.20.0 of the xbmc.python api
69703:48:52.383 T:139750006564608 DEBUG: -->Python Interpreter Initialized<--
69803:48:52.383 T:139750006564608 DEBUG: CPythonInvoker(10, /home/jamesz/.kodi/addons/screensaver.atv4/service.py): the source file to load is "/home/jamesz/.kodi/addons/screensaver.atv4/service.py"
69903:48:52.383 T:139750023350016 DEBUG: -->Python Interpreter Initialized<--
70003:48:52.383 T:139750023350016 DEBUG: CPythonInvoker(8, /home/jamesz/.kodi/addons/script.skin.helper.service/service.py): the source file to load is "/home/jamesz/.kodi/addons/script.skin.helper.service/service.py"
70103:48:52.386 T:139749511657216 DEBUG: -->Python Interpreter Initialized<--
70203:48:52.386 T:139749511657216 DEBUG: CPythonInvoker(11, /home/jamesz/.kodi/addons/plugin.video.1channel/service.py): the source file to load is "/home/jamesz/.kodi/addons/plugin.video.1channel/service.py"
70303:48:53.165 T:139751243679488 DEBUG: GetImageHash - unable to stat url /media/XBMC 1/Movies/Hateful 8 (The)/The Hateful 8-fanart.jpg
70403:48:53.166 T:139751243679488 DEBUG: GetImageHash - unable to stat url /media/XBMC 4/TV/Walking Dead (The)/fanart.jpg
70503:48:53.167 T:139751243679488 DEBUG: GetImageHash - unable to stat url /media/XBMC 2/Music Videos, Concerts & Docs/Billy Joel/Billy Joel.jpg
70603:48:53.179 T:139750471300864 DEBUG: -->Python Interpreter Initialized<--
70703:48:53.180 T:139750471300864 DEBUG: CPythonInvoker(3, /home/jamesz/.kodi/addons/service.skin.widgets/default.py): the source file to load is "/home/jamesz/.kodi/addons/service.skin.widgets/default.py"
70803:48:53.334 T:139748687533824 WARNING: CPlayerCoreFactory::GetPlayer(Pioneer Receiver): no such player: Pioneer Receiver
70903:48:53.334 T:139748687533824 DEBUG: CPlayerCoreConfig::<ctor>: created player Pioneer Receiver
71003:48:56.020 T:139749511657216 DEBUG: CPythonInvoker(11, /home/jamesz/.kodi/addons/plugin.video.1channel/service.py): setting the Python path to /home/jamesz/.kodi/addons/plugin.video.1channel:/home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/:/home/jamesz/.kodi/addons/script.module.addon.common/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.metahandler/lib:/home/jamesz/.kodi/addons/script.module.myconnpy/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/home/jamesz/.kodi/addons/script.module.urlresolver/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
71103:48:56.020 T:139749511657216 DEBUG: CPythonInvoker(11, /home/jamesz/.kodi/addons/plugin.video.1channel/service.py): entering source directory /home/jamesz/.kodi/addons/plugin.video.1channel
71203:48:56.020 T:139749511657216 DEBUG: CPythonInvoker(11, /home/jamesz/.kodi/addons/plugin.video.1channel/service.py): instantiating addon using automatically obtained id of "plugin.video.1channel" dependent on version 2.1.0 of the xbmc.python api
71303:48:56.175 T:139750040135424 DEBUG: Skin Helper Backup --> Service started
71403:48:56.176 T:139750488086272 DEBUG: -->Python Interpreter Initialized<--
71503:48:56.176 T:139750488086272 DEBUG: CPythonInvoker(1, /home/jamesz/.kodi/addons/script.skin.helper.backgrounds/service.py): the source file to load is "/home/jamesz/.kodi/addons/script.skin.helper.backgrounds/service.py"
71603:48:56.179 T:139750471300864 DEBUG: CPythonInvoker(3, /home/jamesz/.kodi/addons/service.skin.widgets/default.py): setting the Python path to /home/jamesz/.kodi/addons/service.skin.widgets:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
71703:48:56.179 T:139750471300864 DEBUG: CPythonInvoker(3, /home/jamesz/.kodi/addons/service.skin.widgets/default.py): entering source directory /home/jamesz/.kodi/addons/service.skin.widgets
71803:48:56.180 T:139750471300864 DEBUG: CPythonInvoker(3, /home/jamesz/.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
71903:48:56.238 T:139749494871808 DEBUG: [plugin.video.youtube] YouTube settings startup initialization...
72003:48:56.238 T:139750031742720 DEBUG: -->Python Interpreter Initialized<--
72103:48:56.238 T:139750031742720 DEBUG: CPythonInvoker(7, /home/jamesz/.kodi/addons/service.library.data.provider/service.py): the source file to load is "/home/jamesz/.kodi/addons/service.library.data.provider/service.py"
72203:48:56.239 T:139750023350016 DEBUG: CPythonInvoker(8, /home/jamesz/.kodi/addons/script.skin.helper.service/service.py): setting the Python path to /home/jamesz/.kodi/addons/script.skin.helper.service:/home/jamesz/.kodi/addons/script.module.arrow/lib:/home/jamesz/.kodi/addons/script.module.beautifulsoup/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.cherrypy/lib:/home/jamesz/.kodi/addons/script.module.dateutil/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.metadatautils/lib:/home/jamesz/.kodi/addons/script.module.musicbrainz/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplecache/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.six/lib:/home/jamesz/.kodi/addons/script.module.thetvdb/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
72303:48:56.239 T:139750023350016 DEBUG: CPythonInvoker(8, /home/jamesz/.kodi/addons/script.skin.helper.service/service.py): entering source directory /home/jamesz/.kodi/addons/script.skin.helper.service
72403:48:56.240 T:139750023350016 DEBUG: CPythonInvoker(8, /home/jamesz/.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
72503:48:56.421 T:139749494871808 NOTICE: [plugin.video.youtube] Startup: detected Krypton (Kodi-17.6), DASH_SUPPORT_ADDON = True
72603:48:56.497 T:139749998171904 DEBUG: -->Python Interpreter Initialized<--
72703:48:56.497 T:139749998171904 DEBUG: CPythonInvoker(15, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): the source file to load is "/home/jamesz/.kodi/addons/script.skinshortcuts/default.py"
72803:48:56.611 T:139750462908160 DEBUG: -->Python Interpreter Initialized<--
72903:48:56.611 T:139750462908160 DEBUG: CPythonInvoker(4, /home/jamesz/.kodi/addons/service.nextup.notification/service.py): the source file to load is "/home/jamesz/.kodi/addons/service.nextup.notification/service.py"
73003:48:56.820 T:139750479693568 DEBUG: CPythonInvoker(2, /home/jamesz/.kodi/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/jamesz/.kodi/addons/service.xbmc.versioncheck:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
73103:48:56.820 T:139750479693568 DEBUG: CPythonInvoker(2, /home/jamesz/.kodi/addons/service.xbmc.versioncheck/service.py): entering source directory /home/jamesz/.kodi/addons/service.xbmc.versioncheck
73203:48:56.820 T:139750479693568 DEBUG: CPythonInvoker(2, /home/jamesz/.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
73303:48:56.837 T:139750014957312 DEBUG: -->Python Interpreter Initialized<--
73403:48:56.838 T:139750014957312 DEBUG: CPythonInvoker(9, /home/jamesz/.kodi/addons/script.tv.show.next.aired/service.py): the source file to load is "/home/jamesz/.kodi/addons/script.tv.show.next.aired/service.py"
73503:49:00.402 T:139750496478976 NOTICE: Skin Helper Widgets --> Backgroundservice started
73603:49:00.403 T:139749998171904 DEBUG: CPythonInvoker(15, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): setting the Python path to /home/jamesz/.kodi/addons/script.skinshortcuts:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.unidecode/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
73703:49:00.403 T:139749998171904 DEBUG: CPythonInvoker(15, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): entering source directory /home/jamesz/.kodi/addons/script.skinshortcuts
73803:49:00.403 T:139750023350016 DEBUG: Skin Helper Simplecache --> Initialized
73903:49:00.404 T:139750023350016 DEBUG: script.module.metadatautils --> Initialized
74003:49:00.404 T:139749998171904 DEBUG: CPythonInvoker(15, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): instantiating addon using automatically obtained id of "script.skinshortcuts" dependent on version 2.20.0 of the xbmc.python api
74103:49:00.455 T:139750488086272 DEBUG: CPythonInvoker(1, /home/jamesz/.kodi/addons/script.skin.helper.backgrounds/service.py): setting the Python path to /home/jamesz/.kodi/addons/script.skin.helper.backgrounds:/home/jamesz/.kodi/addons/script.module.arrow/lib:/home/jamesz/.kodi/addons/script.module.beautifulsoup/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.dateutil/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.metadatautils/lib:/home/jamesz/.kodi/addons/script.module.musicbrainz/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplecache/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.six/lib:/home/jamesz/.kodi/addons/script.module.thetvdb/lib:/home/jamesz/.kodi/addons/script.module.urllib3/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:/usr/lib/python2.7/dist-packages/gtk-2.0
74203:49:00.455 T:139750488086272 DEBUG: CPythonInvoker(1, /home/jamesz/.kodi/addons/script.skin.helper.backgrounds/service.py): entering source directory /home/jamesz/.kodi/addons/script.skin.helper.backgrounds
74303:49:00.456 T:139750023350016 DEBUG: Skin Helper Simplecache --> Initialized
74403:49:00.457 T:139750048528128 DEBUG: -->Python Interpreter Initialized<--
74503:49:00.457 T:139750048528128 DEBUG: CPythonInvoker(5, /home/jamesz/.kodi/addons/script.grab.fanart/service.py): the source file to load is "/home/jamesz/.kodi/addons/script.grab.fanart/service.py"
74603:49:00.458 T:139750488086272 DEBUG: CPythonInvoker(1, /home/jamesz/.kodi/addons/script.skin.helper.backgrounds/service.py): instantiating addon using automatically obtained id of "script.skin.helper.backgrounds" dependent on version 2.13.0 of the xbmc.python api
74703:49:00.477 T:139748612282112 DEBUG: Skin Helper Service --> ListItemMonitor - started
74803:49:00.477 T:139750006564608 DEBUG: CPythonInvoker(10, /home/jamesz/.kodi/addons/screensaver.atv4/service.py): setting the Python path to /home/jamesz/.kodi/addons/screensaver.atv4:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
74903:49:00.477 T:139750006564608 DEBUG: CPythonInvoker(10, /home/jamesz/.kodi/addons/screensaver.atv4/service.py): entering source directory /home/jamesz/.kodi/addons/screensaver.atv4
75003:49:00.478 T:139750006564608 DEBUG: CPythonInvoker(10, /home/jamesz/.kodi/addons/screensaver.atv4/service.py): instantiating addon using automatically obtained id of "screensaver.atv4" dependent on version 2.25.0 of the xbmc.python api
75103:49:00.489 T:139749503264512 DEBUG: -->Python Interpreter Initialized<--
75203:49:00.489 T:139749503264512 DEBUG: CPythonInvoker(12, /home/jamesz/.kodi/addons/script.common.plugin.cache/default.py): the source file to load is "/home/jamesz/.kodi/addons/script.common.plugin.cache/default.py"
75303:49:00.489 T:139748662544128 NOTICE: Skin Helper Service --> Starting WebService on port 52307
75403:49:00.517 T:139750023350016 NOTICE: Skin Helper Service --> Skin Helper Service version 1.1.24 started
75503:49:00.658 T:139750462908160 DEBUG: CPythonInvoker(4, /home/jamesz/.kodi/addons/service.nextup.notification/service.py): setting the Python path to /home/jamesz/.kodi/addons/service.nextup.notification:/home/jamesz/.kodi/addons/script.module.addon.signals/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
75603:49:00.658 T:139750462908160 DEBUG: CPythonInvoker(4, /home/jamesz/.kodi/addons/service.nextup.notification/service.py): entering source directory /home/jamesz/.kodi/addons/service.nextup.notification
75703:49:00.658 T:139750462908160 DEBUG: CPythonInvoker(4, /home/jamesz/.kodi/addons/service.nextup.notification/service.py): instantiating addon using automatically obtained id of "service.nextup.notification" dependent on version 2.20.0 of the xbmc.python api
75803:49:02.749 T:139750488086272 DEBUG: Skin Helper Simplecache --> Initialized
75903:49:02.749 T:139750488086272 DEBUG: Previous line repeats 1 times.
76003:49:02.749 T:139750488086272 DEBUG: script.module.metadatautils --> Initialized
76103:49:02.750 T:139748620674816 NOTICE: Skin Helper Backgrounds --> BackgroundsUpdater - started
76203:49:02.752 T:139750006564608 INFO: CPythonInvoker(10, /home/jamesz/.kodi/addons/screensaver.atv4/service.py): script successfully run
76303:49:02.781 T:139750014957312 DEBUG: CPythonInvoker(9, /home/jamesz/.kodi/addons/script.tv.show.next.aired/service.py): setting the Python path to /home/jamesz/.kodi/addons/script.tv.show.next.aired:/home/jamesz/.kodi/addons/script.module.arrow/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.dateutil/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplecache/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.six/lib:/home/jamesz/.kodi/addons/script.module.thetvdb/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
76403:49:02.781 T:139750014957312 DEBUG: CPythonInvoker(9, /home/jamesz/.kodi/addons/script.tv.show.next.aired/service.py): entering source directory /home/jamesz/.kodi/addons/script.tv.show.next.aired
76503:49:02.781 T:139750031742720 DEBUG: CPythonInvoker(7, /home/jamesz/.kodi/addons/service.library.data.provider/service.py): setting the Python path to /home/jamesz/.kodi/addons/service.library.data.provider:/home/jamesz/.kodi/addons/script.module.routing/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
76603:49:02.781 T:139750031742720 DEBUG: CPythonInvoker(7, /home/jamesz/.kodi/addons/service.library.data.provider/service.py): entering source directory /home/jamesz/.kodi/addons/service.library.data.provider
76703:49:02.782 T:139750014957312 DEBUG: CPythonInvoker(9, /home/jamesz/.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
76803:49:02.800 T:139750031742720 DEBUG: CPythonInvoker(7, /home/jamesz/.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
76903:49:02.989 T:139750048528128 DEBUG: CPythonInvoker(5, /home/jamesz/.kodi/addons/script.grab.fanart/service.py): setting the Python path to /home/jamesz/.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:/usr/lib/python2.7/dist-packages/gtk-2.0
77003:49:02.990 T:139750048528128 DEBUG: CPythonInvoker(5, /home/jamesz/.kodi/addons/script.grab.fanart/service.py): entering source directory /home/jamesz/.kodi/addons/script.grab.fanart
77103:49:02.990 T:139750048528128 DEBUG: CPythonInvoker(5, /home/jamesz/.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
77203:49:03.022 T:139748706318080 DEBUG: -->Python Interpreter Initialized<--
77303:49:03.023 T:139748706318080 DEBUG: CPythonInvoker(16, /home/jamesz/.kodi/addons/weather.yahoo/default.py): the source file to load is "/home/jamesz/.kodi/addons/weather.yahoo/default.py"
77403:49:03.126 T:139750006564608 INFO: Python script stopped
77503:49:03.126 T:139750006564608 DEBUG: Thread LanguageInvoker 139750006564608 terminating
77603:49:03.126 T:139749486479104 DEBUG: -->Python Interpreter Initialized<--
77703:49:03.127 T:139749486479104 DEBUG: CPythonInvoker(14, /home/jamesz/.kodi/addons/plugin.video.exodus/service.py): the source file to load is "/home/jamesz/.kodi/addons/plugin.video.exodus/service.py"
77803:49:03.415 T:139750014957312 NOTICE: script.tv.show.next.aired --> TV Show - Next Aired version 7.0.1 started
77903:49:03.441 T:139748687533824 DEBUG: Thread LanguageInvoker start, auto delete: false
78003:49:03.441 T:139748687533824 INFO: initializing python engine.
78103:49:03.441 T:139748687533824 DEBUG: CPythonInvoker(17, /home/jamesz/.kodi/addons/script.tv.show.next.aired/default.py): start processing
78203:49:03.618 T:139749486479104 DEBUG: CPythonInvoker(14, /home/jamesz/.kodi/addons/plugin.video.exodus/service.py): setting the Python path to /home/jamesz/.kodi/addons/plugin.video.exodus:/home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/:/home/jamesz/.kodi/addons/script.exodus.artwork/lib:/home/jamesz/.kodi/addons/script.exodus.metadata/lib:/home/jamesz/.kodi/addons/script.module.addon.common/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.metahandler/lib:/home/jamesz/.kodi/addons/script.module.myconnpy/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/home/jamesz/.kodi/addons/script.module.urlresolver/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
78303:49:03.618 T:139749486479104 DEBUG: CPythonInvoker(14, /home/jamesz/.kodi/addons/plugin.video.exodus/service.py): entering source directory /home/jamesz/.kodi/addons/plugin.video.exodus
78403:49:03.619 T:139749486479104 DEBUG: CPythonInvoker(14, /home/jamesz/.kodi/addons/plugin.video.exodus/service.py): instantiating addon using automatically obtained id of "plugin.video.exodus" dependent on version 2.19.0 of the xbmc.python api
78503:49:03.730 T:139749503264512 DEBUG: CPythonInvoker(12, /home/jamesz/.kodi/addons/script.common.plugin.cache/default.py): setting the Python path to /home/jamesz/.kodi/addons/script.common.plugin.cache:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
78603:49:03.731 T:139749503264512 DEBUG: CPythonInvoker(12, /home/jamesz/.kodi/addons/script.common.plugin.cache/default.py): entering source directory /home/jamesz/.kodi/addons/script.common.plugin.cache
78703:49:03.756 T:139749503264512 DEBUG: CPythonInvoker(12, /home/jamesz/.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
78803:49:04.298 T:139749486479104 INFO: CPythonInvoker(14, /home/jamesz/.kodi/addons/plugin.video.exodus/service.py): script successfully run
78903:49:04.326 T:139751857735744 DEBUG: RunScriptWithParams - calling plugin Exodus('plugin://plugin.video.exodus/','-1','?action=service')
79003:49:04.326 T:139748548470528 DEBUG: Thread LanguageInvoker start, auto delete: false
79103:49:04.327 T:139748548470528 INFO: initializing python engine.
79203:49:04.327 T:139748548470528 DEBUG: CPythonInvoker(18, /home/jamesz/.kodi/addons/plugin.video.exodus/exodus.py): start processing
79303:49:04.364 T:139750479693568 DEBUG: Version Check: Version 0.3.26 started
79403:49:05.344 T:139750462908160 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> Starting NextUp Service
79503:49:05.345 T:139748706318080 DEBUG: CPythonInvoker(16, /home/jamesz/.kodi/addons/weather.yahoo/default.py): setting the Python path to /home/jamesz/.kodi/addons/weather.yahoo:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
79603:49:05.345 T:139748706318080 DEBUG: CPythonInvoker(16, /home/jamesz/.kodi/addons/weather.yahoo/default.py): entering source directory /home/jamesz/.kodi/addons/weather.yahoo
79703:49:05.345 T:139750462908160 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> ======== START NEXTUP SERVICE NOTIFICATION ========
79803:49:05.345 T:139750462908160 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> KODI Version: 17.6 Git:20171114-a9a7a20
79903:49:05.345 T:139750462908160 NOTICE: NEXTUP SERVICE NOTIFICATION Service -> NEXTUP SERVICE NOTIFICATION Version: 1.0.37
80003:49:05.350 T:139750031742720 DEBUG: Library Data Provider: service version 0.3.2 started
80103:49:05.351 T:139748706318080 DEBUG: CPythonInvoker(16, /home/jamesz/.kodi/addons/weather.yahoo/default.py): instantiating addon using automatically obtained id of "weather.yahoo" dependent on version 2.25.0 of the xbmc.python api
80203:49:05.548 T:139750031742720 DEBUG: RunQuery took 48 ms for 395 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
80303:49:06.018 T:139749511657216 NOTICE: 1Channel: Service: Installed Version: 2.5.72
80403:49:06.025 T:139750471300864 DEBUG: Skin Widgets: script version 0.0.33 started
80503:49:06.207 T:139749486479104 INFO: Python script stopped
80603:49:06.207 T:139749486479104 DEBUG: Thread LanguageInvoker 139749486479104 terminating
80703:49:06.209 T:139750048528128 NOTICE: script.grab.fanart: Grab Fanart Service Started
80803:49:06.347 T:139750471300864 DEBUG: RunQuery took 39 ms for 395 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
80903:49:06.451 T:139750048528128 DEBUG: script.grab.fanart: media type is: random
81003:49:06.587 T:139750048528128 DEBUG: RunQuery took 124 ms for 1123 items query: select * from movie_view
81103:49:06.629 T:139749511657216 NOTICE: 1Channel: Loading sqlite3 as DB engine
81203:49:06.700 T:139749511657216 DEBUG: 1Channel: Running: SELECT value FROM db_info WHERE setting="version" with []
81303:49:06.820 T:139749511657216 DEBUG: 1Channel: Building PrimeWire Database
81403:49:06.821 T:139749511657216 DEBUG: 1Channel: Running: CREATE TABLE IF NOT EXISTS seasons (season UNIQUE, contents) with []
81503:49:06.821 T:139749511657216 DEBUG: 1Channel: Running: CREATE TABLE IF NOT EXISTS favorites (type, name, url, year) with []
81603:49:06.822 T:139749511657216 DEBUG: 1Channel: Running: CREATE TABLE IF NOT EXISTS subscriptions (url, title, img, year, imdbnum, days VARCHAR(7)) with []
81703:49:06.926 T:139749511657216 DEBUG: 1Channel: Running: CREATE TABLE IF NOT EXISTS url_cache (url UNIQUE, response, timestamp) with []
81803:49:06.926 T:139749511657216 DEBUG: 1Channel: Running: CREATE TABLE IF NOT EXISTS db_info (setting TEXT, value TEXT) with []
81903:49:06.933 T:139749511657216 DEBUG: 1Channel: Running: CREATE TABLE IF NOT EXISTS new_bkmark (url TEXT PRIMARY KEY NOT NULL, resumepoint DOUBLE NOT NULL) with []
82003:49:06.934 T:139749511657216 DEBUG: 1Channel: Running: CREATE TABLE IF NOT EXISTS external_subs (type INTEGER NOT NULL, url TEXT NOT NULL, imdbnum TEXT, days VARCHAR(7), PRIMARY KEY (type, url)) with []
82103:49:06.985 T:139749511657216 DEBUG: 1Channel: Running: CREATE UNIQUE INDEX IF NOT EXISTS unique_fav ON favorites (url) with []
82203:49:07.110 T:139749511657216 DEBUG: 1Channel: Running: CREATE UNIQUE INDEX IF NOT EXISTS unique_sub ON subscriptions (url) with []
82303:49:07.169 T:139749511657216 DEBUG: 1Channel: Running: CREATE UNIQUE INDEX IF NOT EXISTS unique_url ON url_cache (url) with []
82403:49:07.170 T:139749511657216 DEBUG: 1Channel: Running: CREATE UNIQUE INDEX IF NOT EXISTS unique_db_info ON db_info (setting) with []
82503:49:07.170 T:139749511657216 DEBUG: 1Channel: Running: INSERT OR REPLACE INTO db_info (setting, value) VALUES(?,?) with ('version', '2.5.72')
82603:49:07.675 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
82703:49:07.685 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
82803:49:07.685 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
82903:49:07.868 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
83003:49:07.877 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
83103:49:07.957 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
83203:49:07.957 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
83303:49:08.012 T:139748548470528 DEBUG: -->Python Interpreter Initialized<--
83403:49:08.013 T:139748548470528 DEBUG: CPythonInvoker(18, /home/jamesz/.kodi/addons/plugin.video.exodus/exodus.py): the source file to load is "/home/jamesz/.kodi/addons/plugin.video.exodus/exodus.py"
83503:49:08.022 T:139748548470528 DEBUG: CPythonInvoker(18, /home/jamesz/.kodi/addons/plugin.video.exodus/exodus.py): setting the Python path to /home/jamesz/.kodi/addons/plugin.video.exodus:/home/jamesz/.kodi/addons/plugin.video.youtube/resources/lib/:/home/jamesz/.kodi/addons/script.exodus.artwork/lib:/home/jamesz/.kodi/addons/script.exodus.metadata/lib:/home/jamesz/.kodi/addons/script.module.addon.common/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.metahandler/lib:/home/jamesz/.kodi/addons/script.module.myconnpy/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/home/jamesz/.kodi/addons/script.module.urlresolver/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
83603:49:08.022 T:139748548470528 DEBUG: CPythonInvoker(18, /home/jamesz/.kodi/addons/plugin.video.exodus/exodus.py): entering source directory /home/jamesz/.kodi/addons/plugin.video.exodus
83703:49:08.022 T:139748548470528 DEBUG: CPythonInvoker(18, /home/jamesz/.kodi/addons/plugin.video.exodus/exodus.py): instantiating addon using automatically obtained id of "plugin.video.exodus" dependent on version 2.19.0 of the xbmc.python api
83803:49:08.067 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
83903:49:08.067 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
84003:49:08.178 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
84103:49:08.178 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
84203:49:08.192 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
84303:49:08.192 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
84403:49:08.207 T:139749511657216 NOTICE: 1Channel: Service: Resetting...
84503:49:08.208 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
84603:49:08.209 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
84703:49:08.211 T:139749511657216 NOTICE: 1Channel: Service: starting...
84803:49:08.219 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
84903:49:08.219 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
85003:49:08.234 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
85103:49:08.234 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
85203:49:08.243 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
85303:49:08.243 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
85403:49:08.249 T:139748687533824 DEBUG: -->Python Interpreter Initialized<--
85503:49:08.250 T:139748687533824 DEBUG: CPythonInvoker(17, /home/jamesz/.kodi/addons/script.tv.show.next.aired/default.py): the source file to load is "/home/jamesz/.kodi/addons/script.tv.show.next.aired/default.py"
85603:49:08.254 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
85703:49:08.254 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
85803:49:08.265 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
85903:49:08.265 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
86003:49:08.275 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
86103:49:08.275 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
86203:49:08.298 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
86303:49:08.298 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
86403:49:08.315 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
86503:49:08.320 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
86603:49:08.329 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
86703:49:08.329 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
86803:49:08.421 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
86903:49:08.421 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
87003:49:08.478 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
87103:49:08.483 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
87203:49:08.499 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
87303:49:08.499 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
87403:49:08.515 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
87503:49:08.515 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
87603:49:08.563 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
87703:49:08.563 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
87803:49:08.607 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
87903:49:08.614 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
88003:49:08.621 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
88103:49:08.622 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
88203:49:08.668 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
88303:49:08.669 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
88403:49:08.704 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
88503:49:08.714 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
88603:49:08.725 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
88703:49:08.737 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
88803:49:08.744 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
88903:49:08.746 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
89003:49:08.762 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
89103:49:08.765 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
89203:49:08.781 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
89303:49:08.791 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
89403:49:08.801 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
89503:49:08.802 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
89603:49:08.827 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
89703:49:08.834 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
89803:49:08.856 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
89903:49:08.860 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
90003:49:08.873 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
90103:49:08.873 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
90203:49:08.888 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
90303:49:08.902 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
90403:49:08.904 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
90503:49:08.906 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
90603:49:08.918 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
90703:49:08.918 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
90803:49:08.935 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
90903:49:08.938 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
91003:49:08.954 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
91103:49:08.956 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
91203:49:08.994 T:139748450875136 DEBUG: Previous line repeats 1 times.
91303:49:08.994 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
91403:49:08.998 T:139748687533824 DEBUG: CPythonInvoker(17, /home/jamesz/.kodi/addons/script.tv.show.next.aired/default.py): setting the Python path to /home/jamesz/.kodi/addons/script.tv.show.next.aired:/home/jamesz/.kodi/addons/script.module.arrow/lib:/home/jamesz/.kodi/addons/script.module.certifi/lib:/home/jamesz/.kodi/addons/script.module.chardet/lib:/home/jamesz/.kodi/addons/script.module.dateutil/lib:/home/jamesz/.kodi/addons/script.module.idna/lib:/home/jamesz/.kodi/addons/script.module.requests/lib:/home/jamesz/.kodi/addons/script.module.simplecache/lib:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.six/lib:/home/jamesz/.kodi/addons/script.module.thetvdb/lib:/home/jamesz/.kodi/addons/script.module.urllib3/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
91503:49:08.998 T:139748687533824 DEBUG: CPythonInvoker(17, /home/jamesz/.kodi/addons/script.tv.show.next.aired/default.py): entering source directory /home/jamesz/.kodi/addons/script.tv.show.next.aired
91603:49:09.012 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
91703:49:09.013 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
91803:49:09.044 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
91903:49:09.045 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
92003:49:09.062 T:139748706318080 DEBUG: weather.yahoo: version 4.3.1 started: ['/home/jamesz/.kodi/addons/weather.yahoo/default.py', '1']
92103:49:09.063 T:139749503264512 DEBUG: StorageServer Module loaded RUN
92203:49:09.063 T:139749503264512 DEBUG: StorageClient-2.5.4 Starting server
92303:49:09.064 T:139748687533824 DEBUG: CPythonInvoker(17, /home/jamesz/.kodi/addons/script.tv.show.next.aired/default.py): instantiating addon using automatically obtained id of "script.tv.show.next.aired" dependent on version 2.1.0 of the xbmc.python api
92403:49:09.070 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
92503:49:09.070 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
92603:49:09.107 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
92703:49:09.107 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
92803:49:09.122 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
92903:49:09.123 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
93003:49:09.150 T:139750471300864 DEBUG: RunQuery took 471 ms for 4883 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
93103:49:09.180 T:139748706318080 DEBUG: weather.yahoo: weather location: 2399018
93203:49:09.186 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
93303:49:09.186 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
93403:49:09.229 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
93503:49:09.229 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
93603:49:09.280 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
93703:49:09.280 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
93803:49:09.324 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
93903:49:09.325 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
94003:49:09.339 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
94103:49:09.339 T:139748450875136 DEBUG: Thread Timer start, auto delete: false
94203:49:09.425 T:139748450875136 DEBUG: Thread Timer 139748450875136 terminating
94303:49:09.425 T:139748442482432 DEBUG: Thread Timer start, auto delete: false
94403:49:09.817 T:139748450875136 DEBUG: Skin Helper Service --> Started Background worker...
94503:49:09.941 T:139748442482432 DEBUG: Thread Timer 139748442482432 terminating
94603:49:10.201 T:139750048528128 DEBUG: script.grab.fanart: found 1119 movies files
94703:49:10.314 T:139750048528128 DEBUG: RunQuery took 103 ms for 68 items query: SELECT * FROM tvshow_view
94803:49:10.544 T:139750048528128 DEBUG: script.grab.fanart: found 67 tv files
94903:49:10.551 T:139748450875136 DEBUG: CFavourites::Load - no system favourites found, skipping
95003:49:10.552 T:139750048528128 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
95103:49:10.831 T:139748450875136 DEBUG: RunQuery took 70 ms for 17 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet ORDER BY sets.idSet
95203:49:10.939 T:139748450875136 DEBUG: RunQuery took 20 ms for 12 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=1 ORDER BY sets.idSet
95303:49:10.993 T:139750031742720 DEBUG: RunQuery took 552 ms for 4883 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
95403:49:11.098 T:139748450875136 DEBUG: RunQuery took 128 ms for 12 items query: select * from movie_view WHERE movie_view.idSet = 1
95503:49:11.128 T:139750471300864 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view
95603:49:11.194 T:139750471300864 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
95703:49:11.205 T:139748450875136 DEBUG: RunQuery took 23 ms for 5 items query: select * from movie_view JOIN sets ON movie_view.idSet = sets.idSet WHERE sets.idSet=2 ORDER BY sets.idSet
95803:49:11.351 T:139748450875136 DEBUG: RunQuery took 124 ms for 5 items query: select * from movie_view WHERE movie_view.idSet = 2
95903:49:11.458 T:139748450875136 DEBUG: Skin Helper Service --> Ended Background worker...
96003:49:11.845 T:139750471300864 DEBUG: GetAlbumsByWhere - query took 650 ms
96103:49:11.911 T:139750048528128 DEBUG: Time to retrieve artists from dataset = 1360
96203:49:12.532 T:139750031742720 DEBUG: GetSongsFullByWhere query = SELECT sv.*, songartistview.* FROM (SELECT songview.* FROM songview WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1)) ORDER BY RANDOM() LIMIT 20) AS sv JOIN songartistview ON songartistview.idsong = sv.idsong ORDER BY songartistview.idsong, songartistview.idRole, songartistview.iOrder
96303:49:12.772 T:139750031742720 DEBUG: GetSongsFullByWhere() - took 302 ms
96403:49:12.875 T:139748687533824 DEBUG: Skin Helper Simplecache --> Initialized
96503:49:12.876 T:139748687533824 DEBUG: script.module.thetvdb --> Initialized
96603:49:13.138 T:139750031742720 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
96703:49:13.347 T:139750031742720 DEBUG: GetAlbumsByWhere - query took 209 ms
96803:49:13.350 T:139748687533824 DEBUG: script.tv.show.next.aired --> MainModule called with action: update - parameter: true
96903:49:13.398 T:139748687533824 NOTICE: script.tv.show.next.aired --> Updating TheTVDB info for all continuing Kodi tv shows...
97003:49:13.416 T:139750471300864 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
97103:49:13.430 T:139750048528128 DEBUG: script.grab.fanart: found 573 music files
97203:49:13.544 T:139748687533824 DEBUG: RunQuery took 122 ms for 68 items query: SELECT * FROM tvshow_view
97303:49:13.616 T:139750471300864 DEBUG: Time to retrieve artists from dataset = 201
97403:49:13.695 T:139750479693568 DEBUG: Version Check: Version installed {u'major': 17, u'tag': u'stable', u'minor': 6, u'revision': u'20171114-a9a7a20'}
97503:49:13.696 T:139750479693568 DEBUG: Version Check: Version available {u'major': u'17', u'extrainfo': u'final', u'tagversion': u'', u'tag': u'stable', u'addon_support': u'yes', u'minor': u'6', u'revision': u'20171114-a9a7a20'}
97603:49:13.696 T:139750479693568 DEBUG: Version Check: There is no newer stable available
97703:49:13.697 T:139750479693568 INFO: CPythonInvoker(2, /home/jamesz/.kodi/addons/service.xbmc.versioncheck/service.py): script successfully run
97803:49:13.700 T:139750031742720 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1))
97903:49:13.872 T:139750479693568 INFO: Python script stopped
98003:49:13.872 T:139750479693568 DEBUG: Thread LanguageInvoker 139750479693568 terminating
98103:49:13.899 T:139750479693568 DEBUG: Thread Timer start, auto delete: false
98203:49:13.935 T:139750031742720 DEBUG: RunQuery took 44 ms for 395 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
98303:49:13.959 T:139748706318080 DEBUG: weather.yahoo: forecast data: {"query":{"count":1,"created":"2018-04-26T07:49:13Z","lang":"en-US","results":{"channel":{"units":{"distance":"km","pressure":"mb","speed":"km/h","temperature":"C"},"title":"Yahoo! Weather - Elmont, NY, US","link":"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-2399018/","description":"Yahoo! Weather for Elmont, NY, US","language":"en-us","lastBuildDate":"Thu, 26 Apr 2018 03:49 AM EDT","ttl":"60","location":{"city":"Elmont","country":"United States","region":" NY"},"wind":{"chill":"52","direction":"300","speed":"17.70"},"atmosphere":{"humidity":"95","pressure":"33863.90","rising":"0","visibility":"16.09"},"astronomy":{"sunrise":"6:0 am","sunset":"7:46 pm"},"image":{"title":"Yahoo! Weather","width":"142","height":"18","link":"http://weather.yahoo.com","url":"http://l.yimg.com/a/i/brand/purplelogo//uh/us/news-wea.gif"},"item":{"title":"Conditions for Elmont, NY, US at 03:00 AM EDT","lat":"40.703499","long":"-73.708702","link":"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-2399018/","pubDate":"Thu, 26 Apr 2018 03:00 AM EDT","condition":{"code":"11","date":"Thu, 26 Apr 2018 03:00 AM EDT","temp":"11","text":"Showers"},"forecast":[{"code":"12","date":"26 Apr 2018","day":"Thu","high":"17","low":"11","text":"Rain"},{"code":"11","date":"27 Apr 2018","day":"Fri","high":"12","low":"10","text":"Showers"},{"code":"30","date":"28 Apr 2018","day":"Sat","high":"15","low":"8","text":"Partly Cloudy"},{"code":"30","date":"29 Apr 2018","day":"Sun","high":"13","low":"6","text":"Partly Cloudy"},{"code":"34","date":"30 Apr 2018","day":"Mon","high":"16","low":"5","text":"Mostly Sunny"},{"code":"32","date":"01 May 2018","day":"Tue","high":"20","low":"8","text":"Sunny"},{"code":"30","date":"02 May 2018","day":"Wed","high":"22","low":"12","text":"Partly Cloudy"},{"code":"28","date":"03 May 2018","day":"Thu","high":"22","low":"15","text":"Mostly Cloudy"},{"code":"12","date":"04 May 2018","day":"Fri","high":"20","low":"13","text":"Rain"},{"code":"28","date":"05 May 2018","day":"Sat","high":"17","low":"12","text":"Mostly Cloudy"}],"description":"<![CDATA[<img src=\"http://l.yimg.com/a/i/us/we/52/11.gif\"/>\n<BR />\n<b>Current Conditions:</b>\n<BR />Showers\n<BR />\n<BR />\n<b>Forecast:</b>\n<BR /> Thu - Rain. High: 17Low: 11\n<BR /> Fri - Showers. High: 12Low: 10\n<BR /> Sat - Partly Cloudy. High: 15Low: 8\n<BR /> Sun - Partly Cloudy. High: 13Low: 6\n<BR /> Mon - Mostly Sunny. High: 16Low: 5\n<BR />\n<BR />\n<a href=\"http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-2399018/\">Full Forecast at Yahoo! Weather</a>\n<BR />\n<BR />\n<BR />\n]]>","guid":{"isPermaLink":"false"}}}}}}
98403:49:13.972 T:139750471300864 DEBUG: GetSongsFullByWhere query = SELECT sv.*, songartistview.* FROM (SELECT songview.* FROM songview WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1)) ORDER BY RANDOM() LIMIT 20) AS sv JOIN songartistview ON songartistview.idsong = sv.idsong ORDER BY songartistview.idsong, songartistview.idRole, songartistview.iOrder
98503:49:14.024 T:139748706318080 DEBUG: weather.yahoo: available locations: 1
98603:49:14.024 T:139748706318080 DEBUG: weather.yahoo: finished
98703:49:14.024 T:139748706318080 INFO: CPythonInvoker(16, /home/jamesz/.kodi/addons/weather.yahoo/default.py): script successfully run
98803:49:14.192 T:139748706318080 INFO: Python script stopped
98903:49:14.192 T:139748706318080 DEBUG: Thread LanguageInvoker 139748706318080 terminating
99003:49:14.248 T:139750471300864 DEBUG: GetSongsFullByWhere() - took 322 ms
99103:49:14.262 T:139748731496192 DEBUG: POParser: loaded 130 weather tokens
99203:49:14.433 T:139750479693568 DEBUG: Thread Timer 139750479693568 terminating
99303:49:14.503 T:139748620674816 DEBUG: Testing Existence (multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
99403:49:14.503 T:139748620674816 DEBUG: Testing Existence (special://profile/playlists/video)
99503:49:14.573 T:139748620674816 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
99603:49:14.574 T:139748620674816 DEBUG: Getting Directory (special://profile/playlists/video)
99703:49:14.617 T:139748620674816 DEBUG: Getting Directory (special://profile/playlists/mixed)
99803:49:14.648 T:139748620674816 DEBUG: CMultiPathDirectory::MergeItems, items = 0
99903:49:14.650 T:139748620674816 DEBUG: Testing Existence (multipath://special%3a%2f%2fprofile%2fplaylists%2fmusic/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
100003:49:14.651 T:139748620674816 DEBUG: Testing Existence (special://profile/playlists/music)
100103:49:14.654 T:139748620674816 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fmusic/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
100203:49:14.654 T:139748620674816 DEBUG: Getting Directory (special://profile/playlists/music)
100303:49:14.692 T:139748620674816 DEBUG: Getting Directory (special://profile/playlists/mixed)
100403:49:14.692 T:139748620674816 DEBUG: CMultiPathDirectory::MergeItems, items = 0
100503:49:14.711 T:139749998171904 INFO: CPythonInvoker(15, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): script successfully run
100603:49:14.720 T:139750479693568 DEBUG: Thread Timer start, auto delete: false
100703:49:14.884 T:139749998171904 INFO: Python script stopped
100803:49:14.884 T:139749998171904 DEBUG: Thread LanguageInvoker 139749998171904 terminating
100903:49:15.243 T:139750479693568 DEBUG: Thread Timer 139750479693568 terminating
101003:49:15.535 T:139750471300864 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:09.239073
101103:49:15.572 T:139750471300864 DEBUG: RunQuery took 18 ms for 116 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
101203:49:15.686 T:139750031742720 DEBUG: RunQuery took 354 ms for 4883 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
101303:49:15.942 T:139750471300864 DEBUG: RunQuery took 147 ms for 33 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))))
101403:49:16.016 T:139750471300864 DEBUG: RunQuery took 7 ms for 31 items query: select * from episode_view WHERE (episode_view.idShow = 69) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
101503:49:16.016 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
101603:49:16.079 T:139750471300864 DEBUG: RunQuery took 4 ms for 11 items query: select * from episode_view WHERE (episode_view.idShow = 68) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
101703:49:16.079 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
101803:49:16.133 T:139750471300864 DEBUG: RunQuery took 6 ms for 28 items query: select * from episode_view WHERE (episode_view.idShow = 81) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
101903:49:16.133 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
102003:49:16.161 T:139748687533824 NOTICE: script.tv.show.next.aired --> Retrieving next airing episodes for all continuing Kodi tv shows...
102103:49:16.202 T:139750471300864 DEBUG: RunQuery took 4 ms for 12 items query: select * from episode_view WHERE (episode_view.idShow = 5) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
102203:49:16.202 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
102303:49:16.267 T:139748687533824 DEBUG: RunQuery took 91 ms for 68 items query: SELECT * FROM tvshow_view
102403:49:16.267 T:139750471300864 DEBUG: RunQuery took 16 ms for 126 items query: select * from episode_view WHERE (episode_view.idShow = 92) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
102503:49:16.268 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
102603:49:16.345 T:139750471300864 DEBUG: RunQuery took 18 ms for 128 items query: select * from episode_view WHERE (episode_view.idShow = 77) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
102703:49:16.346 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
102803:49:16.411 T:139750471300864 DEBUG: RunQuery took 7 ms for 2 items query: select * from episode_view WHERE (episode_view.idShow = 37) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
102903:49:16.411 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
103003:49:16.518 T:139750471300864 DEBUG: RunQuery took 24 ms for 171 items query: select * from episode_view WHERE (episode_view.idShow = 88) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
103103:49:16.518 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
103203:49:16.599 T:139750471300864 DEBUG: RunQuery took 17 ms for 90 items query: select * from episode_view WHERE (episode_view.idShow = 53) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
103303:49:16.599 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
103403:49:16.672 T:139750031742720 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
103503:49:16.690 T:139750471300864 DEBUG: RunQuery took 33 ms for 241 items query: select * from episode_view WHERE (episode_view.idShow = 7) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
103603:49:16.690 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
103703:49:16.966 T:139750031742720 DEBUG: GetAlbumsByWhere - query took 294 ms
103803:49:17.205 T:139750471300864 DEBUG: RunQuery took 6 ms for 52 items query: select * from episode_view WHERE (episode_view.idShow = 79) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
103903:49:17.205 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
104003:49:17.321 T:139750471300864 DEBUG: RunQuery took 7 ms for 46 items query: select * from episode_view WHERE (episode_view.idShow = 87) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
104103:49:17.321 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
104203:49:17.397 T:139750471300864 DEBUG: RunQuery took 21 ms for 228 items query: select * from episode_view WHERE (episode_view.idShow = 21) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
104303:49:17.397 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
104403:49:17.407 T:139750031742720 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1))
104503:49:17.461 T:139750031742720 DEBUG: RunQuery took 30 ms for 116 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
104603:49:17.484 T:139750471300864 DEBUG: RunQuery took 4 ms for 9 items query: select * from episode_view WHERE (episode_view.idShow = 72) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
104703:49:17.484 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
104803:49:17.567 T:139750471300864 DEBUG: RunQuery took 15 ms for 169 items query: select * from episode_view WHERE (episode_view.idShow = 65) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
104903:49:17.568 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
105003:49:17.642 T:139750471300864 DEBUG: RunQuery took 11 ms for 111 items query: select * from episode_view WHERE (episode_view.idShow = 86) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
105103:49:17.642 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
105203:49:17.704 T:139750471300864 DEBUG: RunQuery took 11 ms for 94 items query: select * from episode_view WHERE (episode_view.idShow = 55) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
105303:49:17.704 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
105403:49:17.736 T:139748687533824 DEBUG: RunQuery took 83 ms for 68 items query: SELECT * FROM tvshow_view
105503:49:17.761 T:139750471300864 DEBUG: RunQuery took 13 ms for 93 items query: select * from episode_view WHERE (episode_view.idShow = 85) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
105603:49:17.761 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
105703:49:17.836 T:139750471300864 DEBUG: RunQuery took 21 ms for 161 items query: select * from episode_view WHERE (episode_view.idShow = 25) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
105803:49:17.836 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
105903:49:17.930 T:139750471300864 DEBUG: RunQuery took 23 ms for 210 items query: select * from episode_view WHERE (episode_view.idShow = 3) AND (((episode_view.playCount IS NULL OR episode_view.playCount = 0)))
106003:49:17.930 T:139750471300864 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
106103:49:18.012 T:139750471300864 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
106203:49:18.267 T:139750031742720 DEBUG: RunQuery took 151 ms for 33 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))))
106303:49:18.375 T:139750471300864 DEBUG: GetAlbumsByWhere - query took 363 ms
106403:49:18.468 T:139750031742720 DEBUG: RunQuery took 6 ms for 31 items query: select * from episode_view WHERE (episode_view.idShow = 69) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
106503:49:18.468 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
106603:49:18.667 T:139750031742720 DEBUG: RunQuery took 3 ms for 11 items query: select * from episode_view WHERE (episode_view.idShow = 68) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
106703:49:18.668 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
106803:49:18.720 T:139750031742720 DEBUG: RunQuery took 7 ms for 28 items query: select * from episode_view WHERE (episode_view.idShow = 81) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
106903:49:18.720 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
107003:49:18.783 T:139750031742720 DEBUG: RunQuery took 3 ms for 12 items query: select * from episode_view WHERE (episode_view.idShow = 5) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
107103:49:18.783 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
107203:49:18.817 T:139750031742720 DEBUG: RunQuery took 8 ms for 126 items query: select * from episode_view WHERE (episode_view.idShow = 92) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
107303:49:18.817 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
107403:49:18.850 T:139748687533824 NOTICE: script.tv.show.next.aired --> Update complete
107503:49:18.851 T:139748687533824 DEBUG: Skin Helper Simplecache --> Closed
107603:49:18.852 T:139748687533824 DEBUG: script.module.thetvdb --> Exited
107703:49:18.852 T:139748687533824 DEBUG: script.tv.show.next.aired --> MainModule exited
107803:49:18.852 T:139748687533824 INFO: CPythonInvoker(17, /home/jamesz/.kodi/addons/script.tv.show.next.aired/default.py): script successfully run
107903:49:18.859 T:139750031742720 DEBUG: RunQuery took 9 ms for 128 items query: select * from episode_view WHERE (episode_view.idShow = 77) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
108003:49:18.859 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
108103:49:18.871 T:139748687533824 INFO: Python script stopped
108203:49:18.872 T:139748687533824 DEBUG: Thread LanguageInvoker 139748687533824 terminating
108303:49:18.905 T:139750471300864 DEBUG: RunQuery took 4 ms for 0 items query: select * from musicvideo_view
108403:49:18.907 T:139750471300864 DEBUG: Skin Widgets: Total time needed to request recommended queries: 0:00:03.371694
108503:49:18.920 T:139750031742720 DEBUG: RunQuery took 6 ms for 2 items query: select * from episode_view WHERE (episode_view.idShow = 37) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
108603:49:18.920 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
108703:49:18.955 T:139750471300864 DEBUG: RunQuery took 32 ms for 395 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
108803:49:19.144 T:139750031742720 DEBUG: RunQuery took 20 ms for 171 items query: select * from episode_view WHERE (episode_view.idShow = 88) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
108903:49:19.144 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
109003:49:19.195 T:139750031742720 DEBUG: RunQuery took 7 ms for 90 items query: select * from episode_view WHERE (episode_view.idShow = 53) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
109103:49:19.195 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
109203:49:19.257 T:139750031742720 DEBUG: RunQuery took 21 ms for 241 items query: select * from episode_view WHERE (episode_view.idShow = 7) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
109303:49:19.257 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
109403:49:19.332 T:139750031742720 DEBUG: RunQuery took 5 ms for 52 items query: select * from episode_view WHERE (episode_view.idShow = 79) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
109503:49:19.332 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
109603:49:19.397 T:139750031742720 DEBUG: RunQuery took 6 ms for 46 items query: select * from episode_view WHERE (episode_view.idShow = 87) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
109703:49:19.397 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
109803:49:19.418 T:139750471300864 DEBUG: RunQuery took 294 ms for 4883 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
109903:49:19.459 T:139750031742720 DEBUG: RunQuery took 16 ms for 228 items query: select * from episode_view WHERE (episode_view.idShow = 21) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
110003:49:19.459 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
110103:49:19.515 T:139750031742720 DEBUG: RunQuery took 3 ms for 9 items query: select * from episode_view WHERE (episode_view.idShow = 72) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
110203:49:19.515 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
110303:49:19.593 T:139750031742720 DEBUG: RunQuery took 13 ms for 169 items query: select * from episode_view WHERE (episode_view.idShow = 65) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
110403:49:19.593 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
110503:49:19.653 T:139750031742720 DEBUG: RunQuery took 13 ms for 111 items query: select * from episode_view WHERE (episode_view.idShow = 86) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
110603:49:19.653 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
110703:49:19.712 T:139750031742720 DEBUG: RunQuery took 11 ms for 94 items query: select * from episode_view WHERE (episode_view.idShow = 55) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
110803:49:19.712 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
110903:49:19.774 T:139750031742720 DEBUG: RunQuery took 11 ms for 93 items query: select * from episode_view WHERE (episode_view.idShow = 85) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
111003:49:19.774 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
111103:49:19.824 T:139750031742720 DEBUG: RunQuery took 10 ms for 161 items query: select * from episode_view WHERE (episode_view.idShow = 25) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
111203:49:19.824 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
111303:49:19.898 T:139750031742720 DEBUG: RunQuery took 22 ms for 210 items query: select * from episode_view WHERE (episode_view.idShow = 3) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
111403:49:19.899 T:139750031742720 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 30
111503:49:19.983 T:139750031742720 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
111603:49:20.043 T:139750471300864 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view
111703:49:20.062 T:139750471300864 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
111803:49:20.258 T:139750031742720 DEBUG: GetAlbumsByWhere - query took 275 ms
111903:49:20.378 T:139750471300864 DEBUG: GetAlbumsByWhere - query took 315 ms
112003:49:20.606 T:139750031742720 DEBUG: CFavourites::Load - no system favourites found, skipping
112103:49:20.677 T:139750031742720 DEBUG: RunQuery took 61 ms for 58 items query: SELECT * FROM tvshow_view WHERE ((CASE WHEN COALESCE(tvshow_view.totalCount - tvshow_view.watchedcount, 0) > 0 THEN 0 ELSE 1 END < 1))
112203:49:20.769 T:139750031742720 DEBUG: Library Data Provider: Total time needed for all queries: 0:00:15.282836
112303:49:20.780 T:139750471300864 DEBUG: Skin Widgets: Total time needed to request recent items queries: 0:00:01.872023
112403:49:20.780 T:139750471300864 DEBUG: Skin Widgets: Total time needed for all queries: 0:00:14.483490
112503:49:35.110 T:139751857735744 DEBUG: LIRC: Update - NEW at 75190:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
112603:49:35.110 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
112703:49:35.699 T:139751857735744 DEBUG: LIRC: Update - NEW at 75779:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
112803:49:35.699 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
112903:49:36.690 T:139751857735744 DEBUG: LIRC: Update - NEW at 76769:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
113003:49:36.690 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
113103:49:37.657 T:139751857735744 DEBUG: LIRC: Update - NEW at 77737:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
113203:49:37.657 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
113303:49:38.067 T:139748620674816 DEBUG: RunQuery took 68 ms for 1123 items query: select * from movie_view
113403:49:38.534 T:139748620674816 DEBUG: RunQuery took 14 ms for 116 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
113503:49:38.807 T:139748620674816 DEBUG: RunQuery took 78 ms for 25 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 25
113603:49:38.871 T:139751857735744 DEBUG: LIRC: Update - NEW at 78951:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK)
113703:49:38.871 T:139751857735744 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
113803:49:38.871 T:139751857735744 DEBUG: Activating window ID: 10502
113903:49:39.079 T:139751857735744 DEBUG: ------ Window Deinit (Home.xml) ------
114003:49:39.346 T:139751857735744 DEBUG: ------ Window Init (MyMusicNav.xml) ------
114103:49:39.346 T:139751857735744 INFO: Loading skin file: MyMusicNav.xml, load type: KEEP_IN_MEMORY
114203:49:40.664 T:139751857735744 WARNING: Trying to add unsupported control type 1
114303:49:43.099 T:139751857735744 WARNING: Previous line repeats 9 times.
114403:49:43.099 T:139751857735744 DEBUG: CGUIMediaWindow::GetDirectory (/home/jamesz/Multichannel Music/)
114503:49:43.099 T:139751857735744 DEBUG: ParentPath = [/home/jamesz/Multichannel Music/]
114603:49:43.143 T:139751857735744 DEBUG: RetrieveMusicInfo() took 0 msec
114703:49:43.153 T:139748687533824 DEBUG: Thread BackgroundLoader start, auto delete: false
114803:49:43.272 T:139748687533824 DEBUG: Thread BackgroundLoader 139748687533824 terminating
114903:49:43.306 T:139748620674816 DEBUG: RunQuery took 82 ms for 1123 items query: select * from movie_view
115003:49:43.999 T:139748620674816 DEBUG: RunQuery took 65 ms for 67 items query: SELECT * FROM tvshow_view WHERE totalCount IS NOT NULL AND totalCount > 0
115103:49:44.061 T:139749461300992 DEBUG: Thread JobWorker 139749461300992 terminating (autodelete)
115203:49:44.061 T:139751243679488 DEBUG: Thread JobWorker 139751243679488 terminating (autodelete)
115303:49:44.113 T:139748723103488 DEBUG: Thread JobWorker 139748723103488 terminating (autodelete)
115403:49:44.263 T:139748731496192 DEBUG: Thread JobWorker 139748731496192 terminating (autodelete)
115503:49:44.300 T:139748620674816 DEBUG: RunQuery took 87 ms for 33 items query: SELECT * FROM tvshow_view WHERE (totalCount IS NOT NULL AND totalCount > 0) 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)))))
115603:49:44.762 T:139748620674816 DEBUG: RunQuery took 303 ms for 25 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 25
115703:49:44.869 T:139751857735744 DEBUG: LIRC: Update - NEW at 84949:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
115803:49:44.870 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
115903:49:44.878 T:139748620674816 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE ((EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artistview.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artistview.idArtist)) AND (artistview.strArtist != '')) AND (artistview.strArtist <> 'Various artists')
116003:49:45.290 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
116103:49:45.428 T:139748620674816 DEBUG: Previous line repeats 1 times.
116203:49:45.428 T:139748620674816 DEBUG: Time to retrieve artists from dataset = 550
116303:49:45.506 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
116403:49:45.663 T:139748620674816 DEBUG: Previous line repeats 1 times.
116503:49:45.664 T:139748620674816 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
116603:49:45.706 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
116703:49:46.028 T:139748620674816 DEBUG: Previous line repeats 2 times.
116803:49:46.028 T:139748620674816 DEBUG: GetSongsFullByWhere query = SELECT songview.*, songartistview.* FROM songview JOIN songartistview ON songartistview.idsong = songview.idsong ORDER BY songartistview.idsong, songartistview.idRole, songartistview.iOrder
116903:49:46.724 T:139751857735744 DEBUG: LIRC: Update - NEW at 86804:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
117003:49:46.724 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
117103:49:47.085 T:139748620674816 DEBUG: GetSongsFullByWhere() - took 1090 ms
117203:49:47.594 T:139751857735744 DEBUG: LIRC: Update - NEW at 87673:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK)
117303:49:47.594 T:139751857735744 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
117403:49:47.596 T:139751857735744 DEBUG: CGUIMediaWindow::GetDirectory (/home/jamesz/Multichannel Music/Billy Joel/)
117503:49:47.596 T:139751857735744 DEBUG: ParentPath = [/home/jamesz/Multichannel Music/]
117603:49:47.596 T:139748396394240 DEBUG: Thread JobWorker start, auto delete: true
117703:49:47.623 T:139751857735744 DEBUG: RetrieveMusicInfo() took 0 msec
117803:49:47.628 T:139748706318080 DEBUG: Thread BackgroundLoader start, auto delete: false
117903:49:47.649 T:139748706318080 DEBUG: Thread BackgroundLoader 139748706318080 terminating
118003:49:48.502 T:139751857735744 DEBUG: LIRC: Update - NEW at 88581:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
118103:49:48.502 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
118203:49:48.726 T:139748620674816 DEBUG: unrecognized ID3 frame detected: TSSE
118303:49:48.747 T:139748620674816 DEBUG: Caching image '/home/jamesz/Music/Fleetwood Mac/You Make Loving Fun (Sessions, Roughs & Outtakes).mp3' to '8/8747cda4.jpg':
118403:49:48.940 T:139748620674816 DEBUG: cached image 'special://masterprofile/Thumbnails/8/8747cda4.jpg' size 720x720
118503:49:49.285 T:139751857735744 DEBUG: LIRC: Update - NEW at 89365:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK)
118603:49:49.286 T:139751857735744 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
118703:49:49.287 T:139751857735744 DEBUG: CGUIMediaWindow::GetDirectory (/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/)
118803:49:49.287 T:139751857735744 DEBUG: ParentPath = [/home/jamesz/Multichannel Music/Billy Joel/]
118903:49:49.596 T:139750479693568 DEBUG: Thread BackgroundLoader start, auto delete: false
119003:49:49.700 T:139750479693568 DEBUG: unrecognized XipComment name: ENCODER
119103:49:51.098 T:139751857735744 DEBUG: Previous line repeats 14 times.
119203:49:51.098 T:139751857735744 DEBUG: DialogProgress::Open called
119303:49:51.098 T:139751857735744 DEBUG: ------ Window Init (DialogConfirm.xml) ------
119403:49:51.098 T:139751857735744 INFO: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY
119503:49:51.158 T:139751857735744 ERROR: Control 11 in window 10101 has been asked to focus, but it can't
119603:49:51.207 T:139750479693568 DEBUG: unrecognized XipComment name: ENCODER
119703:49:51.258 T:139748620674816 ERROR: GetDirectory - Error getting /home/jamesz/Pictures/
119803:49:51.316 T:139750479693568 ERROR: Previous line repeats 1 times.
119903:49:51.316 T:139750479693568 DEBUG: unrecognized XipComment name: ENCODER
120003:49:51.755 T:139750479693568 DEBUG: Previous line repeats 5 times.
120103:49:51.755 T:139750479693568 DEBUG: Saving fileitems [/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/]
120203:49:51.756 T:139750479693568 DEBUG: -- items: 27, sort method: 0, ascending: true
120303:49:51.757 T:139750479693568 DEBUG: Thread BackgroundLoader 139750479693568 terminating
120403:49:51.757 T:139751857735744 DEBUG: RetrieveMusicInfo() took 2161 msec
120503:49:51.767 T:139750479693568 DEBUG: Thread BackgroundLoader start, auto delete: false
120603:49:51.828 T:139750479693568 DEBUG: Thread BackgroundLoader 139750479693568 terminating
120703:49:51.967 T:139751857735744 DEBUG: ------ Window Deinit (DialogConfirm.xml) ------
120803:49:52.822 T:139751857735744 DEBUG: LIRC: Update - NEW at 92902:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
120903:49:52.823 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
121003:49:53.740 T:139751857735744 DEBUG: LIRC: Update - NEW at 93819:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
121103:49:53.740 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
121203:49:54.323 T:139751857735744 DEBUG: LIRC: Update - NEW at 94403:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
121303:49:54.324 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
121403:49:54.895 T:139751857735744 DEBUG: LIRC: Update - NEW at 94974:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
121503:49:54.895 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
121603:49:55.474 T:139751857735744 DEBUG: LIRC: Update - NEW at 95554:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
121703:49:55.474 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
121803:49:56.047 T:139751857735744 DEBUG: LIRC: Update - NEW at 96127:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
121903:49:56.048 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
122003:49:56.536 T:139751857735744 DEBUG: LIRC: Update - NEW at 96615:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
122103:49:56.536 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
122203:49:57.098 T:139751857735744 DEBUG: LIRC: Update - NEW at 97177:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
122303:49:57.098 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
122403:49:58.801 T:139751857735744 DEBUG: LIRC: Update - NEW at 98881:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
122503:49:58.801 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
122603:50:01.052 T:139751857735744 DEBUG: LIRC: Update - NEW at 101132:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
122703:50:01.052 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
122803:50:01.085 T:139748396394240 DEBUG: unrecognized XipComment name: ENCODER
122903:50:01.189 T:139748396394240 DEBUG: Caching image '/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Keeping the Faith.ogg' to 'b/b8899949.jpg':
123003:50:01.357 T:139748396394240 DEBUG: cached image 'special://masterprofile/Thumbnails/b/b8899949.jpg' size 720x720
123103:50:01.568 T:139751857735744 DEBUG: LIRC: Update - NEW at 101648:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
123203:50:01.569 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
123303:50:01.574 T:139748731496192 DEBUG: Thread JobWorker start, auto delete: true
123403:50:02.077 T:139751857735744 DEBUG: LIRC: Update - NEW at 102156:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
123503:50:02.077 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
123603:50:02.120 T:139748396394240 DEBUG: unrecognized XipComment name: ENCODER
123703:50:02.189 T:139748396394240 DEBUG: Caching image '/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Leningrad.ogg' to '8/803104f6.jpg':
123803:50:02.360 T:139748396394240 DEBUG: cached image 'special://masterprofile/Thumbnails/8/803104f6.jpg' size 720x720
123903:50:02.656 T:139751857735744 DEBUG: LIRC: Update - NEW at 102736:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
124003:50:02.657 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
124103:50:02.713 T:139748731496192 DEBUG: unrecognized XipComment name: ENCODER
124203:50:02.782 T:139748731496192 DEBUG: Caching image '/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Pressure.ogg' to '9/9dd00f43.jpg':
124303:50:02.947 T:139748731496192 DEBUG: cached image 'special://masterprofile/Thumbnails/9/9dd00f43.jpg' size 720x720
124403:50:03.236 T:139751857735744 DEBUG: LIRC: Update - NEW at 103315:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
124503:50:03.236 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
124603:50:03.278 T:139748396394240 DEBUG: unrecognized XipComment name: ENCODER
124703:50:03.346 T:139748396394240 DEBUG: Caching image '/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Say Goodbye to Hollywood (Unfaded).ogg' to 'd/d8067af5.jpg':
124803:50:03.512 T:139748396394240 DEBUG: cached image 'special://masterprofile/Thumbnails/d/d8067af5.jpg' size 720x720
124903:50:04.043 T:139751857735744 DEBUG: LIRC: Update - NEW at 104122:000000037ff07be1 00 KEY_UP mceusb (KEY_UP)
125003:50:04.043 T:139751857735744 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
125103:50:05.433 T:139751857735744 DEBUG: LIRC: Update - NEW at 105512:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK)
125203:50:05.433 T:139751857735744 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
125303:50:05.447 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
125403:50:05.447 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
125503:50:05.455 T:139751857735744 DEBUG: Loading settings for /home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Pressure.ogg
125603:50:05.457 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
125703:50:05.457 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers(/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Pressure.ogg)
125803:50:05.457 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
125903:50:05.457 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
126003:50:05.457 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
126103:50:05.457 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
126203:50:05.457 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
126303:50:05.457 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
126403:50:05.457 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
126503:50:05.458 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
126603:50:05.458 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
126703:50:05.458 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
126803:50:05.458 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
126903:50:05.458 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
127003:50:05.458 T:139751857735744 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
127103:50:05.458 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
127203:50:05.458 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
127303:50:05.458 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
127403:50:05.458 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
127503:50:05.458 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer
127603:50:05.458 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: Pioneer Receiver
127703:50:05.458 T:139751857735744 DEBUG: CPlayerCoreFactory::GetPlayers: added 3 players
127803:50:05.462 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
127903:50:05.462 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
128003:50:05.467 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
128103:50:05.467 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
128203:50:05.472 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
128303:50:05.472 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
128403:50:05.476 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
128503:50:05.477 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
128603:50:05.481 T:139751857735744 DEBUG: Open - probing detected format [ogg]
128703:50:05.481 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
128803:50:05.482 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
128903:50:05.487 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
129003:50:05.487 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
129103:50:05.492 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
129203:50:05.492 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
129303:50:05.497 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
129403:50:05.497 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
129503:50:05.501 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
129603:50:05.502 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
129703:50:05.507 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
129803:50:05.507 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
129903:50:05.509 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"A Matter of Trust","type":"song"},"playlistid":0,"position":0}
130003:50:05.510 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"All About Soul","type":"song"},"playlistid":0,"position":1}
130103:50:05.511 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"All for Leyna","type":"song"},"playlistid":0,"position":2}
130203:50:05.513 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Allentown","type":"song"},"playlistid":0,"position":3}
130303:50:05.513 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
130403:50:05.513 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
130503:50:05.514 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Baby Grand (with Ray Charles)","type":"song"},"playlistid":0,"position":4}
130603:50:05.515 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Everybody Loves You Now (Live)","type":"song"},"playlistid":0,"position":5}
130703:50:05.516 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Goodnight Saigon (Live)","type":"song"},"playlistid":0,"position":6}
130803:50:05.517 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"I Go to Extremes (Unfaded)","type":"song"},"playlistid":0,"position":7}
130903:50:05.519 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"It's Still Rock & Roll to Me","type":"song"},"playlistid":0,"position":8}
131003:50:05.520 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Keeping the Faith","type":"song"},"playlistid":0,"position":9}
131103:50:05.521 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
131203:50:05.521 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
131303:50:05.521 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Leave a Tender Moment Alone (Live)","type":"song"},"playlistid":0,"position":10}
131403:50:05.522 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Leningrad","type":"song"},"playlistid":0,"position":11}
131503:50:05.523 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Pressure","type":"song"},"playlistid":0,"position":12}
131603:50:05.525 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Say Goodbye to Hollywood (Unfaded)","type":"song"},"playlistid":0,"position":13}
131703:50:05.528 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
131803:50:05.528 T:139751857735744 DEBUG: Open - avformat_find_stream_info starting
131903:50:05.528 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
132003:50:05.530 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"A Matter of Trust","type":"song"},"playlistid":0,"position":0}
132103:50:05.533 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"All About Soul","type":"song"},"playlistid":0,"position":1}
132203:50:05.534 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"All for Leyna","type":"song"},"playlistid":0,"position":2}
132303:50:05.534 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Allentown","type":"song"},"playlistid":0,"position":3}
132403:50:05.534 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Baby Grand (with Ray Charles)","type":"song"},"playlistid":0,"position":4}
132503:50:05.535 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Everybody Loves You Now (Live)","type":"song"},"playlistid":0,"position":5}
132603:50:05.535 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
132703:50:05.535 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Goodnight Saigon (Live)","type":"song"},"playlistid":0,"position":6}
132803:50:05.535 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
132903:50:05.536 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"I Go to Extremes (Unfaded)","type":"song"},"playlistid":0,"position":7}
133003:50:05.536 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"It's Still Rock & Roll to Me","type":"song"},"playlistid":0,"position":8}
133103:50:05.537 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Keeping the Faith","type":"song"},"playlistid":0,"position":9}
133203:50:05.537 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Leave a Tender Moment Alone (Live)","type":"song"},"playlistid":0,"position":10}
133303:50:05.537 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Leningrad","type":"song"},"playlistid":0,"position":11}
133403:50:05.538 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Pressure","type":"song"},"playlistid":0,"position":12}
133503:50:05.538 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Say Goodbye to Hollywood (Unfaded)","type":"song"},"playlistid":0,"position":13}
133603:50:05.539 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"She's Right on Time","type":"song"},"playlistid":0,"position":14}
133703:50:05.539 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Sometimes a Fantasy","type":"song"},"playlistid":0,"position":15}
133803:50:05.540 T:139751857735744 DEBUG: Open - av_find_stream_info finished
133903:50:05.541 T:139751857735744 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
134003:50:05.541 T:139751857735744 DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
134103:50:05.541 T:139751857735744 DEBUG: FactoryCodec - Audio: passthrough - Opening
134203:50:05.541 T:139751857735744 DEBUG: FactoryCodec - Audio: passthrough - Failed
134303:50:05.542 T:139751857735744 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
134403:50:05.542 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
134503:50:05.542 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
134603:50:05.547 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
134703:50:05.547 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
134803:50:05.550 T:139751857735744 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
134903:50:05.552 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
135003:50:05.552 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
135103:50:05.557 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
135203:50:05.557 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
135303:50:05.562 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
135403:50:05.562 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
135503:50:05.567 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
135603:50:05.567 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
135703:50:05.569 T:139751857735744 DEBUG: unrecognized XipComment name: ENCODER
135803:50:05.572 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
135903:50:05.573 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
136003:50:05.577 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
136103:50:05.577 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
136203:50:05.582 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
136303:50:05.582 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
136403:50:05.587 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
136503:50:05.587 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
136603:50:05.621 T:139751857735744 DEBUG: SeekTime - seek ended up on time 0
136703:50:05.626 T:139750023350016 DEBUG: Previous line repeats 1 times.
136803:50:05.626 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"She's Right on Time","type":"song"},"playlistid":0,"position":14}
136903:50:05.627 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Sometimes a Fantasy","type":"song"},"playlistid":0,"position":15}
137003:50:05.627 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Downeaster \"Alexa\"","type":"song"},"playlistid":0,"position":16}
137103:50:05.628 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Entertainer (Remix)","type":"song"},"playlistid":0,"position":17}
137203:50:05.629 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Longest Time","type":"song"},"playlistid":0,"position":18}
137303:50:05.630 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Night Is Still Young","type":"song"},"playlistid":0,"position":19}
137403:50:05.631 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The River of Dreams","type":"song"},"playlistid":0,"position":20}
137503:50:05.632 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Uptown Girl","type":"song"},"playlistid":0,"position":21}
137603:50:05.632 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"We Didn't Start the Fire (Unfaded)","type":"song"},"playlistid":0,"position":22}
137703:50:05.633 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"You May Be Right","type":"song"},"playlistid":0,"position":23}
137803:50:05.634 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"You May Be Right (Unfaded)","type":"song"},"playlistid":0,"position":24}
137903:50:05.635 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"You're Only Human (Second Wind)","type":"song"},"playlistid":0,"position":25}
138003:50:05.640 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Downeaster \"Alexa\"","type":"song"},"playlistid":0,"position":16}
138103:50:05.640 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Entertainer (Remix)","type":"song"},"playlistid":0,"position":17}
138203:50:05.640 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Longest Time","type":"song"},"playlistid":0,"position":18}
138303:50:05.640 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The Night Is Still Young","type":"song"},"playlistid":0,"position":19}
138403:50:05.640 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"The River of Dreams","type":"song"},"playlistid":0,"position":20}
138503:50:05.641 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Uptown Girl","type":"song"},"playlistid":0,"position":21}
138603:50:05.641 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"We Didn't Start the Fire (Unfaded)","type":"song"},"playlistid":0,"position":22}
138703:50:05.641 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"You May Be Right","type":"song"},"playlistid":0,"position":23}
138803:50:05.641 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"You May Be Right (Unfaded)","type":"song"},"playlistid":0,"position":24}
138903:50:05.642 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Playlist.OnAdd - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"You're Only Human (Second Wind)","type":"song"},"playlistid":0,"position":25}
139003:50:05.899 T:139751857735744 INFO: AudioDecoder: File is queued
139103:50:05.900 T:139751857735744 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000
139203:50:05.934 T:139751226894080 INFO: CActiveAESink::OpenSink - initialize sink
139303:50:06.230 T:139751226894080 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=0
139403:50:06.230 T:139751226894080 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=0"
139503:50:06.237 T:139751226894080 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
139603:50:06.237 T:139751226894080 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
139703:50:06.237 T:139751226894080 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
139803:50:06.237 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 680, bufferSize 2720
139903:50:06.314 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 544, bufferSize 2720
140003:50:06.314 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 62 ms
140103:50:06.418 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 6 Output Channel Count: 6
140203:50:06.418 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR,FC,LFE,BL,BR
140303:50:06.418 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR,BL,BR,FC,LFE (ALSA: FL FR RL RR FC LFE)
140403:50:06.418 T:139751226894080 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
140503:50:06.418 T:139751226894080 DEBUG: Output Device : HDA NVidia
140603:50:06.418 T:139751226894080 DEBUG: Sample Rate : 44100
140703:50:06.418 T:139751226894080 DEBUG: Sample Format : AE_FMT_S32NE
140803:50:06.418 T:139751226894080 DEBUG: Channel Count : 6
140903:50:06.418 T:139751226894080 DEBUG: Channel Layout: FL,FR,BL,BR,FC,LFE
141003:50:06.418 T:139751226894080 DEBUG: Frames : 544
141103:50:06.418 T:139751226894080 DEBUG: Frame Size : 24
141203:50:06.424 T:139751235286784 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
141303:50:06.445 T:139751857735744 DEBUG: Previous line repeats 1 times.
141403:50:06.445 T:139751857735744 INFO: PAPlayer::PrepareStream - Ready
141503:50:06.445 T:139751857735744 DEBUG: PlayFile: OpenFile succeed, play state 1
141603:50:06.446 T:139751243679488 DEBUG: Thread PAPlayer start, auto delete: false
141703:50:06.446 T:139751243679488 DEBUG: PAPlayer::Process - Playback started
141803:50:06.446 T:139751243679488 DEBUG: OnPlayBackStarted: play state was 1, starting 0
141903:50:06.475 T:139748731496192 DEBUG: CGUIInfoManager::SetCurrentSong(/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Pressure.ogg)
142003:50:06.476 T:139748731496192 DEBUG: Loading additional tag info for file /home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Pressure.ogg
142103:50:06.485 T:139749511657216 NOTICE: 1Channel: Service: Playback started
142203:50:06.488 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
142303:50:06.488 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
142403:50:06.496 T:139748413179648 DEBUG: Thread JobWorker start, auto delete: true
142503:50:06.508 T:139751405172480 DEBUG: UPnP: Building didl for object '/home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Pressure.ogg'
142603:50:06.520 T:139748731496192 DEBUG: unrecognized XipComment name: ENCODER
142703:50:06.537 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Pressure","type":"song"},"player":{"playerid":0,"speed":1}}
142803:50:06.543 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnPlay - data: {"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Pressure","type":"song"},"player":{"playerid":0,"speed":1}}
142903:50:06.610 T:139751857735744 DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'diffuse/music_cover_cd_medium.png'
143003:50:06.796 T:139748413179648 DEBUG: DoWork - took 150 ms to load special://masterprofile/Thumbnails/0/0fe2b990.jpg
143103:50:06.823 T:139750023350016 DEBUG: Skin Helper Simplecache --> Initialized
143203:50:07.606 T:139750023350016 DEBUG: Previous line repeats 2 times.
143303:50:07.606 T:139750023350016 DEBUG: Skin Helper Service --> Show OSD Infopanel - number of seconds: 4
143403:50:27.662 T:139751857735744 DEBUG: LIRC: Update - NEW at 127742:000000037ff07be6 00 KEY_STOP mceusb (KEY_STOP)
143503:50:27.663 T:139751857735744 DEBUG: OnKey: guide (0xe0) pressed, action is Stop
143603:50:27.795 T:139751235286784 DEBUG: CActiveAE::DiscardStream - audio stream deleted
143703:50:27.795 T:139751235286784 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
143803:50:27.804 T:139751243679488 DEBUG: Previous line repeats 2 times.
143903:50:27.804 T:139751243679488 DEBUG: OnPlayBackStopped: play state was 2, starting 0
144003:50:27.804 T:139751243679488 DEBUG: Thread PAPlayer 139751243679488 terminating
144103:50:27.810 T:139751405172480 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
144203:50:27.811 T:139751405172480 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
144303:50:27.820 T:139750023350016 DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Pressure","type":"song"}}
144403:50:27.833 T:139750496478976 DEBUG: Skin Helper Widgets --> Kodi_Monitor: sender xbmc - method: Player.OnStop - data: {"end":false,"item":{"album":"Billy Joel (Multichannel Mixes)","artist":["Billy Joel"],"title":"Pressure","type":"song"}}
144503:50:27.842 T:139749511657216 NOTICE: 1Channel: Service: Playback Stopped
144603:50:27.842 T:139749511657216 NOTICE: 1Channel: Service: Resetting...
144703:50:27.985 T:139748396394240 DEBUG: DoWork - Saving file state for audio item /home/jamesz/Multichannel Music/Billy Joel/Custom Mixes/Pressure.ogg
144803:50:28.073 T:139751226894080 INFO: CActiveAESink::OpenSink - initialize sink
144903:50:28.242 T:139751226894080 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=NVidia,DEV=0
145003:50:28.242 T:139751226894080 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=NVidia,DEV=0"
145103:50:28.249 T:139751226894080 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=NVidia,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
145203:50:28.249 T:139751226894080 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
145303:50:28.249 T:139751226894080 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
145403:50:28.249 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
145503:50:29.294 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
145603:50:29.294 T:139751226894080 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 186 ms
145703:50:29.398 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
145803:50:29.398 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
145903:50:29.398 T:139751226894080 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)
146003:50:29.398 T:139751226894080 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
146103:50:29.398 T:139751226894080 DEBUG: Output Device : HDA NVidia
146203:50:29.398 T:139751226894080 DEBUG: Sample Rate : 44100
146303:50:29.398 T:139751226894080 DEBUG: Sample Format : AE_FMT_S32NE
146403:50:29.398 T:139751226894080 DEBUG: Channel Count : 2
146503:50:29.398 T:139751226894080 DEBUG: Channel Layout: FL,FR
146603:50:29.398 T:139751226894080 DEBUG: Frames : 2048
146703:50:29.398 T:139751226894080 DEBUG: Frame Size : 8
146803:50:29.402 T:139751235286784 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
146903:50:29.675 T:139750031742720 DEBUG: Previous line repeats 1 times.
147003:50:29.675 T:139750031742720 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
147103:50:30.025 T:139750471300864 DEBUG: Previous line repeats 1 times.
147203:50:30.025 T:139750471300864 DEBUG: GetAlbumsByWhere - query took 350 ms
147303:50:30.058 T:139750031742720 DEBUG: GetAlbumsByWhere - query took 383 ms
147403:50:30.418 T:139750471300864 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview JOIN albumartistview ON albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
147503:50:30.759 T:139750471300864 DEBUG: Previous line repeats 1 times.
147603:50:30.759 T:139750471300864 DEBUG: GetAlbumsByWhere - query took 342 ms
147703:50:30.833 T:139750031742720 DEBUG: GetAlbumsByWhere - query took 358 ms
147803:50:35.882 T:139751857735744 DEBUG: LIRC: Update - NEW at 135962:000000037ff07bdc 00 KEY_BACK mceusb (KEY_BACK)
147903:50:35.882 T:139751857735744 DEBUG: OnKey: menu (0xd8) pressed, action is Back
148003:50:35.886 T:139751857735744 DEBUG: CGUIMediaWindow::GetDirectory (/home/jamesz/Multichannel Music/Billy Joel/)
148103:50:35.886 T:139751857735744 DEBUG: ParentPath = [/home/jamesz/Multichannel Music/]
148203:50:35.895 T:139751857735744 DEBUG: RetrieveMusicInfo() took 0 msec
148303:50:35.900 T:139748450019072 DEBUG: Thread BackgroundLoader start, auto delete: false
148403:50:35.912 T:139748450019072 DEBUG: Thread BackgroundLoader 139748450019072 terminating
148503:50:36.844 T:139751857735744 DEBUG: LIRC: Update - NEW at 136924:000000037ff07bdc 00 KEY_BACK mceusb (KEY_BACK)
148603:50:36.845 T:139751857735744 DEBUG: OnKey: menu (0xd8) pressed, action is Back
148703:50:36.847 T:139751857735744 DEBUG: CGUIMediaWindow::GetDirectory (/home/jamesz/Multichannel Music/)
148803:50:36.848 T:139751857735744 DEBUG: ParentPath = [/home/jamesz/]
148903:50:36.856 T:139751857735744 DEBUG: RetrieveMusicInfo() took 0 msec
149003:50:36.864 T:139751243679488 DEBUG: Thread BackgroundLoader start, auto delete: false
149103:50:36.917 T:139751243679488 DEBUG: Thread BackgroundLoader 139751243679488 terminating
149203:50:37.523 T:139751857735744 DEBUG: LIRC: Update - NEW at 137602:000000037ff07bdc 00 KEY_BACK mceusb (KEY_BACK)
149303:50:37.523 T:139751857735744 DEBUG: OnKey: menu (0xd8) pressed, action is Back
149403:50:37.526 T:139751857735744 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
149503:50:37.754 T:139751857735744 DEBUG: ------ Window Deinit (MyMusicNav.xml) ------
149603:50:37.762 T:139751857735744 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
149703:50:37.762 T:139751857735744 DEBUG: ------ Window Init (Home.xml) ------
149803:50:37.772 T:139748687550208 DEBUG: Thread LanguageInvoker start, auto delete: false
149903:50:37.772 T:139748687550208 INFO: initializing python engine.
150003:50:37.772 T:139748687550208 DEBUG: CPythonInvoker(19, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): start processing
150103:50:37.988 T:139748687550208 DEBUG: -->Python Interpreter Initialized<--
150203:50:37.988 T:139748687550208 DEBUG: CPythonInvoker(19, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): the source file to load is "/home/jamesz/.kodi/addons/script.skinshortcuts/default.py"
150303:50:37.989 T:139748687550208 DEBUG: CPythonInvoker(19, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): setting the Python path to /home/jamesz/.kodi/addons/script.skinshortcuts:/home/jamesz/.kodi/addons/script.module.simplejson/lib:/home/jamesz/.kodi/addons/script.module.unidecode/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gtk-2.0
150403:50:37.989 T:139748687550208 DEBUG: CPythonInvoker(19, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): entering source directory /home/jamesz/.kodi/addons/script.skinshortcuts
150503:50:37.989 T:139748687550208 DEBUG: CPythonInvoker(19, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): instantiating addon using automatically obtained id of "script.skinshortcuts" dependent on version 2.20.0 of the xbmc.python api
150603:50:38.696 T:139748354430720 DEBUG: Thread Timer start, auto delete: false
150703:50:38.712 T:139748687550208 INFO: CPythonInvoker(19, /home/jamesz/.kodi/addons/script.skinshortcuts/default.py): script successfully run
150803:50:38.749 T:139748354430720 DEBUG: Thread Timer 139748354430720 terminating
150903:50:38.750 T:139748346038016 DEBUG: Thread Timer start, auto delete: false
151003:50:38.859 T:139748687550208 INFO: Python script stopped
151103:50:38.859 T:139748687550208 DEBUG: Thread LanguageInvoker 139748687550208 terminating
151203:50:39.172 T:139751857735744 DEBUG: LIRC: Update - NEW at 139251:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
151303:50:39.172 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
151403:50:39.267 T:139748346038016 DEBUG: Thread Timer 139748346038016 terminating
151503:50:39.689 T:139751857735744 DEBUG: LIRC: Update - NEW at 139769:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
151603:50:39.689 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
151703:50:40.707 T:139751857735744 DEBUG: LIRC: Update - NEW at 140787:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
151803:50:40.707 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
151903:50:42.138 T:139751857735744 DEBUG: LIRC: Update - NEW at 142218:000000037ff07be1 00 KEY_UP mceusb (KEY_UP)
152003:50:42.138 T:139751857735744 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
152103:50:42.947 T:139751857735744 DEBUG: LIRC: Update - NEW at 143026:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK)
152203:50:42.947 T:139751857735744 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
152303:50:42.952 T:139751857735744 DEBUG: Activating window ID: 10004
152403:50:43.168 T:139751857735744 DEBUG: ------ Window Deinit (Home.xml) ------
152503:50:43.179 T:139751857735744 DEBUG: ------ Window Init (Settings.xml) ------
152603:50:43.179 T:139751857735744 INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
152703:50:43.656 T:139748450019072 DEBUG: Thread AlarmClock start, auto delete: false
152803:50:43.656 T:139751857735744 DEBUG: started alarm with name: setfocus
152903:50:43.678 T:139748346038016 DEBUG: Thread JobWorker start, auto delete: true
153003:50:43.790 T:139748731496192 DEBUG: DoWork - took 124 ms to load resource://resource.images.skinbackgrounds.titanium/settings_skin_settings.jpg
153103:50:45.211 T:139751857735744 DEBUG: LIRC: Update - NEW at 145291:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
153203:50:45.212 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
153303:50:45.445 T:139748346038016 DEBUG: DoWork - took 233 ms to load resource://resource.images.skinbackgrounds.titanium/settings_interface.jpg
153403:50:45.719 T:139751857735744 DEBUG: LIRC: Update - NEW at 145798:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
153503:50:45.719 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
153603:50:46.243 T:139751857735744 DEBUG: LIRC: Update - NEW at 146322:000000037ff07be0 00 KEY_DOWN mceusb (KEY_DOWN)
153703:50:46.243 T:139751857735744 DEBUG: OnKey: 167 (0xa7, obc88) pressed, action is Down
153803:50:46.799 T:139751857735744 DEBUG: LIRC: Update - NEW at 146879:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
153903:50:46.799 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
154003:50:47.661 T:139751857735744 DEBUG: LIRC: Update - NEW at 147741:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK)
154103:50:47.661 T:139751857735744 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
154203:50:47.661 T:139751857735744 DEBUG: Activating window ID: 10016
154303:50:47.872 T:139751857735744 DEBUG: ------ Window Deinit (Settings.xml) ------
154403:50:47.882 T:139751857735744 DEBUG: ------ Window Init (SettingsCategory.xml) ------
154503:50:47.882 T:139751857735744 INFO: Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY
154603:50:50.401 T:139751857735744 DEBUG: LIRC: Update - NEW at 150481:000000037ff07be1 00 KEY_UP mceusb (KEY_UP)
154703:50:50.401 T:139751857735744 DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is Up
154803:50:51.497 T:139751857735744 DEBUG: LIRC: Update - NEW at 151577:000000037ff07bde 00 KEY_RIGHT mceusb (KEY_RIGHT)
154903:50:51.498 T:139751857735744 DEBUG: OnKey: 168 (0xa8, obc87) pressed, action is Right
155003:50:54.283 T:139751857735744 DEBUG: LIRC: Update - NEW at 154363:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK)
155103:50:54.283 T:139751857735744 DEBUG: OnKey: 11 (0x0b, obc244) pressed, action is Select
155203:50:54.284 T:139751857735744 NOTICE: Disabled debug logging due to GUI setting. Level 0.
155303:50:54.284 T:139751857735744 NOTICE: Log level changed to "LOG_LEVEL_NORMAL"