11:37:17 T:140029830780800  NOTICE: special://profile/ is mapped to: special://masterprofile/
11:37:17 T:140029830780800  NOTICE: -----------------------------------------------------------------------
11:37:17 T:140029830780800  NOTICE: Starting XBMC (14.0-ALPHA1 Git:c63bc80). Platform: x86 Linux 64-bit
11:37:17 T:140029830780800  NOTICE: Using Release XBMC x64 build, compiled Mar 24 2014 by GCC 4.8.1 for x86 Linux 64-bit 3.11.10
11:37:17 T:140029830780800  NOTICE: Running on Linux 64-bit (Ubuntu 13.10, 3.13.6-031306-generic x86_64)
11:37:17 T:140029830780800  NOTICE: Host CPU: AMD A6-6400K APU with Radeon(tm) HD Graphics, 2 cores available
11:37:17 T:140029830780800  NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
11:37:17 T:140029830780800  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
11:37:17 T:140029830780800  NOTICE: special://masterprofile/ is mapped to: /home/xbmc/.xbmc/userdata
11:37:17 T:140029830780800  NOTICE: special://home/ is mapped to: /home/xbmc/.xbmc
11:37:17 T:140029830780800  NOTICE: special://temp/ is mapped to: /home/xbmc/.xbmc/temp
11:37:17 T:140029830780800  NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
11:37:17 T:140029830780800  NOTICE: Local hostname: xbmc
11:37:17 T:140029830780800  NOTICE: Log File is located: /home/xbmc/.xbmc/temp/xbmc.log
11:37:17 T:140029830780800  NOTICE: -----------------------------------------------------------------------
11:37:17 T:140029830780800  NOTICE: load settings...
11:37:17 T:140029830780800   ERROR: PulseAudio: Failed to connect context
11:37:17 T:140029830780800  NOTICE: PulseAudio might not be running. Context was not created.
11:37:17 T:140029830780800  NOTICE: Found 1 Lists of Devices
11:37:17 T:140029830780800  NOTICE: Enumerated ALSA devices:
11:37:17 T:140029830780800  NOTICE:     Device 1
11:37:17 T:140029830780800  NOTICE:         m_deviceName      : hdmi:CARD=HDMI,DEV=0
11:37:17 T:140029830780800  NOTICE:         m_displayName     : HDA ATI
11:37:17 T:140029830780800  NOTICE:         m_displayNameExtra: HDMI
11:37:17 T:140029830780800  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
11:37:17 T:140029830780800  NOTICE:         m_channels        : FL,FR,BL,BR,FC,LFE,SL,SR
11:37:17 T:140029830780800  NOTICE:         m_sampleRates     : 32000,44100,48000,88200,96000,176400,192000
11:37:17 T:140029830780800  NOTICE:         m_dataFormats     : AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE
11:37:17 T:140029830780800  NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
11:37:17 T:140029830780800  NOTICE: Loaded settings file from special://profile/advancedsettings.xml
11:37:17 T:140029830780800  NOTICE: Contents of special://profile/advancedsettings.xml are...
                                            <advancedsettings>
                                              <loglevel hide="false">2</loglevel>
                                              <gputempcommand>sensors|sed -ne &apos;1h;1!H;${g;s/.*temp1: \+\([-+][0-9]\+\).*/\1 C/p}&apos;</gputempcommand>
                                              <cputempcommand>sensors|sed -ne &apos;0,/.*\([-+][0-9]\).*/s//\1 C/p&apos;</cputempcommand>
                                              <gui>
                                                <algorithmdirtyregions>3</algorithmdirtyregions>
                                                <nofliptimeout>0</nofliptimeout>
                                              </gui>
                                              <video>
                                                <latency>
                                                  <delay>0</delay>
                                                  <refresh>
                                                    <min>23</min>
                                                    <max>24</max>
                                                    <delay>175</delay>
                                                    <!-- set to zero or adjust if audio seems out of sync with 24p movies -->
                                                  </refresh>
                                                </latency>
                                              </video>
                                              <network>
                                                <buffermode>2</buffermode>
                                                <cachemembuffersize>249525333</cachemembuffersize>
                                                <readbufferfactor>8.0</readbufferfactor>
                                              </network>
                                            </advancedsettings>
11:37:17 T:140029830780800  NOTICE: Log level changed to 2
11:37:17 T:140029830780800  NOTICE: Default DVD Player: dvdplayer
11:37:17 T:140029830780800  NOTICE: Default Video Player: dvdplayer
11:37:17 T:140029830780800  NOTICE: Default Audio Player: paplayer
11:37:17 T:140029830780800  NOTICE: Disabled debug logging due to GUI setting. Level 1.
11:37:17 T:140029830780800  NOTICE: Log level changed to 1
11:37:17 T:140029830780800  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
11:37:17 T:140029830780800  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
11:37:17 T:140029830780800   DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
11:37:17 T:140029830780800   DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
11:37:17 T:140029830780800   DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
11:37:17 T:140029830780800   DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
11:37:17 T:140029830780800  NOTICE: Loaded playercorefactory configuration
11:37:17 T:140029830780800  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
11:37:17 T:140029830780800  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
11:37:17 T:140029830780800    INFO: creating subdirectories
11:37:17 T:140029830780800    INFO: userdata folder: special://masterprofile/
11:37:17 T:140029830780800    INFO: recording folder:
11:37:17 T:140029830780800    INFO: screenshots folder:
11:37:17 T:140029830780800    INFO: load language info file: special://xbmc/language/English/langinfo.xml
11:37:17 T:140029830780800   DEBUG: trying to set locale to en_US.UTF-8
11:37:17 T:140029830780800    INFO: global locale set to en_US.UTF-8
11:37:17 T:140029830780800    INFO: load English language file, from path: special://xbmc/language/
11:37:17 T:140029830780800   DEBUG: POParser: loaded 3166 strings from file /usr/share/xbmc/language/English/strings.po
11:37:17 T:140029486003968  NOTICE: Thread ActiveAE start, auto delete: false
11:37:17 T:140029477611264  NOTICE: Thread AESink start, auto delete: false
11:37:17 T:140029486003968    INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
11:37:17 T:140029477611264    INFO: CActiveAESink::OpenSink - initialize sink
11:37:17 T:140029477611264   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
11:37:17 T:140029477611264    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0"
11:37:17 T:140029477611264    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
11:37:17 T:140029477611264    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
11:37:17 T:140029477611264    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 171 ms
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
11:37:17 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
11:37:17 T:140029477611264   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
11:37:17 T:140029477611264   DEBUG:   Output Device : HDA ATI
11:37:17 T:140029477611264   DEBUG:   Sample Rate   : 48000
11:37:17 T:140029477611264   DEBUG:   Sample Format : AE_FMT_S32NE
11:37:17 T:140029477611264   DEBUG:   Channel Count : 2
11:37:17 T:140029477611264   DEBUG:   Channel Layout: FL,FR
11:37:17 T:140029477611264   DEBUG:   Frames        : 2048
11:37:17 T:140029477611264   DEBUG:   Frame Samples : 4096
11:37:17 T:140029477611264   DEBUG:   Frame Size    : 8
11:37:17 T:140029830780800  NOTICE: Running database version Addons16
11:37:17 T:140029830780800   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-x86_64-linux.so)
11:37:17 T:140029830780800   DEBUG: Loading: /usr/lib/xbmc/system/libcpluff-x86_64-linux.so
11:37:17 T:140029830780800    INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/xbmc/addons: No such file or directory'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.t0mm0.common has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in visualization.fishbmc has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.ororotv has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.trakt.tv has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.dailymotion_com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.universal has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.ofdb.de has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in weather.wunderground has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.sportsaholic has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in repository.lambda has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in service.xbmc.versioncheck has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.hulu-beta has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.muchmovies.hd has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.yifymovies.hd has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.audio.tuneinradio has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in service.skin.widgets has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.espn_3 has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.free.cable-beta has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.simple.downloader has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.impa.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.itunes_podcasts has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.artwork.downloader has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.audio.npr has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.football.today has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.ustvnow has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.movieposterdb.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.port.hu has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in repository.mash2k3 has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.cinema.experience has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in skin.eminence has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.universal has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.rt.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.mechanize has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmcplus.xbmc-plugins-beta has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.debug has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.xbmcswift2 has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.xbmc.ads has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.SportsDevil has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.feedparser has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Plug-in script.module.metahandler has been installed.'
11:37:17 T:140029830780800   DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
11:37:17 T:140029830780800  NOTICE: ADDONS: Using repository repository.xbmc.org
11:37:17 T:140029830780800  NOTICE: ADDONS: Using repository repository.lambda
11:37:17 T:140029830780800  NOTICE: ADDONS: Using repository repository.mash2k3
11:37:17 T:140029830780800  NOTICE: ADDONS: Using repository repository.xbmcplus.xbmc-plugins-beta
11:37:17 T:140029830780800    INFO: LIRC Initialize: using: /dev/lircd
11:37:17 T:140029830780800    INFO: LIRC Initialize: connect failed: No such file or directory
11:37:17 T:140029830780800   DEBUG: Failed to connect to LIRC. Retry in 10s.
11:37:17 T:140029830780800   DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
11:37:17 T:140029830780800   DEBUG: LoadMappings - loaded node "CEC Adapter"
11:37:17 T:140029830780800   DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
11:37:17 T:140029830780800   DEBUG: LoadMappings - loaded node "iMON HID device"
11:37:17 T:140029830780800   DEBUG: CPeripheralBusUSB - initialised udev monitor
11:37:17 T:140029830780800   DEBUG: SECTION:LoadDLL(libcec.so.2)
11:37:17 T:140029830780800   DEBUG: Loading: libcec.so.2
11:37:17 T:140029459953408  NOTICE: Thread PeripBusCEC start, auto delete: false
11:37:17 T:140029451560704  NOTICE: Thread PeripBusUSBUdev start, auto delete: false
11:37:17 T:140029830780800   DEBUG: Selected UDisks as storage provider
11:37:17 T:140029830780800   DEBUG: UDisks: DaemonVersion 1
11:37:17 T:140029830780800   DEBUG: UDisks: Querying available devices
11:37:17 T:140029830780800   DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sdd: IsFileSystem false HasFileSystem  IsSystemInternal false IsMounted false IsRemovable false IsPartition false IsOptical false
11:37:17 T:140029830780800   DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sdc: IsFileSystem false HasFileSystem  IsSystemInternal false IsMounted false IsRemovable false IsPartition false IsOptical false
11:37:17 T:140029830780800   DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sdd2: IsFileSystem true HasFileSystem ntfs IsSystemInternal false IsMounted true IsRemovable false IsPartition true IsOptical false
11:37:17 T:140029830780800    INFO: UDisks: Added /media/Music
11:37:17 T:140029830780800   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
11:37:17 T:140029830780800   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
11:37:17 T:140029830780800   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
11:37:17 T:140029830780800   DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sdb: IsFileSystem false HasFileSystem  IsSystemInternal false IsMounted false IsRemovable false IsPartition false IsOptical false
11:37:17 T:140029830780800   DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sdc1: IsFileSystem true HasFileSystem ntfs IsSystemInternal false IsMounted true IsRemovable false IsPartition true IsOptical false
11:37:17 T:140029830780800    INFO: UDisks: Added /media/Gooner
11:37:17 T:140029830780800   DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/sdb1: IsFileSystem true HasFileSystem ntfs IsSystemInternal false IsMounted true IsRemovable false IsPartition true IsOptical false
11:37:17 T:140029830780800    INFO: UDisks: Added /media/Osiris
11:37:17 T:140029830780800   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
11:37:17 T:140029830780800  NOTICE: Setup SDL
11:37:18 T:140029830780800    INFO: Available videomodes (xrandr):
11:37:18 T:140029830780800    INFO: Output 'HDMI-0' has 18 modes
11:37:18 T:140029830780800    INFO: ID:0x57 Name:1024x768 Refresh:60.003841 Width:1024 Height:768
11:37:18 T:140029830780800    INFO: Pixel Ratio: 1.000000
11:37:18 T:140029830780800    INFO: ID:0x58 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x59 Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x5b Name:1920x1080 Refresh:30.000000 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x5c Name:1920x1080 Refresh:24.000000 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x5e Name:1920x1080 Refresh:29.970100 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x5f Name:1920x1080 Refresh:23.976080 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x5a Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x5d Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x60 Name:1280x720 Refresh:60.000000 Width:1280 Height:720
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x61 Name:1280x720 Refresh:59.940201 Width:1280 Height:720
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.750000
11:37:18 T:140029830780800    INFO: ID:0x62 Name:800x600 Refresh:60.316540 Width:800 Height:600
11:37:18 T:140029830780800    INFO: Pixel Ratio: 1.000000
11:37:18 T:140029830780800    INFO: ID:0x63 Name:720x480 Refresh:60.000000 Width:720 Height:480
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.888889
11:37:18 T:140029830780800    INFO: ID:0x64 Name:720x480 Refresh:59.940060 Width:720 Height:480
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.888889
11:37:18 T:140029830780800    INFO: ID:0x65 Name:640x480 Refresh:60.000000 Width:640 Height:480
11:37:18 T:140029830780800    INFO: Pixel Ratio: 1.000000
11:37:18 T:140029830780800    INFO: ID:0x66 Name:640x480 Refresh:59.940479 Width:640 Height:480
11:37:18 T:140029830780800    INFO: Pixel Ratio: 1.000000
11:37:18 T:140029830780800    INFO: ID:0x67 Name:640x480 Refresh:59.940479 Width:640 Height:480
11:37:18 T:140029830780800    INFO: Pixel Ratio: 1.000000
11:37:18 T:140029830780800    INFO: ID:0x68 Name:720x400 Refresh:70.081657 Width:720 Height:400
11:37:18 T:140029830780800    INFO: Pixel Ratio: 0.740741
11:37:18 T:140029830780800  NOTICE: Checking resolution 16
11:37:18 T:140029830780800   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so)
11:37:18 T:140029830780800   DEBUG: Loading: /usr/lib/xbmc/system/ImageLib-x86_64-linux.so
11:37:18 T:140029830780800  NOTICE: Using visual 0x1e3
11:37:18 T:140029830780800    INFO: GL: Maximum texture width: 16384
11:37:18 T:140029830780800   DEBUG: GLX_EXTENSIONS: GLX_ARB_create_context GLX_ARB_create_context_profile GLX_ARB_get_proc_address GLX_ARB_multisample GLX_EXT_import_context GLX_EXT_visual_info GLX_EXT_visual_rating GLX_EXT_create_context_es2_profile GLX_MESA_copy_sub_buffer GLX_MESA_multithread_makecurrent GLX_MESA_swap_control GLX_OML_swap_method GLX_OML_sync_control GLX_SGI_make_current_read GLX_SGI_swap_control GLX_SGI_video_sync GLX_SGIS_multisample GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGIX_visual_select_group GLX_EXT_texture_from_pixmap GLX_INTEL_swap_event
11:37:18 T:140029830780800  NOTICE: GL_VENDOR = X.Org
11:37:18 T:140029830780800  NOTICE: GL_RENDERER = Gallium 0.4 on AMD ARUBA
11:37:18 T:140029830780800  NOTICE: GL_VERSION = 3.0 Mesa 10.0.3
11:37:18 T:140029830780800  NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.30
11:37:18 T:140029830780800  NOTICE: GL_EXTENSIONS = GL_ARB_multisample GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_copy_texture GL_EXT_polygon_offset GL_EXT_subtexture GL_EXT_texture_object GL_EXT_vertex_array GL_EXT_compiled_vertex_array GL_EXT_texture GL_EXT_texture3D GL_IBM_rasterpos_clip GL_ARB_point_parameters GL_EXT_draw_range_elements GL_EXT_packed_pixels GL_EXT_point_parameters GL_EXT_rescale_normal GL_EXT_separate_specular_color GL_EXT_texture_edge_clamp GL_SGIS_generate_mipmap GL_SGIS_texture_border_clamp GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_ARB_framebuffer_sRGB GL_ARB_multitexture GL_EXT_framebuffer_sRGB GL_IBM_multimode_draw_arrays GL_IBM_texture_mirrored_repeat GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_transpose_matrix GL_EXT_blend_func_separate GL_EXT_fog_coord GL_EXT_multi_draw_arrays GL_EXT_secondary_color GL_EXT_texture_env_add GL_EXT_texture_filter_anisotropic GL_EXT_texture_lod_bias GL_INGR_blend_func_separate GL_NV_blend_square GL_NV_light_max_exponent GL_NV_texgen_reflection GL_NV_texture_env_combine4 GL_S3_s3tc GL_SUN_multi_draw_arrays GL_ARB_texture_border_clamp GL_ARB_texture_compression GL_EXT_framebuffer_object GL_EXT_texture_compression_s3tc GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_MESA_window_pos GL_NV_packed_depth_stencil GL_NV_texture_rectangle GL_ARB_depth_texture GL_ARB_occlusion_query GL_ARB_shadow GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_mirrored_repeat GL_ARB_window_pos GL_EXT_stencil_two_side GL_EXT_texture_cube_map GL_NV_depth_clamp GL_NV_fog_distance GL_APPLE_packed_pixels GL_APPLE_vertex_array_object GL_ARB_draw_buffers GL_ARB_fragment_program GL_ARB_fragment_shader GL_ARB_shader_objects GL_ARB_vertex_program GL_ARB_vertex_shader GL_ATI_draw_buffers GL_ATI_texture_env_combine3 GL_ATI_texture_float GL_EXT_shadow_funcs GL_EXT_stencil_wrap GL_MESA_pack_invert GL_NV_primitive_restart GL_ARB_depth_clamp GL_ARB_fragment_program_shadow GL_ARB_half_float_pixel GL_ARB_occlusion_query2 GL_ARB_point_sprite GL_ARB_shading_language_100 GL_ARB_sync GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ATI_blend_equation_separate GL_EXT_blend_equation_separate GL_OES_read_format GL_ARB_color_buffer_float GL_ARB_pixel_buffer_object GL_ARB_texture_compression_rgtc GL_ARB_texture_float GL_ARB_texture_rectangle GL_ATI_texture_compression_3dc GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_mirror_clamp GL_EXT_texture_rectangle GL_EXT_texture_sRGB GL_EXT_texture_shared_exponent GL_ARB_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_packed_depth_stencil GL_ARB_vertex_array_object GL_ATI_separate_stencil GL_ATI_texture_mirror_once GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_gpu_program_parameters GL_EXT_texture_array GL_EXT_texture_compression_latc GL_EXT_texture_integer GL_EXT_texture_sRGB_decode GL_EXT_timer_query GL_OES_EGL_image GL_MESA_texture_array GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_draw_instanced GL_ARB_half_float_vertex GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_texture_rg GL_ARB_texture_swizzle GL_ARB_vertex_array_bgra GL_EXT_texture_swizzle GL_EXT_vertex_array_bgra GL_NV_conditional_render GL_AMD_conservative_depth GL_AMD_draw_buffers_blend GL_AMD_seamless_cubemap_per_texture GL_AMD_shader_stencil_export GL_ARB_ES2_compatibility GL_ARB_blend_func_extended GL_ARB_debug_output GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_provoking_vertex GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_shader_stencil_export GL_ARB_shader_texture_lod GL_ARB_texture_cube_map_array GL_ARB_texture_multisample GL_ARB_texture_rgb10_a2ui GL_ARB_uniform_buffer_object GL_ARB_vertex_type_2_10_10_10_rev GL_EXT_provoking_vertex GL_EXT_texture_snorm GL_MESA_texture_signed_rgba GL_NV_texture_barrier GL_ARB_get_program_binary GL_ARB_robustness GL_ARB_shader_bit_encoding GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_NV_vdpau_interop GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_base_instance GL_ARB_conservative_depth GL_ARB_internalformat_query GL_ARB_map_buffer_alignment GL_ARB_shading_language_420pack GL_ARB_shading_language_packing GL_ARB_texture_storage GL_ARB_transform_feedback_instanced GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_transform_feedback GL_ARB_invalidate_subdata GL_ARB_texture_storage_multisample GL_ARB_vertex_attrib_binding GL_KHR_debug GL_ARB_texture_mirror_clamp_to_edge GL_ARB_vertex_type_10f_11f_11f_rev
11:37:18 T:140029830780800    INFO: GL: Maximum texture width: 16384
11:37:18 T:140029830780800   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
11:37:18 T:140029830780800    INFO: load default splash image: /usr/share/xbmc/media/Splash.png
11:37:18 T:140029830780800    INFO: load keymapping
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/appcommand.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/gamepad.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/joystick.xml
11:37:18 T:140029830780800  NOTICE: No Joystick name specified, loading default map
11:37:18 T:140029830780800  NOTICE: Previous line repeats 35 times.
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/keyboard.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/mouse.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/remote.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
11:37:18 T:140029830780800    INFO: Loading special://xbmc/system/Lircmap.xml
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'mceusb'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'XboxDVDDongle'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'Microsoft_Xbox'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'anysee'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'iMON-PAD'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'MCE_via_iMON'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'TwinHanRemote'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'linux-input-layer'
11:37:18 T:140029830780800    INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'mediacenter'
11:37:18 T:140029830780800    INFO: * Adding remote mapping for device 'devinput'
11:37:18 T:140029830780800   DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
11:37:18 T:140029830780800    INFO: GUI format 1920x1080, Display 1920x1080@ 60.00 - Full Screen
11:37:18 T:140029830780800   DEBUG: guilib: Fill viewport on change for solving rendering passes
11:37:18 T:140029830780800   DEBUG: SECTION:LoadDLL(libcurl-gnutls.so.4)
11:37:18 T:140029830780800   DEBUG: Loading: libcurl-gnutls.so.4
11:37:18 T:140029830780800  NOTICE: Running database version Addons16
11:37:18 T:140029830780800   DEBUG: Initialize, updating databases...
11:37:18 T:140029830780800  NOTICE: Running database version ViewModes6
11:37:18 T:140029830780800  NOTICE: Running database version Textures13
11:37:18 T:140029830780800  NOTICE: Running database version MyMusic46
11:37:18 T:140029830780800  NOTICE: Running database version MyVideos78
11:37:18 T:140029830780800  NOTICE: Running database version TV22
11:37:18 T:140029830780800  NOTICE: Running database version Epg7
11:37:18 T:140029830780800   DEBUG: Initialize, updating databases... DONE
11:37:18 T:140029830780800  NOTICE: start dvd mediatype detection
11:37:18 T:140029830780800  NOTICE: initializing playlistplayer
11:37:18 T:140029830780800  NOTICE: DONE initializing playlistplayer
11:37:18 T:140029829400320  NOTICE: Thread DetectDVDMedia start, auto delete: false
11:37:18 T:140029829400320   DEBUG: Compiled with libcdio Version 0.83
11:37:18 T:140029829400320   DEBUG: Thread DetectDVDMedia 140029829400320 terminating
11:37:18 T:140029830780800   DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY
11:37:18 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
11:37:18 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
11:37:18 T:140029830780800    INFO: Unloading old skin ...
11:37:18 T:140029830780800    INFO:   load skin from: /home/xbmc/.xbmc/addons/skin.eminence (version: 0.9.28)
11:37:18 T:140029830780800    INFO:   load fonts for skin...
11:37:18 T:140029830780800    INFO: Loading fonts from /home/xbmc/.xbmc/addons/skin.eminence/1080i/Font.xml
11:37:18 T:140029830780800   DEBUG: POParser: loaded 107 strings from file /home/xbmc/.xbmc/addons/skin.eminence/language/English/strings.po
11:37:18 T:140029830780800    INFO: Loading skin includes from /home/xbmc/.xbmc/addons/skin.eminence/1080i/Includes.xml
11:37:18 T:140029830780800    INFO:   load new skin...
11:37:18 T:140029830780800    INFO: Loading user windows, path /home/xbmc/.xbmc/addons/skin.eminence/1080i
11:37:18 T:140029830780800   DEBUG: Load Skin XML: 11.87ms
11:37:18 T:140029830780800    INFO:   initialize new skin...
11:37:18 T:140029830780800   DEBUG: guilib: Fill viewport on change for solving rendering passes
11:37:18 T:140029830780800    INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
11:37:18 T:140029830780800    INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
11:37:18 T:140029830780800    INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
11:37:18 T:140029830780800    INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
11:37:18 T:140029830780800    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
11:37:18 T:140029830780800    INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
11:37:18 T:140029830780800    INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
11:37:18 T:140029830780800    INFO: Loading /home/xbmc/.xbmc/addons/skin.eminence/sounds/sounds.xml
11:37:18 T:140029830780800  NOTICE: /home/xbmc/.xbmc/addons/skin.eminence/sounds/sounds.xml, Line 0
                                            Failed to open file
11:37:18 T:140029830780800    INFO:   skin loaded...
11:37:18 T:140029830780800   DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
11:37:18 T:140029830780800 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
11:37:18 T:140029830780800    INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
11:37:18 T:140029830780800    INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
11:37:18 T:140029830780800   DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
11:37:18 T:140029830780800 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
11:37:18 T:140029830780800    INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
11:37:18 T:140029830780800    INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
11:37:18 T:140029830780800    INFO: JSONRPC v6.14.1: Successfully initialized
11:37:18 T:140029830780800   DEBUG: ADDON: Starting service addons.
11:37:18 T:140029011683072  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140029011683072    INFO: initializing python engine.
11:37:18 T:140029003290368  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140029003290368    INFO: initializing python engine.
11:37:18 T:140029830780800   DEBUG: Activating window ID: 12999
11:37:18 T:140029830780800   DEBUG: ------ Window Init (Startup.xml) ------
11:37:18 T:140029830780800    INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
11:37:18 T:140028994897664  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140028994897664    INFO: initializing python engine.
11:37:18 T:140028986504960  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140028986504960    INFO: initializing python engine.
11:37:18 T:140028843894528  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140028843894528    INFO: initializing python engine.
11:37:18 T:140029830780800    INFO: removing tempfiles
11:37:18 T:140029830780800   DEBUG: ADDON: Starting service addons.
11:37:18 T:140028978112256  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140028978112256    INFO: initializing python engine.
11:37:18 T:140029830780800  NOTICE: initialize done
11:37:18 T:140029830780800  NOTICE: Running the application...
11:37:18 T:140028969719552  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140028969719552    INFO: initializing python engine.
11:37:18 T:140029830780800   DEBUG: started alarm with name: gohome
11:37:18 T:140029830780800   DEBUG: no profile autoexec.py (/home/xbmc/.xbmc/userdata/autoexec.py) found, skipping
11:37:18 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers(:0)
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
11:37:18 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
11:37:18 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
11:37:18 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
11:37:18 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
11:37:18 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
11:37:18 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
11:37:18 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
11:37:18 T:140028877465344  NOTICE: Thread AlarmClock start, auto delete: false
11:37:18 T:140028885858048  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:18 T:140028885858048    INFO: initializing python engine.
11:37:18 T:140029003290368   DEBUG: CPythonInvoker(1, /home/xbmc/.xbmc/addons/service.skin.widgets/default.py): start processing
11:37:18 T:140028994897664   DEBUG: CPythonInvoker(2, /home/xbmc/.xbmc/addons/script.module.simple.downloader/default.py): start processing
11:37:18 T:140028986504960   DEBUG: CPythonInvoker(3, /home/xbmc/.xbmc/addons/script.artwork.downloader/service.py): start processing
11:37:18 T:140028843894528   DEBUG: CPythonInvoker(4, /home/xbmc/.xbmc/addons/script.cinema.experience/service.py): start processing
11:37:18 T:140028978112256   DEBUG: CPythonInvoker(5, /home/xbmc/.xbmc/addons/plugin.video.ororotv/service.py): start processing
11:37:18 T:140028969719552   DEBUG: CPythonInvoker(6, /home/xbmc/.xbmc/addons/script.common.plugin.cache/default.py): start processing
11:37:18 T:140029830780800  NOTICE: DVDPlayer: Opening: :0
11:37:18 T:140029830780800 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
11:37:18 T:140029830780800   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
11:37:18 T:140029830780800   DEBUG: LinuxRendererGL: Cleaning up GL resources
11:37:18 T:140029011683072   DEBUG: CPythonInvoker(0, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): start processing
11:37:18 T:140028885858048   DEBUG: CPythonInvoker(7, /home/xbmc/.xbmc/addons/script.module.universal/default.py): start processing
11:37:18 T:140029830780800   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
11:37:18 T:140028869072640  NOTICE: Thread DVDPlayer start, auto delete: false
11:37:18 T:140028869072640  NOTICE: Creating InputStream
11:37:18 T:140028869072640   ERROR: CDVDPlayer::OpenInputStream - error opening [:0]
11:37:18 T:140028869072640  NOTICE: CDVDPlayer::OnExit()
11:37:18 T:140028869072640  NOTICE: CDVDPlayer::OnExit() deleting input stream
11:37:18 T:140028869072640   DEBUG: OnPlayBackStopped : play state was 1, starting 1
11:37:18 T:140028869072640   DEBUG: Thread DVDPlayer 140028869072640 terminating
11:37:18 T:140029830780800   DEBUG: OnPlayBackStopped : play state was 3, starting 0
11:37:18 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
11:37:18 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
11:37:18 T:140029830780800   ERROR: Playlist Player: skipping unplayable item: 0, path [:0]
11:37:18 T:140029830780800   DEBUG: Playlist Player: no more playable items... aborting playback
11:37:18 T:140029830780800   DEBUG: NetworkMessage - Starting network services
11:37:18 T:140029830780800   DEBUG: CZeroconfAvahi::clientCallback: client is up and running
11:37:18 T:140029830780800  NOTICE: starting zeroconf publishing
11:37:18 T:140029830780800  NOTICE: Webserver: Starting...
11:37:18 T:140029830780800  NOTICE: WebServer: Started the webserver
11:37:18 T:140029830780800  NOTICE: ES: Starting event server
11:37:18 T:140029830780800    INFO: JSONRPC Server: Successfully initialized
11:37:18 T:140028860679936  NOTICE: Thread JobWorker start, auto delete: true
11:37:18 T:140028860679936   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:XBMC (xbmc) port:8080
11:37:18 T:140028860679936   DEBUG: CZeroconfAvahi::addService() named: XBMC (xbmc) type: _http._tcp port:8080
11:37:18 T:140028609029888  NOTICE: Thread TCPServer start, auto delete: false
11:37:18 T:140028617422592  NOTICE: Thread EventServer start, auto delete: false
11:37:18 T:140028617422592  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
11:37:18 T:140028617422592  NOTICE: UDP: Listening on port 9777
11:37:18 T:140028600637184  NOTICE: Thread JobWorker start, auto delete: true
11:37:18 T:140028600637184   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:XBMC (xbmc) port:8080
11:37:18 T:140028860679936   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:XBMC (xbmc) port:9090
11:37:18 T:140028860679936   DEBUG: CZeroconfAvahi::addService() named: XBMC (xbmc) type: _xbmc-jsonrpc._tcp port:9090
11:37:18 T:140028860679936   DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC (xbmc) port:9777
11:37:18 T:140028860679936   DEBUG: CZeroconfAvahi::addService() named: XBMC (xbmc) type: _xbmc-events._udp port:9777
11:37:18 T:140028600637184   DEBUG: CZeroconfAvahi::addService() named: XBMC (xbmc) type: _xbmc-jsonrpc-h._tcp port:8080
11:37:18 T:140029830780800  NOTICE: CDVDPlayer::CloseFile()
11:37:18 T:140029830780800  NOTICE: DVDPlayer: waiting for threads to exit
11:37:18 T:140029830780800  NOTICE: DVDPlayer: finished waiting
11:37:18 T:140029830780800   DEBUG: LinuxRendererGL: Cleaning up GL resources
11:37:18 T:140029830780800  NOTICE: CDVDPlayer::CloseFile()
11:37:18 T:140029830780800  NOTICE: DVDPlayer: waiting for threads to exit
11:37:18 T:140029830780800  NOTICE: DVDPlayer: finished waiting
11:37:18 T:140029830780800   DEBUG: LinuxRendererGL: Cleaning up GL resources
11:37:18 T:140029003290368  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140029003290368   DEBUG: CPythonInvoker(1, /home/xbmc/.xbmc/addons/service.skin.widgets/default.py): the source file to load is /home/xbmc/.xbmc/addons/service.skin.widgets/default.py
11:37:18 T:140028994897664  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140028994897664   DEBUG: CPythonInvoker(2, /home/xbmc/.xbmc/addons/script.module.simple.downloader/default.py): the source file to load is /home/xbmc/.xbmc/addons/script.module.simple.downloader/default.py
11:37:18 T:140028986504960  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140028986504960   DEBUG: CPythonInvoker(3, /home/xbmc/.xbmc/addons/script.artwork.downloader/service.py): the source file to load is /home/xbmc/.xbmc/addons/script.artwork.downloader/service.py
11:37:18 T:140029003290368   DEBUG: CPythonInvoker(1, /home/xbmc/.xbmc/addons/service.skin.widgets/default.py): setting the Python path to /home/xbmc/.xbmc/addons/service.skin.widgets:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140029003290368   DEBUG: CPythonInvoker(1, /home/xbmc/.xbmc/addons/service.skin.widgets/default.py): entering source directory /home/xbmc/.xbmc/addons/service.skin.widgets
11:37:18 T:140029003290368   DEBUG: CPythonInvoker(1, /home/xbmc/.xbmc/addons/service.skin.widgets/default.py): instantiating addon using automatically obtained id of "service.skin.widgets" dependent on version 2.1.0 of the xbmc.python api
11:37:18 T:140028986504960   DEBUG: CPythonInvoker(3, /home/xbmc/.xbmc/addons/script.artwork.downloader/service.py): setting the Python path to /home/xbmc/.xbmc/addons/script.artwork.downloader:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140028986504960   DEBUG: CPythonInvoker(3, /home/xbmc/.xbmc/addons/script.artwork.downloader/service.py): entering source directory /home/xbmc/.xbmc/addons/script.artwork.downloader
11:37:18 T:140028986504960   DEBUG: CPythonInvoker(3, /home/xbmc/.xbmc/addons/script.artwork.downloader/service.py): instantiating addon using automatically obtained id of "script.artwork.downloader" dependent on version 2.1.0 of the xbmc.python api
11:37:18 T:140028978112256  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140028978112256   DEBUG: CPythonInvoker(5, /home/xbmc/.xbmc/addons/plugin.video.ororotv/service.py): the source file to load is /home/xbmc/.xbmc/addons/plugin.video.ororotv/service.py
11:37:18 T:140029003290368   DEBUG: Skin Widgets: script version 0.0.27 started
11:37:18 T:140028843894528  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140028843894528   DEBUG: CPythonInvoker(4, /home/xbmc/.xbmc/addons/script.cinema.experience/service.py): the source file to load is /home/xbmc/.xbmc/addons/script.cinema.experience/service.py
11:37:18 T:140028885858048  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140028885858048   DEBUG: CPythonInvoker(7, /home/xbmc/.xbmc/addons/script.module.universal/default.py): the source file to load is /home/xbmc/.xbmc/addons/script.module.universal/default.py
11:37:18 T:140028994897664   DEBUG: CPythonInvoker(2, /home/xbmc/.xbmc/addons/script.module.simple.downloader/default.py): setting the Python path to /home/xbmc/.xbmc/addons/script.module.simple.downloader:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140028994897664   DEBUG: CPythonInvoker(2, /home/xbmc/.xbmc/addons/script.module.simple.downloader/default.py): entering source directory /home/xbmc/.xbmc/addons/script.module.simple.downloader
11:37:18 T:140028994897664   DEBUG: CPythonInvoker(2, /home/xbmc/.xbmc/addons/script.module.simple.downloader/default.py): instantiating addon using automatically obtained id of "script.module.simple.downloader" dependent on version 2.1.0 of the xbmc.python api
11:37:18 T:140028978112256   DEBUG: CPythonInvoker(5, /home/xbmc/.xbmc/addons/plugin.video.ororotv/service.py): setting the Python path to /home/xbmc/.xbmc/addons/plugin.video.ororotv:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140028978112256   DEBUG: CPythonInvoker(5, /home/xbmc/.xbmc/addons/plugin.video.ororotv/service.py): entering source directory /home/xbmc/.xbmc/addons/plugin.video.ororotv
11:37:18 T:140028978112256   DEBUG: CPythonInvoker(5, /home/xbmc/.xbmc/addons/plugin.video.ororotv/service.py): instantiating addon using automatically obtained id of "plugin.video.ororotv" dependent on version 2.1.0 of the xbmc.python api
11:37:18 T:140028885858048   DEBUG: CPythonInvoker(7, /home/xbmc/.xbmc/addons/script.module.universal/default.py): setting the Python path to /home/xbmc/.xbmc/addons/script.module.universal:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140028885858048   DEBUG: CPythonInvoker(7, /home/xbmc/.xbmc/addons/script.module.universal/default.py): entering source directory /home/xbmc/.xbmc/addons/script.module.universal
11:37:18 T:140028885858048   DEBUG: CPythonInvoker(7, /home/xbmc/.xbmc/addons/script.module.universal/default.py): instantiating addon using automatically obtained id of "script.module.universal" dependent on version 2.1.0 of the xbmc.python api
11:37:18 T:140029003290368   DEBUG: RunQuery took 15 ms for 295 items query: select * from movieview  WHERE ((movieview.playCount IS NULL OR movieview.playCount < 1))
11:37:18 T:140028986504960   DEBUG: POParser: loaded 100 strings from file /home/xbmc/.xbmc/addons/script.artwork.downloader/resources/language/English/strings.po
11:37:18 T:140028969719552  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140028969719552   DEBUG: CPythonInvoker(6, /home/xbmc/.xbmc/addons/script.common.plugin.cache/default.py): the source file to load is /home/xbmc/.xbmc/addons/script.common.plugin.cache/default.py
11:37:18 T:140028969719552   DEBUG: CPythonInvoker(6, /home/xbmc/.xbmc/addons/script.common.plugin.cache/default.py): setting the Python path to /home/xbmc/.xbmc/addons/script.common.plugin.cache:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140028969719552   DEBUG: CPythonInvoker(6, /home/xbmc/.xbmc/addons/script.common.plugin.cache/default.py): entering source directory /home/xbmc/.xbmc/addons/script.common.plugin.cache
11:37:18 T:140028969719552   DEBUG: CPythonInvoker(6, /home/xbmc/.xbmc/addons/script.common.plugin.cache/default.py): instantiating addon using automatically obtained id of "script.common.plugin.cache" dependent on version 2.1.0 of the xbmc.python api
11:37:18 T:140028843894528   DEBUG: CPythonInvoker(4, /home/xbmc/.xbmc/addons/script.cinema.experience/service.py): setting the Python path to /home/xbmc/.xbmc/addons/script.cinema.experience:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140028843894528   DEBUG: CPythonInvoker(4, /home/xbmc/.xbmc/addons/script.cinema.experience/service.py): entering source directory /home/xbmc/.xbmc/addons/script.cinema.experience
11:37:18 T:140028969719552  NOTICE:  StorageServer Module loaded RUN
11:37:18 T:140028969719552  NOTICE: StorageClient-2.5.4 Starting server
11:37:18 T:140028969719552  NOTICE: StorageServer-2.5.4 Storage Server starting /home/xbmc/.xbmc/temp/commoncache.db
11:37:18 T:140028843894528   DEBUG: CPythonInvoker(4, /home/xbmc/.xbmc/addons/script.cinema.experience/service.py): instantiating addon using automatically obtained id of "script.cinema.experience" dependent on version 2.12.0 of the xbmc.python api
11:37:18 T:140029011683072  NOTICE: -->Python Interpreter Initialized<--
11:37:18 T:140029011683072   DEBUG: CPythonInvoker(0, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): the source file to load is /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py
11:37:18 T:140029011683072   DEBUG: CPythonInvoker(0, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/xbmc/.xbmc/addons/service.xbmc.versioncheck:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:18 T:140029011683072   DEBUG: CPythonInvoker(0, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): entering source directory /home/xbmc/.xbmc/addons/service.xbmc.versioncheck
11:37:18 T:140029011683072   DEBUG: CPythonInvoker(0, /home/xbmc/.xbmc/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
11:37:18 T:140028986504960  NOTICE: Artwork Downloader: ## Service - Run at startup: False
11:37:18 T:140028986504960  NOTICE: Artwork Downloader: ## Service - Delayed startup:  minutes
11:37:18 T:140028986504960  NOTICE: Artwork Downloader: ## Service - Run as service: False
11:37:18 T:140028986504960  NOTICE: Artwork Downloader: ## Service - Time: :00
11:37:18 T:140028986504960    INFO: CPythonInvoker(3, /home/xbmc/.xbmc/addons/script.artwork.downloader/service.py): script successfully run
11:37:18 T:140029011683072   DEBUG: XBMC Version Check: Version 0.1.19 started
11:37:18 T:140029011683072    INFO: CPythonInvoker(0, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): script successfully run
11:37:18 T:140028885858048  NOTICE: Universal - An Addons Toolkit: -watchhistory- -Loading sqlite3 as DB engine version: 3.7.17
11:37:18 T:140028885858048  NOTICE: Universal - An Addons Toolkit: - watchhistory - -Auto Cleanup Start
11:37:18 T:140029830780800   DEBUG: started alarm with name: checkatboot
11:37:18 T:140029830780800   DEBUG: started alarm with name: checkwhilerunning
11:37:18 T:140029011683072    INFO: Python script stopped
11:37:18 T:140029011683072   DEBUG: Thread LanguageInvoker 140029011683072 terminating
11:37:18 T:140028885858048   DEBUG: Universal - An Addons Toolkit: -watchhistory- -Table watch_history initialized
11:37:18 T:140028885858048  NOTICE: Universal - An Addons Toolkit: -watchhistory- -DELETE FROM watch_history WHERE lastwatched < '2014-03-04'
11:37:18 T:140028885858048  NOTICE: Universal - An Addons Toolkit: - watchhistory - -Auto Cleanup End
11:37:18 T:140028885858048    INFO: CPythonInvoker(7, /home/xbmc/.xbmc/addons/script.module.universal/default.py): script successfully run
11:37:18 T:140028994897664    INFO: CPythonInvoker(2, /home/xbmc/.xbmc/addons/script.module.simple.downloader/default.py): script successfully run
11:37:18 T:140028994897664    INFO: Python script stopped
11:37:18 T:140028994897664   DEBUG: Thread LanguageInvoker 140028994897664 terminating
11:37:18 T:140028986504960    INFO: Python script stopped
11:37:18 T:140028986504960   DEBUG: Thread LanguageInvoker 140028986504960 terminating
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - settings() - __init__
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - settings() - start
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - Reading settings.xml
11:37:18 T:140028885858048    INFO: Python script stopped
11:37:18 T:140028885858048   DEBUG: Thread LanguageInvoker 140028885858048 terminating
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - Reading settings.xml
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - Cinema Experience service script version 4.0.10 started
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - Reading settings.xml
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - settings() - start
11:37:18 T:140028843894528   DEBUG: [Cinema Experience] - Reading settings.xml
11:37:18 T:140029003290368   DEBUG: RunQuery took 57 ms for 2824 items query: select * from episodeview  WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
11:37:18 T:140029830780800   DEBUG: UPower: Received an unknown signal NameAcquired
11:37:19 T:140029003290368   DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideoview
11:37:19 T:140029003290368   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
11:37:19 T:140029003290368   DEBUG: GetAlbumsByWhere - query took 12 ms
11:37:19 T:140029003290368   DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview  WHERE (artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist JOIN album ON album.idAlbum = album_artist.idAlbum WHERE album.bCompilation = 0 )) and artistview.strArtist != ''
11:37:19 T:140029003290368   DEBUG: Time to retrieve artists from dataset = 7
11:37:19 T:140029003290368   DEBUG: GetSongsByWhere query = SELECT songview.* FROM songview  WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
11:37:19 T:140029003290368   DEBUG: GetSongsByWhere() - took 139 ms
11:37:19 T:140029003290368   DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:00.753904
11:37:19 T:140029003290368   DEBUG: RunQuery took 0 ms for 0 items query: select * from movieview  WHERE (movieview.idFile  IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
11:37:19 T:140028869072640   DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
11:37:19 T:140029003290368   DEBUG: Previous line repeats 3 times.
11:37:19 T:140029003290368   DEBUG: RunQuery took 37 ms for 6 items query: SELECT * FROM tvshowview  WHERE ( ((tvshowview.watchedcount > 0 AND tvshowview.watchedcount < tvshowview.totalCount) OR (tvshowview.watchedcount = 0 AND EXISTS (SELECT 1 FROM episodeview WHERE episodeview.idShow = tvshowview.idShow AND episodeview.resumeTimeInSeconds > 0))))
11:37:19 T:140029003290368   DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=9) order by idShow
11:37:19 T:140029003290368   DEBUG: RunQuery took 3 ms for 121 items query: select * from episodeview  WHERE (episodeview.idShow IN (9,37)) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
11:37:19 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
11:37:19 T:140029003290368   DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=3) order by idShow
11:37:19 T:140029003290368   DEBUG: RunQuery took 2 ms for 41 items query: select * from episodeview  WHERE (episodeview.idShow = 3) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
11:37:19 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
11:37:19 T:140029003290368   DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=5) order by idShow
11:37:19 T:140029003290368   DEBUG: RunQuery took 4 ms for 133 items query: select * from episodeview  WHERE (episodeview.idShow = 5) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
11:37:19 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
11:37:19 T:140029003290368   DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=2) order by idShow
11:37:19 T:140029003290368   DEBUG: RunQuery took 1 ms for 6 items query: select * from episodeview  WHERE (episodeview.idShow = 2) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
11:37:19 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
11:37:19 T:140029003290368   DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=17) order by idShow
11:37:19 T:140029003290368   DEBUG: RunQuery took 1 ms for 11 items query: select * from episodeview  WHERE (episodeview.idShow IN (17,52)) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
11:37:19 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
11:37:19 T:140029003290368   DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=12) order by idShow
11:37:19 T:140029003290368   DEBUG: RunQuery took 1 ms for 34 items query: select * from episodeview  WHERE (episodeview.idShow = 12) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
11:37:19 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 25
11:37:19 T:140029003290368   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
11:37:19 T:140029830780800   DEBUG: Activating window ID: 10000
11:37:19 T:140029830780800   DEBUG: ------ Window Deinit (Startup.xml) ------
11:37:19 T:140029830780800   DEBUG: ------ Window Init (Home.xml) ------
11:37:19 T:140029830780800    INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
11:37:19 T:140029003290368   DEBUG: GetAlbumsByWhere - query took 10 ms
11:37:19 T:140028600637184   DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
11:37:19 T:140028600637184   DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum
11:37:19 T:140029830780800   DEBUG: started alarm with name: move9600
11:37:19 T:140028600637184   DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
11:37:19 T:140028860679936    INFO: WEATHER: Downloading weather
11:37:19 T:140029830780800   DEBUG: ------ Window Init (Custom_Debug_Overlay.xml) ------
11:37:19 T:140029830780800    INFO: Loading skin file: Custom_Debug_Overlay.xml, load type: KEEP_IN_MEMORY
11:37:19 T:140028885858048  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:19 T:140028885858048    INFO: initializing python engine.
11:37:19 T:140028885858048   DEBUG: CPythonInvoker(8, /home/xbmc/.xbmc/addons/weather.wunderground/default.py): start processing
11:37:19 T:140028885858048  NOTICE: -->Python Interpreter Initialized<--
11:37:19 T:140028885858048   DEBUG: CPythonInvoker(8, /home/xbmc/.xbmc/addons/weather.wunderground/default.py): the source file to load is /home/xbmc/.xbmc/addons/weather.wunderground/default.py
11:37:19 T:140028885858048   DEBUG: CPythonInvoker(8, /home/xbmc/.xbmc/addons/weather.wunderground/default.py): setting the Python path to /home/xbmc/.xbmc/addons/weather.wunderground:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:19 T:140028885858048   DEBUG: CPythonInvoker(8, /home/xbmc/.xbmc/addons/weather.wunderground/default.py): entering source directory /home/xbmc/.xbmc/addons/weather.wunderground
11:37:19 T:140028885858048   DEBUG: CPythonInvoker(8, /home/xbmc/.xbmc/addons/weather.wunderground/default.py): instantiating addon using automatically obtained id of "weather.wunderground" dependent on version 2.12.0 of the xbmc.python api
11:37:19 T:140028986504960  NOTICE: Thread JobWorker start, auto delete: true
11:37:19 T:140028600637184   DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
11:37:19 T:140028600637184   DEBUG: RunQuery took 21 ms for 10 items query: select * from movieview  ORDER BY dateAdded desc, idMovie desc LIMIT 10
11:37:19 T:140028885858048   DEBUG: POParser: loaded 40 strings from file /home/xbmc/.xbmc/addons/weather.wunderground/resources/language/English/strings.po
11:37:19 T:140029003290368   DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideoview
11:37:19 T:140029003290368   DEBUG: Skin Widgets: Total time needed to request recommended queries: 0:00:00.414322
11:37:19 T:140028600637184   DEBUG: RunQuery took 78 ms for 10 items query: select * from episodeview  ORDER BY dateAdded desc, idEpisode desc LIMIT 10
11:37:19 T:140029003290368   DEBUG: RunQuery took 17 ms for 295 items query: select * from movieview  WHERE ((movieview.playCount IS NULL OR movieview.playCount = 0))
11:37:19 T:140028600637184   DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideoview  ORDER BY dateAdded desc, idMVideo desc LIMIT 10
11:37:19 T:140028600637184   DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
11:37:20 T:140029003290368   DEBUG: RunQuery took 60 ms for 2824 items query: select * from episodeview  WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
11:37:20 T:140029003290368   DEBUG: RunQuery took 0 ms for 0 items query: select * from musicvideoview
11:37:20 T:140029003290368   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
11:37:20 T:140029003290368   DEBUG: GetAlbumsByWhere - query took 11 ms
11:37:20 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 36
11:37:20 T:140029003290368   DEBUG: Skin Widgets: Total time needed to request recent items queries: 0:00:00.763600
11:37:20 T:140029003290368   DEBUG: Skin Widgets: Total time needed for all queries: 0:00:01.932008
11:37:20 T:140028885858048    INFO: CPythonInvoker(8, /home/xbmc/.xbmc/addons/weather.wunderground/default.py): script successfully run
11:37:20 T:140028885858048    INFO: Python script stopped
11:37:20 T:140028885858048   DEBUG: Thread LanguageInvoker 140028885858048 terminating
11:37:20 T:140028860679936   DEBUG: GetZipList - Processing zip://special%3a%2f%2fxbmc%2fmedia%2fweather.zip/
11:37:21 T:140028860679936   DEBUG: POParser: loaded 109 weather tokens
11:37:21 T:140029830780800   DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
11:37:21 T:140029830780800   DEBUG: OnKey: right (0xf083) pressed, action is Right
11:37:21 T:140029830780800   DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
11:37:21 T:140029830780800   DEBUG: OnKey: right (0xf083) pressed, action is Right
11:37:22 T:140029830780800   DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11:37:22 T:140029830780800   DEBUG: OnKey: return (0xf00d) pressed, action is Select
11:37:22 T:140029830780800   DEBUG: Activating window ID: 10502
11:37:22 T:140029830780800   DEBUG: ------ Window Deinit (Home.xml) ------
11:37:22 T:140029830780800   DEBUG: ------ Window Init (MyMusicNav.xml) ------
11:37:22 T:140029830780800    INFO: Loading skin file: MyMusicNav.xml, load type: KEEP_IN_MEMORY
11:37:22 T:140029830780800   DEBUG: CGUIMediaWindow::GetDirectory (musicdb://albums/)
11:37:22 T:140029830780800   DEBUG:   ParentPath = [musicdb://albums/]
11:37:22 T:140029830780800   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
11:37:22 T:140029830780800   DEBUG: GetAlbumsByWhere - query took 8 ms
11:37:22 T:140029830780800   DEBUG: Album format left  = [%B]
11:37:22 T:140029830780800   DEBUG: Album format right = [%A]
11:37:22 T:140029830780800   DEBUG: Album format left  = [%B]
11:37:22 T:140029830780800   DEBUG: Album format right = [%A]
11:37:23 T:140028885858048  NOTICE: Thread BackgroundLoader start, auto delete: false
11:37:23 T:140028994897664  NOTICE: Thread JobWorker start, auto delete: true
11:37:23 T:140028885858048   DEBUG: Thread BackgroundLoader 140028885858048 terminating
11:37:24 T:140029830780800   DEBUG: Keyboard: scancode: 0x71, sym: 0x0114, unicode: 0x0000, modifier: 0x0
11:37:24 T:140029830780800   DEBUG: OnKey: left (0xf082) pressed, action is Left
11:37:25 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:25 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:25 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:25 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:26 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:26 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:26 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:26 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:27 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:27 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:27 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:27 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:28 T:140029830780800    INFO: LIRC Initialize: using: /dev/lircd
11:37:28 T:140029830780800   DEBUG: Failed to connect to LIRC. Retry in 20s.
11:37:28 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:28 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:29 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:29 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:30 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:30 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:31 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:31 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:32 T:140029830780800   DEBUG: Keyboard: scancode: 0x74, sym: 0x0112, unicode: 0x0000, modifier: 0x0
11:37:32 T:140029830780800   DEBUG: OnKey: down (0xf081) pressed, action is Down
11:37:32 T:140029830780800   DEBUG: Keyboard: scancode: 0x72, sym: 0x0113, unicode: 0x0000, modifier: 0x0
11:37:32 T:140029830780800   DEBUG: OnKey: right (0xf083) pressed, action is Right
11:37:33 T:140029830780800   DEBUG: UPower: Received an unknown signal DeviceChanged
11:37:33 T:140029830780800   DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11:37:33 T:140029830780800   DEBUG: OnKey: return (0xf00d) pressed, action is Select
11:37:33 T:140029830780800   DEBUG: CGUIMediaWindow::GetDirectory (musicdb://albums/187/)
11:37:33 T:140029830780800   DEBUG:   ParentPath = [musicdb://albums/]
11:37:33 T:140028860679936   DEBUG: GetSongsByWhere query = SELECT songview.* FROM songview  WHERE songview.idAlbum = 187
11:37:33 T:140028860679936   DEBUG: GetSongsByWhere() - took 1 ms
11:37:33 T:140029830780800   DEBUG: Album format left  = [%B]
11:37:33 T:140029830780800   DEBUG: Album format right = [%A]
11:37:33 T:140029830780800   DEBUG: Album format left  = [%B]
11:37:33 T:140029830780800   DEBUG: Album format right = [%A]
11:37:33 T:140028885858048  NOTICE: Thread BackgroundLoader start, auto delete: false
11:37:33 T:140028885858048   DEBUG: Thread BackgroundLoader 140028885858048 terminating
11:37:33 T:140029830780800   DEBUG: UPower: Received an unknown signal DeviceChanged
11:37:34 T:140029830780800   DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11:37:34 T:140029830780800   DEBUG: OnKey: return (0xf00d) pressed, action is Select
11:37:34 T:140029830780800   DEBUG: SELECT albumview.*,albumartistview.*   FROM albumview   JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum   WHERE albumview.idAlbum = 187   ORDER BY albumartistview.iOrder
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:37:34 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers(musicdb://albums/187/2418.mp3?albumid=187)
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
11:37:34 T:140029830780800   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
11:37:34 T:140029830780800   DEBUG: SECTION:LoadDLL(libmad.so.0)
11:37:34 T:140029830780800   DEBUG: Loading: libmad.so.0
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
11:37:34 T:140029830780800   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
11:37:34 T:140029830780800   DEBUG: CFileCache::Open - opening <albums/187/2418.mp3> using cache
11:37:34 T:140028885858048  NOTICE: Thread FileCache start, auto delete: false
11:37:34 T:140029830780800   DEBUG: Seek - waiting for position 6603062.
11:37:34 T:140029830780800    INFO: AudioDecoder: File is queued
11:37:34 T:140029830780800   DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 0.000000
11:37:34 T:140029486003968    INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
11:37:35 T:140029486003968   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
11:37:35 T:140029830780800    INFO: PAPlayer::PrepareStream - Ready
11:37:35 T:140029830780800   DEBUG: PlayFile : OpenFile succeed, play state 1
11:37:35 T:140029011683072  NOTICE: Thread PAPlayer start, auto delete: false
11:37:35 T:140029011683072   DEBUG: PAPlayer::Process - Playback started
11:37:35 T:140029011683072   DEBUG: OnPlayBackStarted : play state was 1, starting 0
11:37:35 T:140029477611264    INFO: CActiveAESink::OpenSink - initialize sink
11:37:35 T:140029477611264   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
11:37:35 T:140029477611264    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0"
11:37:35 T:140029477611264    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
11:37:35 T:140029477611264    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
11:37:35 T:140029477611264    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2048, bufferSize 8192
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2048, bufferSize 8192
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 171 ms
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR
11:37:35 T:140029477611264   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR
11:37:35 T:140029477611264   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
11:37:35 T:140029477611264   DEBUG:   Output Device : HDA ATI
11:37:35 T:140029477611264   DEBUG:   Sample Rate   : 48000
11:37:35 T:140029477611264   DEBUG:   Sample Format : AE_FMT_S32NE
11:37:35 T:140029477611264   DEBUG:   Channel Count : 2
11:37:35 T:140029477611264   DEBUG:   Channel Layout: FL,FR
11:37:35 T:140029477611264   DEBUG:   Frames        : 2048
11:37:35 T:140029477611264   DEBUG:   Frame Samples : 4096
11:37:35 T:140029477611264   DEBUG:   Frame Size    : 8
11:37:35 T:140029830780800   DEBUG: ------ Window Init (MusicOverlay.xml) ------
11:37:35 T:140029830780800    INFO: Loading skin file: MusicOverlay.xml, load type: KEEP_IN_MEMORY
11:37:35 T:140029830780800   ERROR: unable to load:/home/xbmc/.xbmc/addons/skin.eminence/1080i/MusicOverlay.xml, Line 0
                                            Failed to open file
11:37:35 T:140029830780800   DEBUG: CGUIInfoManager::SetCurrentSong(musicdb://albums/187/2418.mp3?albumid=187)
11:37:35 T:140029830780800   DEBUG: SELECT albumview.*,albumartistview.*   FROM albumview   JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum   WHERE albumview.idAlbum = 187   ORDER BY albumartistview.iOrder
11:37:35 T:140029830780800   DEBUG: Loading additional tag info for file /media/Music/John Legend/Once Again/01 Save Room.mp3
11:37:35 T:140029830780800   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
11:37:35 T:140029830780800   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
11:37:39 T:140029830780800   DEBUG: Keyboard: scancode: 0x17, sym: 0x0009, unicode: 0x0009, modifier: 0x0
11:37:39 T:140029830780800   DEBUG: OnKey: tab (0xf009) pressed, action is FullScreen
11:37:39 T:140029830780800   DEBUG: Activating window ID: 12006
11:37:39 T:140029830780800   DEBUG: ------ Window Deinit (MyMusicNav.xml) ------
11:37:39 T:140029830780800   DEBUG: ------ Window Init (MusicVisualisation.xml) ------
11:37:39 T:140029830780800    INFO: Loading skin file: MusicVisualisation.xml, load type: KEEP_IN_MEMORY
11:37:39 T:140029830780800   DEBUG: ADDON: Dll Initializing - projectM
11:37:39 T:140029830780800   DEBUG: SECTION:LoadDLL(/usr/lib/xbmc/addons/visualization.projectm/projectM.vis)
11:37:39 T:140029830780800   DEBUG: Loading: /usr/lib/xbmc/addons/visualization.projectm/projectM.vis
11:37:39 T:140029830780800   DEBUG: Calling TransferSettings for: projectM
11:37:39 T:140029830780800   DEBUG: GetZipList - Processing zip://special%3a%2f%2fxbmc%2faddons%2fvisualization.projectm%2fresources%2fpresets.zip/
11:37:39 T:140029830780800   DEBUG: Visualisation::Start()
11:37:39 T:140029830780800   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
11:37:39 T:140029830780800    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
11:37:39 T:140029486003968   DEBUG: OnInitialize() started
11:37:39 T:140029486003968   DEBUG: Updating visualisation albumart: http://assets.fanart.tv/fanart/music/75a72702-a5ef-4513-bca5-c5b944903546/albumcover/once-again-502288a9ef42d.jpg
11:37:39 T:140029486003968   DEBUG: OnInitialize() done
11:37:39 T:140029830780800   DEBUG: PROJECTM - Changed preset to: Rovastar & Che - Adela The Flower (Altars Of Madness Mix 2).milk
11:37:40 T:140028885858048    INFO: CFileCache::Process - Hit eof.
11:37:47 T:140029830780800   DEBUG: SECTION:UnloadDelayed(DLL: libcrystalhd.so.3)
11:37:47 T:140029830780800   DEBUG: Unloading: libcrystalhd.so.3
11:37:48 T:140029830780800    INFO: LIRC Initialize: using: /dev/lircd
11:37:48 T:140029830780800   DEBUG: Failed to connect to LIRC. Retry in 40s.
11:37:48 T:140028579673856  NOTICE: Thread LanguageInvoker start, auto delete: false
11:37:48 T:140028579673856    INFO: initializing python engine.
11:37:48 T:140028579673856   DEBUG: CPythonInvoker(9, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): start processing
11:37:48 T:140028579673856  NOTICE: -->Python Interpreter Initialized<--
11:37:48 T:140028579673856   DEBUG: CPythonInvoker(9, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): the source file to load is /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py
11:37:48 T:140028579673856   DEBUG: CPythonInvoker(9, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): setting the Python path to /home/xbmc/.xbmc/addons/service.xbmc.versioncheck:/home/xbmc/.xbmc/addons/script.module.t0mm0.common/lib:/home/xbmc/.xbmc/addons/script.module.simplejson/lib:/home/xbmc/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/home/xbmc/.xbmc/addons/script.common.plugin.cache/lib:/home/xbmc/.xbmc/addons/script.module.simple.downloader/lib:/home/xbmc/.xbmc/addons/script.module.universal/lib:/home/xbmc/.xbmc/addons/script.module.mechanize/lib:/usr/share/xbmc/addons/xbmc.debug/:/home/xbmc/.xbmc/addons/script.module.parsedom/lib:/home/xbmc/.xbmc/addons/script.module.xbmcswift2/lib:/home/xbmc/.xbmc/addons/script.module.xbmc.ads/lib:/home/xbmc/.xbmc/addons/script.module.feedparser/lib:/home/xbmc/.xbmc/addons/script.module.metahandler/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
11:37:48 T:140028579673856   DEBUG: CPythonInvoker(9, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): entering source directory /home/xbmc/.xbmc/addons/service.xbmc.versioncheck
11:37:48 T:140028579673856   DEBUG: CPythonInvoker(9, /home/xbmc/.xbmc/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
11:37:48 T:140028579673856   DEBUG: XBMC Version Check: Version 0.1.19 started
11:37:48 T:140028579673856   DEBUG: XBMC Version Check: Version installed {u'major': 14, u'tag': u'prealpha', u'minor': 0, u'revision': u'c63bc80'}
11:37:48 T:140028579673856   DEBUG: XBMC Version Check: Nothing to see here, move along. Running a latest non stable release
11:37:48 T:140028579673856    INFO: CPythonInvoker(9, /home/xbmc/.xbmc/addons/service.xbmc.versioncheck/service.py): script successfully run
11:37:48 T:140028579673856    INFO: Python script stopped
11:37:48 T:140028579673856   DEBUG: Thread LanguageInvoker 140028579673856 terminating
11:38:03 T:140028994897664   DEBUG: Thread JobWorker 140028994897664 terminating (autodelete)
11:38:03 T:140028860679936   DEBUG: Thread JobWorker 140028860679936 terminating (autodelete)
11:38:03 T:140028986504960   DEBUG: Thread JobWorker 140028986504960 terminating (autodelete)
11:38:03 T:140028600637184   DEBUG: Thread JobWorker 140028600637184 terminating (autodelete)
11:38:09 T:140029830780800   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)
11:38:09 T:140029830780800   DEBUG: Unloading: ImageLib-x86_64-linux.so
11:38:28 T:140029830780800    INFO: LIRC Initialize: using: /dev/lircd
11:38:28 T:140029830780800   DEBUG: Failed to connect to LIRC. Giving up.
11:38:29 T:140029830780800   DEBUG: PROJECTM - Changed preset to: Rovastar - Cosmic Mosaic (Active Mix).milk
11:38:33 T:140029830780800   DEBUG: UPower: Received an unknown signal DeviceChanged
11:38:36 T:140029830780800   DEBUG: Previous line repeats 1 times.
11:38:36 T:140029830780800   DEBUG: Keyboard: scancode: 0x3a, sym: 0x006d, unicode: 0x006d, modifier: 0x0
11:38:36 T:140029830780800   DEBUG: OnKey: m (0xf04d) pressed, action is OSD
11:38:36 T:140029830780800   DEBUG: Activating window ID: 10120
11:38:36 T:140029830780800   DEBUG: ------ Window Init (MusicOSD.xml) ------
11:38:36 T:140029830780800    INFO: Loading skin file: MusicOSD.xml, load type: KEEP_IN_MEMORY
11:38:36 T:140029830780800   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so)
11:38:36 T:140029830780800   DEBUG: Loading: /usr/lib/xbmc/system/ImageLib-x86_64-linux.so
11:38:49 T:140029830780800   DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
11:38:49 T:140029830780800   DEBUG: OnKey: return (0xf00d) pressed, action is Select
11:38:49 T:140028885858048   DEBUG: Thread FileCache 140028885858048 terminating
11:38:49 T:140029486003968   DEBUG: CActiveAE::DiscardStream - audio stream deleted
11:38:49 T:140029486003968   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
11:38:49 T:140029011683072   DEBUG: Previous line repeats 1 times.
11:38:49 T:140029011683072   DEBUG: OnPlayBackStopped : play state was 2, starting 0
11:38:49 T:140029011683072   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
11:38:49 T:140029011683072   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
11:38:49 T:140029011683072   DEBUG: Thread PAPlayer 140029011683072 terminating
11:38:49 T:140029830780800   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
11:38:49 T:140029830780800   DEBUG: ------ Window Deinit (MusicOSD.xml) ------
11:38:49 T:140029830780800   DEBUG: ------ Window Deinit (MusicVisualisation.xml) ------
11:38:49 T:140029830780800   DEBUG: FreeVisualisation() started
11:38:49 T:140029830780800    INFO: ADDON: Dll Stopped - projectM
11:38:49 T:140029830780800    INFO: ADDON: Dll Destroyed - projectM
11:38:49 T:140029830780800   DEBUG: FreeVisualisation() done
11:38:49 T:140029830780800   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
11:38:49 T:140029830780800   DEBUG: ------ Window Init (MyMusicNav.xml) ------
11:38:49 T:140029830780800   DEBUG: CGUIMediaWindow::GetDirectory (musicdb://albums/187/)
11:38:49 T:140029830780800   DEBUG:   ParentPath = [musicdb://albums/187/]
11:38:49 T:140029830780800   DEBUG: GetSongsByWhere query = SELECT songview.* FROM songview  WHERE songview.idAlbum = 187
11:38:49 T:140029830780800   DEBUG: GetSongsByWhere() - took 1 ms
11:38:49 T:140029830780800   DEBUG: Album format left  = [%B]
11:38:49 T:140029830780800   DEBUG: Album format right = [%A]
11:38:49 T:140029830780800   DEBUG: Album format left  = [%B]
11:38:49 T:140029830780800   DEBUG: Album format right = [%A]
11:38:49 T:140029011683072  NOTICE: Thread BackgroundLoader start, auto delete: false
11:38:49 T:140029011683072   DEBUG: Thread BackgroundLoader 140029011683072 terminating
11:38:49 T:140029011683072  NOTICE: Thread JobWorker start, auto delete: true
11:38:49 T:140029830780800  NOTICE: Previous line repeats 1 times.
11:38:49 T:140029830780800   DEBUG: ------ Window Deinit (MusicOverlay.xml) ------
11:38:49 T:140029830780800   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
11:38:49 T:140029486003968    INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
11:38:49 T:140029486003968   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
11:38:49 T:140029011683072   DEBUG: Previous line repeats 1 times.
11:38:49 T:140029011683072   DEBUG: DoWork - Saving file state for audio item musicdb://albums/187/2418.mp3?albumid=187
11:38:50 T:140029003290368   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
11:38:50 T:140029003290368   DEBUG: GetAlbumsByWhere - query took 13 ms
11:38:50 T:140029003290368   DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview  WHERE albumview.strAlbum <> ''
11:38:50 T:140029003290368   DEBUG: GetAlbumsByWhere - query took 7 ms
11:38:50 T:140029003290368   DEBUG: DatabaseUtils::GetSortFieldList: unknown field 36